{"level":"warn","ts":"2026-06-05T02:42:22.19739Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-05T02:42:22.197471Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.127:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.127:2380","--initial-cluster=instance=https://199.19.213.127:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.127:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.127:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-06-05T02:42:22.19759Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-05T02:42:22.197603Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.127:2380"]} {"level":"info","ts":"2026-06-05T02:42:22.197622Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-06-05T02:42:22.19825Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.127:2379"]} {"level":"info","ts":"2026-06-05T02:42:22.198333Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.127:2380"],"listen-peer-urls":["https://199.19.213.127:2380"],"advertise-client-urls":["https://199.19.213.127:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.127:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.127:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-06-05T02:42:22.202147Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.607147ms"} {"level":"info","ts":"2026-06-05T02:42:22.209064Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"975f045ff4f5c0fb","cluster-id":"b3df79a2daf9b6ed"} {"level":"info","ts":"2026-06-05T02:42:22.209143Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"975f045ff4f5c0fb switched to configuration voters=()"} {"level":"info","ts":"2026-06-05T02:42:22.209162Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"975f045ff4f5c0fb became follower at term 0"} {"level":"info","ts":"2026-06-05T02:42:22.209176Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 975f045ff4f5c0fb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-05T02:42:22.209187Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"975f045ff4f5c0fb became follower at term 1"} {"level":"info","ts":"2026-06-05T02:42:22.20922Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"975f045ff4f5c0fb switched to configuration voters=(10907441632692781307)"} {"level":"warn","ts":"2026-06-05T02:42:22.215394Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-05T02:42:22.217009Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-05T02:42:22.220351Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-06-05T02:42:22.224601Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"975f045ff4f5c0fb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-05T02:42:22.224764Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"975f045ff4f5c0fb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-05T02:42:22.224909Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-05T02:42:22.224961Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-05T02:42:22.224974Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-05T02:42:22.227658Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"975f045ff4f5c0fb switched to configuration voters=(10907441632692781307)"} {"level":"info","ts":"2026-06-05T02:42:22.22785Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b3df79a2daf9b6ed","local-member-id":"975f045ff4f5c0fb","added-peer-id":"975f045ff4f5c0fb","added-peer-peer-urls":["https://199.19.213.127:2380"]} {"level":"info","ts":"2026-06-05T02:42:22.229697Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-06-05T02:42:22.229926Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"975f045ff4f5c0fb","initial-advertise-peer-urls":["https://199.19.213.127:2380"],"listen-peer-urls":["https://199.19.213.127:2380"],"advertise-client-urls":["https://199.19.213.127:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.127:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-05T02:42:22.229962Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-05T02:42:22.230401Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.127:2380"} {"level":"info","ts":"2026-06-05T02:42:22.230433Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.127:2380"} {"level":"info","ts":"2026-06-05T02:42:22.30962Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"975f045ff4f5c0fb is starting a new election at term 1"} {"level":"info","ts":"2026-06-05T02:42:22.309689Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"975f045ff4f5c0fb became pre-candidate at term 1"} {"level":"info","ts":"2026-06-05T02:42:22.309885Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"975f045ff4f5c0fb received MsgPreVoteResp from 975f045ff4f5c0fb at term 1"} {"level":"info","ts":"2026-06-05T02:42:22.309921Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"975f045ff4f5c0fb became candidate at term 2"} {"level":"info","ts":"2026-06-05T02:42:22.309933Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"975f045ff4f5c0fb received MsgVoteResp from 975f045ff4f5c0fb at term 2"} {"level":"info","ts":"2026-06-05T02:42:22.310033Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"975f045ff4f5c0fb became leader at term 2"} {"level":"info","ts":"2026-06-05T02:42:22.310068Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 975f045ff4f5c0fb elected leader 975f045ff4f5c0fb at term 2"} {"level":"info","ts":"2026-06-05T02:42:22.311204Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-05T02:42:22.312182Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"975f045ff4f5c0fb","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.127:2379]}","request-path":"/0/members/975f045ff4f5c0fb/attributes","cluster-id":"b3df79a2daf9b6ed","publish-timeout":"7s"} {"level":"info","ts":"2026-06-05T02:42:22.312325Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-05T02:42:22.312388Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-05T02:42:22.31352Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b3df79a2daf9b6ed","local-member-id":"975f045ff4f5c0fb","cluster-version":"3.5"} {"level":"info","ts":"2026-06-05T02:42:22.313802Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-05T02:42:22.313902Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-05T02:42:22.315273Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.127:2379"} {"level":"info","ts":"2026-06-05T02:42:22.315428Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-05T02:42:22.31545Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-05T02:42:22.315877Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-05T02:43:04.197731Z","caller":"traceutil/trace.go:171","msg":"trace[1494094862] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"148.826941ms","start":"2026-06-05T02:43:04.048875Z","end":"2026-06-05T02:43:04.197702Z","steps":["trace[1494094862] 'process raft request' (duration: 148.30982ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T02:43:04.39937Z","caller":"traceutil/trace.go:171","msg":"trace[1120931343] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"180.31783ms","start":"2026-06-05T02:43:04.219019Z","end":"2026-06-05T02:43:04.399336Z","steps":["trace[1120931343] 'process raft request' (duration: 157.536649ms)","trace[1120931343] 'compare' (duration: 22.570267ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T02:43:04.410646Z","caller":"traceutil/trace.go:171","msg":"trace[2099883119] linearizableReadLoop","detail":"{readStateIndex:658; appliedIndex:656; }","duration":"138.959688ms","start":"2026-06-05T02:43:04.271659Z","end":"2026-06-05T02:43:04.410619Z","steps":["trace[2099883119] 'read index received' (duration: 104.933024ms)","trace[2099883119] 'applied index is now lower than readState.Index' (duration: 34.025504ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T02:43:04.410746Z","caller":"traceutil/trace.go:171","msg":"trace[765564219] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"158.9965ms","start":"2026-06-05T02:43:04.251725Z","end":"2026-06-05T02:43:04.410721Z","steps":["trace[765564219] 'process raft request' (duration: 158.740734ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T02:43:04.410937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.261874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-06-05T02:43:04.411039Z","caller":"traceutil/trace.go:171","msg":"trace[1593807175] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:629; }","duration":"139.390186ms","start":"2026-06-05T02:43:04.271631Z","end":"2026-06-05T02:43:04.411021Z","steps":["trace[1593807175] 'agreement among raft nodes before linearized reading' (duration: 139.11583ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T02:43:04.997851Z","caller":"traceutil/trace.go:171","msg":"trace[1867839882] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"173.423552ms","start":"2026-06-05T02:43:04.82439Z","end":"2026-06-05T02:43:04.997813Z","steps":["trace[1867839882] 'process raft request' (duration: 172.580444ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T02:43:19.204183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.838695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-05T02:43:19.204283Z","caller":"traceutil/trace.go:171","msg":"trace[411449951] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:787; }","duration":"106.975148ms","start":"2026-06-05T02:43:19.097289Z","end":"2026-06-05T02:43:19.204264Z","steps":["trace[411449951] 'range keys from in-memory index tree' (duration: 106.683642ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T02:44:11.919922Z","caller":"traceutil/trace.go:171","msg":"trace[683208519] transaction","detail":"{read_only:false; response_revision:1454; number_of_response:1; }","duration":"157.748929ms","start":"2026-06-05T02:44:11.762144Z","end":"2026-06-05T02:44:11.919893Z","steps":["trace[683208519] 'process raft request' (duration: 157.437452ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T02:44:36.671776Z","caller":"traceutil/trace.go:171","msg":"trace[689909409] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"154.826786ms","start":"2026-06-05T02:44:36.516906Z","end":"2026-06-05T02:44:36.671733Z","steps":["trace[689909409] 'process raft request' (duration: 154.580041ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T02:45:21.074624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.613131ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-05T02:45:21.074853Z","caller":"traceutil/trace.go:171","msg":"trace[804903428] transaction","detail":"{read_only:false; response_revision:2144; number_of_response:1; }","duration":"166.66613ms","start":"2026-06-05T02:45:20.90816Z","end":"2026-06-05T02:45:21.074826Z","steps":["trace[804903428] 'process raft request' (duration: 53.205001ms)","trace[804903428] 'compare' (duration: 112.308155ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T02:45:21.11343Z","caller":"traceutil/trace.go:171","msg":"trace[378087312] linearizableReadLoop","detail":"{readStateIndex:2233; appliedIndex:2231; }","duration":"117.342251ms","start":"2026-06-05T02:45:20.996048Z","end":"2026-06-05T02:45:21.113391Z","steps":["trace[378087312] 'read index received' (duration: 13.7551ms)","trace[378087312] 'applied index is now lower than readState.Index' (duration: 103.585401ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T02:45:21.113648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.549366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-05T02:45:21.113874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.696803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-05T02:45:21.113928Z","caller":"traceutil/trace.go:171","msg":"trace[619771963] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2144; }","duration":"111.807625ms","start":"2026-06-05T02:45:21.002104Z","end":"2026-06-05T02:45:21.113912Z","steps":["trace[619771963] 'agreement among raft nodes before linearized reading' (duration: 111.493698ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T02:45:21.113767Z","caller":"traceutil/trace.go:171","msg":"trace[1227607669] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2144; }","duration":"117.754259ms","start":"2026-06-05T02:45:20.995992Z","end":"2026-06-05T02:45:21.113746Z","steps":["trace[1227607669] 'agreement among raft nodes before linearized reading' (duration: 117.542805ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T02:45:26.609597Z","caller":"traceutil/trace.go:171","msg":"trace[1088563795] transaction","detail":"{read_only:false; response_revision:2175; number_of_response:1; }","duration":"118.123997ms","start":"2026-06-05T02:45:26.491449Z","end":"2026-06-05T02:45:26.609573Z","steps":["trace[1088563795] 'process raft request' (duration: 118.000414ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T02:46:53.17943Z","caller":"traceutil/trace.go:171","msg":"trace[354303590] transaction","detail":"{read_only:false; response_revision:2836; number_of_response:1; }","duration":"143.051118ms","start":"2026-06-05T02:46:53.036348Z","end":"2026-06-05T02:46:53.179399Z","steps":["trace[354303590] 'process raft request' (duration: 69.890948ms)","trace[354303590] 'compare' (duration: 73.043067ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T02:46:53.465244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.77707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-06-05T02:46:53.465353Z","caller":"traceutil/trace.go:171","msg":"trace[1750664250] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2836; }","duration":"113.934233ms","start":"2026-06-05T02:46:53.35139Z","end":"2026-06-05T02:46:53.465324Z","steps":["trace[1750664250] 'range keys from in-memory index tree' (duration: 113.596326ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T02:49:24.166084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.581292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T02:49:24.166195Z","caller":"traceutil/trace.go:171","msg":"trace[314513463] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3831; }","duration":"162.714475ms","start":"2026-06-05T02:49:24.00346Z","end":"2026-06-05T02:49:24.166174Z","steps":["trace[314513463] 'range keys from in-memory index tree' (duration: 162.490291ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T02:49:24.166292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.831439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-972ph\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-06-05T02:49:24.166468Z","caller":"traceutil/trace.go:171","msg":"trace[941889598] range","detail":"{range_begin:/registry/pods/openstack/keepalived-972ph; range_end:; response_count:1; response_revision:3831; }","duration":"109.213236ms","start":"2026-06-05T02:49:24.057223Z","end":"2026-06-05T02:49:24.166437Z","steps":["trace[941889598] 'range keys from in-memory index tree' (duration: 108.549203ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T02:50:44.940307Z","caller":"traceutil/trace.go:171","msg":"trace[1615099884] transaction","detail":"{read_only:false; response_revision:4827; number_of_response:1; }","duration":"105.349434ms","start":"2026-06-05T02:50:44.834923Z","end":"2026-06-05T02:50:44.940272Z","steps":["trace[1615099884] 'process raft request' (duration: 72.986477ms)","trace[1615099884] 'compare' (duration: 32.212553ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T02:50:45.970073Z","caller":"traceutil/trace.go:171","msg":"trace[1253371106] transaction","detail":"{read_only:false; response_revision:4850; number_of_response:1; }","duration":"123.119538ms","start":"2026-06-05T02:50:45.846925Z","end":"2026-06-05T02:50:45.970045Z","steps":["trace[1253371106] 'process raft request' (duration: 108.969519ms)","trace[1253371106] 'compare' (duration: 13.983856ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T02:50:45.98003Z","caller":"traceutil/trace.go:171","msg":"trace[1028180133] transaction","detail":"{read_only:false; response_revision:4852; number_of_response:1; }","duration":"132.685892ms","start":"2026-06-05T02:50:45.84731Z","end":"2026-06-05T02:50:45.979996Z","steps":["trace[1028180133] 'process raft request' (duration: 132.522508ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T02:50:45.98031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.042755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-06-05T02:50:45.980346Z","caller":"traceutil/trace.go:171","msg":"trace[572583691] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4856; }","duration":"130.139128ms","start":"2026-06-05T02:50:45.850199Z","end":"2026-06-05T02:50:45.980338Z","steps":["trace[572583691] 'agreement among raft nodes before linearized reading' (duration: 129.932113ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T02:50:45.980072Z","caller":"traceutil/trace.go:171","msg":"trace[970080331] linearizableReadLoop","detail":"{readStateIndex:5046; appliedIndex:5040; }","duration":"129.824581ms","start":"2026-06-05T02:50:45.850227Z","end":"2026-06-05T02:50:45.980052Z","steps":["trace[970080331] 'read index received' (duration: 105.682989ms)","trace[970080331] 'applied index is now lower than readState.Index' (duration: 24.103941ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T02:50:45.980546Z","caller":"traceutil/trace.go:171","msg":"trace[281616278] transaction","detail":"{read_only:false; response_revision:4853; number_of_response:1; }","duration":"132.13595ms","start":"2026-06-05T02:50:45.848401Z","end":"2026-06-05T02:50:45.980537Z","steps":["trace[281616278] 'process raft request' (duration: 131.475896ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T02:50:45.980505Z","caller":"traceutil/trace.go:171","msg":"trace[680674746] transaction","detail":"{read_only:false; response_revision:4851; number_of_response:1; }","duration":"133.178222ms","start":"2026-06-05T02:50:45.847283Z","end":"2026-06-05T02:50:45.980462Z","steps":["trace[680674746] 'process raft request' (duration: 132.430757ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T02:50:45.980629Z","caller":"traceutil/trace.go:171","msg":"trace[977505673] transaction","detail":"{read_only:false; response_revision:4854; number_of_response:1; }","duration":"132.11574ms","start":"2026-06-05T02:50:45.848505Z","end":"2026-06-05T02:50:45.980621Z","steps":["trace[977505673] 'process raft request' (duration: 131.387704ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T02:50:45.980671Z","caller":"traceutil/trace.go:171","msg":"trace[1284796600] transaction","detail":"{read_only:false; response_revision:4855; number_of_response:1; }","duration":"132.10617ms","start":"2026-06-05T02:50:45.84856Z","end":"2026-06-05T02:50:45.980666Z","steps":["trace[1284796600] 'process raft request' (duration: 131.358604ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T02:50:45.980764Z","caller":"traceutil/trace.go:171","msg":"trace[1675180143] transaction","detail":"{read_only:false; response_revision:4856; number_of_response:1; }","duration":"123.62892ms","start":"2026-06-05T02:50:45.857119Z","end":"2026-06-05T02:50:45.980747Z","steps":["trace[1675180143] 'process raft request' (duration: 122.849404ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T02:50:49.993818Z","caller":"traceutil/trace.go:171","msg":"trace[1835716657] transaction","detail":"{read_only:false; response_revision:5023; number_of_response:1; }","duration":"199.10345ms","start":"2026-06-05T02:50:49.794686Z","end":"2026-06-05T02:50:49.993789Z","steps":["trace[1835716657] 'process raft request' (duration: 198.898076ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T02:50:50.050068Z","caller":"traceutil/trace.go:171","msg":"trace[1593814943] transaction","detail":"{read_only:false; response_revision:5024; number_of_response:1; }","duration":"254.670778ms","start":"2026-06-05T02:50:49.795371Z","end":"2026-06-05T02:50:50.050041Z","steps":["trace[1593814943] 'process raft request' (duration: 249.462238ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T02:51:03.230093Z","caller":"traceutil/trace.go:171","msg":"trace[1701332633] transaction","detail":"{read_only:false; response_revision:5285; number_of_response:1; }","duration":"248.947387ms","start":"2026-06-05T02:51:02.981119Z","end":"2026-06-05T02:51:03.230066Z","steps":["trace[1701332633] 'process raft request' (duration: 248.801004ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T02:51:03.266897Z","caller":"traceutil/trace.go:171","msg":"trace[1060759411] transaction","detail":"{read_only:false; response_revision:5287; number_of_response:1; }","duration":"284.759866ms","start":"2026-06-05T02:51:02.982104Z","end":"2026-06-05T02:51:03.266864Z","steps":["trace[1060759411] 'process raft request' (duration: 284.654964ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T02:51:03.267071Z","caller":"traceutil/trace.go:171","msg":"trace[897420732] linearizableReadLoop","detail":"{readStateIndex:5494; appliedIndex:5490; }","duration":"279.156588ms","start":"2026-06-05T02:51:02.987875Z","end":"2026-06-05T02:51:03.267031Z","steps":["trace[897420732] 'read index received' (duration: 242.102122ms)","trace[897420732] 'applied index is now lower than readState.Index' (duration: 37.051805ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T02:51:03.267166Z","caller":"traceutil/trace.go:171","msg":"trace[2057218503] transaction","detail":"{read_only:false; response_revision:5288; number_of_response:1; }","duration":"284.556412ms","start":"2026-06-05T02:51:02.982579Z","end":"2026-06-05T02:51:03.267136Z","steps":["trace[2057218503] 'process raft request' (duration: 284.230045ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T02:51:03.26691Z","caller":"traceutil/trace.go:171","msg":"trace[390434312] transaction","detail":"{read_only:false; response_revision:5286; number_of_response:1; }","duration":"285.262737ms","start":"2026-06-05T02:51:02.981608Z","end":"2026-06-05T02:51:03.266871Z","steps":["trace[390434312] 'process raft request' (duration: 284.836068ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T02:51:03.267336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.438453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-nc5dw\" ","response":"range_response_count:1 size:9942"} {"level":"info","ts":"2026-06-05T02:51:03.267377Z","caller":"traceutil/trace.go:171","msg":"trace[118372688] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-nc5dw; range_end:; response_count:1; response_revision:5288; }","duration":"279.574427ms","start":"2026-06-05T02:51:02.987792Z","end":"2026-06-05T02:51:03.267366Z","steps":["trace[118372688] 'agreement among raft nodes before linearized reading' (duration: 279.391713ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T02:51:03.335919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.670831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-05T02:51:03.335995Z","caller":"traceutil/trace.go:171","msg":"trace[245615870] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5289; }","duration":"315.797194ms","start":"2026-06-05T02:51:03.020181Z","end":"2026-06-05T02:51:03.335978Z","steps":["trace[245615870] 'agreement among raft nodes before linearized reading' (duration: 315.580679ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T02:51:03.336027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T02:51:03.020163Z","time spent":"315.857785ms","remote":"127.0.0.1:33442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-05T02:51:10.849565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.65599ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-05T02:51:10.849776Z","caller":"traceutil/trace.go:171","msg":"trace[716053351] transaction","detail":"{read_only:false; response_revision:5447; number_of_response:1; }","duration":"172.518658ms","start":"2026-06-05T02:51:10.677239Z","end":"2026-06-05T02:51:10.849758Z","steps":["trace[716053351] 'process raft request' (duration: 49.586342ms)","trace[716053351] 'compare' (duration: 122.390494ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T02:51:11.16787Z","caller":"traceutil/trace.go:171","msg":"trace[1205284074] linearizableReadLoop","detail":"{readStateIndex:5661; appliedIndex:5660; }","duration":"104.359992ms","start":"2026-06-05T02:51:11.063488Z","end":"2026-06-05T02:51:11.167848Z","steps":["trace[1205284074] 'read index received' (duration: 104.207049ms)","trace[1205284074] 'applied index is now lower than readState.Index' (duration: 152.283µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T02:51:11.167967Z","caller":"traceutil/trace.go:171","msg":"trace[1320109673] transaction","detail":"{read_only:false; response_revision:5450; number_of_response:1; }","duration":"134.475461ms","start":"2026-06-05T02:51:11.033457Z","end":"2026-06-05T02:51:11.167933Z","steps":["trace[1320109673] 'process raft request' (duration: 134.265377ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T02:51:11.168021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.523915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-331f5861-ac21-4b94-a64a-11c8fb9b969f\" ","response":"range_response_count:1 size:1275"} {"level":"info","ts":"2026-06-05T02:51:11.168045Z","caller":"traceutil/trace.go:171","msg":"trace[1500002103] range","detail":"{range_begin:/registry/persistentvolumes/pvc-331f5861-ac21-4b94-a64a-11c8fb9b969f; range_end:; response_count:1; response_revision:5450; }","duration":"104.592686ms","start":"2026-06-05T02:51:11.063444Z","end":"2026-06-05T02:51:11.168037Z","steps":["trace[1500002103] 'agreement among raft nodes before linearized reading' (duration: 104.491234ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T02:51:22.266576Z","caller":"traceutil/trace.go:171","msg":"trace[1669945274] transaction","detail":"{read_only:false; response_revision:5608; number_of_response:1; }","duration":"123.723283ms","start":"2026-06-05T02:51:22.142816Z","end":"2026-06-05T02:51:22.266539Z","steps":["trace[1669945274] 'process raft request' (duration: 123.463057ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T02:52:22.700807Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2998} {"level":"info","ts":"2026-06-05T02:52:22.771398Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2998,"took":"68.242958ms","hash":870032952,"current-db-size-bytes":58085376,"current-db-size":"58 MB","current-db-size-in-use-bytes":34185216,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-05T02:52:22.771483Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":870032952,"revision":2998,"compact-revision":-1} {"level":"info","ts":"2026-06-05T02:57:22.70752Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6115} {"level":"info","ts":"2026-06-05T02:57:22.8165Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6115,"took":"100.161663ms","hash":1362352874,"current-db-size-bytes":58085376,"current-db-size":"58 MB","current-db-size-in-use-bytes":23261184,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-06-05T02:57:22.816674Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1362352874,"revision":6115,"compact-revision":2998} {"level":"info","ts":"2026-06-05T02:57:32.401499Z","caller":"traceutil/trace.go:171","msg":"trace[1242804720] transaction","detail":"{read_only:false; response_revision:8359; number_of_response:1; }","duration":"102.030703ms","start":"2026-06-05T02:57:32.299424Z","end":"2026-06-05T02:57:32.401455Z","steps":["trace[1242804720] 'process raft request' (duration: 101.792008ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T02:57:33.332205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.57736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-972ph\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-06-05T02:57:33.332309Z","caller":"traceutil/trace.go:171","msg":"trace[1654546902] range","detail":"{range_begin:/registry/pods/openstack/keepalived-972ph; range_end:; response_count:1; response_revision:8365; }","duration":"235.738403ms","start":"2026-06-05T02:57:33.096545Z","end":"2026-06-05T02:57:33.332284Z","steps":["trace[1654546902] 'range keys from in-memory index tree' (duration: 235.333235ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T02:58:00.974604Z","caller":"traceutil/trace.go:171","msg":"trace[1296453729] transaction","detail":"{read_only:false; response_revision:8568; number_of_response:1; }","duration":"279.639764ms","start":"2026-06-05T02:58:00.694941Z","end":"2026-06-05T02:58:00.974581Z","steps":["trace[1296453729] 'process raft request' (duration: 279.49076ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T02:58:00.975109Z","caller":"traceutil/trace.go:171","msg":"trace[1503823932] linearizableReadLoop","detail":"{readStateIndex:8878; appliedIndex:8878; }","duration":"279.987359ms","start":"2026-06-05T02:58:00.695112Z","end":"2026-06-05T02:58:00.975099Z","steps":["trace[1503823932] 'read index received' (duration: 279.982789ms)","trace[1503823932] 'applied index is now lower than readState.Index' (duration: 4.01µs)"],"step_count":2} {"level":"warn","ts":"2026-06-05T02:58:00.975218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.051102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T02:58:00.975253Z","caller":"traceutil/trace.go:171","msg":"trace[70527733] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:8568; }","duration":"280.151843ms","start":"2026-06-05T02:58:00.695091Z","end":"2026-06-05T02:58:00.975243Z","steps":["trace[70527733] 'agreement among raft nodes before linearized reading' (duration: 280.04776ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T02:58:01.153061Z","caller":"traceutil/trace.go:171","msg":"trace[1347083347] transaction","detail":"{read_only:false; response_revision:8569; number_of_response:1; }","duration":"228.027638ms","start":"2026-06-05T02:58:00.925014Z","end":"2026-06-05T02:58:01.153042Z","steps":["trace[1347083347] 'process raft request' (duration: 227.907645ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T02:58:04.621891Z","caller":"traceutil/trace.go:171","msg":"trace[113997287] transaction","detail":"{read_only:false; response_revision:8586; number_of_response:1; }","duration":"100.845507ms","start":"2026-06-05T02:58:04.520985Z","end":"2026-06-05T02:58:04.62183Z","steps":["trace[113997287] 'process raft request' (duration: 100.602702ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T02:58:13.583152Z","caller":"traceutil/trace.go:171","msg":"trace[1273367777] transaction","detail":"{read_only:false; response_revision:8676; number_of_response:1; }","duration":"191.872884ms","start":"2026-06-05T02:58:13.391252Z","end":"2026-06-05T02:58:13.583125Z","steps":["trace[1273367777] 'process raft request' (duration: 191.625809ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T02:58:13.731167Z","caller":"traceutil/trace.go:171","msg":"trace[1905050031] transaction","detail":"{read_only:false; response_revision:8678; number_of_response:1; }","duration":"129.377268ms","start":"2026-06-05T02:58:13.601749Z","end":"2026-06-05T02:58:13.731126Z","steps":["trace[1905050031] 'process raft request' (duration: 127.850185ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T02:58:13.800706Z","caller":"traceutil/trace.go:171","msg":"trace[277349364] transaction","detail":"{read_only:false; response_revision:8679; number_of_response:1; }","duration":"197.956076ms","start":"2026-06-05T02:58:13.602707Z","end":"2026-06-05T02:58:13.800663Z","steps":["trace[277349364] 'process raft request' (duration: 197.69768ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T02:58:14.766752Z","caller":"traceutil/trace.go:171","msg":"trace[573860717] transaction","detail":"{read_only:false; response_revision:8683; number_of_response:1; }","duration":"107.158263ms","start":"2026-06-05T02:58:14.659543Z","end":"2026-06-05T02:58:14.766701Z","steps":["trace[573860717] 'process raft request' (duration: 106.850597ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T02:58:31.335552Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023d4.wal"} {"level":"info","ts":"2026-06-05T02:59:31.526538Z","caller":"traceutil/trace.go:171","msg":"trace[1168219792] transaction","detail":"{read_only:false; response_revision:9436; number_of_response:1; }","duration":"103.630326ms","start":"2026-06-05T02:59:31.422866Z","end":"2026-06-05T02:59:31.526497Z","steps":["trace[1168219792] 'process raft request' (duration: 103.33553ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:00:02.274159Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"975f045ff4f5c0fb","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-05T03:00:02.279566Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-06-05T03:00:02.279701Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-06-05T03:02:22.714121Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8308} {"level":"info","ts":"2026-06-05T03:02:22.800511Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8308,"took":"83.986544ms","hash":1915862647,"current-db-size-bytes":58085376,"current-db-size":"58 MB","current-db-size-in-use-bytes":26456064,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-06-05T03:02:22.800598Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1915862647,"revision":8308,"compact-revision":6115} {"level":"info","ts":"2026-06-05T03:07:22.720234Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10803} {"level":"info","ts":"2026-06-05T03:07:22.802974Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10803,"took":"80.867166ms","hash":2608701546,"current-db-size-bytes":58085376,"current-db-size":"58 MB","current-db-size-in-use-bytes":27537408,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-06-05T03:07:22.803031Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2608701546,"revision":10803,"compact-revision":8308} {"level":"info","ts":"2026-06-05T03:09:31.637588Z","caller":"traceutil/trace.go:171","msg":"trace[864027641] linearizableReadLoop","detail":"{readStateIndex:14336; appliedIndex:14335; }","duration":"107.328837ms","start":"2026-06-05T03:09:31.530226Z","end":"2026-06-05T03:09:31.637555Z","steps":["trace[864027641] 'read index received' (duration: 84.396717ms)","trace[864027641] 'applied index is now lower than readState.Index' (duration: 22.93055ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T03:09:31.637703Z","caller":"traceutil/trace.go:171","msg":"trace[2006553500] transaction","detail":"{read_only:false; response_revision:13854; number_of_response:1; }","duration":"162.526739ms","start":"2026-06-05T03:09:31.475142Z","end":"2026-06-05T03:09:31.637669Z","steps":["trace[2006553500] 'process raft request' (duration: 139.409774ms)","trace[2006553500] 'compare' (duration: 22.8811ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T03:09:31.637806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.561432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T03:09:31.637872Z","caller":"traceutil/trace.go:171","msg":"trace[1513964635] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:13854; }","duration":"107.712396ms","start":"2026-06-05T03:09:31.530145Z","end":"2026-06-05T03:09:31.637857Z","steps":["trace[1513964635] 'agreement among raft nodes before linearized reading' (duration: 107.566253ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:09:48.840815Z","caller":"traceutil/trace.go:171","msg":"trace[665943460] transaction","detail":"{read_only:false; response_revision:13955; number_of_response:1; }","duration":"167.71626ms","start":"2026-06-05T03:09:48.67307Z","end":"2026-06-05T03:09:48.840786Z","steps":["trace[665943460] 'process raft request' (duration: 167.507736ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:10:38.003599Z","caller":"traceutil/trace.go:171","msg":"trace[257350135] transaction","detail":"{read_only:false; response_revision:14572; number_of_response:1; }","duration":"138.26105ms","start":"2026-06-05T03:10:37.865308Z","end":"2026-06-05T03:10:38.003569Z","steps":["trace[257350135] 'process raft request' (duration: 78.484ms)","trace[257350135] 'compare' (duration: 59.36573ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T03:10:38.003792Z","caller":"traceutil/trace.go:171","msg":"trace[912915656] linearizableReadLoop","detail":"{readStateIndex:15078; appliedIndex:15077; }","duration":"136.358499ms","start":"2026-06-05T03:10:37.867405Z","end":"2026-06-05T03:10:38.003763Z","steps":["trace[912915656] 'read index received' (duration: 76.501087ms)","trace[912915656] 'applied index is now lower than readState.Index' (duration: 59.856192ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T03:10:38.004172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.742227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-nova\" ","response":"range_response_count:1 size:3222"} {"level":"info","ts":"2026-06-05T03:10:38.004223Z","caller":"traceutil/trace.go:171","msg":"trace[1335263171] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-nova; range_end:; response_count:1; response_revision:14573; }","duration":"136.816319ms","start":"2026-06-05T03:10:37.867394Z","end":"2026-06-05T03:10:38.00421Z","steps":["trace[1335263171] 'agreement among raft nodes before linearized reading' (duration: 136.608254ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:11:06.457029Z","caller":"traceutil/trace.go:171","msg":"trace[1433340096] transaction","detail":"{read_only:false; response_revision:15044; number_of_response:1; }","duration":"113.178933ms","start":"2026-06-05T03:11:06.343815Z","end":"2026-06-05T03:11:06.456994Z","steps":["trace[1433340096] 'process raft request' (duration: 64.579192ms)","trace[1433340096] 'compare' (duration: 48.467258ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T03:11:38.91018Z","caller":"traceutil/trace.go:171","msg":"trace[1292000005] transaction","detail":"{read_only:false; response_revision:15212; number_of_response:1; }","duration":"126.461507ms","start":"2026-06-05T03:11:38.783689Z","end":"2026-06-05T03:11:38.91015Z","steps":["trace[1292000005] 'process raft request' (duration: 126.297933ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:12:22.72619Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12907} {"level":"info","ts":"2026-06-05T03:12:22.814374Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12907,"took":"85.570558ms","hash":3421769364,"current-db-size-bytes":58085376,"current-db-size":"58 MB","current-db-size-in-use-bytes":31285248,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-05T03:12:22.814437Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3421769364,"revision":12907,"compact-revision":10803} {"level":"info","ts":"2026-06-05T03:14:33.09056Z","caller":"traceutil/trace.go:171","msg":"trace[523788723] transaction","detail":"{read_only:false; response_revision:16583; number_of_response:1; }","duration":"105.769288ms","start":"2026-06-05T03:14:32.984773Z","end":"2026-06-05T03:14:33.090543Z","steps":["trace[523788723] 'process raft request' (duration: 105.650785ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:17:21.561711Z","caller":"traceutil/trace.go:171","msg":"trace[2104968802] transaction","detail":"{read_only:false; response_revision:17805; number_of_response:1; }","duration":"145.624576ms","start":"2026-06-05T03:17:21.41605Z","end":"2026-06-05T03:17:21.561674Z","steps":["trace[2104968802] 'process raft request' (duration: 145.160476ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:17:21.949785Z","caller":"traceutil/trace.go:171","msg":"trace[80896367] transaction","detail":"{read_only:false; response_revision:17807; number_of_response:1; }","duration":"371.263155ms","start":"2026-06-05T03:17:21.578494Z","end":"2026-06-05T03:17:21.949757Z","steps":["trace[80896367] 'process raft request' (duration: 371.100131ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:17:21.949993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T03:17:21.578465Z","time spent":"371.436029ms","remote":"127.0.0.1:33360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20979,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T03:17:22.24088Z","caller":"traceutil/trace.go:171","msg":"trace[814800189] transaction","detail":"{read_only:false; response_revision:17809; number_of_response:1; }","duration":"490.69227ms","start":"2026-06-05T03:17:21.750158Z","end":"2026-06-05T03:17:22.240851Z","steps":["trace[814800189] 'process raft request' (duration: 490.602308ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:17:22.240902Z","caller":"traceutil/trace.go:171","msg":"trace[255597472] transaction","detail":"{read_only:false; response_revision:17808; number_of_response:1; }","duration":"512.1863ms","start":"2026-06-05T03:17:21.728647Z","end":"2026-06-05T03:17:22.240834Z","steps":["trace[255597472] 'process raft request' (duration: 496.362131ms)","trace[255597472] 'compare' (duration: 15.409929ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T03:17:22.240993Z","caller":"traceutil/trace.go:171","msg":"trace[219831508] linearizableReadLoop","detail":"{readStateIndex:18418; appliedIndex:18414; }","duration":"430.105044ms","start":"2026-06-05T03:17:21.810867Z","end":"2026-06-05T03:17:22.240972Z","steps":["trace[219831508] 'read index received' (duration: 138.75652ms)","trace[219831508] 'applied index is now lower than readState.Index' (duration: 291.346204ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T03:17:22.241048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T03:17:21.750129Z","time spent":"490.829914ms","remote":"127.0.0.1:33442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T03:17:22.241084Z","caller":"traceutil/trace.go:171","msg":"trace[728219488] transaction","detail":"{read_only:false; response_revision:17810; number_of_response:1; }","duration":"486.051921ms","start":"2026-06-05T03:17:21.755001Z","end":"2026-06-05T03:17:22.241053Z","steps":["trace[728219488] 'process raft request' (duration: 485.814516ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:17:22.241122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T03:17:21.728616Z","time spent":"512.384754ms","remote":"127.0.0.1:33442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T03:17:22.241138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.259927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-05T03:17:22.241184Z","caller":"traceutil/trace.go:171","msg":"trace[2077223627] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:17810; }","duration":"430.352149ms","start":"2026-06-05T03:17:21.810818Z","end":"2026-06-05T03:17:22.24117Z","steps":["trace[2077223627] 'agreement among raft nodes before linearized reading' (duration: 430.262017ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:17:22.241226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T03:17:21.810774Z","time spent":"430.44309ms","remote":"127.0.0.1:56932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":30,"request content":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true "} {"level":"warn","ts":"2026-06-05T03:17:22.241258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T03:17:21.754988Z","time spent":"486.177583ms","remote":"127.0.0.1:33442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T03:17:22.24145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.021791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-05T03:17:22.241492Z","caller":"traceutil/trace.go:171","msg":"trace[1020024668] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:17810; }","duration":"358.113253ms","start":"2026-06-05T03:17:21.883369Z","end":"2026-06-05T03:17:22.241482Z","steps":["trace[1020024668] 'agreement among raft nodes before linearized reading' (duration: 358.011131ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:17:22.241525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T03:17:21.883349Z","time spent":"358.165534ms","remote":"127.0.0.1:33642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":32,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-06-05T03:17:22.241518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.709899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-05T03:17:22.24165Z","caller":"traceutil/trace.go:171","msg":"trace[266425456] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17810; }","duration":"246.873922ms","start":"2026-06-05T03:17:21.994731Z","end":"2026-06-05T03:17:22.241605Z","steps":["trace[266425456] 'agreement among raft nodes before linearized reading' (duration: 246.674698ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:17:22.241559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.365682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-tzp2z\" ","response":"range_response_count:1 size:22126"} {"level":"info","ts":"2026-06-05T03:17:22.241756Z","caller":"traceutil/trace.go:171","msg":"trace[2107457311] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-tzp2z; range_end:; response_count:1; response_revision:17810; }","duration":"289.613357ms","start":"2026-06-05T03:17:21.952131Z","end":"2026-06-05T03:17:22.241744Z","steps":["trace[2107457311] 'agreement among raft nodes before linearized reading' (duration: 289.27699ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:17:22.731368Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15502} {"level":"info","ts":"2026-06-05T03:17:22.89241Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15502,"took":"157.718764ms","hash":146554657,"current-db-size-bytes":58085376,"current-db-size":"58 MB","current-db-size-in-use-bytes":32661504,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-06-05T03:17:22.892849Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":146554657,"revision":15502,"compact-revision":12907} {"level":"info","ts":"2026-06-05T03:20:25.436695Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"975f045ff4f5c0fb","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-05T03:20:25.446344Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-06-05T03:20:25.446468Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-06-05T03:22:22.739718Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17818} {"level":"info","ts":"2026-06-05T03:22:22.837666Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17818,"took":"94.38204ms","hash":1635401003,"current-db-size-bytes":58085376,"current-db-size":"58 MB","current-db-size-in-use-bytes":34185216,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-05T03:22:22.837723Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1635401003,"revision":17818,"compact-revision":15502} {"level":"info","ts":"2026-06-05T03:22:30.123523Z","caller":"traceutil/trace.go:171","msg":"trace[1487163515] linearizableReadLoop","detail":"{readStateIndex:20890; appliedIndex:20888; }","duration":"165.931601ms","start":"2026-06-05T03:22:29.957565Z","end":"2026-06-05T03:22:30.123497Z","steps":["trace[1487163515] 'read index received' (duration: 17.846962ms)","trace[1487163515] 'applied index is now lower than readState.Index' (duration: 148.083149ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T03:22:30.123676Z","caller":"traceutil/trace.go:171","msg":"trace[1414789769] transaction","detail":"{read_only:false; response_revision:20203; number_of_response:1; }","duration":"245.233508ms","start":"2026-06-05T03:22:29.878354Z","end":"2026-06-05T03:22:30.123588Z","steps":["trace[1414789769] 'process raft request' (duration: 244.957882ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:22:30.123842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.151425ms","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:6"} {"level":"info","ts":"2026-06-05T03:22:30.123899Z","caller":"traceutil/trace.go:171","msg":"trace[80354024] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:20203; }","duration":"166.36993ms","start":"2026-06-05T03:22:29.957514Z","end":"2026-06-05T03:22:30.123884Z","steps":["trace[80354024] 'agreement among raft nodes before linearized reading' (duration: 166.144825ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:22:30.124611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.580804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-05T03:22:30.124709Z","caller":"traceutil/trace.go:171","msg":"trace[1759311914] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20203; }","duration":"130.934161ms","start":"2026-06-05T03:22:29.993755Z","end":"2026-06-05T03:22:30.124689Z","steps":["trace[1759311914] 'agreement among raft nodes before linearized reading' (duration: 129.93518ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:22:45.555183Z","caller":"traceutil/trace.go:171","msg":"trace[1639525525] linearizableReadLoop","detail":"{readStateIndex:20971; appliedIndex:20970; }","duration":"112.264073ms","start":"2026-06-05T03:22:45.442893Z","end":"2026-06-05T03:22:45.555157Z","steps":["trace[1639525525] 'read index received' (duration: 112.1401ms)","trace[1639525525] 'applied index is now lower than readState.Index' (duration: 122.713µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T03:22:45.555256Z","caller":"traceutil/trace.go:171","msg":"trace[1228083137] transaction","detail":"{read_only:false; response_revision:20280; number_of_response:1; }","duration":"153.076166ms","start":"2026-06-05T03:22:45.402145Z","end":"2026-06-05T03:22:45.555221Z","steps":["trace[1228083137] 'process raft request' (duration: 152.807119ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:22:45.555366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.445357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-05T03:22:45.555413Z","caller":"traceutil/trace.go:171","msg":"trace[595725728] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:20280; }","duration":"112.55657ms","start":"2026-06-05T03:22:45.442844Z","end":"2026-06-05T03:22:45.555401Z","steps":["trace[595725728] 'agreement among raft nodes before linearized reading' (duration: 112.416667ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:22:51.425136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.665465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-06-05T03:22:51.42529Z","caller":"traceutil/trace.go:171","msg":"trace[1344937526] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:20308; }","duration":"236.87747ms","start":"2026-06-05T03:22:51.18839Z","end":"2026-06-05T03:22:51.425268Z","steps":["trace[1344937526] 'range keys from in-memory index tree' (duration: 236.487241ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:22:51.836382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.160656ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-06-05T03:22:51.83663Z","caller":"traceutil/trace.go:171","msg":"trace[1747535234] linearizableReadLoop","detail":"{readStateIndex:21001; appliedIndex:21000; }","duration":"550.322327ms","start":"2026-06-05T03:22:51.286293Z","end":"2026-06-05T03:22:51.836615Z","steps":["trace[1747535234] 'read index received' (duration: 90.872µs)","trace[1747535234] 'applied index is now lower than readState.Index' (duration: 550.230415ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T03:22:51.8374Z","caller":"traceutil/trace.go:171","msg":"trace[1994571257] transaction","detail":"{read_only:false; response_revision:20309; number_of_response:1; }","duration":"253.262939ms","start":"2026-06-05T03:22:51.58411Z","end":"2026-06-05T03:22:51.837372Z","steps":["trace[1994571257] 'process raft request' (duration: 252.39862ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:22:51.911125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.81448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-06-05T03:22:51.911204Z","caller":"traceutil/trace.go:171","msg":"trace[1470440703] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20309; }","duration":"624.955543ms","start":"2026-06-05T03:22:51.28623Z","end":"2026-06-05T03:22:51.911186Z","steps":["trace[1470440703] 'agreement among raft nodes before linearized reading' (duration: 551.053712ms)","trace[1470440703] 'range keys from in-memory index tree' (duration: 73.706347ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T03:22:51.911237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T03:22:51.286199Z","time spent":"625.030764ms","remote":"127.0.0.1:33442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-05T03:22:51.911345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.114883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-06-05T03:22:51.911415Z","caller":"traceutil/trace.go:171","msg":"trace[60054082] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20311; }","duration":"624.222196ms","start":"2026-06-05T03:22:51.287177Z","end":"2026-06-05T03:22:51.911399Z","steps":["trace[60054082] 'agreement among raft nodes before linearized reading' (duration: 624.025842ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:22:51.911451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T03:22:51.287162Z","time spent":"624.281367ms","remote":"127.0.0.1:33442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-05T03:22:51.911474Z","caller":"traceutil/trace.go:171","msg":"trace[248001289] transaction","detail":"{read_only:false; response_revision:20311; number_of_response:1; }","duration":"326.746521ms","start":"2026-06-05T03:22:51.584718Z","end":"2026-06-05T03:22:51.911464Z","steps":["trace[248001289] 'process raft request' (duration: 326.438515ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:22:51.911572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T03:22:51.584685Z","time spent":"326.815533ms","remote":"127.0.0.1:33442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T03:22:51.911629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.538174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-05T03:22:51.911659Z","caller":"traceutil/trace.go:171","msg":"trace[2064571602] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:20311; }","duration":"244.626405ms","start":"2026-06-05T03:22:51.667023Z","end":"2026-06-05T03:22:51.911649Z","steps":["trace[2064571602] 'agreement among raft nodes before linearized reading' (duration: 244.575964ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:22:51.91187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.087136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-06-05T03:22:51.911895Z","caller":"traceutil/trace.go:171","msg":"trace[30451644] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:20311; }","duration":"482.132386ms","start":"2026-06-05T03:22:51.429756Z","end":"2026-06-05T03:22:51.911889Z","steps":["trace[30451644] 'agreement among raft nodes before linearized reading' (duration: 482.034994ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:22:51.911915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T03:22:51.429739Z","time spent":"482.171147ms","remote":"127.0.0.1:33360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12241,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-06-05T03:22:51.912037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.511337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-05T03:22:51.912065Z","caller":"traceutil/trace.go:171","msg":"trace[705436823] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:20311; }","duration":"612.574678ms","start":"2026-06-05T03:22:51.299481Z","end":"2026-06-05T03:22:51.912056Z","steps":["trace[705436823] 'agreement among raft nodes before linearized reading' (duration: 612.532887ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:22:51.912088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T03:22:51.299464Z","time spent":"612.618219ms","remote":"127.0.0.1:42164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":30,"request content":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true "} {"level":"info","ts":"2026-06-05T03:22:51.912454Z","caller":"traceutil/trace.go:171","msg":"trace[1493946557] transaction","detail":"{read_only:false; response_revision:20310; number_of_response:1; }","duration":"327.990218ms","start":"2026-06-05T03:22:51.584443Z","end":"2026-06-05T03:22:51.912434Z","steps":["trace[1493946557] 'process raft request' (duration: 252.909611ms)","trace[1493946557] 'compare' (duration: 73.675016ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T03:22:51.912545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T03:22:51.584431Z","time spent":"328.06994ms","remote":"127.0.0.1:33442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T03:22:52.231386Z","caller":"traceutil/trace.go:171","msg":"trace[982206209] transaction","detail":"{read_only:false; response_revision:20313; number_of_response:1; }","duration":"314.925369ms","start":"2026-06-05T03:22:51.916439Z","end":"2026-06-05T03:22:52.231364Z","steps":["trace[982206209] 'process raft request' (duration: 314.744595ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:22:52.231547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T03:22:51.916417Z","time spent":"315.041921ms","remote":"127.0.0.1:33442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T03:22:52.231548Z","caller":"traceutil/trace.go:171","msg":"trace[392455508] linearizableReadLoop","detail":"{readStateIndex:21007; appliedIndex:21005; }","duration":"208.967842ms","start":"2026-06-05T03:22:52.02256Z","end":"2026-06-05T03:22:52.231528Z","steps":["trace[392455508] 'read index received' (duration: 208.570013ms)","trace[392455508] 'applied index is now lower than readState.Index' (duration: 396.149µs)"],"step_count":2} {"level":"warn","ts":"2026-06-05T03:22:52.231652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.080804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-05T03:22:52.231737Z","caller":"traceutil/trace.go:171","msg":"trace[1267718652] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:20314; }","duration":"209.218697ms","start":"2026-06-05T03:22:52.022506Z","end":"2026-06-05T03:22:52.231725Z","steps":["trace[1267718652] 'agreement among raft nodes before linearized reading' (duration: 209.075184ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:22:52.231755Z","caller":"traceutil/trace.go:171","msg":"trace[897971361] transaction","detail":"{read_only:false; response_revision:20314; number_of_response:1; }","duration":"315.151704ms","start":"2026-06-05T03:22:51.916518Z","end":"2026-06-05T03:22:52.231669Z","steps":["trace[897971361] 'process raft request' (duration: 314.803476ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:22:52.232272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.235648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-05T03:22:52.232345Z","caller":"traceutil/trace.go:171","msg":"trace[1083788713] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20314; }","duration":"117.357441ms","start":"2026-06-05T03:22:52.114973Z","end":"2026-06-05T03:22:52.232331Z","steps":["trace[1083788713] 'agreement among raft nodes before linearized reading' (duration: 116.914701ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:22:52.231996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.955587ms","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:6"} {"level":"warn","ts":"2026-06-05T03:22:52.232334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T03:22:51.916509Z","time spent":"315.696835ms","remote":"127.0.0.1:33442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T03:22:52.232396Z","caller":"traceutil/trace.go:171","msg":"trace[509169282] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:20314; }","duration":"199.386906ms","start":"2026-06-05T03:22:52.032993Z","end":"2026-06-05T03:22:52.23238Z","steps":["trace[509169282] 'agreement among raft nodes before linearized reading' (duration: 198.855064ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:22:52.232024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.298461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-05T03:22:52.232677Z","caller":"traceutil/trace.go:171","msg":"trace[1437200771] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:20314; }","duration":"175.986406ms","start":"2026-06-05T03:22:52.056679Z","end":"2026-06-05T03:22:52.232665Z","steps":["trace[1437200771] 'agreement among raft nodes before linearized reading' (duration: 175.315312ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:22:52.454638Z","caller":"traceutil/trace.go:171","msg":"trace[1047990451] transaction","detail":"{read_only:false; response_revision:20315; number_of_response:1; }","duration":"215.647795ms","start":"2026-06-05T03:22:52.238956Z","end":"2026-06-05T03:22:52.454604Z","steps":["trace[1047990451] 'process raft request' (duration: 156.816406ms)","trace[1047990451] 'compare' (duration: 58.612344ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T03:22:52.454677Z","caller":"traceutil/trace.go:171","msg":"trace[233427822] transaction","detail":"{read_only:false; response_revision:20316; number_of_response:1; }","duration":"212.567978ms","start":"2026-06-05T03:22:52.242085Z","end":"2026-06-05T03:22:52.454653Z","steps":["trace[233427822] 'process raft request' (duration: 212.490976ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:22:52.454716Z","caller":"traceutil/trace.go:171","msg":"trace[1615004607] linearizableReadLoop","detail":"{readStateIndex:21008; appliedIndex:21007; }","duration":"215.4542ms","start":"2026-06-05T03:22:52.239229Z","end":"2026-06-05T03:22:52.454683Z","steps":["trace[1615004607] 'read index received' (duration: 156.56258ms)","trace[1615004607] 'applied index is now lower than readState.Index' (duration: 58.88801ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T03:22:52.454921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.441331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-06-05T03:22:52.454964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.434631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-06-05T03:22:52.454964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.730266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-06-05T03:22:52.454977Z","caller":"traceutil/trace.go:171","msg":"trace[574230913] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:20316; }","duration":"130.573904ms","start":"2026-06-05T03:22:52.324389Z","end":"2026-06-05T03:22:52.454963Z","steps":["trace[574230913] 'agreement among raft nodes before linearized reading' (duration: 130.459351ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:22:52.455001Z","caller":"traceutil/trace.go:171","msg":"trace[1608451538] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20316; }","duration":"209.501783ms","start":"2026-06-05T03:22:52.245486Z","end":"2026-06-05T03:22:52.454988Z","steps":["trace[1608451538] 'agreement among raft nodes before linearized reading' (duration: 209.353229ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:22:52.455005Z","caller":"traceutil/trace.go:171","msg":"trace[902430847] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:20316; }","duration":"215.84396ms","start":"2026-06-05T03:22:52.239151Z","end":"2026-06-05T03:22:52.454995Z","steps":["trace[902430847] 'agreement among raft nodes before linearized reading' (duration: 215.665985ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:22:52.455241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.550927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1634"} {"level":"info","ts":"2026-06-05T03:22:52.455279Z","caller":"traceutil/trace.go:171","msg":"trace[913870500] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:20316; }","duration":"175.589728ms","start":"2026-06-05T03:22:52.279681Z","end":"2026-06-05T03:22:52.455271Z","steps":["trace[913870500] 'agreement among raft nodes before linearized reading' (duration: 175.458435ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:26:43.473992Z","caller":"traceutil/trace.go:171","msg":"trace[2041757002] transaction","detail":"{read_only:false; response_revision:22724; number_of_response:1; }","duration":"318.815699ms","start":"2026-06-05T03:26:43.155151Z","end":"2026-06-05T03:26:43.473967Z","steps":["trace[2041757002] 'process raft request' (duration: 318.651776ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:26:43.474157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T03:26:43.155122Z","time spent":"318.949992ms","remote":"127.0.0.1:33442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T03:26:43.475629Z","caller":"traceutil/trace.go:171","msg":"trace[1086459439] transaction","detail":"{read_only:false; response_revision:22725; number_of_response:1; }","duration":"201.466795ms","start":"2026-06-05T03:26:43.274132Z","end":"2026-06-05T03:26:43.475599Z","steps":["trace[1086459439] 'process raft request' (duration: 201.291641ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:27:22.746771Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20163} {"level":"info","ts":"2026-06-05T03:27:22.854916Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20163,"took":"104.026878ms","hash":2239828927,"current-db-size-bytes":58507264,"current-db-size":"58 MB","current-db-size-in-use-bytes":40677376,"current-db-size-in-use":"41 MB"} {"level":"info","ts":"2026-06-05T03:27:22.855004Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2239828927,"revision":20163,"compact-revision":17818} {"level":"info","ts":"2026-06-05T03:30:03.565349Z","caller":"traceutil/trace.go:171","msg":"trace[932632910] transaction","detail":"{read_only:false; response_revision:24768; number_of_response:1; }","duration":"131.735891ms","start":"2026-06-05T03:30:03.433528Z","end":"2026-06-05T03:30:03.565264Z","steps":["trace[932632910] 'process raft request' (duration: 96.872665ms)","trace[932632910] 'compare' (duration: 34.662472ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T03:30:09.093325Z","caller":"traceutil/trace.go:171","msg":"trace[1963375153] transaction","detail":"{read_only:false; response_revision:24821; number_of_response:1; }","duration":"116.942726ms","start":"2026-06-05T03:30:08.976336Z","end":"2026-06-05T03:30:09.093279Z","steps":["trace[1963375153] 'process raft request' (duration: 116.604767ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:30:09.853272Z","caller":"traceutil/trace.go:171","msg":"trace[449927008] linearizableReadLoop","detail":"{readStateIndex:25645; appliedIndex:25641; }","duration":"113.297828ms","start":"2026-06-05T03:30:09.739944Z","end":"2026-06-05T03:30:09.853242Z","steps":["trace[449927008] 'read index received' (duration: 111.274074ms)","trace[449927008] 'applied index is now lower than readState.Index' (duration: 2.022794ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T03:30:09.853668Z","caller":"traceutil/trace.go:171","msg":"trace[1521622483] transaction","detail":"{read_only:false; response_revision:24826; number_of_response:1; }","duration":"239.95156ms","start":"2026-06-05T03:30:09.613651Z","end":"2026-06-05T03:30:09.853603Z","steps":["trace[1521622483] 'process raft request' (duration: 239.545682ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:30:09.853718Z","caller":"traceutil/trace.go:171","msg":"trace[1794636799] transaction","detail":"{read_only:false; response_revision:24823; number_of_response:1; }","duration":"242.324292ms","start":"2026-06-05T03:30:09.61134Z","end":"2026-06-05T03:30:09.853664Z","steps":["trace[1794636799] 'process raft request' (duration: 240.022422ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:30:09.853718Z","caller":"traceutil/trace.go:171","msg":"trace[310806376] transaction","detail":"{read_only:false; response_revision:24825; number_of_response:1; }","duration":"240.102344ms","start":"2026-06-05T03:30:09.613563Z","end":"2026-06-05T03:30:09.853666Z","steps":["trace[310806376] 'process raft request' (duration: 239.588193ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:30:09.853671Z","caller":"traceutil/trace.go:171","msg":"trace[1794087018] transaction","detail":"{read_only:false; response_revision:24824; number_of_response:1; }","duration":"240.177825ms","start":"2026-06-05T03:30:09.613441Z","end":"2026-06-05T03:30:09.853619Z","steps":["trace[1794087018] 'process raft request' (duration: 239.605313ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:30:09.853961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.987782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-06-05T03:30:09.854042Z","caller":"traceutil/trace.go:171","msg":"trace[1476167610] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:24826; }","duration":"114.116754ms","start":"2026-06-05T03:30:09.739903Z","end":"2026-06-05T03:30:09.85402Z","steps":["trace[1476167610] 'agreement among raft nodes before linearized reading' (duration: 113.855999ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:30:10.343134Z","caller":"traceutil/trace.go:171","msg":"trace[1987901076] transaction","detail":"{read_only:false; response_revision:24828; number_of_response:1; }","duration":"230.055388ms","start":"2026-06-05T03:30:10.113039Z","end":"2026-06-05T03:30:10.343094Z","steps":["trace[1987901076] 'process raft request' (duration: 229.862774ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:30:10.376007Z","caller":"traceutil/trace.go:171","msg":"trace[523154099] transaction","detail":"{read_only:false; response_revision:24830; number_of_response:1; }","duration":"138.794132ms","start":"2026-06-05T03:30:10.237173Z","end":"2026-06-05T03:30:10.375967Z","steps":["trace[523154099] 'process raft request' (duration: 138.71607ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:30:10.376182Z","caller":"traceutil/trace.go:171","msg":"trace[1879112987] transaction","detail":"{read_only:false; response_revision:24829; number_of_response:1; }","duration":"211.215394ms","start":"2026-06-05T03:30:10.164923Z","end":"2026-06-05T03:30:10.376139Z","steps":["trace[1879112987] 'process raft request' (duration: 210.777394ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:30:11.022654Z","caller":"traceutil/trace.go:171","msg":"trace[819928939] linearizableReadLoop","detail":"{readStateIndex:25652; appliedIndex:25651; }","duration":"262.590664ms","start":"2026-06-05T03:30:10.760033Z","end":"2026-06-05T03:30:11.022624Z","steps":["trace[819928939] 'read index received' (duration: 262.466931ms)","trace[819928939] 'applied index is now lower than readState.Index' (duration: 122.833µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T03:30:11.022962Z","caller":"traceutil/trace.go:171","msg":"trace[1683527631] transaction","detail":"{read_only:false; response_revision:24833; number_of_response:1; }","duration":"362.376451ms","start":"2026-06-05T03:30:10.660562Z","end":"2026-06-05T03:30:11.022938Z","steps":["trace[1683527631] 'process raft request' (duration: 361.872471ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:30:11.023103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T03:30:10.660542Z","time spent":"362.472844ms","remote":"127.0.0.1:33442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T03:30:11.02312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.092225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-05T03:30:11.023203Z","caller":"traceutil/trace.go:171","msg":"trace[1707065336] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24833; }","duration":"263.235519ms","start":"2026-06-05T03:30:10.759945Z","end":"2026-06-05T03:30:11.023181Z","steps":["trace[1707065336] 'agreement among raft nodes before linearized reading' (duration: 262.982123ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:30:11.023718Z","caller":"traceutil/trace.go:171","msg":"trace[413011531] transaction","detail":"{read_only:false; response_revision:24834; number_of_response:1; }","duration":"218.875928ms","start":"2026-06-05T03:30:10.80482Z","end":"2026-06-05T03:30:11.023696Z","steps":["trace[413011531] 'process raft request' (duration: 218.673224ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:30:11.226951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.281024ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-06-05T03:30:11.227204Z","caller":"traceutil/trace.go:171","msg":"trace[400590403] linearizableReadLoop","detail":"{readStateIndex:25654; appliedIndex:25652; }","duration":"204.430568ms","start":"2026-06-05T03:30:11.02275Z","end":"2026-06-05T03:30:11.227181Z","steps":["trace[400590403] 'read index received' (duration: 681.305µs)","trace[400590403] 'applied index is now lower than readState.Index' (duration: 203.748163ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T03:30:11.227351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.894439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-05T03:30:11.227466Z","caller":"traceutil/trace.go:171","msg":"trace[579104853] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24834; }","duration":"233.068352ms","start":"2026-06-05T03:30:10.994375Z","end":"2026-06-05T03:30:11.227443Z","steps":["trace[579104853] 'agreement among raft nodes before linearized reading' (duration: 232.837427ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:30:11.227643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.561285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-06-05T03:30:11.227694Z","caller":"traceutil/trace.go:171","msg":"trace[225260618] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24835; }","duration":"129.633787ms","start":"2026-06-05T03:30:11.098039Z","end":"2026-06-05T03:30:11.227673Z","steps":["trace[225260618] 'agreement among raft nodes before linearized reading' (duration: 129.524614ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:30:11.227822Z","caller":"traceutil/trace.go:171","msg":"trace[544839909] transaction","detail":"{read_only:false; response_revision:24835; number_of_response:1; }","duration":"200.077946ms","start":"2026-06-05T03:30:11.027738Z","end":"2026-06-05T03:30:11.227816Z","steps":["trace[544839909] 'process raft request' (duration: 199.334149ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:30:11.227381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.809788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-06-05T03:30:11.227902Z","caller":"traceutil/trace.go:171","msg":"trace[2106982024] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24834; }","duration":"389.385551ms","start":"2026-06-05T03:30:10.838495Z","end":"2026-06-05T03:30:11.22788Z","steps":["trace[2106982024] 'agreement among raft nodes before linearized reading' (duration: 388.744227ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:30:11.227943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T03:30:10.838473Z","time spent":"389.456102ms","remote":"127.0.0.1:33442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-05T03:30:13.575319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.420909ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-05T03:30:13.575574Z","caller":"traceutil/trace.go:171","msg":"trace[461078957] linearizableReadLoop","detail":"{readStateIndex:25674; appliedIndex:25672; }","duration":"222.980567ms","start":"2026-06-05T03:30:13.352571Z","end":"2026-06-05T03:30:13.575551Z","steps":["trace[461078957] 'read index received' (duration: 60.21651ms)","trace[461078957] 'applied index is now lower than readState.Index' (duration: 162.762927ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T03:30:13.575823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.249942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1634"} {"level":"info","ts":"2026-06-05T03:30:13.575889Z","caller":"traceutil/trace.go:171","msg":"trace[1051919882] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:24854; }","duration":"223.348454ms","start":"2026-06-05T03:30:13.35252Z","end":"2026-06-05T03:30:13.575869Z","steps":["trace[1051919882] 'agreement among raft nodes before linearized reading' (duration: 223.100559ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:30:13.575805Z","caller":"traceutil/trace.go:171","msg":"trace[309368993] transaction","detail":"{read_only:false; response_revision:24853; number_of_response:1; }","duration":"260.136852ms","start":"2026-06-05T03:30:13.315632Z","end":"2026-06-05T03:30:13.575768Z","steps":["trace[309368993] 'process raft request' (duration: 97.13674ms)","trace[309368993] 'compare' (duration: 161.746495ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T03:30:13.57606Z","caller":"traceutil/trace.go:171","msg":"trace[175826410] transaction","detail":"{read_only:false; response_revision:24854; number_of_response:1; }","duration":"260.272646ms","start":"2026-06-05T03:30:13.315748Z","end":"2026-06-05T03:30:13.576021Z","steps":["trace[175826410] 'process raft request' (duration: 259.709854ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:30:13.823046Z","caller":"traceutil/trace.go:171","msg":"trace[1750637398] linearizableReadLoop","detail":"{readStateIndex:25675; appliedIndex:25674; }","duration":"243.130858ms","start":"2026-06-05T03:30:13.579878Z","end":"2026-06-05T03:30:13.823009Z","steps":["trace[1750637398] 'read index received' (duration: 220.847441ms)","trace[1750637398] 'applied index is now lower than readState.Index' (duration: 22.282317ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T03:30:13.823186Z","caller":"traceutil/trace.go:171","msg":"trace[885822116] transaction","detail":"{read_only:false; response_revision:24855; number_of_response:1; }","duration":"369.116867ms","start":"2026-06-05T03:30:13.454016Z","end":"2026-06-05T03:30:13.823133Z","steps":["trace[885822116] 'process raft request' (duration: 346.656845ms)","trace[885822116] 'compare' (duration: 21.862219ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T03:30:13.823308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.428965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-3b537496-7597-4764-9cf6-d53b9d484f8a\" ","response":"range_response_count:1 size:1294"} {"level":"info","ts":"2026-06-05T03:30:13.823395Z","caller":"traceutil/trace.go:171","msg":"trace[688063678] range","detail":"{range_begin:/registry/persistentvolumes/pvc-3b537496-7597-4764-9cf6-d53b9d484f8a; range_end:; response_count:1; response_revision:24855; }","duration":"243.576158ms","start":"2026-06-05T03:30:13.579802Z","end":"2026-06-05T03:30:13.823379Z","steps":["trace[688063678] 'agreement among raft nodes before linearized reading' (duration: 243.354723ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:30:13.823478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T03:30:13.45399Z","time spent":"369.26683ms","remote":"127.0.0.1:33442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T03:30:13.823598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.883018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-06-05T03:30:13.823956Z","caller":"traceutil/trace.go:171","msg":"trace[191894659] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24855; }","duration":"190.273256ms","start":"2026-06-05T03:30:13.633642Z","end":"2026-06-05T03:30:13.823916Z","steps":["trace[191894659] 'agreement among raft nodes before linearized reading' (duration: 189.723265ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:30:14.116825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.559136ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-05T03:30:14.116945Z","caller":"traceutil/trace.go:171","msg":"trace[416682650] transaction","detail":"{read_only:false; response_revision:24856; number_of_response:1; }","duration":"288.01335ms","start":"2026-06-05T03:30:13.828909Z","end":"2026-06-05T03:30:14.116922Z","steps":["trace[416682650] 'process raft request' (duration: 144.235999ms)","trace[416682650] 'compare' (duration: 143.397633ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T03:30:14.156705Z","caller":"traceutil/trace.go:171","msg":"trace[1526793530] transaction","detail":"{read_only:false; response_revision:24858; number_of_response:1; }","duration":"287.877296ms","start":"2026-06-05T03:30:13.868811Z","end":"2026-06-05T03:30:14.156688Z","steps":["trace[1526793530] 'process raft request' (duration: 287.653012ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:30:14.156927Z","caller":"traceutil/trace.go:171","msg":"trace[1614451928] transaction","detail":"{read_only:false; response_revision:24859; number_of_response:1; }","duration":"284.404171ms","start":"2026-06-05T03:30:13.872495Z","end":"2026-06-05T03:30:14.156899Z","steps":["trace[1614451928] 'process raft request' (duration: 284.139205ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:30:14.157205Z","caller":"traceutil/trace.go:171","msg":"trace[620868019] linearizableReadLoop","detail":"{readStateIndex:25680; appliedIndex:25675; }","duration":"273.482937ms","start":"2026-06-05T03:30:13.883708Z","end":"2026-06-05T03:30:14.157191Z","steps":["trace[620868019] 'read index received' (duration: 89.512577ms)","trace[620868019] 'applied index is now lower than readState.Index' (duration: 183.96927ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T03:30:14.157351Z","caller":"traceutil/trace.go:171","msg":"trace[1387725765] transaction","detail":"{read_only:false; response_revision:24860; number_of_response:1; }","duration":"283.353069ms","start":"2026-06-05T03:30:13.873987Z","end":"2026-06-05T03:30:14.15734Z","steps":["trace[1387725765] 'process raft request' (duration: 282.835638ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:30:14.157588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.429226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-05T03:30:14.157646Z","caller":"traceutil/trace.go:171","msg":"trace[420354559] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:24860; }","duration":"217.61095ms","start":"2026-06-05T03:30:13.940017Z","end":"2026-06-05T03:30:14.157628Z","steps":["trace[420354559] 'agreement among raft nodes before linearized reading' (duration: 217.440907ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:30:14.157739Z","caller":"traceutil/trace.go:171","msg":"trace[803718453] transaction","detail":"{read_only:false; response_revision:24857; number_of_response:1; }","duration":"288.99686ms","start":"2026-06-05T03:30:13.867567Z","end":"2026-06-05T03:30:14.156563Z","steps":["trace[803718453] 'process raft request' (duration: 288.716334ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:30:14.15785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.152402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/subnets/\" range_end:\"/registry/openstack.k-orc.cloud/subnets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-05T03:30:14.157891Z","caller":"traceutil/trace.go:171","msg":"trace[250694840] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/subnets/; range_end:/registry/openstack.k-orc.cloud/subnets0; response_count:0; response_revision:24860; }","duration":"274.233913ms","start":"2026-06-05T03:30:13.883644Z","end":"2026-06-05T03:30:14.157878Z","steps":["trace[250694840] 'agreement among raft nodes before linearized reading' (duration: 274.168402ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:30:14.601032Z","caller":"traceutil/trace.go:171","msg":"trace[704127022] transaction","detail":"{read_only:false; response_revision:24861; number_of_response:1; }","duration":"212.916131ms","start":"2026-06-05T03:30:14.388086Z","end":"2026-06-05T03:30:14.601002Z","steps":["trace[704127022] 'process raft request' (duration: 212.752088ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:30:14.602227Z","caller":"traceutil/trace.go:171","msg":"trace[232623943] transaction","detail":"{read_only:false; response_revision:24862; number_of_response:1; }","duration":"205.91769ms","start":"2026-06-05T03:30:14.396273Z","end":"2026-06-05T03:30:14.60219Z","steps":["trace[232623943] 'process raft request' (duration: 205.414749ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:30:25.379304Z","caller":"traceutil/trace.go:171","msg":"trace[1464568260] linearizableReadLoop","detail":"{readStateIndex:25771; appliedIndex:25770; }","duration":"291.346561ms","start":"2026-06-05T03:30:25.087926Z","end":"2026-06-05T03:30:25.379273Z","steps":["trace[1464568260] 'read index received' (duration: 291.080145ms)","trace[1464568260] 'applied index is now lower than readState.Index' (duration: 265.556µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T03:30:25.379452Z","caller":"traceutil/trace.go:171","msg":"trace[353804381] transaction","detail":"{read_only:false; response_revision:24949; number_of_response:1; }","duration":"304.231856ms","start":"2026-06-05T03:30:25.075183Z","end":"2026-06-05T03:30:25.379415Z","steps":["trace[353804381] 'process raft request' (duration: 303.906889ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:30:25.379596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.641617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-06-05T03:30:25.379635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T03:30:25.075136Z","time spent":"304.40629ms","remote":"127.0.0.1:33442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T03:30:25.379675Z","caller":"traceutil/trace.go:171","msg":"trace[936669264] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24949; }","duration":"291.77822ms","start":"2026-06-05T03:30:25.087879Z","end":"2026-06-05T03:30:25.379657Z","steps":["trace[936669264] 'agreement among raft nodes before linearized reading' (duration: 291.568646ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:30:25.398613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.724517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-05T03:30:25.398711Z","caller":"traceutil/trace.go:171","msg":"trace[254594365] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:24950; }","duration":"283.892081ms","start":"2026-06-05T03:30:25.114798Z","end":"2026-06-05T03:30:25.39869Z","steps":["trace[254594365] 'agreement among raft nodes before linearized reading' (duration: 283.686547ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:30:29.632799Z","caller":"traceutil/trace.go:171","msg":"trace[1872608677] linearizableReadLoop","detail":"{readStateIndex:25802; appliedIndex:25801; }","duration":"217.413856ms","start":"2026-06-05T03:30:29.415353Z","end":"2026-06-05T03:30:29.632767Z","steps":["trace[1872608677] 'read index received' (duration: 217.187162ms)","trace[1872608677] 'applied index is now lower than readState.Index' (duration: 225.114µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T03:30:29.632919Z","caller":"traceutil/trace.go:171","msg":"trace[215133090] transaction","detail":"{read_only:false; response_revision:24979; number_of_response:1; }","duration":"230.492468ms","start":"2026-06-05T03:30:29.402395Z","end":"2026-06-05T03:30:29.632887Z","steps":["trace[215133090] 'process raft request' (duration: 230.15707ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:30:29.633126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.678253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-06-05T03:30:29.63319Z","caller":"traceutil/trace.go:171","msg":"trace[988229580] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24979; }","duration":"217.781585ms","start":"2026-06-05T03:30:29.415392Z","end":"2026-06-05T03:30:29.633174Z","steps":["trace[988229580] 'agreement among raft nodes before linearized reading' (duration: 217.576301ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:30:29.633487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.098302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-05T03:30:29.633664Z","caller":"traceutil/trace.go:171","msg":"trace[2031922199] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24979; }","duration":"218.320367ms","start":"2026-06-05T03:30:29.415307Z","end":"2026-06-05T03:30:29.633628Z","steps":["trace[2031922199] 'agreement among raft nodes before linearized reading' (duration: 217.668873ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:30:29.94691Z","caller":"traceutil/trace.go:171","msg":"trace[1270224437] transaction","detail":"{read_only:false; response_revision:24980; number_of_response:1; }","duration":"309.337287ms","start":"2026-06-05T03:30:29.637542Z","end":"2026-06-05T03:30:29.94688Z","steps":["trace[1270224437] 'process raft request' (duration: 221.850672ms)","trace[1270224437] 'compare' (duration: 86.86368ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T03:30:29.947069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T03:30:29.637506Z","time spent":"309.48077ms","remote":"127.0.0.1:33442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T03:30:29.947198Z","caller":"traceutil/trace.go:171","msg":"trace[372967838] linearizableReadLoop","detail":"{readStateIndex:25804; appliedIndex:25802; }","duration":"271.964486ms","start":"2026-06-05T03:30:29.675197Z","end":"2026-06-05T03:30:29.947162Z","steps":["trace[372967838] 'read index received' (duration: 184.211635ms)","trace[372967838] 'applied index is now lower than readState.Index' (duration: 87.75032ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T03:30:29.947524Z","caller":"traceutil/trace.go:171","msg":"trace[183019164] transaction","detail":"{read_only:false; response_revision:24981; number_of_response:1; }","duration":"309.362237ms","start":"2026-06-05T03:30:29.638132Z","end":"2026-06-05T03:30:29.947494Z","steps":["trace[183019164] 'process raft request' (duration: 308.690522ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:30:29.947616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.413945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-06-05T03:30:29.947625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.575907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-05T03:30:29.947686Z","caller":"traceutil/trace.go:171","msg":"trace[996130375] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24981; }","duration":"272.526347ms","start":"2026-06-05T03:30:29.67514Z","end":"2026-06-05T03:30:29.947667Z","steps":["trace[996130375] 'agreement among raft nodes before linearized reading' (duration: 272.336503ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:30:29.947721Z","caller":"traceutil/trace.go:171","msg":"trace[615895265] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:24981; }","duration":"102.69764ms","start":"2026-06-05T03:30:29.845001Z","end":"2026-06-05T03:30:29.947698Z","steps":["trace[615895265] 'agreement among raft nodes before linearized reading' (duration: 102.501596ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:30:29.947721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T03:30:29.638106Z","time spent":"309.48481ms","remote":"127.0.0.1:33442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T03:30:29.947799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.126264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-06-05T03:30:29.947903Z","caller":"traceutil/trace.go:171","msg":"trace[1455382074] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24981; }","duration":"268.340748ms","start":"2026-06-05T03:30:29.679542Z","end":"2026-06-05T03:30:29.947882Z","steps":["trace[1455382074] 'agreement among raft nodes before linearized reading' (duration: 267.948239ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:30:30.523663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.47467ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-05T03:30:30.52379Z","caller":"traceutil/trace.go:171","msg":"trace[291028799] transaction","detail":"{read_only:false; response_revision:24982; number_of_response:1; }","duration":"571.977573ms","start":"2026-06-05T03:30:29.951789Z","end":"2026-06-05T03:30:30.523767Z","steps":["trace[291028799] 'process raft request' (duration: 338.303017ms)","trace[291028799] 'compare' (duration: 232.639403ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T03:30:30.523853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T03:30:29.951764Z","time spent":"572.059254ms","remote":"127.0.0.1:33442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T03:30:30.829682Z","caller":"traceutil/trace.go:171","msg":"trace[386033146] linearizableReadLoop","detail":"{readStateIndex:25806; appliedIndex:25804; }","duration":"618.942798ms","start":"2026-06-05T03:30:30.210716Z","end":"2026-06-05T03:30:30.829659Z","steps":["trace[386033146] 'read index received' (duration: 79.39203ms)","trace[386033146] 'applied index is now lower than readState.Index' (duration: 539.550118ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T03:30:30.830198Z","caller":"traceutil/trace.go:171","msg":"trace[1551903783] transaction","detail":"{read_only:false; response_revision:24985; number_of_response:1; }","duration":"614.023122ms","start":"2026-06-05T03:30:30.216124Z","end":"2026-06-05T03:30:30.830147Z","steps":["trace[1551903783] 'process raft request' (duration: 613.44088ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:30:30.830508Z","caller":"traceutil/trace.go:171","msg":"trace[757207713] transaction","detail":"{read_only:false; response_revision:24983; number_of_response:1; }","duration":"878.291502ms","start":"2026-06-05T03:30:29.952199Z","end":"2026-06-05T03:30:30.83049Z","steps":["trace[757207713] 'process raft request' (duration: 857.589459ms)","trace[757207713] 'compare' (duration: 19.644981ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T03:30:30.830535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T03:30:30.21611Z","time spent":"614.296538ms","remote":"127.0.0.1:33442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T03:30:30.830534Z","caller":"traceutil/trace.go:171","msg":"trace[179107525] transaction","detail":"{read_only:false; response_revision:24987; number_of_response:1; }","duration":"608.006254ms","start":"2026-06-05T03:30:30.222513Z","end":"2026-06-05T03:30:30.83052Z","steps":["trace[179107525] 'process raft request' (duration: 607.100205ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:30:30.830583Z","caller":"traceutil/trace.go:171","msg":"trace[1019839396] transaction","detail":"{read_only:false; response_revision:24986; number_of_response:1; }","duration":"609.989227ms","start":"2026-06-05T03:30:30.220554Z","end":"2026-06-05T03:30:30.830543Z","steps":["trace[1019839396] 'process raft request' (duration: 609.035636ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:30:30.830717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T03:30:29.952164Z","time spent":"878.412656ms","remote":"127.0.0.1:33442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T03:30:30.830595Z","caller":"traceutil/trace.go:171","msg":"trace[609509964] transaction","detail":"{read_only:false; response_revision:24984; number_of_response:1; }","duration":"614.904541ms","start":"2026-06-05T03:30:30.215656Z","end":"2026-06-05T03:30:30.830561Z","steps":["trace[609509964] 'process raft request' (duration: 613.876459ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:30:30.830773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T03:30:30.220526Z","time spent":"610.151839ms","remote":"127.0.0.1:33442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T03:30:30.830726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T03:30:30.222488Z","time spent":"608.122736ms","remote":"127.0.0.1:33442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T03:30:30.830861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.145133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-06-05T03:30:30.830878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T03:30:30.215616Z","time spent":"615.176217ms","remote":"127.0.0.1:33442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T03:30:30.830922Z","caller":"traceutil/trace.go:171","msg":"trace[1282855057] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24987; }","duration":"620.249725ms","start":"2026-06-05T03:30:30.210654Z","end":"2026-06-05T03:30:30.830903Z","steps":["trace[1282855057] 'agreement among raft nodes before linearized reading' (duration: 620.01322ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:30:30.830976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T03:30:30.21063Z","time spent":"620.337478ms","remote":"127.0.0.1:33442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-05T03:30:30.853026Z","caller":"traceutil/trace.go:171","msg":"trace[194193373] transaction","detail":"{read_only:false; response_revision:24988; number_of_response:1; }","duration":"188.988327ms","start":"2026-06-05T03:30:30.664012Z","end":"2026-06-05T03:30:30.853Z","steps":["trace[194193373] 'process raft request' (duration: 188.821304ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:30:30.85344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.080861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-05T03:30:30.853504Z","caller":"traceutil/trace.go:171","msg":"trace[1423010221] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachines/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachines0; response_count:0; response_revision:24989; }","duration":"225.151653ms","start":"2026-06-05T03:30:30.628335Z","end":"2026-06-05T03:30:30.853487Z","steps":["trace[1423010221] 'agreement among raft nodes before linearized reading' (duration: 225.00776ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:30:30.853486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.588064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-05T03:30:30.85359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.933095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-06-05T03:30:30.853611Z","caller":"traceutil/trace.go:171","msg":"trace[1353530293] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:24989; }","duration":"585.790678ms","start":"2026-06-05T03:30:30.267795Z","end":"2026-06-05T03:30:30.853586Z","steps":["trace[1353530293] 'agreement among raft nodes before linearized reading' (duration: 585.463491ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:30:30.853654Z","caller":"traceutil/trace.go:171","msg":"trace[593341333] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:24989; }","duration":"336.051728ms","start":"2026-06-05T03:30:30.517579Z","end":"2026-06-05T03:30:30.853631Z","steps":["trace[593341333] 'agreement among raft nodes before linearized reading' (duration: 335.851084ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:30:30.853681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T03:30:30.267765Z","time spent":"585.895429ms","remote":"127.0.0.1:42136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":30,"request content":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true "} {"level":"warn","ts":"2026-06-05T03:30:30.853698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T03:30:30.51755Z","time spent":"336.13701ms","remote":"127.0.0.1:33208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":140,"response size":33,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-06-05T03:30:30.853782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.785689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-05T03:30:30.853829Z","caller":"traceutil/trace.go:171","msg":"trace[780117819] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:24989; }","duration":"206.85647ms","start":"2026-06-05T03:30:30.646961Z","end":"2026-06-05T03:30:30.853818Z","steps":["trace[780117819] 'agreement among raft nodes before linearized reading' (duration: 206.738357ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:30:30.853891Z","caller":"traceutil/trace.go:171","msg":"trace[1187689960] transaction","detail":"{read_only:false; response_revision:24989; number_of_response:1; }","duration":"161.607531ms","start":"2026-06-05T03:30:30.692249Z","end":"2026-06-05T03:30:30.853856Z","steps":["trace[1187689960] 'process raft request' (duration: 160.700921ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:30:31.112878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.299193ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-05T03:30:31.113754Z","caller":"traceutil/trace.go:171","msg":"trace[1977661393] transaction","detail":"{read_only:false; response_revision:24991; number_of_response:1; }","duration":"254.874059ms","start":"2026-06-05T03:30:30.858842Z","end":"2026-06-05T03:30:31.113716Z","steps":["trace[1977661393] 'process raft request' (duration: 150.653787ms)","trace[1977661393] 'compare' (duration: 103.112149ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T03:30:31.285302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.303301ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-06-05T03:30:31.285413Z","caller":"traceutil/trace.go:171","msg":"trace[1764142661] linearizableReadLoop","detail":"{readStateIndex:25815; appliedIndex:25813; }","duration":"293.843913ms","start":"2026-06-05T03:30:30.991554Z","end":"2026-06-05T03:30:31.285398Z","steps":["trace[1764142661] 'read index received' (duration: 17.995875ms)","trace[1764142661] 'applied index is now lower than readState.Index' (duration: 275.846708ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T03:30:31.285488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.935615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-05T03:30:31.285513Z","caller":"traceutil/trace.go:171","msg":"trace[334652858] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24991; }","duration":"294.006147ms","start":"2026-06-05T03:30:30.9915Z","end":"2026-06-05T03:30:31.285506Z","steps":["trace[334652858] 'agreement among raft nodes before linearized reading' (duration: 293.934345ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:30:31.634419Z","caller":"traceutil/trace.go:171","msg":"trace[1115002608] linearizableReadLoop","detail":"{readStateIndex:25816; appliedIndex:25815; }","duration":"117.728621ms","start":"2026-06-05T03:30:31.516662Z","end":"2026-06-05T03:30:31.634391Z","steps":["trace[1115002608] 'read index received' (duration: 117.309733ms)","trace[1115002608] 'applied index is now lower than readState.Index' (duration: 417.598µs)"],"step_count":2} {"level":"warn","ts":"2026-06-05T03:30:31.634624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.933036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-05T03:30:31.6347Z","caller":"traceutil/trace.go:171","msg":"trace[2096743286] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:24992; }","duration":"118.031338ms","start":"2026-06-05T03:30:31.516657Z","end":"2026-06-05T03:30:31.634688Z","steps":["trace[2096743286] 'agreement among raft nodes before linearized reading' (duration: 117.847984ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:30:31.635054Z","caller":"traceutil/trace.go:171","msg":"trace[738602353] transaction","detail":"{read_only:false; response_revision:24992; number_of_response:1; }","duration":"335.864005ms","start":"2026-06-05T03:30:31.299178Z","end":"2026-06-05T03:30:31.635042Z","steps":["trace[738602353] 'process raft request' (duration: 334.906285ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:30:31.635204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T03:30:31.29916Z","time spent":"335.931976ms","remote":"127.0.0.1:33442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T03:30:32.099236Z","caller":"traceutil/trace.go:171","msg":"trace[2021389458] transaction","detail":"{read_only:false; response_revision:24994; number_of_response:1; }","duration":"140.327976ms","start":"2026-06-05T03:30:31.95888Z","end":"2026-06-05T03:30:32.099208Z","steps":["trace[2021389458] 'process raft request' (duration: 140.178052ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:30:32.389468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.962971ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-05T03:30:32.38959Z","caller":"traceutil/trace.go:171","msg":"trace[911674922] linearizableReadLoop","detail":"{readStateIndex:25819; appliedIndex:25818; }","duration":"397.305991ms","start":"2026-06-05T03:30:31.992265Z","end":"2026-06-05T03:30:32.389571Z","steps":["trace[911674922] 'read index received' (duration: 106.90965ms)","trace[911674922] 'applied index is now lower than readState.Index' (duration: 290.394681ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T03:30:32.389691Z","caller":"traceutil/trace.go:171","msg":"trace[1963199318] transaction","detail":"{read_only:false; response_revision:24995; number_of_response:1; }","duration":"430.597364ms","start":"2026-06-05T03:30:31.959072Z","end":"2026-06-05T03:30:32.38967Z","steps":["trace[1963199318] 'process raft request' (duration: 280.918207ms)","trace[1963199318] 'compare' (duration: 148.529502ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T03:30:32.389747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T03:30:31.959066Z","time spent":"430.651305ms","remote":"127.0.0.1:33442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T03:30:32.389883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.575535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-05T03:30:32.389964Z","caller":"traceutil/trace.go:171","msg":"trace[1494863705] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24995; }","duration":"396.711058ms","start":"2026-06-05T03:30:31.993234Z","end":"2026-06-05T03:30:32.389945Z","steps":["trace[1494863705] 'agreement among raft nodes before linearized reading' (duration: 396.583155ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:30:32.390003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T03:30:31.9932Z","time spent":"396.79561ms","remote":"127.0.0.1:33158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-05T03:30:32.390015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.7468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-06-05T03:30:32.390079Z","caller":"traceutil/trace.go:171","msg":"trace[966922716] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24995; }","duration":"397.844442ms","start":"2026-06-05T03:30:31.992217Z","end":"2026-06-05T03:30:32.390062Z","steps":["trace[966922716] 'agreement among raft nodes before linearized reading' (duration: 397.646148ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:30:32.390125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T03:30:31.992179Z","time spent":"397.931134ms","remote":"127.0.0.1:33360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12241,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-06-05T03:30:32.683492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.117844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-05T03:30:32.683633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.172022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-06-05T03:30:32.683655Z","caller":"traceutil/trace.go:171","msg":"trace[1797851468] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:24995; }","duration":"191.309759ms","start":"2026-06-05T03:30:32.492294Z","end":"2026-06-05T03:30:32.683604Z","steps":["trace[1797851468] 'count revisions from in-memory index tree' (duration: 191.015462ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:30:32.683731Z","caller":"traceutil/trace.go:171","msg":"trace[1735578787] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24995; }","duration":"153.331705ms","start":"2026-06-05T03:30:32.530366Z","end":"2026-06-05T03:30:32.683698Z","steps":["trace[1735578787] 'range keys from in-memory index tree' (duration: 152.843284ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:30:33.02018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.108131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-06-05T03:30:33.020272Z","caller":"traceutil/trace.go:171","msg":"trace[1589154419] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24996; }","duration":"182.280774ms","start":"2026-06-05T03:30:32.837975Z","end":"2026-06-05T03:30:33.020256Z","steps":["trace[1589154419] 'range keys from in-memory index tree' (duration: 181.906566ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:30:33.046798Z","caller":"traceutil/trace.go:171","msg":"trace[1912656690] transaction","detail":"{read_only:false; response_revision:24997; number_of_response:1; }","duration":"208.324642ms","start":"2026-06-05T03:30:32.838433Z","end":"2026-06-05T03:30:33.046757Z","steps":["trace[1912656690] 'process raft request' (duration: 204.556482ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:30:33.095579Z","caller":"traceutil/trace.go:171","msg":"trace[1552556248] transaction","detail":"{read_only:false; response_revision:24998; number_of_response:1; }","duration":"256.604837ms","start":"2026-06-05T03:30:32.838908Z","end":"2026-06-05T03:30:33.095513Z","steps":["trace[1552556248] 'process raft request' (duration: 256.206128ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:30:33.095616Z","caller":"traceutil/trace.go:171","msg":"trace[2093721147] transaction","detail":"{read_only:false; response_revision:24999; number_of_response:1; }","duration":"256.212029ms","start":"2026-06-05T03:30:32.839379Z","end":"2026-06-05T03:30:33.095591Z","steps":["trace[2093721147] 'process raft request' (duration: 255.879451ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:30:33.095897Z","caller":"traceutil/trace.go:171","msg":"trace[1868564564] transaction","detail":"{read_only:false; response_revision:25000; number_of_response:1; }","duration":"255.993974ms","start":"2026-06-05T03:30:32.839892Z","end":"2026-06-05T03:30:33.095886Z","steps":["trace[1868564564] 'process raft request' (duration: 255.652477ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:30:33.096045Z","caller":"traceutil/trace.go:171","msg":"trace[994867160] transaction","detail":"{read_only:false; response_revision:25001; number_of_response:1; }","duration":"235.818441ms","start":"2026-06-05T03:30:32.860204Z","end":"2026-06-05T03:30:33.096022Z","steps":["trace[994867160] 'process raft request' (duration: 235.564675ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:30:33.096138Z","caller":"traceutil/trace.go:171","msg":"trace[2062030971] transaction","detail":"{read_only:false; response_revision:25002; number_of_response:1; }","duration":"235.099196ms","start":"2026-06-05T03:30:32.861015Z","end":"2026-06-05T03:30:33.096115Z","steps":["trace[2062030971] 'process raft request' (duration: 234.81821ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:30:33.096165Z","caller":"traceutil/trace.go:171","msg":"trace[508130155] transaction","detail":"{read_only:false; response_revision:25003; number_of_response:1; }","duration":"187.18661ms","start":"2026-06-05T03:30:32.90897Z","end":"2026-06-05T03:30:33.096157Z","steps":["trace[508130155] 'process raft request' (duration: 187.065787ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:30:33.096314Z","caller":"traceutil/trace.go:171","msg":"trace[260973938] linearizableReadLoop","detail":"{readStateIndex:25827; appliedIndex:25820; }","duration":"181.237753ms","start":"2026-06-05T03:30:32.915069Z","end":"2026-06-05T03:30:33.096306Z","steps":["trace[260973938] 'read index received' (duration: 127.452021ms)","trace[260973938] 'applied index is now lower than readState.Index' (duration: 53.784882ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T03:30:33.096568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.501258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-06-05T03:30:33.096598Z","caller":"traceutil/trace.go:171","msg":"trace[1199943033] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25005; }","duration":"181.60494ms","start":"2026-06-05T03:30:32.914986Z","end":"2026-06-05T03:30:33.096591Z","steps":["trace[1199943033] 'agreement among raft nodes before linearized reading' (duration: 181.482637ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:30:33.298374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.650205ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-05T03:30:33.298482Z","caller":"traceutil/trace.go:171","msg":"trace[1998923152] linearizableReadLoop","detail":"{readStateIndex:25830; appliedIndex:25829; }","duration":"181.392056ms","start":"2026-06-05T03:30:33.117073Z","end":"2026-06-05T03:30:33.298465Z","steps":["trace[1998923152] 'read index received' (duration: 3.491636ms)","trace[1998923152] 'applied index is now lower than readState.Index' (duration: 177.89865ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T03:30:33.298577Z","caller":"traceutil/trace.go:171","msg":"trace[1985168727] transaction","detail":"{read_only:false; response_revision:25006; number_of_response:1; }","duration":"196.660402ms","start":"2026-06-05T03:30:33.101857Z","end":"2026-06-05T03:30:33.298517Z","steps":["trace[1985168727] 'process raft request' (duration: 18.748782ms)","trace[1985168727] 'compare' (duration: 177.470311ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T03:30:33.298646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.57415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-06-05T03:30:33.298675Z","caller":"traceutil/trace.go:171","msg":"trace[150915824] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25006; }","duration":"181.63039ms","start":"2026-06-05T03:30:33.117035Z","end":"2026-06-05T03:30:33.298665Z","steps":["trace[150915824] 'agreement among raft nodes before linearized reading' (duration: 181.514738ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:30:34.084755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.792787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-05T03:30:34.084873Z","caller":"traceutil/trace.go:171","msg":"trace[1805138491] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagerconfigs/; range_end:/registry/monitoring.coreos.com/alertmanagerconfigs0; response_count:0; response_revision:25008; }","duration":"126.996551ms","start":"2026-06-05T03:30:33.957845Z","end":"2026-06-05T03:30:34.084842Z","steps":["trace[1805138491] 'count revisions from in-memory index tree' (duration: 126.649913ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:30:34.37656Z","caller":"traceutil/trace.go:171","msg":"trace[46377658] linearizableReadLoop","detail":"{readStateIndex:25833; appliedIndex:25832; }","duration":"170.987243ms","start":"2026-06-05T03:30:34.205542Z","end":"2026-06-05T03:30:34.376529Z","steps":["trace[46377658] 'read index received' (duration: 170.637885ms)","trace[46377658] 'applied index is now lower than readState.Index' (duration: 347.548µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T03:30:34.376748Z","caller":"traceutil/trace.go:171","msg":"trace[2040149414] transaction","detail":"{read_only:false; response_revision:25009; number_of_response:1; }","duration":"268.864559ms","start":"2026-06-05T03:30:34.107831Z","end":"2026-06-05T03:30:34.376696Z","steps":["trace[2040149414] 'process raft request' (duration: 268.395259ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:30:34.376912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.34549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-05T03:30:34.37706Z","caller":"traceutil/trace.go:171","msg":"trace[1193825021] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:25009; }","duration":"171.538984ms","start":"2026-06-05T03:30:34.205475Z","end":"2026-06-05T03:30:34.377014Z","steps":["trace[1193825021] 'agreement among raft nodes before linearized reading' (duration: 171.250778ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:30:40.074174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.148573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-06-05T03:30:40.074258Z","caller":"traceutil/trace.go:171","msg":"trace[1540417940] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:25108; }","duration":"161.284156ms","start":"2026-06-05T03:30:39.912953Z","end":"2026-06-05T03:30:40.074238Z","steps":["trace[1540417940] 'range keys from in-memory index tree' (duration: 160.857586ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:30:40.074632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.818642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b9fb7156-102f-4e19-9a43-848c3239e42a\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-06-05T03:30:40.074734Z","caller":"traceutil/trace.go:171","msg":"trace[988527901] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b9fb7156-102f-4e19-9a43-848c3239e42a; range_end:; response_count:1; response_revision:25108; }","duration":"144.989066ms","start":"2026-06-05T03:30:39.929723Z","end":"2026-06-05T03:30:40.074712Z","steps":["trace[988527901] 'range keys from in-memory index tree' (duration: 144.625299ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:30:40.200088Z","caller":"traceutil/trace.go:171","msg":"trace[85522819] transaction","detail":"{read_only:false; response_revision:25109; number_of_response:1; }","duration":"121.273599ms","start":"2026-06-05T03:30:40.078769Z","end":"2026-06-05T03:30:40.200042Z","steps":["trace[85522819] 'process raft request' (duration: 120.762717ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:30:40.572648Z","caller":"traceutil/trace.go:171","msg":"trace[1394721389] linearizableReadLoop","detail":"{readStateIndex:25936; appliedIndex:25935; }","duration":"148.179994ms","start":"2026-06-05T03:30:40.424438Z","end":"2026-06-05T03:30:40.572618Z","steps":["trace[1394721389] 'read index received' (duration: 147.98767ms)","trace[1394721389] 'applied index is now lower than readState.Index' (duration: 191.204µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T03:30:40.572777Z","caller":"traceutil/trace.go:171","msg":"trace[841611483] transaction","detail":"{read_only:false; response_revision:25110; number_of_response:1; }","duration":"164.729319ms","start":"2026-06-05T03:30:40.408009Z","end":"2026-06-05T03:30:40.572738Z","steps":["trace[841611483] 'process raft request' (duration: 164.450603ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:30:40.572869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.417399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-05T03:30:40.572903Z","caller":"traceutil/trace.go:171","msg":"trace[1393068968] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25110; }","duration":"148.504501ms","start":"2026-06-05T03:30:40.424389Z","end":"2026-06-05T03:30:40.572893Z","steps":["trace[1393068968] 'agreement among raft nodes before linearized reading' (duration: 148.372898ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:30:40.876231Z","caller":"traceutil/trace.go:171","msg":"trace[468232574] transaction","detail":"{read_only:false; response_revision:25112; number_of_response:1; }","duration":"272.245791ms","start":"2026-06-05T03:30:40.603944Z","end":"2026-06-05T03:30:40.87619Z","steps":["trace[468232574] 'process raft request' (duration: 272.071728ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:30:41.171818Z","caller":"traceutil/trace.go:171","msg":"trace[906014268] linearizableReadLoop","detail":"{readStateIndex:25939; appliedIndex:25937; }","duration":"434.218851ms","start":"2026-06-05T03:30:40.737558Z","end":"2026-06-05T03:30:41.171777Z","steps":["trace[906014268] 'read index received' (duration: 138.473826ms)","trace[906014268] 'applied index is now lower than readState.Index' (duration: 295.743265ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T03:30:41.171891Z","caller":"traceutil/trace.go:171","msg":"trace[54786902] transaction","detail":"{read_only:false; response_revision:25113; number_of_response:1; }","duration":"562.918468ms","start":"2026-06-05T03:30:40.608939Z","end":"2026-06-05T03:30:41.171858Z","steps":["trace[54786902] 'process raft request' (duration: 562.56183ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:30:41.172051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.465576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-06-05T03:30:41.172073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T03:30:40.608922Z","time spent":"563.056311ms","remote":"127.0.0.1:33360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3746,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T03:30:41.172099Z","caller":"traceutil/trace.go:171","msg":"trace[232888193] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:25113; }","duration":"434.592929ms","start":"2026-06-05T03:30:40.737489Z","end":"2026-06-05T03:30:41.172082Z","steps":["trace[232888193] 'agreement among raft nodes before linearized reading' (duration: 434.466996ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:30:41.172161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T03:30:40.737462Z","time spent":"434.687131ms","remote":"127.0.0.1:33642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":32,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-06-05T03:30:41.191898Z","caller":"traceutil/trace.go:171","msg":"trace[1716182360] transaction","detail":"{read_only:false; response_revision:25114; number_of_response:1; }","duration":"113.203465ms","start":"2026-06-05T03:30:41.078633Z","end":"2026-06-05T03:30:41.191837Z","steps":["trace[1716182360] 'process raft request' (duration: 113.034511ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:30:41.192539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.132373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b9fb7156-102f-4e19-9a43-848c3239e42a\" ","response":"range_response_count:1 size:3764"} {"level":"info","ts":"2026-06-05T03:30:41.192597Z","caller":"traceutil/trace.go:171","msg":"trace[835290016] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b9fb7156-102f-4e19-9a43-848c3239e42a; range_end:; response_count:1; response_revision:25119; }","duration":"113.240065ms","start":"2026-06-05T03:30:41.079344Z","end":"2026-06-05T03:30:41.192584Z","steps":["trace[835290016] 'agreement among raft nodes before linearized reading' (duration: 113.095912ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:30:41.192544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.734894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-06-05T03:30:41.192706Z","caller":"traceutil/trace.go:171","msg":"trace[431968864] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25119; }","duration":"414.930768ms","start":"2026-06-05T03:30:40.777748Z","end":"2026-06-05T03:30:41.192679Z","steps":["trace[431968864] 'agreement among raft nodes before linearized reading' (duration: 414.643902ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:30:41.192797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T03:30:40.777727Z","time spent":"415.03535ms","remote":"127.0.0.1:33442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-06-05T03:30:55.116981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.000605ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-05T03:30:55.117114Z","caller":"traceutil/trace.go:171","msg":"trace[712234057] linearizableReadLoop","detail":"{readStateIndex:26074; appliedIndex:26072; }","duration":"225.43204ms","start":"2026-06-05T03:30:54.891672Z","end":"2026-06-05T03:30:55.117104Z","steps":["trace[712234057] 'read index received' (duration: 124.266592ms)","trace[712234057] 'applied index is now lower than readState.Index' (duration: 101.164698ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T03:30:55.117264Z","caller":"traceutil/trace.go:171","msg":"trace[875655010] transaction","detail":"{read_only:false; response_revision:25244; number_of_response:1; }","duration":"393.490419ms","start":"2026-06-05T03:30:54.723763Z","end":"2026-06-05T03:30:55.117254Z","steps":["trace[875655010] 'process raft request' (duration: 136.155717ms)","trace[875655010] 'compare' (duration: 256.410401ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T03:30:55.117304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T03:30:54.723743Z","time spent":"393.541501ms","remote":"127.0.0.1:33442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T03:30:55.117404Z","caller":"traceutil/trace.go:171","msg":"trace[2059465096] transaction","detail":"{read_only:false; response_revision:25245; number_of_response:1; }","duration":"236.331313ms","start":"2026-06-05T03:30:54.881067Z","end":"2026-06-05T03:30:55.117398Z","steps":["trace[2059465096] 'process raft request' (duration: 236.001966ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:30:55.117621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.966441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:418154"} {"level":"info","ts":"2026-06-05T03:30:55.117645Z","caller":"traceutil/trace.go:171","msg":"trace[726116460] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:25245; }","duration":"226.044742ms","start":"2026-06-05T03:30:54.891593Z","end":"2026-06-05T03:30:55.117638Z","steps":["trace[726116460] 'agreement among raft nodes before linearized reading' (duration: 225.872168ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:30:55.11782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.961618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-05T03:30:55.11784Z","caller":"traceutil/trace.go:171","msg":"trace[1987582974] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25245; }","duration":"126.013459ms","start":"2026-06-05T03:30:54.991822Z","end":"2026-06-05T03:30:55.117835Z","steps":["trace[1987582974] 'agreement among raft nodes before linearized reading' (duration: 125.978688ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:30:55.532772Z","caller":"traceutil/trace.go:171","msg":"trace[1303398733] transaction","detail":"{read_only:false; response_revision:25247; number_of_response:1; }","duration":"287.821476ms","start":"2026-06-05T03:30:55.244926Z","end":"2026-06-05T03:30:55.532748Z","steps":["trace[1303398733] 'process raft request' (duration: 243.991477ms)","trace[1303398733] 'compare' (duration: 43.673815ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T03:30:55.533063Z","caller":"traceutil/trace.go:171","msg":"trace[2129161811] transaction","detail":"{read_only:false; response_revision:25248; number_of_response:1; }","duration":"287.399977ms","start":"2026-06-05T03:30:55.245636Z","end":"2026-06-05T03:30:55.533035Z","steps":["trace[2129161811] 'process raft request' (duration: 287.0714ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:30:55.533476Z","caller":"traceutil/trace.go:171","msg":"trace[407953637] transaction","detail":"{read_only:false; response_revision:25249; number_of_response:1; }","duration":"286.466226ms","start":"2026-06-05T03:30:55.246993Z","end":"2026-06-05T03:30:55.53346Z","steps":["trace[407953637] 'process raft request' (duration: 285.940544ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:30:55.533605Z","caller":"traceutil/trace.go:171","msg":"trace[658687318] linearizableReadLoop","detail":"{readStateIndex:26081; appliedIndex:26075; }","duration":"271.933395ms","start":"2026-06-05T03:30:55.261607Z","end":"2026-06-05T03:30:55.533541Z","steps":["trace[658687318] 'read index received' (duration: 227.424202ms)","trace[658687318] 'applied index is now lower than readState.Index' (duration: 44.508113ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T03:30:55.533799Z","caller":"traceutil/trace.go:171","msg":"trace[136357864] transaction","detail":"{read_only:false; response_revision:25250; number_of_response:1; }","duration":"282.433548ms","start":"2026-06-05T03:30:55.25135Z","end":"2026-06-05T03:30:55.533784Z","steps":["trace[136357864] 'process raft request' (duration: 281.920938ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:30:55.53404Z","caller":"traceutil/trace.go:171","msg":"trace[2033373236] transaction","detail":"{read_only:false; response_revision:25251; number_of_response:1; }","duration":"279.864693ms","start":"2026-06-05T03:30:55.254163Z","end":"2026-06-05T03:30:55.534028Z","steps":["trace[2033373236] 'process raft request' (duration: 279.263861ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:30:55.534407Z","caller":"traceutil/trace.go:171","msg":"trace[2135552466] transaction","detail":"{read_only:false; response_revision:25252; number_of_response:1; }","duration":"277.29065ms","start":"2026-06-05T03:30:55.257095Z","end":"2026-06-05T03:30:55.534386Z","steps":["trace[2135552466] 'process raft request' (duration: 276.38301ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:30:55.534801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.173692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-06-05T03:30:55.534865Z","caller":"traceutil/trace.go:171","msg":"trace[1564911980] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25252; }","duration":"273.297055ms","start":"2026-06-05T03:30:55.261552Z","end":"2026-06-05T03:30:55.534849Z","steps":["trace[1564911980] 'agreement among raft nodes before linearized reading' (duration: 273.08909ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:30:55.562543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.731133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-06-05T03:30:55.562624Z","caller":"traceutil/trace.go:171","msg":"trace[256015573] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25253; }","duration":"300.874155ms","start":"2026-06-05T03:30:55.26173Z","end":"2026-06-05T03:30:55.562604Z","steps":["trace[256015573] 'agreement among raft nodes before linearized reading' (duration: 300.701082ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:30:55.562652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T03:30:55.261715Z","time spent":"300.931966ms","remote":"127.0.0.1:33442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-05T03:30:55.562814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.901006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-06-05T03:30:55.562836Z","caller":"traceutil/trace.go:171","msg":"trace[1227023896] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25253; }","duration":"110.970667ms","start":"2026-06-05T03:30:55.45186Z","end":"2026-06-05T03:30:55.562831Z","steps":["trace[1227023896] 'agreement among raft nodes before linearized reading' (duration: 110.907226ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:30:55.56294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.40192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-06-05T03:30:55.562966Z","caller":"traceutil/trace.go:171","msg":"trace[1100969102] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25253; }","duration":"156.465142ms","start":"2026-06-05T03:30:55.406495Z","end":"2026-06-05T03:30:55.56296Z","steps":["trace[1100969102] 'agreement among raft nodes before linearized reading' (duration: 156.408331ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:30:55.563088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.249919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-06-05T03:30:55.563153Z","caller":"traceutil/trace.go:171","msg":"trace[73677345] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25253; }","duration":"298.335751ms","start":"2026-06-05T03:30:55.264811Z","end":"2026-06-05T03:30:55.563146Z","steps":["trace[73677345] 'agreement among raft nodes before linearized reading' (duration: 298.239259ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:30:59.858956Z","caller":"traceutil/trace.go:171","msg":"trace[2067443298] linearizableReadLoop","detail":"{readStateIndex:26113; appliedIndex:26107; }","duration":"258.160809ms","start":"2026-06-05T03:30:59.600765Z","end":"2026-06-05T03:30:59.858926Z","steps":["trace[2067443298] 'read index received' (duration: 5.051348ms)","trace[2067443298] 'applied index is now lower than readState.Index' (duration: 253.108391ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T03:30:59.859127Z","caller":"traceutil/trace.go:171","msg":"trace[1333189517] transaction","detail":"{read_only:false; response_revision:25282; number_of_response:1; }","duration":"300.746732ms","start":"2026-06-05T03:30:59.558367Z","end":"2026-06-05T03:30:59.859114Z","steps":["trace[1333189517] 'process raft request' (duration: 300.481347ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:30:59.859178Z","caller":"traceutil/trace.go:171","msg":"trace[2104908080] transaction","detail":"{read_only:false; response_revision:25281; number_of_response:1; }","duration":"301.306944ms","start":"2026-06-05T03:30:59.55783Z","end":"2026-06-05T03:30:59.859137Z","steps":["trace[2104908080] 'process raft request' (duration: 300.981577ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:30:59.859267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T03:30:59.558337Z","time spent":"300.816163ms","remote":"127.0.0.1:33442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T03:30:59.859265Z","caller":"traceutil/trace.go:171","msg":"trace[20384814] transaction","detail":"{read_only:false; response_revision:25283; number_of_response:1; }","duration":"300.490896ms","start":"2026-06-05T03:30:59.558728Z","end":"2026-06-05T03:30:59.859219Z","steps":["trace[20384814] 'process raft request' (duration: 300.158699ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:30:59.859334Z","caller":"traceutil/trace.go:171","msg":"trace[1233078237] transaction","detail":"{read_only:false; response_revision:25280; number_of_response:1; }","duration":"301.556299ms","start":"2026-06-05T03:30:59.557736Z","end":"2026-06-05T03:30:59.859292Z","steps":["trace[1233078237] 'process raft request' (duration: 301.020698ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:30:59.859393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.612049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-06-05T03:30:59.859448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.261526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-06-05T03:30:59.859484Z","caller":"traceutil/trace.go:171","msg":"trace[309104687] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25283; }","duration":"258.758293ms","start":"2026-06-05T03:30:59.600708Z","end":"2026-06-05T03:30:59.859466Z","steps":["trace[309104687] 'agreement among raft nodes before linearized reading' (duration: 258.440146ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:30:59.8595Z","caller":"traceutil/trace.go:171","msg":"trace[1090752297] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25283; }","duration":"254.396678ms","start":"2026-06-05T03:30:59.605096Z","end":"2026-06-05T03:30:59.859492Z","steps":["trace[1090752297] 'agreement among raft nodes before linearized reading' (duration: 254.183704ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:30:59.85949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T03:30:59.558713Z","time spent":"300.66796ms","remote":"127.0.0.1:33442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T03:30:59.859514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T03:30:59.557727Z","time spent":"301.704552ms","remote":"127.0.0.1:33442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T03:30:59.859407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T03:30:59.557822Z","time spent":"301.465647ms","remote":"127.0.0.1:33442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T03:30:59.859193Z","caller":"traceutil/trace.go:171","msg":"trace[2018430323] transaction","detail":"{read_only:false; response_revision:25279; number_of_response:1; }","duration":"301.518578ms","start":"2026-06-05T03:30:59.557631Z","end":"2026-06-05T03:30:59.85915Z","steps":["trace[2018430323] 'process raft request' (duration: 300.923615ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:30:59.859584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.251843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-06-05T03:30:59.859694Z","caller":"traceutil/trace.go:171","msg":"trace[980518269] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25283; }","duration":"252.421188ms","start":"2026-06-05T03:30:59.607246Z","end":"2026-06-05T03:30:59.859667Z","steps":["trace[980518269] 'agreement among raft nodes before linearized reading' (duration: 252.08436ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:30:59.85971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T03:30:59.557603Z","time spent":"302.03188ms","remote":"127.0.0.1:33442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T03:30:59.859617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.565793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-06-05T03:30:59.859826Z","caller":"traceutil/trace.go:171","msg":"trace[516681655] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25283; }","duration":"253.816688ms","start":"2026-06-05T03:30:59.60599Z","end":"2026-06-05T03:30:59.859806Z","steps":["trace[516681655] 'agreement among raft nodes before linearized reading' (duration: 253.461739ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:31:00.126989Z","caller":"traceutil/trace.go:171","msg":"trace[1341883017] transaction","detail":"{read_only:false; response_revision:25285; number_of_response:1; }","duration":"262.573695ms","start":"2026-06-05T03:30:59.864383Z","end":"2026-06-05T03:31:00.126957Z","steps":["trace[1341883017] 'process raft request' (duration: 262.506613ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:31:00.127043Z","caller":"traceutil/trace.go:171","msg":"trace[1155364572] transaction","detail":"{read_only:false; response_revision:25284; number_of_response:1; }","duration":"262.626096ms","start":"2026-06-05T03:30:59.86438Z","end":"2026-06-05T03:31:00.127006Z","steps":["trace[1155364572] 'process raft request' (duration: 262.253438ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:31:00.278859Z","caller":"traceutil/trace.go:171","msg":"trace[129372719] transaction","detail":"{read_only:false; response_revision:25286; number_of_response:1; }","duration":"414.403487ms","start":"2026-06-05T03:30:59.864423Z","end":"2026-06-05T03:31:00.278827Z","steps":["trace[129372719] 'process raft request' (duration: 359.858359ms)","trace[129372719] 'compare' (duration: 54.340163ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T03:31:00.279049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T03:30:59.864397Z","time spent":"414.55518ms","remote":"127.0.0.1:33442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T03:31:00.279157Z","caller":"traceutil/trace.go:171","msg":"trace[430688011] linearizableReadLoop","detail":"{readStateIndex:26118; appliedIndex:26113; }","duration":"287.788634ms","start":"2026-06-05T03:30:59.991343Z","end":"2026-06-05T03:31:00.279131Z","steps":["trace[430688011] 'read index received' (duration: 135.39121ms)","trace[430688011] 'applied index is now lower than readState.Index' (duration: 152.396284ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T03:31:00.27917Z","caller":"traceutil/trace.go:171","msg":"trace[2103448613] transaction","detail":"{read_only:false; response_revision:25287; number_of_response:1; }","duration":"414.683133ms","start":"2026-06-05T03:30:59.864448Z","end":"2026-06-05T03:31:00.279131Z","steps":["trace[2103448613] 'process raft request' (duration: 414.326526ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:31:00.279224Z","caller":"traceutil/trace.go:171","msg":"trace[1201378735] transaction","detail":"{read_only:false; response_revision:25288; number_of_response:1; }","duration":"414.254313ms","start":"2026-06-05T03:30:59.86493Z","end":"2026-06-05T03:31:00.279185Z","steps":["trace[1201378735] 'process raft request' (duration: 414.08494ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:31:00.279295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.944287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-05T03:31:00.279326Z","caller":"traceutil/trace.go:171","msg":"trace[1957397213] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25288; }","duration":"288.020179ms","start":"2026-06-05T03:30:59.991298Z","end":"2026-06-05T03:31:00.279318Z","steps":["trace[1957397213] 'agreement among raft nodes before linearized reading' (duration: 287.947817ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:31:00.279312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T03:30:59.864418Z","time spent":"414.817915ms","remote":"127.0.0.1:33442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T03:31:00.279395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T03:30:59.864923Z","time spent":"414.376907ms","remote":"127.0.0.1:33442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T03:31:00.856169Z","caller":"traceutil/trace.go:171","msg":"trace[1172260680] transaction","detail":"{read_only:false; response_revision:25289; number_of_response:1; }","duration":"121.658016ms","start":"2026-06-05T03:31:00.734478Z","end":"2026-06-05T03:31:00.856136Z","steps":["trace[1172260680] 'process raft request' (duration: 121.453541ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:31:03.354379Z","caller":"traceutil/trace.go:171","msg":"trace[190780578] transaction","detail":"{read_only:false; response_revision:25305; number_of_response:1; }","duration":"158.100867ms","start":"2026-06-05T03:31:03.196165Z","end":"2026-06-05T03:31:03.354266Z","steps":["trace[190780578] 'process raft request' (duration: 68.995857ms)","trace[190780578] 'compare' (duration: 88.896865ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T03:31:12.110759Z","caller":"traceutil/trace.go:171","msg":"trace[997905083] transaction","detail":"{read_only:false; response_revision:25383; number_of_response:1; }","duration":"201.551097ms","start":"2026-06-05T03:31:11.909179Z","end":"2026-06-05T03:31:12.11073Z","steps":["trace[997905083] 'process raft request' (duration: 201.479116ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:31:12.110772Z","caller":"traceutil/trace.go:171","msg":"trace[771825875] transaction","detail":"{read_only:false; response_revision:25382; number_of_response:1; }","duration":"203.246224ms","start":"2026-06-05T03:31:11.907485Z","end":"2026-06-05T03:31:12.110731Z","steps":["trace[771825875] 'process raft request' (duration: 203.02971ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:31:12.111023Z","caller":"traceutil/trace.go:171","msg":"trace[64536159] transaction","detail":"{read_only:false; response_revision:25384; number_of_response:1; }","duration":"201.635479ms","start":"2026-06-05T03:31:11.909356Z","end":"2026-06-05T03:31:12.110991Z","steps":["trace[64536159] 'process raft request' (duration: 201.341823ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:31:12.111151Z","caller":"traceutil/trace.go:171","msg":"trace[949167171] transaction","detail":"{read_only:false; response_revision:25385; number_of_response:1; }","duration":"199.748368ms","start":"2026-06-05T03:31:11.911389Z","end":"2026-06-05T03:31:12.111137Z","steps":["trace[949167171] 'process raft request' (duration: 199.562494ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:31:12.111133Z","caller":"traceutil/trace.go:171","msg":"trace[1983276460] linearizableReadLoop","detail":"{readStateIndex:26218; appliedIndex:26214; }","duration":"118.62415ms","start":"2026-06-05T03:31:11.992484Z","end":"2026-06-05T03:31:12.111108Z","steps":["trace[1983276460] 'read index received' (duration: 117.972527ms)","trace[1983276460] 'applied index is now lower than readState.Index' (duration: 650.233µs)"],"step_count":2} {"level":"warn","ts":"2026-06-05T03:31:12.111294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.791254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-05T03:31:12.111333Z","caller":"traceutil/trace.go:171","msg":"trace[1778502148] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25385; }","duration":"118.892106ms","start":"2026-06-05T03:31:11.992432Z","end":"2026-06-05T03:31:12.111324Z","steps":["trace[1778502148] 'agreement among raft nodes before linearized reading' (duration: 118.764973ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:31:12.551225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.80843ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-05T03:31:12.551329Z","caller":"traceutil/trace.go:171","msg":"trace[1365126123] linearizableReadLoop","detail":"{readStateIndex:26221; appliedIndex:26220; }","duration":"218.120622ms","start":"2026-06-05T03:31:12.333192Z","end":"2026-06-05T03:31:12.551312Z","steps":["trace[1365126123] 'read index received' (duration: 67.008005ms)","trace[1365126123] 'applied index is now lower than readState.Index' (duration: 151.111627ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T03:31:12.55142Z","caller":"traceutil/trace.go:171","msg":"trace[366369304] transaction","detail":"{read_only:false; response_revision:25388; number_of_response:1; }","duration":"232.220694ms","start":"2026-06-05T03:31:12.31918Z","end":"2026-06-05T03:31:12.551401Z","steps":["trace[366369304] 'process raft request' (duration: 81.150558ms)","trace[366369304] 'compare' (duration: 150.683848ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T03:31:12.551613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.258105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-06-05T03:31:12.551687Z","caller":"traceutil/trace.go:171","msg":"trace[366060018] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25388; }","duration":"218.399838ms","start":"2026-06-05T03:31:12.333269Z","end":"2026-06-05T03:31:12.551669Z","steps":["trace[366060018] 'agreement among raft nodes before linearized reading' (duration: 218.179583ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:31:12.552122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.911279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-06-05T03:31:12.552233Z","caller":"traceutil/trace.go:171","msg":"trace[153308348] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25388; }","duration":"219.077152ms","start":"2026-06-05T03:31:12.33313Z","end":"2026-06-05T03:31:12.552207Z","steps":["trace[153308348] 'agreement among raft nodes before linearized reading' (duration: 218.449509ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:31:12.75074Z","caller":"traceutil/trace.go:171","msg":"trace[1712098963] transaction","detail":"{read_only:false; response_revision:25389; number_of_response:1; }","duration":"193.238909ms","start":"2026-06-05T03:31:12.557463Z","end":"2026-06-05T03:31:12.750702Z","steps":["trace[1712098963] 'process raft request' (duration: 162.635623ms)","trace[1712098963] 'compare' (duration: 30.433263ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T03:31:12.75179Z","caller":"traceutil/trace.go:171","msg":"trace[715439282] transaction","detail":"{read_only:false; response_revision:25390; number_of_response:1; }","duration":"193.481295ms","start":"2026-06-05T03:31:12.558286Z","end":"2026-06-05T03:31:12.751768Z","steps":["trace[715439282] 'process raft request' (duration: 193.27993ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:31:18.681081Z","caller":"traceutil/trace.go:171","msg":"trace[555931438] linearizableReadLoop","detail":"{readStateIndex:26266; appliedIndex:26265; }","duration":"220.150945ms","start":"2026-06-05T03:31:18.460905Z","end":"2026-06-05T03:31:18.681056Z","steps":["trace[555931438] 'read index received' (duration: 220.032052ms)","trace[555931438] 'applied index is now lower than readState.Index' (duration: 118.053µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T03:31:18.681188Z","caller":"traceutil/trace.go:171","msg":"trace[2020782874] transaction","detail":"{read_only:false; response_revision:25432; number_of_response:1; }","duration":"238.203572ms","start":"2026-06-05T03:31:18.442974Z","end":"2026-06-05T03:31:18.681178Z","steps":["trace[2020782874] 'process raft request' (duration: 237.947627ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:31:18.681353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.446452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-05T03:31:18.681391Z","caller":"traceutil/trace.go:171","msg":"trace[302941889] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25432; }","duration":"220.548934ms","start":"2026-06-05T03:31:18.460835Z","end":"2026-06-05T03:31:18.681384Z","steps":["trace[302941889] 'agreement among raft nodes before linearized reading' (duration: 220.465272ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:31:19.029281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.785635ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-05T03:31:19.029381Z","caller":"traceutil/trace.go:171","msg":"trace[1153970010] linearizableReadLoop","detail":"{readStateIndex:26267; appliedIndex:26266; }","duration":"348.21868ms","start":"2026-06-05T03:31:18.681147Z","end":"2026-06-05T03:31:19.029366Z","steps":["trace[1153970010] 'read index received' (duration: 207.102196ms)","trace[1153970010] 'applied index is now lower than readState.Index' (duration: 141.115444ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T03:31:19.029464Z","caller":"traceutil/trace.go:171","msg":"trace[720911463] transaction","detail":"{read_only:false; response_revision:25433; number_of_response:1; }","duration":"456.646761ms","start":"2026-06-05T03:31:18.572805Z","end":"2026-06-05T03:31:19.029452Z","steps":["trace[720911463] 'process raft request' (duration: 315.589119ms)","trace[720911463] 'compare' (duration: 140.684894ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T03:31:19.029512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T03:31:18.572778Z","time spent":"456.712293ms","remote":"127.0.0.1:33442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T03:31:19.02965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.399299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-05T03:31:19.029748Z","caller":"traceutil/trace.go:171","msg":"trace[1334369810] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25433; }","duration":"345.545822ms","start":"2026-06-05T03:31:18.684178Z","end":"2026-06-05T03:31:19.029724Z","steps":["trace[1334369810] 'agreement among raft nodes before linearized reading' (duration: 345.374258ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:31:19.029797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T03:31:18.684156Z","time spent":"345.632974ms","remote":"127.0.0.1:33368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":30,"request content":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-06-05T03:31:19.029892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.204321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/routerinterfaces/\" range_end:\"/registry/openstack.k-orc.cloud/routerinterfaces0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-05T03:31:19.02991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.648489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-06-05T03:31:19.029964Z","caller":"traceutil/trace.go:171","msg":"trace[1954529673] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/routerinterfaces/; range_end:/registry/openstack.k-orc.cloud/routerinterfaces0; response_count:0; response_revision:25433; }","duration":"546.366544ms","start":"2026-06-05T03:31:18.483579Z","end":"2026-06-05T03:31:19.029946Z","steps":["trace[1954529673] 'agreement among raft nodes before linearized reading' (duration: 546.18328ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:31:19.029989Z","caller":"traceutil/trace.go:171","msg":"trace[394755722] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25433; }","duration":"250.775852ms","start":"2026-06-05T03:31:18.77919Z","end":"2026-06-05T03:31:19.029966Z","steps":["trace[394755722] 'agreement among raft nodes before linearized reading' (duration: 250.584038ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:31:19.030002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T03:31:18.483552Z","time spent":"546.442025ms","remote":"127.0.0.1:45812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":30,"request content":"key:\"/registry/openstack.k-orc.cloud/routerinterfaces/\" range_end:\"/registry/openstack.k-orc.cloud/routerinterfaces0\" count_only:true "} {"level":"warn","ts":"2026-06-05T03:31:19.029999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.957569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-06-05T03:31:19.030082Z","caller":"traceutil/trace.go:171","msg":"trace[197406258] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25433; }","duration":"253.082402ms","start":"2026-06-05T03:31:18.77698Z","end":"2026-06-05T03:31:19.030062Z","steps":["trace[197406258] 'agreement among raft nodes before linearized reading' (duration: 252.832797ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:31:19.352424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.280078ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-05T03:31:19.35294Z","caller":"traceutil/trace.go:171","msg":"trace[556381855] transaction","detail":"{read_only:false; response_revision:25435; number_of_response:1; }","duration":"317.986992ms","start":"2026-06-05T03:31:19.034711Z","end":"2026-06-05T03:31:19.352698Z","steps":["trace[556381855] 'process raft request' (duration: 154.312795ms)","trace[556381855] 'compare' (duration: 163.096095ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T03:31:19.352958Z","caller":"traceutil/trace.go:171","msg":"trace[2008556324] linearizableReadLoop","detail":"{readStateIndex:26270; appliedIndex:26268; }","duration":"316.195874ms","start":"2026-06-05T03:31:19.036729Z","end":"2026-06-05T03:31:19.352925Z","steps":["trace[2008556324] 'read index received' (duration: 20.54688ms)","trace[2008556324] 'applied index is now lower than readState.Index' (duration: 295.647934ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T03:31:19.353075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T03:31:19.034688Z","time spent":"318.324569ms","remote":"127.0.0.1:33442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T03:31:19.353131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.385368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18572"} {"level":"info","ts":"2026-06-05T03:31:19.353187Z","caller":"traceutil/trace.go:171","msg":"trace[323606654] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25436; }","duration":"316.455409ms","start":"2026-06-05T03:31:19.036724Z","end":"2026-06-05T03:31:19.353179Z","steps":["trace[323606654] 'agreement among raft nodes before linearized reading' (duration: 316.294416ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:31:19.353211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T03:31:19.0367Z","time spent":"316.50661ms","remote":"127.0.0.1:33354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18596,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-06-05T03:31:19.352994Z","caller":"traceutil/trace.go:171","msg":"trace[1574618656] transaction","detail":"{read_only:false; response_revision:25436; number_of_response:1; }","duration":"317.949191ms","start":"2026-06-05T03:31:19.03502Z","end":"2026-06-05T03:31:19.352969Z","steps":["trace[1574618656] 'process raft request' (duration: 317.574653ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:31:19.353455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T03:31:19.035003Z","time spent":"318.40536ms","remote":"127.0.0.1:33442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T03:31:19.670718Z","caller":"traceutil/trace.go:171","msg":"trace[878714311] transaction","detail":"{read_only:false; response_revision:25437; number_of_response:1; }","duration":"102.078896ms","start":"2026-06-05T03:31:19.56861Z","end":"2026-06-05T03:31:19.670689Z","steps":["trace[878714311] 'process raft request' (duration: 101.892682ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:31:19.983301Z","caller":"traceutil/trace.go:171","msg":"trace[483222223] transaction","detail":"{read_only:false; response_revision:25438; number_of_response:1; }","duration":"202.425186ms","start":"2026-06-05T03:31:19.780658Z","end":"2026-06-05T03:31:19.983083Z","steps":["trace[483222223] 'process raft request' (duration: 106.611554ms)","trace[483222223] 'compare' (duration: 95.014805ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T03:31:46.194777Z","caller":"traceutil/trace.go:171","msg":"trace[1545525176] transaction","detail":"{read_only:false; response_revision:25639; number_of_response:1; }","duration":"117.555228ms","start":"2026-06-05T03:31:46.07719Z","end":"2026-06-05T03:31:46.194745Z","steps":["trace[1545525176] 'process raft request' (duration: 117.358364ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:31:46.56808Z","caller":"traceutil/trace.go:171","msg":"trace[290654331] transaction","detail":"{read_only:false; response_revision:25641; number_of_response:1; }","duration":"327.72311ms","start":"2026-06-05T03:31:46.240326Z","end":"2026-06-05T03:31:46.568049Z","steps":["trace[290654331] 'process raft request' (duration: 327.468695ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:31:46.568254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T03:31:46.240295Z","time spent":"327.865423ms","remote":"127.0.0.1:33442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T03:31:46.568331Z","caller":"traceutil/trace.go:171","msg":"trace[312694674] transaction","detail":"{read_only:false; response_revision:25642; number_of_response:1; }","duration":"327.329381ms","start":"2026-06-05T03:31:46.24097Z","end":"2026-06-05T03:31:46.568299Z","steps":["trace[312694674] 'process raft request' (duration: 326.995864ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:31:46.568491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T03:31:46.240957Z","time spent":"327.457264ms","remote":"127.0.0.1:33442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T03:31:46.568701Z","caller":"traceutil/trace.go:171","msg":"trace[2096371258] transaction","detail":"{read_only:false; response_revision:25643; number_of_response:1; }","duration":"326.34139ms","start":"2026-06-05T03:31:46.242346Z","end":"2026-06-05T03:31:46.568688Z","steps":["trace[2096371258] 'process raft request' (duration: 325.86811ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:31:46.568722Z","caller":"traceutil/trace.go:171","msg":"trace[1380449946] transaction","detail":"{read_only:false; response_revision:25644; number_of_response:1; }","duration":"326.190546ms","start":"2026-06-05T03:31:46.242518Z","end":"2026-06-05T03:31:46.568708Z","steps":["trace[1380449946] 'process raft request' (duration: 326.002262ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:31:46.568695Z","caller":"traceutil/trace.go:171","msg":"trace[177853914] linearizableReadLoop","detail":"{readStateIndex:26484; appliedIndex:26480; }","duration":"175.776735ms","start":"2026-06-05T03:31:46.392888Z","end":"2026-06-05T03:31:46.568665Z","steps":["trace[177853914] 'read index received' (duration: 174.786824ms)","trace[177853914] 'applied index is now lower than readState.Index' (duration: 987.961µs)"],"step_count":2} {"level":"warn","ts":"2026-06-05T03:31:46.568783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T03:31:46.242274Z","time spent":"326.469863ms","remote":"127.0.0.1:33442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T03:31:46.56879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T03:31:46.24249Z","time spent":"326.267358ms","remote":"127.0.0.1:33442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T03:31:46.568959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.055112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-06-05T03:31:46.568992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.400116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-06-05T03:31:46.56901Z","caller":"traceutil/trace.go:171","msg":"trace[131181676] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25644; }","duration":"176.168894ms","start":"2026-06-05T03:31:46.392828Z","end":"2026-06-05T03:31:46.568996Z","steps":["trace[131181676] 'agreement among raft nodes before linearized reading' (duration: 175.999601ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:31:46.569059Z","caller":"traceutil/trace.go:171","msg":"trace[320217156] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25644; }","duration":"174.509978ms","start":"2026-06-05T03:31:46.394532Z","end":"2026-06-05T03:31:46.569042Z","steps":["trace[320217156] 'agreement among raft nodes before linearized reading' (duration: 174.315834ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:31:46.912954Z","caller":"traceutil/trace.go:171","msg":"trace[1317958953] transaction","detail":"{read_only:false; response_revision:25647; number_of_response:1; }","duration":"131.339943ms","start":"2026-06-05T03:31:46.781532Z","end":"2026-06-05T03:31:46.912872Z","steps":["trace[1317958953] 'process raft request' (duration: 130.934995ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:31:47.31625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.350049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-05T03:31:47.316385Z","caller":"traceutil/trace.go:171","msg":"trace[1170643569] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25647; }","duration":"325.522193ms","start":"2026-06-05T03:31:46.990831Z","end":"2026-06-05T03:31:47.316353Z","steps":["trace[1170643569] 'range keys from in-memory index tree' (duration: 325.282488ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:31:47.316446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T03:31:46.990813Z","time spent":"325.618305ms","remote":"127.0.0.1:33158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-05T03:31:47.316683Z","caller":"traceutil/trace.go:171","msg":"trace[870996321] linearizableReadLoop","detail":"{readStateIndex:26488; appliedIndex:26487; }","duration":"129.236728ms","start":"2026-06-05T03:31:47.187416Z","end":"2026-06-05T03:31:47.316652Z","steps":["trace[870996321] 'read index received' (duration: 128.997253ms)","trace[870996321] 'applied index is now lower than readState.Index' (duration: 237.735µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T03:31:47.317033Z","caller":"traceutil/trace.go:171","msg":"trace[637110262] transaction","detail":"{read_only:false; response_revision:25648; number_of_response:1; }","duration":"163.777768ms","start":"2026-06-05T03:31:47.153238Z","end":"2026-06-05T03:31:47.317016Z","steps":["trace[637110262] 'process raft request' (duration: 163.174985ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T03:31:47.317469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.056445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-06-05T03:31:47.317525Z","caller":"traceutil/trace.go:171","msg":"trace[333386248] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25648; }","duration":"130.144897ms","start":"2026-06-05T03:31:47.187367Z","end":"2026-06-05T03:31:47.317512Z","steps":["trace[333386248] 'agreement among raft nodes before linearized reading' (duration: 129.964654ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T03:31:47.571449Z","caller":"traceutil/trace.go:171","msg":"trace[541505267] transaction","detail":"{read_only:false; response_revision:25649; number_of_response:1; }","duration":"248.955683ms","start":"2026-06-05T03:31:47.322457Z","end":"2026-06-05T03:31:47.571413Z","steps":["trace[541505267] 'process raft request' (duration: 223.763964ms)","trace[541505267] 'compare' (duration: 25.017595ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T03:31:47.753364Z","caller":"traceutil/trace.go:171","msg":"trace[1738805534] transaction","detail":"{read_only:false; response_revision:25651; number_of_response:1; }","duration":"176.806157ms","start":"2026-06-05T03:31:47.576529Z","end":"2026-06-05T03:31:47.753335Z","steps":["trace[1738805534] 'process raft request' (duration: 153.914287ms)","trace[1738805534] 'compare' (duration: 22.655725ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T03:32:22.753913Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23121} {"level":"info","ts":"2026-06-05T03:32:22.895529Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23121,"took":"132.634672ms","hash":3032662073,"current-db-size-bytes":58929152,"current-db-size":"59 MB","current-db-size-in-use-bytes":35426304,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-06-05T03:32:22.895602Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3032662073,"revision":23121,"compact-revision":20163} {"level":"info","ts":"2026-06-05T03:32:55.332391Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006a4a.wal"} {"level":"info","ts":"2026-06-05T03:37:22.76107Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26084} {"level":"info","ts":"2026-06-05T03:37:22.908031Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26084,"took":"142.672168ms","hash":3439245039,"current-db-size-bytes":60616704,"current-db-size":"61 MB","current-db-size-in-use-bytes":34447360,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-05T03:37:22.908119Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3439245039,"revision":26084,"compact-revision":23121} {"level":"info","ts":"2026-06-05T03:38:02.047945Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"975f045ff4f5c0fb","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-05T03:38:02.053231Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-06-05T03:38:02.053341Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-06-05T03:42:22.768783Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28786} {"level":"info","ts":"2026-06-05T03:42:22.912931Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28786,"took":"138.706713ms","hash":140142238,"current-db-size-bytes":60616704,"current-db-size":"61 MB","current-db-size-in-use-bytes":30109696,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-05T03:42:22.913023Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":140142238,"revision":28786,"compact-revision":26084} {"level":"info","ts":"2026-06-05T03:47:22.776051Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31002} {"level":"info","ts":"2026-06-05T03:47:22.887908Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31002,"took":"108.191708ms","hash":264075326,"current-db-size-bytes":60616704,"current-db-size":"61 MB","current-db-size-in-use-bytes":31010816,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-05T03:47:22.887984Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":264075326,"revision":31002,"compact-revision":28786} {"level":"info","ts":"2026-06-05T03:52:22.784209Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33272} {"level":"info","ts":"2026-06-05T03:52:22.935531Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33272,"took":"147.066042ms","hash":682138410,"current-db-size-bytes":61661184,"current-db-size":"62 MB","current-db-size-in-use-bytes":31719424,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-05T03:52:22.935607Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":682138410,"revision":33272,"compact-revision":31002} {"level":"info","ts":"2026-06-05T03:57:22.791269Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35572} {"level":"info","ts":"2026-06-05T03:57:22.937178Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35572,"took":"140.716356ms","hash":2489330476,"current-db-size-bytes":61661184,"current-db-size":"62 MB","current-db-size-in-use-bytes":30924800,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-05T03:57:22.937293Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2489330476,"revision":35572,"compact-revision":33272} {"level":"info","ts":"2026-06-05T03:59:32.777784Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"975f045ff4f5c0fb","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-05T03:59:32.783521Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-06-05T03:59:32.783618Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-06-05T04:02:22.798016Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37801} {"level":"info","ts":"2026-06-05T04:02:22.916045Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37801,"took":"114.349541ms","hash":1124012657,"current-db-size-bytes":61661184,"current-db-size":"62 MB","current-db-size-in-use-bytes":31690752,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-05T04:02:22.916101Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1124012657,"revision":37801,"compact-revision":35572}