{"level":"warn","ts":"2026-09-22T03:50:45.431295Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-22T03:50:45.431435Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.22: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://162.253.55.22:2380","--initial-cluster=instance=https://162.253.55.22:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.22:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.22: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-09-22T03:50:45.43153Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-22T03:50:45.431553Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.22:2380"]} {"level":"info","ts":"2026-09-22T03:50:45.431577Z","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-09-22T03:50:45.432365Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.22:2379"]} {"level":"info","ts":"2026-09-22T03:50:45.432469Z","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://162.253.55.22:2380"],"listen-peer-urls":["https://162.253.55.22:2380"],"advertise-client-urls":["https://162.253.55.22:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.22:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.22: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-09-22T03:50:45.447196Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"14.412068ms"} {"level":"info","ts":"2026-09-22T03:50:45.467388Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"8608f4af3693a53b","cluster-id":"c541111188849f0e"} {"level":"info","ts":"2026-09-22T03:50:45.467486Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b switched to configuration voters=()"} {"level":"info","ts":"2026-09-22T03:50:45.467723Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b became follower at term 0"} {"level":"info","ts":"2026-09-22T03:50:45.467767Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8608f4af3693a53b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-22T03:50:45.467793Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b became follower at term 1"} {"level":"info","ts":"2026-09-22T03:50:45.467875Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b switched to configuration voters=(9658238434268128571)"} {"level":"warn","ts":"2026-09-22T03:50:45.478008Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-22T03:50:45.484182Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-22T03:50:45.486437Z","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-09-22T03:50:45.489931Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"8608f4af3693a53b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-22T03:50:45.490207Z","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-09-22T03:50:45.490273Z","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-09-22T03:50:45.490309Z","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-09-22T03:50:45.490161Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"8608f4af3693a53b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-22T03:50:45.492854Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b switched to configuration voters=(9658238434268128571)"} {"level":"info","ts":"2026-09-22T03:50:45.493132Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c541111188849f0e","local-member-id":"8608f4af3693a53b","added-peer-id":"8608f4af3693a53b","added-peer-peer-urls":["https://162.253.55.22:2380"]} {"level":"info","ts":"2026-09-22T03:50:45.494384Z","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-09-22T03:50:45.494509Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.22:2380"} {"level":"info","ts":"2026-09-22T03:50:45.494556Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.22:2380"} {"level":"info","ts":"2026-09-22T03:50:45.495995Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"8608f4af3693a53b","initial-advertise-peer-urls":["https://162.253.55.22:2380"],"listen-peer-urls":["https://162.253.55.22:2380"],"advertise-client-urls":["https://162.253.55.22:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.22:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-22T03:50:45.496108Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-22T03:50:45.568822Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b is starting a new election at term 1"} {"level":"info","ts":"2026-09-22T03:50:45.568863Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b became pre-candidate at term 1"} {"level":"info","ts":"2026-09-22T03:50:45.568907Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b received MsgPreVoteResp from 8608f4af3693a53b at term 1"} {"level":"info","ts":"2026-09-22T03:50:45.568927Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b became candidate at term 2"} {"level":"info","ts":"2026-09-22T03:50:45.568939Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b received MsgVoteResp from 8608f4af3693a53b at term 2"} {"level":"info","ts":"2026-09-22T03:50:45.56896Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b became leader at term 2"} {"level":"info","ts":"2026-09-22T03:50:45.568972Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8608f4af3693a53b elected leader 8608f4af3693a53b at term 2"} {"level":"info","ts":"2026-09-22T03:50:45.570083Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"8608f4af3693a53b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.22:2379]}","request-path":"/0/members/8608f4af3693a53b/attributes","cluster-id":"c541111188849f0e","publish-timeout":"7s"} {"level":"info","ts":"2026-09-22T03:50:45.570126Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-22T03:50:45.570167Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-22T03:50:45.570298Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-22T03:50:45.570448Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-22T03:50:45.570657Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-22T03:50:45.57161Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c541111188849f0e","local-member-id":"8608f4af3693a53b","cluster-version":"3.5"} {"level":"info","ts":"2026-09-22T03:50:45.572637Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-22T03:50:45.572938Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-22T03:50:45.573651Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-22T03:50:45.589799Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.22:2379"} {"level":"info","ts":"2026-09-22T03:51:04.561104Z","caller":"traceutil/trace.go:171","msg":"trace[1784625996] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"115.716027ms","start":"2026-09-22T03:51:04.445359Z","end":"2026-09-22T03:51:04.561075Z","steps":["trace[1784625996] 'process raft request' (duration: 115.527302ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:51:05.104885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.761005ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-09-22T03:51:05.105071Z","caller":"traceutil/trace.go:171","msg":"trace[1525110405] linearizableReadLoop","detail":"{readStateIndex:435; appliedIndex:434; }","duration":"355.193233ms","start":"2026-09-22T03:51:04.749852Z","end":"2026-09-22T03:51:05.105046Z","steps":["trace[1525110405] 'read index received' (duration: 61.382µs)","trace[1525110405] 'applied index is now lower than readState.Index' (duration: 355.129951ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T03:51:05.105189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.331937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T03:51:05.105224Z","caller":"traceutil/trace.go:171","msg":"trace[1923153693] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:420; }","duration":"355.470581ms","start":"2026-09-22T03:51:04.749744Z","end":"2026-09-22T03:51:05.105214Z","steps":["trace[1923153693] 'agreement among raft nodes before linearized reading' (duration: 355.379199ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:51:05.105265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:51:04.749711Z","time spent":"355.545553ms","remote":"127.0.0.1:42866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-22T03:51:26.799662Z","caller":"traceutil/trace.go:171","msg":"trace[2152489] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"119.673914ms","start":"2026-09-22T03:51:26.67996Z","end":"2026-09-22T03:51:26.799634Z","steps":["trace[2152489] 'process raft request' (duration: 119.464028ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:51:33.334236Z","caller":"traceutil/trace.go:171","msg":"trace[1278149150] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"167.274265ms","start":"2026-09-22T03:51:33.166926Z","end":"2026-09-22T03:51:33.334201Z","steps":["trace[1278149150] 'process raft request' (duration: 166.610016ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:51:33.719161Z","caller":"traceutil/trace.go:171","msg":"trace[1133440127] linearizableReadLoop","detail":"{readStateIndex:665; appliedIndex:664; }","duration":"145.412282ms","start":"2026-09-22T03:51:33.573721Z","end":"2026-09-22T03:51:33.719133Z","steps":["trace[1133440127] 'read index received' (duration: 145.157775ms)","trace[1133440127] 'applied index is now lower than readState.Index' (duration: 253.367µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:51:33.719389Z","caller":"traceutil/trace.go:171","msg":"trace[197194684] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"304.473598ms","start":"2026-09-22T03:51:33.414879Z","end":"2026-09-22T03:51:33.719352Z","steps":["trace[197194684] 'process raft request' (duration: 304.101037ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:51:33.719459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.725591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-xvhtd\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-09-22T03:51:33.719526Z","caller":"traceutil/trace.go:171","msg":"trace[1913760795] range","detail":"{range_begin:/registry/pods/kube-system/cilium-xvhtd; range_end:; response_count:1; response_revision:640; }","duration":"145.831584ms","start":"2026-09-22T03:51:33.573654Z","end":"2026-09-22T03:51:33.719485Z","steps":["trace[1913760795] 'agreement among raft nodes before linearized reading' (duration: 145.635989ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:51:33.720059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:51:33.414846Z","time spent":"304.621972ms","remote":"127.0.0.1:43236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1044,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-22T03:51:34.207429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.196725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-22T03:51:34.2076Z","caller":"traceutil/trace.go:171","msg":"trace[2121394334] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:641; }","duration":"297.410831ms","start":"2026-09-22T03:51:33.91014Z","end":"2026-09-22T03:51:34.207551Z","steps":["trace[2121394334] 'range keys from in-memory index tree' (duration: 296.938807ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:51:39.008814Z","caller":"traceutil/trace.go:171","msg":"trace[1992885483] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"105.092997ms","start":"2026-09-22T03:51:38.90369Z","end":"2026-09-22T03:51:39.008783Z","steps":["trace[1992885483] 'process raft request' (duration: 104.865441ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:51:57.733709Z","caller":"traceutil/trace.go:171","msg":"trace[437661680] transaction","detail":"{read_only:false; response_revision:834; number_of_response:1; }","duration":"169.396813ms","start":"2026-09-22T03:51:57.564274Z","end":"2026-09-22T03:51:57.733671Z","steps":["trace[437661680] 'process raft request' (duration: 132.569084ms)","trace[437661680] 'compare' (duration: 36.521161ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:52:27.839103Z","caller":"traceutil/trace.go:171","msg":"trace[1348362098] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"130.057275ms","start":"2026-09-22T03:52:27.709Z","end":"2026-09-22T03:52:27.839057Z","steps":["trace[1348362098] 'process raft request' (duration: 129.840889ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:53:17.189027Z","caller":"traceutil/trace.go:171","msg":"trace[655228394] linearizableReadLoop","detail":"{readStateIndex:1983; appliedIndex:1982; }","duration":"180.527479ms","start":"2026-09-22T03:53:17.008471Z","end":"2026-09-22T03:53:17.188998Z","steps":["trace[655228394] 'read index received' (duration: 180.316963ms)","trace[655228394] 'applied index is now lower than readState.Index' (duration: 207.236µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:53:17.189087Z","caller":"traceutil/trace.go:171","msg":"trace[1114071141] transaction","detail":"{read_only:false; response_revision:1909; number_of_response:1; }","duration":"187.403174ms","start":"2026-09-22T03:53:17.001645Z","end":"2026-09-22T03:53:17.189048Z","steps":["trace[1114071141] 'process raft request' (duration: 187.170528ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:53:17.189566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.073265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-09-22T03:53:17.189629Z","caller":"traceutil/trace.go:171","msg":"trace[1261416697] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1909; }","duration":"181.204578ms","start":"2026-09-22T03:53:17.008408Z","end":"2026-09-22T03:53:17.189612Z","steps":["trace[1261416697] 'agreement among raft nodes before linearized reading' (duration: 180.759276ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:54:01.622089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.731025ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-22T03:54:01.622387Z","caller":"traceutil/trace.go:171","msg":"trace[1900374708] transaction","detail":"{read_only:false; response_revision:2201; number_of_response:1; }","duration":"174.115478ms","start":"2026-09-22T03:54:01.44824Z","end":"2026-09-22T03:54:01.622356Z","steps":["trace[1900374708] 'process raft request' (duration: 57.705913ms)","trace[1900374708] 'compare' (duration: 115.5502ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:54:07.230783Z","caller":"traceutil/trace.go:171","msg":"trace[1967561562] transaction","detail":"{read_only:false; response_revision:2229; number_of_response:1; }","duration":"129.241238ms","start":"2026-09-22T03:54:07.101511Z","end":"2026-09-22T03:54:07.230753Z","steps":["trace[1967561562] 'process raft request' (duration: 129.053983ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:54:07.514935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.475836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-22T03:54:07.515046Z","caller":"traceutil/trace.go:171","msg":"trace[989956247] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:2230; }","duration":"134.662622ms","start":"2026-09-22T03:54:07.380363Z","end":"2026-09-22T03:54:07.515026Z","steps":["trace[989956247] 'count revisions from in-memory index tree' (duration: 134.267241ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:54:07.515581Z","caller":"traceutil/trace.go:171","msg":"trace[710487945] transaction","detail":"{read_only:false; response_revision:2231; number_of_response:1; }","duration":"106.509204ms","start":"2026-09-22T03:54:07.409026Z","end":"2026-09-22T03:54:07.515535Z","steps":["trace[710487945] 'process raft request' (duration: 104.711303ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:54:07.842447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.154518ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-22T03:54:07.842666Z","caller":"traceutil/trace.go:171","msg":"trace[1116198352] transaction","detail":"{read_only:false; response_revision:2233; number_of_response:1; }","duration":"199.070354ms","start":"2026-09-22T03:54:07.643565Z","end":"2026-09-22T03:54:07.842636Z","steps":["trace[1116198352] 'process raft request' (duration: 41.577077ms)","trace[1116198352] 'compare' (duration: 156.928581ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:55:06.376838Z","caller":"traceutil/trace.go:171","msg":"trace[754124911] transaction","detail":"{read_only:false; response_revision:2724; number_of_response:1; }","duration":"139.435226ms","start":"2026-09-22T03:55:06.237346Z","end":"2026-09-22T03:55:06.376781Z","steps":["trace[754124911] 'process raft request' (duration: 113.108121ms)","trace[754124911] 'compare' (duration: 26.12872ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:55:06.636444Z","caller":"traceutil/trace.go:171","msg":"trace[2004121252] transaction","detail":"{read_only:false; response_revision:2725; number_of_response:1; }","duration":"178.324547ms","start":"2026-09-22T03:55:06.458084Z","end":"2026-09-22T03:55:06.636409Z","steps":["trace[2004121252] 'process raft request' (duration: 171.986268ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:55:41.863446Z","caller":"traceutil/trace.go:171","msg":"trace[927304712] transaction","detail":"{read_only:false; response_revision:2934; number_of_response:1; }","duration":"101.488918ms","start":"2026-09-22T03:55:41.761934Z","end":"2026-09-22T03:55:41.863423Z","steps":["trace[927304712] 'process raft request' (duration: 100.940052ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:55:42.022099Z","caller":"traceutil/trace.go:171","msg":"trace[511852214] transaction","detail":"{read_only:false; response_revision:2935; number_of_response:1; }","duration":"153.197654ms","start":"2026-09-22T03:55:41.868865Z","end":"2026-09-22T03:55:42.022063Z","steps":["trace[511852214] 'process raft request' (duration: 65.31976ms)","trace[511852214] 'compare' (duration: 87.664658ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:58:53.806393Z","caller":"traceutil/trace.go:171","msg":"trace[503748680] transaction","detail":"{read_only:false; response_revision:4141; number_of_response:1; }","duration":"140.542499ms","start":"2026-09-22T03:58:53.665822Z","end":"2026-09-22T03:58:53.806365Z","steps":["trace[503748680] 'process raft request' (duration: 140.2326ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:58:59.214276Z","caller":"traceutil/trace.go:171","msg":"trace[1425096280] transaction","detail":"{read_only:false; response_revision:4172; number_of_response:1; }","duration":"142.819294ms","start":"2026-09-22T03:58:59.071413Z","end":"2026-09-22T03:58:59.214233Z","steps":["trace[1425096280] 'process raft request' (duration: 142.540146ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:58:59.298968Z","caller":"traceutil/trace.go:171","msg":"trace[444935269] linearizableReadLoop","detail":"{readStateIndex:4332; appliedIndex:4330; }","duration":"180.897082ms","start":"2026-09-22T03:58:59.118045Z","end":"2026-09-22T03:58:59.298942Z","steps":["trace[444935269] 'read index received' (duration: 96.000588ms)","trace[444935269] 'applied index is now lower than readState.Index' (duration: 84.891814ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:58:59.299129Z","caller":"traceutil/trace.go:171","msg":"trace[2087631032] transaction","detail":"{read_only:false; response_revision:4173; number_of_response:1; }","duration":"214.665638ms","start":"2026-09-22T03:58:59.084449Z","end":"2026-09-22T03:58:59.299115Z","steps":["trace[2087631032] 'process raft request' (duration: 188.651091ms)","trace[2087631032] 'compare' (duration: 25.704968ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T03:58:59.299295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.223452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-22T03:58:59.299402Z","caller":"traceutil/trace.go:171","msg":"trace[931837266] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:4173; }","duration":"181.413507ms","start":"2026-09-22T03:58:59.117961Z","end":"2026-09-22T03:58:59.299375Z","steps":["trace[931837266] 'agreement among raft nodes before linearized reading' (duration: 181.239162ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:00:39.525996Z","caller":"traceutil/trace.go:171","msg":"trace[1954414141] linearizableReadLoop","detail":"{readStateIndex:5622; appliedIndex:5621; }","duration":"131.409671ms","start":"2026-09-22T04:00:39.394562Z","end":"2026-09-22T04:00:39.525971Z","steps":["trace[1954414141] 'read index received' (duration: 57.274087ms)","trace[1954414141] 'applied index is now lower than readState.Index' (duration: 74.134244ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:00:39.526031Z","caller":"traceutil/trace.go:171","msg":"trace[352421826] transaction","detail":"{read_only:false; response_revision:5414; number_of_response:1; }","duration":"144.122ms","start":"2026-09-22T04:00:39.381878Z","end":"2026-09-22T04:00:39.526Z","steps":["trace[352421826] 'process raft request' (duration: 69.927844ms)","trace[352421826] 'compare' (duration: 73.968509ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:00:39.526205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.623967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a4e3b03f-82b8-4965-a24a-5b86c67c09fa\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-09-22T04:00:39.526238Z","caller":"traceutil/trace.go:171","msg":"trace[866211823] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a4e3b03f-82b8-4965-a24a-5b86c67c09fa; range_end:; response_count:1; response_revision:5414; }","duration":"131.709029ms","start":"2026-09-22T04:00:39.394519Z","end":"2026-09-22T04:00:39.526228Z","steps":["trace[866211823] 'agreement among raft nodes before linearized reading' (duration: 131.559085ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:00:39.714782Z","caller":"traceutil/trace.go:171","msg":"trace[678530543] linearizableReadLoop","detail":"{readStateIndex:5625; appliedIndex:5623; }","duration":"149.063959ms","start":"2026-09-22T04:00:39.565697Z","end":"2026-09-22T04:00:39.714761Z","steps":["trace[678530543] 'read index received' (duration: 142.455842ms)","trace[678530543] 'applied index is now lower than readState.Index' (duration: 6.607337ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:00:39.715029Z","caller":"traceutil/trace.go:171","msg":"trace[1790823146] transaction","detail":"{read_only:false; response_revision:5415; number_of_response:1; }","duration":"182.621777ms","start":"2026-09-22T04:00:39.532392Z","end":"2026-09-22T04:00:39.715014Z","steps":["trace[1790823146] 'process raft request' (duration: 175.748013ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:00:39.715338Z","caller":"traceutil/trace.go:171","msg":"trace[1800642531] transaction","detail":"{read_only:false; response_revision:5416; number_of_response:1; }","duration":"181.012551ms","start":"2026-09-22T04:00:39.534284Z","end":"2026-09-22T04:00:39.715296Z","steps":["trace[1800642531] 'process raft request' (duration: 180.412854ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:00:39.715469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.34615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-jp4xw-tls\" ","response":"range_response_count:1 size:4423"} {"level":"info","ts":"2026-09-22T04:00:39.715548Z","caller":"traceutil/trace.go:171","msg":"trace[624813112] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-jp4xw-tls; range_end:; response_count:1; response_revision:5416; }","duration":"181.470864ms","start":"2026-09-22T04:00:39.534059Z","end":"2026-09-22T04:00:39.71553Z","steps":["trace[624813112] 'agreement among raft nodes before linearized reading' (duration: 181.176266ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:00:39.73651Z","caller":"traceutil/trace.go:171","msg":"trace[232717816] transaction","detail":"{read_only:false; response_revision:5417; number_of_response:1; }","duration":"169.467286ms","start":"2026-09-22T04:00:39.566998Z","end":"2026-09-22T04:00:39.736465Z","steps":["trace[232717816] 'process raft request' (duration: 169.027843ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:00:39.737048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.736766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/vector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T04:00:39.737144Z","caller":"traceutil/trace.go:171","msg":"trace[1325605256] range","detail":"{range_begin:/registry/clusterroles/vector; range_end:; response_count:0; response_revision:5418; }","duration":"167.8893ms","start":"2026-09-22T04:00:39.569233Z","end":"2026-09-22T04:00:39.737123Z","steps":["trace[1325605256] 'agreement among raft nodes before linearized reading' (duration: 167.022106ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:00:46.022933Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2954} {"level":"info","ts":"2026-09-22T04:00:46.100943Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2954,"took":"76.456489ms","hash":2794968482,"current-db-size-bytes":55128064,"current-db-size":"55 MB","current-db-size-in-use-bytes":30588928,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-22T04:00:46.101202Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2794968482,"revision":2954,"compact-revision":-1} {"level":"info","ts":"2026-09-22T04:00:54.695743Z","caller":"traceutil/trace.go:171","msg":"trace[878030544] transaction","detail":"{read_only:false; response_revision:5687; number_of_response:1; }","duration":"102.466064ms","start":"2026-09-22T04:00:54.593223Z","end":"2026-09-22T04:00:54.695689Z","steps":["trace[878030544] 'process raft request' (duration: 67.97179ms)","trace[878030544] 'compare' (duration: 34.229856ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:00:55.029436Z","caller":"traceutil/trace.go:171","msg":"trace[420824975] linearizableReadLoop","detail":"{readStateIndex:5910; appliedIndex:5906; }","duration":"186.735133ms","start":"2026-09-22T04:00:54.842678Z","end":"2026-09-22T04:00:55.029413Z","steps":["trace[420824975] 'read index received' (duration: 186.453985ms)","trace[420824975] 'applied index is now lower than readState.Index' (duration: 280.078µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:00:55.029671Z","caller":"traceutil/trace.go:171","msg":"trace[1217438955] transaction","detail":"{read_only:false; response_revision:5691; number_of_response:1; }","duration":"251.997266ms","start":"2026-09-22T04:00:54.777666Z","end":"2026-09-22T04:00:55.029663Z","steps":["trace[1217438955] 'process raft request' (duration: 251.591554ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:00:55.029831Z","caller":"traceutil/trace.go:171","msg":"trace[1852542201] transaction","detail":"{read_only:false; response_revision:5692; number_of_response:1; }","duration":"252.131809ms","start":"2026-09-22T04:00:54.777686Z","end":"2026-09-22T04:00:55.029818Z","steps":["trace[1852542201] 'process raft request' (duration: 251.640045ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:00:55.029447Z","caller":"traceutil/trace.go:171","msg":"trace[1920032031] transaction","detail":"{read_only:false; response_revision:5690; number_of_response:1; }","duration":"252.002266ms","start":"2026-09-22T04:00:54.777416Z","end":"2026-09-22T04:00:55.029418Z","steps":["trace[1920032031] 'process raft request' (duration: 251.655876ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:00:55.030008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.087977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-rr2sk\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-09-22T04:00:55.030026Z","caller":"traceutil/trace.go:171","msg":"trace[1758539907] transaction","detail":"{read_only:false; response_revision:5693; number_of_response:1; }","duration":"251.431099ms","start":"2026-09-22T04:00:54.778567Z","end":"2026-09-22T04:00:55.029998Z","steps":["trace[1758539907] 'process raft request' (duration: 250.804862ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:00:55.030085Z","caller":"traceutil/trace.go:171","msg":"trace[1149231819] range","detail":"{range_begin:/registry/pods/openstack/keepalived-rr2sk; range_end:; response_count:1; response_revision:5693; }","duration":"202.22983ms","start":"2026-09-22T04:00:54.827835Z","end":"2026-09-22T04:00:55.030065Z","steps":["trace[1149231819] 'agreement among raft nodes before linearized reading' (duration: 201.959313ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:00:55.030107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.244662ms","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-09-22T04:00:55.030192Z","caller":"traceutil/trace.go:171","msg":"trace[1338454585] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5693; }","duration":"218.357925ms","start":"2026-09-22T04:00:54.811814Z","end":"2026-09-22T04:00:55.030172Z","steps":["trace[1338454585] 'agreement among raft nodes before linearized reading' (duration: 218.046536ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:00:55.069348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.071435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-22T04:00:55.06942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.807604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1986"} {"level":"info","ts":"2026-09-22T04:00:55.069521Z","caller":"traceutil/trace.go:171","msg":"trace[1237845570] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:5694; }","duration":"107.99236ms","start":"2026-09-22T04:00:54.961507Z","end":"2026-09-22T04:00:55.0695Z","steps":["trace[1237845570] 'agreement among raft nodes before linearized reading' (duration: 107.65425ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:00:55.069457Z","caller":"traceutil/trace.go:171","msg":"trace[902510455] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5694; }","duration":"225.270551ms","start":"2026-09-22T04:00:54.844157Z","end":"2026-09-22T04:00:55.069428Z","steps":["trace[902510455] 'agreement among raft nodes before linearized reading' (duration: 225.038464ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:00:59.732729Z","caller":"traceutil/trace.go:171","msg":"trace[1462808014] transaction","detail":"{read_only:false; response_revision:5747; number_of_response:1; }","duration":"124.29295ms","start":"2026-09-22T04:00:59.608416Z","end":"2026-09-22T04:00:59.732709Z","steps":["trace[1462808014] 'process raft request' (duration: 124.150926ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:01:04.07259Z","caller":"traceutil/trace.go:171","msg":"trace[1601412268] transaction","detail":"{read_only:false; response_revision:5860; number_of_response:1; }","duration":"205.355878ms","start":"2026-09-22T04:01:03.867179Z","end":"2026-09-22T04:01:04.072535Z","steps":["trace[1601412268] 'process raft request' (duration: 204.827053ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:01:04.073488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.343827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e6056d1a-edd5-4799-816f-1e1e2f8326b9\" ","response":"range_response_count:1 size:3765"} {"level":"info","ts":"2026-09-22T04:01:04.073828Z","caller":"traceutil/trace.go:171","msg":"trace[1380930719] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e6056d1a-edd5-4799-816f-1e1e2f8326b9; range_end:; response_count:1; response_revision:5860; }","duration":"194.77728ms","start":"2026-09-22T04:01:03.879028Z","end":"2026-09-22T04:01:04.073806Z","steps":["trace[1380930719] 'agreement among raft nodes before linearized reading' (duration: 193.581326ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:01:04.07256Z","caller":"traceutil/trace.go:171","msg":"trace[903081551] linearizableReadLoop","detail":"{readStateIndex:6082; appliedIndex:6080; }","duration":"193.37868ms","start":"2026-09-22T04:01:03.879124Z","end":"2026-09-22T04:01:04.072503Z","steps":["trace[903081551] 'read index received' (duration: 48.702345ms)","trace[903081551] 'applied index is now lower than readState.Index' (duration: 144.674745ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:01:04.074553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.742629ms","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-09-22T04:01:04.076539Z","caller":"traceutil/trace.go:171","msg":"trace[60577997] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5860; }","duration":"117.729615ms","start":"2026-09-22T04:01:03.958793Z","end":"2026-09-22T04:01:04.076523Z","steps":["trace[60577997] 'agreement among raft nodes before linearized reading' (duration: 115.657526ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:01:04.299163Z","caller":"traceutil/trace.go:171","msg":"trace[1680659286] linearizableReadLoop","detail":"{readStateIndex:6085; appliedIndex:6084; }","duration":"122.417787ms","start":"2026-09-22T04:01:04.176704Z","end":"2026-09-22T04:01:04.299121Z","steps":["trace[1680659286] 'read index received' (duration: 70.076309ms)","trace[1680659286] 'applied index is now lower than readState.Index' (duration: 52.339548ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:01:04.299308Z","caller":"traceutil/trace.go:171","msg":"trace[1668592334] transaction","detail":"{read_only:false; response_revision:5863; number_of_response:1; }","duration":"168.090716ms","start":"2026-09-22T04:01:04.131135Z","end":"2026-09-22T04:01:04.299226Z","steps":["trace[1668592334] 'process raft request' (duration: 115.749248ms)","trace[1668592334] 'compare' (duration: 51.910456ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:01:04.29946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.740056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-09-22T04:01:04.299569Z","caller":"traceutil/trace.go:171","msg":"trace[355169737] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5863; }","duration":"122.931931ms","start":"2026-09-22T04:01:04.176611Z","end":"2026-09-22T04:01:04.299543Z","steps":["trace[355169737] 'agreement among raft nodes before linearized reading' (duration: 122.652133ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:01:15.113454Z","caller":"traceutil/trace.go:171","msg":"trace[611604201] transaction","detail":"{read_only:false; response_revision:6011; number_of_response:1; }","duration":"100.051376ms","start":"2026-09-22T04:01:15.013309Z","end":"2026-09-22T04:01:15.11336Z","steps":["trace[611604201] 'process raft request' (duration: 99.560752ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:05:46.031256Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5558} {"level":"info","ts":"2026-09-22T04:05:46.123652Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5558,"took":"90.249687ms","hash":3988309688,"current-db-size-bytes":55128064,"current-db-size":"55 MB","current-db-size-in-use-bytes":28483584,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-22T04:05:46.123777Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3988309688,"revision":5558,"compact-revision":2954} {"level":"info","ts":"2026-09-22T04:07:22.45831Z","caller":"traceutil/trace.go:171","msg":"trace[963423678] linearizableReadLoop","detail":"{readStateIndex:9067; appliedIndex:9066; }","duration":"188.033595ms","start":"2026-09-22T04:07:22.270247Z","end":"2026-09-22T04:07:22.458281Z","steps":["trace[963423678] 'read index received' (duration: 187.819479ms)","trace[963423678] 'applied index is now lower than readState.Index' (duration: 212.886µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:07:22.458409Z","caller":"traceutil/trace.go:171","msg":"trace[1679923820] transaction","detail":"{read_only:false; response_revision:8754; number_of_response:1; }","duration":"209.729488ms","start":"2026-09-22T04:07:22.248621Z","end":"2026-09-22T04:07:22.45835Z","steps":["trace[1679923820] 'process raft request' (duration: 209.481551ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:07:22.45853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.245682ms","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":"warn","ts":"2026-09-22T04:07:22.458562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.898706ms","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-09-22T04:07:22.458598Z","caller":"traceutil/trace.go:171","msg":"trace[445241544] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8754; }","duration":"112.940207ms","start":"2026-09-22T04:07:22.345647Z","end":"2026-09-22T04:07:22.458587Z","steps":["trace[445241544] 'agreement among raft nodes before linearized reading' (duration: 112.799083ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:07:22.45862Z","caller":"traceutil/trace.go:171","msg":"trace[84522099] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:8754; }","duration":"188.402286ms","start":"2026-09-22T04:07:22.270198Z","end":"2026-09-22T04:07:22.458601Z","steps":["trace[84522099] 'agreement among raft nodes before linearized reading' (duration: 188.241912ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:07:22.641824Z","caller":"traceutil/trace.go:171","msg":"trace[1494039988] transaction","detail":"{read_only:false; response_revision:8755; number_of_response:1; }","duration":"178.809865ms","start":"2026-09-22T04:07:22.462983Z","end":"2026-09-22T04:07:22.641793Z","steps":["trace[1494039988] 'process raft request' (duration: 177.252221ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:07:22.641797Z","caller":"traceutil/trace.go:171","msg":"trace[214971115] linearizableReadLoop","detail":"{readStateIndex:9068; appliedIndex:9067; }","duration":"153.534532ms","start":"2026-09-22T04:07:22.488215Z","end":"2026-09-22T04:07:22.641749Z","steps":["trace[214971115] 'read index received' (duration: 152.087131ms)","trace[214971115] 'applied index is now lower than readState.Index' (duration: 1.445281ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:07:22.642124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.879392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-09-22T04:07:22.642193Z","caller":"traceutil/trace.go:171","msg":"trace[1321140700] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8755; }","duration":"154.023966ms","start":"2026-09-22T04:07:22.488151Z","end":"2026-09-22T04:07:22.642175Z","steps":["trace[1321140700] 'agreement among raft nodes before linearized reading' (duration: 153.725608ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:07:35.15188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.641749ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-09-22T04:07:35.152299Z","caller":"traceutil/trace.go:171","msg":"trace[330068429] linearizableReadLoop","detail":"{readStateIndex:9136; appliedIndex:9135; }","duration":"203.89564ms","start":"2026-09-22T04:07:34.948279Z","end":"2026-09-22T04:07:35.152175Z","steps":["trace[330068429] 'read index received' (duration: 61.021µs)","trace[330068429] 'applied index is now lower than readState.Index' (duration: 203.833329ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:07:35.152548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.27078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-22T04:07:35.152582Z","caller":"traceutil/trace.go:171","msg":"trace[1962263123] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8820; }","duration":"204.320222ms","start":"2026-09-22T04:07:34.948251Z","end":"2026-09-22T04:07:35.152571Z","steps":["trace[1962263123] 'agreement among raft nodes before linearized reading' (duration: 204.154727ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:07:35.15287Z","caller":"traceutil/trace.go:171","msg":"trace[1004137662] transaction","detail":"{read_only:false; response_revision:8821; number_of_response:1; }","duration":"108.10885ms","start":"2026-09-22T04:07:35.044742Z","end":"2026-09-22T04:07:35.152851Z","steps":["trace[1004137662] 'process raft request' (duration: 107.298477ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:08:07.699766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.847618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-22T04:08:07.699921Z","caller":"traceutil/trace.go:171","msg":"trace[410042354] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9047; }","duration":"263.079464ms","start":"2026-09-22T04:08:07.436809Z","end":"2026-09-22T04:08:07.699889Z","steps":["trace[410042354] 'range keys from in-memory index tree' (duration: 262.54241ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:08:07.699777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.549625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-22T04:08:07.700402Z","caller":"traceutil/trace.go:171","msg":"trace[1106968095] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9047; }","duration":"260.251014ms","start":"2026-09-22T04:08:07.440132Z","end":"2026-09-22T04:08:07.700383Z","steps":["trace[1106968095] 'range keys from in-memory index tree' (duration: 259.275437ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:08:07.701324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.481769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-09-22T04:08:07.701949Z","caller":"traceutil/trace.go:171","msg":"trace[1617375486] linearizableReadLoop","detail":"{readStateIndex:9373; appliedIndex:9372; }","duration":"130.898994ms","start":"2026-09-22T04:08:07.571021Z","end":"2026-09-22T04:08:07.70192Z","steps":["trace[1617375486] 'read index received' (duration: 126.602222ms)","trace[1617375486] 'applied index is now lower than readState.Index' (duration: 4.294552ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:08:07.702025Z","caller":"traceutil/trace.go:171","msg":"trace[1130865021] transaction","detail":"{read_only:false; response_revision:9048; number_of_response:1; }","duration":"136.323198ms","start":"2026-09-22T04:08:07.565674Z","end":"2026-09-22T04:08:07.701998Z","steps":["trace[1130865021] 'process raft request' (duration: 132.066438ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:08:07.702167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.082159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-22T04:08:07.70221Z","caller":"traceutil/trace.go:171","msg":"trace[1644231991] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:9048; }","duration":"131.204123ms","start":"2026-09-22T04:08:07.570993Z","end":"2026-09-22T04:08:07.702197Z","steps":["trace[1644231991] 'agreement among raft nodes before linearized reading' (duration: 131.065549ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:08:07.70226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.328053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-22T04:08:07.702436Z","caller":"traceutil/trace.go:171","msg":"trace[658373543] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:9048; }","duration":"104.530198ms","start":"2026-09-22T04:08:07.597851Z","end":"2026-09-22T04:08:07.702381Z","steps":["trace[658373543] 'agreement among raft nodes before linearized reading' (duration: 104.2485ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:08:07.702499Z","caller":"traceutil/trace.go:171","msg":"trace[1704589174] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:9047; }","duration":"179.602532ms","start":"2026-09-22T04:08:07.521801Z","end":"2026-09-22T04:08:07.701404Z","steps":["trace[1704589174] 'range keys from in-memory index tree' (duration: 179.270683ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:08:07.84665Z","caller":"traceutil/trace.go:171","msg":"trace[1049305427] transaction","detail":"{read_only:false; response_revision:9049; number_of_response:1; }","duration":"140.109345ms","start":"2026-09-22T04:08:07.706494Z","end":"2026-09-22T04:08:07.846604Z","steps":["trace[1049305427] 'process raft request' (duration: 139.805487ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:08:07.847803Z","caller":"traceutil/trace.go:171","msg":"trace[1034452767] transaction","detail":"{read_only:false; response_revision:9050; number_of_response:1; }","duration":"140.052913ms","start":"2026-09-22T04:08:07.707714Z","end":"2026-09-22T04:08:07.847767Z","steps":["trace[1034452767] 'process raft request' (duration: 139.670912ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:08:07.847873Z","caller":"traceutil/trace.go:171","msg":"trace[545011026] transaction","detail":"{read_only:false; response_revision:9051; number_of_response:1; }","duration":"137.974924ms","start":"2026-09-22T04:08:07.709861Z","end":"2026-09-22T04:08:07.847836Z","steps":["trace[545011026] 'process raft request' (duration: 137.754288ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:08:17.252484Z","caller":"traceutil/trace.go:171","msg":"trace[2049732443] linearizableReadLoop","detail":"{readStateIndex:9424; appliedIndex:9423; }","duration":"191.839229ms","start":"2026-09-22T04:08:17.060626Z","end":"2026-09-22T04:08:17.252466Z","steps":["trace[2049732443] 'read index received' (duration: 191.648053ms)","trace[2049732443] 'applied index is now lower than readState.Index' (duration: 190.206µs)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:08:17.252672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.038235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-22T04:08:17.252708Z","caller":"traceutil/trace.go:171","msg":"trace[167618452] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9097; }","duration":"192.138228ms","start":"2026-09-22T04:08:17.060562Z","end":"2026-09-22T04:08:17.252701Z","steps":["trace[167618452] 'agreement among raft nodes before linearized reading' (duration: 192.007324ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:08:17.252654Z","caller":"traceutil/trace.go:171","msg":"trace[943858729] transaction","detail":"{read_only:false; response_revision:9097; number_of_response:1; }","duration":"197.446698ms","start":"2026-09-22T04:08:17.055122Z","end":"2026-09-22T04:08:17.252569Z","steps":["trace[943858729] 'process raft request' (duration: 197.207721ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:08:17.252818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.200662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-09-22T04:08:17.252891Z","caller":"traceutil/trace.go:171","msg":"trace[2140634609] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:9097; }","duration":"190.306885ms","start":"2026-09-22T04:08:17.062563Z","end":"2026-09-22T04:08:17.25287Z","steps":["trace[2140634609] 'agreement among raft nodes before linearized reading' (duration: 190.078149ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:08:17.369798Z","caller":"traceutil/trace.go:171","msg":"trace[292110467] transaction","detail":"{read_only:false; response_revision:9099; number_of_response:1; }","duration":"111.538726ms","start":"2026-09-22T04:08:17.258218Z","end":"2026-09-22T04:08:17.369757Z","steps":["trace[292110467] 'process raft request' (duration: 100.71213ms)","trace[292110467] 'compare' (duration: 10.635611ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:08:21.912047Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024f6.wal"} {"level":"info","ts":"2026-09-22T04:08:21.912715Z","caller":"traceutil/trace.go:171","msg":"trace[1610801112] transaction","detail":"{read_only:false; response_revision:9133; number_of_response:1; }","duration":"271.616356ms","start":"2026-09-22T04:08:21.641057Z","end":"2026-09-22T04:08:21.912674Z","steps":["trace[1610801112] 'process raft request' (duration: 271.41334ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:08:21.923034Z","caller":"traceutil/trace.go:171","msg":"trace[27817110] linearizableReadLoop","detail":"{readStateIndex:9462; appliedIndex:9460; }","duration":"172.78748ms","start":"2026-09-22T04:08:21.750223Z","end":"2026-09-22T04:08:21.923011Z","steps":["trace[27817110] 'read index received' (duration: 162.216721ms)","trace[27817110] 'applied index is now lower than readState.Index' (duration: 10.569819ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:08:21.923312Z","caller":"traceutil/trace.go:171","msg":"trace[476844436] transaction","detail":"{read_only:false; number_of_response:1; response_revision:9134; }","duration":"279.971273ms","start":"2026-09-22T04:08:21.643331Z","end":"2026-09-22T04:08:21.923302Z","steps":["trace[476844436] 'process raft request' (duration: 279.481779ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:08:21.923617Z","caller":"traceutil/trace.go:171","msg":"trace[702101692] transaction","detail":"{read_only:false; response_revision:9135; number_of_response:1; }","duration":"157.278661ms","start":"2026-09-22T04:08:21.766328Z","end":"2026-09-22T04:08:21.923607Z","steps":["trace[702101692] 'process raft request' (duration: 156.618542ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:08:21.923777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.5244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T04:08:21.923872Z","caller":"traceutil/trace.go:171","msg":"trace[1918020441] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9135; }","duration":"173.693486ms","start":"2026-09-22T04:08:21.750163Z","end":"2026-09-22T04:08:21.923856Z","steps":["trace[1918020441] 'agreement among raft nodes before linearized reading' (duration: 173.50843ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:08:22.194163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.979995ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-22T04:08:22.194288Z","caller":"traceutil/trace.go:171","msg":"trace[1225554575] linearizableReadLoop","detail":"{readStateIndex:9465; appliedIndex:9464; }","duration":"229.831024ms","start":"2026-09-22T04:08:21.964436Z","end":"2026-09-22T04:08:22.194267Z","steps":["trace[1225554575] 'read index received' (duration: 97.615822ms)","trace[1225554575] 'applied index is now lower than readState.Index' (duration: 132.213762ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:08:22.194488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.024329ms","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-09-22T04:08:22.194479Z","caller":"traceutil/trace.go:171","msg":"trace[2131348230] transaction","detail":"{read_only:false; response_revision:9137; number_of_response:1; }","duration":"243.85376ms","start":"2026-09-22T04:08:21.950566Z","end":"2026-09-22T04:08:22.19442Z","steps":["trace[2131348230] 'process raft request' (duration: 111.505945ms)","trace[2131348230] 'compare' (duration: 131.848381ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:08:22.194544Z","caller":"traceutil/trace.go:171","msg":"trace[2095366586] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9137; }","duration":"230.120982ms","start":"2026-09-22T04:08:21.964407Z","end":"2026-09-22T04:08:22.194528Z","steps":["trace[2095366586] 'agreement among raft nodes before linearized reading' (duration: 229.949077ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:08:22.208555Z","caller":"traceutil/trace.go:171","msg":"trace[894521697] transaction","detail":"{read_only:false; response_revision:9138; number_of_response:1; }","duration":"119.177213ms","start":"2026-09-22T04:08:22.08936Z","end":"2026-09-22T04:08:22.208537Z","steps":["trace[894521697] 'process raft request' (duration: 119.048039ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:08:22.209001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.416394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/openstack/ceph\" ","response":"range_response_count:1 size:3672"} {"level":"info","ts":"2026-09-22T04:08:22.209091Z","caller":"traceutil/trace.go:171","msg":"trace[1865426487] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/openstack/ceph; range_end:; response_count:1; response_revision:9138; }","duration":"199.579748ms","start":"2026-09-22T04:08:22.009488Z","end":"2026-09-22T04:08:22.209068Z","steps":["trace[1865426487] 'agreement among raft nodes before linearized reading' (duration: 199.157936ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:09:20.854578Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"8608f4af3693a53b","local-member-applied-index":10003,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-22T04:09:20.861946Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10003} {"level":"info","ts":"2026-09-22T04:09:20.862078Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5003} {"level":"warn","ts":"2026-09-22T04:09:49.165157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.051364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-rr2sk\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-09-22T04:09:49.165295Z","caller":"traceutil/trace.go:171","msg":"trace[1221907245] range","detail":"{range_begin:/registry/pods/openstack/keepalived-rr2sk; range_end:; response_count:1; response_revision:9970; }","duration":"153.25509ms","start":"2026-09-22T04:09:49.012009Z","end":"2026-09-22T04:09:49.165264Z","steps":["trace[1221907245] 'range keys from in-memory index tree' (duration: 152.791117ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:10:46.041431Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7981} {"level":"info","ts":"2026-09-22T04:10:46.135708Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7981,"took":"90.840408ms","hash":3703326116,"current-db-size-bytes":55128064,"current-db-size":"55 MB","current-db-size-in-use-bytes":25116672,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-09-22T04:10:46.135821Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3703326116,"revision":7981,"compact-revision":5558} {"level":"info","ts":"2026-09-22T04:15:46.050113Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10381} {"level":"info","ts":"2026-09-22T04:15:46.140909Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10381,"took":"86.969981ms","hash":3722867659,"current-db-size-bytes":55128064,"current-db-size":"55 MB","current-db-size-in-use-bytes":25669632,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-22T04:15:46.141003Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3722867659,"revision":10381,"compact-revision":7981} {"level":"info","ts":"2026-09-22T04:20:46.057712Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12523} {"level":"info","ts":"2026-09-22T04:20:46.14137Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12523,"took":"80.449246ms","hash":551181873,"current-db-size-bytes":55128064,"current-db-size":"55 MB","current-db-size-in-use-bytes":28131328,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-22T04:20:46.141524Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":551181873,"revision":12523,"compact-revision":10381} {"level":"info","ts":"2026-09-22T04:21:29.376067Z","caller":"traceutil/trace.go:171","msg":"trace[2026122009] transaction","detail":"{read_only:false; response_revision:14860; number_of_response:1; }","duration":"108.642498ms","start":"2026-09-22T04:21:29.26739Z","end":"2026-09-22T04:21:29.376032Z","steps":["trace[2026122009] 'process raft request' (duration: 108.435112ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:21:51.864547Z","caller":"traceutil/trace.go:171","msg":"trace[1683988251] transaction","detail":"{read_only:false; response_revision:14975; number_of_response:1; }","duration":"146.085241ms","start":"2026-09-22T04:21:51.718439Z","end":"2026-09-22T04:21:51.864524Z","steps":["trace[1683988251] 'process raft request' (duration: 145.738192ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:21:52.479821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.783788ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-22T04:21:52.479974Z","caller":"traceutil/trace.go:171","msg":"trace[1362096225] transaction","detail":"{read_only:false; response_revision:14977; number_of_response:1; }","duration":"178.457322ms","start":"2026-09-22T04:21:52.301486Z","end":"2026-09-22T04:21:52.479944Z","steps":["trace[1362096225] 'process raft request' (duration: 73.448817ms)","trace[1362096225] 'compare' (duration: 104.516711ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:22:10.673061Z","caller":"traceutil/trace.go:171","msg":"trace[1717836805] transaction","detail":"{read_only:false; response_revision:15087; number_of_response:1; }","duration":"253.045862ms","start":"2026-09-22T04:22:10.419982Z","end":"2026-09-22T04:22:10.673028Z","steps":["trace[1717836805] 'process raft request' (duration: 252.795385ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:22:10.804216Z","caller":"traceutil/trace.go:171","msg":"trace[1756445004] transaction","detail":"{read_only:false; response_revision:15088; number_of_response:1; }","duration":"125.911838ms","start":"2026-09-22T04:22:10.678268Z","end":"2026-09-22T04:22:10.804179Z","steps":["trace[1756445004] 'process raft request' (duration: 59.83896ms)","trace[1756445004] 'compare' (duration: 65.739109ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:22:10.804226Z","caller":"traceutil/trace.go:171","msg":"trace[2140454301] transaction","detail":"{read_only:false; response_revision:15089; number_of_response:1; }","duration":"125.360222ms","start":"2026-09-22T04:22:10.678827Z","end":"2026-09-22T04:22:10.804187Z","steps":["trace[2140454301] 'process raft request' (duration: 125.239469ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:25:46.064773Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14555} {"level":"info","ts":"2026-09-22T04:25:46.152032Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14555,"took":"83.903148ms","hash":797320375,"current-db-size-bytes":55128064,"current-db-size":"55 MB","current-db-size-in-use-bytes":30875648,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-22T04:25:46.152157Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":797320375,"revision":14555,"compact-revision":12523} {"level":"warn","ts":"2026-09-22T04:30:21.781557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.274632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-22T04:30:21.781653Z","caller":"traceutil/trace.go:171","msg":"trace[1360661184] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18966; }","duration":"134.413235ms","start":"2026-09-22T04:30:21.647216Z","end":"2026-09-22T04:30:21.781629Z","steps":["trace[1360661184] 'range keys from in-memory index tree' (duration: 134.097776ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:30:22.126232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.506864ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T04:30:22.126464Z","caller":"traceutil/trace.go:171","msg":"trace[1226609798] linearizableReadLoop","detail":"{readStateIndex:19628; appliedIndex:19626; }","duration":"310.961479ms","start":"2026-09-22T04:30:21.815484Z","end":"2026-09-22T04:30:22.126446Z","steps":["trace[1226609798] 'read index received' (duration: 96.195697ms)","trace[1226609798] 'applied index is now lower than readState.Index' (duration: 214.764652ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:30:22.126561Z","caller":"traceutil/trace.go:171","msg":"trace[1525330075] transaction","detail":"{read_only:false; response_revision:18970; number_of_response:1; }","duration":"316.829516ms","start":"2026-09-22T04:30:21.809721Z","end":"2026-09-22T04:30:22.126551Z","steps":["trace[1525330075] 'process raft request' (duration: 316.614989ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:30:22.126629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:30:21.809699Z","time spent":"316.895218ms","remote":"127.0.0.1:43068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20874,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T04:30:22.126723Z","caller":"traceutil/trace.go:171","msg":"trace[1161730258] transaction","detail":"{read_only:false; response_revision:18969; number_of_response:1; }","duration":"336.136205ms","start":"2026-09-22T04:30:21.790514Z","end":"2026-09-22T04:30:22.12665Z","steps":["trace[1161730258] 'process raft request' (duration: 121.097356ms)","trace[1161730258] 'compare' (duration: 214.36502ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:30:22.127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:30:21.790377Z","time spent":"336.489606ms","remote":"127.0.0.1:43160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T04:30:22.127029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.341002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-09-22T04:30:22.127068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.205814ms","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-09-22T04:30:22.127231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.959422ms","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-09-22T04:30:22.127277Z","caller":"traceutil/trace.go:171","msg":"trace[2056955751] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18970; }","duration":"199.052504ms","start":"2026-09-22T04:30:21.928209Z","end":"2026-09-22T04:30:22.127262Z","steps":["trace[2056955751] 'agreement among raft nodes before linearized reading' (duration: 198.92844ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:30:22.127671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.282312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T04:30:22.127879Z","caller":"traceutil/trace.go:171","msg":"trace[110389730] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:18970; }","duration":"101.592161ms","start":"2026-09-22T04:30:22.026248Z","end":"2026-09-22T04:30:22.127841Z","steps":["trace[110389730] 'agreement among raft nodes before linearized reading' (duration: 101.152989ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:30:22.127098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.079899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-22T04:30:22.127178Z","caller":"traceutil/trace.go:171","msg":"trace[1038265920] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18970; }","duration":"280.385239ms","start":"2026-09-22T04:30:21.846771Z","end":"2026-09-22T04:30:22.127157Z","steps":["trace[1038265920] 'agreement among raft nodes before linearized reading' (duration: 279.981887ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:30:22.127969Z","caller":"traceutil/trace.go:171","msg":"trace[1985632868] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:18970; }","duration":"190.968514ms","start":"2026-09-22T04:30:21.936978Z","end":"2026-09-22T04:30:22.127946Z","steps":["trace[1985632868] 'agreement among raft nodes before linearized reading' (duration: 190.075739ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:30:22.127118Z","caller":"traceutil/trace.go:171","msg":"trace[1055490123] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:18970; }","duration":"180.463975ms","start":"2026-09-22T04:30:21.946631Z","end":"2026-09-22T04:30:22.127095Z","steps":["trace[1055490123] 'agreement among raft nodes before linearized reading' (duration: 180.136076ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:30:22.128333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.27765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:94 size:946809"} {"level":"info","ts":"2026-09-22T04:30:22.128405Z","caller":"traceutil/trace.go:171","msg":"trace[542614074] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:94; response_revision:18970; }","duration":"329.387083ms","start":"2026-09-22T04:30:21.799002Z","end":"2026-09-22T04:30:22.128389Z","steps":["trace[542614074] 'agreement among raft nodes before linearized reading' (duration: 328.146678ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:30:22.128464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:30:21.798987Z","time spent":"329.458884ms","remote":"127.0.0.1:43068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":94,"response size":946833,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-09-22T04:30:22.128543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.27855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T04:30:22.128643Z","caller":"traceutil/trace.go:171","msg":"trace[1842742002] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18970; }","duration":"232.419703ms","start":"2026-09-22T04:30:21.896201Z","end":"2026-09-22T04:30:22.128621Z","steps":["trace[1842742002] 'agreement among raft nodes before linearized reading' (duration: 231.179558ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:30:22.129003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.368543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-09-22T04:30:22.129182Z","caller":"traceutil/trace.go:171","msg":"trace[1992575704] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:18970; }","duration":"200.480914ms","start":"2026-09-22T04:30:21.928668Z","end":"2026-09-22T04:30:22.129149Z","steps":["trace[1992575704] 'agreement among raft nodes before linearized reading' (duration: 198.153798ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:30:22.308845Z","caller":"traceutil/trace.go:171","msg":"trace[1297500585] transaction","detail":"{read_only:false; response_revision:18972; number_of_response:1; }","duration":"165.021126ms","start":"2026-09-22T04:30:22.14376Z","end":"2026-09-22T04:30:22.308781Z","steps":["trace[1297500585] 'process raft request' (duration: 154.016843ms)","trace[1297500585] 'compare' (duration: 10.453257ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:30:22.309164Z","caller":"traceutil/trace.go:171","msg":"trace[1427084687] transaction","detail":"{read_only:false; response_revision:18973; number_of_response:1; }","duration":"165.368456ms","start":"2026-09-22T04:30:22.143767Z","end":"2026-09-22T04:30:22.309136Z","steps":["trace[1427084687] 'process raft request' (duration: 164.933914ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:30:46.071542Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16884} {"level":"info","ts":"2026-09-22T04:30:46.182438Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16884,"took":"106.249903ms","hash":3091591748,"current-db-size-bytes":55128064,"current-db-size":"55 MB","current-db-size-in-use-bytes":32141312,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-22T04:30:46.182514Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3091591748,"revision":16884,"compact-revision":14555} {"level":"info","ts":"2026-09-22T04:30:58.073011Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"8608f4af3693a53b","local-member-applied-index":20004,"local-member-snapshot-index":10003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-22T04:30:58.080755Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20004} {"level":"info","ts":"2026-09-22T04:30:58.080959Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15004} {"level":"info","ts":"2026-09-22T04:35:46.080926Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19257} {"level":"info","ts":"2026-09-22T04:35:46.188112Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19257,"took":"103.093589ms","hash":3690688017,"current-db-size-bytes":55128064,"current-db-size":"55 MB","current-db-size-in-use-bytes":33570816,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-09-22T04:35:46.188201Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3690688017,"revision":19257,"compact-revision":16884} {"level":"info","ts":"2026-09-22T04:36:01.029389Z","caller":"traceutil/trace.go:171","msg":"trace[302267414] transaction","detail":"{read_only:false; response_revision:21613; number_of_response:1; }","duration":"118.738234ms","start":"2026-09-22T04:36:00.910606Z","end":"2026-09-22T04:36:01.029345Z","steps":["trace[302267414] 'process raft request' (duration: 118.452635ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:36:16.659971Z","caller":"traceutil/trace.go:171","msg":"trace[1519894556] linearizableReadLoop","detail":"{readStateIndex:22443; appliedIndex:22442; }","duration":"181.639361ms","start":"2026-09-22T04:36:16.478299Z","end":"2026-09-22T04:36:16.659939Z","steps":["trace[1519894556] 'read index received' (duration: 181.429805ms)","trace[1519894556] 'applied index is now lower than readState.Index' (duration: 207.566µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:36:16.660079Z","caller":"traceutil/trace.go:171","msg":"trace[732287428] transaction","detail":"{read_only:false; response_revision:21692; number_of_response:1; }","duration":"184.667277ms","start":"2026-09-22T04:36:16.475386Z","end":"2026-09-22T04:36:16.660053Z","steps":["trace[732287428] 'process raft request' (duration: 184.364859ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:36:16.66025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.94784ms","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-09-22T04:36:16.660297Z","caller":"traceutil/trace.go:171","msg":"trace[2061308357] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21692; }","duration":"182.058523ms","start":"2026-09-22T04:36:16.478225Z","end":"2026-09-22T04:36:16.660283Z","steps":["trace[2061308357] 'agreement among raft nodes before linearized reading' (duration: 181.827316ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:36:16.920597Z","caller":"traceutil/trace.go:171","msg":"trace[765658959] linearizableReadLoop","detail":"{readStateIndex:22444; appliedIndex:22443; }","duration":"109.408939ms","start":"2026-09-22T04:36:16.811157Z","end":"2026-09-22T04:36:16.920566Z","steps":["trace[765658959] 'read index received' (duration: 109.09078ms)","trace[765658959] 'applied index is now lower than readState.Index' (duration: 316.179µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:36:16.920706Z","caller":"traceutil/trace.go:171","msg":"trace[134108164] transaction","detail":"{read_only:false; response_revision:21693; number_of_response:1; }","duration":"254.265615ms","start":"2026-09-22T04:36:16.666381Z","end":"2026-09-22T04:36:16.920647Z","steps":["trace[134108164] 'process raft request' (duration: 253.962276ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:36:16.92084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.665646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-09-22T04:36:16.920955Z","caller":"traceutil/trace.go:171","msg":"trace[2083861966] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21693; }","duration":"109.826771ms","start":"2026-09-22T04:36:16.811102Z","end":"2026-09-22T04:36:16.920929Z","steps":["trace[2083861966] 'agreement among raft nodes before linearized reading' (duration: 109.628275ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:36:17.35034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.372917ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T04:36:17.350448Z","caller":"traceutil/trace.go:171","msg":"trace[1480619139] transaction","detail":"{read_only:false; response_revision:21696; number_of_response:1; }","duration":"250.033545ms","start":"2026-09-22T04:36:17.100394Z","end":"2026-09-22T04:36:17.350428Z","steps":["trace[1480619139] 'process raft request' (duration: 94.974279ms)","trace[1480619139] 'compare' (duration: 153.653846ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:36:18.036173Z","caller":"traceutil/trace.go:171","msg":"trace[1395872209] transaction","detail":"{read_only:false; response_revision:21698; number_of_response:1; }","duration":"131.399363ms","start":"2026-09-22T04:36:17.904744Z","end":"2026-09-22T04:36:18.036143Z","steps":["trace[1395872209] 'process raft request' (duration: 131.118526ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:40:03.685699Z","caller":"traceutil/trace.go:171","msg":"trace[1395637897] linearizableReadLoop","detail":"{readStateIndex:24293; appliedIndex:24292; }","duration":"147.563998ms","start":"2026-09-22T04:40:03.538103Z","end":"2026-09-22T04:40:03.685667Z","steps":["trace[1395637897] 'read index received' (duration: 147.262569ms)","trace[1395637897] 'applied index is now lower than readState.Index' (duration: 300.179µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:40:03.685777Z","caller":"traceutil/trace.go:171","msg":"trace[1843019191] transaction","detail":"{read_only:false; response_revision:23479; number_of_response:1; }","duration":"232.64801ms","start":"2026-09-22T04:40:03.453083Z","end":"2026-09-22T04:40:03.685731Z","steps":["trace[1843019191] 'process raft request' (duration: 232.340692ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:03.685998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.874706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:521"} {"level":"info","ts":"2026-09-22T04:40:03.68607Z","caller":"traceutil/trace.go:171","msg":"trace[1889985154] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:23479; }","duration":"147.991179ms","start":"2026-09-22T04:40:03.538065Z","end":"2026-09-22T04:40:03.686056Z","steps":["trace[1889985154] 'agreement among raft nodes before linearized reading' (duration: 147.752243ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:40:06.207985Z","caller":"traceutil/trace.go:171","msg":"trace[784712406] transaction","detail":"{read_only:false; response_revision:23496; number_of_response:1; }","duration":"159.266458ms","start":"2026-09-22T04:40:06.048685Z","end":"2026-09-22T04:40:06.207951Z","steps":["trace[784712406] 'process raft request' (duration: 158.918349ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:10.884824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.554313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T04:40:10.884983Z","caller":"traceutil/trace.go:171","msg":"trace[452467418] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23635; }","duration":"134.83367ms","start":"2026-09-22T04:40:10.75012Z","end":"2026-09-22T04:40:10.884954Z","steps":["trace[452467418] 'range keys from in-memory index tree' (duration: 134.247064ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:40:26.508095Z","caller":"traceutil/trace.go:171","msg":"trace[1018567055] transaction","detail":"{read_only:false; response_revision:24231; number_of_response:1; }","duration":"140.048214ms","start":"2026-09-22T04:40:26.368021Z","end":"2026-09-22T04:40:26.508069Z","steps":["trace[1018567055] 'process raft request' (duration: 139.849928ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:40:26.574816Z","caller":"traceutil/trace.go:171","msg":"trace[1983917300] linearizableReadLoop","detail":"{readStateIndex:25061; appliedIndex:25059; }","duration":"105.543622ms","start":"2026-09-22T04:40:26.469235Z","end":"2026-09-22T04:40:26.574779Z","steps":["trace[1983917300] 'read index received' (duration: 38.637175ms)","trace[1983917300] 'applied index is now lower than readState.Index' (duration: 66.904627ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:40:26.574859Z","caller":"traceutil/trace.go:171","msg":"trace[13165962] transaction","detail":"{read_only:false; response_revision:24232; number_of_response:1; }","duration":"121.60246ms","start":"2026-09-22T04:40:26.453216Z","end":"2026-09-22T04:40:26.574818Z","steps":["trace[13165962] 'process raft request' (duration: 120.342805ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:26.575141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.8742ms","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-09-22T04:40:26.575218Z","caller":"traceutil/trace.go:171","msg":"trace[1365123505] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24232; }","duration":"105.981093ms","start":"2026-09-22T04:40:26.469216Z","end":"2026-09-22T04:40:26.575197Z","steps":["trace[1365123505] 'agreement among raft nodes before linearized reading' (duration: 105.667864ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:26.57533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.812736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T04:40:26.5754Z","caller":"traceutil/trace.go:171","msg":"trace[127148552] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:24232; }","duration":"102.960301ms","start":"2026-09-22T04:40:26.472419Z","end":"2026-09-22T04:40:26.575379Z","steps":["trace[127148552] 'agreement among raft nodes before linearized reading' (duration: 102.795456ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:40:46.089711Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21535} {"level":"info","ts":"2026-09-22T04:40:46.214916Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21535,"took":"120.569311ms","hash":464842892,"current-db-size-bytes":55971840,"current-db-size":"56 MB","current-db-size-in-use-bytes":42233856,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-09-22T04:40:46.214968Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":464842892,"revision":21535,"compact-revision":19257} {"level":"info","ts":"2026-09-22T04:43:41.738071Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006954.wal"} {"level":"info","ts":"2026-09-22T04:44:03.317593Z","caller":"traceutil/trace.go:171","msg":"trace[1562097262] transaction","detail":"{read_only:false; response_revision:26367; number_of_response:1; }","duration":"101.306266ms","start":"2026-09-22T04:44:03.216229Z","end":"2026-09-22T04:44:03.317535Z","steps":["trace[1562097262] 'process raft request' (duration: 101.063929ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:44:03.649775Z","caller":"traceutil/trace.go:171","msg":"trace[1150868665] transaction","detail":"{read_only:false; response_revision:26369; number_of_response:1; }","duration":"195.678283ms","start":"2026-09-22T04:44:03.45406Z","end":"2026-09-22T04:44:03.649738Z","steps":["trace[1150868665] 'process raft request' (duration: 171.169014ms)","trace[1150868665] 'compare' (duration: 24.357354ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:44:04.117721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:44:03.804338Z","time spent":"313.369386ms","remote":"127.0.0.1:34716","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-09-22T04:44:04.118395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.023443ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T04:44:04.118841Z","caller":"traceutil/trace.go:171","msg":"trace[686266439] transaction","detail":"{read_only:false; response_revision:26373; number_of_response:1; }","duration":"408.281897ms","start":"2026-09-22T04:44:03.710533Z","end":"2026-09-22T04:44:04.118815Z","steps":["trace[686266439] 'process raft request' (duration: 408.234396ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:44:04.119123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:44:03.710516Z","time spent":"408.556374ms","remote":"127.0.0.1:43160","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-09-22T04:44:04.119337Z","caller":"traceutil/trace.go:171","msg":"trace[1037102533] linearizableReadLoop","detail":"{readStateIndex:27257; appliedIndex:27255; }","duration":"456.160684ms","start":"2026-09-22T04:44:03.663165Z","end":"2026-09-22T04:44:04.119325Z","steps":["trace[1037102533] 'read index received' (duration: 104.112823ms)","trace[1037102533] 'applied index is now lower than readState.Index' (duration: 352.046101ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:44:04.119357Z","caller":"traceutil/trace.go:171","msg":"trace[1862815819] transaction","detail":"{read_only:false; response_revision:26371; number_of_response:1; }","duration":"509.50929ms","start":"2026-09-22T04:44:03.609817Z","end":"2026-09-22T04:44:04.119327Z","steps":["trace[1862815819] 'process raft request' (duration: 508.736129ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:44:04.119361Z","caller":"traceutil/trace.go:171","msg":"trace[1122002008] transaction","detail":"{read_only:false; response_revision:26372; number_of_response:1; }","duration":"412.520482ms","start":"2026-09-22T04:44:03.706812Z","end":"2026-09-22T04:44:04.119333Z","steps":["trace[1122002008] 'process raft request' (duration: 411.903535ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:44:04.119465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:44:03.706776Z","time spent":"412.632895ms","remote":"127.0.0.1:43160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T04:44:04.119448Z","caller":"traceutil/trace.go:171","msg":"trace[1005394433] transaction","detail":"{read_only:false; response_revision:26370; number_of_response:1; }","duration":"540.349132ms","start":"2026-09-22T04:44:03.57903Z","end":"2026-09-22T04:44:04.119379Z","steps":["trace[1005394433] 'process raft request' (duration: 188.165997ms)","trace[1005394433] 'compare' (duration: 350.419607ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:44:04.119528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:44:03.609776Z","time spent":"509.622833ms","remote":"127.0.0.1:43160","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-09-22T04:44:04.119631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.440681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:19028"} {"level":"warn","ts":"2026-09-22T04:44:04.119716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:44:03.578992Z","time spent":"540.584739ms","remote":"127.0.0.1:43160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T04:44:04.119726Z","caller":"traceutil/trace.go:171","msg":"trace[263765790] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:26373; }","duration":"456.554024ms","start":"2026-09-22T04:44:03.663153Z","end":"2026-09-22T04:44:04.119707Z","steps":["trace[263765790] 'agreement among raft nodes before linearized reading' (duration: 456.237866ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:44:04.119861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:44:03.663069Z","time spent":"456.783491ms","remote":"127.0.0.1:43060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":1,"response size":19052,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" "} {"level":"info","ts":"2026-09-22T04:44:04.521954Z","caller":"traceutil/trace.go:171","msg":"trace[668679920] linearizableReadLoop","detail":"{readStateIndex:27261; appliedIndex:27259; }","duration":"402.55783ms","start":"2026-09-22T04:44:04.119364Z","end":"2026-09-22T04:44:04.521922Z","steps":["trace[668679920] 'read index received' (duration: 11.251827ms)","trace[668679920] 'applied index is now lower than readState.Index' (duration: 391.304473ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:44:04.522153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.175471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T04:44:04.522122Z","caller":"traceutil/trace.go:171","msg":"trace[1400962128] transaction","detail":"{read_only:false; response_revision:26375; number_of_response:1; }","duration":"447.546899ms","start":"2026-09-22T04:44:04.074503Z","end":"2026-09-22T04:44:04.52205Z","steps":["trace[1400962128] 'process raft request' (duration: 447.21031ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:44:04.522238Z","caller":"traceutil/trace.go:171","msg":"trace[1081552154] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:26375; }","duration":"650.371357ms","start":"2026-09-22T04:44:03.871854Z","end":"2026-09-22T04:44:04.522225Z","steps":["trace[1081552154] 'agreement among raft nodes before linearized reading' (duration: 650.188492ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:44:04.522235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.85417ms","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-09-22T04:44:04.522278Z","caller":"traceutil/trace.go:171","msg":"trace[590412620] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26375; }","duration":"650.959733ms","start":"2026-09-22T04:44:03.871305Z","end":"2026-09-22T04:44:04.522265Z","steps":["trace[590412620] 'agreement among raft nodes before linearized reading' (duration: 650.758937ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:44:04.522329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:44:03.871286Z","time spent":"651.033254ms","remote":"127.0.0.1:43160","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-09-22T04:44:04.522271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:44:03.871823Z","time spent":"650.438728ms","remote":"127.0.0.1:59438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":30,"request content":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true "} {"level":"warn","ts":"2026-09-22T04:44:04.522405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:44:04.074486Z","time spent":"447.778355ms","remote":"127.0.0.1:43160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":605,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T04:44:04.522422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"772.8515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T04:44:04.522544Z","caller":"traceutil/trace.go:171","msg":"trace[907087675] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26375; }","duration":"773.049866ms","start":"2026-09-22T04:44:03.74946Z","end":"2026-09-22T04:44:04.52251Z","steps":["trace[907087675] 'agreement among raft nodes before linearized reading' (duration: 772.792658ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:44:04.522606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:44:03.749422Z","time spent":"773.174119ms","remote":"127.0.0.1:42866","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-09-22T04:44:04.523047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.774105ms","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-09-22T04:44:04.52309Z","caller":"traceutil/trace.go:171","msg":"trace[1019695269] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26375; }","duration":"599.862287ms","start":"2026-09-22T04:44:03.923216Z","end":"2026-09-22T04:44:04.523079Z","steps":["trace[1019695269] 'agreement among raft nodes before linearized reading' (duration: 598.744797ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:44:04.523111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:44:03.923198Z","time spent":"599.908829ms","remote":"127.0.0.1:43160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-22T04:44:04.832557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.451772ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T04:44:04.832715Z","caller":"traceutil/trace.go:171","msg":"trace[394897238] linearizableReadLoop","detail":"{readStateIndex:27262; appliedIndex:27261; }","duration":"310.641871ms","start":"2026-09-22T04:44:04.522032Z","end":"2026-09-22T04:44:04.832674Z","steps":["trace[394897238] 'read index received' (duration: 194.988704ms)","trace[394897238] 'applied index is now lower than readState.Index' (duration: 115.651757ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:44:04.832807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.061772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedrainrules/\" range_end:\"/registry/cluster.x-k8s.io/machinedrainrules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T04:44:04.832887Z","caller":"traceutil/trace.go:171","msg":"trace[1401903564] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedrainrules/; range_end:/registry/cluster.x-k8s.io/machinedrainrules0; response_count:0; response_revision:26376; }","duration":"389.224657ms","start":"2026-09-22T04:44:04.443639Z","end":"2026-09-22T04:44:04.832863Z","steps":["trace[1401903564] 'agreement among raft nodes before linearized reading' (duration: 389.081393ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:44:04.832906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.451132ms","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-09-22T04:44:04.832842Z","caller":"traceutil/trace.go:171","msg":"trace[612137461] transaction","detail":"{read_only:false; response_revision:26376; number_of_response:1; }","duration":"458.144328ms","start":"2026-09-22T04:44:04.374645Z","end":"2026-09-22T04:44:04.832789Z","steps":["trace[612137461] 'process raft request' (duration: 342.348216ms)","trace[612137461] 'compare' (duration: 115.233246ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:44:04.832936Z","caller":"traceutil/trace.go:171","msg":"trace[1013710559] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26376; }","duration":"656.534014ms","start":"2026-09-22T04:44:04.176391Z","end":"2026-09-22T04:44:04.832925Z","steps":["trace[1013710559] 'agreement among raft nodes before linearized reading' (duration: 656.38382ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:44:04.832948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:44:04.443607Z","time spent":"389.323059ms","remote":"127.0.0.1:33952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":30,"request content":"key:\"/registry/cluster.x-k8s.io/machinedrainrules/\" range_end:\"/registry/cluster.x-k8s.io/machinedrainrules0\" count_only:true "} {"level":"warn","ts":"2026-09-22T04:44:04.832971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:44:04.176363Z","time spent":"656.600356ms","remote":"127.0.0.1:43160","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-09-22T04:44:04.832964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.503914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-09-22T04:44:04.832983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"681.881866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-22T04:44:04.833031Z","caller":"traceutil/trace.go:171","msg":"trace[258240003] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26376; }","duration":"656.617796ms","start":"2026-09-22T04:44:04.176389Z","end":"2026-09-22T04:44:04.833007Z","steps":["trace[258240003] 'agreement among raft nodes before linearized reading' (duration: 656.425941ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:44:04.833092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:44:04.374611Z","time spent":"458.364924ms","remote":"127.0.0.1:43160","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-09-22T04:44:04.833102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:44:04.176362Z","time spent":"656.72575ms","remote":"127.0.0.1:43160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-09-22T04:44:04.833119Z","caller":"traceutil/trace.go:171","msg":"trace[163888685] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:26376; }","duration":"682.078312ms","start":"2026-09-22T04:44:04.15099Z","end":"2026-09-22T04:44:04.833068Z","steps":["trace[163888685] 'agreement among raft nodes before linearized reading' (duration: 681.822695ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:44:04.833175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:44:04.150963Z","time spent":"682.200055ms","remote":"127.0.0.1:43404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":39,"response size":32,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"info","ts":"2026-09-22T04:44:05.014826Z","caller":"traceutil/trace.go:171","msg":"trace[697788634] transaction","detail":"{read_only:false; response_revision:26378; number_of_response:1; }","duration":"486.757539ms","start":"2026-09-22T04:44:04.528023Z","end":"2026-09-22T04:44:05.01478Z","steps":["trace[697788634] 'process raft request' (duration: 485.010661ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:44:05.014961Z","caller":"traceutil/trace.go:171","msg":"trace[991052063] transaction","detail":"{read_only:false; response_revision:26380; number_of_response:1; }","duration":"466.740213ms","start":"2026-09-22T04:44:04.548168Z","end":"2026-09-22T04:44:05.014908Z","steps":["trace[991052063] 'process raft request' (duration: 464.949034ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:44:05.014981Z","caller":"traceutil/trace.go:171","msg":"trace[1571042616] transaction","detail":"{read_only:false; response_revision:26379; number_of_response:1; }","duration":"466.764923ms","start":"2026-09-22T04:44:04.548166Z","end":"2026-09-22T04:44:05.014931Z","steps":["trace[1571042616] 'process raft request' (duration: 464.913143ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:44:05.014964Z","caller":"traceutil/trace.go:171","msg":"trace[1309683576] transaction","detail":"{read_only:false; response_revision:26377; number_of_response:1; }","duration":"487.441057ms","start":"2026-09-22T04:44:04.527463Z","end":"2026-09-22T04:44:05.014905Z","steps":["trace[1309683576] 'process raft request' (duration: 485.35131ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:44:05.015003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:44:04.528007Z","time spent":"486.901423ms","remote":"127.0.0.1:43160","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-09-22T04:44:05.015202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:44:04.548137Z","time spent":"466.940058ms","remote":"127.0.0.1:43160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T04:44:05.01522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:44:04.527443Z","time spent":"487.655844ms","remote":"127.0.0.1:43160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T04:44:05.015202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:44:04.548137Z","time spent":"466.946048ms","remote":"127.0.0.1:43160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T04:44:05.251801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.611634ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-09-22T04:44:05.252141Z","caller":"traceutil/trace.go:171","msg":"trace[806982184] linearizableReadLoop","detail":"{readStateIndex:27267; appliedIndex:27266; }","duration":"419.374239ms","start":"2026-09-22T04:44:04.832743Z","end":"2026-09-22T04:44:05.252117Z","steps":["trace[806982184] 'read index received' (duration: 182.317288ms)","trace[806982184] 'applied index is now lower than readState.Index' (duration: 237.055081ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:44:05.252423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"728.271162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T04:44:05.252544Z","caller":"traceutil/trace.go:171","msg":"trace[149821955] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26380; }","duration":"728.432367ms","start":"2026-09-22T04:44:04.524075Z","end":"2026-09-22T04:44:05.252508Z","steps":["trace[149821955] 'agreement among raft nodes before linearized reading' (duration: 728.136999ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:44:05.252608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:44:04.524061Z","time spent":"728.533139ms","remote":"127.0.0.1:42882","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-09-22T04:44:05.252657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"729.735772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/auth-system/keycloak\" ","response":"range_response_count:1 size:906"} {"level":"info","ts":"2026-09-22T04:44:05.25272Z","caller":"traceutil/trace.go:171","msg":"trace[209267652] range","detail":"{range_begin:/registry/serviceaccounts/auth-system/keycloak; range_end:; response_count:1; response_revision:26380; }","duration":"729.874786ms","start":"2026-09-22T04:44:04.522828Z","end":"2026-09-22T04:44:05.252703Z","steps":["trace[209267652] 'agreement among raft nodes before linearized reading' (duration: 729.347562ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:44:05.252755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:44:04.522801Z","time spent":"729.945889ms","remote":"127.0.0.1:43082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":930,"request content":"key:\"/registry/serviceaccounts/auth-system/keycloak\" "} {"level":"info","ts":"2026-09-22T04:44:05.253729Z","caller":"traceutil/trace.go:171","msg":"trace[485110251] transaction","detail":"{read_only:false; response_revision:26381; number_of_response:1; }","duration":"415.020251ms","start":"2026-09-22T04:44:04.838649Z","end":"2026-09-22T04:44:05.253669Z","steps":["trace[485110251] 'process raft request' (duration: 413.299024ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:44:05.254003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:44:04.83861Z","time spent":"415.217736ms","remote":"127.0.0.1:43160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T04:44:05.414304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.569833ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T04:44:05.414417Z","caller":"traceutil/trace.go:171","msg":"trace[1197274044] linearizableReadLoop","detail":"{readStateIndex:27269; appliedIndex:27268; }","duration":"159.800523ms","start":"2026-09-22T04:44:05.2546Z","end":"2026-09-22T04:44:05.414401Z","steps":["trace[1197274044] 'read index received' (duration: 102.153µs)","trace[1197274044] 'applied index is now lower than readState.Index' (duration: 159.69735ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:44:05.414538Z","caller":"traceutil/trace.go:171","msg":"trace[1689943018] transaction","detail":"{read_only:false; response_revision:26382; number_of_response:1; }","duration":"575.198153ms","start":"2026-09-22T04:44:04.839327Z","end":"2026-09-22T04:44:05.414525Z","steps":["trace[1689943018] 'process raft request' (duration: 414.313281ms)","trace[1689943018] 'compare' (duration: 160.140072ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:44:05.414595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:44:04.839299Z","time spent":"575.261335ms","remote":"127.0.0.1:43160","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-09-22T04:44:05.414763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.130782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/auth-system/keycloak-0\" ","response":"range_response_count:1 size:7960"} {"level":"info","ts":"2026-09-22T04:44:05.414865Z","caller":"traceutil/trace.go:171","msg":"trace[1990477581] range","detail":"{range_begin:/registry/pods/auth-system/keycloak-0; range_end:; response_count:1; response_revision:26382; }","duration":"160.407249ms","start":"2026-09-22T04:44:05.254434Z","end":"2026-09-22T04:44:05.414842Z","steps":["trace[1990477581] 'agreement among raft nodes before linearized reading' (duration: 160.132621ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:44:05.415569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.270372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T04:44:05.415814Z","caller":"traceutil/trace.go:171","msg":"trace[1225595193] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26382; }","duration":"154.466507ms","start":"2026-09-22T04:44:05.261196Z","end":"2026-09-22T04:44:05.415662Z","steps":["trace[1225595193] 'agreement among raft nodes before linearized reading' (duration: 154.216521ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:44:08.667443Z","caller":"traceutil/trace.go:171","msg":"trace[1972942487] transaction","detail":"{read_only:false; response_revision:26404; number_of_response:1; }","duration":"126.511904ms","start":"2026-09-22T04:44:08.540893Z","end":"2026-09-22T04:44:08.667405Z","steps":["trace[1972942487] 'process raft request' (duration: 126.297778ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:44:09.162291Z","caller":"traceutil/trace.go:171","msg":"trace[1584567696] linearizableReadLoop","detail":"{readStateIndex:27292; appliedIndex:27291; }","duration":"131.308525ms","start":"2026-09-22T04:44:09.030946Z","end":"2026-09-22T04:44:09.162254Z","steps":["trace[1584567696] 'read index received' (duration: 131.109789ms)","trace[1584567696] 'applied index is now lower than readState.Index' (duration: 197.256µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:44:09.162362Z","caller":"traceutil/trace.go:171","msg":"trace[739571283] transaction","detail":"{read_only:false; response_revision:26405; number_of_response:1; }","duration":"131.430958ms","start":"2026-09-22T04:44:09.03089Z","end":"2026-09-22T04:44:09.162321Z","steps":["trace[739571283] 'process raft request' (duration: 131.13752ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:44:09.162589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.609303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-22T04:44:09.162641Z","caller":"traceutil/trace.go:171","msg":"trace[570043355] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26405; }","duration":"131.742546ms","start":"2026-09-22T04:44:09.030883Z","end":"2026-09-22T04:44:09.162625Z","steps":["trace[570043355] 'agreement among raft nodes before linearized reading' (duration: 131.52316ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:44:09.461726Z","caller":"traceutil/trace.go:171","msg":"trace[1754952735] transaction","detail":"{read_only:false; response_revision:26406; number_of_response:1; }","duration":"430.464202ms","start":"2026-09-22T04:44:09.031224Z","end":"2026-09-22T04:44:09.461689Z","steps":["trace[1754952735] 'process raft request' (duration: 416.48611ms)","trace[1754952735] 'compare' (duration: 13.382706ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:44:09.461908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:44:09.031215Z","time spent":"430.606776ms","remote":"127.0.0.1:43160","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-09-22T04:44:09.461911Z","caller":"traceutil/trace.go:171","msg":"trace[1440000571] linearizableReadLoop","detail":"{readStateIndex:27294; appliedIndex:27292; }","duration":"299.503207ms","start":"2026-09-22T04:44:09.162378Z","end":"2026-09-22T04:44:09.461882Z","steps":["trace[1440000571] 'read index received' (duration: 285.609018ms)","trace[1440000571] 'applied index is now lower than readState.Index' (duration: 13.892439ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:44:09.46219Z","caller":"traceutil/trace.go:171","msg":"trace[1913904569] transaction","detail":"{read_only:false; response_revision:26407; number_of_response:1; }","duration":"335.88361ms","start":"2026-09-22T04:44:09.126294Z","end":"2026-09-22T04:44:09.462178Z","steps":["trace[1913904569] 'process raft request' (duration: 335.50491ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:44:09.462343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.39317ms","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-09-22T04:44:09.462394Z","caller":"traceutil/trace.go:171","msg":"trace[597773876] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26407; }","duration":"430.455712ms","start":"2026-09-22T04:44:09.031917Z","end":"2026-09-22T04:44:09.462373Z","steps":["trace[597773876] 'agreement among raft nodes before linearized reading' (duration: 430.308078ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:44:09.462413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:44:09.031904Z","time spent":"430.505763ms","remote":"127.0.0.1:43160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-22T04:44:09.462516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:44:09.126238Z","time spent":"335.979862ms","remote":"127.0.0.1:43160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T04:44:09.519627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.219662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T04:44:09.519731Z","caller":"traceutil/trace.go:171","msg":"trace[553627189] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:26408; }","duration":"272.402977ms","start":"2026-09-22T04:44:09.247308Z","end":"2026-09-22T04:44:09.519711Z","steps":["trace[553627189] 'agreement among raft nodes before linearized reading' (duration: 272.165461ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:44:09.519867Z","caller":"traceutil/trace.go:171","msg":"trace[613877713] transaction","detail":"{read_only:false; response_revision:26408; number_of_response:1; }","duration":"352.410832ms","start":"2026-09-22T04:44:09.167423Z","end":"2026-09-22T04:44:09.519834Z","steps":["trace[613877713] 'process raft request' (duration: 351.895398ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:44:09.520053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:44:09.167393Z","time spent":"352.546616ms","remote":"127.0.0.1:43160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T04:44:09.520483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.336373ms","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-09-22T04:44:09.52058Z","caller":"traceutil/trace.go:171","msg":"trace[1428380224] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26408; }","duration":"247.546668ms","start":"2026-09-22T04:44:09.27301Z","end":"2026-09-22T04:44:09.520556Z","steps":["trace[1428380224] 'agreement among raft nodes before linearized reading' (duration: 246.882ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:44:09.805748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.920751ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T04:44:09.806067Z","caller":"traceutil/trace.go:171","msg":"trace[195527797] transaction","detail":"{read_only:false; response_revision:26410; number_of_response:1; }","duration":"281.219658ms","start":"2026-09-22T04:44:09.524826Z","end":"2026-09-22T04:44:09.806046Z","steps":["trace[195527797] 'process raft request' (duration: 281.086654ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:44:09.806224Z","caller":"traceutil/trace.go:171","msg":"trace[2137726959] transaction","detail":"{read_only:false; response_revision:26409; number_of_response:1; }","duration":"340.097105ms","start":"2026-09-22T04:44:09.466078Z","end":"2026-09-22T04:44:09.806175Z","steps":["trace[2137726959] 'process raft request' (duration: 192.594898ms)","trace[2137726959] 'compare' (duration: 146.754587ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:44:09.806353Z","caller":"traceutil/trace.go:171","msg":"trace[2087606751] transaction","detail":"{read_only:false; response_revision:26411; number_of_response:1; }","duration":"281.404653ms","start":"2026-09-22T04:44:09.524931Z","end":"2026-09-22T04:44:09.806335Z","steps":["trace[2087606751] 'process raft request' (duration: 281.073964ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:44:09.806402Z","caller":"traceutil/trace.go:171","msg":"trace[1511522170] linearizableReadLoop","detail":"{readStateIndex:27299; appliedIndex:27295; }","duration":"272.828559ms","start":"2026-09-22T04:44:09.53355Z","end":"2026-09-22T04:44:09.806378Z","steps":["trace[1511522170] 'read index received' (duration: 125.100996ms)","trace[1511522170] 'applied index is now lower than readState.Index' (duration: 147.726183ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:44:09.806449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:44:09.466057Z","time spent":"340.26527ms","remote":"127.0.0.1:43160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T04:44:09.806453Z","caller":"traceutil/trace.go:171","msg":"trace[756315864] transaction","detail":"{read_only:false; response_revision:26412; number_of_response:1; }","duration":"280.56662ms","start":"2026-09-22T04:44:09.525832Z","end":"2026-09-22T04:44:09.806399Z","steps":["trace[756315864] 'process raft request' (duration: 280.418966ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:44:09.806553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.756897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-22T04:44:09.806588Z","caller":"traceutil/trace.go:171","msg":"trace[401969] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26412; }","duration":"235.801128ms","start":"2026-09-22T04:44:09.570774Z","end":"2026-09-22T04:44:09.806575Z","steps":["trace[401969] 'agreement among raft nodes before linearized reading' (duration: 235.728796ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:44:09.806576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.022034ms","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-09-22T04:44:09.806636Z","caller":"traceutil/trace.go:171","msg":"trace[1031011884] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26412; }","duration":"273.114646ms","start":"2026-09-22T04:44:09.533508Z","end":"2026-09-22T04:44:09.806623Z","steps":["trace[1031011884] 'agreement among raft nodes before linearized reading' (duration: 272.957242ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:44:09.806788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.467254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.22\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-09-22T04:44:09.806818Z","caller":"traceutil/trace.go:171","msg":"trace[1797377299] range","detail":"{range_begin:/registry/masterleases/162.253.55.22; range_end:; response_count:1; response_revision:26412; }","duration":"211.533526ms","start":"2026-09-22T04:44:09.595278Z","end":"2026-09-22T04:44:09.806811Z","steps":["trace[1797377299] 'agreement among raft nodes before linearized reading' (duration: 211.451863ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:44:10.139377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:44:09.808323Z","time spent":"331.041198ms","remote":"127.0.0.1:42904","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-09-22T04:44:10.139829Z","caller":"traceutil/trace.go:171","msg":"trace[1447072927] transaction","detail":"{read_only:false; response_revision:26413; number_of_response:1; }","duration":"326.975417ms","start":"2026-09-22T04:44:09.812829Z","end":"2026-09-22T04:44:10.139804Z","steps":["trace[1447072927] 'process raft request' (duration: 326.596376ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:44:10.139968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:44:09.812797Z","time spent":"327.076919ms","remote":"127.0.0.1:43160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T04:44:10.140336Z","caller":"traceutil/trace.go:171","msg":"trace[806204286] transaction","detail":"{read_only:false; response_revision:26414; number_of_response:1; }","duration":"326.194105ms","start":"2026-09-22T04:44:09.814109Z","end":"2026-09-22T04:44:10.140303Z","steps":["trace[806204286] 'process raft request' (duration: 325.503496ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:44:10.140522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:44:09.814081Z","time spent":"326.337839ms","remote":"127.0.0.1:43160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T04:44:10.356328Z","caller":"traceutil/trace.go:171","msg":"trace[1107213389] transaction","detail":"{read_only:false; response_revision:26416; number_of_response:1; }","duration":"210.63636ms","start":"2026-09-22T04:44:10.145627Z","end":"2026-09-22T04:44:10.356263Z","steps":["trace[1107213389] 'process raft request' (duration: 210.149227ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:44:10.356744Z","caller":"traceutil/trace.go:171","msg":"trace[1829679943] transaction","detail":"{read_only:false; response_revision:26417; number_of_response:1; }","duration":"205.875131ms","start":"2026-09-22T04:44:10.15079Z","end":"2026-09-22T04:44:10.356665Z","steps":["trace[1829679943] 'process raft request' (duration: 205.374037ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:44:10.356985Z","caller":"traceutil/trace.go:171","msg":"trace[1005181575] transaction","detail":"{read_only:false; response_revision:26418; number_of_response:1; }","duration":"188.402073ms","start":"2026-09-22T04:44:10.168571Z","end":"2026-09-22T04:44:10.356973Z","steps":["trace[1005181575] 'process raft request' (duration: 188.012293ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:44:10.357014Z","caller":"traceutil/trace.go:171","msg":"trace[615224005] linearizableReadLoop","detail":"{readStateIndex:27307; appliedIndex:27303; }","duration":"164.673755ms","start":"2026-09-22T04:44:10.192303Z","end":"2026-09-22T04:44:10.356977Z","steps":["trace[615224005] 'read index received' (duration: 163.183435ms)","trace[615224005] 'applied index is now lower than readState.Index' (duration: 1.48863ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:44:10.357321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.992815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-09-22T04:44:10.357367Z","caller":"traceutil/trace.go:171","msg":"trace[1097787733] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:26419; }","duration":"165.070097ms","start":"2026-09-22T04:44:10.192282Z","end":"2026-09-22T04:44:10.357352Z","steps":["trace[1097787733] 'agreement among raft nodes before linearized reading' (duration: 164.8285ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:44:10.357438Z","caller":"traceutil/trace.go:171","msg":"trace[1654463730] transaction","detail":"{read_only:false; response_revision:26419; number_of_response:1; }","duration":"188.25467ms","start":"2026-09-22T04:44:10.169165Z","end":"2026-09-22T04:44:10.35742Z","steps":["trace[1654463730] 'process raft request' (duration: 187.742846ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:44:11.605808Z","caller":"traceutil/trace.go:171","msg":"trace[1521247653] transaction","detail":"{read_only:false; response_revision:26422; number_of_response:1; }","duration":"132.704603ms","start":"2026-09-22T04:44:11.473074Z","end":"2026-09-22T04:44:11.605779Z","steps":["trace[1521247653] 'process raft request' (duration: 132.452056ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:44:11.76935Z","caller":"traceutil/trace.go:171","msg":"trace[1441357643] transaction","detail":"{read_only:false; response_revision:26423; number_of_response:1; }","duration":"156.959515ms","start":"2026-09-22T04:44:11.612357Z","end":"2026-09-22T04:44:11.769317Z","steps":["trace[1441357643] 'process raft request' (duration: 143.423286ms)","trace[1441357643] 'compare' (duration: 12.859521ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:44:12.0282Z","caller":"traceutil/trace.go:171","msg":"trace[1866710251] linearizableReadLoop","detail":"{readStateIndex:27312; appliedIndex:27310; }","duration":"278.765631ms","start":"2026-09-22T04:44:11.749399Z","end":"2026-09-22T04:44:12.028165Z","steps":["trace[1866710251] 'read index received' (duration: 6.458857ms)","trace[1866710251] 'applied index is now lower than readState.Index' (duration: 272.305014ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:44:12.028299Z","caller":"traceutil/trace.go:171","msg":"trace[263680808] transaction","detail":"{read_only:false; response_revision:26424; number_of_response:1; }","duration":"344.215608ms","start":"2026-09-22T04:44:11.68401Z","end":"2026-09-22T04:44:12.028226Z","steps":["trace[263680808] 'process raft request' (duration: 268.607923ms)","trace[263680808] 'compare' (duration: 75.388989ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:44:12.028433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.995047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-22T04:44:12.028504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:44:11.683985Z","time spent":"344.419233ms","remote":"127.0.0.1:43160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T04:44:12.028511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.243424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-22T04:44:12.028561Z","caller":"traceutil/trace.go:171","msg":"trace[913323897] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26424; }","duration":"279.173372ms","start":"2026-09-22T04:44:11.749359Z","end":"2026-09-22T04:44:12.028532Z","steps":["trace[913323897] 'agreement among raft nodes before linearized reading' (duration: 278.928455ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:44:12.028588Z","caller":"traceutil/trace.go:171","msg":"trace[779543342] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26424; }","duration":"216.332806ms","start":"2026-09-22T04:44:11.812231Z","end":"2026-09-22T04:44:12.028564Z","steps":["trace[779543342] 'agreement among raft nodes before linearized reading' (duration: 216.11748ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:44:12.028735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.583436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-09-22T04:44:12.028775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.718847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/images/\" range_end:\"/registry/openstack.k-orc.cloud/images0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T04:44:12.028792Z","caller":"traceutil/trace.go:171","msg":"trace[2058265250] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26424; }","duration":"215.662548ms","start":"2026-09-22T04:44:11.813115Z","end":"2026-09-22T04:44:12.028777Z","steps":["trace[2058265250] 'agreement among raft nodes before linearized reading' (duration: 215.480263ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:44:12.028814Z","caller":"traceutil/trace.go:171","msg":"trace[80980360] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/images/; range_end:/registry/openstack.k-orc.cloud/images0; response_count:0; response_revision:26424; }","duration":"127.813709ms","start":"2026-09-22T04:44:11.900987Z","end":"2026-09-22T04:44:12.0288Z","steps":["trace[80980360] 'agreement among raft nodes before linearized reading' (duration: 127.742138ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:44:12.028804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.439379ms","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-09-22T04:44:12.028872Z","caller":"traceutil/trace.go:171","msg":"trace[2126394772] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26424; }","duration":"216.554612ms","start":"2026-09-22T04:44:11.812303Z","end":"2026-09-22T04:44:12.028858Z","steps":["trace[2126394772] 'agreement among raft nodes before linearized reading' (duration: 216.313455ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:44:12.028944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.622464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-09-22T04:44:12.029069Z","caller":"traceutil/trace.go:171","msg":"trace[1391712454] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26424; }","duration":"216.767889ms","start":"2026-09-22T04:44:11.812262Z","end":"2026-09-22T04:44:12.029029Z","steps":["trace[1391712454] 'agreement among raft nodes before linearized reading' (duration: 216.43721ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:44:12.281989Z","caller":"traceutil/trace.go:171","msg":"trace[66201532] transaction","detail":"{read_only:false; response_revision:26425; number_of_response:1; }","duration":"248.325199ms","start":"2026-09-22T04:44:12.03362Z","end":"2026-09-22T04:44:12.281945Z","steps":["trace[66201532] 'process raft request' (duration: 148.936376ms)","trace[66201532] 'compare' (duration: 98.686374ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:44:12.282109Z","caller":"traceutil/trace.go:171","msg":"trace[673766142] transaction","detail":"{read_only:false; response_revision:26426; number_of_response:1; }","duration":"248.404252ms","start":"2026-09-22T04:44:12.033659Z","end":"2026-09-22T04:44:12.282063Z","steps":["trace[673766142] 'process raft request' (duration: 248.050512ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:44:12.282284Z","caller":"traceutil/trace.go:171","msg":"trace[870760113] transaction","detail":"{read_only:false; response_revision:26427; number_of_response:1; }","duration":"247.509587ms","start":"2026-09-22T04:44:12.034744Z","end":"2026-09-22T04:44:12.282253Z","steps":["trace[870760113] 'process raft request' (duration: 247.053694ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:44:12.282462Z","caller":"traceutil/trace.go:171","msg":"trace[1687785271] transaction","detail":"{read_only:false; response_revision:26428; number_of_response:1; }","duration":"244.992598ms","start":"2026-09-22T04:44:12.037455Z","end":"2026-09-22T04:44:12.282448Z","steps":["trace[1687785271] 'process raft request' (duration: 244.70861ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:44:12.282402Z","caller":"traceutil/trace.go:171","msg":"trace[2057881590] linearizableReadLoop","detail":"{readStateIndex:27316; appliedIndex:27312; }","duration":"137.812062ms","start":"2026-09-22T04:44:12.144559Z","end":"2026-09-22T04:44:12.282371Z","steps":["trace[2057881590] 'read index received' (duration: 38.051989ms)","trace[2057881590] 'applied index is now lower than readState.Index' (duration: 99.758213ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:44:12.28279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.193383ms","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-09-22T04:44:12.282891Z","caller":"traceutil/trace.go:171","msg":"trace[1861695942] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26428; }","duration":"138.388098ms","start":"2026-09-22T04:44:12.144481Z","end":"2026-09-22T04:44:12.28287Z","steps":["trace[1861695942] 'agreement among raft nodes before linearized reading' (duration: 138.010727ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:44:12.660571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.05172ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T04:44:12.660714Z","caller":"traceutil/trace.go:171","msg":"trace[969818905] transaction","detail":"{read_only:false; response_revision:26430; number_of_response:1; }","duration":"296.384021ms","start":"2026-09-22T04:44:12.364287Z","end":"2026-09-22T04:44:12.660671Z","steps":["trace[969818905] 'process raft request' (duration: 162.127716ms)","trace[969818905] 'compare' (duration: 133.854104ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:44:12.682439Z","caller":"traceutil/trace.go:171","msg":"trace[1565582940] transaction","detail":"{read_only:false; response_revision:26431; number_of_response:1; }","duration":"317.794126ms","start":"2026-09-22T04:44:12.364605Z","end":"2026-09-22T04:44:12.682399Z","steps":["trace[1565582940] 'process raft request' (duration: 317.410896ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:44:12.68265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:44:12.364562Z","time spent":"317.967831ms","remote":"127.0.0.1:43160","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-09-22T04:44:12.682406Z","caller":"traceutil/trace.go:171","msg":"trace[1310269786] transaction","detail":"{read_only:false; response_revision:26432; number_of_response:1; }","duration":"317.607441ms","start":"2026-09-22T04:44:12.364764Z","end":"2026-09-22T04:44:12.682372Z","steps":["trace[1310269786] 'process raft request' (duration: 317.446296ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:44:12.682709Z","caller":"traceutil/trace.go:171","msg":"trace[991368641] transaction","detail":"{read_only:false; response_revision:26433; number_of_response:1; }","duration":"317.036385ms","start":"2026-09-22T04:44:12.365616Z","end":"2026-09-22T04:44:12.682652Z","steps":["trace[991368641] 'process raft request' (duration: 316.637504ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:44:12.682884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:44:12.365598Z","time spent":"317.18813ms","remote":"127.0.0.1:43160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T04:44:12.682934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:44:12.364711Z","time spent":"318.071934ms","remote":"127.0.0.1:43160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T04:44:13.018356Z","caller":"traceutil/trace.go:171","msg":"trace[251419930] transaction","detail":"{read_only:false; response_revision:26434; number_of_response:1; }","duration":"246.810827ms","start":"2026-09-22T04:44:12.771494Z","end":"2026-09-22T04:44:13.018305Z","steps":["trace[251419930] 'process raft request' (duration: 212.987634ms)","trace[251419930] 'compare' (duration: 33.488884ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:44:13.3959Z","caller":"traceutil/trace.go:171","msg":"trace[1338265701] transaction","detail":"{read_only:false; response_revision:26435; number_of_response:1; }","duration":"128.894409ms","start":"2026-09-22T04:44:13.26697Z","end":"2026-09-22T04:44:13.395864Z","steps":["trace[1338265701] 'process raft request' (duration: 128.641102ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:44:13.717949Z","caller":"traceutil/trace.go:171","msg":"trace[1601698438] transaction","detail":"{read_only:false; response_revision:26436; number_of_response:1; }","duration":"104.128243ms","start":"2026-09-22T04:44:13.613784Z","end":"2026-09-22T04:44:13.717912Z","steps":["trace[1601698438] 'process raft request' (duration: 103.863096ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:44:13.956023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.238275ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T04:44:13.956152Z","caller":"traceutil/trace.go:171","msg":"trace[1836536889] linearizableReadLoop","detail":"{readStateIndex:27325; appliedIndex:27323; }","duration":"259.273408ms","start":"2026-09-22T04:44:13.696855Z","end":"2026-09-22T04:44:13.956129Z","steps":["trace[1836536889] 'read index received' (duration: 20.965472ms)","trace[1836536889] 'applied index is now lower than readState.Index' (duration: 238.306216ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:44:13.956279Z","caller":"traceutil/trace.go:171","msg":"trace[2117756611] transaction","detail":"{read_only:false; response_revision:26437; number_of_response:1; }","duration":"264.343427ms","start":"2026-09-22T04:44:13.691856Z","end":"2026-09-22T04:44:13.956199Z","steps":["trace[2117756611] 'process raft request' (duration: 151.778803ms)","trace[2117756611] 'compare' (duration: 111.757912ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:44:13.95641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.156366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-22T04:44:13.956421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.515285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-22T04:44:13.956489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.368989ms","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-09-22T04:44:13.956527Z","caller":"traceutil/trace.go:171","msg":"trace[732705524] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26437; }","duration":"207.357751ms","start":"2026-09-22T04:44:13.749144Z","end":"2026-09-22T04:44:13.956502Z","steps":["trace[732705524] 'agreement among raft nodes before linearized reading' (duration: 207.139825ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:44:13.95655Z","caller":"traceutil/trace.go:171","msg":"trace[1160970353] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:26437; }","duration":"259.687419ms","start":"2026-09-22T04:44:13.69683Z","end":"2026-09-22T04:44:13.956517Z","steps":["trace[1160970353] 'agreement among raft nodes before linearized reading' (duration: 259.407532ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:44:13.956579Z","caller":"traceutil/trace.go:171","msg":"trace[1741871651] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26437; }","duration":"182.511782ms","start":"2026-09-22T04:44:13.774039Z","end":"2026-09-22T04:44:13.95655Z","steps":["trace[1741871651] 'agreement among raft nodes before linearized reading' (duration: 182.239025ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:44:19.250545Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11906286817463042379,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-22T04:44:19.265788Z","caller":"traceutil/trace.go:171","msg":"trace[637135130] transaction","detail":"{read_only:false; response_revision:26472; number_of_response:1; }","duration":"583.965373ms","start":"2026-09-22T04:44:18.681786Z","end":"2026-09-22T04:44:19.265751Z","steps":["trace[637135130] 'process raft request' (duration: 583.648694ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:44:19.266185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:44:18.681758Z","time spent":"584.236101ms","remote":"127.0.0.1:43160","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-09-22T04:44:19.305553Z","caller":"traceutil/trace.go:171","msg":"trace[1510366717] transaction","detail":"{read_only:false; response_revision:26473; number_of_response:1; }","duration":"580.91428ms","start":"2026-09-22T04:44:18.724575Z","end":"2026-09-22T04:44:19.305489Z","steps":["trace[1510366717] 'process raft request' (duration: 580.512569ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:44:19.305885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:44:18.724553Z","time spent":"581.172116ms","remote":"127.0.0.1:43160","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-09-22T04:44:19.305884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.117003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T04:44:19.306073Z","caller":"traceutil/trace.go:171","msg":"trace[1762941851] transaction","detail":"{read_only:false; response_revision:26474; number_of_response:1; }","duration":"579.593573ms","start":"2026-09-22T04:44:18.725939Z","end":"2026-09-22T04:44:19.305533Z","steps":["trace[1762941851] 'process raft request' (duration: 579.375367ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:44:19.306257Z","caller":"traceutil/trace.go:171","msg":"trace[1741034133] linearizableReadLoop","detail":"{readStateIndex:27365; appliedIndex:27360; }","duration":"556.00904ms","start":"2026-09-22T04:44:18.749766Z","end":"2026-09-22T04:44:19.305775Z","steps":["trace[1741034133] 'read index received' (duration: 515.641998ms)","trace[1741034133] 'applied index is now lower than readState.Index' (duration: 40.365662ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:44:19.306417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:44:18.725913Z","time spent":"580.343444ms","remote":"127.0.0.1:43160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T04:44:19.306467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.290713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T04:44:19.306481Z","caller":"traceutil/trace.go:171","msg":"trace[1127870176] transaction","detail":"{read_only:false; response_revision:26475; number_of_response:1; }","duration":"579.748228ms","start":"2026-09-22T04:44:18.726215Z","end":"2026-09-22T04:44:19.305964Z","steps":["trace[1127870176] 'process raft request' (duration: 579.227814ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:44:19.306389Z","caller":"traceutil/trace.go:171","msg":"trace[400726924] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26476; }","duration":"556.342388ms","start":"2026-09-22T04:44:18.749601Z","end":"2026-09-22T04:44:19.305944Z","steps":["trace[400726924] 'agreement among raft nodes before linearized reading' (duration: 556.179094ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:44:19.306548Z","caller":"traceutil/trace.go:171","msg":"trace[199851134] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:26476; }","duration":"150.420037ms","start":"2026-09-22T04:44:19.156089Z","end":"2026-09-22T04:44:19.306509Z","steps":["trace[199851134] 'agreement among raft nodes before linearized reading' (duration: 150.302514ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:44:19.306592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:44:18.749561Z","time spent":"557.006317ms","remote":"127.0.0.1:42866","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-09-22T04:44:19.306704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:44:18.7262Z","time spent":"580.341204ms","remote":"127.0.0.1:43160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T04:44:19.306957Z","caller":"traceutil/trace.go:171","msg":"trace[287153107] transaction","detail":"{read_only:false; response_revision:26476; number_of_response:1; }","duration":"266.147596ms","start":"2026-09-22T04:44:19.039826Z","end":"2026-09-22T04:44:19.305974Z","steps":["trace[287153107] 'process raft request' (duration: 265.801837ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:44:19.891541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.0625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-22T04:44:19.892174Z","caller":"traceutil/trace.go:171","msg":"trace[1888433888] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:26476; }","duration":"523.885373ms","start":"2026-09-22T04:44:19.368248Z","end":"2026-09-22T04:44:19.892133Z","steps":["trace[1888433888] 'count revisions from in-memory index tree' (duration: 522.903386ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:44:19.891719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.705501ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T04:44:19.892781Z","caller":"traceutil/trace.go:171","msg":"trace[1341150748] transaction","detail":"{read_only:false; response_revision:26477; number_of_response:1; }","duration":"471.582975ms","start":"2026-09-22T04:44:19.421159Z","end":"2026-09-22T04:44:19.892742Z","steps":["trace[1341150748] 'process raft request' (duration: 214.603849ms)","trace[1341150748] 'compare' (duration: 255.140205ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:44:19.893006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:44:19.421127Z","time spent":"471.76353ms","remote":"127.0.0.1:43160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T04:44:19.892952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:44:19.368218Z","time spent":"524.072097ms","remote":"127.0.0.1:43048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":90,"response size":32,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"info","ts":"2026-09-22T04:44:20.187085Z","caller":"traceutil/trace.go:171","msg":"trace[1617689165] transaction","detail":"{read_only:false; response_revision:26479; number_of_response:1; }","duration":"448.620138ms","start":"2026-09-22T04:44:19.73843Z","end":"2026-09-22T04:44:20.18705Z","steps":["trace[1617689165] 'process raft request' (duration: 448.550426ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:44:20.187393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.961934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.22\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-09-22T04:44:20.187462Z","caller":"traceutil/trace.go:171","msg":"trace[1883634961] range","detail":"{range_begin:/registry/masterleases/162.253.55.22; range_end:; response_count:1; response_revision:26479; }","duration":"591.101128ms","start":"2026-09-22T04:44:19.596347Z","end":"2026-09-22T04:44:20.187448Z","steps":["trace[1883634961] 'agreement among raft nodes before linearized reading' (duration: 590.83349ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:44:20.187559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:44:19.596322Z","time spent":"591.16846ms","remote":"127.0.0.1:42904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":161,"request content":"key:\"/registry/masterleases/162.253.55.22\" "} {"level":"warn","ts":"2026-09-22T04:44:20.187774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:44:19.73839Z","time spent":"449.047389ms","remote":"127.0.0.1:43160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T04:44:20.187112Z","caller":"traceutil/trace.go:171","msg":"trace[395542990] linearizableReadLoop","detail":"{readStateIndex:27367; appliedIndex:27365; }","duration":"590.651256ms","start":"2026-09-22T04:44:19.596414Z","end":"2026-09-22T04:44:20.187065Z","steps":["trace[395542990] 'read index received' (duration: 39.470268ms)","trace[395542990] 'applied index is now lower than readState.Index' (duration: 551.176738ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:44:20.187098Z","caller":"traceutil/trace.go:171","msg":"trace[1883755094] transaction","detail":"{read_only:false; response_revision:26478; number_of_response:1; }","duration":"711.237898ms","start":"2026-09-22T04:44:19.475817Z","end":"2026-09-22T04:44:20.187055Z","steps":["trace[1883755094] 'process raft request' (duration: 686.243975ms)","trace[1883755094] 'compare' (duration: 24.276093ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:44:20.188096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:44:19.475789Z","time spent":"712.222624ms","remote":"127.0.0.1:43160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T04:44:20.188173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.166295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-22T04:44:20.188224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.503364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T04:44:20.188271Z","caller":"traceutil/trace.go:171","msg":"trace[36820766] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:26479; }","duration":"337.342349ms","start":"2026-09-22T04:44:19.850903Z","end":"2026-09-22T04:44:20.188245Z","steps":["trace[36820766] 'agreement among raft nodes before linearized reading' (duration: 337.160164ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:44:20.188328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:44:19.85088Z","time spent":"337.433242ms","remote":"127.0.0.1:38294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":30,"request content":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true "} {"level":"info","ts":"2026-09-22T04:44:20.188335Z","caller":"traceutil/trace.go:171","msg":"trace[1933309696] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:26479; }","duration":"163.698489ms","start":"2026-09-22T04:44:20.024602Z","end":"2026-09-22T04:44:20.188301Z","steps":["trace[1933309696] 'agreement among raft nodes before linearized reading' (duration: 163.471723ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:44:20.188218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.157242ms","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-09-22T04:44:20.188466Z","caller":"traceutil/trace.go:171","msg":"trace[1742817955] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26479; }","duration":"197.43296ms","start":"2026-09-22T04:44:19.990986Z","end":"2026-09-22T04:44:20.188419Z","steps":["trace[1742817955] 'agreement among raft nodes before linearized reading' (duration: 197.089411ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:44:20.38076Z","caller":"traceutil/trace.go:171","msg":"trace[249721830] transaction","detail":"{read_only:false; response_revision:26480; number_of_response:1; }","duration":"186.998395ms","start":"2026-09-22T04:44:20.193721Z","end":"2026-09-22T04:44:20.38072Z","steps":["trace[249721830] 'process raft request' (duration: 182.77376ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:44:20.381335Z","caller":"traceutil/trace.go:171","msg":"trace[346339465] transaction","detail":"{read_only:false; response_revision:26481; number_of_response:1; }","duration":"170.283558ms","start":"2026-09-22T04:44:20.21104Z","end":"2026-09-22T04:44:20.381323Z","steps":["trace[346339465] 'process raft request' (duration: 169.2386ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:44:27.455408Z","caller":"traceutil/trace.go:171","msg":"trace[327405115] transaction","detail":"{read_only:false; response_revision:26589; number_of_response:1; }","duration":"160.747478ms","start":"2026-09-22T04:44:27.294633Z","end":"2026-09-22T04:44:27.45538Z","steps":["trace[327405115] 'process raft request' (duration: 160.564893ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:44:27.767432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.435085ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T04:44:27.767676Z","caller":"traceutil/trace.go:171","msg":"trace[1973423103] transaction","detail":"{read_only:false; response_revision:26591; number_of_response:1; }","duration":"416.423739ms","start":"2026-09-22T04:44:27.351218Z","end":"2026-09-22T04:44:27.767642Z","steps":["trace[1973423103] 'process raft request' (duration: 416.332517ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:44:27.767738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:44:27.351183Z","time spent":"416.527012ms","remote":"127.0.0.1:43160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T04:44:27.767747Z","caller":"traceutil/trace.go:171","msg":"trace[1591273624] transaction","detail":"{read_only:false; response_revision:26590; number_of_response:1; }","duration":"421.351674ms","start":"2026-09-22T04:44:27.346347Z","end":"2026-09-22T04:44:27.767699Z","steps":["trace[1591273624] 'process raft request' (duration: 236.556749ms)","trace[1591273624] 'compare' (duration: 183.593832ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:44:27.767948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:44:27.346278Z","time spent":"421.561199ms","remote":"127.0.0.1:43160","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-09-22T04:44:27.768113Z","caller":"traceutil/trace.go:171","msg":"trace[1696266388] transaction","detail":"{read_only:false; response_revision:26592; number_of_response:1; }","duration":"414.26202ms","start":"2026-09-22T04:44:27.35382Z","end":"2026-09-22T04:44:27.768082Z","steps":["trace[1696266388] 'process raft request' (duration: 413.774817ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:44:27.768616Z","caller":"traceutil/trace.go:171","msg":"trace[639357971] linearizableReadLoop","detail":"{readStateIndex:27484; appliedIndex:27479; }","duration":"336.392124ms","start":"2026-09-22T04:44:27.432209Z","end":"2026-09-22T04:44:27.768601Z","steps":["trace[639357971] 'read index received' (duration: 23.066529ms)","trace[639357971] 'applied index is now lower than readState.Index' (duration: 313.323835ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:44:27.768744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.539828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-22T04:44:27.768699Z","caller":"traceutil/trace.go:171","msg":"trace[1950446419] transaction","detail":"{read_only:false; response_revision:26593; number_of_response:1; }","duration":"412.242425ms","start":"2026-09-22T04:44:27.356378Z","end":"2026-09-22T04:44:27.768621Z","steps":["trace[1950446419] 'process raft request' (duration: 411.553846ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:44:27.768771Z","caller":"traceutil/trace.go:171","msg":"trace[1001766977] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:26593; }","duration":"336.62657ms","start":"2026-09-22T04:44:27.432136Z","end":"2026-09-22T04:44:27.768763Z","steps":["trace[1001766977] 'agreement among raft nodes before linearized reading' (duration: 336.554178ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:44:27.768798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:44:27.432105Z","time spent":"336.686402ms","remote":"127.0.0.1:43380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":20,"response size":32,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-09-22T04:44:27.768814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.494274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-22T04:44:27.768853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.28268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"info","ts":"2026-09-22T04:44:27.768876Z","caller":"traceutil/trace.go:171","msg":"trace[913252934] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:26593; }","duration":"274.561276ms","start":"2026-09-22T04:44:27.494294Z","end":"2026-09-22T04:44:27.768856Z","steps":["trace[913252934] 'agreement among raft nodes before linearized reading' (duration: 274.444362ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:44:27.768895Z","caller":"traceutil/trace.go:171","msg":"trace[843583324] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0; range_end:; response_count:1; response_revision:26593; }","duration":"303.399213ms","start":"2026-09-22T04:44:27.465484Z","end":"2026-09-22T04:44:27.768883Z","steps":["trace[843583324] 'agreement among raft nodes before linearized reading' (duration: 303.229639ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:44:27.768898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.046169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c041140c-8bd3-4f18-a0d2-994b9f6e05b9\" ","response":"range_response_count:1 size:3643"} {"level":"warn","ts":"2026-09-22T04:44:27.768921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:44:27.465392Z","time spent":"303.523206ms","remote":"127.0.0.1:43042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0\" "} {"level":"info","ts":"2026-09-22T04:44:27.768967Z","caller":"traceutil/trace.go:171","msg":"trace[1566173659] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c041140c-8bd3-4f18-a0d2-994b9f6e05b9; range_end:; response_count:1; response_revision:26593; }","duration":"253.181892ms","start":"2026-09-22T04:44:27.515768Z","end":"2026-09-22T04:44:27.76895Z","steps":["trace[1566173659] 'agreement among raft nodes before linearized reading' (duration: 252.964706ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:44:27.768957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:44:27.356366Z","time spent":"412.43376ms","remote":"127.0.0.1:43160","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-09-22T04:44:27.768642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:44:27.353801Z","time spent":"414.398334ms","remote":"127.0.0.1:43160","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-09-22T04:44:28.069226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.822012ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T04:44:28.06944Z","caller":"traceutil/trace.go:171","msg":"trace[1680850565] transaction","detail":"{read_only:false; response_revision:26595; number_of_response:1; }","duration":"146.87254ms","start":"2026-09-22T04:44:27.922546Z","end":"2026-09-22T04:44:28.069419Z","steps":["trace[1680850565] 'process raft request' (duration: 146.799278ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:44:28.069499Z","caller":"traceutil/trace.go:171","msg":"trace[914334234] linearizableReadLoop","detail":"{readStateIndex:27485; appliedIndex:27484; }","duration":"288.648631ms","start":"2026-09-22T04:44:27.780794Z","end":"2026-09-22T04:44:28.069443Z","steps":["trace[914334234] 'read index received' (duration: 187.502739ms)","trace[914334234] 'applied index is now lower than readState.Index' (duration: 101.142542ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:44:28.069587Z","caller":"traceutil/trace.go:171","msg":"trace[1983020685] transaction","detail":"{read_only:false; response_revision:26594; number_of_response:1; }","duration":"291.246021ms","start":"2026-09-22T04:44:27.778313Z","end":"2026-09-22T04:44:28.069559Z","steps":["trace[1983020685] 'process raft request' (duration: 189.964566ms)","trace[1983020685] 'compare' (duration: 100.677969ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:44:28.069653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.848726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T04:44:28.069741Z","caller":"traceutil/trace.go:171","msg":"trace[743301765] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:26595; }","duration":"288.941458ms","start":"2026-09-22T04:44:27.780769Z","end":"2026-09-22T04:44:28.06971Z","steps":["trace[743301765] 'agreement among raft nodes before linearized reading' (duration: 288.829535ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:44:28.328374Z","caller":"traceutil/trace.go:171","msg":"trace[1697842485] transaction","detail":"{read_only:false; response_revision:26596; number_of_response:1; }","duration":"110.855656ms","start":"2026-09-22T04:44:28.217491Z","end":"2026-09-22T04:44:28.328347Z","steps":["trace[1697842485] 'process raft request' (duration: 110.668741ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:44:28.594171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.929439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-09-22T04:44:28.59421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.788775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-22T04:44:28.594285Z","caller":"traceutil/trace.go:171","msg":"trace[1017235260] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26596; }","duration":"151.120125ms","start":"2026-09-22T04:44:28.443134Z","end":"2026-09-22T04:44:28.594254Z","steps":["trace[1017235260] 'range keys from in-memory index tree' (duration: 150.701744ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:44:28.594311Z","caller":"traceutil/trace.go:171","msg":"trace[1336955340] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26596; }","duration":"154.933918ms","start":"2026-09-22T04:44:28.439344Z","end":"2026-09-22T04:44:28.594278Z","steps":["trace[1336955340] 'range keys from in-memory index tree' (duration: 154.574649ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:44:28.594889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.067453ms","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-09-22T04:44:28.594503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.129083ms","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-09-22T04:44:28.594959Z","caller":"traceutil/trace.go:171","msg":"trace[1746710141] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26596; }","duration":"170.674499ms","start":"2026-09-22T04:44:28.424269Z","end":"2026-09-22T04:44:28.594943Z","steps":["trace[1746710141] 'range keys from in-memory index tree' (duration: 169.787674ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:44:28.594962Z","caller":"traceutil/trace.go:171","msg":"trace[1553082764] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26596; }","duration":"145.028818ms","start":"2026-09-22T04:44:28.449905Z","end":"2026-09-22T04:44:28.594933Z","steps":["trace[1553082764] 'range keys from in-memory index tree' (duration: 143.839246ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:44:28.594826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.364962ms","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-09-22T04:44:28.595125Z","caller":"traceutil/trace.go:171","msg":"trace[621259672] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26596; }","duration":"147.763783ms","start":"2026-09-22T04:44:28.447352Z","end":"2026-09-22T04:44:28.595116Z","steps":["trace[621259672] 'range keys from in-memory index tree' (duration: 147.167417ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:44:28.594768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.84466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-09-22T04:44:28.595206Z","caller":"traceutil/trace.go:171","msg":"trace[957570154] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26596; }","duration":"161.291413ms","start":"2026-09-22T04:44:28.433905Z","end":"2026-09-22T04:44:28.595196Z","steps":["trace[957570154] 'range keys from in-memory index tree' (duration: 160.340836ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:44:31.051809Z","caller":"traceutil/trace.go:171","msg":"trace[585765667] transaction","detail":"{read_only:false; response_revision:26620; number_of_response:1; }","duration":"203.28913ms","start":"2026-09-22T04:44:30.848465Z","end":"2026-09-22T04:44:31.051754Z","steps":["trace[585765667] 'process raft request' (duration: 202.761725ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:44:34.907019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.075774ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T04:44:34.90718Z","caller":"traceutil/trace.go:171","msg":"trace[1640135426] linearizableReadLoop","detail":"{readStateIndex:27553; appliedIndex:27552; }","duration":"240.28788ms","start":"2026-09-22T04:44:34.666869Z","end":"2026-09-22T04:44:34.907157Z","steps":["trace[1640135426] 'read index received' (duration: 106.9586ms)","trace[1640135426] 'applied index is now lower than readState.Index' (duration: 133.32738ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:44:34.907412Z","caller":"traceutil/trace.go:171","msg":"trace[1085400577] transaction","detail":"{read_only:false; response_revision:26660; number_of_response:1; }","duration":"245.139583ms","start":"2026-09-22T04:44:34.662231Z","end":"2026-09-22T04:44:34.907371Z","steps":["trace[1085400577] 'process raft request' (duration: 111.601407ms)","trace[1085400577] 'compare' (duration: 132.864968ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:44:34.907549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.619589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-22T04:44:34.907641Z","caller":"traceutil/trace.go:171","msg":"trace[293984282] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26660; }","duration":"240.775034ms","start":"2026-09-22T04:44:34.666843Z","end":"2026-09-22T04:44:34.907618Z","steps":["trace[293984282] 'agreement among raft nodes before linearized reading' (duration: 240.489656ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:44:34.907697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.860738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:10358"} {"level":"warn","ts":"2026-09-22T04:44:34.90771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.630215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-09-22T04:44:34.907757Z","caller":"traceutil/trace.go:171","msg":"trace[1182711494] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:26660; }","duration":"206.961061ms","start":"2026-09-22T04:44:34.70078Z","end":"2026-09-22T04:44:34.907741Z","steps":["trace[1182711494] 'agreement among raft nodes before linearized reading' (duration: 206.695804ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:44:34.907552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.090567ms","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-09-22T04:44:34.907771Z","caller":"traceutil/trace.go:171","msg":"trace[1278526280] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26660; }","duration":"238.731908ms","start":"2026-09-22T04:44:34.669016Z","end":"2026-09-22T04:44:34.907748Z","steps":["trace[1278526280] 'agreement among raft nodes before linearized reading' (duration: 238.585394ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:44:34.907802Z","caller":"traceutil/trace.go:171","msg":"trace[375941139] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26660; }","duration":"239.431607ms","start":"2026-09-22T04:44:34.668358Z","end":"2026-09-22T04:44:34.90779Z","steps":["trace[375941139] 'agreement among raft nodes before linearized reading' (duration: 238.998026ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:44:34.907876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.359492ms","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":"warn","ts":"2026-09-22T04:44:34.90795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.958214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-09-22T04:44:34.907982Z","caller":"traceutil/trace.go:171","msg":"trace[278584741] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26660; }","duration":"238.998865ms","start":"2026-09-22T04:44:34.668975Z","end":"2026-09-22T04:44:34.907973Z","steps":["trace[278584741] 'agreement among raft nodes before linearized reading' (duration: 238.914943ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:44:34.908012Z","caller":"traceutil/trace.go:171","msg":"trace[164859811] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:26660; }","duration":"114.525226ms","start":"2026-09-22T04:44:34.793449Z","end":"2026-09-22T04:44:34.907975Z","steps":["trace[164859811] 'agreement among raft nodes before linearized reading' (duration: 114.079484ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:44:35.16992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.816677ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T04:44:35.170091Z","caller":"traceutil/trace.go:171","msg":"trace[1509300437] transaction","detail":"{read_only:false; response_revision:26661; number_of_response:1; }","duration":"255.66834ms","start":"2026-09-22T04:44:34.91438Z","end":"2026-09-22T04:44:35.170048Z","steps":["trace[1509300437] 'process raft request' (duration: 141.578786ms)","trace[1509300437] 'compare' (duration: 113.600341ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:44:35.172897Z","caller":"traceutil/trace.go:171","msg":"trace[538010442] transaction","detail":"{read_only:false; response_revision:26662; number_of_response:1; }","duration":"258.22257ms","start":"2026-09-22T04:44:34.914627Z","end":"2026-09-22T04:44:35.172849Z","steps":["trace[538010442] 'process raft request' (duration: 257.86479ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:44:35.173091Z","caller":"traceutil/trace.go:171","msg":"trace[216280727] transaction","detail":"{read_only:false; response_revision:26663; number_of_response:1; }","duration":"258.291082ms","start":"2026-09-22T04:44:34.914779Z","end":"2026-09-22T04:44:35.17307Z","steps":["trace[216280727] 'process raft request' (duration: 257.990373ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:44:35.17338Z","caller":"traceutil/trace.go:171","msg":"trace[312751525] transaction","detail":"{read_only:false; response_revision:26664; number_of_response:1; }","duration":"258.529348ms","start":"2026-09-22T04:44:34.914839Z","end":"2026-09-22T04:44:35.173368Z","steps":["trace[312751525] 'process raft request' (duration: 258.179968ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:44:35.173402Z","caller":"traceutil/trace.go:171","msg":"trace[1049602554] transaction","detail":"{read_only:false; response_revision:26665; number_of_response:1; }","duration":"251.192917ms","start":"2026-09-22T04:44:34.922157Z","end":"2026-09-22T04:44:35.17335Z","steps":["trace[1049602554] 'process raft request' (duration: 251.059374ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:44:38.500579Z","caller":"traceutil/trace.go:171","msg":"trace[1238973017] transaction","detail":"{read_only:false; response_revision:26698; number_of_response:1; }","duration":"132.031205ms","start":"2026-09-22T04:44:38.368515Z","end":"2026-09-22T04:44:38.500546Z","steps":["trace[1238973017] 'process raft request' (duration: 131.8363ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:44:38.770947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.377248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-4f0fcff4-d417-4a96-87dc-3b8702b8514b\" ","response":"range_response_count:1 size:1304"} {"level":"info","ts":"2026-09-22T04:44:38.771017Z","caller":"traceutil/trace.go:171","msg":"trace[433650090] range","detail":"{range_begin:/registry/persistentvolumes/pvc-4f0fcff4-d417-4a96-87dc-3b8702b8514b; range_end:; response_count:1; response_revision:26698; }","duration":"238.512001ms","start":"2026-09-22T04:44:38.532489Z","end":"2026-09-22T04:44:38.771001Z","steps":["trace[433650090] 'range keys from in-memory index tree' (duration: 238.161261ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:44:42.079624Z","caller":"traceutil/trace.go:171","msg":"trace[50335398] transaction","detail":"{read_only:false; response_revision:26726; number_of_response:1; }","duration":"250.305244ms","start":"2026-09-22T04:44:41.829268Z","end":"2026-09-22T04:44:42.079573Z","steps":["trace[50335398] 'process raft request' (duration: 249.968355ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:44:42.079773Z","caller":"traceutil/trace.go:171","msg":"trace[1575433764] transaction","detail":"{read_only:false; response_revision:26728; number_of_response:1; }","duration":"246.618423ms","start":"2026-09-22T04:44:41.833139Z","end":"2026-09-22T04:44:42.079758Z","steps":["trace[1575433764] 'process raft request' (duration: 246.392717ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:44:42.079616Z","caller":"traceutil/trace.go:171","msg":"trace[1109612542] transaction","detail":"{read_only:false; response_revision:26727; number_of_response:1; }","duration":"248.778992ms","start":"2026-09-22T04:44:41.8308Z","end":"2026-09-22T04:44:42.079579Z","steps":["trace[1109612542] 'process raft request' (duration: 248.648178ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:44:42.309894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.435258ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T04:44:42.31002Z","caller":"traceutil/trace.go:171","msg":"trace[42673780] transaction","detail":"{read_only:false; response_revision:26729; number_of_response:1; }","duration":"175.751778ms","start":"2026-09-22T04:44:42.134243Z","end":"2026-09-22T04:44:42.309995Z","steps":["trace[42673780] 'process raft request' (duration: 70.096644ms)","trace[42673780] 'compare' (duration: 105.269214ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:44:44.991867Z","caller":"traceutil/trace.go:171","msg":"trace[51207451] transaction","detail":"{read_only:false; response_revision:26747; number_of_response:1; }","duration":"108.525872ms","start":"2026-09-22T04:44:44.883279Z","end":"2026-09-22T04:44:44.991805Z","steps":["trace[51207451] 'process raft request' (duration: 108.175113ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:44:59.328061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.820052ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T04:44:59.32818Z","caller":"traceutil/trace.go:171","msg":"trace[1269336233] linearizableReadLoop","detail":"{readStateIndex:27762; appliedIndex:27761; }","duration":"226.503024ms","start":"2026-09-22T04:44:59.10166Z","end":"2026-09-22T04:44:59.328163Z","steps":["trace[1269336233] 'read index received' (duration: 69.389914ms)","trace[1269336233] 'applied index is now lower than readState.Index' (duration: 157.112089ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:44:59.328302Z","caller":"traceutil/trace.go:171","msg":"trace[1750080138] transaction","detail":"{read_only:false; response_revision:26863; number_of_response:1; }","duration":"241.36868ms","start":"2026-09-22T04:44:59.08688Z","end":"2026-09-22T04:44:59.328248Z","steps":["trace[1750080138] 'process raft request' (duration: 84.260261ms)","trace[1750080138] 'compare' (duration: 156.674147ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:44:59.328385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.813682ms","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-09-22T04:44:59.328426Z","caller":"traceutil/trace.go:171","msg":"trace[705174641] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26863; }","duration":"226.873393ms","start":"2026-09-22T04:44:59.101539Z","end":"2026-09-22T04:44:59.328413Z","steps":["trace[705174641] 'agreement among raft nodes before linearized reading' (duration: 226.712979ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:44:59.676345Z","caller":"traceutil/trace.go:171","msg":"trace[1602825917] transaction","detail":"{read_only:false; response_revision:26865; number_of_response:1; }","duration":"342.060309ms","start":"2026-09-22T04:44:59.334232Z","end":"2026-09-22T04:44:59.676292Z","steps":["trace[1602825917] 'process raft request' (duration: 341.829233ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:44:59.676549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:44:59.334211Z","time spent":"342.218683ms","remote":"127.0.0.1:43160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T04:44:59.676656Z","caller":"traceutil/trace.go:171","msg":"trace[1898877083] linearizableReadLoop","detail":"{readStateIndex:27767; appliedIndex:27763; }","duration":"311.855514ms","start":"2026-09-22T04:44:59.364757Z","end":"2026-09-22T04:44:59.676612Z","steps":["trace[1898877083] 'read index received' (duration: 57.537451ms)","trace[1898877083] 'applied index is now lower than readState.Index' (duration: 254.316573ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:44:59.676743Z","caller":"traceutil/trace.go:171","msg":"trace[1348469554] transaction","detail":"{read_only:false; response_revision:26867; number_of_response:1; }","duration":"341.902275ms","start":"2026-09-22T04:44:59.334824Z","end":"2026-09-22T04:44:59.676726Z","steps":["trace[1348469554] 'process raft request' (duration: 341.638108ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:44:59.676772Z","caller":"traceutil/trace.go:171","msg":"trace[1584766878] transaction","detail":"{read_only:false; response_revision:26868; number_of_response:1; }","duration":"341.341029ms","start":"2026-09-22T04:44:59.335416Z","end":"2026-09-22T04:44:59.676757Z","steps":["trace[1584766878] 'process raft request' (duration: 341.134883ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:44:59.676835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:44:59.334811Z","time spent":"341.977477ms","remote":"127.0.0.1:43160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T04:44:59.676855Z","caller":"traceutil/trace.go:171","msg":"trace[136622535] transaction","detail":"{read_only:false; response_revision:26866; number_of_response:1; }","duration":"342.527032ms","start":"2026-09-22T04:44:59.334298Z","end":"2026-09-22T04:44:59.676825Z","steps":["trace[136622535] 'process raft request' (duration: 341.938286ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:44:59.676977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.216924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-09-22T04:44:59.676875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:44:59.335394Z","time spent":"341.408521ms","remote":"127.0.0.1:43160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T04:44:59.677012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:44:59.334271Z","time spent":"342.654586ms","remote":"127.0.0.1:43160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T04:44:59.677039Z","caller":"traceutil/trace.go:171","msg":"trace[23901709] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:26868; }","duration":"312.307166ms","start":"2026-09-22T04:44:59.364717Z","end":"2026-09-22T04:44:59.677024Z","steps":["trace[23901709] 'agreement among raft nodes before linearized reading' (duration: 312.197123ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:44:59.677097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:44:59.364698Z","time spent":"312.381149ms","remote":"127.0.0.1:43318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":32,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-09-22T04:44:59.686499Z","caller":"traceutil/trace.go:171","msg":"trace[2056291247] transaction","detail":"{read_only:false; response_revision:26869; number_of_response:1; }","duration":"108.03236ms","start":"2026-09-22T04:44:59.578391Z","end":"2026-09-22T04:44:59.686424Z","steps":["trace[2056291247] 'process raft request' (duration: 107.847705ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:45:00.12409Z","caller":"traceutil/trace.go:171","msg":"trace[1867672797] transaction","detail":"{read_only:false; response_revision:26871; number_of_response:1; }","duration":"129.642769ms","start":"2026-09-22T04:44:59.994432Z","end":"2026-09-22T04:45:00.124075Z","steps":["trace[1867672797] 'process raft request' (duration: 129.536566ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:45:01.621068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.305184ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T04:45:01.621211Z","caller":"traceutil/trace.go:171","msg":"trace[16379414] linearizableReadLoop","detail":"{readStateIndex:27790; appliedIndex:27789; }","duration":"162.563948ms","start":"2026-09-22T04:45:01.458623Z","end":"2026-09-22T04:45:01.621187Z","steps":["trace[16379414] 'read index received' (duration: 48.938506ms)","trace[16379414] 'applied index is now lower than readState.Index' (duration: 113.620802ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:45:01.621316Z","caller":"traceutil/trace.go:171","msg":"trace[1845426343] transaction","detail":"{read_only:false; response_revision:26890; number_of_response:1; }","duration":"181.136345ms","start":"2026-09-22T04:45:01.440168Z","end":"2026-09-22T04:45:01.621304Z","steps":["trace[1845426343] 'process raft request' (duration: 67.486302ms)","trace[1845426343] 'compare' (duration: 113.139779ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:45:01.621661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.99079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-09-22T04:45:01.621748Z","caller":"traceutil/trace.go:171","msg":"trace[78731834] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0; range_end:; response_count:1; response_revision:26890; }","duration":"163.161215ms","start":"2026-09-22T04:45:01.458566Z","end":"2026-09-22T04:45:01.621727Z","steps":["trace[78731834] 'agreement among raft nodes before linearized reading' (duration: 162.854086ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:45:01.799086Z","caller":"traceutil/trace.go:171","msg":"trace[1905251592] transaction","detail":"{read_only:false; response_revision:26893; number_of_response:1; }","duration":"106.706693ms","start":"2026-09-22T04:45:01.692361Z","end":"2026-09-22T04:45:01.799068Z","steps":["trace[1905251592] 'process raft request' (duration: 106.656552ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:45:01.799203Z","caller":"traceutil/trace.go:171","msg":"trace[1315993340] transaction","detail":"{read_only:false; response_revision:26892; number_of_response:1; }","duration":"108.440551ms","start":"2026-09-22T04:45:01.690758Z","end":"2026-09-22T04:45:01.799199Z","steps":["trace[1315993340] 'process raft request' (duration: 108.144993ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:45:01.799282Z","caller":"traceutil/trace.go:171","msg":"trace[827531748] transaction","detail":"{read_only:false; response_revision:26894; number_of_response:1; }","duration":"106.811246ms","start":"2026-09-22T04:45:01.692463Z","end":"2026-09-22T04:45:01.799274Z","steps":["trace[827531748] 'process raft request' (duration: 106.58345ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:45:01.799433Z","caller":"traceutil/trace.go:171","msg":"trace[133857576] linearizableReadLoop","detail":"{readStateIndex:27795; appliedIndex:27791; }","duration":"101.197033ms","start":"2026-09-22T04:45:01.69823Z","end":"2026-09-22T04:45:01.799427Z","steps":["trace[133857576] 'read index received' (duration: 6.671963ms)","trace[133857576] 'applied index is now lower than readState.Index' (duration: 94.52426ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:45:01.799581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.332857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-22T04:45:01.799658Z","caller":"traceutil/trace.go:171","msg":"trace[651650308] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:26895; }","duration":"101.419599ms","start":"2026-09-22T04:45:01.698205Z","end":"2026-09-22T04:45:01.799625Z","steps":["trace[651650308] 'agreement among raft nodes before linearized reading' (duration: 101.319666ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:45:01.799809Z","caller":"traceutil/trace.go:171","msg":"trace[1927125535] transaction","detail":"{read_only:false; response_revision:26895; number_of_response:1; }","duration":"106.856817ms","start":"2026-09-22T04:45:01.692942Z","end":"2026-09-22T04:45:01.799799Z","steps":["trace[1927125535] 'process raft request' (duration: 106.311472ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:45:04.428837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.93254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T04:45:04.428926Z","caller":"traceutil/trace.go:171","msg":"trace[1026176227] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:26917; }","duration":"129.074424ms","start":"2026-09-22T04:45:04.299832Z","end":"2026-09-22T04:45:04.428906Z","steps":["trace[1026176227] 'count revisions from in-memory index tree' (duration: 128.766546ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:45:04.5776Z","caller":"traceutil/trace.go:171","msg":"trace[1186894075] transaction","detail":"{read_only:false; response_revision:26918; number_of_response:1; }","duration":"177.825425ms","start":"2026-09-22T04:45:04.399747Z","end":"2026-09-22T04:45:04.577572Z","steps":["trace[1186894075] 'process raft request' (duration: 177.63985ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:45:04.841331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.728788ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T04:45:04.841464Z","caller":"traceutil/trace.go:171","msg":"trace[1513021940] linearizableReadLoop","detail":"{readStateIndex:27819; appliedIndex:27818; }","duration":"121.516767ms","start":"2026-09-22T04:45:04.719927Z","end":"2026-09-22T04:45:04.841443Z","steps":["trace[1513021940] 'read index received' (duration: 8.550783ms)","trace[1513021940] 'applied index is now lower than readState.Index' (duration: 112.964734ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:45:04.841507Z","caller":"traceutil/trace.go:171","msg":"trace[2015081391] transaction","detail":"{read_only:false; response_revision:26919; number_of_response:1; }","duration":"198.714546ms","start":"2026-09-22T04:45:04.642749Z","end":"2026-09-22T04:45:04.841464Z","steps":["trace[2015081391] 'process raft request' (duration: 85.740561ms)","trace[2015081391] 'compare' (duration: 112.546193ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:45:04.841637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.679462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T04:45:04.84171Z","caller":"traceutil/trace.go:171","msg":"trace[1468007492] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:26919; }","duration":"121.828756ms","start":"2026-09-22T04:45:04.719865Z","end":"2026-09-22T04:45:04.841694Z","steps":["trace[1468007492] 'agreement among raft nodes before linearized reading' (duration: 121.682722ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:45:05.230151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.19678ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-09-22T04:45:05.230278Z","caller":"traceutil/trace.go:171","msg":"trace[986231963] linearizableReadLoop","detail":"{readStateIndex:27820; appliedIndex:27819; }","duration":"386.515262ms","start":"2026-09-22T04:45:04.843736Z","end":"2026-09-22T04:45:05.230251Z","steps":["trace[986231963] 'read index received' (duration: 136.196178ms)","trace[986231963] 'applied index is now lower than readState.Index' (duration: 250.314724ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:45:05.230429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.691437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T04:45:05.230687Z","caller":"traceutil/trace.go:171","msg":"trace[1296048233] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26919; }","duration":"386.777179ms","start":"2026-09-22T04:45:04.843671Z","end":"2026-09-22T04:45:05.230448Z","steps":["trace[1296048233] 'agreement among raft nodes before linearized reading' (duration: 386.690037ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:45:05.230748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:45:04.843646Z","time spent":"387.085968ms","remote":"127.0.0.1:42882","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-09-22T04:45:05.356017Z","caller":"traceutil/trace.go:171","msg":"trace[1344822478] linearizableReadLoop","detail":"{readStateIndex:27821; appliedIndex:27820; }","duration":"125.666501ms","start":"2026-09-22T04:45:05.230317Z","end":"2026-09-22T04:45:05.355984Z","steps":["trace[1344822478] 'read index received' (duration: 125.511786ms)","trace[1344822478] 'applied index is now lower than readState.Index' (duration: 152.975µs)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:45:05.356112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.594008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterctl.cluster.x-k8s.io/providers/\" range_end:\"/registry/clusterctl.cluster.x-k8s.io/providers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-09-22T04:45:05.35617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.734318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T04:45:05.35618Z","caller":"traceutil/trace.go:171","msg":"trace[1007913905] range","detail":"{range_begin:/registry/clusterctl.cluster.x-k8s.io/providers/; range_end:/registry/clusterctl.cluster.x-k8s.io/providers0; response_count:0; response_revision:26920; }","duration":"366.761122ms","start":"2026-09-22T04:45:04.9894Z","end":"2026-09-22T04:45:05.356161Z","steps":["trace[1007913905] 'agreement among raft nodes before linearized reading' (duration: 366.603168ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:45:05.356218Z","caller":"traceutil/trace.go:171","msg":"trace[1323767598] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0; response_count:0; response_revision:26920; }","duration":"453.81203ms","start":"2026-09-22T04:45:04.902392Z","end":"2026-09-22T04:45:05.356204Z","steps":["trace[1323767598] 'agreement among raft nodes before linearized reading' (duration: 453.703027ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:45:05.356224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:45:04.989369Z","time spent":"366.844835ms","remote":"127.0.0.1:33692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":4,"response size":32,"request content":"key:\"/registry/clusterctl.cluster.x-k8s.io/providers/\" range_end:\"/registry/clusterctl.cluster.x-k8s.io/providers0\" count_only:true "} {"level":"warn","ts":"2026-09-22T04:45:05.356226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.634008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-09-22T04:45:05.356263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:45:04.902296Z","time spent":"453.956264ms","remote":"127.0.0.1:55420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":144,"response count":0,"response size":30,"request content":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true "} {"level":"info","ts":"2026-09-22T04:45:05.356241Z","caller":"traceutil/trace.go:171","msg":"trace[352334555] transaction","detail":"{read_only:false; response_revision:26920; number_of_response:1; }","duration":"333.966638ms","start":"2026-09-22T04:45:05.022206Z","end":"2026-09-22T04:45:05.356172Z","steps":["trace[352334555] 'process raft request' (duration: 333.571007ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:45:05.356305Z","caller":"traceutil/trace.go:171","msg":"trace[1874616384] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:26920; }","duration":"389.778612ms","start":"2026-09-22T04:45:04.966505Z","end":"2026-09-22T04:45:05.356284Z","steps":["trace[1874616384] 'agreement among raft nodes before linearized reading' (duration: 389.529855ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:45:05.356513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:45:04.96648Z","time spent":"390.008988ms","remote":"127.0.0.1:43124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":78,"response size":32,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-09-22T04:45:05.356563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:45:05.022171Z","time spent":"334.236725ms","remote":"127.0.0.1:59496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7897,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T04:45:05.591217Z","caller":"traceutil/trace.go:171","msg":"trace[831786177] linearizableReadLoop","detail":"{readStateIndex:27822; appliedIndex:27821; }","duration":"226.706879ms","start":"2026-09-22T04:45:05.364483Z","end":"2026-09-22T04:45:05.59119Z","steps":["trace[831786177] 'read index received' (duration: 194.279284ms)","trace[831786177] 'applied index is now lower than readState.Index' (duration: 32.426385ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:45:05.591263Z","caller":"traceutil/trace.go:171","msg":"trace[271614962] transaction","detail":"{read_only:false; response_revision:26921; number_of_response:1; }","duration":"337.657239ms","start":"2026-09-22T04:45:05.25357Z","end":"2026-09-22T04:45:05.591227Z","steps":["trace[271614962] 'process raft request' (duration: 305.185352ms)","trace[271614962] 'compare' (duration: 32.283872ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:45:05.59142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:45:05.253543Z","time spent":"337.783792ms","remote":"127.0.0.1:43160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T04:45:05.591472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.986607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:19028"} {"level":"info","ts":"2026-09-22T04:45:05.591553Z","caller":"traceutil/trace.go:171","msg":"trace[1104432286] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:26921; }","duration":"227.10275ms","start":"2026-09-22T04:45:05.364436Z","end":"2026-09-22T04:45:05.591539Z","steps":["trace[1104432286] 'agreement among raft nodes before linearized reading' (duration: 226.865424ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:45:05.591556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.502415ms","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-09-22T04:45:05.591608Z","caller":"traceutil/trace.go:171","msg":"trace[484709331] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26921; }","duration":"207.651739ms","start":"2026-09-22T04:45:05.383943Z","end":"2026-09-22T04:45:05.591595Z","steps":["trace[484709331] 'agreement among raft nodes before linearized reading' (duration: 207.435693ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:45:05.591577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.177181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T04:45:05.591697Z","caller":"traceutil/trace.go:171","msg":"trace[24342185] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:26921; }","duration":"198.413797ms","start":"2026-09-22T04:45:05.393258Z","end":"2026-09-22T04:45:05.591672Z","steps":["trace[24342185] 'agreement among raft nodes before linearized reading' (duration: 198.16604ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:45:05.817409Z","caller":"traceutil/trace.go:171","msg":"trace[668417323] transaction","detail":"{read_only:false; response_revision:26922; number_of_response:1; }","duration":"220.784858ms","start":"2026-09-22T04:45:05.596527Z","end":"2026-09-22T04:45:05.817312Z","steps":["trace[668417323] 'process raft request' (duration: 216.817219ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:45:05.817485Z","caller":"traceutil/trace.go:171","msg":"trace[129410926] linearizableReadLoop","detail":"{readStateIndex:27823; appliedIndex:27822; }","duration":"176.413817ms","start":"2026-09-22T04:45:05.641048Z","end":"2026-09-22T04:45:05.817462Z","steps":["trace[129410926] 'read index received' (duration: 172.218622ms)","trace[129410926] 'applied index is now lower than readState.Index' (duration: 4.194105ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:45:05.817723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.674523ms","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-09-22T04:45:05.817777Z","caller":"traceutil/trace.go:171","msg":"trace[1038573780] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26923; }","duration":"176.739795ms","start":"2026-09-22T04:45:05.641021Z","end":"2026-09-22T04:45:05.817761Z","steps":["trace[1038573780] 'agreement among raft nodes before linearized reading' (duration: 176.56235ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:45:08.368591Z","caller":"traceutil/trace.go:171","msg":"trace[959647100] linearizableReadLoop","detail":"{readStateIndex:27852; appliedIndex:27851; }","duration":"180.55358ms","start":"2026-09-22T04:45:08.188007Z","end":"2026-09-22T04:45:08.36856Z","steps":["trace[959647100] 'read index received' (duration: 180.308003ms)","trace[959647100] 'applied index is now lower than readState.Index' (duration: 235.096µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:45:08.368664Z","caller":"traceutil/trace.go:171","msg":"trace[723310928] transaction","detail":"{read_only:false; response_revision:26951; number_of_response:1; }","duration":"212.721048ms","start":"2026-09-22T04:45:08.155879Z","end":"2026-09-22T04:45:08.3686Z","steps":["trace[723310928] 'process raft request' (duration: 212.45266ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:45:08.368793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.736194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T04:45:08.368869Z","caller":"traceutil/trace.go:171","msg":"trace[1846356769] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:26951; }","duration":"180.849658ms","start":"2026-09-22T04:45:08.188Z","end":"2026-09-22T04:45:08.36885Z","steps":["trace[1846356769] 'agreement among raft nodes before linearized reading' (duration: 180.684973ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:45:08.737057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.137974ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T04:45:08.737312Z","caller":"traceutil/trace.go:171","msg":"trace[1429060596] linearizableReadLoop","detail":"{readStateIndex:27855; appliedIndex:27852; }","duration":"368.567863ms","start":"2026-09-22T04:45:08.368727Z","end":"2026-09-22T04:45:08.737295Z","steps":["trace[1429060596] 'read index received' (duration: 205.11017ms)","trace[1429060596] 'applied index is now lower than readState.Index' (duration: 163.456543ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:45:08.737436Z","caller":"traceutil/trace.go:171","msg":"trace[2089590407] transaction","detail":"{read_only:false; response_revision:26953; number_of_response:1; }","duration":"499.324362ms","start":"2026-09-22T04:45:08.238101Z","end":"2026-09-22T04:45:08.737426Z","steps":["trace[2089590407] 'process raft request' (duration: 499.055495ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:45:08.737499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:45:08.23807Z","time spent":"499.395955ms","remote":"127.0.0.1:43160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T04:45:08.737545Z","caller":"traceutil/trace.go:171","msg":"trace[1594699503] transaction","detail":"{read_only:false; response_revision:26952; number_of_response:1; }","duration":"501.725208ms","start":"2026-09-22T04:45:08.235785Z","end":"2026-09-22T04:45:08.73751Z","steps":["trace[1594699503] 'process raft request' (duration: 338.043239ms)","trace[1594699503] 'compare' (duration: 162.654831ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:45:08.737573Z","caller":"traceutil/trace.go:171","msg":"trace[1001373393] transaction","detail":"{read_only:false; response_revision:26954; number_of_response:1; }","duration":"498.689915ms","start":"2026-09-22T04:45:08.238858Z","end":"2026-09-22T04:45:08.737548Z","steps":["trace[1001373393] 'process raft request' (duration: 498.392007ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:45:08.737776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:45:08.238846Z","time spent":"498.817249ms","remote":"127.0.0.1:43160","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-09-22T04:45:08.737771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:45:08.235764Z","time spent":"501.897713ms","remote":"127.0.0.1:43160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T04:45:08.737916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.686237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-22T04:45:08.738045Z","caller":"traceutil/trace.go:171","msg":"trace[1221214852] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:26954; }","duration":"549.899124ms","start":"2026-09-22T04:45:08.188117Z","end":"2026-09-22T04:45:08.738016Z","steps":["trace[1221214852] 'agreement among raft nodes before linearized reading' (duration: 549.493622ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:45:08.738461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:45:08.188111Z","time spent":"550.332165ms","remote":"127.0.0.1:43368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":54,"response size":32,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"info","ts":"2026-09-22T04:45:08.752588Z","caller":"traceutil/trace.go:171","msg":"trace[737397673] transaction","detail":"{read_only:false; response_revision:26955; number_of_response:1; }","duration":"284.999681ms","start":"2026-09-22T04:45:08.467563Z","end":"2026-09-22T04:45:08.752563Z","steps":["trace[737397673] 'process raft request' (duration: 284.797365ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:45:08.754414Z","caller":"traceutil/trace.go:171","msg":"trace[538441423] transaction","detail":"{read_only:false; response_revision:26956; number_of_response:1; }","duration":"165.009235ms","start":"2026-09-22T04:45:08.589381Z","end":"2026-09-22T04:45:08.75439Z","steps":["trace[538441423] 'process raft request' (duration: 164.678395ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:45:08.754703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.850168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T04:45:08.75477Z","caller":"traceutil/trace.go:171","msg":"trace[1148071434] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:26956; }","duration":"380.966191ms","start":"2026-09-22T04:45:08.373784Z","end":"2026-09-22T04:45:08.754751Z","steps":["trace[1148071434] 'agreement among raft nodes before linearized reading' (duration: 380.864419ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:45:08.754819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:45:08.373686Z","time spent":"381.120685ms","remote":"127.0.0.1:43076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":30,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"info","ts":"2026-09-22T04:45:09.981285Z","caller":"traceutil/trace.go:171","msg":"trace[442196722] linearizableReadLoop","detail":"{readStateIndex:27862; appliedIndex:27860; }","duration":"120.831239ms","start":"2026-09-22T04:45:09.860435Z","end":"2026-09-22T04:45:09.981266Z","steps":["trace[442196722] 'read index received' (duration: 16.467179ms)","trace[442196722] 'applied index is now lower than readState.Index' (duration: 104.36318ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:45:09.981471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.681155ms","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-09-22T04:45:09.981498Z","caller":"traceutil/trace.go:171","msg":"trace[1120965683] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26960; }","duration":"120.736586ms","start":"2026-09-22T04:45:09.860754Z","end":"2026-09-22T04:45:09.981491Z","steps":["trace[1120965683] 'agreement among raft nodes before linearized reading' (duration: 120.624653ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:45:09.98154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.080945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-22T04:45:09.98164Z","caller":"traceutil/trace.go:171","msg":"trace[1378970240] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26960; }","duration":"121.202419ms","start":"2026-09-22T04:45:09.860402Z","end":"2026-09-22T04:45:09.981605Z","steps":["trace[1378970240] 'agreement among raft nodes before linearized reading' (duration: 120.969763ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:45:09.981778Z","caller":"traceutil/trace.go:171","msg":"trace[1129021800] transaction","detail":"{read_only:false; response_revision:26960; number_of_response:1; }","duration":"137.824972ms","start":"2026-09-22T04:45:09.84394Z","end":"2026-09-22T04:45:09.981765Z","steps":["trace[1129021800] 'process raft request' (duration: 135.761986ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:45:22.156003Z","caller":"traceutil/trace.go:171","msg":"trace[1016004717] linearizableReadLoop","detail":"{readStateIndex:27963; appliedIndex:27962; }","duration":"102.986182ms","start":"2026-09-22T04:45:22.053001Z","end":"2026-09-22T04:45:22.155987Z","steps":["trace[1016004717] 'read index received' (duration: 102.817647ms)","trace[1016004717] 'applied index is now lower than readState.Index' (duration: 167.845µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:45:22.156185Z","caller":"traceutil/trace.go:171","msg":"trace[914244335] transaction","detail":"{read_only:false; response_revision:27059; number_of_response:1; }","duration":"122.231047ms","start":"2026-09-22T04:45:22.033926Z","end":"2026-09-22T04:45:22.156157Z","steps":["trace[914244335] 'process raft request' (duration: 121.928539ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:45:22.156212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.194307ms","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-09-22T04:45:22.156244Z","caller":"traceutil/trace.go:171","msg":"trace[615606210] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27059; }","duration":"103.258919ms","start":"2026-09-22T04:45:22.052974Z","end":"2026-09-22T04:45:22.156233Z","steps":["trace[615606210] 'agreement among raft nodes before linearized reading' (duration: 103.110965ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:45:22.15633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.230929ms","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-09-22T04:45:22.156396Z","caller":"traceutil/trace.go:171","msg":"trace[1027678839] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:27059; }","duration":"103.324851ms","start":"2026-09-22T04:45:22.05305Z","end":"2026-09-22T04:45:22.156375Z","steps":["trace[1027678839] 'agreement among raft nodes before linearized reading' (duration: 103.129246ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:45:22.156488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.636427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-09-22T04:45:22.156509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.357772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-22T04:45:22.156574Z","caller":"traceutil/trace.go:171","msg":"trace[1934583735] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27059; }","duration":"100.752601ms","start":"2026-09-22T04:45:22.0558Z","end":"2026-09-22T04:45:22.156553Z","steps":["trace[1934583735] 'agreement among raft nodes before linearized reading' (duration: 100.454313ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:45:22.156594Z","caller":"traceutil/trace.go:171","msg":"trace[814354293] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27059; }","duration":"103.484156ms","start":"2026-09-22T04:45:22.05309Z","end":"2026-09-22T04:45:22.156574Z","steps":["trace[814354293] 'agreement among raft nodes before linearized reading' (duration: 103.197098ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:45:28.365975Z","caller":"traceutil/trace.go:171","msg":"trace[1320491740] transaction","detail":"{read_only:false; response_revision:27114; number_of_response:1; }","duration":"155.790743ms","start":"2026-09-22T04:45:28.210154Z","end":"2026-09-22T04:45:28.365945Z","steps":["trace[1320491740] 'process raft request' (duration: 73.427125ms)","trace[1320491740] 'compare' (duration: 82.212264ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:45:28.445588Z","caller":"traceutil/trace.go:171","msg":"trace[23449361] transaction","detail":"{read_only:false; response_revision:27115; number_of_response:1; }","duration":"234.499442ms","start":"2026-09-22T04:45:28.211063Z","end":"2026-09-22T04:45:28.445562Z","steps":["trace[23449361] 'process raft request' (duration: 234.289386ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:45:28.446038Z","caller":"traceutil/trace.go:171","msg":"trace[1446276483] linearizableReadLoop","detail":"{readStateIndex:28021; appliedIndex:28018; }","duration":"234.457921ms","start":"2026-09-22T04:45:28.211558Z","end":"2026-09-22T04:45:28.446016Z","steps":["trace[1446276483] 'read index received' (duration: 72.061248ms)","trace[1446276483] 'applied index is now lower than readState.Index' (duration: 162.395803ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:45:28.446119Z","caller":"traceutil/trace.go:171","msg":"trace[1782506190] transaction","detail":"{read_only:false; response_revision:27117; number_of_response:1; }","duration":"234.368749ms","start":"2026-09-22T04:45:28.211733Z","end":"2026-09-22T04:45:28.446102Z","steps":["trace[1782506190] 'process raft request' (duration: 234.179833ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:45:28.446214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.635746ms","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-09-22T04:45:28.446253Z","caller":"traceutil/trace.go:171","msg":"trace[934806203] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27117; }","duration":"234.691577ms","start":"2026-09-22T04:45:28.211547Z","end":"2026-09-22T04:45:28.446238Z","steps":["trace[934806203] 'agreement among raft nodes before linearized reading' (duration: 234.556884ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:45:28.446244Z","caller":"traceutil/trace.go:171","msg":"trace[1057463876] transaction","detail":"{read_only:false; response_revision:27116; number_of_response:1; }","duration":"234.888793ms","start":"2026-09-22T04:45:28.211222Z","end":"2026-09-22T04:45:28.446111Z","steps":["trace[1057463876] 'process raft request' (duration: 234.286026ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:45:46.099338Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24429} {"level":"info","ts":"2026-09-22T04:45:46.250551Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24429,"took":"147.055724ms","hash":4262631117,"current-db-size-bytes":57237504,"current-db-size":"57 MB","current-db-size-in-use-bytes":37167104,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-09-22T04:45:46.250622Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4262631117,"revision":24429,"compact-revision":21535} {"level":"info","ts":"2026-09-22T04:45:49.133024Z","caller":"traceutil/trace.go:171","msg":"trace[1839887103] transaction","detail":"{read_only:false; response_revision:27411; number_of_response:1; }","duration":"208.451491ms","start":"2026-09-22T04:45:48.924551Z","end":"2026-09-22T04:45:49.133003Z","steps":["trace[1839887103] 'process raft request' (duration: 208.256445ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:45:49.133243Z","caller":"traceutil/trace.go:171","msg":"trace[1307208389] transaction","detail":"{read_only:false; response_revision:27412; number_of_response:1; }","duration":"208.346948ms","start":"2026-09-22T04:45:48.924885Z","end":"2026-09-22T04:45:49.133232Z","steps":["trace[1307208389] 'process raft request' (duration: 208.07336ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:45:49.133431Z","caller":"traceutil/trace.go:171","msg":"trace[2009563692] transaction","detail":"{read_only:false; response_revision:27413; number_of_response:1; }","duration":"164.508031ms","start":"2026-09-22T04:45:48.968914Z","end":"2026-09-22T04:45:49.133422Z","steps":["trace[2009563692] 'process raft request' (duration: 164.274325ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:48:44.135103Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"8608f4af3693a53b","local-member-applied-index":30005,"local-member-snapshot-index":20004,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-22T04:48:44.148226Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30005} {"level":"info","ts":"2026-09-22T04:48:44.148487Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25005} {"level":"info","ts":"2026-09-22T04:50:46.106502Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27368} {"level":"info","ts":"2026-09-22T04:50:46.243683Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27368,"took":"133.370671ms","hash":2925898550,"current-db-size-bytes":59355136,"current-db-size":"59 MB","current-db-size-in-use-bytes":37814272,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-09-22T04:50:46.243771Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2925898550,"revision":27368,"compact-revision":24429} {"level":"info","ts":"2026-09-22T04:55:46.117887Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30127} {"level":"info","ts":"2026-09-22T04:55:46.287135Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30127,"took":"163.466183ms","hash":1723052286,"current-db-size-bytes":59355136,"current-db-size":"59 MB","current-db-size-in-use-bytes":30941184,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-22T04:55:46.287214Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1723052286,"revision":30127,"compact-revision":27368} {"level":"info","ts":"2026-09-22T05:00:46.126329Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32381} {"level":"info","ts":"2026-09-22T05:00:46.289505Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32381,"took":"156.997898ms","hash":3269898263,"current-db-size-bytes":59355136,"current-db-size":"59 MB","current-db-size-in-use-bytes":30507008,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-22T05:00:46.289595Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3269898263,"revision":32381,"compact-revision":30127} {"level":"info","ts":"2026-09-22T05:05:46.135639Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34666} {"level":"info","ts":"2026-09-22T05:05:46.273584Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34666,"took":"133.252475ms","hash":1383402165,"current-db-size-bytes":59355136,"current-db-size":"59 MB","current-db-size-in-use-bytes":30965760,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-22T05:05:46.273731Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1383402165,"revision":34666,"compact-revision":32381} {"level":"info","ts":"2026-09-22T05:09:45.13694Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"8608f4af3693a53b","local-member-applied-index":40006,"local-member-snapshot-index":30005,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-22T05:09:45.143231Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40006} {"level":"info","ts":"2026-09-22T05:09:45.143354Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35006} {"level":"info","ts":"2026-09-22T05:10:46.145244Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":36927} {"level":"info","ts":"2026-09-22T05:10:46.286544Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":36927,"took":"136.655072ms","hash":811698328,"current-db-size-bytes":61050880,"current-db-size":"61 MB","current-db-size-in-use-bytes":32907264,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-09-22T05:10:46.286631Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":811698328,"revision":36927,"compact-revision":34666} {"level":"info","ts":"2026-09-22T05:15:46.152441Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39265} {"level":"info","ts":"2026-09-22T05:15:46.292251Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39265,"took":"135.315141ms","hash":165783605,"current-db-size-bytes":64028672,"current-db-size":"64 MB","current-db-size-in-use-bytes":32321536,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-22T05:15:46.292299Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":165783605,"revision":39265,"compact-revision":36927}