{"level":"warn","ts":"2026-05-19T04:02:18.299938Z","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-19T04:02:18.300075Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.235: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.235:2380","--initial-cluster=instance=https://199.204.45.235:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.235:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.235: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-19T04:02:18.30017Z","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-19T04:02:18.300181Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.235:2380"]} {"level":"info","ts":"2026-05-19T04:02:18.300217Z","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-19T04:02:18.301171Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"]} {"level":"info","ts":"2026-05-19T04:02:18.301299Z","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.235:2380"],"listen-peer-urls":["https://199.204.45.235:2380"],"advertise-client-urls":["https://199.204.45.235:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.235: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-19T04:02:18.306859Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.35646ms"} {"level":"info","ts":"2026-05-19T04:02:18.314066Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"aab6040abcf11","cluster-id":"2bae7d3d8857c059"} {"level":"info","ts":"2026-05-19T04:02:18.314188Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=()"} {"level":"info","ts":"2026-05-19T04:02:18.314226Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became follower at term 0"} {"level":"info","ts":"2026-05-19T04:02:18.314244Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft aab6040abcf11 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-19T04:02:18.31426Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became follower at term 1"} {"level":"info","ts":"2026-05-19T04:02:18.314312Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=(3003179657318161)"} {"level":"warn","ts":"2026-05-19T04:02:18.320342Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-19T04:02:18.321866Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-19T04:02:18.323324Z","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-19T04:02:18.325649Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"aab6040abcf11","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-19T04:02:18.325864Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"aab6040abcf11","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-19T04:02:18.325835Z","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-19T04:02:18.325971Z","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-19T04:02:18.326001Z","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-19T04:02:18.32826Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=(3003179657318161)"} {"level":"info","ts":"2026-05-19T04:02:18.329809Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2bae7d3d8857c059","local-member-id":"aab6040abcf11","added-peer-id":"aab6040abcf11","added-peer-peer-urls":["https://199.204.45.235:2380"]} {"level":"info","ts":"2026-05-19T04:02:18.332167Z","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-19T04:02:18.332274Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.235:2380"} {"level":"info","ts":"2026-05-19T04:02:18.332325Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.235:2380"} {"level":"info","ts":"2026-05-19T04:02:18.332677Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"aab6040abcf11","initial-advertise-peer-urls":["https://199.204.45.235:2380"],"listen-peer-urls":["https://199.204.45.235:2380"],"advertise-client-urls":["https://199.204.45.235:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-19T04:02:18.332782Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-19T04:02:19.315512Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 is starting a new election at term 1"} {"level":"info","ts":"2026-05-19T04:02:19.315587Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-19T04:02:19.315607Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 received MsgPreVoteResp from aab6040abcf11 at term 1"} {"level":"info","ts":"2026-05-19T04:02:19.315626Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became candidate at term 2"} {"level":"info","ts":"2026-05-19T04:02:19.315633Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 received MsgVoteResp from aab6040abcf11 at term 2"} {"level":"info","ts":"2026-05-19T04:02:19.315644Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became leader at term 2"} {"level":"info","ts":"2026-05-19T04:02:19.315653Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: aab6040abcf11 elected leader aab6040abcf11 at term 2"} {"level":"info","ts":"2026-05-19T04:02:19.316644Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"aab6040abcf11","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.235:2379]}","request-path":"/0/members/aab6040abcf11/attributes","cluster-id":"2bae7d3d8857c059","publish-timeout":"7s"} {"level":"info","ts":"2026-05-19T04:02:19.316646Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T04:02:19.316677Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T04:02:19.316886Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T04:02:19.317325Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-19T04:02:19.317448Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-19T04:02:19.317869Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2bae7d3d8857c059","local-member-id":"aab6040abcf11","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T04:02:19.318055Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T04:02:19.318149Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T04:02:19.319393Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-19T04:02:19.321404Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.235:2379"} {"level":"warn","ts":"2026-05-19T04:02:19.625922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.899899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-19T04:02:19.625977Z","caller":"traceutil/trace.go:171","msg":"trace[876322506] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"204.977211ms","start":"2026-05-19T04:02:19.420987Z","end":"2026-05-19T04:02:19.625964Z","steps":["trace[876322506] 'count revisions from in-memory index tree' (duration: 204.836358ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:02:19.626216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.936083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-19T04:02:19.626291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.079053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-19T04:02:19.626311Z","caller":"traceutil/trace.go:171","msg":"trace[702581121] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1; }","duration":"115.091367ms","start":"2026-05-19T04:02:19.511201Z","end":"2026-05-19T04:02:19.626293Z","steps":["trace[702581121] 'range keys from in-memory index tree' (duration: 114.839112ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:02:19.626339Z","caller":"traceutil/trace.go:171","msg":"trace[1222427469] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"205.189266ms","start":"2026-05-19T04:02:19.421139Z","end":"2026-05-19T04:02:19.626328Z","steps":["trace[1222427469] 'range keys from in-memory index tree' (duration: 204.979431ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:02:19.626465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.604517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-19T04:02:19.625861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.631978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-19T04:02:19.626554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.066144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-19T04:02:19.626621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.258901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-19T04:02:19.626631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.14145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-19T04:02:19.626654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.044586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-19T04:02:19.626665Z","caller":"traceutil/trace.go:171","msg":"trace[421512020] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1; }","duration":"154.309131ms","start":"2026-05-19T04:02:19.472345Z","end":"2026-05-19T04:02:19.626654Z","steps":["trace[421512020] 'count revisions from in-memory index tree' (duration: 154.21401ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:02:19.626667Z","caller":"traceutil/trace.go:171","msg":"trace[937084304] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1; }","duration":"184.200971ms","start":"2026-05-19T04:02:19.442459Z","end":"2026-05-19T04:02:19.62666Z","steps":["trace[937084304] 'count revisions from in-memory index tree' (duration: 183.64383ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:02:19.626638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.689857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-19T04:02:19.62668Z","caller":"traceutil/trace.go:171","msg":"trace[382791769] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1; }","duration":"142.070107ms","start":"2026-05-19T04:02:19.484601Z","end":"2026-05-19T04:02:19.626671Z","steps":["trace[382791769] 'range keys from in-memory index tree' (duration: 141.944254ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:02:19.626644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.584927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-19T04:02:19.626683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.381843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-19T04:02:19.62674Z","caller":"traceutil/trace.go:171","msg":"trace[896069081] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1; }","duration":"165.790869ms","start":"2026-05-19T04:02:19.460907Z","end":"2026-05-19T04:02:19.626698Z","steps":["trace[896069081] 'range keys from in-memory index tree' (duration: 165.612246ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:02:19.62676Z","caller":"traceutil/trace.go:171","msg":"trace[912531044] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1; }","duration":"101.764132ms","start":"2026-05-19T04:02:19.524947Z","end":"2026-05-19T04:02:19.626711Z","steps":["trace[912531044] 'range keys from in-memory index tree' (duration: 101.472736ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:02:19.626794Z","caller":"traceutil/trace.go:171","msg":"trace[767237433] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1; }","duration":"142.529266ms","start":"2026-05-19T04:02:19.484244Z","end":"2026-05-19T04:02:19.626773Z","steps":["trace[767237433] 'count revisions from in-memory index tree' (duration: 142.313172ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:02:19.626783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.878525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-19T04:02:19.62666Z","caller":"traceutil/trace.go:171","msg":"trace[727531058] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1; }","duration":"192.168716ms","start":"2026-05-19T04:02:19.434475Z","end":"2026-05-19T04:02:19.626644Z","steps":["trace[727531058] 'range keys from in-memory index tree' (duration: 191.954692ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:02:19.626852Z","caller":"traceutil/trace.go:171","msg":"trace[157283528] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1; }","duration":"129.979336ms","start":"2026-05-19T04:02:19.496862Z","end":"2026-05-19T04:02:19.626841Z","steps":["trace[157283528] 'range keys from in-memory index tree' (duration: 129.760553ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:02:19.626562Z","caller":"traceutil/trace.go:171","msg":"trace[897081094] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1; }","duration":"101.734751ms","start":"2026-05-19T04:02:19.524806Z","end":"2026-05-19T04:02:19.626541Z","steps":["trace[897081094] 'count revisions from in-memory index tree' (duration: 101.497835ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:02:19.626559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.100256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-19T04:02:19.626907Z","caller":"traceutil/trace.go:171","msg":"trace[1455295197] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1; }","duration":"154.483135ms","start":"2026-05-19T04:02:19.472416Z","end":"2026-05-19T04:02:19.626899Z","steps":["trace[1455295197] 'range keys from in-memory index tree' (duration: 153.960253ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:02:19.626582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.813913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-19T04:02:19.626584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.026894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-19T04:02:19.626949Z","caller":"traceutil/trace.go:171","msg":"trace[1670670337] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1; }","duration":"183.18893ms","start":"2026-05-19T04:02:19.443751Z","end":"2026-05-19T04:02:19.62694Z","steps":["trace[1670670337] 'range keys from in-memory index tree' (duration: 182.481936ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:02:19.626971Z","caller":"traceutil/trace.go:171","msg":"trace[74471822] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1; }","duration":"192.436572ms","start":"2026-05-19T04:02:19.434523Z","end":"2026-05-19T04:02:19.62696Z","steps":["trace[74471822] 'count revisions from in-memory index tree' (duration: 191.966703ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:02:19.626613Z","caller":"traceutil/trace.go:171","msg":"trace[1251587935] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1; }","duration":"115.397324ms","start":"2026-05-19T04:02:19.511185Z","end":"2026-05-19T04:02:19.626582Z","steps":["trace[1251587935] 'count revisions from in-memory index tree' (duration: 114.558816ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:02:19.626603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.876681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-19T04:02:19.627052Z","caller":"traceutil/trace.go:171","msg":"trace[2137102756] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1; }","duration":"166.352811ms","start":"2026-05-19T04:02:19.460687Z","end":"2026-05-19T04:02:19.62704Z","steps":["trace[2137102756] 'count revisions from in-memory index tree' (duration: 165.80925ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:02:19.626628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.710551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-19T04:02:19.627129Z","caller":"traceutil/trace.go:171","msg":"trace[1141688457] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1; }","duration":"130.234732ms","start":"2026-05-19T04:02:19.496883Z","end":"2026-05-19T04:02:19.627118Z","steps":["trace[1141688457] 'count revisions from in-memory index tree' (duration: 129.63579ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:02:20.763267Z","caller":"traceutil/trace.go:171","msg":"trace[518637737] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"115.265111ms","start":"2026-05-19T04:02:20.647976Z","end":"2026-05-19T04:02:20.763242Z","steps":["trace[518637737] 'read index received' (duration: 47.890213ms)","trace[518637737] 'applied index is now lower than readState.Index' (duration: 67.373148ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:02:20.763309Z","caller":"traceutil/trace.go:171","msg":"trace[49530462] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"127.551705ms","start":"2026-05-19T04:02:20.635728Z","end":"2026-05-19T04:02:20.76328Z","steps":["trace[49530462] 'process raft request' (duration: 110.723576ms)","trace[49530462] 'compare' (duration: 16.551014ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T04:02:20.763474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.485645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-19T04:02:20.763538Z","caller":"traceutil/trace.go:171","msg":"trace[513529] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:3; }","duration":"115.589037ms","start":"2026-05-19T04:02:20.647935Z","end":"2026-05-19T04:02:20.763524Z","steps":["trace[513529] 'agreement among raft nodes before linearized reading' (duration: 115.424294ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:02:20.880438Z","caller":"traceutil/trace.go:171","msg":"trace[1604108885] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"159.795484ms","start":"2026-05-19T04:02:20.720516Z","end":"2026-05-19T04:02:20.880312Z","steps":["trace[1604108885] 'process raft request' (duration: 155.690229ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:02:20.880462Z","caller":"traceutil/trace.go:171","msg":"trace[234444224] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"159.09154ms","start":"2026-05-19T04:02:20.721325Z","end":"2026-05-19T04:02:20.880409Z","steps":["trace[234444224] 'process raft request' (duration: 158.898386ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:02:20.880514Z","caller":"traceutil/trace.go:171","msg":"trace[1207387183] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"158.936537ms","start":"2026-05-19T04:02:20.721517Z","end":"2026-05-19T04:02:20.880454Z","steps":["trace[1207387183] 'process raft request' (duration: 158.764773ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:02:20.88076Z","caller":"traceutil/trace.go:171","msg":"trace[464502992] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"159.09012ms","start":"2026-05-19T04:02:20.72156Z","end":"2026-05-19T04:02:20.880651Z","steps":["trace[464502992] 'process raft request' (duration: 158.760583ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:02:20.880932Z","caller":"traceutil/trace.go:171","msg":"trace[2056766354] linearizableReadLoop","detail":"{readStateIndex:14; appliedIndex:6; }","duration":"117.571808ms","start":"2026-05-19T04:02:20.763344Z","end":"2026-05-19T04:02:20.880916Z","steps":["trace[2056766354] 'read index received' (duration: 112.8017ms)","trace[2056766354] 'applied index is now lower than readState.Index' (duration: 4.768778ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T04:02:20.881193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.072356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-19T04:02:20.881242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.220418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-19T04:02:20.881272Z","caller":"traceutil/trace.go:171","msg":"trace[720820278] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"195.202828ms","start":"2026-05-19T04:02:20.686049Z","end":"2026-05-19T04:02:20.881251Z","steps":["trace[720820278] 'agreement among raft nodes before linearized reading' (duration: 195.047825ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:02:20.881299Z","caller":"traceutil/trace.go:171","msg":"trace[460712632] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"195.309051ms","start":"2026-05-19T04:02:20.685966Z","end":"2026-05-19T04:02:20.881275Z","steps":["trace[460712632] 'agreement among raft nodes before linearized reading' (duration: 195.193738ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:02:20.881299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.381315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-19T04:02:20.881369Z","caller":"traceutil/trace.go:171","msg":"trace[2515480] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"159.484027ms","start":"2026-05-19T04:02:20.721857Z","end":"2026-05-19T04:02:20.881341Z","steps":["trace[2515480] 'agreement among raft nodes before linearized reading' (duration: 159.196901ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:02:20.881531Z","caller":"traceutil/trace.go:171","msg":"trace[1495263183] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"149.893029ms","start":"2026-05-19T04:02:20.731624Z","end":"2026-05-19T04:02:20.881517Z","steps":["trace[1495263183] 'process raft request' (duration: 149.213565ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:02:20.881544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.154934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-19T04:02:20.88158Z","caller":"traceutil/trace.go:171","msg":"trace[673428663] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"162.209755ms","start":"2026-05-19T04:02:20.71936Z","end":"2026-05-19T04:02:20.881569Z","steps":["trace[673428663] 'agreement among raft nodes before linearized reading' (duration: 162.102631ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:02:20.881573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.744199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3175"} {"level":"info","ts":"2026-05-19T04:02:20.881598Z","caller":"traceutil/trace.go:171","msg":"trace[1587249241] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"159.934938ms","start":"2026-05-19T04:02:20.721616Z","end":"2026-05-19T04:02:20.881551Z","steps":["trace[1587249241] 'process raft request' (duration: 158.733933ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:02:20.881695Z","caller":"traceutil/trace.go:171","msg":"trace[1579049218] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:11; }","duration":"114.910724ms","start":"2026-05-19T04:02:20.766757Z","end":"2026-05-19T04:02:20.881668Z","steps":["trace[1579049218] 'agreement among raft nodes before linearized reading' (duration: 114.624857ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:02:20.881716Z","caller":"traceutil/trace.go:171","msg":"trace[1172088089] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"149.320487ms","start":"2026-05-19T04:02:20.732383Z","end":"2026-05-19T04:02:20.881703Z","steps":["trace[1172088089] 'process raft request' (duration: 148.49507ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:02:20.881735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.204636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.235\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-19T04:02:20.881767Z","caller":"traceutil/trace.go:171","msg":"trace[1935697754] range","detail":"{range_begin:/registry/masterleases/199.204.45.235; range_end:; response_count:0; response_revision:11; }","duration":"163.271797ms","start":"2026-05-19T04:02:20.718485Z","end":"2026-05-19T04:02:20.881756Z","steps":["trace[1935697754] 'agreement among raft nodes before linearized reading' (duration: 163.226497ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:02:20.881802Z","caller":"traceutil/trace.go:171","msg":"trace[1016617057] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"159.852915ms","start":"2026-05-19T04:02:20.721938Z","end":"2026-05-19T04:02:20.88179Z","steps":["trace[1016617057] 'process raft request' (duration: 158.842324ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:02:20.881805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.87857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-19T04:02:20.881851Z","caller":"traceutil/trace.go:171","msg":"trace[461840032] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"110.933161ms","start":"2026-05-19T04:02:20.770897Z","end":"2026-05-19T04:02:20.881831Z","steps":["trace[461840032] 'agreement among raft nodes before linearized reading' (duration: 110.86626ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:02:20.881971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.118898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-19T04:02:20.882013Z","caller":"traceutil/trace.go:171","msg":"trace[206751215] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"195.160628ms","start":"2026-05-19T04:02:20.68684Z","end":"2026-05-19T04:02:20.882Z","steps":["trace[206751215] 'agreement among raft nodes before linearized reading' (duration: 195.097147ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:02:31.61992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.581907ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T04:02:31.62007Z","caller":"traceutil/trace.go:171","msg":"trace[243523620] linearizableReadLoop","detail":"{readStateIndex:411; appliedIndex:410; }","duration":"185.65585ms","start":"2026-05-19T04:02:31.434397Z","end":"2026-05-19T04:02:31.620052Z","steps":["trace[243523620] 'read index received' (duration: 69.176724ms)","trace[243523620] 'applied index is now lower than readState.Index' (duration: 116.477416ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T04:02:31.620223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.528242ms","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-19T04:02:31.620193Z","caller":"traceutil/trace.go:171","msg":"trace[1991553965] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"246.627516ms","start":"2026-05-19T04:02:31.373512Z","end":"2026-05-19T04:02:31.62014Z","steps":["trace[1991553965] 'process raft request' (duration: 130.064398ms)","trace[1991553965] 'compare' (duration: 115.423084ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:02:31.62027Z","caller":"traceutil/trace.go:171","msg":"trace[1680718188] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:401; }","duration":"163.592553ms","start":"2026-05-19T04:02:31.456666Z","end":"2026-05-19T04:02:31.620259Z","steps":["trace[1680718188] 'agreement among raft nodes before linearized reading' (duration: 163.494081ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:02:31.620256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.835814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-19T04:02:31.620367Z","caller":"traceutil/trace.go:171","msg":"trace[688347628] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:401; }","duration":"185.992997ms","start":"2026-05-19T04:02:31.434344Z","end":"2026-05-19T04:02:31.620337Z","steps":["trace[688347628] 'agreement among raft nodes before linearized reading' (duration: 185.780993ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:02:56.016305Z","caller":"traceutil/trace.go:171","msg":"trace[1688567400] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"133.485815ms","start":"2026-05-19T04:02:55.882787Z","end":"2026-05-19T04:02:56.016273Z","steps":["trace[1688567400] 'process raft request' (duration: 130.971848ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:02:56.711122Z","caller":"traceutil/trace.go:171","msg":"trace[1619676686] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"283.924666ms","start":"2026-05-19T04:02:56.427166Z","end":"2026-05-19T04:02:56.711091Z","steps":["trace[1619676686] 'process raft request' (duration: 283.68454ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:02:56.759442Z","caller":"traceutil/trace.go:171","msg":"trace[59890360] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:637; }","duration":"175.156841ms","start":"2026-05-19T04:02:56.58426Z","end":"2026-05-19T04:02:56.759417Z","steps":["trace[59890360] 'read index received' (duration: 126.731229ms)","trace[59890360] 'applied index is now lower than readState.Index' (duration: 48.424402ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:02:56.759531Z","caller":"traceutil/trace.go:171","msg":"trace[112401903] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"228.569376ms","start":"2026-05-19T04:02:56.530931Z","end":"2026-05-19T04:02:56.759501Z","steps":["trace[112401903] 'process raft request' (duration: 228.010375ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:02:56.759733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.424716ms","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-19T04:02:56.759853Z","caller":"traceutil/trace.go:171","msg":"trace[2097319923] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:618; }","duration":"175.63224ms","start":"2026-05-19T04:02:56.584203Z","end":"2026-05-19T04:02:56.759836Z","steps":["trace[2097319923] 'agreement among raft nodes before linearized reading' (duration: 175.306524ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:02:57.221832Z","caller":"traceutil/trace.go:171","msg":"trace[1682293571] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:640; }","duration":"352.450891ms","start":"2026-05-19T04:02:56.86935Z","end":"2026-05-19T04:02:57.2218Z","steps":["trace[1682293571] 'read index received' (duration: 352.190806ms)","trace[1682293571] 'applied index is now lower than readState.Index' (duration: 258.955µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:02:57.221859Z","caller":"traceutil/trace.go:171","msg":"trace[1160036206] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"394.30532ms","start":"2026-05-19T04:02:56.82752Z","end":"2026-05-19T04:02:57.221826Z","steps":["trace[1160036206] 'process raft request' (duration: 394.127587ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:02:57.221993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:02:56.827496Z","time spent":"394.422952ms","remote":"127.0.0.1:43972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T04:02:57.222127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.96584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-19T04:02:57.222111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.740376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T04:02:57.222171Z","caller":"traceutil/trace.go:171","msg":"trace[527574924] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:620; }","duration":"172.050752ms","start":"2026-05-19T04:02:57.050108Z","end":"2026-05-19T04:02:57.222159Z","steps":["trace[527574924] 'agreement among raft nodes before linearized reading' (duration: 171.837078ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:02:57.222213Z","caller":"traceutil/trace.go:171","msg":"trace[807975961] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:620; }","duration":"352.875819ms","start":"2026-05-19T04:02:56.86931Z","end":"2026-05-19T04:02:57.222186Z","steps":["trace[807975961] 'agreement among raft nodes before linearized reading' (duration: 352.614734ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:02:57.222279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:02:56.869294Z","time spent":"352.97188ms","remote":"127.0.0.1:43628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-19T04:03:12.813027Z","caller":"traceutil/trace.go:171","msg":"trace[1352611523] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"277.718019ms","start":"2026-05-19T04:03:12.535262Z","end":"2026-05-19T04:03:12.81298Z","steps":["trace[1352611523] 'process raft request' (duration: 276.906764ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:03:12.813696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.749816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-19T04:03:12.813799Z","caller":"traceutil/trace.go:171","msg":"trace[1997193642] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:781; }","duration":"223.926039ms","start":"2026-05-19T04:03:12.589855Z","end":"2026-05-19T04:03:12.813781Z","steps":["trace[1997193642] 'agreement among raft nodes before linearized reading' (duration: 223.667024ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:03:12.814376Z","caller":"traceutil/trace.go:171","msg":"trace[1797643230] linearizableReadLoop","detail":"{readStateIndex:807; appliedIndex:806; }","duration":"223.182914ms","start":"2026-05-19T04:03:12.589929Z","end":"2026-05-19T04:03:12.813112Z","steps":["trace[1797643230] 'read index received' (duration: 221.955571ms)","trace[1797643230] 'applied index is now lower than readState.Index' (duration: 1.220863ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:04:27.814024Z","caller":"traceutil/trace.go:171","msg":"trace[1412289753] linearizableReadLoop","detail":"{readStateIndex:1921; appliedIndex:1920; }","duration":"168.706837ms","start":"2026-05-19T04:04:27.645293Z","end":"2026-05-19T04:04:27.814Z","steps":["trace[1412289753] 'read index received' (duration: 168.562285ms)","trace[1412289753] 'applied index is now lower than readState.Index' (duration: 143.482µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:04:27.81415Z","caller":"traceutil/trace.go:171","msg":"trace[666340172] transaction","detail":"{read_only:false; response_revision:1853; number_of_response:1; }","duration":"283.712654ms","start":"2026-05-19T04:04:27.530426Z","end":"2026-05-19T04:04:27.814139Z","steps":["trace[666340172] 'process raft request' (duration: 283.421028ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:04:27.814366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.066886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18b0dbbee2078438\" ","response":"range_response_count:1 size:982"} {"level":"info","ts":"2026-05-19T04:04:27.81441Z","caller":"traceutil/trace.go:171","msg":"trace[1267351825] range","detail":"{range_begin:/registry/events/openstack/datadir-percona-xtradb-pxc-0.18b0dbbee2078438; range_end:; response_count:1; response_revision:1853; }","duration":"169.152217ms","start":"2026-05-19T04:04:27.645244Z","end":"2026-05-19T04:04:27.814396Z","steps":["trace[1267351825] 'agreement among raft nodes before linearized reading' (duration: 169.010434ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:04:27.814542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.672948ms","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-19T04:04:27.814614Z","caller":"traceutil/trace.go:171","msg":"trace[1015517520] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1853; }","duration":"100.7859ms","start":"2026-05-19T04:04:27.713812Z","end":"2026-05-19T04:04:27.814598Z","steps":["trace[1015517520] 'agreement among raft nodes before linearized reading' (duration: 100.598466ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:04:27.936162Z","caller":"traceutil/trace.go:171","msg":"trace[755700509] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"117.046367ms","start":"2026-05-19T04:04:27.819074Z","end":"2026-05-19T04:04:27.93612Z","steps":["trace[755700509] 'process raft request' (duration: 85.789858ms)","trace[755700509] 'compare' (duration: 30.831671ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:04:28.199216Z","caller":"traceutil/trace.go:171","msg":"trace[1605005165] transaction","detail":"{read_only:false; response_revision:1856; number_of_response:1; }","duration":"255.389114ms","start":"2026-05-19T04:04:27.9438Z","end":"2026-05-19T04:04:28.19919Z","steps":["trace[1605005165] 'process raft request' (duration: 255.213051ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:04:28.238896Z","caller":"traceutil/trace.go:171","msg":"trace[1124545705] linearizableReadLoop","detail":"{readStateIndex:1925; appliedIndex:1923; }","duration":"209.820516ms","start":"2026-05-19T04:04:28.029051Z","end":"2026-05-19T04:04:28.238872Z","steps":["trace[1124545705] 'read index received' (duration: 170.012735ms)","trace[1124545705] 'applied index is now lower than readState.Index' (duration: 39.806761ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:04:28.238972Z","caller":"traceutil/trace.go:171","msg":"trace[1513423398] transaction","detail":"{read_only:false; response_revision:1857; number_of_response:1; }","duration":"135.990068ms","start":"2026-05-19T04:04:28.102953Z","end":"2026-05-19T04:04:28.238943Z","steps":["trace[1513423398] 'process raft request' (duration: 135.801925ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:04:28.239078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.007679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T04:04:28.239118Z","caller":"traceutil/trace.go:171","msg":"trace[1358949269] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1857; }","duration":"210.101322ms","start":"2026-05-19T04:04:28.029007Z","end":"2026-05-19T04:04:28.239109Z","steps":["trace[1358949269] 'agreement among raft nodes before linearized reading' (duration: 209.99743ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:04:28.239577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.784651ms","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-19T04:04:28.239636Z","caller":"traceutil/trace.go:171","msg":"trace[1633877685] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1857; }","duration":"111.899874ms","start":"2026-05-19T04:04:28.12772Z","end":"2026-05-19T04:04:28.23962Z","steps":["trace[1633877685] 'agreement among raft nodes before linearized reading' (duration: 111.432984ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:04:42.397832Z","caller":"traceutil/trace.go:171","msg":"trace[425977925] transaction","detail":"{read_only:false; response_revision:1977; number_of_response:1; }","duration":"110.564037ms","start":"2026-05-19T04:04:42.287238Z","end":"2026-05-19T04:04:42.397802Z","steps":["trace[425977925] 'process raft request' (duration: 110.315022ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:05:12.792147Z","caller":"traceutil/trace.go:171","msg":"trace[976975337] linearizableReadLoop","detail":"{readStateIndex:2233; appliedIndex:2232; }","duration":"210.058212ms","start":"2026-05-19T04:05:12.582068Z","end":"2026-05-19T04:05:12.792126Z","steps":["trace[976975337] 'read index received' (duration: 118.689399ms)","trace[976975337] 'applied index is now lower than readState.Index' (duration: 91.367713ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:05:12.792168Z","caller":"traceutil/trace.go:171","msg":"trace[1589130015] transaction","detail":"{read_only:false; response_revision:2154; number_of_response:1; }","duration":"216.157236ms","start":"2026-05-19T04:05:12.575985Z","end":"2026-05-19T04:05:12.792143Z","steps":["trace[1589130015] 'process raft request' (duration: 124.858454ms)","trace[1589130015] 'compare' (duration: 91.175229ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T04:05:12.792455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.350438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-19T04:05:12.792541Z","caller":"traceutil/trace.go:171","msg":"trace[12097186] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2154; }","duration":"210.492211ms","start":"2026-05-19T04:05:12.582028Z","end":"2026-05-19T04:05:12.79252Z","steps":["trace[12097186] 'agreement among raft nodes before linearized reading' (duration: 210.202545ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:05:12.792766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.439369ms","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-19T04:05:12.792984Z","caller":"traceutil/trace.go:171","msg":"trace[1025668315] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2154; }","duration":"172.724085ms","start":"2026-05-19T04:05:12.620211Z","end":"2026-05-19T04:05:12.792935Z","steps":["trace[1025668315] 'agreement among raft nodes before linearized reading' (duration: 172.173933ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:05:19.691561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.831746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-19T04:05:19.691646Z","caller":"traceutil/trace.go:171","msg":"trace[2051977412] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2191; }","duration":"137.978449ms","start":"2026-05-19T04:05:19.553649Z","end":"2026-05-19T04:05:19.691627Z","steps":["trace[2051977412] 'range keys from in-memory index tree' (duration: 137.631433ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:05:19.691767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.426228ms","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-19T04:05:19.691942Z","caller":"traceutil/trace.go:171","msg":"trace[1266433433] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2191; }","duration":"201.788224ms","start":"2026-05-19T04:05:19.490128Z","end":"2026-05-19T04:05:19.691917Z","steps":["trace[1266433433] 'range keys from in-memory index tree' (duration: 201.234313ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:05:19.835054Z","caller":"traceutil/trace.go:171","msg":"trace[1381813983] transaction","detail":"{read_only:false; response_revision:2192; number_of_response:1; }","duration":"138.899247ms","start":"2026-05-19T04:05:19.696116Z","end":"2026-05-19T04:05:19.835015Z","steps":["trace[1381813983] 'process raft request' (duration: 138.726014ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:05:19.847088Z","caller":"traceutil/trace.go:171","msg":"trace[325401505] transaction","detail":"{read_only:false; response_revision:2193; number_of_response:1; }","duration":"150.603796ms","start":"2026-05-19T04:05:19.696462Z","end":"2026-05-19T04:05:19.847066Z","steps":["trace[325401505] 'process raft request' (duration: 150.439922ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:06:44.041392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.799751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T04:06:44.041501Z","caller":"traceutil/trace.go:171","msg":"trace[539361773] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2839; }","duration":"170.963505ms","start":"2026-05-19T04:06:43.870516Z","end":"2026-05-19T04:06:44.041479Z","steps":["trace[539361773] 'range keys from in-memory index tree' (duration: 170.72522ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:10:41.058948Z","caller":"traceutil/trace.go:171","msg":"trace[678215520] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4851; }","duration":"100.573779ms","start":"2026-05-19T04:10:40.958321Z","end":"2026-05-19T04:10:41.058894Z","steps":["trace[678215520] 'process raft request' (duration: 74.243598ms)","trace[678215520] 'compare' (duration: 25.606306ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T04:10:43.416616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.042386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T04:10:43.416727Z","caller":"traceutil/trace.go:171","msg":"trace[1122000851] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:4921; }","duration":"113.2396ms","start":"2026-05-19T04:10:43.303464Z","end":"2026-05-19T04:10:43.416703Z","steps":["trace[1122000851] 'count revisions from in-memory index tree' (duration: 112.908294ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:10:43.416832Z","caller":"traceutil/trace.go:171","msg":"trace[1975894666] transaction","detail":"{read_only:false; response_revision:4922; number_of_response:1; }","duration":"105.794317ms","start":"2026-05-19T04:10:43.310987Z","end":"2026-05-19T04:10:43.416782Z","steps":["trace[1975894666] 'process raft request' (duration: 71.073172ms)","trace[1975894666] 'compare' (duration: 34.5108ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:10:43.49255Z","caller":"traceutil/trace.go:171","msg":"trace[221166311] transaction","detail":"{read_only:false; response_revision:4923; number_of_response:1; }","duration":"122.846478ms","start":"2026-05-19T04:10:43.369662Z","end":"2026-05-19T04:10:43.492508Z","steps":["trace[221166311] 'process raft request' (duration: 122.597913ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:10:47.737081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.493589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-05-19T04:10:47.737171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.961641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-05-19T04:10:47.737189Z","caller":"traceutil/trace.go:171","msg":"trace[577231256] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5061; }","duration":"189.668283ms","start":"2026-05-19T04:10:47.547497Z","end":"2026-05-19T04:10:47.737165Z","steps":["trace[577231256] 'range keys from in-memory index tree' (duration: 189.272134ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:10:47.737222Z","caller":"traceutil/trace.go:171","msg":"trace[320492897] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5061; }","duration":"161.041023ms","start":"2026-05-19T04:10:47.576171Z","end":"2026-05-19T04:10:47.737212Z","steps":["trace[320492897] 'range keys from in-memory index tree' (duration: 160.844908ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:10:47.737356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.024003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-19T04:10:47.737458Z","caller":"traceutil/trace.go:171","msg":"trace[1198874042] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5061; }","duration":"161.173265ms","start":"2026-05-19T04:10:47.576262Z","end":"2026-05-19T04:10:47.737436Z","steps":["trace[1198874042] 'range keys from in-memory index tree' (duration: 160.852489ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:10:59.84041Z","caller":"traceutil/trace.go:171","msg":"trace[447216214] transaction","detail":"{read_only:false; response_revision:5294; number_of_response:1; }","duration":"160.615995ms","start":"2026-05-19T04:10:59.679739Z","end":"2026-05-19T04:10:59.840355Z","steps":["trace[447216214] 'process raft request' (duration: 94.8023ms)","trace[447216214] 'compare' (duration: 65.455926ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:10:59.840762Z","caller":"traceutil/trace.go:171","msg":"trace[1392343026] transaction","detail":"{read_only:false; response_revision:5295; number_of_response:1; }","duration":"127.602276ms","start":"2026-05-19T04:10:59.71315Z","end":"2026-05-19T04:10:59.840752Z","steps":["trace[1392343026] 'process raft request' (duration: 126.975593ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:10:59.840868Z","caller":"traceutil/trace.go:171","msg":"trace[802941307] transaction","detail":"{read_only:false; response_revision:5296; number_of_response:1; }","duration":"127.381261ms","start":"2026-05-19T04:10:59.71348Z","end":"2026-05-19T04:10:59.840861Z","steps":["trace[802941307] 'process raft request' (duration: 126.993473ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:11:00.325706Z","caller":"traceutil/trace.go:171","msg":"trace[2141167415] transaction","detail":"{read_only:false; response_revision:5301; number_of_response:1; }","duration":"258.360035ms","start":"2026-05-19T04:11:00.067321Z","end":"2026-05-19T04:11:00.325681Z","steps":["trace[2141167415] 'process raft request' (duration: 258.213252ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:11:00.484501Z","caller":"traceutil/trace.go:171","msg":"trace[1270803903] linearizableReadLoop","detail":"{readStateIndex:5502; appliedIndex:5499; }","duration":"120.832887ms","start":"2026-05-19T04:11:00.363637Z","end":"2026-05-19T04:11:00.484469Z","steps":["trace[1270803903] 'read index received' (duration: 120.408137ms)","trace[1270803903] 'applied index is now lower than readState.Index' (duration: 423.05µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:11:00.48454Z","caller":"traceutil/trace.go:171","msg":"trace[904500989] transaction","detail":"{read_only:false; response_revision:5304; number_of_response:1; }","duration":"152.441507ms","start":"2026-05-19T04:11:00.332061Z","end":"2026-05-19T04:11:00.484503Z","steps":["trace[904500989] 'process raft request' (duration: 152.229892ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:11:00.484689Z","caller":"traceutil/trace.go:171","msg":"trace[1579372416] transaction","detail":"{read_only:false; response_revision:5305; number_of_response:1; }","duration":"151.57848ms","start":"2026-05-19T04:11:00.333095Z","end":"2026-05-19T04:11:00.484673Z","steps":["trace[1579372416] 'process raft request' (duration: 151.274003ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:11:00.484812Z","caller":"traceutil/trace.go:171","msg":"trace[2097702793] transaction","detail":"{read_only:false; response_revision:5303; number_of_response:1; }","duration":"152.979528ms","start":"2026-05-19T04:11:00.331788Z","end":"2026-05-19T04:11:00.484767Z","steps":["trace[2097702793] 'process raft request' (duration: 152.270742ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:11:00.48492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.257226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T04:11:00.484987Z","caller":"traceutil/trace.go:171","msg":"trace[1957177227] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:5305; }","duration":"121.390179ms","start":"2026-05-19T04:11:00.363577Z","end":"2026-05-19T04:11:00.484967Z","steps":["trace[1957177227] 'agreement among raft nodes before linearized reading' (duration: 121.264826ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:11:08.321102Z","caller":"traceutil/trace.go:171","msg":"trace[567935396] linearizableReadLoop","detail":"{readStateIndex:5689; appliedIndex:5688; }","duration":"180.535235ms","start":"2026-05-19T04:11:08.140538Z","end":"2026-05-19T04:11:08.321073Z","steps":["trace[567935396] 'read index received' (duration: 114.808983ms)","trace[567935396] 'applied index is now lower than readState.Index' (duration: 65.725102ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:11:08.321233Z","caller":"traceutil/trace.go:171","msg":"trace[1439940270] transaction","detail":"{read_only:false; response_revision:5487; number_of_response:1; }","duration":"200.174678ms","start":"2026-05-19T04:11:08.121021Z","end":"2026-05-19T04:11:08.321196Z","steps":["trace[1439940270] 'process raft request' (duration: 134.388804ms)","trace[1439940270] 'compare' (duration: 65.491418ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T04:11:08.321371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.80881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-62e80120-e544-4926-baa4-56134cdc4ae6\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-05-19T04:11:08.321414Z","caller":"traceutil/trace.go:171","msg":"trace[1265411376] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-62e80120-e544-4926-baa4-56134cdc4ae6; range_end:; response_count:1; response_revision:5487; }","duration":"180.917193ms","start":"2026-05-19T04:11:08.140484Z","end":"2026-05-19T04:11:08.321401Z","steps":["trace[1265411376] 'agreement among raft nodes before linearized reading' (duration: 180.743379ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:11:08.321529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.309831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-05-19T04:11:08.3216Z","caller":"traceutil/trace.go:171","msg":"trace[1720970520] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5487; }","duration":"111.440594ms","start":"2026-05-19T04:11:08.210143Z","end":"2026-05-19T04:11:08.321584Z","steps":["trace[1720970520] 'agreement among raft nodes before linearized reading' (duration: 111.24691ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:11:08.482634Z","caller":"traceutil/trace.go:171","msg":"trace[1479583803] transaction","detail":"{read_only:false; response_revision:5488; number_of_response:1; }","duration":"153.998549ms","start":"2026-05-19T04:11:08.3286Z","end":"2026-05-19T04:11:08.482598Z","steps":["trace[1479583803] 'process raft request' (duration: 153.545298ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:11:08.689381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.760129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:9 size:80055"} {"level":"info","ts":"2026-05-19T04:11:08.689479Z","caller":"traceutil/trace.go:171","msg":"trace[271670014] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:9; response_revision:5489; }","duration":"113.915563ms","start":"2026-05-19T04:11:08.575545Z","end":"2026-05-19T04:11:08.689461Z","steps":["trace[271670014] 'range keys from in-memory index tree' (duration: 113.416943ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:11:08.6895Z","caller":"traceutil/trace.go:171","msg":"trace[1227801738] transaction","detail":"{read_only:false; response_revision:5490; number_of_response:1; }","duration":"113.313851ms","start":"2026-05-19T04:11:08.576156Z","end":"2026-05-19T04:11:08.689469Z","steps":["trace[1227801738] 'process raft request' (duration: 99.009017ms)","trace[1227801738] 'compare' (duration: 14.145691ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:11:08.716318Z","caller":"traceutil/trace.go:171","msg":"trace[1004030839] transaction","detail":"{read_only:false; response_revision:5491; number_of_response:1; }","duration":"139.744945ms","start":"2026-05-19T04:11:08.576549Z","end":"2026-05-19T04:11:08.716294Z","steps":["trace[1004030839] 'process raft request' (duration: 139.562172ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:11:08.71663Z","caller":"traceutil/trace.go:171","msg":"trace[1147928509] transaction","detail":"{read_only:false; response_revision:5492; number_of_response:1; }","duration":"139.939639ms","start":"2026-05-19T04:11:08.576634Z","end":"2026-05-19T04:11:08.716574Z","steps":["trace[1147928509] 'process raft request' (duration: 139.619032ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:11:08.716714Z","caller":"traceutil/trace.go:171","msg":"trace[1343538982] transaction","detail":"{read_only:false; response_revision:5493; number_of_response:1; }","duration":"139.385448ms","start":"2026-05-19T04:11:08.577287Z","end":"2026-05-19T04:11:08.716672Z","steps":["trace[1343538982] 'process raft request' (duration: 139.206824ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:11:08.716877Z","caller":"traceutil/trace.go:171","msg":"trace[2023468918] transaction","detail":"{read_only:false; response_revision:5494; number_of_response:1; }","duration":"139.47744ms","start":"2026-05-19T04:11:08.577385Z","end":"2026-05-19T04:11:08.716862Z","steps":["trace[2023468918] 'process raft request' (duration: 139.243855ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:11:08.71689Z","caller":"traceutil/trace.go:171","msg":"trace[1122706236] linearizableReadLoop","detail":"{readStateIndex:5697; appliedIndex:5691; }","duration":"131.418494ms","start":"2026-05-19T04:11:08.585451Z","end":"2026-05-19T04:11:08.71687Z","steps":["trace[1122706236] 'read index received' (duration: 89.707226ms)","trace[1122706236] 'applied index is now lower than readState.Index' (duration: 41.709808ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:11:08.716972Z","caller":"traceutil/trace.go:171","msg":"trace[1290345575] transaction","detail":"{read_only:false; response_revision:5495; number_of_response:1; }","duration":"139.461869ms","start":"2026-05-19T04:11:08.577486Z","end":"2026-05-19T04:11:08.716948Z","steps":["trace[1290345575] 'process raft request' (duration: 139.276086ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:11:08.717064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.501728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T04:11:08.717113Z","caller":"traceutil/trace.go:171","msg":"trace[36137678] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5495; }","duration":"118.57378ms","start":"2026-05-19T04:11:08.598527Z","end":"2026-05-19T04:11:08.717101Z","steps":["trace[36137678] 'agreement among raft nodes before linearized reading' (duration: 118.474858ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:11:08.717112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.637598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T04:11:08.717158Z","caller":"traceutil/trace.go:171","msg":"trace[886211362] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:5495; }","duration":"131.72814ms","start":"2026-05-19T04:11:08.585419Z","end":"2026-05-19T04:11:08.717147Z","steps":["trace[886211362] 'agreement among raft nodes before linearized reading' (duration: 131.563467ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:12:19.347021Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3034} {"level":"info","ts":"2026-05-19T04:12:19.439173Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3034,"took":"90.182052ms","hash":436003037,"current-db-size-bytes":58744832,"current-db-size":"59 MB","current-db-size-in-use-bytes":31703040,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-19T04:12:19.439252Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":436003037,"revision":3034,"compact-revision":-1} {"level":"info","ts":"2026-05-19T04:17:19.354219Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6140} {"level":"info","ts":"2026-05-19T04:17:19.461388Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6140,"took":"104.608189ms","hash":2124586771,"current-db-size-bytes":58744832,"current-db-size":"59 MB","current-db-size-in-use-bytes":23797760,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-05-19T04:17:19.461467Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2124586771,"revision":6140,"compact-revision":3034} {"level":"warn","ts":"2026-05-19T04:17:22.98881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.984906ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T04:17:22.988919Z","caller":"traceutil/trace.go:171","msg":"trace[77563467] linearizableReadLoop","detail":"{readStateIndex:8646; appliedIndex:8645; }","duration":"120.354827ms","start":"2026-05-19T04:17:22.868546Z","end":"2026-05-19T04:17:22.988901Z","steps":["trace[77563467] 'read index received' (duration: 98.942µs)","trace[77563467] 'applied index is now lower than readState.Index' (duration: 120.254645ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:17:22.98906Z","caller":"traceutil/trace.go:171","msg":"trace[1841263683] transaction","detail":"{read_only:false; response_revision:8351; number_of_response:1; }","duration":"217.456975ms","start":"2026-05-19T04:17:22.77159Z","end":"2026-05-19T04:17:22.989047Z","steps":["trace[1841263683] 'process raft request' (duration: 62.123192ms)","trace[1841263683] 'compare' (duration: 154.780151ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T04:17:22.989369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.16766ms","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-19T04:17:22.989434Z","caller":"traceutil/trace.go:171","msg":"trace[355598255] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8351; }","duration":"101.246281ms","start":"2026-05-19T04:17:22.888173Z","end":"2026-05-19T04:17:22.98942Z","steps":["trace[355598255] 'agreement among raft nodes before linearized reading' (duration: 101.040747ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:17:22.989604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.081401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T04:17:22.989622Z","caller":"traceutil/trace.go:171","msg":"trace[281140414] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8351; }","duration":"121.144502ms","start":"2026-05-19T04:17:22.868472Z","end":"2026-05-19T04:17:22.989616Z","steps":["trace[281140414] 'agreement among raft nodes before linearized reading' (duration: 121.113112ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:17:35.599264Z","caller":"traceutil/trace.go:171","msg":"trace[699427428] transaction","detail":"{read_only:false; response_revision:8418; number_of_response:1; }","duration":"116.54602ms","start":"2026-05-19T04:17:35.482684Z","end":"2026-05-19T04:17:35.59923Z","steps":["trace[699427428] 'process raft request' (duration: 116.299055ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:17:36.469838Z","caller":"traceutil/trace.go:171","msg":"trace[1730453503] transaction","detail":"{read_only:false; response_revision:8422; number_of_response:1; }","duration":"107.673472ms","start":"2026-05-19T04:17:36.362133Z","end":"2026-05-19T04:17:36.469806Z","steps":["trace[1730453503] 'process raft request' (duration: 107.490458ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:18:05.596426Z","caller":"traceutil/trace.go:171","msg":"trace[638923842] transaction","detail":"{read_only:false; response_revision:8630; number_of_response:1; }","duration":"300.362807ms","start":"2026-05-19T04:18:05.296027Z","end":"2026-05-19T04:18:05.59639Z","steps":["trace[638923842] 'process raft request' (duration: 300.156183ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:18:05.596757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:18:05.296009Z","time spent":"300.580712ms","remote":"127.0.0.1:43890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T04:18:05.617495Z","caller":"traceutil/trace.go:171","msg":"trace[1572319736] linearizableReadLoop","detail":"{readStateIndex:8936; appliedIndex:8934; }","duration":"297.668064ms","start":"2026-05-19T04:18:05.319813Z","end":"2026-05-19T04:18:05.617481Z","steps":["trace[1572319736] 'read index received' (duration: 276.336143ms)","trace[1572319736] 'applied index is now lower than readState.Index' (duration: 21.33051ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:18:05.6176Z","caller":"traceutil/trace.go:171","msg":"trace[1318361103] transaction","detail":"{read_only:false; response_revision:8631; number_of_response:1; }","duration":"320.190528ms","start":"2026-05-19T04:18:05.297399Z","end":"2026-05-19T04:18:05.61759Z","steps":["trace[1318361103] 'process raft request' (duration: 319.993144ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:18:05.617669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:18:05.297362Z","time spent":"320.266689ms","remote":"127.0.0.1:43890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T04:18:05.617864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.060871ms","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-19T04:18:05.617904Z","caller":"traceutil/trace.go:171","msg":"trace[215639472] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8631; }","duration":"298.144733ms","start":"2026-05-19T04:18:05.319747Z","end":"2026-05-19T04:18:05.617892Z","steps":["trace[215639472] 'agreement among raft nodes before linearized reading' (duration: 298.00992ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:18:05.618065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.955828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-b7cvc\" ","response":"range_response_count:1 size:6451"} {"level":"info","ts":"2026-05-19T04:18:05.618098Z","caller":"traceutil/trace.go:171","msg":"trace[1879802038] range","detail":"{range_begin:/registry/pods/openstack/keepalived-b7cvc; range_end:; response_count:1; response_revision:8631; }","duration":"236.03625ms","start":"2026-05-19T04:18:05.382053Z","end":"2026-05-19T04:18:05.618089Z","steps":["trace[1879802038] 'agreement among raft nodes before linearized reading' (duration: 235.943618ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:18:05.61834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.718232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-05-19T04:18:05.618454Z","caller":"traceutil/trace.go:171","msg":"trace[1437171655] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:8631; }","duration":"230.875495ms","start":"2026-05-19T04:18:05.387551Z","end":"2026-05-19T04:18:05.618427Z","steps":["trace[1437171655] 'agreement among raft nodes before linearized reading' (duration: 230.61753ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:18:09.053989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.104582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T04:18:09.054101Z","caller":"traceutil/trace.go:171","msg":"trace[340808479] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8649; }","duration":"184.274085ms","start":"2026-05-19T04:18:08.869793Z","end":"2026-05-19T04:18:09.054068Z","steps":["trace[340808479] 'range keys from in-memory index tree' (duration: 184.01068ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:18:18.360438Z","caller":"traceutil/trace.go:171","msg":"trace[179403355] transaction","detail":"{read_only:false; response_revision:8740; number_of_response:1; }","duration":"101.607679ms","start":"2026-05-19T04:18:18.258737Z","end":"2026-05-19T04:18:18.360345Z","steps":["trace[179403355] 'process raft request' (duration: 61.96818ms)","trace[179403355] 'compare' (duration: 39.305322ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:18:19.072789Z","caller":"traceutil/trace.go:171","msg":"trace[99460583] transaction","detail":"{read_only:false; response_revision:8748; number_of_response:1; }","duration":"125.484461ms","start":"2026-05-19T04:18:18.947256Z","end":"2026-05-19T04:18:19.072741Z","steps":["trace[99460583] 'process raft request' (duration: 125.229706ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:18:19.349189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.277384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T04:18:19.349303Z","caller":"traceutil/trace.go:171","msg":"trace[1194091324] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:8748; }","duration":"117.5318ms","start":"2026-05-19T04:18:19.231747Z","end":"2026-05-19T04:18:19.349279Z","steps":["trace[1194091324] 'count revisions from in-memory index tree' (duration: 117.045029ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:18:19.349455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.265665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/prometheus-operated\" ","response":"range_response_count:1 size:993"} {"level":"info","ts":"2026-05-19T04:18:19.349577Z","caller":"traceutil/trace.go:171","msg":"trace[1499773296] range","detail":"{range_begin:/registry/services/specs/monitoring/prometheus-operated; range_end:; response_count:1; response_revision:8748; }","duration":"179.439018ms","start":"2026-05-19T04:18:19.170113Z","end":"2026-05-19T04:18:19.349552Z","steps":["trace[1499773296] 'range keys from in-memory index tree' (duration: 178.963848ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:18:32.584152Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023db.wal"} {"level":"info","ts":"2026-05-19T04:19:37.065296Z","caller":"traceutil/trace.go:171","msg":"trace[1407515763] transaction","detail":"{read_only:false; response_revision:9502; number_of_response:1; }","duration":"108.512509ms","start":"2026-05-19T04:19:36.956742Z","end":"2026-05-19T04:19:37.065254Z","steps":["trace[1407515763] 'process raft request' (duration: 79.251898ms)","trace[1407515763] 'compare' (duration: 29.091188ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T04:19:37.38308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.060563ms","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-19T04:19:37.383452Z","caller":"traceutil/trace.go:171","msg":"trace[197231091] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9502; }","duration":"134.499952ms","start":"2026-05-19T04:19:37.248927Z","end":"2026-05-19T04:19:37.383427Z","steps":["trace[197231091] 'range keys from in-memory index tree' (duration: 133.813539ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:19:57.037539Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"aab6040abcf11","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-19T04:19:57.042705Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-19T04:19:57.042824Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"warn","ts":"2026-05-19T04:21:18.773704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.616055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-05-19T04:21:18.773875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.405889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T04:21:18.773997Z","caller":"traceutil/trace.go:171","msg":"trace[1229472925] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:10203; }","duration":"157.602493ms","start":"2026-05-19T04:21:18.61637Z","end":"2026-05-19T04:21:18.773973Z","steps":["trace[1229472925] 'count revisions from in-memory index tree' (duration: 157.290406ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:21:18.773926Z","caller":"traceutil/trace.go:171","msg":"trace[1852191677] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:10203; }","duration":"101.884351ms","start":"2026-05-19T04:21:18.672019Z","end":"2026-05-19T04:21:18.773904Z","steps":["trace[1852191677] 'range keys from in-memory index tree' (duration: 101.450022ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:22:19.361269Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8330} {"level":"info","ts":"2026-05-19T04:22:19.526359Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8330,"took":"162.327058ms","hash":1764571009,"current-db-size-bytes":58744832,"current-db-size":"59 MB","current-db-size-in-use-bytes":24862720,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-05-19T04:22:19.526447Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1764571009,"revision":8330,"compact-revision":6140} {"level":"info","ts":"2026-05-19T04:25:18.413753Z","caller":"traceutil/trace.go:171","msg":"trace[469244571] transaction","detail":"{read_only:false; response_revision:11946; number_of_response:1; }","duration":"120.899524ms","start":"2026-05-19T04:25:18.292818Z","end":"2026-05-19T04:25:18.413718Z","steps":["trace[469244571] 'process raft request' (duration: 120.70242ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:25:18.440053Z","caller":"traceutil/trace.go:171","msg":"trace[1777976691] transaction","detail":"{read_only:false; response_revision:11947; number_of_response:1; }","duration":"132.213201ms","start":"2026-05-19T04:25:18.307796Z","end":"2026-05-19T04:25:18.44001Z","steps":["trace[1777976691] 'process raft request' (duration: 132.038797ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:27:19.369613Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10555} {"level":"info","ts":"2026-05-19T04:27:19.453236Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10555,"took":"80.740524ms","hash":1297538258,"current-db-size-bytes":58744832,"current-db-size":"59 MB","current-db-size-in-use-bytes":26095616,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-19T04:27:19.453339Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1297538258,"revision":10555,"compact-revision":8330} {"level":"info","ts":"2026-05-19T04:31:33.621261Z","caller":"traceutil/trace.go:171","msg":"trace[1894274676] transaction","detail":"{read_only:false; response_revision:14471; number_of_response:1; }","duration":"145.222929ms","start":"2026-05-19T04:31:33.476Z","end":"2026-05-19T04:31:33.621223Z","steps":["trace[1894274676] 'process raft request' (duration: 145.038215ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:31:48.974132Z","caller":"traceutil/trace.go:171","msg":"trace[533387284] linearizableReadLoop","detail":"{readStateIndex:15051; appliedIndex:15050; }","duration":"104.061644ms","start":"2026-05-19T04:31:48.870043Z","end":"2026-05-19T04:31:48.974104Z","steps":["trace[533387284] 'read index received' (duration: 103.87404ms)","trace[533387284] 'applied index is now lower than readState.Index' (duration: 186.164µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:31:48.974281Z","caller":"traceutil/trace.go:171","msg":"trace[964538942] transaction","detail":"{read_only:false; response_revision:14549; number_of_response:1; }","duration":"184.473046ms","start":"2026-05-19T04:31:48.789753Z","end":"2026-05-19T04:31:48.974226Z","steps":["trace[964538942] 'process raft request' (duration: 184.17826ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:31:48.974425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.451712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-19T04:31:48.974473Z","caller":"traceutil/trace.go:171","msg":"trace[700019286] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14549; }","duration":"102.568374ms","start":"2026-05-19T04:31:48.871888Z","end":"2026-05-19T04:31:48.974456Z","steps":["trace[700019286] 'agreement among raft nodes before linearized reading' (duration: 102.404191ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:31:48.974443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.34526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T04:31:48.974616Z","caller":"traceutil/trace.go:171","msg":"trace[1397152189] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14549; }","duration":"104.582655ms","start":"2026-05-19T04:31:48.869968Z","end":"2026-05-19T04:31:48.974551Z","steps":["trace[1397152189] 'agreement among raft nodes before linearized reading' (duration: 104.303209ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:32:13.300143Z","caller":"traceutil/trace.go:171","msg":"trace[2120958611] transaction","detail":"{read_only:false; response_revision:14887; number_of_response:1; }","duration":"186.342563ms","start":"2026-05-19T04:32:13.113767Z","end":"2026-05-19T04:32:13.300109Z","steps":["trace[2120958611] 'process raft request' (duration: 186.117658ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:32:13.433065Z","caller":"traceutil/trace.go:171","msg":"trace[724290450] transaction","detail":"{read_only:false; response_revision:14888; number_of_response:1; }","duration":"318.266094ms","start":"2026-05-19T04:32:13.114757Z","end":"2026-05-19T04:32:13.433023Z","steps":["trace[724290450] 'process raft request' (duration: 293.896837ms)","trace[724290450] 'compare' (duration: 24.152023ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T04:32:13.433266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:32:13.114732Z","time spent":"318.435797ms","remote":"127.0.0.1:43890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T04:32:13.433451Z","caller":"traceutil/trace.go:171","msg":"trace[255113388] linearizableReadLoop","detail":"{readStateIndex:15408; appliedIndex:15404; }","duration":"281.714183ms","start":"2026-05-19T04:32:13.15171Z","end":"2026-05-19T04:32:13.433424Z","steps":["trace[255113388] 'read index received' (duration: 148.24207ms)","trace[255113388] 'applied index is now lower than readState.Index' (duration: 133.470693ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:32:13.433367Z","caller":"traceutil/trace.go:171","msg":"trace[129985263] transaction","detail":"{read_only:false; response_revision:14889; number_of_response:1; }","duration":"295.645772ms","start":"2026-05-19T04:32:13.137685Z","end":"2026-05-19T04:32:13.43333Z","steps":["trace[129985263] 'process raft request' (duration: 295.278414ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:32:13.433683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.964888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3cc7d235-9f82-473e-b6f9-1b6dd8b37bd5\" ","response":"range_response_count:1 size:3637"} {"level":"info","ts":"2026-05-19T04:32:13.433665Z","caller":"traceutil/trace.go:171","msg":"trace[947723733] transaction","detail":"{read_only:false; response_revision:14890; number_of_response:1; }","duration":"291.775866ms","start":"2026-05-19T04:32:13.141726Z","end":"2026-05-19T04:32:13.433502Z","steps":["trace[947723733] 'process raft request' (duration: 291.465259ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:32:13.433793Z","caller":"traceutil/trace.go:171","msg":"trace[1613239252] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3cc7d235-9f82-473e-b6f9-1b6dd8b37bd5; range_end:; response_count:1; response_revision:14890; }","duration":"282.05346ms","start":"2026-05-19T04:32:13.151671Z","end":"2026-05-19T04:32:13.433724Z","steps":["trace[1613239252] 'agreement among raft nodes before linearized reading' (duration: 281.843516ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:32:19.377706Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12777} {"level":"info","ts":"2026-05-19T04:32:19.457008Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12777,"took":"76.007373ms","hash":1528318245,"current-db-size-bytes":58744832,"current-db-size":"59 MB","current-db-size-in-use-bytes":28536832,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-19T04:32:19.457076Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1528318245,"revision":12777,"compact-revision":10555} {"level":"warn","ts":"2026-05-19T04:33:40.716965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.436007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T04:33:40.717086Z","caller":"traceutil/trace.go:171","msg":"trace[1915474315] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:15702; }","duration":"300.58948ms","start":"2026-05-19T04:33:40.416471Z","end":"2026-05-19T04:33:40.717061Z","steps":["trace[1915474315] 'count revisions from in-memory index tree' (duration: 300.291035ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:33:40.717138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:33:40.416399Z","time spent":"300.727264ms","remote":"127.0.0.1:44642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":4,"response size":28,"request content":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true "} {"level":"info","ts":"2026-05-19T04:33:40.717328Z","caller":"traceutil/trace.go:171","msg":"trace[406180870] transaction","detail":"{read_only:false; response_revision:15703; number_of_response:1; }","duration":"270.696243ms","start":"2026-05-19T04:33:40.446593Z","end":"2026-05-19T04:33:40.717289Z","steps":["trace[406180870] 'process raft request' (duration: 218.819194ms)","trace[406180870] 'compare' (duration: 51.588713ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:33:40.773092Z","caller":"traceutil/trace.go:171","msg":"trace[937462358] linearizableReadLoop","detail":"{readStateIndex:16247; appliedIndex:16245; }","duration":"294.229573ms","start":"2026-05-19T04:33:40.478802Z","end":"2026-05-19T04:33:40.773031Z","steps":["trace[937462358] 'read index received' (duration: 186.627908ms)","trace[937462358] 'applied index is now lower than readState.Index' (duration: 107.600325ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:33:40.773177Z","caller":"traceutil/trace.go:171","msg":"trace[855657322] transaction","detail":"{read_only:false; response_revision:15704; number_of_response:1; }","duration":"319.698244ms","start":"2026-05-19T04:33:40.453419Z","end":"2026-05-19T04:33:40.773118Z","steps":["trace[855657322] 'process raft request' (duration: 319.427309ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:33:40.77328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.446467ms","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-19T04:33:40.773328Z","caller":"traceutil/trace.go:171","msg":"trace[993975055] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15704; }","duration":"294.519789ms","start":"2026-05-19T04:33:40.478794Z","end":"2026-05-19T04:33:40.773314Z","steps":["trace[993975055] 'agreement among raft nodes before linearized reading' (duration: 294.423747ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:33:40.773545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.367166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"warn","ts":"2026-05-19T04:33:40.773586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.846652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-19T04:33:40.773664Z","caller":"traceutil/trace.go:171","msg":"trace[97963881] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15704; }","duration":"294.56ms","start":"2026-05-19T04:33:40.47908Z","end":"2026-05-19T04:33:40.77364Z","steps":["trace[97963881] 'agreement among raft nodes before linearized reading' (duration: 294.214043ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:33:40.773726Z","caller":"traceutil/trace.go:171","msg":"trace[1586154792] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15704; }","duration":"183.039475ms","start":"2026-05-19T04:33:40.590657Z","end":"2026-05-19T04:33:40.773696Z","steps":["trace[1586154792] 'agreement among raft nodes before linearized reading' (duration: 182.680368ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:33:40.773615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.868215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"info","ts":"2026-05-19T04:33:40.773834Z","caller":"traceutil/trace.go:171","msg":"trace[873932162] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15704; }","duration":"282.092779ms","start":"2026-05-19T04:33:40.491713Z","end":"2026-05-19T04:33:40.773805Z","steps":["trace[873932162] 'agreement among raft nodes before linearized reading' (duration: 281.733492ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:33:40.77417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:33:40.453391Z","time spent":"320.221524ms","remote":"127.0.0.1:43890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T04:37:19.384644Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14945} {"level":"info","ts":"2026-05-19T04:37:19.479438Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14945,"took":"92.181907ms","hash":774280868,"current-db-size-bytes":58744832,"current-db-size":"59 MB","current-db-size-in-use-bytes":30261248,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-19T04:37:19.479531Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":774280868,"revision":14945,"compact-revision":12777} {"level":"info","ts":"2026-05-19T04:39:27.151386Z","caller":"traceutil/trace.go:171","msg":"trace[900743396] transaction","detail":"{read_only:false; response_revision:18279; number_of_response:1; }","duration":"168.226686ms","start":"2026-05-19T04:39:26.983129Z","end":"2026-05-19T04:39:27.151355Z","steps":["trace[900743396] 'process raft request' (duration: 168.064053ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:39:33.013617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.595557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-ssj87\" ","response":"range_response_count:1 size:20778"} {"level":"info","ts":"2026-05-19T04:39:33.013742Z","caller":"traceutil/trace.go:171","msg":"trace[1425805826] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-ssj87; range_end:; response_count:1; response_revision:18311; }","duration":"135.787611ms","start":"2026-05-19T04:39:32.877928Z","end":"2026-05-19T04:39:33.013716Z","steps":["trace[1425805826] 'range keys from in-memory index tree' (duration: 135.331321ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:39:33.158068Z","caller":"traceutil/trace.go:171","msg":"trace[994841964] transaction","detail":"{read_only:false; response_revision:18312; number_of_response:1; }","duration":"125.494455ms","start":"2026-05-19T04:39:33.032533Z","end":"2026-05-19T04:39:33.158028Z","steps":["trace[994841964] 'process raft request' (duration: 125.261501ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:39:33.374007Z","caller":"traceutil/trace.go:171","msg":"trace[1294010667] transaction","detail":"{read_only:false; response_revision:18315; number_of_response:1; }","duration":"128.427485ms","start":"2026-05-19T04:39:33.245551Z","end":"2026-05-19T04:39:33.373978Z","steps":["trace[1294010667] 'process raft request' (duration: 128.383594ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:39:33.374098Z","caller":"traceutil/trace.go:171","msg":"trace[2125565596] transaction","detail":"{read_only:false; response_revision:18314; number_of_response:1; }","duration":"132.484586ms","start":"2026-05-19T04:39:33.241568Z","end":"2026-05-19T04:39:33.374052Z","steps":["trace[2125565596] 'process raft request' (duration: 132.316402ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:39:33.374098Z","caller":"traceutil/trace.go:171","msg":"trace[1646254718] transaction","detail":"{read_only:false; response_revision:18313; number_of_response:1; }","duration":"195.334396ms","start":"2026-05-19T04:39:33.178713Z","end":"2026-05-19T04:39:33.374048Z","steps":["trace[1646254718] 'process raft request' (duration: 155.968342ms)","trace[1646254718] 'compare' (duration: 39.050226ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:41:15.824548Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"aab6040abcf11","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-19T04:41:15.844175Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-19T04:41:15.844322Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-19T04:42:19.391345Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17397} {"level":"info","ts":"2026-05-19T04:42:19.494399Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17397,"took":"99.287635ms","hash":489791736,"current-db-size-bytes":58744832,"current-db-size":"59 MB","current-db-size-in-use-bytes":34648064,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-05-19T04:42:19.494467Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":489791736,"revision":17397,"compact-revision":14945} {"level":"info","ts":"2026-05-19T04:45:10.320058Z","caller":"traceutil/trace.go:171","msg":"trace[1587505145] transaction","detail":"{read_only:false; response_revision:20944; number_of_response:1; }","duration":"207.631168ms","start":"2026-05-19T04:45:10.112391Z","end":"2026-05-19T04:45:10.320022Z","steps":["trace[1587505145] 'process raft request' (duration: 207.442314ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:45:10.923043Z","caller":"traceutil/trace.go:171","msg":"trace[615758883] transaction","detail":"{read_only:false; response_revision:20947; number_of_response:1; }","duration":"186.62258ms","start":"2026-05-19T04:45:10.736394Z","end":"2026-05-19T04:45:10.923017Z","steps":["trace[615758883] 'process raft request' (duration: 186.511598ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:45:10.923033Z","caller":"traceutil/trace.go:171","msg":"trace[1596255051] transaction","detail":"{read_only:false; response_revision:20946; number_of_response:1; }","duration":"189.445806ms","start":"2026-05-19T04:45:10.73354Z","end":"2026-05-19T04:45:10.922986Z","steps":["trace[1596255051] 'process raft request' (duration: 176.58891ms)","trace[1596255051] 'compare' (duration: 12.452288ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:47:19.398262Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19771} {"level":"info","ts":"2026-05-19T04:47:19.513165Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19771,"took":"109.921472ms","hash":1621851915,"current-db-size-bytes":58744832,"current-db-size":"59 MB","current-db-size-in-use-bytes":29487104,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-19T04:47:19.513246Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1621851915,"revision":19771,"compact-revision":17397} {"level":"info","ts":"2026-05-19T04:49:17.54452Z","caller":"traceutil/trace.go:171","msg":"trace[313978890] transaction","detail":"{read_only:false; response_revision:23436; number_of_response:1; }","duration":"110.963441ms","start":"2026-05-19T04:49:17.433527Z","end":"2026-05-19T04:49:17.54449Z","steps":["trace[313978890] 'process raft request' (duration: 110.680985ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:49:18.420231Z","caller":"traceutil/trace.go:171","msg":"trace[2039885326] transaction","detail":"{read_only:false; response_revision:23454; number_of_response:1; }","duration":"532.858301ms","start":"2026-05-19T04:49:17.887328Z","end":"2026-05-19T04:49:18.420186Z","steps":["trace[2039885326] 'process raft request' (duration: 518.093577ms)","trace[2039885326] 'compare' (duration: 13.884127ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T04:49:18.420425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:49:17.887306Z","time spent":"533.018474ms","remote":"127.0.0.1:43890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T04:49:18.42948Z","caller":"traceutil/trace.go:171","msg":"trace[898674810] transaction","detail":"{read_only:false; response_revision:23455; number_of_response:1; }","duration":"399.683997ms","start":"2026-05-19T04:49:18.029779Z","end":"2026-05-19T04:49:18.429463Z","steps":["trace[898674810] 'process raft request' (duration: 399.553384ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:49:18.429592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:49:18.029742Z","time spent":"399.789279ms","remote":"127.0.0.1:43890","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-19T04:49:18.429707Z","caller":"traceutil/trace.go:171","msg":"trace[896227901] linearizableReadLoop","detail":"{readStateIndex:24254; appliedIndex:24252; }","duration":"356.425644ms","start":"2026-05-19T04:49:18.073214Z","end":"2026-05-19T04:49:18.429639Z","steps":["trace[896227901] 'read index received' (duration: 332.325824ms)","trace[896227901] 'applied index is now lower than readState.Index' (duration: 24.09716ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:49:18.429778Z","caller":"traceutil/trace.go:171","msg":"trace[1245455809] transaction","detail":"{read_only:false; response_revision:23456; number_of_response:1; }","duration":"215.808092ms","start":"2026-05-19T04:49:18.213959Z","end":"2026-05-19T04:49:18.429767Z","steps":["trace[1245455809] 'process raft request' (duration: 215.461965ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:49:18.430011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.810632ms","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-19T04:49:18.430049Z","caller":"traceutil/trace.go:171","msg":"trace[788163237] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:23456; }","duration":"356.912994ms","start":"2026-05-19T04:49:18.073126Z","end":"2026-05-19T04:49:18.430039Z","steps":["trace[788163237] 'agreement among raft nodes before linearized reading' (duration: 356.72491ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:49:18.430077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:49:18.073093Z","time spent":"356.977354ms","remote":"127.0.0.1:43890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-19T04:49:18.430011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.68399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" ","response":"range_response_count:1 size:626"} {"level":"info","ts":"2026-05-19T04:49:18.430151Z","caller":"traceutil/trace.go:171","msg":"trace[896080777] range","detail":"{range_begin:/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi; range_end:; response_count:1; response_revision:23456; }","duration":"347.868414ms","start":"2026-05-19T04:49:18.082266Z","end":"2026-05-19T04:49:18.430134Z","steps":["trace[896080777] 'agreement among raft nodes before linearized reading' (duration: 347.610219ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:49:18.430189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:49:18.082252Z","time spent":"347.927105ms","remote":"127.0.0.1:43890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":1,"response size":647,"request content":"key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" "} {"level":"info","ts":"2026-05-19T04:49:19.165281Z","caller":"traceutil/trace.go:171","msg":"trace[117509258] transaction","detail":"{read_only:false; response_revision:23457; number_of_response:1; }","duration":"730.214775ms","start":"2026-05-19T04:49:18.435031Z","end":"2026-05-19T04:49:19.165246Z","steps":["trace[117509258] 'process raft request' (duration: 723.46418ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:49:19.165458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:49:18.435007Z","time spent":"730.355908ms","remote":"127.0.0.1:43890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T04:49:19.554542Z","caller":"traceutil/trace.go:171","msg":"trace[825615797] transaction","detail":"{read_only:false; response_revision:23459; number_of_response:1; }","duration":"1.093067897s","start":"2026-05-19T04:49:18.461439Z","end":"2026-05-19T04:49:19.554507Z","steps":["trace[825615797] 'process raft request' (duration: 1.092950905s)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:49:19.554562Z","caller":"traceutil/trace.go:171","msg":"trace[2023451886] transaction","detail":"{read_only:false; response_revision:23458; number_of_response:1; }","duration":"1.101136218s","start":"2026-05-19T04:49:18.453391Z","end":"2026-05-19T04:49:19.554527Z","steps":["trace[2023451886] 'process raft request' (duration: 1.004483481s)","trace[2023451886] 'compare' (duration: 96.29618ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:49:19.554646Z","caller":"traceutil/trace.go:171","msg":"trace[543078781] transaction","detail":"{read_only:false; response_revision:23460; number_of_response:1; }","duration":"306.358717ms","start":"2026-05-19T04:49:19.248262Z","end":"2026-05-19T04:49:19.554621Z","steps":["trace[543078781] 'process raft request' (duration: 306.240295ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:49:19.554699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:49:18.461424Z","time spent":"1.09319421s","remote":"127.0.0.1:43890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T04:49:19.55471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:49:18.453355Z","time spent":"1.101274401s","remote":"127.0.0.1:43890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T04:49:19.554722Z","caller":"traceutil/trace.go:171","msg":"trace[1936883365] transaction","detail":"{read_only:false; response_revision:23461; number_of_response:1; }","duration":"293.124742ms","start":"2026-05-19T04:49:19.261565Z","end":"2026-05-19T04:49:19.55469Z","steps":["trace[1936883365] 'process raft request' (duration: 293.02013ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:49:19.554775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:49:19.24823Z","time spent":"306.47488ms","remote":"127.0.0.1:43890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T04:49:19.554817Z","caller":"traceutil/trace.go:171","msg":"trace[788813725] linearizableReadLoop","detail":"{readStateIndex:24258; appliedIndex:24255; }","duration":"862.972071ms","start":"2026-05-19T04:49:18.691837Z","end":"2026-05-19T04:49:19.554809Z","steps":["trace[788813725] 'read index received' (duration: 466.785643ms)","trace[788813725] 'applied index is now lower than readState.Index' (duration: 396.185418ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T04:49:19.554957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"863.125924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-19T04:49:19.554983Z","caller":"traceutil/trace.go:171","msg":"trace[196552383] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:23461; }","duration":"863.197125ms","start":"2026-05-19T04:49:18.691779Z","end":"2026-05-19T04:49:19.554977Z","steps":["trace[196552383] 'agreement among raft nodes before linearized reading' (duration: 863.082513ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:49:19.555002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:49:18.691756Z","time spent":"863.241756ms","remote":"127.0.0.1:44078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":18,"response size":29,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-05-19T04:49:19.555025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"859.516063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-19T04:49:19.555056Z","caller":"traceutil/trace.go:171","msg":"trace[467944532] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:23461; }","duration":"859.593954ms","start":"2026-05-19T04:49:18.695452Z","end":"2026-05-19T04:49:19.555046Z","steps":["trace[467944532] 'agreement among raft nodes before linearized reading' (duration: 859.447882ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:49:19.555078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:49:18.695444Z","time spent":"859.627675ms","remote":"127.0.0.1:43890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-19T04:49:19.555174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.690905ms","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-19T04:49:19.555213Z","caller":"traceutil/trace.go:171","msg":"trace[1696318642] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23461; }","duration":"577.780726ms","start":"2026-05-19T04:49:18.977423Z","end":"2026-05-19T04:49:19.555203Z","steps":["trace[1696318642] 'agreement among raft nodes before linearized reading' (duration: 577.618523ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:49:19.555218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"825.178329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-19T04:49:19.555233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.897979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T04:49:19.555244Z","caller":"traceutil/trace.go:171","msg":"trace[426057198] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:23461; }","duration":"825.201729ms","start":"2026-05-19T04:49:18.730033Z","end":"2026-05-19T04:49:19.555234Z","steps":["trace[426057198] 'agreement among raft nodes before linearized reading' (duration: 825.165088ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:49:19.55526Z","caller":"traceutil/trace.go:171","msg":"trace[1258637303] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:23461; }","duration":"280.97156ms","start":"2026-05-19T04:49:19.27428Z","end":"2026-05-19T04:49:19.555251Z","steps":["trace[1258637303] 'agreement among raft nodes before linearized reading' (duration: 280.92696ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:49:19.555271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:49:18.729997Z","time spent":"825.26687ms","remote":"127.0.0.1:55682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-05-19T04:49:19.555308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.014605ms","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-19T04:49:19.555335Z","caller":"traceutil/trace.go:171","msg":"trace[1156404005] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23461; }","duration":"511.103357ms","start":"2026-05-19T04:49:19.044223Z","end":"2026-05-19T04:49:19.555326Z","steps":["trace[1156404005] 'agreement among raft nodes before linearized reading' (duration: 511.012535ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:49:19.555353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:49:19.044194Z","time spent":"511.154168ms","remote":"127.0.0.1:43890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-19T04:49:19.555244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:49:18.977396Z","time spent":"577.838287ms","remote":"127.0.0.1:43890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-19T04:49:19.555184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.637489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-19T04:49:19.555448Z","caller":"traceutil/trace.go:171","msg":"trace[2141784172] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:23461; }","duration":"654.942585ms","start":"2026-05-19T04:49:18.900497Z","end":"2026-05-19T04:49:19.555439Z","steps":["trace[2141784172] 'agreement among raft nodes before linearized reading' (duration: 654.624899ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:49:19.555478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:49:18.900468Z","time spent":"655.001526ms","remote":"127.0.0.1:44072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":52,"response size":29,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"info","ts":"2026-05-19T04:52:19.405675Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21981} {"level":"info","ts":"2026-05-19T04:52:19.520216Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21981,"took":"110.23261ms","hash":3198211329,"current-db-size-bytes":58744832,"current-db-size":"59 MB","current-db-size-in-use-bytes":42696704,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-05-19T04:52:19.520279Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3198211329,"revision":21981,"compact-revision":19771} {"level":"info","ts":"2026-05-19T04:52:59.832964Z","caller":"traceutil/trace.go:171","msg":"trace[1811908367] transaction","detail":"{read_only:false; response_revision:25659; number_of_response:1; }","duration":"130.829523ms","start":"2026-05-19T04:52:59.70211Z","end":"2026-05-19T04:52:59.832939Z","steps":["trace[1811908367] 'process raft request' (duration: 130.653069ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:53:00.108317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.472928ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T04:53:00.108425Z","caller":"traceutil/trace.go:171","msg":"trace[778639570] linearizableReadLoop","detail":"{readStateIndex:26515; appliedIndex:26514; }","duration":"238.703297ms","start":"2026-05-19T04:52:59.869702Z","end":"2026-05-19T04:53:00.108406Z","steps":["trace[778639570] 'read index received' (duration: 87.087056ms)","trace[778639570] 'applied index is now lower than readState.Index' (duration: 151.614911ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:53:00.108487Z","caller":"traceutil/trace.go:171","msg":"trace[181100041] transaction","detail":"{read_only:false; response_revision:25660; number_of_response:1; }","duration":"371.464689ms","start":"2026-05-19T04:52:59.736992Z","end":"2026-05-19T04:53:00.108457Z","steps":["trace[181100041] 'process raft request' (duration: 219.765776ms)","trace[181100041] 'compare' (duration: 151.280734ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T04:53:00.108533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.834419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T04:53:00.108566Z","caller":"traceutil/trace.go:171","msg":"trace[1482062537] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25660; }","duration":"238.903791ms","start":"2026-05-19T04:52:59.869652Z","end":"2026-05-19T04:53:00.108556Z","steps":["trace[1482062537] 'agreement among raft nodes before linearized reading' (duration: 238.84299ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:53:00.108645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:52:59.736955Z","time spent":"371.600872ms","remote":"127.0.0.1:43890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T04:53:04.34722Z","caller":"traceutil/trace.go:171","msg":"trace[2049616053] transaction","detail":"{read_only:false; response_revision:25697; number_of_response:1; }","duration":"112.473255ms","start":"2026-05-19T04:53:04.234723Z","end":"2026-05-19T04:53:04.347196Z","steps":["trace[2049616053] 'process raft request' (duration: 107.403413ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:53:04.575208Z","caller":"traceutil/trace.go:171","msg":"trace[335381702] transaction","detail":"{read_only:false; response_revision:25700; number_of_response:1; }","duration":"109.692419ms","start":"2026-05-19T04:53:04.46549Z","end":"2026-05-19T04:53:04.575182Z","steps":["trace[335381702] 'process raft request' (duration: 109.647298ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:53:04.575465Z","caller":"traceutil/trace.go:171","msg":"trace[382273212] transaction","detail":"{read_only:false; response_revision:25698; number_of_response:1; }","duration":"190.914229ms","start":"2026-05-19T04:53:04.384538Z","end":"2026-05-19T04:53:04.575452Z","steps":["trace[382273212] 'process raft request' (duration: 126.023077ms)","trace[382273212] 'compare' (duration: 64.410082ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:53:04.575641Z","caller":"traceutil/trace.go:171","msg":"trace[1788922145] linearizableReadLoop","detail":"{readStateIndex:26554; appliedIndex:26553; }","duration":"189.627132ms","start":"2026-05-19T04:53:04.385972Z","end":"2026-05-19T04:53:04.575599Z","steps":["trace[1788922145] 'read index received' (duration: 124.771641ms)","trace[1788922145] 'applied index is now lower than readState.Index' (duration: 64.854161ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:53:04.575648Z","caller":"traceutil/trace.go:171","msg":"trace[746294801] transaction","detail":"{read_only:false; response_revision:25699; number_of_response:1; }","duration":"140.64499ms","start":"2026-05-19T04:53:04.43494Z","end":"2026-05-19T04:53:04.575585Z","steps":["trace[746294801] 'process raft request' (duration: 140.157171ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:53:04.575904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.922368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-f96930fe-6379-4c64-89f1-5347f5d765e0\" ","response":"range_response_count:1 size:1276"} {"level":"warn","ts":"2026-05-19T04:53:04.575913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.721414ms","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-19T04:53:04.575952Z","caller":"traceutil/trace.go:171","msg":"trace[1140221275] range","detail":"{range_begin:/registry/persistentvolumes/pvc-f96930fe-6379-4c64-89f1-5347f5d765e0; range_end:; response_count:1; response_revision:25700; }","duration":"190.02201ms","start":"2026-05-19T04:53:04.385915Z","end":"2026-05-19T04:53:04.575937Z","steps":["trace[1140221275] 'agreement among raft nodes before linearized reading' (duration: 189.814126ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:53:04.575954Z","caller":"traceutil/trace.go:171","msg":"trace[1303618677] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25700; }","duration":"171.829955ms","start":"2026-05-19T04:53:04.404114Z","end":"2026-05-19T04:53:04.575944Z","steps":["trace[1303618677] 'agreement among raft nodes before linearized reading' (duration: 171.664642ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:53:04.986782Z","caller":"traceutil/trace.go:171","msg":"trace[568210276] transaction","detail":"{read_only:false; response_revision:25702; number_of_response:1; }","duration":"191.771705ms","start":"2026-05-19T04:53:04.794979Z","end":"2026-05-19T04:53:04.986751Z","steps":["trace[568210276] 'process raft request' (duration: 191.140853ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:53:05.198996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.965797ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T04:53:05.199089Z","caller":"traceutil/trace.go:171","msg":"trace[1405795673] transaction","detail":"{read_only:false; response_revision:25703; number_of_response:1; }","duration":"207.481161ms","start":"2026-05-19T04:53:04.991592Z","end":"2026-05-19T04:53:05.199073Z","steps":["trace[1405795673] 'process raft request' (duration: 59.366601ms)","trace[1405795673] 'compare' (duration: 147.853015ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:53:05.30096Z","caller":"traceutil/trace.go:171","msg":"trace[240403734] transaction","detail":"{read_only:false; response_revision:25705; number_of_response:1; }","duration":"309.039507ms","start":"2026-05-19T04:53:04.991892Z","end":"2026-05-19T04:53:05.300932Z","steps":["trace[240403734] 'process raft request' (duration: 308.945395ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:53:05.301095Z","caller":"traceutil/trace.go:171","msg":"trace[1499449378] transaction","detail":"{read_only:false; response_revision:25704; number_of_response:1; }","duration":"309.268471ms","start":"2026-05-19T04:53:04.991761Z","end":"2026-05-19T04:53:05.301029Z","steps":["trace[1499449378] 'process raft request' (duration: 308.258151ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:53:05.301361Z","caller":"traceutil/trace.go:171","msg":"trace[633201438] transaction","detail":"{read_only:false; response_revision:25707; number_of_response:1; }","duration":"305.795292ms","start":"2026-05-19T04:53:04.995535Z","end":"2026-05-19T04:53:05.30133Z","steps":["trace[633201438] 'process raft request' (duration: 305.623248ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:53:05.301409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:53:04.991728Z","time spent":"309.507106ms","remote":"127.0.0.1:43890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T04:53:05.301577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:53:04.995532Z","time spent":"305.922754ms","remote":"127.0.0.1:43890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T04:53:05.301629Z","caller":"traceutil/trace.go:171","msg":"trace[1878269664] transaction","detail":"{read_only:false; response_revision:25706; number_of_response:1; }","duration":"306.119058ms","start":"2026-05-19T04:53:04.995492Z","end":"2026-05-19T04:53:05.301611Z","steps":["trace[1878269664] 'process raft request' (duration: 305.399134ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:53:05.301148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:53:04.991882Z","time spent":"309.164279ms","remote":"127.0.0.1:43890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T04:53:05.301757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:53:04.995485Z","time spent":"306.2023ms","remote":"127.0.0.1:43890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T04:53:06.016602Z","caller":"traceutil/trace.go:171","msg":"trace[159053209] linearizableReadLoop","detail":"{readStateIndex:26566; appliedIndex:26565; }","duration":"147.721073ms","start":"2026-05-19T04:53:05.868846Z","end":"2026-05-19T04:53:06.016567Z","steps":["trace[159053209] 'read index received' (duration: 147.404767ms)","trace[159053209] 'applied index is now lower than readState.Index' (duration: 315.126µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T04:53:06.017134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.719431ms","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-19T04:53:06.017202Z","caller":"traceutil/trace.go:171","msg":"trace[1037236774] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:25710; }","duration":"123.856803ms","start":"2026-05-19T04:53:05.893329Z","end":"2026-05-19T04:53:06.017186Z","steps":["trace[1037236774] 'agreement among raft nodes before linearized reading' (duration: 123.71179ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:53:06.017202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.325615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T04:53:06.01732Z","caller":"traceutil/trace.go:171","msg":"trace[892689662] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25710; }","duration":"148.536439ms","start":"2026-05-19T04:53:05.86876Z","end":"2026-05-19T04:53:06.017296Z","steps":["trace[892689662] 'agreement among raft nodes before linearized reading' (duration: 148.321995ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:53:06.017632Z","caller":"traceutil/trace.go:171","msg":"trace[685281847] transaction","detail":"{read_only:false; response_revision:25710; number_of_response:1; }","duration":"166.239524ms","start":"2026-05-19T04:53:05.851375Z","end":"2026-05-19T04:53:06.017615Z","steps":["trace[685281847] 'process raft request' (duration: 164.954958ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:53:06.01806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.079038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-19T04:53:06.018121Z","caller":"traceutil/trace.go:171","msg":"trace[53294843] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:25710; }","duration":"116.142219ms","start":"2026-05-19T04:53:05.901964Z","end":"2026-05-19T04:53:06.018106Z","steps":["trace[53294843] 'agreement among raft nodes before linearized reading' (duration: 115.998176ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:53:06.188397Z","caller":"traceutil/trace.go:171","msg":"trace[413782297] transaction","detail":"{read_only:false; response_revision:25711; number_of_response:1; }","duration":"164.964838ms","start":"2026-05-19T04:53:06.023396Z","end":"2026-05-19T04:53:06.188361Z","steps":["trace[413782297] 'process raft request' (duration: 73.775419ms)","trace[413782297] 'compare' (duration: 90.745841ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:53:06.383342Z","caller":"traceutil/trace.go:171","msg":"trace[1400455886] transaction","detail":"{read_only:false; response_revision:25712; number_of_response:1; }","duration":"253.546395ms","start":"2026-05-19T04:53:06.129758Z","end":"2026-05-19T04:53:06.383304Z","steps":["trace[1400455886] 'process raft request' (duration: 253.310029ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:53:06.773669Z","caller":"traceutil/trace.go:171","msg":"trace[748611920] transaction","detail":"{read_only:false; response_revision:25714; number_of_response:1; }","duration":"191.895468ms","start":"2026-05-19T04:53:06.581737Z","end":"2026-05-19T04:53:06.773632Z","steps":["trace[748611920] 'process raft request' (duration: 191.672164ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:53:06.79085Z","caller":"traceutil/trace.go:171","msg":"trace[878100238] linearizableReadLoop","detail":"{readStateIndex:26572; appliedIndex:26569; }","duration":"183.251784ms","start":"2026-05-19T04:53:06.607571Z","end":"2026-05-19T04:53:06.790822Z","steps":["trace[878100238] 'read index received' (duration: 165.808994ms)","trace[878100238] 'applied index is now lower than readState.Index' (duration: 17.44171ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:53:06.790934Z","caller":"traceutil/trace.go:171","msg":"trace[556280222] transaction","detail":"{read_only:false; response_revision:25715; number_of_response:1; }","duration":"207.93857ms","start":"2026-05-19T04:53:06.582968Z","end":"2026-05-19T04:53:06.790907Z","steps":["trace[556280222] 'process raft request' (duration: 207.611933ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:53:06.790997Z","caller":"traceutil/trace.go:171","msg":"trace[1420509463] transaction","detail":"{read_only:false; response_revision:25716; number_of_response:1; }","duration":"207.919529ms","start":"2026-05-19T04:53:06.583064Z","end":"2026-05-19T04:53:06.790984Z","steps":["trace[1420509463] 'process raft request' (duration: 207.711335ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:53:06.791422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.821495ms","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-19T04:53:06.791533Z","caller":"traceutil/trace.go:171","msg":"trace[152951490] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25716; }","duration":"183.994548ms","start":"2026-05-19T04:53:06.607515Z","end":"2026-05-19T04:53:06.791509Z","steps":["trace[152951490] 'agreement among raft nodes before linearized reading' (duration: 183.691392ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:53:06.985765Z","caller":"traceutil/trace.go:171","msg":"trace[1168979971] transaction","detail":"{read_only:false; response_revision:25718; number_of_response:1; }","duration":"185.987341ms","start":"2026-05-19T04:53:06.799735Z","end":"2026-05-19T04:53:06.985723Z","steps":["trace[1168979971] 'process raft request' (duration: 171.122892ms)","trace[1168979971] 'compare' (duration: 14.641054ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:53:06.987751Z","caller":"traceutil/trace.go:171","msg":"trace[410641939] linearizableReadLoop","detail":"{readStateIndex:26575; appliedIndex:26573; }","duration":"172.380906ms","start":"2026-05-19T04:53:06.815358Z","end":"2026-05-19T04:53:06.987739Z","steps":["trace[410641939] 'read index received' (duration: 155.515408ms)","trace[410641939] 'applied index is now lower than readState.Index' (duration: 16.858188ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:53:06.987976Z","caller":"traceutil/trace.go:171","msg":"trace[594259369] transaction","detail":"{read_only:false; response_revision:25719; number_of_response:1; }","duration":"179.51954ms","start":"2026-05-19T04:53:06.808448Z","end":"2026-05-19T04:53:06.987968Z","steps":["trace[594259369] 'process raft request' (duration: 179.191623ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:53:06.988181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.807835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-05-19T04:53:06.988237Z","caller":"traceutil/trace.go:171","msg":"trace[543030387] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25719; }","duration":"172.855055ms","start":"2026-05-19T04:53:06.815349Z","end":"2026-05-19T04:53:06.988204Z","steps":["trace[543030387] 'agreement among raft nodes before linearized reading' (duration: 172.720523ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:53:06.988744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.452426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T04:53:06.988779Z","caller":"traceutil/trace.go:171","msg":"trace[1056026531] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25719; }","duration":"119.538238ms","start":"2026-05-19T04:53:06.869233Z","end":"2026-05-19T04:53:06.988771Z","steps":["trace[1056026531] 'agreement among raft nodes before linearized reading' (duration: 119.476566ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:53:06.988825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.620009ms","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-19T04:53:06.988941Z","caller":"traceutil/trace.go:171","msg":"trace[173311613] 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:25719; }","duration":"132.819883ms","start":"2026-05-19T04:53:06.856095Z","end":"2026-05-19T04:53:06.988915Z","steps":["trace[173311613] 'agreement among raft nodes before linearized reading' (duration: 132.595499ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:53:07.182332Z","caller":"traceutil/trace.go:171","msg":"trace[1754061857] linearizableReadLoop","detail":"{readStateIndex:26577; appliedIndex:26576; }","duration":"106.504176ms","start":"2026-05-19T04:53:07.075785Z","end":"2026-05-19T04:53:07.182289Z","steps":["trace[1754061857] 'read index received' (duration: 96.685939ms)","trace[1754061857] 'applied index is now lower than readState.Index' (duration: 9.808477ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:53:07.182369Z","caller":"traceutil/trace.go:171","msg":"trace[1901257973] transaction","detail":"{read_only:false; response_revision:25721; number_of_response:1; }","duration":"142.754173ms","start":"2026-05-19T04:53:07.039571Z","end":"2026-05-19T04:53:07.182325Z","steps":["trace[1901257973] 'process raft request' (duration: 133.311523ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:53:07.182479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.675589ms","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-19T04:53:07.182563Z","caller":"traceutil/trace.go:171","msg":"trace[626671822] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25721; }","duration":"106.832092ms","start":"2026-05-19T04:53:07.075713Z","end":"2026-05-19T04:53:07.182545Z","steps":["trace[626671822] 'agreement among raft nodes before linearized reading' (duration: 106.668489ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:53:07.464569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.04259ms","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-19T04:53:07.464752Z","caller":"traceutil/trace.go:171","msg":"trace[1880393393] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:25721; }","duration":"173.239015ms","start":"2026-05-19T04:53:07.291492Z","end":"2026-05-19T04:53:07.464731Z","steps":["trace[1880393393] 'range keys from in-memory index tree' (duration: 173.02088ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:53:07.476943Z","caller":"traceutil/trace.go:171","msg":"trace[957926323] linearizableReadLoop","detail":"{readStateIndex:26578; appliedIndex:26577; }","duration":"171.261014ms","start":"2026-05-19T04:53:07.30566Z","end":"2026-05-19T04:53:07.476921Z","steps":["trace[957926323] 'read index received' (duration: 171.102171ms)","trace[957926323] 'applied index is now lower than readState.Index' (duration: 157.633µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:53:07.477036Z","caller":"traceutil/trace.go:171","msg":"trace[375466656] transaction","detail":"{read_only:false; response_revision:25722; number_of_response:1; }","duration":"269.041695ms","start":"2026-05-19T04:53:07.207955Z","end":"2026-05-19T04:53:07.476996Z","steps":["trace[375466656] 'process raft request' (duration: 268.799371ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:53:07.47717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.497599ms","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-19T04:53:07.477214Z","caller":"traceutil/trace.go:171","msg":"trace[1942600619] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25722; }","duration":"171.57554ms","start":"2026-05-19T04:53:07.305624Z","end":"2026-05-19T04:53:07.4772Z","steps":["trace[1942600619] 'agreement among raft nodes before linearized reading' (duration: 171.422867ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:53:07.497596Z","caller":"traceutil/trace.go:171","msg":"trace[1820413489] transaction","detail":"{read_only:false; response_revision:25723; number_of_response:1; }","duration":"136.225032ms","start":"2026-05-19T04:53:07.361351Z","end":"2026-05-19T04:53:07.497576Z","steps":["trace[1820413489] 'process raft request' (duration: 136.003637ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:53:07.498012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.118443ms","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-19T04:53:07.498057Z","caller":"traceutil/trace.go:171","msg":"trace[1128768997] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25724; }","duration":"191.228195ms","start":"2026-05-19T04:53:07.306818Z","end":"2026-05-19T04:53:07.498047Z","steps":["trace[1128768997] 'agreement among raft nodes before linearized reading' (duration: 191.025231ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:53:07.498215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.324046ms","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-19T04:53:07.498331Z","caller":"traceutil/trace.go:171","msg":"trace[1608707783] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25724; }","duration":"178.50238ms","start":"2026-05-19T04:53:07.319806Z","end":"2026-05-19T04:53:07.498308Z","steps":["trace[1608707783] 'agreement among raft nodes before linearized reading' (duration: 178.188493ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:53:08.162138Z","caller":"traceutil/trace.go:171","msg":"trace[1734882330] transaction","detail":"{read_only:false; response_revision:25728; number_of_response:1; }","duration":"277.387552ms","start":"2026-05-19T04:53:07.884704Z","end":"2026-05-19T04:53:08.162091Z","steps":["trace[1734882330] 'process raft request' (duration: 277.088625ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:53:08.184721Z","caller":"traceutil/trace.go:171","msg":"trace[129066247] transaction","detail":"{read_only:false; response_revision:25729; number_of_response:1; }","duration":"160.721403ms","start":"2026-05-19T04:53:08.023961Z","end":"2026-05-19T04:53:08.184682Z","steps":["trace[129066247] 'process raft request' (duration: 160.040319ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:53:09.02583Z","caller":"traceutil/trace.go:171","msg":"trace[2064297561] linearizableReadLoop","detail":"{readStateIndex:26592; appliedIndex:26591; }","duration":"111.090248ms","start":"2026-05-19T04:53:08.914714Z","end":"2026-05-19T04:53:09.025804Z","steps":["trace[2064297561] 'read index received' (duration: 39.948611ms)","trace[2064297561] 'applied index is now lower than readState.Index' (duration: 71.140097ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:53:09.025884Z","caller":"traceutil/trace.go:171","msg":"trace[174829214] transaction","detail":"{read_only:false; response_revision:25735; number_of_response:1; }","duration":"145.474398ms","start":"2026-05-19T04:53:08.880336Z","end":"2026-05-19T04:53:09.02581Z","steps":["trace[174829214] 'process raft request' (duration: 74.243529ms)","trace[174829214] 'compare' (duration: 70.949363ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T04:53:09.025973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.238031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T04:53:09.02601Z","caller":"traceutil/trace.go:171","msg":"trace[1728413584] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:25735; }","duration":"111.341213ms","start":"2026-05-19T04:53:08.914657Z","end":"2026-05-19T04:53:09.025998Z","steps":["trace[1728413584] 'agreement among raft nodes before linearized reading' (duration: 111.244491ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:53:09.851518Z","caller":"traceutil/trace.go:171","msg":"trace[52303997] linearizableReadLoop","detail":"{readStateIndex:26595; appliedIndex:26593; }","duration":"296.977785ms","start":"2026-05-19T04:53:09.554518Z","end":"2026-05-19T04:53:09.851496Z","steps":["trace[52303997] 'read index received' (duration: 296.834802ms)","trace[52303997] 'applied index is now lower than readState.Index' (duration: 141.563µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:53:09.851588Z","caller":"traceutil/trace.go:171","msg":"trace[839615821] transaction","detail":"{read_only:false; response_revision:25738; number_of_response:1; }","duration":"331.195222ms","start":"2026-05-19T04:53:09.520339Z","end":"2026-05-19T04:53:09.851534Z","steps":["trace[839615821] 'process raft request' (duration: 331.1108ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:53:09.851719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.383886ms","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-19T04:53:09.85175Z","caller":"traceutil/trace.go:171","msg":"trace[1441945850] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25738; }","duration":"331.475858ms","start":"2026-05-19T04:53:09.520264Z","end":"2026-05-19T04:53:09.85174Z","steps":["trace[1441945850] 'agreement among raft nodes before linearized reading' (duration: 331.346575ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:53:09.851777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:53:09.520248Z","time spent":"331.523398ms","remote":"127.0.0.1:43890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-19T04:53:09.851898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.765841ms","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-19T04:53:09.85196Z","caller":"traceutil/trace.go:171","msg":"trace[1676605368] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25738; }","duration":"296.882674ms","start":"2026-05-19T04:53:09.555063Z","end":"2026-05-19T04:53:09.851946Z","steps":["trace[1676605368] 'agreement among raft nodes before linearized reading' (duration: 296.69848ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:53:09.851829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:53:09.520309Z","time spent":"331.389026ms","remote":"127.0.0.1:43890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T04:53:09.852073Z","caller":"traceutil/trace.go:171","msg":"trace[1766650994] transaction","detail":"{read_only:false; response_revision:25737; number_of_response:1; }","duration":"347.902716ms","start":"2026-05-19T04:53:09.504161Z","end":"2026-05-19T04:53:09.852064Z","steps":["trace[1766650994] 'process raft request' (duration: 347.176362ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:53:09.852112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.828244ms","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-19T04:53:09.852151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.11383ms","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-19T04:53:09.852174Z","caller":"traceutil/trace.go:171","msg":"trace[1778843175] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:25738; }","duration":"136.179661ms","start":"2026-05-19T04:53:09.715988Z","end":"2026-05-19T04:53:09.852167Z","steps":["trace[1778843175] 'agreement among raft nodes before linearized reading' (duration: 136.13626ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:53:09.852176Z","caller":"traceutil/trace.go:171","msg":"trace[1241389482] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25738; }","duration":"331.943677ms","start":"2026-05-19T04:53:09.520217Z","end":"2026-05-19T04:53:09.852161Z","steps":["trace[1241389482] 'agreement among raft nodes before linearized reading' (duration: 331.714032ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:53:09.852166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:53:09.504128Z","time spent":"347.971908ms","remote":"127.0.0.1:43890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T04:53:09.852214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:53:09.520199Z","time spent":"332.008798ms","remote":"127.0.0.1:43890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-19T04:53:10.317471Z","caller":"traceutil/trace.go:171","msg":"trace[1069929537] transaction","detail":"{read_only:false; response_revision:25742; number_of_response:1; }","duration":"126.644949ms","start":"2026-05-19T04:53:10.190748Z","end":"2026-05-19T04:53:10.317393Z","steps":["trace[1069929537] 'process raft request' (duration: 126.388854ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:53:10.663367Z","caller":"traceutil/trace.go:171","msg":"trace[1145534309] transaction","detail":"{read_only:false; response_revision:25743; number_of_response:1; }","duration":"240.319768ms","start":"2026-05-19T04:53:10.422971Z","end":"2026-05-19T04:53:10.663291Z","steps":["trace[1145534309] 'process raft request' (duration: 240.101144ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:53:10.685342Z","caller":"traceutil/trace.go:171","msg":"trace[1008836515] transaction","detail":"{read_only:false; response_revision:25745; number_of_response:1; }","duration":"185.717933ms","start":"2026-05-19T04:53:10.499574Z","end":"2026-05-19T04:53:10.685291Z","steps":["trace[1008836515] 'process raft request' (duration: 185.515529ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:53:10.685163Z","caller":"traceutil/trace.go:171","msg":"trace[1721165669] transaction","detail":"{read_only:false; response_revision:25744; number_of_response:1; }","duration":"188.588821ms","start":"2026-05-19T04:53:10.496539Z","end":"2026-05-19T04:53:10.685128Z","steps":["trace[1721165669] 'process raft request' (duration: 186.981479ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:53:11.038041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.946705ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T04:53:11.038558Z","caller":"traceutil/trace.go:171","msg":"trace[2092605390] transaction","detail":"{read_only:false; response_revision:25747; number_of_response:1; }","duration":"156.445068ms","start":"2026-05-19T04:53:10.882076Z","end":"2026-05-19T04:53:11.038522Z","steps":["trace[2092605390] 'process raft request' (duration: 156.109771ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:53:11.038668Z","caller":"traceutil/trace.go:171","msg":"trace[1220210511] transaction","detail":"{read_only:false; response_revision:25748; number_of_response:1; }","duration":"156.325484ms","start":"2026-05-19T04:53:10.882286Z","end":"2026-05-19T04:53:11.038612Z","steps":["trace[1220210511] 'process raft request' (duration: 155.999238ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:53:11.038576Z","caller":"traceutil/trace.go:171","msg":"trace[1691161981] transaction","detail":"{read_only:false; response_revision:25746; number_of_response:1; }","duration":"156.729803ms","start":"2026-05-19T04:53:10.881779Z","end":"2026-05-19T04:53:11.038508Z","steps":["trace[1691161981] 'process raft request' (duration: 53.219627ms)","trace[1691161981] 'compare' (duration: 102.742691ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:53:11.249229Z","caller":"traceutil/trace.go:171","msg":"trace[1150230962] transaction","detail":"{read_only:false; response_revision:25749; number_of_response:1; }","duration":"201.602322ms","start":"2026-05-19T04:53:11.04758Z","end":"2026-05-19T04:53:11.249182Z","steps":["trace[1150230962] 'process raft request' (duration: 171.246484ms)","trace[1150230962] 'compare' (duration: 29.394008ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T04:53:16.464987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.426396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T04:53:16.465093Z","caller":"traceutil/trace.go:171","msg":"trace[311737023] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:25786; }","duration":"342.59129ms","start":"2026-05-19T04:53:16.122473Z","end":"2026-05-19T04:53:16.465064Z","steps":["trace[311737023] 'count revisions from in-memory index tree' (duration: 342.341865ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:53:16.465147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:53:16.122453Z","time spent":"342.681221ms","remote":"127.0.0.1:55680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":27,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true "} {"level":"info","ts":"2026-05-19T04:53:16.466745Z","caller":"traceutil/trace.go:171","msg":"trace[1006572371] transaction","detail":"{read_only:false; response_revision:25787; number_of_response:1; }","duration":"124.693321ms","start":"2026-05-19T04:53:16.342009Z","end":"2026-05-19T04:53:16.466702Z","steps":["trace[1006572371] 'process raft request' (duration: 124.431736ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:53:17.18199Z","caller":"traceutil/trace.go:171","msg":"trace[1942953914] transaction","detail":"{read_only:false; response_revision:25788; number_of_response:1; }","duration":"470.237539ms","start":"2026-05-19T04:53:16.711721Z","end":"2026-05-19T04:53:17.181959Z","steps":["trace[1942953914] 'process raft request' (duration: 470.004605ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:53:17.18217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:53:16.711683Z","time spent":"470.394953ms","remote":"127.0.0.1:43890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T04:53:17.205845Z","caller":"traceutil/trace.go:171","msg":"trace[845685787] transaction","detail":"{read_only:false; response_revision:25789; number_of_response:1; }","duration":"492.314312ms","start":"2026-05-19T04:53:16.713506Z","end":"2026-05-19T04:53:17.20582Z","steps":["trace[845685787] 'process raft request' (duration: 490.773241ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:53:17.2061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:53:16.713475Z","time spent":"492.535337ms","remote":"127.0.0.1:43890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T04:53:17.206175Z","caller":"traceutil/trace.go:171","msg":"trace[1195796857] transaction","detail":"{read_only:false; response_revision:25790; number_of_response:1; }","duration":"147.238863ms","start":"2026-05-19T04:53:17.058915Z","end":"2026-05-19T04:53:17.206154Z","steps":["trace[1195796857] 'process raft request' (duration: 146.833535ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:53:17.206301Z","caller":"traceutil/trace.go:171","msg":"trace[2113771857] transaction","detail":"{read_only:false; response_revision:25791; number_of_response:1; }","duration":"141.907656ms","start":"2026-05-19T04:53:17.064369Z","end":"2026-05-19T04:53:17.206277Z","steps":["trace[2113771857] 'process raft request' (duration: 141.647911ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:53:17.206409Z","caller":"traceutil/trace.go:171","msg":"trace[1054959063] linearizableReadLoop","detail":"{readStateIndex:26647; appliedIndex:26645; }","duration":"288.054336ms","start":"2026-05-19T04:53:16.91833Z","end":"2026-05-19T04:53:17.206384Z","steps":["trace[1054959063] 'read index received' (duration: 263.381081ms)","trace[1054959063] 'applied index is now lower than readState.Index' (duration: 24.671825ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:53:17.206551Z","caller":"traceutil/trace.go:171","msg":"trace[1491059955] transaction","detail":"{read_only:false; response_revision:25792; number_of_response:1; }","duration":"142.099879ms","start":"2026-05-19T04:53:17.064441Z","end":"2026-05-19T04:53:17.20654Z","steps":["trace[1491059955] 'process raft request' (duration: 141.810234ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:53:17.20676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.61546ms","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-19T04:53:17.206854Z","caller":"traceutil/trace.go:171","msg":"trace[1022626657] 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:25792; }","duration":"153.756513ms","start":"2026-05-19T04:53:17.053083Z","end":"2026-05-19T04:53:17.206839Z","steps":["trace[1022626657] 'agreement among raft nodes before linearized reading' (duration: 153.620111ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:53:17.206931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.550696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T04:53:17.207012Z","caller":"traceutil/trace.go:171","msg":"trace[1613299351] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:25792; }","duration":"288.718319ms","start":"2026-05-19T04:53:16.918275Z","end":"2026-05-19T04:53:17.206993Z","steps":["trace[1613299351] 'agreement among raft nodes before linearized reading' (duration: 288.497555ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:53:17.549662Z","caller":"traceutil/trace.go:171","msg":"trace[1781249938] linearizableReadLoop","detail":"{readStateIndex:26651; appliedIndex:26650; }","duration":"209.370839ms","start":"2026-05-19T04:53:17.340267Z","end":"2026-05-19T04:53:17.549637Z","steps":["trace[1781249938] 'read index received' (duration: 184.347617ms)","trace[1781249938] 'applied index is now lower than readState.Index' (duration: 25.022062ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T04:53:17.549916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.627874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-05-19T04:53:17.549956Z","caller":"traceutil/trace.go:171","msg":"trace[1251708506] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25793; }","duration":"209.716626ms","start":"2026-05-19T04:53:17.340228Z","end":"2026-05-19T04:53:17.549945Z","steps":["trace[1251708506] 'agreement among raft nodes before linearized reading' (duration: 209.551082ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:53:17.549689Z","caller":"traceutil/trace.go:171","msg":"trace[609616582] transaction","detail":"{read_only:false; response_revision:25793; number_of_response:1; }","duration":"273.331141ms","start":"2026-05-19T04:53:17.276318Z","end":"2026-05-19T04:53:17.549649Z","steps":["trace[609616582] 'process raft request' (duration: 248.302769ms)","trace[609616582] 'compare' (duration: 24.900479ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:53:17.710208Z","caller":"traceutil/trace.go:171","msg":"trace[1620299148] transaction","detail":"{read_only:false; response_revision:25794; number_of_response:1; }","duration":"106.850232ms","start":"2026-05-19T04:53:17.603332Z","end":"2026-05-19T04:53:17.710182Z","steps":["trace[1620299148] 'process raft request' (duration: 106.627198ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:53:24.188315Z","caller":"traceutil/trace.go:171","msg":"trace[2015344199] transaction","detail":"{read_only:false; response_revision:25900; number_of_response:1; }","duration":"193.761465ms","start":"2026-05-19T04:53:23.994529Z","end":"2026-05-19T04:53:24.18829Z","steps":["trace[2015344199] 'process raft request' (duration: 193.590111ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:53:24.432687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.5618ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T04:53:24.432973Z","caller":"traceutil/trace.go:171","msg":"trace[2006024532] linearizableReadLoop","detail":"{readStateIndex:26763; appliedIndex:26761; }","duration":"182.381427ms","start":"2026-05-19T04:53:24.25057Z","end":"2026-05-19T04:53:24.432952Z","steps":["trace[2006024532] 'read index received' (duration: 38.408459ms)","trace[2006024532] 'applied index is now lower than readState.Index' (duration: 143.971288ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:53:24.432962Z","caller":"traceutil/trace.go:171","msg":"trace[951234494] transaction","detail":"{read_only:false; response_revision:25901; number_of_response:1; }","duration":"438.136176ms","start":"2026-05-19T04:53:23.994775Z","end":"2026-05-19T04:53:24.432911Z","steps":["trace[951234494] 'process raft request' (duration: 294.177368ms)","trace[951234494] 'compare' (duration: 142.654771ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:53:24.433093Z","caller":"traceutil/trace.go:171","msg":"trace[1493114262] transaction","detail":"{read_only:false; response_revision:25902; number_of_response:1; }","duration":"438.241288ms","start":"2026-05-19T04:53:23.994824Z","end":"2026-05-19T04:53:24.433065Z","steps":["trace[1493114262] 'process raft request' (duration: 437.987523ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:53:24.433185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.605632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/projects/\" range_end:\"/registry/openstack.k-orc.cloud/projects0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-19T04:53:24.433185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:53:23.99475Z","time spent":"438.3116ms","remote":"127.0.0.1:43890","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-19T04:53:24.433208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:53:23.994795Z","time spent":"438.357231ms","remote":"127.0.0.1:43890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T04:53:24.43326Z","caller":"traceutil/trace.go:171","msg":"trace[656095861] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/projects/; range_end:/registry/openstack.k-orc.cloud/projects0; response_count:0; response_revision:25902; }","duration":"182.743144ms","start":"2026-05-19T04:53:24.250497Z","end":"2026-05-19T04:53:24.43324Z","steps":["trace[656095861] 'agreement among raft nodes before linearized reading' (duration: 182.579301ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:53:24.433932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.606459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-19T04:53:24.433991Z","caller":"traceutil/trace.go:171","msg":"trace[745839173] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:25902; }","duration":"132.714271ms","start":"2026-05-19T04:53:24.301261Z","end":"2026-05-19T04:53:24.433975Z","steps":["trace[745839173] 'agreement among raft nodes before linearized reading' (duration: 132.599868ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:53:24.632053Z","caller":"traceutil/trace.go:171","msg":"trace[1710288034] transaction","detail":"{read_only:false; response_revision:25903; number_of_response:1; }","duration":"189.328358ms","start":"2026-05-19T04:53:24.442701Z","end":"2026-05-19T04:53:24.63203Z","steps":["trace[1710288034] 'process raft request' (duration: 167.723444ms)","trace[1710288034] 'compare' (duration: 21.498131ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:53:24.714628Z","caller":"traceutil/trace.go:171","msg":"trace[1069880219] transaction","detail":"{read_only:false; response_revision:25904; number_of_response:1; }","duration":"215.294588ms","start":"2026-05-19T04:53:24.49931Z","end":"2026-05-19T04:53:24.714604Z","steps":["trace[1069880219] 'process raft request' (duration: 215.159375ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:53:25.147843Z","caller":"traceutil/trace.go:171","msg":"trace[948138702] linearizableReadLoop","detail":"{readStateIndex:26766; appliedIndex:26765; }","duration":"124.977397ms","start":"2026-05-19T04:53:25.022827Z","end":"2026-05-19T04:53:25.147804Z","steps":["trace[948138702] 'read index received' (duration: 124.716052ms)","trace[948138702] 'applied index is now lower than readState.Index' (duration: 259.965µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:53:25.147927Z","caller":"traceutil/trace.go:171","msg":"trace[1134908656] transaction","detail":"{read_only:false; response_revision:25905; number_of_response:1; }","duration":"125.985376ms","start":"2026-05-19T04:53:25.02188Z","end":"2026-05-19T04:53:25.147865Z","steps":["trace[1134908656] 'process raft request' (duration: 125.731371ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:53:25.14807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.219961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9604"} {"level":"info","ts":"2026-05-19T04:53:25.14812Z","caller":"traceutil/trace.go:171","msg":"trace[148885457] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:25905; }","duration":"125.301183ms","start":"2026-05-19T04:53:25.022804Z","end":"2026-05-19T04:53:25.148106Z","steps":["trace[148885457] 'agreement among raft nodes before linearized reading' (duration: 125.118729ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:53:25.320818Z","caller":"traceutil/trace.go:171","msg":"trace[195368185] linearizableReadLoop","detail":"{readStateIndex:26767; appliedIndex:26766; }","duration":"156.674952ms","start":"2026-05-19T04:53:25.164111Z","end":"2026-05-19T04:53:25.320785Z","steps":["trace[195368185] 'read index received' (duration: 156.626461ms)","trace[195368185] 'applied index is now lower than readState.Index' (duration: 46.89µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:53:25.320906Z","caller":"traceutil/trace.go:171","msg":"trace[446125513] transaction","detail":"{read_only:false; response_revision:25906; number_of_response:1; }","duration":"165.902998ms","start":"2026-05-19T04:53:25.154962Z","end":"2026-05-19T04:53:25.320865Z","steps":["trace[446125513] 'process raft request' (duration: 165.628303ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:53:25.321095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.967587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9604"} {"level":"info","ts":"2026-05-19T04:53:25.321145Z","caller":"traceutil/trace.go:171","msg":"trace[1588216824] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:25906; }","duration":"157.051909ms","start":"2026-05-19T04:53:25.164078Z","end":"2026-05-19T04:53:25.32113Z","steps":["trace[1588216824] 'agreement among raft nodes before linearized reading' (duration: 156.865626ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:53:25.428105Z","caller":"traceutil/trace.go:171","msg":"trace[1929033489] transaction","detail":"{read_only:false; response_revision:25907; number_of_response:1; }","duration":"222.130474ms","start":"2026-05-19T04:53:25.205945Z","end":"2026-05-19T04:53:25.428076Z","steps":["trace[1929033489] 'process raft request' (duration: 219.580692ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:53:25.428469Z","caller":"traceutil/trace.go:171","msg":"trace[543809766] transaction","detail":"{read_only:false; response_revision:25908; number_of_response:1; }","duration":"192.124662ms","start":"2026-05-19T04:53:25.236297Z","end":"2026-05-19T04:53:25.428422Z","steps":["trace[543809766] 'process raft request' (duration: 191.730825ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:53:25.428569Z","caller":"traceutil/trace.go:171","msg":"trace[1504548347] transaction","detail":"{read_only:false; response_revision:25909; number_of_response:1; }","duration":"191.01261ms","start":"2026-05-19T04:53:25.237527Z","end":"2026-05-19T04:53:25.42854Z","steps":["trace[1504548347] 'process raft request' (duration: 190.769805ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:53:25.428611Z","caller":"traceutil/trace.go:171","msg":"trace[104813857] transaction","detail":"{read_only:false; response_revision:25911; number_of_response:1; }","duration":"189.301165ms","start":"2026-05-19T04:53:25.239297Z","end":"2026-05-19T04:53:25.428598Z","steps":["trace[104813857] 'process raft request' (duration: 189.219893ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:53:25.42861Z","caller":"traceutil/trace.go:171","msg":"trace[1136866760] linearizableReadLoop","detail":"{readStateIndex:26772; appliedIndex:26767; }","duration":"107.661099ms","start":"2026-05-19T04:53:25.320916Z","end":"2026-05-19T04:53:25.428577Z","steps":["trace[1136866760] 'read index received' (duration: 104.620088ms)","trace[1136866760] 'applied index is now lower than readState.Index' (duration: 3.03966ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:53:25.428707Z","caller":"traceutil/trace.go:171","msg":"trace[269551505] transaction","detail":"{read_only:false; response_revision:25910; number_of_response:1; }","duration":"190.941289ms","start":"2026-05-19T04:53:25.237757Z","end":"2026-05-19T04:53:25.428698Z","steps":["trace[269551505] 'process raft request' (duration: 190.696124ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:53:25.428906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.168957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-19T04:53:25.428928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.560937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-manila.18b0de6acd6fd193\" ","response":"range_response_count:1 size:747"} {"level":"info","ts":"2026-05-19T04:53:25.42896Z","caller":"traceutil/trace.go:171","msg":"trace[1454641922] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:25911; }","duration":"261.290489ms","start":"2026-05-19T04:53:25.167653Z","end":"2026-05-19T04:53:25.428944Z","steps":["trace[1454641922] 'agreement among raft nodes before linearized reading' (duration: 261.084805ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:53:25.428994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.677673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-19T04:53:25.429009Z","caller":"traceutil/trace.go:171","msg":"trace[217146755] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-manila.18b0de6acd6fd193; range_end:; response_count:1; response_revision:25911; }","duration":"103.685329ms","start":"2026-05-19T04:53:25.3253Z","end":"2026-05-19T04:53:25.428985Z","steps":["trace[217146755] 'agreement among raft nodes before linearized reading' (duration: 103.461554ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:53:25.429068Z","caller":"traceutil/trace.go:171","msg":"trace[1255761749] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:25911; }","duration":"180.807745ms","start":"2026-05-19T04:53:25.24824Z","end":"2026-05-19T04:53:25.429048Z","steps":["trace[1255761749] 'agreement among raft nodes before linearized reading' (duration: 180.484868ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:53:27.798605Z","caller":"traceutil/trace.go:171","msg":"trace[1660304499] transaction","detail":"{read_only:false; response_revision:25929; number_of_response:1; }","duration":"198.255085ms","start":"2026-05-19T04:53:27.60033Z","end":"2026-05-19T04:53:27.798585Z","steps":["trace[1660304499] 'process raft request' (duration: 198.073502ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:53:27.943661Z","caller":"traceutil/trace.go:171","msg":"trace[630617145] transaction","detail":"{read_only:false; response_revision:25931; number_of_response:1; }","duration":"141.955337ms","start":"2026-05-19T04:53:27.801675Z","end":"2026-05-19T04:53:27.94363Z","steps":["trace[630617145] 'process raft request' (duration: 140.727282ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:53:27.943863Z","caller":"traceutil/trace.go:171","msg":"trace[29986375] transaction","detail":"{read_only:false; response_revision:25932; number_of_response:1; }","duration":"138.920886ms","start":"2026-05-19T04:53:27.804854Z","end":"2026-05-19T04:53:27.943774Z","steps":["trace[29986375] 'process raft request' (duration: 138.666581ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:53:32.937568Z","caller":"traceutil/trace.go:171","msg":"trace[1391903577] transaction","detail":"{read_only:false; response_revision:25987; number_of_response:1; }","duration":"222.696346ms","start":"2026-05-19T04:53:32.71484Z","end":"2026-05-19T04:53:32.937537Z","steps":["trace[1391903577] 'process raft request' (duration: 191.071941ms)","trace[1391903577] 'compare' (duration: 31.482622ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:53:32.962841Z","caller":"traceutil/trace.go:171","msg":"trace[835676364] linearizableReadLoop","detail":"{readStateIndex:26852; appliedIndex:26850; }","duration":"150.646601ms","start":"2026-05-19T04:53:32.812164Z","end":"2026-05-19T04:53:32.96281Z","steps":["trace[835676364] 'read index received' (duration: 93.7629ms)","trace[835676364] 'applied index is now lower than readState.Index' (duration: 56.882331ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:53:32.96295Z","caller":"traceutil/trace.go:171","msg":"trace[809893069] transaction","detail":"{read_only:false; response_revision:25988; number_of_response:1; }","duration":"221.812498ms","start":"2026-05-19T04:53:32.741096Z","end":"2026-05-19T04:53:32.962909Z","steps":["trace[809893069] 'process raft request' (duration: 221.538793ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:53:32.963127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.948187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"info","ts":"2026-05-19T04:53:32.96317Z","caller":"traceutil/trace.go:171","msg":"trace[606415500] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25988; }","duration":"151.065189ms","start":"2026-05-19T04:53:32.81209Z","end":"2026-05-19T04:53:32.963155Z","steps":["trace[606415500] 'agreement among raft nodes before linearized reading' (duration: 150.885016ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:53:33.196688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.22568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-05-19T04:53:33.196774Z","caller":"traceutil/trace.go:171","msg":"trace[1801772192] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25988; }","duration":"101.380983ms","start":"2026-05-19T04:53:33.095374Z","end":"2026-05-19T04:53:33.196755Z","steps":["trace[1801772192] 'range keys from in-memory index tree' (duration: 100.902073ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:53:33.426805Z","caller":"traceutil/trace.go:171","msg":"trace[486878310] transaction","detail":"{read_only:false; response_revision:25989; number_of_response:1; }","duration":"242.654385ms","start":"2026-05-19T04:53:33.184127Z","end":"2026-05-19T04:53:33.426781Z","steps":["trace[486878310] 'process raft request' (duration: 242.466092ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:53:33.438391Z","caller":"traceutil/trace.go:171","msg":"trace[1555440926] transaction","detail":"{read_only:false; response_revision:25990; number_of_response:1; }","duration":"242.092054ms","start":"2026-05-19T04:53:33.196271Z","end":"2026-05-19T04:53:33.438363Z","steps":["trace[1555440926] 'process raft request' (duration: 241.85445ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:53:33.438647Z","caller":"traceutil/trace.go:171","msg":"trace[924002900] linearizableReadLoop","detail":"{readStateIndex:26854; appliedIndex:26852; }","duration":"237.649444ms","start":"2026-05-19T04:53:33.200964Z","end":"2026-05-19T04:53:33.438614Z","steps":["trace[924002900] 'read index received' (duration: 225.643314ms)","trace[924002900] 'applied index is now lower than readState.Index' (duration: 12.00418ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T04:53:33.438917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.946332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"warn","ts":"2026-05-19T04:53:33.438987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.976936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-05-19T04:53:33.438969Z","caller":"traceutil/trace.go:171","msg":"trace[111259891] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25991; }","duration":"238.068584ms","start":"2026-05-19T04:53:33.200889Z","end":"2026-05-19T04:53:33.438957Z","steps":["trace[111259891] 'agreement among raft nodes before linearized reading' (duration: 237.839809ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:53:33.439044Z","caller":"traceutil/trace.go:171","msg":"trace[2062119631] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:25991; }","duration":"116.111988ms","start":"2026-05-19T04:53:33.322914Z","end":"2026-05-19T04:53:33.439026Z","steps":["trace[2062119631] 'agreement among raft nodes before linearized reading' (duration: 115.874864ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:53:33.558639Z","caller":"traceutil/trace.go:171","msg":"trace[1977537602] transaction","detail":"{read_only:false; response_revision:25992; number_of_response:1; }","duration":"102.412933ms","start":"2026-05-19T04:53:33.456203Z","end":"2026-05-19T04:53:33.558616Z","steps":["trace[1977537602] 'process raft request' (duration: 101.76656ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:53:42.129478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.896594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-19T04:53:42.129571Z","caller":"traceutil/trace.go:171","msg":"trace[473020375] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26062; }","duration":"124.052887ms","start":"2026-05-19T04:53:42.005498Z","end":"2026-05-19T04:53:42.129551Z","steps":["trace[473020375] 'range keys from in-memory index tree' (duration: 123.658659ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:53:42.277975Z","caller":"traceutil/trace.go:171","msg":"trace[587102875] transaction","detail":"{read_only:false; response_revision:26065; number_of_response:1; }","duration":"142.748922ms","start":"2026-05-19T04:53:42.135184Z","end":"2026-05-19T04:53:42.277933Z","steps":["trace[587102875] 'process raft request' (duration: 114.046357ms)","trace[587102875] 'compare' (duration: 28.306828ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:53:42.398754Z","caller":"traceutil/trace.go:171","msg":"trace[1267542120] transaction","detail":"{read_only:false; response_revision:26066; number_of_response:1; }","duration":"117.037237ms","start":"2026-05-19T04:53:42.281687Z","end":"2026-05-19T04:53:42.398724Z","steps":["trace[1267542120] 'process raft request' (duration: 115.827423ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:53:42.864515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.937086ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T04:53:42.864619Z","caller":"traceutil/trace.go:171","msg":"trace[1107555831] linearizableReadLoop","detail":"{readStateIndex:26933; appliedIndex:26932; }","duration":"202.307256ms","start":"2026-05-19T04:53:42.662296Z","end":"2026-05-19T04:53:42.864603Z","steps":["trace[1107555831] 'read index received' (duration: 66.131526ms)","trace[1107555831] 'applied index is now lower than readState.Index' (duration: 136.17438ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T04:53:42.864897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.606473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.235\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-05-19T04:53:42.864938Z","caller":"traceutil/trace.go:171","msg":"trace[1134846787] range","detail":"{range_begin:/registry/masterleases/199.204.45.235; range_end:; response_count:1; response_revision:26068; }","duration":"202.728224ms","start":"2026-05-19T04:53:42.662199Z","end":"2026-05-19T04:53:42.864927Z","steps":["trace[1134846787] 'agreement among raft nodes before linearized reading' (duration: 202.610182ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:53:42.865023Z","caller":"traceutil/trace.go:171","msg":"trace[70693206] transaction","detail":"{read_only:false; response_revision:26068; number_of_response:1; }","duration":"298.691659ms","start":"2026-05-19T04:53:42.566293Z","end":"2026-05-19T04:53:42.864984Z","steps":["trace[70693206] 'process raft request' (duration: 162.208382ms)","trace[70693206] 'compare' (duration: 135.820553ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T04:53:42.866196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.04123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-19T04:53:42.866234Z","caller":"traceutil/trace.go:171","msg":"trace[1393705043] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:26068; }","duration":"164.125222ms","start":"2026-05-19T04:53:42.702101Z","end":"2026-05-19T04:53:42.866226Z","steps":["trace[1393705043] 'agreement among raft nodes before linearized reading' (duration: 162.706753ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:53:43.083757Z","caller":"traceutil/trace.go:171","msg":"trace[2059075051] linearizableReadLoop","detail":"{readStateIndex:26934; appliedIndex:26933; }","duration":"135.287323ms","start":"2026-05-19T04:53:42.94844Z","end":"2026-05-19T04:53:43.083727Z","steps":["trace[2059075051] 'read index received' (duration: 135.226942ms)","trace[2059075051] 'applied index is now lower than readState.Index' (duration: 58.441µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T04:53:43.084148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.687591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-19T04:53:43.08422Z","caller":"traceutil/trace.go:171","msg":"trace[1903481218] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:26068; }","duration":"135.820094ms","start":"2026-05-19T04:53:42.948383Z","end":"2026-05-19T04:53:43.084203Z","steps":["trace[1903481218] 'agreement among raft nodes before linearized reading' (duration: 135.542769ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:53:43.106752Z","caller":"traceutil/trace.go:171","msg":"trace[360878243] transaction","detail":"{read_only:false; response_revision:26069; number_of_response:1; }","duration":"109.572967ms","start":"2026-05-19T04:53:42.997139Z","end":"2026-05-19T04:53:43.106712Z","steps":["trace[360878243] 'process raft request' (duration: 109.287012ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:53:43.10757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.253922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"info","ts":"2026-05-19T04:53:43.107633Z","caller":"traceutil/trace.go:171","msg":"trace[319036511] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:26069; }","duration":"122.568788ms","start":"2026-05-19T04:53:42.985048Z","end":"2026-05-19T04:53:43.107617Z","steps":["trace[319036511] 'agreement among raft nodes before linearized reading' (duration: 121.977156ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:53:44.99225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.923355ms","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-19T04:53:44.992315Z","caller":"traceutil/trace.go:171","msg":"trace[1388282864] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26083; }","duration":"123.030326ms","start":"2026-05-19T04:53:44.869268Z","end":"2026-05-19T04:53:44.992298Z","steps":["trace[1388282864] 'range keys from in-memory index tree' (duration: 122.772362ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:53:46.17203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.942137ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T04:53:46.172217Z","caller":"traceutil/trace.go:171","msg":"trace[1644746754] transaction","detail":"{read_only:false; response_revision:26092; number_of_response:1; }","duration":"171.115632ms","start":"2026-05-19T04:53:46.001016Z","end":"2026-05-19T04:53:46.172132Z","steps":["trace[1644746754] 'process raft request' (duration: 29.970792ms)","trace[1644746754] 'compare' (duration: 140.64624ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:53:49.747781Z","caller":"traceutil/trace.go:171","msg":"trace[952614616] transaction","detail":"{read_only:false; response_revision:26116; number_of_response:1; }","duration":"107.433254ms","start":"2026-05-19T04:53:49.640322Z","end":"2026-05-19T04:53:49.747756Z","steps":["trace[952614616] 'process raft request' (duration: 107.262721ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:53:49.75607Z","caller":"traceutil/trace.go:171","msg":"trace[1457358660] transaction","detail":"{read_only:false; response_revision:26117; number_of_response:1; }","duration":"115.146799ms","start":"2026-05-19T04:53:49.640905Z","end":"2026-05-19T04:53:49.756052Z","steps":["trace[1457358660] 'process raft request' (duration: 114.957785ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:53:49.756076Z","caller":"traceutil/trace.go:171","msg":"trace[1065575369] transaction","detail":"{read_only:false; response_revision:26120; number_of_response:1; }","duration":"114.584957ms","start":"2026-05-19T04:53:49.641471Z","end":"2026-05-19T04:53:49.756056Z","steps":["trace[1065575369] 'process raft request' (duration: 114.556707ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:53:49.756162Z","caller":"traceutil/trace.go:171","msg":"trace[960767447] transaction","detail":"{read_only:false; response_revision:26119; number_of_response:1; }","duration":"114.66256ms","start":"2026-05-19T04:53:49.641464Z","end":"2026-05-19T04:53:49.756127Z","steps":["trace[960767447] 'process raft request' (duration: 114.542917ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:53:49.756159Z","caller":"traceutil/trace.go:171","msg":"trace[1752835455] transaction","detail":"{read_only:false; response_revision:26118; number_of_response:1; }","duration":"114.947025ms","start":"2026-05-19T04:53:49.641176Z","end":"2026-05-19T04:53:49.756123Z","steps":["trace[1752835455] 'process raft request' (duration: 114.804452ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:53:51.32325Z","caller":"traceutil/trace.go:171","msg":"trace[1200142007] transaction","detail":"{read_only:false; response_revision:26139; number_of_response:1; }","duration":"102.758871ms","start":"2026-05-19T04:53:51.220461Z","end":"2026-05-19T04:53:51.32322Z","steps":["trace[1200142007] 'process raft request' (duration: 69.437273ms)","trace[1200142007] 'compare' (duration: 33.133924ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:53:55.89277Z","caller":"traceutil/trace.go:171","msg":"trace[458293507] transaction","detail":"{read_only:false; response_revision:26171; number_of_response:1; }","duration":"127.746862ms","start":"2026-05-19T04:53:55.764987Z","end":"2026-05-19T04:53:55.892734Z","steps":["trace[458293507] 'process raft request' (duration: 127.502777ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:53:55.965066Z","caller":"traceutil/trace.go:171","msg":"trace[1046106795] transaction","detail":"{read_only:false; response_revision:26172; number_of_response:1; }","duration":"194.234245ms","start":"2026-05-19T04:53:55.770806Z","end":"2026-05-19T04:53:55.965041Z","steps":["trace[1046106795] 'process raft request' (duration: 193.789856ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:53:55.96532Z","caller":"traceutil/trace.go:171","msg":"trace[435583542] transaction","detail":"{read_only:false; response_revision:26175; number_of_response:1; }","duration":"184.51656ms","start":"2026-05-19T04:53:55.780768Z","end":"2026-05-19T04:53:55.965284Z","steps":["trace[435583542] 'process raft request' (duration: 184.04206ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:53:55.965526Z","caller":"traceutil/trace.go:171","msg":"trace[1609364881] transaction","detail":"{read_only:false; response_revision:26173; number_of_response:1; }","duration":"190.184904ms","start":"2026-05-19T04:53:55.775328Z","end":"2026-05-19T04:53:55.965513Z","steps":["trace[1609364881] 'process raft request' (duration: 189.417849ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:53:55.965746Z","caller":"traceutil/trace.go:171","msg":"trace[412139565] transaction","detail":"{read_only:false; response_revision:26174; number_of_response:1; }","duration":"186.110783ms","start":"2026-05-19T04:53:55.779626Z","end":"2026-05-19T04:53:55.965737Z","steps":["trace[412139565] 'process raft request' (duration: 185.151514ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:53:56.13712Z","caller":"traceutil/trace.go:171","msg":"trace[1460630553] linearizableReadLoop","detail":"{readStateIndex:27044; appliedIndex:27043; }","duration":"172.242793ms","start":"2026-05-19T04:53:55.964845Z","end":"2026-05-19T04:53:56.137087Z","steps":["trace[1460630553] 'read index received' (duration: 133.6742ms)","trace[1460630553] 'applied index is now lower than readState.Index' (duration: 38.567243ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:53:56.137241Z","caller":"traceutil/trace.go:171","msg":"trace[1085240744] transaction","detail":"{read_only:false; response_revision:26176; number_of_response:1; }","duration":"189.474289ms","start":"2026-05-19T04:53:55.947727Z","end":"2026-05-19T04:53:56.137202Z","steps":["trace[1085240744] 'process raft request' (duration: 150.783173ms)","trace[1085240744] 'compare' (duration: 38.42403ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T04:53:56.13728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.939559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/endpoints/\" range_end:\"/registry/openstack.k-orc.cloud/endpoints0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-19T04:53:56.137331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.075551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T04:53:56.137349Z","caller":"traceutil/trace.go:171","msg":"trace[1078191927] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/endpoints/; range_end:/registry/openstack.k-orc.cloud/endpoints0; response_count:0; response_revision:26176; }","duration":"195.054181ms","start":"2026-05-19T04:53:55.942276Z","end":"2026-05-19T04:53:56.13733Z","steps":["trace[1078191927] 'agreement among raft nodes before linearized reading' (duration: 194.945689ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:53:56.137383Z","caller":"traceutil/trace.go:171","msg":"trace[1196282535] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26176; }","duration":"171.164572ms","start":"2026-05-19T04:53:55.966206Z","end":"2026-05-19T04:53:56.137371Z","steps":["trace[1196282535] 'agreement among raft nodes before linearized reading' (duration: 171.05ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:53:58.534961Z","caller":"traceutil/trace.go:171","msg":"trace[760119547] transaction","detail":"{read_only:false; response_revision:26192; number_of_response:1; }","duration":"178.602421ms","start":"2026-05-19T04:53:58.356335Z","end":"2026-05-19T04:53:58.534937Z","steps":["trace[760119547] 'process raft request' (duration: 178.383837ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:53:58.70949Z","caller":"traceutil/trace.go:171","msg":"trace[1395368173] transaction","detail":"{read_only:false; response_revision:26193; number_of_response:1; }","duration":"352.837915ms","start":"2026-05-19T04:53:58.356628Z","end":"2026-05-19T04:53:58.709466Z","steps":["trace[1395368173] 'process raft request' (duration: 276.437183ms)","trace[1395368173] 'compare' (duration: 76.247729ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T04:53:58.70962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:53:58.356601Z","time spent":"352.953177ms","remote":"127.0.0.1:43890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T04:53:58.709683Z","caller":"traceutil/trace.go:171","msg":"trace[1229789887] linearizableReadLoop","detail":"{readStateIndex:27062; appliedIndex:27060; }","duration":"347.949637ms","start":"2026-05-19T04:53:58.361692Z","end":"2026-05-19T04:53:58.709642Z","steps":["trace[1229789887] 'read index received' (duration: 173.076701ms)","trace[1229789887] 'applied index is now lower than readState.Index' (duration: 174.870046ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:53:58.70974Z","caller":"traceutil/trace.go:171","msg":"trace[477454660] transaction","detail":"{read_only:false; response_revision:26194; number_of_response:1; }","duration":"238.129925ms","start":"2026-05-19T04:53:58.471587Z","end":"2026-05-19T04:53:58.709717Z","steps":["trace[477454660] 'process raft request' (duration: 237.846139ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:53:58.709994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.270524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-19T04:53:58.71006Z","caller":"traceutil/trace.go:171","msg":"trace[387571738] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26194; }","duration":"348.400917ms","start":"2026-05-19T04:53:58.36164Z","end":"2026-05-19T04:53:58.710041Z","steps":["trace[387571738] 'agreement among raft nodes before linearized reading' (duration: 348.174341ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:53:58.71011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:53:58.361613Z","time spent":"348.484188ms","remote":"127.0.0.1:43890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-19T04:53:58.710239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.949582ms","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-19T04:53:58.710335Z","caller":"traceutil/trace.go:171","msg":"trace[1219103925] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26194; }","duration":"229.097394ms","start":"2026-05-19T04:53:58.481223Z","end":"2026-05-19T04:53:58.71032Z","steps":["trace[1219103925] 'agreement among raft nodes before linearized reading' (duration: 228.793308ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:53:58.872658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.322723ms","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-19T04:53:58.872752Z","caller":"traceutil/trace.go:171","msg":"trace[1789112264] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:26196; }","duration":"114.461395ms","start":"2026-05-19T04:53:58.758265Z","end":"2026-05-19T04:53:58.872726Z","steps":["trace[1789112264] 'agreement among raft nodes before linearized reading' (duration: 51.094024ms)","trace[1789112264] 'count revisions from in-memory index tree' (duration: 63.230799ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:54:00.89199Z","caller":"traceutil/trace.go:171","msg":"trace[774101384] transaction","detail":"{read_only:false; response_revision:26208; number_of_response:1; }","duration":"108.285ms","start":"2026-05-19T04:54:00.783678Z","end":"2026-05-19T04:54:00.891963Z","steps":["trace[774101384] 'process raft request' (duration: 104.74255ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:54:08.798591Z","caller":"traceutil/trace.go:171","msg":"trace[2030118402] transaction","detail":"{read_only:false; response_revision:26266; number_of_response:1; }","duration":"192.451979ms","start":"2026-05-19T04:54:08.606109Z","end":"2026-05-19T04:54:08.798561Z","steps":["trace[2030118402] 'process raft request' (duration: 192.234895ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:54:09.368878Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920881031293134123,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-19T04:54:09.443104Z","caller":"traceutil/trace.go:171","msg":"trace[485489588] transaction","detail":"{read_only:false; response_revision:26268; number_of_response:1; }","duration":"657.936004ms","start":"2026-05-19T04:54:08.785127Z","end":"2026-05-19T04:54:09.443063Z","steps":["trace[485489588] 'process raft request' (duration: 657.866902ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:54:09.443144Z","caller":"traceutil/trace.go:171","msg":"trace[630631367] transaction","detail":"{read_only:false; response_revision:26267; number_of_response:1; }","duration":"721.246333ms","start":"2026-05-19T04:54:08.721854Z","end":"2026-05-19T04:54:09.443101Z","steps":["trace[630631367] 'process raft request' (duration: 720.820545ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:54:09.443324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:54:08.785077Z","time spent":"658.132378ms","remote":"127.0.0.1:43890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T04:54:09.443341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:54:08.721823Z","time spent":"721.398246ms","remote":"127.0.0.1:43890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T04:54:09.777229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.320306ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T04:54:09.777386Z","caller":"traceutil/trace.go:171","msg":"trace[2060754032] linearizableReadLoop","detail":"{readStateIndex:27140; appliedIndex:27137; }","duration":"909.323193ms","start":"2026-05-19T04:54:08.86804Z","end":"2026-05-19T04:54:09.777363Z","steps":["trace[2060754032] 'read index received' (duration: 574.595331ms)","trace[2060754032] 'applied index is now lower than readState.Index' (duration: 334.725582ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:54:09.777507Z","caller":"traceutil/trace.go:171","msg":"trace[941925803] transaction","detail":"{read_only:false; response_revision:26269; number_of_response:1; }","duration":"574.591941ms","start":"2026-05-19T04:54:09.2029Z","end":"2026-05-19T04:54:09.777492Z","steps":["trace[941925803] 'process raft request' (duration: 393.834737ms)","trace[941925803] 'compare' (duration: 179.868857ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T04:54:09.777593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:54:09.202874Z","time spent":"574.673663ms","remote":"127.0.0.1:43890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T04:54:09.777927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"909.889745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T04:54:09.777975Z","caller":"traceutil/trace.go:171","msg":"trace[1252661885] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26269; }","duration":"909.975596ms","start":"2026-05-19T04:54:08.867986Z","end":"2026-05-19T04:54:09.777962Z","steps":["trace[1252661885] 'agreement among raft nodes before linearized reading' (duration: 909.877104ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:54:09.778008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:54:08.867962Z","time spent":"910.039697ms","remote":"127.0.0.1:43628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-19T04:54:09.778366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"854.340742ms","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-19T04:54:09.778408Z","caller":"traceutil/trace.go:171","msg":"trace[1018315878] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26269; }","duration":"854.427603ms","start":"2026-05-19T04:54:08.92397Z","end":"2026-05-19T04:54:09.778398Z","steps":["trace[1018315878] 'agreement among raft nodes before linearized reading' (duration: 854.206599ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:54:09.778439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:54:08.923948Z","time spent":"854.483314ms","remote":"127.0.0.1:43890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-19T04:54:09.778516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.114239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusterclasses/\" range_end:\"/registry/cluster.x-k8s.io/clusterclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-19T04:54:09.778605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.434552ms","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-19T04:54:09.778647Z","caller":"traceutil/trace.go:171","msg":"trace[250491514] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusterclasses/; range_end:/registry/cluster.x-k8s.io/clusterclasses0; response_count:0; response_revision:26269; }","duration":"133.323354ms","start":"2026-05-19T04:54:09.645294Z","end":"2026-05-19T04:54:09.778617Z","steps":["trace[250491514] 'agreement among raft nodes before linearized reading' (duration: 133.050118ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:54:09.778698Z","caller":"traceutil/trace.go:171","msg":"trace[1837820504] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26269; }","duration":"633.568405ms","start":"2026-05-19T04:54:09.145104Z","end":"2026-05-19T04:54:09.778672Z","steps":["trace[1837820504] 'agreement among raft nodes before linearized reading' (duration: 633.297319ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:54:09.778754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:54:09.145086Z","time spent":"633.659136ms","remote":"127.0.0.1:43890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-19T04:54:09.778735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"843.385771ms","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-19T04:54:09.778806Z","caller":"traceutil/trace.go:171","msg":"trace[2062580211] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26269; }","duration":"843.509914ms","start":"2026-05-19T04:54:08.93528Z","end":"2026-05-19T04:54:09.778789Z","steps":["trace[2062580211] 'agreement among raft nodes before linearized reading' (duration: 843.277789ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:54:09.778868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:54:08.935256Z","time spent":"843.597486ms","remote":"127.0.0.1:43890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-19T04:54:09.778943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"843.351642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-19T04:54:09.779032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.812197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-05-19T04:54:09.779036Z","caller":"traceutil/trace.go:171","msg":"trace[1965815194] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26269; }","duration":"843.513895ms","start":"2026-05-19T04:54:08.935503Z","end":"2026-05-19T04:54:09.779017Z","steps":["trace[1965815194] 'agreement among raft nodes before linearized reading' (duration: 843.28205ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:54:09.779074Z","caller":"traceutil/trace.go:171","msg":"trace[123787584] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26269; }","duration":"567.885028ms","start":"2026-05-19T04:54:09.211178Z","end":"2026-05-19T04:54:09.779063Z","steps":["trace[123787584] 'agreement among raft nodes before linearized reading' (duration: 567.765316ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:54:09.779079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:54:08.935217Z","time spent":"843.853602ms","remote":"127.0.0.1:43890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-19T04:54:09.779115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:54:09.211167Z","time spent":"567.938069ms","remote":"127.0.0.1:43816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12235,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-05-19T04:54:09.918065Z","caller":"traceutil/trace.go:171","msg":"trace[199179911] linearizableReadLoop","detail":"{readStateIndex:27143; appliedIndex:27140; }","duration":"132.19647ms","start":"2026-05-19T04:54:09.785836Z","end":"2026-05-19T04:54:09.918033Z","steps":["trace[199179911] 'read index received' (duration: 64.731508ms)","trace[199179911] 'applied index is now lower than readState.Index' (duration: 67.464112ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:54:09.918126Z","caller":"traceutil/trace.go:171","msg":"trace[1854278254] transaction","detail":"{read_only:false; response_revision:26272; number_of_response:1; }","duration":"132.69155ms","start":"2026-05-19T04:54:09.785421Z","end":"2026-05-19T04:54:09.918112Z","steps":["trace[1854278254] 'process raft request' (duration: 132.535617ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:54:09.9182Z","caller":"traceutil/trace.go:171","msg":"trace[1028811732] transaction","detail":"{read_only:false; response_revision:26273; number_of_response:1; }","duration":"132.323994ms","start":"2026-05-19T04:54:09.785865Z","end":"2026-05-19T04:54:09.918189Z","steps":["trace[1028811732] 'process raft request' (duration: 132.13396ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:54:09.918275Z","caller":"traceutil/trace.go:171","msg":"trace[328060369] transaction","detail":"{read_only:false; response_revision:26271; number_of_response:1; }","duration":"132.940326ms","start":"2026-05-19T04:54:09.78533Z","end":"2026-05-19T04:54:09.918271Z","steps":["trace[328060369] 'process raft request' (duration: 132.463266ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:54:09.918364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.508477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18542"} {"level":"info","ts":"2026-05-19T04:54:09.918404Z","caller":"traceutil/trace.go:171","msg":"trace[1449930774] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:26273; }","duration":"132.589559ms","start":"2026-05-19T04:54:09.785804Z","end":"2026-05-19T04:54:09.918394Z","steps":["trace[1449930774] 'agreement among raft nodes before linearized reading' (duration: 132.448547ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:54:31.863064Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006ad1.wal"} {"level":"info","ts":"2026-05-19T04:57:19.41175Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25231} {"level":"info","ts":"2026-05-19T04:57:19.555738Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25231,"took":"139.711432ms","hash":2965794868,"current-db-size-bytes":59166720,"current-db-size":"59 MB","current-db-size-in-use-bytes":38174720,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-05-19T04:57:19.55584Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2965794868,"revision":25231,"compact-revision":21981} {"level":"info","ts":"2026-05-19T04:58:56.469916Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"aab6040abcf11","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-19T04:58:56.475431Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-05-19T04:58:56.475506Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-05-19T05:02:19.419673Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28220} {"level":"info","ts":"2026-05-19T05:02:19.565489Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28220,"took":"142.42416ms","hash":3470188121,"current-db-size-bytes":59166720,"current-db-size":"59 MB","current-db-size-in-use-bytes":32415744,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-19T05:02:19.565541Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3470188121,"revision":28220,"compact-revision":25231} {"level":"info","ts":"2026-05-19T05:07:19.426816Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30644} {"level":"info","ts":"2026-05-19T05:07:19.565935Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30644,"took":"134.844986ms","hash":730644508,"current-db-size-bytes":59166720,"current-db-size":"59 MB","current-db-size-in-use-bytes":30261248,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-19T05:07:19.566001Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":730644508,"revision":30644,"compact-revision":28220} {"level":"info","ts":"2026-05-19T05:12:19.432533Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32933} {"level":"info","ts":"2026-05-19T05:12:19.592115Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32933,"took":"154.261795ms","hash":3513294075,"current-db-size-bytes":59166720,"current-db-size":"59 MB","current-db-size-in-use-bytes":30744576,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-19T05:12:19.592196Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3513294075,"revision":32933,"compact-revision":30644} {"level":"info","ts":"2026-05-19T05:17:19.440046Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35232} {"level":"info","ts":"2026-05-19T05:17:19.586078Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35232,"took":"141.130511ms","hash":3905636500,"current-db-size-bytes":59588608,"current-db-size":"60 MB","current-db-size-in-use-bytes":31375360,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-19T05:17:19.586157Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3905636500,"revision":35232,"compact-revision":32933} {"level":"info","ts":"2026-05-19T05:20:10.974492Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"aab6040abcf11","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-19T05:20:10.98285Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-05-19T05:20:10.983011Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-05-19T05:22:19.447345Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37468} {"level":"info","ts":"2026-05-19T05:22:19.569811Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37468,"took":"116.23924ms","hash":4221395360,"current-db-size-bytes":60440576,"current-db-size":"60 MB","current-db-size-in-use-bytes":31600640,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-19T05:22:19.569902Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4221395360,"revision":37468,"compact-revision":35232} {"level":"warn","ts":"2026-05-19T05:26:01.397053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.425961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/nova-api-certs\" ","response":"range_response_count:1 size:4313"} {"level":"info","ts":"2026-05-19T05:26:01.397305Z","caller":"traceutil/trace.go:171","msg":"trace[494108030] range","detail":"{range_begin:/registry/secrets/openstack/nova-api-certs; range_end:; response_count:1; response_revision:41409; }","duration":"105.685047ms","start":"2026-05-19T05:26:01.291582Z","end":"2026-05-19T05:26:01.397267Z","steps":["trace[494108030] 'range keys from in-memory index tree' (duration: 105.191686ms)"],"step_count":1}