{"level":"warn","ts":"2026-07-03T18:52:15.890636Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-03T18:52:15.890758Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.74:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.74:2380","--initial-cluster=instance=https://199.204.45.74:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.74:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.74: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-07-03T18:52:15.890871Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-03T18:52:15.89089Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.74:2380"]} {"level":"info","ts":"2026-07-03T18:52:15.890922Z","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-07-03T18:52:15.891948Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"]} {"level":"info","ts":"2026-07-03T18:52:15.892044Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.74:2380"],"listen-peer-urls":["https://199.204.45.74:2380"],"advertise-client-urls":["https://199.204.45.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.74: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-07-03T18:52:15.896037Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.751835ms"} {"level":"info","ts":"2026-07-03T18:52:15.903707Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"89ba38f964b95aa0","cluster-id":"54492f71bf65c400"} {"level":"info","ts":"2026-07-03T18:52:15.903818Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=()"} {"level":"info","ts":"2026-07-03T18:52:15.903852Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became follower at term 0"} {"level":"info","ts":"2026-07-03T18:52:15.903878Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 89ba38f964b95aa0 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-03T18:52:15.90389Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became follower at term 1"} {"level":"info","ts":"2026-07-03T18:52:15.903941Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=(9924307372652190368)"} {"level":"warn","ts":"2026-07-03T18:52:15.91121Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-03T18:52:15.91285Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-03T18:52:15.913967Z","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-07-03T18:52:15.916824Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"89ba38f964b95aa0","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-03T18:52:15.91704Z","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-07-03T18:52:15.917122Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"89ba38f964b95aa0","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-03T18:52:15.917214Z","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-07-03T18:52:15.91724Z","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-07-03T18:52:15.917967Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=(9924307372652190368)"} {"level":"info","ts":"2026-07-03T18:52:15.91815Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"54492f71bf65c400","local-member-id":"89ba38f964b95aa0","added-peer-id":"89ba38f964b95aa0","added-peer-peer-urls":["https://199.204.45.74:2380"]} {"level":"info","ts":"2026-07-03T18:52:15.921532Z","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-07-03T18:52:15.921712Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.74:2380"} {"level":"info","ts":"2026-07-03T18:52:15.9218Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.74:2380"} {"level":"info","ts":"2026-07-03T18:52:15.921847Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"89ba38f964b95aa0","initial-advertise-peer-urls":["https://199.204.45.74:2380"],"listen-peer-urls":["https://199.204.45.74:2380"],"advertise-client-urls":["https://199.204.45.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-03T18:52:15.921908Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-03T18:52:16.405026Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 is starting a new election at term 1"} {"level":"info","ts":"2026-07-03T18:52:16.40517Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-03T18:52:16.405232Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 received MsgPreVoteResp from 89ba38f964b95aa0 at term 1"} {"level":"info","ts":"2026-07-03T18:52:16.40528Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became candidate at term 2"} {"level":"info","ts":"2026-07-03T18:52:16.405322Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 received MsgVoteResp from 89ba38f964b95aa0 at term 2"} {"level":"info","ts":"2026-07-03T18:52:16.40536Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became leader at term 2"} {"level":"info","ts":"2026-07-03T18:52:16.405397Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 89ba38f964b95aa0 elected leader 89ba38f964b95aa0 at term 2"} {"level":"info","ts":"2026-07-03T18:52:16.407288Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"89ba38f964b95aa0","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.74:2379]}","request-path":"/0/members/89ba38f964b95aa0/attributes","cluster-id":"54492f71bf65c400","publish-timeout":"7s"} {"level":"info","ts":"2026-07-03T18:52:16.407641Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-03T18:52:16.408613Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-03T18:52:16.409047Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-03T18:52:16.409264Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-03T18:52:16.409287Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-03T18:52:16.409335Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"54492f71bf65c400","local-member-id":"89ba38f964b95aa0","cluster-version":"3.5"} {"level":"info","ts":"2026-07-03T18:52:16.409425Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-03T18:52:16.409459Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-03T18:52:16.412031Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.74:2379"} {"level":"info","ts":"2026-07-03T18:52:16.412242Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-03T18:52:52.734744Z","caller":"traceutil/trace.go:171","msg":"trace[247038707] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"102.923587ms","start":"2026-07-03T18:52:52.631794Z","end":"2026-07-03T18:52:52.734718Z","steps":["trace[247038707] 'process raft request' (duration: 102.714061ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T18:52:52.734775Z","caller":"traceutil/trace.go:171","msg":"trace[1671646490] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"102.276599ms","start":"2026-07-03T18:52:52.632465Z","end":"2026-07-03T18:52:52.734741Z","steps":["trace[1671646490] 'process raft request' (duration: 102.167375ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T18:52:52.735023Z","caller":"traceutil/trace.go:171","msg":"trace[550649203] transaction","detail":"{read_only:false; number_of_response:1; response_revision:500; }","duration":"101.457206ms","start":"2026-07-03T18:52:52.633553Z","end":"2026-07-03T18:52:52.735011Z","steps":["trace[550649203] 'process raft request' (duration: 101.136107ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T18:52:52.73582Z","caller":"traceutil/trace.go:171","msg":"trace[1515960344] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"101.693392ms","start":"2026-07-03T18:52:52.634105Z","end":"2026-07-03T18:52:52.735798Z","steps":["trace[1515960344] 'process raft request' (duration: 100.775176ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T18:52:58.615403Z","caller":"traceutil/trace.go:171","msg":"trace[448219990] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"104.671806ms","start":"2026-07-03T18:52:58.510706Z","end":"2026-07-03T18:52:58.615378Z","steps":["trace[448219990] 'process raft request' (duration: 104.505321ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T18:52:59.033645Z","caller":"traceutil/trace.go:171","msg":"trace[1586169161] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"277.401854ms","start":"2026-07-03T18:52:58.756215Z","end":"2026-07-03T18:52:59.033617Z","steps":["trace[1586169161] 'process raft request' (duration: 277.235929ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T18:53:14.708973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.81229ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-03T18:53:14.709127Z","caller":"traceutil/trace.go:171","msg":"trace[379672294] linearizableReadLoop","detail":"{readStateIndex:782; appliedIndex:781; }","duration":"261.017536ms","start":"2026-07-03T18:53:14.448089Z","end":"2026-07-03T18:53:14.709106Z","steps":["trace[379672294] 'read index received' (duration: 87.180767ms)","trace[379672294] 'applied index is now lower than readState.Index' (duration: 173.835219ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T18:53:14.709208Z","caller":"traceutil/trace.go:171","msg":"trace[1027478494] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"265.873382ms","start":"2026-07-03T18:53:14.443305Z","end":"2026-07-03T18:53:14.709179Z","steps":["trace[1027478494] 'process raft request' (duration: 92.015652ms)","trace[1027478494] 'compare' (duration: 172.580274ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T18:53:14.709364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.270352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-03T18:53:14.709443Z","caller":"traceutil/trace.go:171","msg":"trace[1727209926] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:754; }","duration":"261.353395ms","start":"2026-07-03T18:53:14.448075Z","end":"2026-07-03T18:53:14.709429Z","steps":["trace[1727209926] 'agreement among raft nodes before linearized reading' (duration: 261.142569ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T18:53:14.709717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.106005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-28swn\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-07-03T18:53:14.709814Z","caller":"traceutil/trace.go:171","msg":"trace[1631811782] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-28swn; range_end:; response_count:1; response_revision:754; }","duration":"136.250358ms","start":"2026-07-03T18:53:14.573544Z","end":"2026-07-03T18:53:14.709794Z","steps":["trace[1631811782] 'agreement among raft nodes before linearized reading' (duration: 135.97759ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T18:54:32.018929Z","caller":"traceutil/trace.go:171","msg":"trace[1053965508] transaction","detail":"{read_only:false; response_revision:1822; number_of_response:1; }","duration":"108.489097ms","start":"2026-07-03T18:54:31.910406Z","end":"2026-07-03T18:54:32.018895Z","steps":["trace[1053965508] 'process raft request' (duration: 108.291301ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T18:54:32.099443Z","caller":"traceutil/trace.go:171","msg":"trace[1058053366] linearizableReadLoop","detail":"{readStateIndex:1895; appliedIndex:1893; }","duration":"116.86496ms","start":"2026-07-03T18:54:31.982541Z","end":"2026-07-03T18:54:32.099406Z","steps":["trace[1058053366] 'read index received' (duration: 36.174626ms)","trace[1058053366] 'applied index is now lower than readState.Index' (duration: 80.688844ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T18:54:32.099446Z","caller":"traceutil/trace.go:171","msg":"trace[1418349687] transaction","detail":"{read_only:false; response_revision:1823; number_of_response:1; }","duration":"179.572784ms","start":"2026-07-03T18:54:31.919838Z","end":"2026-07-03T18:54:32.099411Z","steps":["trace[1418349687] 'process raft request' (duration: 168.622059ms)","trace[1418349687] 'compare' (duration: 10.701808ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T18:54:32.099915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.351174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-193d25da-99ba-42be-aae6-9464433fbb6a\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-07-03T18:54:32.100116Z","caller":"traceutil/trace.go:171","msg":"trace[79557071] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-193d25da-99ba-42be-aae6-9464433fbb6a; range_end:; response_count:1; response_revision:1823; }","duration":"117.489208ms","start":"2026-07-03T18:54:31.982497Z","end":"2026-07-03T18:54:32.099986Z","steps":["trace[79557071] 'agreement among raft nodes before linearized reading' (duration: 117.093987ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T18:55:21.164099Z","caller":"traceutil/trace.go:171","msg":"trace[748944371] transaction","detail":"{read_only:false; response_revision:2136; number_of_response:1; }","duration":"103.726744ms","start":"2026-07-03T18:55:21.06034Z","end":"2026-07-03T18:55:21.164067Z","steps":["trace[748944371] 'process raft request' (duration: 103.58518ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T18:55:21.317278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.448749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-03T18:55:21.317747Z","caller":"traceutil/trace.go:171","msg":"trace[2118731472] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2136; }","duration":"125.980174ms","start":"2026-07-03T18:55:21.191738Z","end":"2026-07-03T18:55:21.317718Z","steps":["trace[2118731472] 'range keys from in-memory index tree' (duration: 125.240564ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T18:55:27.730098Z","caller":"traceutil/trace.go:171","msg":"trace[130021219] transaction","detail":"{read_only:false; response_revision:2170; number_of_response:1; }","duration":"144.09616ms","start":"2026-07-03T18:55:27.585966Z","end":"2026-07-03T18:55:27.730062Z","steps":["trace[130021219] 'process raft request' (duration: 143.910135ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T18:55:27.730804Z","caller":"traceutil/trace.go:171","msg":"trace[1843536072] transaction","detail":"{read_only:false; response_revision:2171; number_of_response:1; }","duration":"142.261029ms","start":"2026-07-03T18:55:27.588516Z","end":"2026-07-03T18:55:27.730777Z","steps":["trace[1843536072] 'process raft request' (duration: 142.081774ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T18:55:27.730919Z","caller":"traceutil/trace.go:171","msg":"trace[1582792275] transaction","detail":"{read_only:false; response_revision:2172; number_of_response:1; }","duration":"142.319621ms","start":"2026-07-03T18:55:27.588565Z","end":"2026-07-03T18:55:27.730884Z","steps":["trace[1582792275] 'process raft request' (duration: 142.156926ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T18:56:01.891724Z","caller":"traceutil/trace.go:171","msg":"trace[1620098683] transaction","detail":"{read_only:false; response_revision:2530; number_of_response:1; }","duration":"154.696381ms","start":"2026-07-03T18:56:01.736938Z","end":"2026-07-03T18:56:01.891634Z","steps":["trace[1620098683] 'process raft request' (duration: 154.143206ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T18:56:36.310179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.784927ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-03T18:56:36.310397Z","caller":"traceutil/trace.go:171","msg":"trace[360728953] transaction","detail":"{read_only:false; response_revision:2739; number_of_response:1; }","duration":"253.836696ms","start":"2026-07-03T18:56:36.056531Z","end":"2026-07-03T18:56:36.310368Z","steps":["trace[360728953] 'process raft request' (duration: 132.690079ms)","trace[360728953] 'compare' (duration: 120.54213ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T18:56:36.310593Z","caller":"traceutil/trace.go:171","msg":"trace[661535580] linearizableReadLoop","detail":"{readStateIndex:2847; appliedIndex:2843; }","duration":"128.009418ms","start":"2026-07-03T18:56:36.18256Z","end":"2026-07-03T18:56:36.31057Z","steps":["trace[661535580] 'read index received' (duration: 6.678076ms)","trace[661535580] 'applied index is now lower than readState.Index' (duration: 121.330032ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T18:56:36.310759Z","caller":"traceutil/trace.go:171","msg":"trace[534051261] transaction","detail":"{read_only:false; response_revision:2742; number_of_response:1; }","duration":"135.211609ms","start":"2026-07-03T18:56:36.17553Z","end":"2026-07-03T18:56:36.310742Z","steps":["trace[534051261] 'process raft request' (duration: 134.988183ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T18:56:36.310795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.234864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-07-03T18:56:36.310795Z","caller":"traceutil/trace.go:171","msg":"trace[1314931311] transaction","detail":"{read_only:false; response_revision:2740; number_of_response:1; }","duration":"253.351411ms","start":"2026-07-03T18:56:36.057407Z","end":"2026-07-03T18:56:36.310759Z","steps":["trace[1314931311] 'process raft request' (duration: 252.917409ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T18:56:36.31082Z","caller":"traceutil/trace.go:171","msg":"trace[1411573217] transaction","detail":"{read_only:false; response_revision:2741; number_of_response:1; }","duration":"253.200547ms","start":"2026-07-03T18:56:36.057544Z","end":"2026-07-03T18:56:36.310745Z","steps":["trace[1411573217] 'process raft request' (duration: 252.887098ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T18:56:36.311051Z","caller":"traceutil/trace.go:171","msg":"trace[2112500624] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2742; }","duration":"128.314046ms","start":"2026-07-03T18:56:36.182513Z","end":"2026-07-03T18:56:36.310827Z","steps":["trace[2112500624] 'agreement among raft nodes before linearized reading' (duration: 128.141432ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T18:59:02.50529Z","caller":"traceutil/trace.go:171","msg":"trace[258694233] transaction","detail":"{read_only:false; response_revision:3708; number_of_response:1; }","duration":"105.488271ms","start":"2026-07-03T18:59:02.399766Z","end":"2026-07-03T18:59:02.505254Z","steps":["trace[258694233] 'process raft request' (duration: 105.238134ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:00:31.172158Z","caller":"traceutil/trace.go:171","msg":"trace[1074849194] transaction","detail":"{read_only:false; response_revision:4889; number_of_response:1; }","duration":"127.426076ms","start":"2026-07-03T19:00:31.044706Z","end":"2026-07-03T19:00:31.172132Z","steps":["trace[1074849194] 'process raft request' (duration: 69.591672ms)","trace[1074849194] 'compare' (duration: 57.712171ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:00:31.19111Z","caller":"traceutil/trace.go:171","msg":"trace[778254013] linearizableReadLoop","detail":"{readStateIndex:5077; appliedIndex:5075; }","duration":"137.354954ms","start":"2026-07-03T19:00:31.053729Z","end":"2026-07-03T19:00:31.191084Z","steps":["trace[778254013] 'read index received' (duration: 60.490389ms)","trace[778254013] 'applied index is now lower than readState.Index' (duration: 76.863275ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:00:31.191139Z","caller":"traceutil/trace.go:171","msg":"trace[682190923] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4890; }","duration":"143.060953ms","start":"2026-07-03T19:00:31.048052Z","end":"2026-07-03T19:00:31.191113Z","steps":["trace[682190923] 'process raft request' (duration: 142.745854ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:00:31.191245Z","caller":"traceutil/trace.go:171","msg":"trace[1982793511] transaction","detail":"{read_only:false; response_revision:4891; number_of_response:1; }","duration":"112.782328ms","start":"2026-07-03T19:00:31.078431Z","end":"2026-07-03T19:00:31.191214Z","steps":["trace[1982793511] 'process raft request' (duration: 112.574082ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:31.191377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.616931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-07-03T19:00:31.191423Z","caller":"traceutil/trace.go:171","msg":"trace[1146605680] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4891; }","duration":"137.719714ms","start":"2026-07-03T19:00:31.053689Z","end":"2026-07-03T19:00:31.191409Z","steps":["trace[1146605680] 'agreement among raft nodes before linearized reading' (duration: 137.544999ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:32.092087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.939272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-07-03T19:00:32.092188Z","caller":"traceutil/trace.go:171","msg":"trace[52334003] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4924; }","duration":"108.094537ms","start":"2026-07-03T19:00:31.984071Z","end":"2026-07-03T19:00:32.092165Z","steps":["trace[52334003] 'range keys from in-memory index tree' (duration: 107.624154ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:00:34.279483Z","caller":"traceutil/trace.go:171","msg":"trace[1672437023] transaction","detail":"{read_only:false; response_revision:4961; number_of_response:1; }","duration":"151.891469ms","start":"2026-07-03T19:00:34.127559Z","end":"2026-07-03T19:00:34.279451Z","steps":["trace[1672437023] 'process raft request' (duration: 139.920455ms)","trace[1672437023] 'compare' (duration: 11.616804ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:00:38.336986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.481571ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-03T19:00:38.337178Z","caller":"traceutil/trace.go:171","msg":"trace[873252738] transaction","detail":"{read_only:false; response_revision:5035; number_of_response:1; }","duration":"199.450926ms","start":"2026-07-03T19:00:38.137705Z","end":"2026-07-03T19:00:38.337156Z","steps":["trace[873252738] 'process raft request' (duration: 68.683837ms)","trace[873252738] 'compare' (duration: 130.277986ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:00:53.256799Z","caller":"traceutil/trace.go:171","msg":"trace[2032168039] transaction","detail":"{read_only:false; response_revision:5288; number_of_response:1; }","duration":"106.349787ms","start":"2026-07-03T19:00:53.150422Z","end":"2026-07-03T19:00:53.256772Z","steps":["trace[2032168039] 'process raft request' (duration: 106.160032ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:00:53.279907Z","caller":"traceutil/trace.go:171","msg":"trace[1922121870] transaction","detail":"{read_only:false; response_revision:5289; number_of_response:1; }","duration":"129.204745ms","start":"2026-07-03T19:00:53.150678Z","end":"2026-07-03T19:00:53.279882Z","steps":["trace[1922121870] 'process raft request' (duration: 128.974659ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:00:53.280256Z","caller":"traceutil/trace.go:171","msg":"trace[1720457712] transaction","detail":"{read_only:false; response_revision:5290; number_of_response:1; }","duration":"129.551425ms","start":"2026-07-03T19:00:53.150675Z","end":"2026-07-03T19:00:53.280226Z","steps":["trace[1720457712] 'process raft request' (duration: 129.128193ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:00:53.28054Z","caller":"traceutil/trace.go:171","msg":"trace[1909553424] transaction","detail":"{read_only:false; response_revision:5291; number_of_response:1; }","duration":"129.719779ms","start":"2026-07-03T19:00:53.150794Z","end":"2026-07-03T19:00:53.280514Z","steps":["trace[1909553424] 'process raft request' (duration: 129.05199ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:00:53.281433Z","caller":"traceutil/trace.go:171","msg":"trace[640540003] transaction","detail":"{read_only:false; response_revision:5292; number_of_response:1; }","duration":"130.628684ms","start":"2026-07-03T19:00:53.150764Z","end":"2026-07-03T19:00:53.281393Z","steps":["trace[640540003] 'process raft request' (duration: 129.714579ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:00:53.281705Z","caller":"traceutil/trace.go:171","msg":"trace[2100562080] transaction","detail":"{read_only:false; response_revision:5293; number_of_response:1; }","duration":"130.886772ms","start":"2026-07-03T19:00:53.150804Z","end":"2026-07-03T19:00:53.281691Z","steps":["trace[2100562080] 'process raft request' (duration: 130.527152ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:00:53.281749Z","caller":"traceutil/trace.go:171","msg":"trace[2013223695] linearizableReadLoop","detail":"{readStateIndex:5492; appliedIndex:5485; }","duration":"101.026268ms","start":"2026-07-03T19:00:53.180674Z","end":"2026-07-03T19:00:53.2817Z","steps":["trace[2013223695] 'read index received' (duration: 75.974569ms)","trace[2013223695] 'applied index is now lower than readState.Index' (duration: 25.049849ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:00:53.281999Z","caller":"traceutil/trace.go:171","msg":"trace[1630992917] transaction","detail":"{read_only:false; response_revision:5294; number_of_response:1; }","duration":"128.970109ms","start":"2026-07-03T19:00:53.152973Z","end":"2026-07-03T19:00:53.281943Z","steps":["trace[1630992917] 'process raft request' (duration: 128.592398ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:53.282061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.361758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-07-03T19:00:53.282182Z","caller":"traceutil/trace.go:171","msg":"trace[1550102863] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5294; }","duration":"101.492691ms","start":"2026-07-03T19:00:53.180617Z","end":"2026-07-03T19:00:53.28211Z","steps":["trace[1550102863] 'agreement among raft nodes before linearized reading' (duration: 101.193193ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:02:16.68027Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2960} {"level":"info","ts":"2026-07-03T19:02:16.754243Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2960,"took":"71.867645ms","hash":879177236,"current-db-size-bytes":57806848,"current-db-size":"58 MB","current-db-size-in-use-bytes":34353152,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-03T19:02:16.754321Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":879177236,"revision":2960,"compact-revision":-1} {"level":"info","ts":"2026-07-03T19:06:46.628734Z","caller":"traceutil/trace.go:171","msg":"trace[1039393584] transaction","detail":"{read_only:false; response_revision:7965; number_of_response:1; }","duration":"121.486711ms","start":"2026-07-03T19:06:46.50722Z","end":"2026-07-03T19:06:46.628707Z","steps":["trace[1039393584] 'process raft request' (duration: 121.315106ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:06:54.8294Z","caller":"traceutil/trace.go:171","msg":"trace[858798151] transaction","detail":"{read_only:false; response_revision:8138; number_of_response:1; }","duration":"162.457684ms","start":"2026-07-03T19:06:54.666912Z","end":"2026-07-03T19:06:54.82937Z","steps":["trace[858798151] 'process raft request' (duration: 162.248638ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:06:55.001757Z","caller":"traceutil/trace.go:171","msg":"trace[1175361868] transaction","detail":"{read_only:false; response_revision:8139; number_of_response:1; }","duration":"190.013133ms","start":"2026-07-03T19:06:54.811716Z","end":"2026-07-03T19:06:55.00173Z","steps":["trace[1175361868] 'process raft request' (duration: 109.651ms)","trace[1175361868] 'compare' (duration: 79.976972ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:06:55.00305Z","caller":"traceutil/trace.go:171","msg":"trace[312241977] transaction","detail":"{read_only:false; response_revision:8140; number_of_response:1; }","duration":"163.806721ms","start":"2026-07-03T19:06:54.839231Z","end":"2026-07-03T19:06:55.003038Z","steps":["trace[312241977] 'process raft request' (duration: 163.727339ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:07:05.973317Z","caller":"traceutil/trace.go:171","msg":"trace[725593309] transaction","detail":"{read_only:false; response_revision:8200; number_of_response:1; }","duration":"129.806763ms","start":"2026-07-03T19:07:05.843474Z","end":"2026-07-03T19:07:05.97328Z","steps":["trace[725593309] 'process raft request' (duration: 58.149383ms)","trace[725593309] 'compare' (duration: 71.478195ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:07:06.64961Z","caller":"traceutil/trace.go:171","msg":"trace[1207103453] linearizableReadLoop","detail":"{readStateIndex:8491; appliedIndex:8490; }","duration":"186.66922ms","start":"2026-07-03T19:07:06.462921Z","end":"2026-07-03T19:07:06.649591Z","steps":["trace[1207103453] 'read index received' (duration: 186.468304ms)","trace[1207103453] 'applied index is now lower than readState.Index' (duration: 200.306µs)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:07:06.649683Z","caller":"traceutil/trace.go:171","msg":"trace[898315504] transaction","detail":"{read_only:false; response_revision:8201; number_of_response:1; }","duration":"248.844865ms","start":"2026-07-03T19:07:06.400819Z","end":"2026-07-03T19:07:06.649664Z","steps":["trace[898315504] 'process raft request' (duration: 248.64411ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:07:06.649719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.789993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T19:07:06.649741Z","caller":"traceutil/trace.go:171","msg":"trace[900500094] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8201; }","duration":"186.885686ms","start":"2026-07-03T19:07:06.462849Z","end":"2026-07-03T19:07:06.649735Z","steps":["trace[900500094] 'agreement among raft nodes before linearized reading' (duration: 186.822184ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:07:07.549611Z","caller":"traceutil/trace.go:171","msg":"trace[1816521855] transaction","detail":"{read_only:false; response_revision:8205; number_of_response:1; }","duration":"236.164122ms","start":"2026-07-03T19:07:07.313399Z","end":"2026-07-03T19:07:07.549563Z","steps":["trace[1816521855] 'process raft request' (duration: 235.892684ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:07:16.69065Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6091} {"level":"info","ts":"2026-07-03T19:07:16.785681Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6091,"took":"92.156322ms","hash":4090728024,"current-db-size-bytes":57806848,"current-db-size":"58 MB","current-db-size-in-use-bytes":23662592,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-07-03T19:07:16.785768Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4090728024,"revision":6091,"compact-revision":2960} {"level":"info","ts":"2026-07-03T19:07:35.902838Z","caller":"traceutil/trace.go:171","msg":"trace[1771957795] linearizableReadLoop","detail":"{readStateIndex:8711; appliedIndex:8710; }","duration":"180.824727ms","start":"2026-07-03T19:07:35.721991Z","end":"2026-07-03T19:07:35.902816Z","steps":["trace[1771957795] 'read index received' (duration: 180.687053ms)","trace[1771957795] 'applied index is now lower than readState.Index' (duration: 136.154µs)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:07:35.90314Z","caller":"traceutil/trace.go:171","msg":"trace[1627405549] transaction","detail":"{read_only:false; response_revision:8412; number_of_response:1; }","duration":"182.120702ms","start":"2026-07-03T19:07:35.721007Z","end":"2026-07-03T19:07:35.903128Z","steps":["trace[1627405549] 'process raft request' (duration: 181.595018ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:07:35.90353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.76452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-03T19:07:35.903586Z","caller":"traceutil/trace.go:171","msg":"trace[1071999506] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:8412; }","duration":"149.839362ms","start":"2026-07-03T19:07:35.753735Z","end":"2026-07-03T19:07:35.903574Z","steps":["trace[1071999506] 'agreement among raft nodes before linearized reading' (duration: 149.606825ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:07:35.903848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.872346ms","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-07-03T19:07:35.903885Z","caller":"traceutil/trace.go:171","msg":"trace[933455052] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8412; }","duration":"181.969188ms","start":"2026-07-03T19:07:35.721907Z","end":"2026-07-03T19:07:35.903876Z","steps":["trace[933455052] 'agreement among raft nodes before linearized reading' (duration: 181.853855ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:07:35.904387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.780131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-07-03T19:07:35.904429Z","caller":"traceutil/trace.go:171","msg":"trace[419760059] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:8412; }","duration":"115.844324ms","start":"2026-07-03T19:07:35.788574Z","end":"2026-07-03T19:07:35.904419Z","steps":["trace[419760059] 'agreement among raft nodes before linearized reading' (duration: 115.754531ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:07:35.904544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.971547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-03T19:07:35.904576Z","caller":"traceutil/trace.go:171","msg":"trace[445582425] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8412; }","duration":"140.029699ms","start":"2026-07-03T19:07:35.764538Z","end":"2026-07-03T19:07:35.904568Z","steps":["trace[445582425] 'agreement among raft nodes before linearized reading' (duration: 139.954147ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:07:48.837662Z","caller":"traceutil/trace.go:171","msg":"trace[1178147848] transaction","detail":"{read_only:false; response_revision:8524; number_of_response:1; }","duration":"161.763935ms","start":"2026-07-03T19:07:48.675865Z","end":"2026-07-03T19:07:48.837629Z","steps":["trace[1178147848] 'process raft request' (duration: 161.553809ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:07:49.986211Z","caller":"traceutil/trace.go:171","msg":"trace[322574592] linearizableReadLoop","detail":"{readStateIndex:8833; appliedIndex:8832; }","duration":"203.441638ms","start":"2026-07-03T19:07:49.782743Z","end":"2026-07-03T19:07:49.986185Z","steps":["trace[322574592] 'read index received' (duration: 203.289454ms)","trace[322574592] 'applied index is now lower than readState.Index' (duration: 150.944µs)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:07:49.98631Z","caller":"traceutil/trace.go:171","msg":"trace[736497004] transaction","detail":"{read_only:false; response_revision:8530; number_of_response:1; }","duration":"274.523712ms","start":"2026-07-03T19:07:49.711757Z","end":"2026-07-03T19:07:49.986281Z","steps":["trace[736497004] 'process raft request' (duration: 274.222764ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:07:49.986497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.731096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.74\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-03T19:07:49.986587Z","caller":"traceutil/trace.go:171","msg":"trace[115976085] range","detail":"{range_begin:/registry/masterleases/199.204.45.74; range_end:; response_count:1; response_revision:8530; }","duration":"203.8668ms","start":"2026-07-03T19:07:49.782699Z","end":"2026-07-03T19:07:49.986566Z","steps":["trace[115976085] 'agreement among raft nodes before linearized reading' (duration: 203.594702ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:08:05.111599Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000231d.wal"} {"level":"info","ts":"2026-07-03T19:09:57.922145Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"89ba38f964b95aa0","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-03T19:09:57.926847Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-03T19:09:57.926953Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-03T19:11:26.228448Z","caller":"traceutil/trace.go:171","msg":"trace[1161278406] transaction","detail":"{read_only:false; response_revision:10338; number_of_response:1; }","duration":"129.773076ms","start":"2026-07-03T19:11:26.098646Z","end":"2026-07-03T19:11:26.22842Z","steps":["trace[1161278406] 'process raft request' (duration: 129.613782ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:11:26.240903Z","caller":"traceutil/trace.go:171","msg":"trace[1015349187] transaction","detail":"{read_only:false; response_revision:10339; number_of_response:1; }","duration":"109.725917ms","start":"2026-07-03T19:11:26.131156Z","end":"2026-07-03T19:11:26.240882Z","steps":["trace[1015349187] 'process raft request' (duration: 109.577153ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:12:16.696107Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8260} {"level":"info","ts":"2026-07-03T19:12:16.773057Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8260,"took":"74.850785ms","hash":1536972921,"current-db-size-bytes":57806848,"current-db-size":"58 MB","current-db-size-in-use-bytes":25440256,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-07-03T19:12:16.773146Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1536972921,"revision":8260,"compact-revision":6091} {"level":"info","ts":"2026-07-03T19:12:51.760996Z","caller":"traceutil/trace.go:171","msg":"trace[1074563181] transaction","detail":"{read_only:false; response_revision:11142; number_of_response:1; }","duration":"115.625912ms","start":"2026-07-03T19:12:51.645333Z","end":"2026-07-03T19:12:51.760959Z","steps":["trace[1074563181] 'process raft request' (duration: 115.394046ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:17:16.704074Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10760} {"level":"info","ts":"2026-07-03T19:17:16.780301Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10760,"took":"74.356622ms","hash":1728081062,"current-db-size-bytes":57806848,"current-db-size":"58 MB","current-db-size-in-use-bytes":27783168,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-07-03T19:17:16.780363Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1728081062,"revision":10760,"compact-revision":8260} {"level":"info","ts":"2026-07-03T19:18:41.921924Z","caller":"traceutil/trace.go:171","msg":"trace[259445195] transaction","detail":"{read_only:false; response_revision:13536; number_of_response:1; }","duration":"129.304313ms","start":"2026-07-03T19:18:41.792585Z","end":"2026-07-03T19:18:41.921889Z","steps":["trace[259445195] 'process raft request' (duration: 125.523608ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:19:16.815492Z","caller":"traceutil/trace.go:171","msg":"trace[2041046225] transaction","detail":"{read_only:false; response_revision:13735; number_of_response:1; }","duration":"107.980029ms","start":"2026-07-03T19:19:16.707479Z","end":"2026-07-03T19:19:16.815459Z","steps":["trace[2041046225] 'process raft request' (duration: 107.520266ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:19:39.155354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.358014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b7eb27ac-fb63-4101-a0c6-7c1cad5e7b36\" ","response":"range_response_count:1 size:3636"} {"level":"info","ts":"2026-07-03T19:19:39.155473Z","caller":"traceutil/trace.go:171","msg":"trace[1666595726] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b7eb27ac-fb63-4101-a0c6-7c1cad5e7b36; range_end:; response_count:1; response_revision:14046; }","duration":"120.534359ms","start":"2026-07-03T19:19:39.034908Z","end":"2026-07-03T19:19:39.155442Z","steps":["trace[1666595726] 'range keys from in-memory index tree' (duration: 120.076236ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:19:39.341565Z","caller":"traceutil/trace.go:171","msg":"trace[1748560263] linearizableReadLoop","detail":"{readStateIndex:14533; appliedIndex:14532; }","duration":"144.860276ms","start":"2026-07-03T19:19:39.196668Z","end":"2026-07-03T19:19:39.341528Z","steps":["trace[1748560263] 'read index received' (duration: 144.526896ms)","trace[1748560263] 'applied index is now lower than readState.Index' (duration: 332.33µs)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:19:39.341682Z","caller":"traceutil/trace.go:171","msg":"trace[1945203894] transaction","detail":"{read_only:false; response_revision:14047; number_of_response:1; }","duration":"179.135941ms","start":"2026-07-03T19:19:39.162506Z","end":"2026-07-03T19:19:39.341642Z","steps":["trace[1945203894] 'process raft request' (duration: 178.73964ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:19:39.341889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.180985ms","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-07-03T19:19:39.341949Z","caller":"traceutil/trace.go:171","msg":"trace[1097186825] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:14047; }","duration":"145.301129ms","start":"2026-07-03T19:19:39.196626Z","end":"2026-07-03T19:19:39.341927Z","steps":["trace[1097186825] 'agreement among raft nodes before linearized reading' (duration: 145.025771ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:22:16.746308Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12957} {"level":"info","ts":"2026-07-03T19:22:16.831787Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12957,"took":"80.542313ms","hash":1784561519,"current-db-size-bytes":57806848,"current-db-size":"58 MB","current-db-size-in-use-bytes":30093312,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-03T19:22:16.831852Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1784561519,"revision":12957,"compact-revision":10760} {"level":"info","ts":"2026-07-03T19:26:58.483989Z","caller":"traceutil/trace.go:171","msg":"trace[1530546660] transaction","detail":"{read_only:false; response_revision:17475; number_of_response:1; }","duration":"106.457247ms","start":"2026-07-03T19:26:58.3775Z","end":"2026-07-03T19:26:58.483957Z","steps":["trace[1530546660] 'process raft request' (duration: 106.285342ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:26:58.714043Z","caller":"traceutil/trace.go:171","msg":"trace[113367827] transaction","detail":"{read_only:false; response_revision:17476; number_of_response:1; }","duration":"335.091877ms","start":"2026-07-03T19:26:58.378924Z","end":"2026-07-03T19:26:58.714016Z","steps":["trace[113367827] 'process raft request' (duration: 334.824789ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:26:58.714252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:26:58.378888Z","time spent":"335.261001ms","remote":"127.0.0.1:34104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T19:26:58.714474Z","caller":"traceutil/trace.go:171","msg":"trace[930303091] linearizableReadLoop","detail":"{readStateIndex:18072; appliedIndex:18070; }","duration":"250.893828ms","start":"2026-07-03T19:26:58.463545Z","end":"2026-07-03T19:26:58.714439Z","steps":["trace[930303091] 'read index received' (duration: 20.462029ms)","trace[930303091] 'applied index is now lower than readState.Index' (duration: 230.429949ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:26:58.714686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.118084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-03T19:26:58.714739Z","caller":"traceutil/trace.go:171","msg":"trace[2082460397] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17477; }","duration":"251.243377ms","start":"2026-07-03T19:26:58.463484Z","end":"2026-07-03T19:26:58.714728Z","steps":["trace[2082460397] 'agreement among raft nodes before linearized reading' (duration: 251.091153ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:26:58.71475Z","caller":"traceutil/trace.go:171","msg":"trace[1104269084] transaction","detail":"{read_only:false; response_revision:17477; number_of_response:1; }","duration":"333.797461ms","start":"2026-07-03T19:26:58.380939Z","end":"2026-07-03T19:26:58.714736Z","steps":["trace[1104269084] 'process raft request' (duration: 333.022129ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:26:58.714813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:26:58.380928Z","time spent":"333.853722ms","remote":"127.0.0.1:34104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T19:26:59.039882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.547816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-03T19:26:59.039966Z","caller":"traceutil/trace.go:171","msg":"trace[888196985] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:17478; }","duration":"193.648048ms","start":"2026-07-03T19:26:58.846298Z","end":"2026-07-03T19:26:59.039946Z","steps":["trace[888196985] 'count revisions from in-memory index tree' (duration: 193.453063ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:27:07.429553Z","caller":"traceutil/trace.go:171","msg":"trace[303177489] linearizableReadLoop","detail":"{readStateIndex:18247; appliedIndex:18245; }","duration":"110.604932ms","start":"2026-07-03T19:27:07.31892Z","end":"2026-07-03T19:27:07.429525Z","steps":["trace[303177489] 'read index received' (duration: 62.364222ms)","trace[303177489] 'applied index is now lower than readState.Index' (duration: 48.23951ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:27:07.429824Z","caller":"traceutil/trace.go:171","msg":"trace[1928839629] transaction","detail":"{read_only:false; response_revision:17646; number_of_response:1; }","duration":"130.845125ms","start":"2026-07-03T19:27:07.298965Z","end":"2026-07-03T19:27:07.429811Z","steps":["trace[1928839629] 'process raft request' (duration: 81.963837ms)","trace[1928839629] 'compare' (duration: 48.351433ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:27:07.430001Z","caller":"traceutil/trace.go:171","msg":"trace[501162970] transaction","detail":"{read_only:false; response_revision:17647; number_of_response:1; }","duration":"128.213811ms","start":"2026-07-03T19:27:07.301751Z","end":"2026-07-03T19:27:07.429965Z","steps":["trace[501162970] 'process raft request' (duration: 127.694066ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:27:07.430239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.28549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:709"} {"level":"info","ts":"2026-07-03T19:27:07.430341Z","caller":"traceutil/trace.go:171","msg":"trace[667314754] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:17647; }","duration":"111.437205ms","start":"2026-07-03T19:27:07.318883Z","end":"2026-07-03T19:27:07.43032Z","steps":["trace[667314754] 'agreement among raft nodes before linearized reading' (duration: 111.128316ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:27:07.435592Z","caller":"traceutil/trace.go:171","msg":"trace[247600702] transaction","detail":"{read_only:false; response_revision:17648; number_of_response:1; }","duration":"113.211244ms","start":"2026-07-03T19:27:07.322346Z","end":"2026-07-03T19:27:07.435557Z","steps":["trace[247600702] 'process raft request' (duration: 112.901576ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:27:07.435691Z","caller":"traceutil/trace.go:171","msg":"trace[1754318641] transaction","detail":"{read_only:false; response_revision:17649; number_of_response:1; }","duration":"108.74307ms","start":"2026-07-03T19:27:07.326927Z","end":"2026-07-03T19:27:07.43567Z","steps":["trace[1754318641] 'process raft request' (duration: 108.549085ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:27:07.43596Z","caller":"traceutil/trace.go:171","msg":"trace[811912603] transaction","detail":"{read_only:false; response_revision:17650; number_of_response:1; }","duration":"102.96731ms","start":"2026-07-03T19:27:07.332948Z","end":"2026-07-03T19:27:07.435915Z","steps":["trace[811912603] 'process raft request' (duration: 102.574539ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:27:16.75211Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15318} {"level":"info","ts":"2026-07-03T19:27:16.848748Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15318,"took":"93.559559ms","hash":2492690889,"current-db-size-bytes":57806848,"current-db-size":"58 MB","current-db-size-in-use-bytes":32059392,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-03T19:27:16.8489Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2492690889,"revision":15318,"compact-revision":12957} {"level":"info","ts":"2026-07-03T19:30:45.981963Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"89ba38f964b95aa0","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-03T19:30:45.987001Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-03T19:30:45.987203Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-07-03T19:32:16.757948Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17729} {"level":"info","ts":"2026-07-03T19:32:16.856187Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17729,"took":"94.528873ms","hash":477158109,"current-db-size-bytes":57806848,"current-db-size":"58 MB","current-db-size-in-use-bytes":33959936,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-03T19:32:16.856378Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":477158109,"revision":17729,"compact-revision":15318} {"level":"warn","ts":"2026-07-03T19:32:25.583649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.926777ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-03T19:32:25.58376Z","caller":"traceutil/trace.go:171","msg":"trace[662610352] linearizableReadLoop","detail":"{readStateIndex:20737; appliedIndex:20736; }","duration":"212.075505ms","start":"2026-07-03T19:32:25.371666Z","end":"2026-07-03T19:32:25.583741Z","steps":["trace[662610352] 'read index received' (duration: 65.066626ms)","trace[662610352] 'applied index is now lower than readState.Index' (duration: 147.006979ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:32:25.583851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.186308ms","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-07-03T19:32:25.583873Z","caller":"traceutil/trace.go:171","msg":"trace[1920187359] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:20054; }","duration":"212.26988ms","start":"2026-07-03T19:32:25.371594Z","end":"2026-07-03T19:32:25.583864Z","steps":["trace[1920187359] 'agreement among raft nodes before linearized reading' (duration: 212.193518ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:32:42.036556Z","caller":"traceutil/trace.go:171","msg":"trace[1097511150] transaction","detail":"{read_only:false; response_revision:20138; number_of_response:1; }","duration":"119.040864ms","start":"2026-07-03T19:32:41.917478Z","end":"2026-07-03T19:32:42.036519Z","steps":["trace[1097511150] 'process raft request' (duration: 118.792727ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:32:42.289549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.832547ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-03T19:32:42.28968Z","caller":"traceutil/trace.go:171","msg":"trace[660466254] linearizableReadLoop","detail":"{readStateIndex:20826; appliedIndex:20825; }","duration":"176.59766ms","start":"2026-07-03T19:32:42.113061Z","end":"2026-07-03T19:32:42.289659Z","steps":["trace[660466254] 'read index received' (duration: 39.480195ms)","trace[660466254] 'applied index is now lower than readState.Index' (duration: 137.115925ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:32:42.289697Z","caller":"traceutil/trace.go:171","msg":"trace[324032462] transaction","detail":"{read_only:false; response_revision:20139; number_of_response:1; }","duration":"185.069955ms","start":"2026-07-03T19:32:42.104592Z","end":"2026-07-03T19:32:42.289662Z","steps":["trace[324032462] 'process raft request' (duration: 48.012212ms)","trace[324032462] 'compare' (duration: 136.604781ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:32:42.289888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.826337ms","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-07-03T19:32:42.289924Z","caller":"traceutil/trace.go:171","msg":"trace[607562818] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20139; }","duration":"176.861648ms","start":"2026-07-03T19:32:42.11305Z","end":"2026-07-03T19:32:42.289911Z","steps":["trace[607562818] 'agreement among raft nodes before linearized reading' (duration: 176.709934ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:32:42.29022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.56876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-03T19:32:42.290316Z","caller":"traceutil/trace.go:171","msg":"trace[496751779] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20139; }","duration":"176.725304ms","start":"2026-07-03T19:32:42.113567Z","end":"2026-07-03T19:32:42.290293Z","steps":["trace[496751779] 'agreement among raft nodes before linearized reading' (duration: 176.424015ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:32:42.482576Z","caller":"traceutil/trace.go:171","msg":"trace[821156865] linearizableReadLoop","detail":"{readStateIndex:20828; appliedIndex:20827; }","duration":"120.308198ms","start":"2026-07-03T19:32:42.362231Z","end":"2026-07-03T19:32:42.48254Z","steps":["trace[821156865] 'read index received' (duration: 119.969979ms)","trace[821156865] 'applied index is now lower than readState.Index' (duration: 336.589µs)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:32:42.482635Z","caller":"traceutil/trace.go:171","msg":"trace[40835909] transaction","detail":"{read_only:false; response_revision:20141; number_of_response:1; }","duration":"185.24044ms","start":"2026-07-03T19:32:42.297342Z","end":"2026-07-03T19:32:42.482582Z","steps":["trace[40835909] 'process raft request' (duration: 184.975413ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:32:42.482837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.587776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-07-03T19:32:42.482917Z","caller":"traceutil/trace.go:171","msg":"trace[1796195347] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20141; }","duration":"120.715859ms","start":"2026-07-03T19:32:42.362182Z","end":"2026-07-03T19:32:42.482898Z","steps":["trace[1796195347] 'agreement among raft nodes before linearized reading' (duration: 120.461952ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:32:42.482951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.315815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1854"} {"level":"info","ts":"2026-07-03T19:32:42.483019Z","caller":"traceutil/trace.go:171","msg":"trace[1044814680] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:20141; }","duration":"117.448059ms","start":"2026-07-03T19:32:42.365547Z","end":"2026-07-03T19:32:42.482995Z","steps":["trace[1044814680] 'agreement among raft nodes before linearized reading' (duration: 117.192242ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:32:42.68228Z","caller":"traceutil/trace.go:171","msg":"trace[766878058] linearizableReadLoop","detail":"{readStateIndex:20829; appliedIndex:20828; }","duration":"194.312212ms","start":"2026-07-03T19:32:42.487937Z","end":"2026-07-03T19:32:42.682249Z","steps":["trace[766878058] 'read index received' (duration: 116.623496ms)","trace[766878058] 'applied index is now lower than readState.Index' (duration: 77.687396ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:32:42.682383Z","caller":"traceutil/trace.go:171","msg":"trace[1375161132] transaction","detail":"{read_only:false; response_revision:20142; number_of_response:1; }","duration":"194.555788ms","start":"2026-07-03T19:32:42.487771Z","end":"2026-07-03T19:32:42.682327Z","steps":["trace[1375161132] 'process raft request' (duration: 116.901773ms)","trace[1375161132] 'compare' (duration: 77.346316ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:32:42.682741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.087045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18260"} {"level":"info","ts":"2026-07-03T19:32:42.682793Z","caller":"traceutil/trace.go:171","msg":"trace[1140204976] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:20142; }","duration":"194.174808ms","start":"2026-07-03T19:32:42.488605Z","end":"2026-07-03T19:32:42.68278Z","steps":["trace[1140204976] 'agreement among raft nodes before linearized reading' (duration: 193.822198ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:32:42.682908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.944809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-193d25da-99ba-42be-aae6-9464433fbb6a\" ","response":"range_response_count:1 size:1304"} {"level":"info","ts":"2026-07-03T19:32:42.683019Z","caller":"traceutil/trace.go:171","msg":"trace[1652674786] range","detail":"{range_begin:/registry/persistentvolumes/pvc-193d25da-99ba-42be-aae6-9464433fbb6a; range_end:; response_count:1; response_revision:20142; }","duration":"195.114264ms","start":"2026-07-03T19:32:42.487878Z","end":"2026-07-03T19:32:42.682992Z","steps":["trace[1652674786] 'agreement among raft nodes before linearized reading' (duration: 194.483867ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:36:33.123405Z","caller":"traceutil/trace.go:171","msg":"trace[206379416] transaction","detail":"{read_only:false; response_revision:22567; number_of_response:1; }","duration":"339.617864ms","start":"2026-07-03T19:36:32.783747Z","end":"2026-07-03T19:36:33.123365Z","steps":["trace[206379416] 'process raft request' (duration: 339.175402ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:36:33.123603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:36:32.783707Z","time spent":"339.793269ms","remote":"127.0.0.1:40380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418157,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T19:36:33.123607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.545931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-07-03T19:36:33.123669Z","caller":"traceutil/trace.go:171","msg":"trace[347858607] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:22567; }","duration":"304.690584ms","start":"2026-07-03T19:36:32.818968Z","end":"2026-07-03T19:36:33.123659Z","steps":["trace[347858607] 'agreement among raft nodes before linearized reading' (duration: 304.475068ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:36:33.123711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:36:32.818943Z","time spent":"304.761907ms","remote":"127.0.0.1:33988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-07-03T19:36:33.123376Z","caller":"traceutil/trace.go:171","msg":"trace[997321240] linearizableReadLoop","detail":"{readStateIndex:23330; appliedIndex:23329; }","duration":"304.291374ms","start":"2026-07-03T19:36:32.819025Z","end":"2026-07-03T19:36:33.123316Z","steps":["trace[997321240] 'read index received' (duration: 303.848112ms)","trace[997321240] 'applied index is now lower than readState.Index' (duration: 442.142µs)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:36:33.160268Z","caller":"traceutil/trace.go:171","msg":"trace[1707125313] transaction","detail":"{read_only:false; response_revision:22568; number_of_response:1; }","duration":"259.600164ms","start":"2026-07-03T19:36:32.900621Z","end":"2026-07-03T19:36:33.160221Z","steps":["trace[1707125313] 'process raft request' (duration: 242.617693ms)","trace[1707125313] 'compare' (duration: 16.717654ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:37:16.765461Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20010} {"level":"info","ts":"2026-07-03T19:37:16.868339Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20010,"took":"99.47863ms","hash":1478061519,"current-db-size-bytes":57806848,"current-db-size":"58 MB","current-db-size-in-use-bytes":42246144,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-07-03T19:37:16.868427Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1478061519,"revision":20010,"compact-revision":17729} {"level":"info","ts":"2026-07-03T19:40:02.16324Z","caller":"traceutil/trace.go:171","msg":"trace[1030584271] transaction","detail":"{read_only:false; response_revision:24676; number_of_response:1; }","duration":"132.702646ms","start":"2026-07-03T19:40:02.030513Z","end":"2026-07-03T19:40:02.163216Z","steps":["trace[1030584271] 'process raft request' (duration: 126.399491ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:02.459009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.395271ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-03T19:40:02.459214Z","caller":"traceutil/trace.go:171","msg":"trace[171476896] transaction","detail":"{read_only:false; response_revision:24677; number_of_response:1; }","duration":"383.895663ms","start":"2026-07-03T19:40:02.075262Z","end":"2026-07-03T19:40:02.459158Z","steps":["trace[171476896] 'process raft request' (duration: 230.276446ms)","trace[171476896] 'compare' (duration: 153.037151ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:40:02.459273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:40:02.07523Z","time spent":"384.014827ms","remote":"127.0.0.1:34104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T19:40:02.459429Z","caller":"traceutil/trace.go:171","msg":"trace[600433492] transaction","detail":"{read_only:false; response_revision:24678; number_of_response:1; }","duration":"166.575327ms","start":"2026-07-03T19:40:02.292818Z","end":"2026-07-03T19:40:02.459394Z","steps":["trace[600433492] 'process raft request' (duration: 166.292279ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:02.817682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.418081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-03T19:40:02.817758Z","caller":"traceutil/trace.go:171","msg":"trace[1413820078] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:24678; }","duration":"234.574996ms","start":"2026-07-03T19:40:02.583165Z","end":"2026-07-03T19:40:02.81774Z","steps":["trace[1413820078] 'count revisions from in-memory index tree' (duration: 234.316139ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:03.168692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.889428ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-03T19:40:03.168809Z","caller":"traceutil/trace.go:171","msg":"trace[1731504071] transaction","detail":"{read_only:false; response_revision:24680; number_of_response:1; }","duration":"262.882163ms","start":"2026-07-03T19:40:02.905903Z","end":"2026-07-03T19:40:03.168785Z","steps":["trace[1731504071] 'process raft request' (duration: 114.83589ms)","trace[1731504071] 'compare' (duration: 147.786065ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:40:03.168846Z","caller":"traceutil/trace.go:171","msg":"trace[1893065981] linearizableReadLoop","detail":"{readStateIndex:25499; appliedIndex:25498; }","duration":"240.308376ms","start":"2026-07-03T19:40:02.928523Z","end":"2026-07-03T19:40:03.168831Z","steps":["trace[1893065981] 'read index received' (duration: 92.225832ms)","trace[1893065981] 'applied index is now lower than readState.Index' (duration: 148.081244ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:40:03.168866Z","caller":"traceutil/trace.go:171","msg":"trace[598546445] transaction","detail":"{read_only:false; response_revision:24682; number_of_response:1; }","duration":"199.170783ms","start":"2026-07-03T19:40:02.969687Z","end":"2026-07-03T19:40:03.168857Z","steps":["trace[598546445] 'process raft request' (duration: 199.140812ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:03.168992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.4789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-07-03T19:40:03.168954Z","caller":"traceutil/trace.go:171","msg":"trace[176519914] transaction","detail":"{read_only:false; response_revision:24681; number_of_response:1; }","duration":"201.903418ms","start":"2026-07-03T19:40:02.967007Z","end":"2026-07-03T19:40:03.168911Z","steps":["trace[176519914] 'process raft request' (duration: 201.784415ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:03.16901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.316438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-03T19:40:03.169041Z","caller":"traceutil/trace.go:171","msg":"trace[1032216948] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24682; }","duration":"131.392389ms","start":"2026-07-03T19:40:03.03764Z","end":"2026-07-03T19:40:03.169033Z","steps":["trace[1032216948] 'agreement among raft nodes before linearized reading' (duration: 131.302567ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:40:03.169016Z","caller":"traceutil/trace.go:171","msg":"trace[2063641824] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24682; }","duration":"240.550032ms","start":"2026-07-03T19:40:02.928459Z","end":"2026-07-03T19:40:03.169009Z","steps":["trace[2063641824] 'agreement among raft nodes before linearized reading' (duration: 240.441419ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:40:03.547817Z","caller":"traceutil/trace.go:171","msg":"trace[427712834] transaction","detail":"{read_only:false; response_revision:24683; number_of_response:1; }","duration":"374.718909ms","start":"2026-07-03T19:40:03.173059Z","end":"2026-07-03T19:40:03.547778Z","steps":["trace[427712834] 'process raft request' (duration: 331.810507ms)","trace[427712834] 'compare' (duration: 42.227463ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:40:03.547973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:40:03.173034Z","time spent":"374.849442ms","remote":"127.0.0.1:34104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T19:40:03.54798Z","caller":"traceutil/trace.go:171","msg":"trace[1626799816] linearizableReadLoop","detail":"{readStateIndex:25502; appliedIndex:25501; }","duration":"374.646027ms","start":"2026-07-03T19:40:03.173294Z","end":"2026-07-03T19:40:03.54794Z","steps":["trace[1626799816] 'read index received' (duration: 331.589741ms)","trace[1626799816] 'applied index is now lower than readState.Index' (duration: 43.053225ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:40:03.5481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.75459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18229"} {"level":"info","ts":"2026-07-03T19:40:03.54819Z","caller":"traceutil/trace.go:171","msg":"trace[911986868] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24683; }","duration":"374.924655ms","start":"2026-07-03T19:40:03.173244Z","end":"2026-07-03T19:40:03.548169Z","steps":["trace[911986868] 'agreement among raft nodes before linearized reading' (duration: 374.597886ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:03.548314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:40:03.173227Z","time spent":"375.056918ms","remote":"127.0.0.1:33974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18252,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-07-03T19:40:03.551535Z","caller":"traceutil/trace.go:171","msg":"trace[2146169307] transaction","detail":"{read_only:false; response_revision:24684; number_of_response:1; }","duration":"377.850035ms","start":"2026-07-03T19:40:03.173653Z","end":"2026-07-03T19:40:03.551503Z","steps":["trace[2146169307] 'process raft request' (duration: 377.688571ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:03.551724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.038056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-03T19:40:03.55175Z","caller":"traceutil/trace.go:171","msg":"trace[1375156844] transaction","detail":"{read_only:false; response_revision:24685; number_of_response:1; }","duration":"107.529788ms","start":"2026-07-03T19:40:03.444192Z","end":"2026-07-03T19:40:03.551721Z","steps":["trace[1375156844] 'process raft request' (duration: 107.26137ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:40:03.551812Z","caller":"traceutil/trace.go:171","msg":"trace[499931738] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:24685; }","duration":"209.204251ms","start":"2026-07-03T19:40:03.342586Z","end":"2026-07-03T19:40:03.55179Z","steps":["trace[499931738] 'agreement among raft nodes before linearized reading' (duration: 209.036277ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:03.551844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.171418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-03T19:40:03.551868Z","caller":"traceutil/trace.go:171","msg":"trace[1399271504] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24685; }","duration":"282.212189ms","start":"2026-07-03T19:40:03.269648Z","end":"2026-07-03T19:40:03.55186Z","steps":["trace[1399271504] 'agreement among raft nodes before linearized reading' (duration: 282.156698ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:03.551793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.546516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-03T19:40:03.552436Z","caller":"traceutil/trace.go:171","msg":"trace[1841860681] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:24685; }","duration":"248.183954ms","start":"2026-07-03T19:40:03.304206Z","end":"2026-07-03T19:40:03.55239Z","steps":["trace[1841860681] 'agreement among raft nodes before linearized reading' (duration: 247.563907ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:03.551755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.850092ms","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-07-03T19:40:03.552559Z","caller":"traceutil/trace.go:171","msg":"trace[1737277830] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24685; }","duration":"285.697586ms","start":"2026-07-03T19:40:03.266844Z","end":"2026-07-03T19:40:03.552542Z","steps":["trace[1737277830] 'agreement among raft nodes before linearized reading' (duration: 284.772421ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:03.552908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:40:03.173634Z","time spent":"377.945359ms","remote":"127.0.0.1:34104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T19:40:03.721047Z","caller":"traceutil/trace.go:171","msg":"trace[550214995] transaction","detail":"{read_only:false; response_revision:24686; number_of_response:1; }","duration":"164.040587ms","start":"2026-07-03T19:40:03.556969Z","end":"2026-07-03T19:40:03.721009Z","steps":["trace[550214995] 'process raft request' (duration: 149.877814ms)","trace[550214995] 'compare' (duration: 13.968908ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:40:04.0373Z","caller":"traceutil/trace.go:171","msg":"trace[721260298] transaction","detail":"{read_only:false; response_revision:24687; number_of_response:1; }","duration":"474.883841ms","start":"2026-07-03T19:40:03.562386Z","end":"2026-07-03T19:40:04.03727Z","steps":["trace[721260298] 'process raft request' (duration: 459.471233ms)","trace[721260298] 'compare' (duration: 14.915074ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:40:04.037435Z","caller":"traceutil/trace.go:171","msg":"trace[771468865] linearizableReadLoop","detail":"{readStateIndex:25507; appliedIndex:25504; }","duration":"394.004554ms","start":"2026-07-03T19:40:03.643407Z","end":"2026-07-03T19:40:04.037411Z","steps":["trace[771468865] 'read index received' (duration: 63.37985ms)","trace[771468865] 'applied index is now lower than readState.Index' (duration: 330.623644ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:40:04.037469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:40:03.562355Z","time spent":"475.024905ms","remote":"127.0.0.1:34104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T19:40:04.037541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.126918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-03T19:40:04.037564Z","caller":"traceutil/trace.go:171","msg":"trace[1111365058] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24688; }","duration":"394.21489ms","start":"2026-07-03T19:40:03.643341Z","end":"2026-07-03T19:40:04.037556Z","steps":["trace[1111365058] 'agreement among raft nodes before linearized reading' (duration: 394.141668ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:04.037584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:40:03.643314Z","time spent":"394.265162ms","remote":"127.0.0.1:34280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-07-03T19:40:04.037578Z","caller":"traceutil/trace.go:171","msg":"trace[1541371156] transaction","detail":"{read_only:false; response_revision:24688; number_of_response:1; }","duration":"474.805279ms","start":"2026-07-03T19:40:03.56274Z","end":"2026-07-03T19:40:04.037545Z","steps":["trace[1541371156] 'process raft request' (duration: 474.461469ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:04.03773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:40:03.562733Z","time spent":"474.908302ms","remote":"127.0.0.1:34104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T19:40:04.301217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.30213ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-03T19:40:04.301364Z","caller":"traceutil/trace.go:171","msg":"trace[1232531006] transaction","detail":"{read_only:false; response_revision:24689; number_of_response:1; }","duration":"132.827189ms","start":"2026-07-03T19:40:04.168508Z","end":"2026-07-03T19:40:04.301335Z","steps":["trace[1232531006] 'process raft request' (duration: 16.311893ms)","trace[1232531006] 'compare' (duration: 116.038893ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:40:04.71312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.085025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-03T19:40:04.713234Z","caller":"traceutil/trace.go:171","msg":"trace[814022320] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24689; }","duration":"251.256909ms","start":"2026-07-03T19:40:04.461961Z","end":"2026-07-03T19:40:04.713218Z","steps":["trace[814022320] 'range keys from in-memory index tree' (duration: 250.996552ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:04.713653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.84249ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-03T19:40:04.713941Z","caller":"traceutil/trace.go:171","msg":"trace[486561158] transaction","detail":"{read_only:false; response_revision:24690; number_of_response:1; }","duration":"243.639757ms","start":"2026-07-03T19:40:04.470289Z","end":"2026-07-03T19:40:04.713929Z","steps":["trace[486561158] 'process raft request' (duration: 47.421487ms)","trace[486561158] 'compare' (duration: 195.48505ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:40:04.714018Z","caller":"traceutil/trace.go:171","msg":"trace[450362738] transaction","detail":"{read_only:false; response_revision:24691; number_of_response:1; }","duration":"238.042902ms","start":"2026-07-03T19:40:04.475955Z","end":"2026-07-03T19:40:04.713997Z","steps":["trace[450362738] 'process raft request' (duration: 237.861047ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:05.074258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.71036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusterclasses/\" range_end:\"/registry/cluster.x-k8s.io/clusterclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-03T19:40:05.074336Z","caller":"traceutil/trace.go:171","msg":"trace[1774706716] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusterclasses/; range_end:/registry/cluster.x-k8s.io/clusterclasses0; response_count:0; response_revision:24691; }","duration":"234.820693ms","start":"2026-07-03T19:40:04.839497Z","end":"2026-07-03T19:40:05.074317Z","steps":["trace[1774706716] 'count revisions from in-memory index tree' (duration: 234.611976ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:05.074611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.774869ms","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-07-03T19:40:05.074611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.376352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-03T19:40:05.074697Z","caller":"traceutil/trace.go:171","msg":"trace[141452063] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:24691; }","duration":"224.504696ms","start":"2026-07-03T19:40:04.850174Z","end":"2026-07-03T19:40:05.074679Z","steps":["trace[141452063] 'count revisions from in-memory index tree' (duration: 224.309241ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:40:05.074687Z","caller":"traceutil/trace.go:171","msg":"trace[2000213060] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24691; }","duration":"226.889613ms","start":"2026-07-03T19:40:04.847775Z","end":"2026-07-03T19:40:05.074665Z","steps":["trace[2000213060] 'range keys from in-memory index tree' (duration: 226.622085ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:05.074981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.528793ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-03T19:40:05.07503Z","caller":"traceutil/trace.go:171","msg":"trace[1156993297] transaction","detail":"{read_only:false; response_revision:24692; number_of_response:1; }","duration":"213.951643ms","start":"2026-07-03T19:40:04.861067Z","end":"2026-07-03T19:40:05.075019Z","steps":["trace[1156993297] 'process raft request' (duration: 68.328129ms)","trace[1156993297] 'compare' (duration: 145.046978ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:40:05.240425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.006059ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-03T19:40:05.393687Z","caller":"traceutil/trace.go:171","msg":"trace[817178748] linearizableReadLoop","detail":"{readStateIndex:25513; appliedIndex:25512; }","duration":"290.814247ms","start":"2026-07-03T19:40:05.10284Z","end":"2026-07-03T19:40:05.393654Z","steps":["trace[817178748] 'read index received' (duration: 290.698124ms)","trace[817178748] 'applied index is now lower than readState.Index' (duration: 115.473µs)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:40:05.393742Z","caller":"traceutil/trace.go:171","msg":"trace[499217525] transaction","detail":"{read_only:false; response_revision:24693; number_of_response:1; }","duration":"313.241621ms","start":"2026-07-03T19:40:05.080482Z","end":"2026-07-03T19:40:05.393724Z","steps":["trace[499217525] 'process raft request' (duration: 312.976134ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:05.393884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:40:05.080455Z","time spent":"313.343224ms","remote":"127.0.0.1:34104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T19:40:05.394031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.154577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11835"} {"level":"info","ts":"2026-07-03T19:40:05.394159Z","caller":"traceutil/trace.go:171","msg":"trace[281040503] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:24693; }","duration":"291.335212ms","start":"2026-07-03T19:40:05.102769Z","end":"2026-07-03T19:40:05.394104Z","steps":["trace[281040503] 'agreement among raft nodes before linearized reading' (duration: 290.988582ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:05.608618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.0032ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-03T19:40:05.608828Z","caller":"traceutil/trace.go:171","msg":"trace[1086061202] linearizableReadLoop","detail":"{readStateIndex:25515; appliedIndex:25513; }","duration":"215.047164ms","start":"2026-07-03T19:40:05.393761Z","end":"2026-07-03T19:40:05.608808Z","steps":["trace[1086061202] 'read index received' (duration: 61.656403ms)","trace[1086061202] 'applied index is now lower than readState.Index' (duration: 153.389381ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:40:05.608917Z","caller":"traceutil/trace.go:171","msg":"trace[1219170325] transaction","detail":"{read_only:false; response_revision:24695; number_of_response:1; }","duration":"434.16921ms","start":"2026-07-03T19:40:05.174737Z","end":"2026-07-03T19:40:05.608906Z","steps":["trace[1219170325] 'process raft request' (duration: 433.992005ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:40:05.60895Z","caller":"traceutil/trace.go:171","msg":"trace[212955177] transaction","detail":"{read_only:false; response_revision:24694; number_of_response:1; }","duration":"434.636053ms","start":"2026-07-03T19:40:05.174282Z","end":"2026-07-03T19:40:05.608918Z","steps":["trace[212955177] 'process raft request' (duration: 281.233682ms)","trace[212955177] 'compare' (duration: 152.716582ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:40:05.609113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:40:05.174248Z","time spent":"434.780807ms","remote":"127.0.0.1:34104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T19:40:05.609142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.502869ms","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-07-03T19:40:05.608986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:40:05.174702Z","time spent":"434.245962ms","remote":"127.0.0.1:34104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T19:40:05.609176Z","caller":"traceutil/trace.go:171","msg":"trace[345759634] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24695; }","duration":"434.642913ms","start":"2026-07-03T19:40:05.174522Z","end":"2026-07-03T19:40:05.609165Z","steps":["trace[345759634] 'agreement among raft nodes before linearized reading' (duration: 434.494619ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:05.609205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:40:05.174487Z","time spent":"434.711195ms","remote":"127.0.0.1:34104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-03T19:40:05.619434Z","caller":"traceutil/trace.go:171","msg":"trace[1791645937] transaction","detail":"{read_only:false; response_revision:24696; number_of_response:1; }","duration":"141.694346ms","start":"2026-07-03T19:40:05.477707Z","end":"2026-07-03T19:40:05.619401Z","steps":["trace[1791645937] 'process raft request' (duration: 141.50304ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:40:05.776136Z","caller":"traceutil/trace.go:171","msg":"trace[1375605019] transaction","detail":"{read_only:false; response_revision:24697; number_of_response:1; }","duration":"211.966529ms","start":"2026-07-03T19:40:05.564144Z","end":"2026-07-03T19:40:05.77611Z","steps":["trace[1375605019] 'process raft request' (duration: 211.600148ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:40:05.776407Z","caller":"traceutil/trace.go:171","msg":"trace[2034063537] linearizableReadLoop","detail":"{readStateIndex:25517; appliedIndex:25515; }","duration":"167.502283ms","start":"2026-07-03T19:40:05.608866Z","end":"2026-07-03T19:40:05.776369Z","steps":["trace[2034063537] 'read index received' (duration: 10.292356ms)","trace[2034063537] 'applied index is now lower than readState.Index' (duration: 157.207207ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:40:05.776524Z","caller":"traceutil/trace.go:171","msg":"trace[685920817] transaction","detail":"{read_only:false; response_revision:24698; number_of_response:1; }","duration":"159.994375ms","start":"2026-07-03T19:40:05.616509Z","end":"2026-07-03T19:40:05.776504Z","steps":["trace[685920817] 'process raft request' (duration: 159.538652ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:05.776627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.470276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-07-03T19:40:05.776609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.541149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-03T19:40:05.776694Z","caller":"traceutil/trace.go:171","msg":"trace[1380209012] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24698; }","duration":"215.577018ms","start":"2026-07-03T19:40:05.561098Z","end":"2026-07-03T19:40:05.776675Z","steps":["trace[1380209012] 'agreement among raft nodes before linearized reading' (duration: 215.332982ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:05.776697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.008254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2083"} {"level":"info","ts":"2026-07-03T19:40:05.776716Z","caller":"traceutil/trace.go:171","msg":"trace[1058676379] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24698; }","duration":"223.692394ms","start":"2026-07-03T19:40:05.552999Z","end":"2026-07-03T19:40:05.776691Z","steps":["trace[1058676379] 'agreement among raft nodes before linearized reading' (duration: 223.422266ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:05.776742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.851245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11835"} {"level":"info","ts":"2026-07-03T19:40:05.776785Z","caller":"traceutil/trace.go:171","msg":"trace[517456273] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:24698; }","duration":"372.127568ms","start":"2026-07-03T19:40:05.404629Z","end":"2026-07-03T19:40:05.776757Z","steps":["trace[517456273] 'agreement among raft nodes before linearized reading' (duration: 371.83935ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:40:05.776793Z","caller":"traceutil/trace.go:171","msg":"trace[11487273] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:24698; }","duration":"373.932007ms","start":"2026-07-03T19:40:05.402846Z","end":"2026-07-03T19:40:05.776778Z","steps":["trace[11487273] 'agreement among raft nodes before linearized reading' (duration: 373.780233ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:05.776842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:40:05.404619Z","time spent":"372.21382ms","remote":"127.0.0.1:33958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2106,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"warn","ts":"2026-07-03T19:40:05.776844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:40:05.402826Z","time spent":"374.00726ms","remote":"127.0.0.1:34280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":11858,"request content":"key:\"/registry/deployments/openstack/glance-api\" "} {"level":"info","ts":"2026-07-03T19:40:05.976867Z","caller":"traceutil/trace.go:171","msg":"trace[240061948] linearizableReadLoop","detail":"{readStateIndex:25522; appliedIndex:25518; }","duration":"166.006681ms","start":"2026-07-03T19:40:05.810836Z","end":"2026-07-03T19:40:05.976843Z","steps":["trace[240061948] 'read index received' (duration: 2.761767ms)","trace[240061948] 'applied index is now lower than readState.Index' (duration: 163.243544ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:40:05.9769Z","caller":"traceutil/trace.go:171","msg":"trace[1727958888] transaction","detail":"{read_only:false; response_revision:24701; number_of_response:1; }","duration":"193.085993ms","start":"2026-07-03T19:40:05.783781Z","end":"2026-07-03T19:40:05.976867Z","steps":["trace[1727958888] 'process raft request' (duration: 192.890588ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:40:05.977013Z","caller":"traceutil/trace.go:171","msg":"trace[1385121206] transaction","detail":"{read_only:false; response_revision:24702; number_of_response:1; }","duration":"189.381101ms","start":"2026-07-03T19:40:05.78762Z","end":"2026-07-03T19:40:05.977001Z","steps":["trace[1385121206] 'process raft request' (duration: 189.168685ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:40:05.977074Z","caller":"traceutil/trace.go:171","msg":"trace[1498789412] transaction","detail":"{read_only:false; response_revision:24700; number_of_response:1; }","duration":"193.758102ms","start":"2026-07-03T19:40:05.783287Z","end":"2026-07-03T19:40:05.977045Z","steps":["trace[1498789412] 'process raft request' (duration: 187.288322ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:05.977399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.548786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/magnum-api-79bdf889cf-qxwrr\" ","response":"range_response_count:1 size:8648"} {"level":"info","ts":"2026-07-03T19:40:05.97745Z","caller":"traceutil/trace.go:171","msg":"trace[488075215] range","detail":"{range_begin:/registry/pods/openstack/magnum-api-79bdf889cf-qxwrr; range_end:; response_count:1; response_revision:24702; }","duration":"166.65979ms","start":"2026-07-03T19:40:05.810775Z","end":"2026-07-03T19:40:05.977435Z","steps":["trace[488075215] 'agreement among raft nodes before linearized reading' (duration: 166.446174ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:05.977667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.810052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:32 size:274755"} {"level":"info","ts":"2026-07-03T19:40:05.977753Z","caller":"traceutil/trace.go:171","msg":"trace[1876987962] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:32; response_revision:24702; }","duration":"108.956967ms","start":"2026-07-03T19:40:05.868779Z","end":"2026-07-03T19:40:05.977736Z","steps":["trace[1876987962] 'agreement among raft nodes before linearized reading' (duration: 108.34055ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:05.977581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.620574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusteridentities/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusteridentities0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-03T19:40:05.977934Z","caller":"traceutil/trace.go:171","msg":"trace[800525795] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusteridentities/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusteridentities0; response_count:0; response_revision:24702; }","duration":"105.980854ms","start":"2026-07-03T19:40:05.871898Z","end":"2026-07-03T19:40:05.977879Z","steps":["trace[800525795] 'agreement among raft nodes before linearized reading' (duration: 105.582943ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:06.336341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.568191ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-03T19:40:06.336505Z","caller":"traceutil/trace.go:171","msg":"trace[1426995360] transaction","detail":"{read_only:false; response_revision:24703; number_of_response:1; }","duration":"347.778461ms","start":"2026-07-03T19:40:05.988711Z","end":"2026-07-03T19:40:06.336489Z","steps":["trace[1426995360] 'process raft request' (duration: 182.980053ms)","trace[1426995360] 'compare' (duration: 164.099298ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:40:06.33655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:40:05.988689Z","time spent":"347.839543ms","remote":"127.0.0.1:33988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8446,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T19:40:06.336639Z","caller":"traceutil/trace.go:171","msg":"trace[1574962111] linearizableReadLoop","detail":"{readStateIndex:25523; appliedIndex:25522; }","duration":"343.679587ms","start":"2026-07-03T19:40:05.992952Z","end":"2026-07-03T19:40:06.336631Z","steps":["trace[1574962111] 'read index received' (duration: 178.686573ms)","trace[1574962111] 'applied index is now lower than readState.Index' (duration: 164.991993ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:40:06.336898Z","caller":"traceutil/trace.go:171","msg":"trace[200980650] transaction","detail":"{read_only:false; response_revision:24704; number_of_response:1; }","duration":"293.950246ms","start":"2026-07-03T19:40:06.042907Z","end":"2026-07-03T19:40:06.336858Z","steps":["trace[200980650] 'process raft request' (duration: 293.523114ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:06.337187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.220152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-03T19:40:06.337263Z","caller":"traceutil/trace.go:171","msg":"trace[1552222927] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:24704; }","duration":"344.313844ms","start":"2026-07-03T19:40:05.992928Z","end":"2026-07-03T19:40:06.337242Z","steps":["trace[1552222927] 'agreement among raft nodes before linearized reading' (duration: 343.738288ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:06.337309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:40:05.992862Z","time spent":"344.436388ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"warn","ts":"2026-07-03T19:40:06.38094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.351235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-07-03T19:40:06.381032Z","caller":"traceutil/trace.go:171","msg":"trace[349393499] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:24705; }","duration":"263.506759ms","start":"2026-07-03T19:40:06.117506Z","end":"2026-07-03T19:40:06.381012Z","steps":["trace[349393499] 'agreement among raft nodes before linearized reading' (duration: 263.303894ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:06.381236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.189184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-03T19:40:06.381282Z","caller":"traceutil/trace.go:171","msg":"trace[2071757269] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24705; }","duration":"338.262366ms","start":"2026-07-03T19:40:06.043007Z","end":"2026-07-03T19:40:06.38127Z","steps":["trace[2071757269] 'agreement among raft nodes before linearized reading' (duration: 337.699211ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:06.381316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:40:06.042998Z","time spent":"338.310337ms","remote":"127.0.0.1:34104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-03T19:40:06.621493Z","caller":"traceutil/trace.go:171","msg":"trace[1349826771] transaction","detail":"{read_only:false; response_revision:24707; number_of_response:1; }","duration":"277.249572ms","start":"2026-07-03T19:40:06.34422Z","end":"2026-07-03T19:40:06.62147Z","steps":["trace[1349826771] 'process raft request' (duration: 277.031436ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:40:06.621635Z","caller":"traceutil/trace.go:171","msg":"trace[607233394] transaction","detail":"{read_only:false; response_revision:24706; number_of_response:1; }","duration":"277.573711ms","start":"2026-07-03T19:40:06.344029Z","end":"2026-07-03T19:40:06.621603Z","steps":["trace[607233394] 'process raft request' (duration: 240.647685ms)","trace[607233394] 'compare' (duration: 36.268087ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:40:06.621739Z","caller":"traceutil/trace.go:171","msg":"trace[1055091078] transaction","detail":"{read_only:false; response_revision:24708; number_of_response:1; }","duration":"276.981874ms","start":"2026-07-03T19:40:06.344748Z","end":"2026-07-03T19:40:06.62173Z","steps":["trace[1055091078] 'process raft request' (duration: 276.625804ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:40:06.621942Z","caller":"traceutil/trace.go:171","msg":"trace[207663566] transaction","detail":"{read_only:false; response_revision:24709; number_of_response:1; }","duration":"275.016639ms","start":"2026-07-03T19:40:06.346894Z","end":"2026-07-03T19:40:06.621911Z","steps":["trace[207663566] 'process raft request' (duration: 274.526865ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:40:07.090423Z","caller":"traceutil/trace.go:171","msg":"trace[1496939765] transaction","detail":"{read_only:false; response_revision:24710; number_of_response:1; }","duration":"705.312112ms","start":"2026-07-03T19:40:06.385068Z","end":"2026-07-03T19:40:07.09038Z","steps":["trace[1496939765] 'process raft request' (duration: 704.947392ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:07.090626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:40:06.385035Z","time spent":"705.471397ms","remote":"127.0.0.1:34104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":513,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T19:40:07.090721Z","caller":"traceutil/trace.go:171","msg":"trace[586822020] transaction","detail":"{read_only:false; response_revision:24711; number_of_response:1; }","duration":"705.284012ms","start":"2026-07-03T19:40:06.385367Z","end":"2026-07-03T19:40:07.090651Z","steps":["trace[586822020] 'process raft request' (duration: 704.918232ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:40:07.090391Z","caller":"traceutil/trace.go:171","msg":"trace[656899088] linearizableReadLoop","detail":"{readStateIndex:25531; appliedIndex:25525; }","duration":"690.961124ms","start":"2026-07-03T19:40:06.399395Z","end":"2026-07-03T19:40:07.090356Z","steps":["trace[656899088] 'read index received' (duration: 185.279497ms)","trace[656899088] 'applied index is now lower than readState.Index' (duration: 505.680157ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:40:07.090794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:40:06.385353Z","time spent":"705.407005ms","remote":"127.0.0.1:34104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T19:40:07.091071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.647182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1936"} {"level":"info","ts":"2026-07-03T19:40:07.091134Z","caller":"traceutil/trace.go:171","msg":"trace[936829559] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:24711; }","duration":"691.779956ms","start":"2026-07-03T19:40:06.399334Z","end":"2026-07-03T19:40:07.091114Z","steps":["trace[936829559] 'agreement among raft nodes before linearized reading' (duration: 691.508098ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:07.091187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:40:06.399309Z","time spent":"691.867488ms","remote":"127.0.0.1:33958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1959,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"info","ts":"2026-07-03T19:40:07.432504Z","caller":"traceutil/trace.go:171","msg":"trace[791976385] transaction","detail":"{read_only:false; response_revision:24712; number_of_response:1; }","duration":"798.851401ms","start":"2026-07-03T19:40:06.633622Z","end":"2026-07-03T19:40:07.432473Z","steps":["trace[791976385] 'process raft request' (duration: 798.281755ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:40:07.432619Z","caller":"traceutil/trace.go:171","msg":"trace[1889431256] transaction","detail":"{read_only:false; response_revision:24714; number_of_response:1; }","duration":"711.875254ms","start":"2026-07-03T19:40:06.720721Z","end":"2026-07-03T19:40:07.432596Z","steps":["trace[1889431256] 'process raft request' (duration: 711.560606ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:40:07.432581Z","caller":"traceutil/trace.go:171","msg":"trace[24091290] transaction","detail":"{read_only:false; response_revision:24713; number_of_response:1; }","duration":"711.807142ms","start":"2026-07-03T19:40:06.720721Z","end":"2026-07-03T19:40:07.432528Z","steps":["trace[24091290] 'process raft request' (duration: 711.414671ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:07.43271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:40:06.720688Z","time spent":"711.980057ms","remote":"127.0.0.1:34104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T19:40:07.432708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.809031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-03T19:40:07.432654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:40:06.633598Z","time spent":"798.971944ms","remote":"127.0.0.1:34280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7836,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T19:40:07.432809Z","caller":"traceutil/trace.go:171","msg":"trace[829696481] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:24714; }","duration":"410.961985ms","start":"2026-07-03T19:40:07.021822Z","end":"2026-07-03T19:40:07.432783Z","steps":["trace[829696481] 'agreement among raft nodes before linearized reading' (duration: 410.791781ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:40:07.432493Z","caller":"traceutil/trace.go:171","msg":"trace[679789886] linearizableReadLoop","detail":"{readStateIndex:25534; appliedIndex:25531; }","duration":"341.666401ms","start":"2026-07-03T19:40:07.090797Z","end":"2026-07-03T19:40:07.432463Z","steps":["trace[679789886] 'read index received' (duration: 341.014402ms)","trace[679789886] 'applied index is now lower than readState.Index' (duration: 650.039µs)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:40:07.432825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"970.675564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-03T19:40:07.432855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:40:06.720688Z","time spent":"712.017358ms","remote":"127.0.0.1:34104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T19:40:07.432959Z","caller":"traceutil/trace.go:171","msg":"trace[1178822633] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24714; }","duration":"970.90283ms","start":"2026-07-03T19:40:06.462011Z","end":"2026-07-03T19:40:07.432914Z","steps":["trace[1178822633] 'agreement among raft nodes before linearized reading' (duration: 970.53791ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:07.433046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:40:06.46199Z","time spent":"971.044153ms","remote":"127.0.0.1:33792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-03T19:40:07.433061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.915528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-03T19:40:07.432861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:40:07.021795Z","time spent":"411.055298ms","remote":"127.0.0.1:32910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-03T19:40:07.433115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.03074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-33e34b66-1cbc-472d-93f1-7366aacb5aa9\" ","response":"range_response_count:1 size:1451"} {"level":"info","ts":"2026-07-03T19:40:07.433155Z","caller":"traceutil/trace.go:171","msg":"trace[1408606558] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0; response_count:0; response_revision:24714; }","duration":"270.073582ms","start":"2026-07-03T19:40:07.163058Z","end":"2026-07-03T19:40:07.433132Z","steps":["trace[1408606558] 'agreement among raft nodes before linearized reading' (duration: 269.904097ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:40:07.433215Z","caller":"traceutil/trace.go:171","msg":"trace[157002446] range","detail":"{range_begin:/registry/persistentvolumes/pvc-33e34b66-1cbc-472d-93f1-7366aacb5aa9; range_end:; response_count:1; response_revision:24714; }","duration":"338.200725ms","start":"2026-07-03T19:40:07.094991Z","end":"2026-07-03T19:40:07.433191Z","steps":["trace[157002446] 'agreement among raft nodes before linearized reading' (duration: 337.939508ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:07.433278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:40:07.094959Z","time spent":"338.308888ms","remote":"127.0.0.1:33948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1474,"request content":"key:\"/registry/persistentvolumes/pvc-33e34b66-1cbc-472d-93f1-7366aacb5aa9\" "} {"level":"info","ts":"2026-07-03T19:40:07.673452Z","caller":"traceutil/trace.go:171","msg":"trace[1069255407] linearizableReadLoop","detail":"{readStateIndex:25536; appliedIndex:25535; }","duration":"212.521163ms","start":"2026-07-03T19:40:07.460885Z","end":"2026-07-03T19:40:07.673406Z","steps":["trace[1069255407] 'read index received' (duration: 137.585992ms)","trace[1069255407] 'applied index is now lower than readState.Index' (duration: 74.929961ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:40:07.673469Z","caller":"traceutil/trace.go:171","msg":"trace[63871267] transaction","detail":"{read_only:false; response_revision:24716; number_of_response:1; }","duration":"234.574066ms","start":"2026-07-03T19:40:07.438863Z","end":"2026-07-03T19:40:07.673437Z","steps":["trace[63871267] 'process raft request' (duration: 159.571563ms)","trace[63871267] 'compare' (duration: 74.406547ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:40:07.673668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.758819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-03T19:40:07.673735Z","caller":"traceutil/trace.go:171","msg":"trace[925922448] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24717; }","duration":"212.898494ms","start":"2026-07-03T19:40:07.460819Z","end":"2026-07-03T19:40:07.673718Z","steps":["trace[925922448] 'agreement among raft nodes before linearized reading' (duration: 212.725519ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:40:11.83796Z","caller":"traceutil/trace.go:171","msg":"trace[1258326351] transaction","detail":"{read_only:false; response_revision:24756; number_of_response:1; }","duration":"144.72934ms","start":"2026-07-03T19:40:11.69319Z","end":"2026-07-03T19:40:11.837919Z","steps":["trace[1258326351] 'process raft request' (duration: 142.669093ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:40:11.838013Z","caller":"traceutil/trace.go:171","msg":"trace[835115496] transaction","detail":"{read_only:false; response_revision:24757; number_of_response:1; }","duration":"103.503736ms","start":"2026-07-03T19:40:11.734479Z","end":"2026-07-03T19:40:11.837983Z","steps":["trace[835115496] 'process raft request' (duration: 103.366412ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:40:12.153202Z","caller":"traceutil/trace.go:171","msg":"trace[1496805521] linearizableReadLoop","detail":"{readStateIndex:25581; appliedIndex:25580; }","duration":"178.882709ms","start":"2026-07-03T19:40:11.974296Z","end":"2026-07-03T19:40:12.153178Z","steps":["trace[1496805521] 'read index received' (duration: 178.672843ms)","trace[1496805521] 'applied index is now lower than readState.Index' (duration: 208.506µs)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:40:12.153286Z","caller":"traceutil/trace.go:171","msg":"trace[1146261619] transaction","detail":"{read_only:false; response_revision:24760; number_of_response:1; }","duration":"282.382703ms","start":"2026-07-03T19:40:11.870875Z","end":"2026-07-03T19:40:12.153258Z","steps":["trace[1146261619] 'process raft request' (duration: 282.128066ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:12.153447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.113715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-03T19:40:12.153492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.610736ms","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-07-03T19:40:12.153532Z","caller":"traceutil/trace.go:171","msg":"trace[2140657342] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24760; }","duration":"149.669388ms","start":"2026-07-03T19:40:12.00385Z","end":"2026-07-03T19:40:12.153519Z","steps":["trace[2140657342] 'agreement among raft nodes before linearized reading' (duration: 149.486213ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:40:12.153532Z","caller":"traceutil/trace.go:171","msg":"trace[845847775] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:24760; }","duration":"179.28219ms","start":"2026-07-03T19:40:11.974229Z","end":"2026-07-03T19:40:12.153511Z","steps":["trace[845847775] 'agreement among raft nodes before linearized reading' (duration: 179.039604ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:12.153768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.970128ms","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-07-03T19:40:12.153795Z","caller":"traceutil/trace.go:171","msg":"trace[1665593633] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24760; }","duration":"149.03353ms","start":"2026-07-03T19:40:12.004753Z","end":"2026-07-03T19:40:12.153787Z","steps":["trace[1665593633] 'agreement among raft nodes before linearized reading' (duration: 148.689231ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:40:12.525678Z","caller":"traceutil/trace.go:171","msg":"trace[965251108] transaction","detail":"{read_only:false; response_revision:24764; number_of_response:1; }","duration":"114.192212ms","start":"2026-07-03T19:40:12.411442Z","end":"2026-07-03T19:40:12.525634Z","steps":["trace[965251108] 'process raft request' (duration: 112.504785ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:40:13.260217Z","caller":"traceutil/trace.go:171","msg":"trace[1409945606] transaction","detail":"{read_only:false; response_revision:24765; number_of_response:1; }","duration":"128.751987ms","start":"2026-07-03T19:40:13.13143Z","end":"2026-07-03T19:40:13.260182Z","steps":["trace[1409945606] 'process raft request' (duration: 128.51607ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:13.594351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.501035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-03T19:40:13.594461Z","caller":"traceutil/trace.go:171","msg":"trace[325327253] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24765; }","duration":"130.67651ms","start":"2026-07-03T19:40:13.46376Z","end":"2026-07-03T19:40:13.594436Z","steps":["trace[325327253] 'range keys from in-memory index tree' (duration: 130.403022ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:40:13.594734Z","caller":"traceutil/trace.go:171","msg":"trace[73007745] transaction","detail":"{read_only:false; response_revision:24766; number_of_response:1; }","duration":"130.446314ms","start":"2026-07-03T19:40:13.464263Z","end":"2026-07-03T19:40:13.594709Z","steps":["trace[73007745] 'process raft request' (duration: 84.716953ms)","trace[73007745] 'compare' (duration: 45.202826ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:40:13.594757Z","caller":"traceutil/trace.go:171","msg":"trace[1566703246] transaction","detail":"{read_only:false; response_revision:24767; number_of_response:1; }","duration":"111.860267ms","start":"2026-07-03T19:40:13.48286Z","end":"2026-07-03T19:40:13.59472Z","steps":["trace[1566703246] 'process raft request' (duration: 111.692773ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:40:13.594913Z","caller":"traceutil/trace.go:171","msg":"trace[661007409] transaction","detail":"{read_only:false; response_revision:24768; number_of_response:1; }","duration":"110.658124ms","start":"2026-07-03T19:40:13.484196Z","end":"2026-07-03T19:40:13.594854Z","steps":["trace[661007409] 'process raft request' (duration: 110.466769ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:15.697592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.297672ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-03T19:40:15.697702Z","caller":"traceutil/trace.go:171","msg":"trace[2054676245] linearizableReadLoop","detail":"{readStateIndex:25605; appliedIndex:25604; }","duration":"234.05554ms","start":"2026-07-03T19:40:15.463629Z","end":"2026-07-03T19:40:15.697684Z","steps":["trace[2054676245] 'read index received' (duration: 46.568353ms)","trace[2054676245] 'applied index is now lower than readState.Index' (duration: 187.485767ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:40:15.697789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.170144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-03T19:40:15.697814Z","caller":"traceutil/trace.go:171","msg":"trace[1524567139] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24783; }","duration":"234.248976ms","start":"2026-07-03T19:40:15.463557Z","end":"2026-07-03T19:40:15.697806Z","steps":["trace[1524567139] 'agreement among raft nodes before linearized reading' (duration: 234.174904ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:40:15.698047Z","caller":"traceutil/trace.go:171","msg":"trace[470256148] transaction","detail":"{read_only:false; response_revision:24783; number_of_response:1; }","duration":"289.666005ms","start":"2026-07-03T19:40:15.408341Z","end":"2026-07-03T19:40:15.698007Z","steps":["trace[470256148] 'process raft request' (duration: 101.868869ms)","trace[470256148] 'compare' (duration: 187.121298ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:40:15.709565Z","caller":"traceutil/trace.go:171","msg":"trace[243767731] transaction","detail":"{read_only:false; response_revision:24784; number_of_response:1; }","duration":"107.926479ms","start":"2026-07-03T19:40:15.60161Z","end":"2026-07-03T19:40:15.709536Z","steps":["trace[243767731] 'process raft request' (duration: 107.686762ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:40:15.759235Z","caller":"traceutil/trace.go:171","msg":"trace[222989944] transaction","detail":"{read_only:false; response_revision:24785; number_of_response:1; }","duration":"157.32686ms","start":"2026-07-03T19:40:15.601881Z","end":"2026-07-03T19:40:15.759208Z","steps":["trace[222989944] 'process raft request' (duration: 157.072623ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:40:15.75932Z","caller":"traceutil/trace.go:171","msg":"trace[1041964125] transaction","detail":"{read_only:false; response_revision:24786; number_of_response:1; }","duration":"126.830483ms","start":"2026-07-03T19:40:15.632458Z","end":"2026-07-03T19:40:15.759288Z","steps":["trace[1041964125] 'process raft request' (duration: 126.646108ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:40:20.147694Z","caller":"traceutil/trace.go:171","msg":"trace[1911742803] transaction","detail":"{read_only:false; response_revision:24823; number_of_response:1; }","duration":"274.222807ms","start":"2026-07-03T19:40:19.873437Z","end":"2026-07-03T19:40:20.14766Z","steps":["trace[1911742803] 'process raft request' (duration: 274.018232ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:40:20.159461Z","caller":"traceutil/trace.go:171","msg":"trace[478407901] linearizableReadLoop","detail":"{readStateIndex:25646; appliedIndex:25644; }","duration":"272.208201ms","start":"2026-07-03T19:40:19.887228Z","end":"2026-07-03T19:40:20.159436Z","steps":["trace[478407901] 'read index received' (duration: 260.338112ms)","trace[478407901] 'applied index is now lower than readState.Index' (duration: 11.868949ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:40:20.159632Z","caller":"traceutil/trace.go:171","msg":"trace[1360319563] transaction","detail":"{read_only:false; response_revision:24824; number_of_response:1; }","duration":"276.151991ms","start":"2026-07-03T19:40:19.883418Z","end":"2026-07-03T19:40:20.15957Z","steps":["trace[1360319563] 'process raft request' (duration: 275.861793ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:20.159935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.414871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.74\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-07-03T19:40:20.159982Z","caller":"traceutil/trace.go:171","msg":"trace[135445459] range","detail":"{range_begin:/registry/masterleases/199.204.45.74; range_end:; response_count:1; response_revision:24824; }","duration":"261.503994ms","start":"2026-07-03T19:40:19.898467Z","end":"2026-07-03T19:40:20.159971Z","steps":["trace[135445459] 'agreement among raft nodes before linearized reading' (duration: 261.236067ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:20.160011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.611585ms","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-07-03T19:40:20.160148Z","caller":"traceutil/trace.go:171","msg":"trace[326815564] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24824; }","duration":"271.79306ms","start":"2026-07-03T19:40:19.88832Z","end":"2026-07-03T19:40:20.160113Z","steps":["trace[326815564] 'agreement among raft nodes before linearized reading' (duration: 271.379839ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:20.160585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.354783ms","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-07-03T19:40:20.16064Z","caller":"traceutil/trace.go:171","msg":"trace[2099829092] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24824; }","duration":"273.439725ms","start":"2026-07-03T19:40:19.887187Z","end":"2026-07-03T19:40:20.160627Z","steps":["trace[2099829092] 'agreement among raft nodes before linearized reading' (duration: 272.360225ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:20.592595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.071571ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-03T19:40:20.59305Z","caller":"traceutil/trace.go:171","msg":"trace[771467337] transaction","detail":"{read_only:false; response_revision:24825; number_of_response:1; }","duration":"428.213955ms","start":"2026-07-03T19:40:20.164781Z","end":"2026-07-03T19:40:20.592995Z","steps":["trace[771467337] 'process raft request' (duration: 125.62739ms)","trace[771467337] 'compare' (duration: 301.919646ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:40:20.59324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:40:20.164741Z","time spent":"428.39604ms","remote":"127.0.0.1:34104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T19:40:20.593263Z","caller":"traceutil/trace.go:171","msg":"trace[1020698747] transaction","detail":"{read_only:false; response_revision:24826; number_of_response:1; }","duration":"426.95418ms","start":"2026-07-03T19:40:20.166271Z","end":"2026-07-03T19:40:20.593225Z","steps":["trace[1020698747] 'process raft request' (duration: 426.446476ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:20.593428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:40:20.166241Z","time spent":"427.090374ms","remote":"127.0.0.1:34104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T19:40:20.593972Z","caller":"traceutil/trace.go:171","msg":"trace[268021623] linearizableReadLoop","detail":"{readStateIndex:25650; appliedIndex:25647; }","duration":"400.764862ms","start":"2026-07-03T19:40:20.193185Z","end":"2026-07-03T19:40:20.593949Z","steps":["trace[268021623] 'read index received' (duration: 97.322923ms)","trace[268021623] 'applied index is now lower than readState.Index' (duration: 303.438209ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:40:20.594021Z","caller":"traceutil/trace.go:171","msg":"trace[775136067] transaction","detail":"{read_only:false; response_revision:24827; number_of_response:1; }","duration":"419.907164ms","start":"2026-07-03T19:40:20.174059Z","end":"2026-07-03T19:40:20.593966Z","steps":["trace[775136067] 'process raft request' (duration: 419.75752ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:20.594306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.105172ms","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-07-03T19:40:20.594622Z","caller":"traceutil/trace.go:171","msg":"trace[1638139934] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24827; }","duration":"401.468983ms","start":"2026-07-03T19:40:20.193135Z","end":"2026-07-03T19:40:20.594604Z","steps":["trace[1638139934] 'agreement among raft nodes before linearized reading' (duration: 400.945508ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:20.594661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:40:20.193118Z","time spent":"401.533344ms","remote":"127.0.0.1:34104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-03T19:40:20.59501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:40:20.174036Z","time spent":"420.494191ms","remote":"127.0.0.1:33818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":119,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T19:40:21.001758Z","caller":"traceutil/trace.go:171","msg":"trace[1386185508] transaction","detail":"{read_only:false; response_revision:24828; number_of_response:1; }","duration":"594.927896ms","start":"2026-07-03T19:40:20.406794Z","end":"2026-07-03T19:40:21.001722Z","steps":["trace[1386185508] 'process raft request' (duration: 594.471574ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:21.001929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:40:20.406757Z","time spent":"595.07835ms","remote":"127.0.0.1:34104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T19:40:21.237019Z","caller":"traceutil/trace.go:171","msg":"trace[176348490] transaction","detail":"{read_only:false; response_revision:24831; number_of_response:1; }","duration":"471.073345ms","start":"2026-07-03T19:40:20.765926Z","end":"2026-07-03T19:40:21.237Z","steps":["trace[176348490] 'process raft request' (duration: 471.014643ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:40:21.237088Z","caller":"traceutil/trace.go:171","msg":"trace[848823959] transaction","detail":"{read_only:false; response_revision:24830; number_of_response:1; }","duration":"637.241811ms","start":"2026-07-03T19:40:20.59984Z","end":"2026-07-03T19:40:21.237082Z","steps":["trace[848823959] 'process raft request' (duration: 637.013564ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:40:21.237026Z","caller":"traceutil/trace.go:171","msg":"trace[889018166] transaction","detail":"{read_only:false; response_revision:24829; number_of_response:1; }","duration":"670.513996ms","start":"2026-07-03T19:40:20.56649Z","end":"2026-07-03T19:40:21.237004Z","steps":["trace[889018166] 'process raft request' (duration: 653.157854ms)","trace[889018166] 'compare' (duration: 17.001722ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:40:21.237143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:40:20.765874Z","time spent":"471.201329ms","remote":"127.0.0.1:34104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T19:40:21.237128Z","caller":"traceutil/trace.go:171","msg":"trace[182259510] linearizableReadLoop","detail":"{readStateIndex:25652; appliedIndex:25650; }","duration":"642.651552ms","start":"2026-07-03T19:40:20.594455Z","end":"2026-07-03T19:40:21.237107Z","steps":["trace[182259510] 'read index received' (duration: 406.758779ms)","trace[182259510] 'applied index is now lower than readState.Index' (duration: 235.890882ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:40:21.237178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:40:20.566434Z","time spent":"670.717941ms","remote":"127.0.0.1:34104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T19:40:21.237253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"773.27272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-03T19:40:21.237328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.032222242s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-07-03T19:40:21.237337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"798.299235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0\" ","response":"range_response_count:1 size:1862"} {"level":"info","ts":"2026-07-03T19:40:21.237353Z","caller":"traceutil/trace.go:171","msg":"trace[852806125] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24832; }","duration":"1.032307875s","start":"2026-07-03T19:40:20.205039Z","end":"2026-07-03T19:40:21.237347Z","steps":["trace[852806125] 'agreement among raft nodes before linearized reading' (duration: 1.032246293s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:21.237378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:40:20.205013Z","time spent":"1.032359667s","remote":"127.0.0.1:34104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-03T19:40:21.237373Z","caller":"traceutil/trace.go:171","msg":"trace[93651552] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0; range_end:; response_count:1; response_revision:24832; }","duration":"798.445149ms","start":"2026-07-03T19:40:20.438914Z","end":"2026-07-03T19:40:21.237359Z","steps":["trace[93651552] 'agreement among raft nodes before linearized reading' (duration: 798.302085ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:40:21.237397Z","caller":"traceutil/trace.go:171","msg":"trace[947721772] transaction","detail":"{read_only:false; response_revision:24832; number_of_response:1; }","duration":"350.345572ms","start":"2026-07-03T19:40:20.88704Z","end":"2026-07-03T19:40:21.237385Z","steps":["trace[947721772] 'process raft request' (duration: 349.930341ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:21.237408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:40:20.438883Z","time spent":"798.516791ms","remote":"127.0.0.1:33958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":1,"response size":1885,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0\" "} {"level":"info","ts":"2026-07-03T19:40:21.237343Z","caller":"traceutil/trace.go:171","msg":"trace[780953379] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24832; }","duration":"773.430603ms","start":"2026-07-03T19:40:20.463892Z","end":"2026-07-03T19:40:21.237322Z","steps":["trace[780953379] 'agreement among raft nodes before linearized reading' (duration: 773.258579ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:21.237456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:40:20.463859Z","time spent":"773.588768ms","remote":"127.0.0.1:33794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-03T19:40:21.2375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:40:20.887012Z","time spent":"350.412863ms","remote":"127.0.0.1:34104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T19:40:21.237557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"959.831942ms","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-07-03T19:40:21.237581Z","caller":"traceutil/trace.go:171","msg":"trace[1453737592] 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:24832; }","duration":"959.875492ms","start":"2026-07-03T19:40:20.277697Z","end":"2026-07-03T19:40:21.237572Z","steps":["trace[1453737592] 'agreement among raft nodes before linearized reading' (duration: 959.835361ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:21.237597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:40:20.277682Z","time spent":"959.910654ms","remote":"127.0.0.1:33838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"warn","ts":"2026-07-03T19:40:21.237702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.033423316s","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-07-03T19:40:21.237737Z","caller":"traceutil/trace.go:171","msg":"trace[689233504] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24832; }","duration":"1.033484658s","start":"2026-07-03T19:40:20.204239Z","end":"2026-07-03T19:40:21.237724Z","steps":["trace[689233504] 'agreement among raft nodes before linearized reading' (duration: 1.033366014s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:21.237767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:40:20.204221Z","time spent":"1.03353785s","remote":"127.0.0.1:34104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-03T19:40:21.23833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:40:20.59982Z","time spent":"637.288763ms","remote":"127.0.0.1:34104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T19:40:21.381849Z","caller":"traceutil/trace.go:171","msg":"trace[1626786339] linearizableReadLoop","detail":"{readStateIndex:25656; appliedIndex:25655; }","duration":"144.643708ms","start":"2026-07-03T19:40:21.237182Z","end":"2026-07-03T19:40:21.381825Z","steps":["trace[1626786339] 'read index received' (duration: 144.574326ms)","trace[1626786339] 'applied index is now lower than readState.Index' (duration: 67.982µs)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:40:21.382344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.859936ms","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":"info","ts":"2026-07-03T19:40:21.38249Z","caller":"traceutil/trace.go:171","msg":"trace[445961692] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:24833; }","duration":"153.090942ms","start":"2026-07-03T19:40:21.229373Z","end":"2026-07-03T19:40:21.382464Z","steps":["trace[445961692] 'agreement among raft nodes before linearized reading' (duration: 152.737772ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:21.38246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.906679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-03T19:40:21.382567Z","caller":"traceutil/trace.go:171","msg":"trace[1079597320] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:24833; }","duration":"233.052313ms","start":"2026-07-03T19:40:21.149488Z","end":"2026-07-03T19:40:21.38254Z","steps":["trace[1079597320] 'agreement among raft nodes before linearized reading' (duration: 232.878869ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:40:21.382006Z","caller":"traceutil/trace.go:171","msg":"trace[29271742] transaction","detail":"{read_only:false; response_revision:24833; number_of_response:1; }","duration":"280.586974ms","start":"2026-07-03T19:40:21.101383Z","end":"2026-07-03T19:40:21.38197Z","steps":["trace[29271742] 'process raft request' (duration: 280.261955ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:21.382694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"784.971105ms","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-07-03T19:40:21.382777Z","caller":"traceutil/trace.go:171","msg":"trace[464906649] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:24833; }","duration":"785.071417ms","start":"2026-07-03T19:40:20.597687Z","end":"2026-07-03T19:40:21.382758Z","steps":["trace[464906649] 'agreement among raft nodes before linearized reading' (duration: 784.667376ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:21.382827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:40:20.597655Z","time spent":"785.16112ms","remote":"127.0.0.1:33968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-07-03T19:40:21.383265Z","caller":"traceutil/trace.go:171","msg":"trace[2020142652] transaction","detail":"{read_only:false; response_revision:24834; number_of_response:1; }","duration":"141.459939ms","start":"2026-07-03T19:40:21.241784Z","end":"2026-07-03T19:40:21.383243Z","steps":["trace[2020142652] 'process raft request' (duration: 141.263073ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:21.383356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.160512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-03T19:40:21.383455Z","caller":"traceutil/trace.go:171","msg":"trace[96176003] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24835; }","duration":"145.292626ms","start":"2026-07-03T19:40:21.238143Z","end":"2026-07-03T19:40:21.383435Z","steps":["trace[96176003] 'agreement among raft nodes before linearized reading' (duration: 145.097731ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:21.383432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.336397ms","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-07-03T19:40:21.383524Z","caller":"traceutil/trace.go:171","msg":"trace[1696998707] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:24835; }","duration":"140.447621ms","start":"2026-07-03T19:40:21.243053Z","end":"2026-07-03T19:40:21.3835Z","steps":["trace[1696998707] 'agreement among raft nodes before linearized reading' (duration: 140.299547ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:40:21.383606Z","caller":"traceutil/trace.go:171","msg":"trace[372613103] transaction","detail":"{read_only:false; response_revision:24835; number_of_response:1; }","duration":"140.281476ms","start":"2026-07-03T19:40:21.243304Z","end":"2026-07-03T19:40:21.383586Z","steps":["trace[372613103] 'process raft request' (duration: 139.878125ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:21.383691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.208468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-c13487b9-8a16-47c2-adba-4635e81e649f\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-07-03T19:40:21.383757Z","caller":"traceutil/trace.go:171","msg":"trace[589175061] range","detail":"{range_begin:/registry/persistentvolumes/pvc-c13487b9-8a16-47c2-adba-4635e81e649f; range_end:; response_count:1; response_revision:24835; }","duration":"143.320961ms","start":"2026-07-03T19:40:21.240418Z","end":"2026-07-03T19:40:21.383739Z","steps":["trace[589175061] 'agreement among raft nodes before linearized reading' (duration: 143.116425ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:26.827604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.931807ms","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-07-03T19:40:26.827683Z","caller":"traceutil/trace.go:171","msg":"trace[1664971759] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:24930; }","duration":"113.070631ms","start":"2026-07-03T19:40:26.714592Z","end":"2026-07-03T19:40:26.827663Z","steps":["trace[1664971759] 'count revisions from in-memory index tree' (duration: 112.830954ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:26.827846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.940687ms","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-07-03T19:40:26.827934Z","caller":"traceutil/trace.go:171","msg":"trace[1577447962] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24930; }","duration":"212.056141ms","start":"2026-07-03T19:40:26.615856Z","end":"2026-07-03T19:40:26.827912Z","steps":["trace[1577447962] 'range keys from in-memory index tree' (duration: 211.731201ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:26.828079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.309345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a4e0194b-dbe0-4709-8dd5-cff1d580a96b\" ","response":"range_response_count:1 size:3643"} {"level":"warn","ts":"2026-07-03T19:40:26.828111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.099659ms","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-07-03T19:40:26.828188Z","caller":"traceutil/trace.go:171","msg":"trace[287088705] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a4e0194b-dbe0-4709-8dd5-cff1d580a96b; range_end:; response_count:1; response_revision:24930; }","duration":"209.437428ms","start":"2026-07-03T19:40:26.618719Z","end":"2026-07-03T19:40:26.828157Z","steps":["trace[287088705] 'range keys from in-memory index tree' (duration: 209.136669ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:40:26.828229Z","caller":"traceutil/trace.go:171","msg":"trace[18386599] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24930; }","duration":"209.277323ms","start":"2026-07-03T19:40:26.618924Z","end":"2026-07-03T19:40:26.828201Z","steps":["trace[18386599] 'range keys from in-memory index tree' (duration: 208.876392ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:40:26.943456Z","caller":"traceutil/trace.go:171","msg":"trace[1291583747] transaction","detail":"{read_only:false; response_revision:24931; number_of_response:1; }","duration":"111.048855ms","start":"2026-07-03T19:40:26.832383Z","end":"2026-07-03T19:40:26.943432Z","steps":["trace[1291583747] 'process raft request' (duration: 110.824259ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:40:26.950604Z","caller":"traceutil/trace.go:171","msg":"trace[22855267] transaction","detail":"{read_only:false; response_revision:24932; number_of_response:1; }","duration":"117.216776ms","start":"2026-07-03T19:40:26.833355Z","end":"2026-07-03T19:40:26.950572Z","steps":["trace[22855267] 'process raft request' (duration: 117.0054ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:40:27.212665Z","caller":"traceutil/trace.go:171","msg":"trace[2072448834] transaction","detail":"{read_only:false; response_revision:24933; number_of_response:1; }","duration":"191.16268ms","start":"2026-07-03T19:40:27.021457Z","end":"2026-07-03T19:40:27.212619Z","steps":["trace[2072448834] 'process raft request' (duration: 159.259374ms)","trace[2072448834] 'compare' (duration: 31.190797ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:40:27.499713Z","caller":"traceutil/trace.go:171","msg":"trace[278245657] transaction","detail":"{read_only:false; response_revision:24934; number_of_response:1; }","duration":"279.094132ms","start":"2026-07-03T19:40:27.22058Z","end":"2026-07-03T19:40:27.499674Z","steps":["trace[278245657] 'process raft request' (duration: 240.300525ms)","trace[278245657] 'compare' (duration: 38.305884ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:40:27.49981Z","caller":"traceutil/trace.go:171","msg":"trace[1504304975] linearizableReadLoop","detail":"{readStateIndex:25758; appliedIndex:25757; }","duration":"277.267172ms","start":"2026-07-03T19:40:27.222367Z","end":"2026-07-03T19:40:27.499634Z","steps":["trace[1504304975] 'read index received' (duration: 238.544037ms)","trace[1504304975] 'applied index is now lower than readState.Index' (duration: 38.721425ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:40:27.49988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.471308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-03T19:40:27.499983Z","caller":"traceutil/trace.go:171","msg":"trace[1608467692] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:24934; }","duration":"277.628242ms","start":"2026-07-03T19:40:27.222332Z","end":"2026-07-03T19:40:27.499961Z","steps":["trace[1608467692] 'agreement among raft nodes before linearized reading' (duration: 277.426097ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:40:27.534598Z","caller":"traceutil/trace.go:171","msg":"trace[21035388] transaction","detail":"{read_only:false; response_revision:24935; number_of_response:1; }","duration":"274.432383ms","start":"2026-07-03T19:40:27.260117Z","end":"2026-07-03T19:40:27.534549Z","steps":["trace[21035388] 'process raft request' (duration: 274.110524ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:27.534643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.403399ms","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-07-03T19:40:27.534706Z","caller":"traceutil/trace.go:171","msg":"trace[786558004] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24935; }","duration":"127.525893ms","start":"2026-07-03T19:40:27.407162Z","end":"2026-07-03T19:40:27.534688Z","steps":["trace[786558004] 'agreement among raft nodes before linearized reading' (duration: 127.328147ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:27.534812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.482071ms","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-07-03T19:40:27.534954Z","caller":"traceutil/trace.go:171","msg":"trace[2126216200] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24935; }","duration":"123.703857ms","start":"2026-07-03T19:40:27.411223Z","end":"2026-07-03T19:40:27.534927Z","steps":["trace[2126216200] 'agreement among raft nodes before linearized reading' (duration: 123.250534ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:27.535025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.523864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-07-03T19:40:27.535102Z","caller":"traceutil/trace.go:171","msg":"trace[1895556040] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24935; }","duration":"122.591855ms","start":"2026-07-03T19:40:27.41247Z","end":"2026-07-03T19:40:27.535062Z","steps":["trace[1895556040] 'agreement among raft nodes before linearized reading' (duration: 121.973638ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:27.535168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.51617ms","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-07-03T19:40:27.535275Z","caller":"traceutil/trace.go:171","msg":"trace[748513990] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24935; }","duration":"272.665774ms","start":"2026-07-03T19:40:27.262576Z","end":"2026-07-03T19:40:27.535242Z","steps":["trace[748513990] 'agreement among raft nodes before linearized reading' (duration: 271.78792ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:40:27.671922Z","caller":"traceutil/trace.go:171","msg":"trace[1671538937] transaction","detail":"{read_only:false; response_revision:24937; number_of_response:1; }","duration":"132.378067ms","start":"2026-07-03T19:40:27.539506Z","end":"2026-07-03T19:40:27.671884Z","steps":["trace[1671538937] 'process raft request' (duration: 118.882162ms)","trace[1671538937] 'compare' (duration: 13.174406ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:40:27.672151Z","caller":"traceutil/trace.go:171","msg":"trace[555744723] transaction","detail":"{read_only:false; response_revision:24938; number_of_response:1; }","duration":"131.461822ms","start":"2026-07-03T19:40:27.540658Z","end":"2026-07-03T19:40:27.67212Z","steps":["trace[555744723] 'process raft request' (duration: 131.336108ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:40:27.672219Z","caller":"traceutil/trace.go:171","msg":"trace[2104554998] transaction","detail":"{read_only:false; response_revision:24939; number_of_response:1; }","duration":"131.510793ms","start":"2026-07-03T19:40:27.540666Z","end":"2026-07-03T19:40:27.672177Z","steps":["trace[2104554998] 'process raft request' (duration: 131.38983ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:27.672437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.581963ms","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-07-03T19:40:27.672502Z","caller":"traceutil/trace.go:171","msg":"trace[927077599] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:24939; }","duration":"105.729527ms","start":"2026-07-03T19:40:27.566761Z","end":"2026-07-03T19:40:27.67249Z","steps":["trace[927077599] 'agreement among raft nodes before linearized reading' (duration: 105.610794ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:40:27.67233Z","caller":"traceutil/trace.go:171","msg":"trace[1014182462] linearizableReadLoop","detail":"{readStateIndex:25763; appliedIndex:25760; }","duration":"105.256204ms","start":"2026-07-03T19:40:27.566838Z","end":"2026-07-03T19:40:27.672094Z","steps":["trace[1014182462] 'read index received' (duration: 91.467631ms)","trace[1014182462] 'applied index is now lower than readState.Index' (duration: 13.787563ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:40:33.087599Z","caller":"traceutil/trace.go:171","msg":"trace[1071720859] transaction","detail":"{read_only:false; response_revision:24996; number_of_response:1; }","duration":"105.593733ms","start":"2026-07-03T19:40:32.981972Z","end":"2026-07-03T19:40:33.087565Z","steps":["trace[1071720859] 'process raft request' (duration: 105.409848ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:33.335122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.5039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18229"} {"level":"info","ts":"2026-07-03T19:40:33.335236Z","caller":"traceutil/trace.go:171","msg":"trace[998413950] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24996; }","duration":"173.703385ms","start":"2026-07-03T19:40:33.161501Z","end":"2026-07-03T19:40:33.335204Z","steps":["trace[998413950] 'range keys from in-memory index tree' (duration: 173.122309ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:40:33.335581Z","caller":"traceutil/trace.go:171","msg":"trace[868712843] transaction","detail":"{read_only:false; response_revision:24997; number_of_response:1; }","duration":"102.691673ms","start":"2026-07-03T19:40:33.232854Z","end":"2026-07-03T19:40:33.335546Z","steps":["trace[868712843] 'process raft request' (duration: 32.257516ms)","trace[868712843] 'compare' (duration: 70.059596ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:40:37.470182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.077181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-03T19:40:37.470273Z","caller":"traceutil/trace.go:171","msg":"trace[1933420938] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesets/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesets0; response_count:0; response_revision:25036; }","duration":"185.253116ms","start":"2026-07-03T19:40:37.284999Z","end":"2026-07-03T19:40:37.470252Z","steps":["trace[1933420938] 'count revisions from in-memory index tree' (duration: 184.980088ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:40:37.554626Z","caller":"traceutil/trace.go:171","msg":"trace[2083578958] transaction","detail":"{read_only:false; response_revision:25037; number_of_response:1; }","duration":"204.898951ms","start":"2026-07-03T19:40:37.349677Z","end":"2026-07-03T19:40:37.554576Z","steps":["trace[2083578958] 'process raft request' (duration: 204.618344ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:40:37.69577Z","caller":"traceutil/trace.go:171","msg":"trace[1188864777] transaction","detail":"{read_only:false; response_revision:25038; number_of_response:1; }","duration":"134.729363ms","start":"2026-07-03T19:40:37.561006Z","end":"2026-07-03T19:40:37.695736Z","steps":["trace[1188864777] 'process raft request' (duration: 130.348261ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:40:37.912979Z","caller":"traceutil/trace.go:171","msg":"trace[257831474] transaction","detail":"{read_only:false; response_revision:25041; number_of_response:1; }","duration":"144.997508ms","start":"2026-07-03T19:40:37.767939Z","end":"2026-07-03T19:40:37.912937Z","steps":["trace[257831474] 'process raft request' (duration: 144.764742ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:40:37.974599Z","caller":"traceutil/trace.go:171","msg":"trace[1291484586] transaction","detail":"{read_only:false; response_revision:25042; number_of_response:1; }","duration":"205.886949ms","start":"2026-07-03T19:40:37.768678Z","end":"2026-07-03T19:40:37.974565Z","steps":["trace[1291484586] 'process raft request' (duration: 203.992276ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:40:37.97493Z","caller":"traceutil/trace.go:171","msg":"trace[1162614370] transaction","detail":"{read_only:false; response_revision:25043; number_of_response:1; }","duration":"206.170177ms","start":"2026-07-03T19:40:37.768677Z","end":"2026-07-03T19:40:37.974847Z","steps":["trace[1162614370] 'process raft request' (duration: 205.819827ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:40:37.975238Z","caller":"traceutil/trace.go:171","msg":"trace[860758360] transaction","detail":"{read_only:false; response_revision:25044; number_of_response:1; }","duration":"119.15971ms","start":"2026-07-03T19:40:37.856061Z","end":"2026-07-03T19:40:37.975221Z","steps":["trace[860758360] 'process raft request' (duration: 118.711368ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:40:37.975517Z","caller":"traceutil/trace.go:171","msg":"trace[920211412] transaction","detail":"{read_only:false; response_revision:25046; number_of_response:1; }","duration":"116.817105ms","start":"2026-07-03T19:40:37.85868Z","end":"2026-07-03T19:40:37.975497Z","steps":["trace[920211412] 'process raft request' (duration: 116.722012ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:40:37.975556Z","caller":"traceutil/trace.go:171","msg":"trace[2054056756] transaction","detail":"{read_only:false; response_revision:25045; number_of_response:1; }","duration":"119.384777ms","start":"2026-07-03T19:40:37.856145Z","end":"2026-07-03T19:40:37.97553Z","steps":["trace[2054056756] 'process raft request' (duration: 119.013516ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:41.241516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.259143ms","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-07-03T19:40:41.241633Z","caller":"traceutil/trace.go:171","msg":"trace[1873367465] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25066; }","duration":"119.442918ms","start":"2026-07-03T19:40:41.122164Z","end":"2026-07-03T19:40:41.241607Z","steps":["trace[1873367465] 'range keys from in-memory index tree' (duration: 119.056597ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:40:41.36391Z","caller":"traceutil/trace.go:171","msg":"trace[381022078] linearizableReadLoop","detail":"{readStateIndex:25894; appliedIndex:25893; }","duration":"102.59301ms","start":"2026-07-03T19:40:41.261285Z","end":"2026-07-03T19:40:41.363878Z","steps":["trace[381022078] 'read index received' (duration: 102.21521ms)","trace[381022078] 'applied index is now lower than readState.Index' (duration: 376.09µs)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:40:41.363994Z","caller":"traceutil/trace.go:171","msg":"trace[931471138] transaction","detail":"{read_only:false; response_revision:25067; number_of_response:1; }","duration":"117.290558ms","start":"2026-07-03T19:40:41.246667Z","end":"2026-07-03T19:40:41.363957Z","steps":["trace[931471138] 'process raft request' (duration: 116.943909ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:41.364301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.987061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/rabbitmq-manila-nodes\" ","response":"range_response_count:1 size:957"} {"level":"info","ts":"2026-07-03T19:40:41.364401Z","caller":"traceutil/trace.go:171","msg":"trace[1343775420] range","detail":"{range_begin:/registry/services/endpoints/openstack/rabbitmq-manila-nodes; range_end:; response_count:1; response_revision:25067; }","duration":"103.151726ms","start":"2026-07-03T19:40:41.261221Z","end":"2026-07-03T19:40:41.364373Z","steps":["trace[1343775420] 'agreement among raft nodes before linearized reading' (duration: 102.837137ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:40:41.401901Z","caller":"traceutil/trace.go:171","msg":"trace[148802673] transaction","detail":"{read_only:false; response_revision:25068; number_of_response:1; }","duration":"139.138235ms","start":"2026-07-03T19:40:41.262734Z","end":"2026-07-03T19:40:41.401873Z","steps":["trace[148802673] 'process raft request' (duration: 138.871478ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:40:50.025741Z","caller":"traceutil/trace.go:171","msg":"trace[1056747525] linearizableReadLoop","detail":"{readStateIndex:25956; appliedIndex:25955; }","duration":"125.067815ms","start":"2026-07-03T19:40:49.900639Z","end":"2026-07-03T19:40:50.025707Z","steps":["trace[1056747525] 'read index received' (duration: 98.473246ms)","trace[1056747525] 'applied index is now lower than readState.Index' (duration: 26.593479ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:40:50.025771Z","caller":"traceutil/trace.go:171","msg":"trace[890304120] transaction","detail":"{read_only:false; response_revision:25128; number_of_response:1; }","duration":"136.729458ms","start":"2026-07-03T19:40:49.889004Z","end":"2026-07-03T19:40:50.025733Z","steps":["trace[890304120] 'process raft request' (duration: 110.216392ms)","trace[890304120] 'compare' (duration: 26.203668ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:40:50.026014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.335932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.74\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-07-03T19:40:50.02653Z","caller":"traceutil/trace.go:171","msg":"trace[288469657] range","detail":"{range_begin:/registry/masterleases/199.204.45.74; range_end:; response_count:1; response_revision:25128; }","duration":"125.925388ms","start":"2026-07-03T19:40:49.900584Z","end":"2026-07-03T19:40:50.02651Z","steps":["trace[288469657] 'agreement among raft nodes before linearized reading' (duration: 125.211638ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:40:50.201999Z","caller":"traceutil/trace.go:171","msg":"trace[1297249752] transaction","detail":"{read_only:false; response_revision:25131; number_of_response:1; }","duration":"178.872509ms","start":"2026-07-03T19:40:50.023102Z","end":"2026-07-03T19:40:50.201974Z","steps":["trace[1297249752] 'process raft request' (duration: 178.778886ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:40:50.202045Z","caller":"traceutil/trace.go:171","msg":"trace[990313174] transaction","detail":"{read_only:false; response_revision:25130; number_of_response:1; }","duration":"178.978741ms","start":"2026-07-03T19:40:50.023006Z","end":"2026-07-03T19:40:50.201985Z","steps":["trace[990313174] 'process raft request' (duration: 178.672093ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:50.202445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.814097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-03T19:40:50.202519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.597929ms","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-07-03T19:40:50.202377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.103074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-03T19:40:50.20257Z","caller":"traceutil/trace.go:171","msg":"trace[1240932455] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:25131; }","duration":"250.968122ms","start":"2026-07-03T19:40:49.951571Z","end":"2026-07-03T19:40:50.20254Z","steps":["trace[1240932455] 'agreement among raft nodes before linearized reading' (duration: 250.726065ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:40:50.202617Z","caller":"traceutil/trace.go:171","msg":"trace[2115200273] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:25131; }","duration":"292.378522ms","start":"2026-07-03T19:40:49.910214Z","end":"2026-07-03T19:40:50.202593Z","steps":["trace[2115200273] 'agreement among raft nodes before linearized reading' (duration: 292.072064ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:40:50.202227Z","caller":"traceutil/trace.go:171","msg":"trace[1323478633] linearizableReadLoop","detail":"{readStateIndex:25959; appliedIndex:25956; }","duration":"176.39145ms","start":"2026-07-03T19:40:50.025803Z","end":"2026-07-03T19:40:50.202195Z","steps":["trace[1323478633] 'read index received' (duration: 14.371669ms)","trace[1323478633] 'applied index is now lower than readState.Index' (duration: 162.018001ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:40:50.202874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.886514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-07-03T19:40:50.202932Z","caller":"traceutil/trace.go:171","msg":"trace[1150729810] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25131; }","duration":"140.987346ms","start":"2026-07-03T19:40:50.061935Z","end":"2026-07-03T19:40:50.202922Z","steps":["trace[1150729810] 'agreement among raft nodes before linearized reading' (duration: 140.853713ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:50.202942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.464207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-07-03T19:40:50.202964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.377951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-03T19:40:50.203036Z","caller":"traceutil/trace.go:171","msg":"trace[1740122029] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25131; }","duration":"169.575631ms","start":"2026-07-03T19:40:50.033439Z","end":"2026-07-03T19:40:50.203015Z","steps":["trace[1740122029] 'agreement among raft nodes before linearized reading' (duration: 169.381245ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:40:50.203058Z","caller":"traceutil/trace.go:171","msg":"trace[1363104760] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25131; }","duration":"171.495684ms","start":"2026-07-03T19:40:50.031545Z","end":"2026-07-03T19:40:50.203041Z","steps":["trace[1363104760] 'agreement among raft nodes before linearized reading' (duration: 171.240896ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:40:50.203102Z","caller":"traceutil/trace.go:171","msg":"trace[2127176149] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25131; }","duration":"216.245567ms","start":"2026-07-03T19:40:49.986836Z","end":"2026-07-03T19:40:50.203081Z","steps":["trace[2127176149] 'agreement among raft nodes before linearized reading' (duration: 215.379843ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:52.373976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.360484ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-03T19:40:52.374082Z","caller":"traceutil/trace.go:171","msg":"trace[1243488049] linearizableReadLoop","detail":"{readStateIndex:25986; appliedIndex:25985; }","duration":"160.405556ms","start":"2026-07-03T19:40:52.213659Z","end":"2026-07-03T19:40:52.374064Z","steps":["trace[1243488049] 'read index received' (duration: 35.860127ms)","trace[1243488049] 'applied index is now lower than readState.Index' (duration: 124.544349ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:40:52.374248Z","caller":"traceutil/trace.go:171","msg":"trace[194660111] transaction","detail":"{read_only:false; response_revision:25157; number_of_response:1; }","duration":"163.80288ms","start":"2026-07-03T19:40:52.210434Z","end":"2026-07-03T19:40:52.374237Z","steps":["trace[194660111] 'process raft request' (duration: 39.075395ms)","trace[194660111] 'compare' (duration: 124.19704ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:40:52.374429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.740245ms","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-07-03T19:40:52.374501Z","caller":"traceutil/trace.go:171","msg":"trace[2028893403] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25157; }","duration":"160.867269ms","start":"2026-07-03T19:40:52.213617Z","end":"2026-07-03T19:40:52.374484Z","steps":["trace[2028893403] 'agreement among raft nodes before linearized reading' (duration: 160.675803ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:40:52.513861Z","caller":"traceutil/trace.go:171","msg":"trace[111367700] transaction","detail":"{read_only:false; response_revision:25159; number_of_response:1; }","duration":"238.541206ms","start":"2026-07-03T19:40:52.275279Z","end":"2026-07-03T19:40:52.51382Z","steps":["trace[111367700] 'process raft request' (duration: 238.455224ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:40:52.514044Z","caller":"traceutil/trace.go:171","msg":"trace[122161518] transaction","detail":"{read_only:false; response_revision:25160; number_of_response:1; }","duration":"235.823741ms","start":"2026-07-03T19:40:52.278199Z","end":"2026-07-03T19:40:52.514023Z","steps":["trace[122161518] 'process raft request' (duration: 235.581674ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:40:52.514272Z","caller":"traceutil/trace.go:171","msg":"trace[1713269628] linearizableReadLoop","detail":"{readStateIndex:25989; appliedIndex:25986; }","duration":"140.156243ms","start":"2026-07-03T19:40:52.374108Z","end":"2026-07-03T19:40:52.514264Z","steps":["trace[1713269628] 'read index received' (duration: 109.351678ms)","trace[1713269628] 'applied index is now lower than readState.Index' (duration: 30.804165ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:40:52.514308Z","caller":"traceutil/trace.go:171","msg":"trace[411805367] transaction","detail":"{read_only:false; response_revision:25161; number_of_response:1; }","duration":"135.817783ms","start":"2026-07-03T19:40:52.378477Z","end":"2026-07-03T19:40:52.514295Z","steps":["trace[411805367] 'process raft request' (duration: 135.497934ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:52.514454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.744398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-07-03T19:40:52.514484Z","caller":"traceutil/trace.go:171","msg":"trace[685999677] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:25161; }","duration":"145.82489ms","start":"2026-07-03T19:40:52.36865Z","end":"2026-07-03T19:40:52.514475Z","steps":["trace[685999677] 'agreement among raft nodes before linearized reading' (duration: 145.692717ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:52.51454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.909741ms","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-07-03T19:40:52.514575Z","caller":"traceutil/trace.go:171","msg":"trace[2069457104] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25161; }","duration":"299.971353ms","start":"2026-07-03T19:40:52.214594Z","end":"2026-07-03T19:40:52.514566Z","steps":["trace[2069457104] 'agreement among raft nodes before linearized reading' (duration: 299.830669ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:52.514605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:40:52.214589Z","time spent":"300.007954ms","remote":"127.0.0.1:34104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-03T19:40:52.514709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.076946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-03T19:40:52.514733Z","caller":"traceutil/trace.go:171","msg":"trace[1452705010] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25161; }","duration":"300.105806ms","start":"2026-07-03T19:40:52.21462Z","end":"2026-07-03T19:40:52.514726Z","steps":["trace[1452705010] 'agreement among raft nodes before linearized reading' (duration: 300.043805ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:52.514755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:40:52.214617Z","time spent":"300.133567ms","remote":"127.0.0.1:34104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-03T19:40:52.51497Z","caller":"traceutil/trace.go:171","msg":"trace[230101547] transaction","detail":"{read_only:false; response_revision:25158; number_of_response:1; }","duration":"291.706203ms","start":"2026-07-03T19:40:52.222551Z","end":"2026-07-03T19:40:52.514257Z","steps":["trace[230101547] 'process raft request' (duration: 260.895557ms)","trace[230101547] 'compare' (duration: 30.133077ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:40:56.616639Z","caller":"traceutil/trace.go:171","msg":"trace[1532400914] transaction","detail":"{read_only:false; response_revision:25190; number_of_response:1; }","duration":"192.878698ms","start":"2026-07-03T19:40:56.423735Z","end":"2026-07-03T19:40:56.616614Z","steps":["trace[1532400914] 'process raft request' (duration: 192.669372ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:40:56.616769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.734437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/ports/\" range_end:\"/registry/openstack.k-orc.cloud/ports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-03T19:40:56.61685Z","caller":"traceutil/trace.go:171","msg":"trace[1402717370] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/ports/; range_end:/registry/openstack.k-orc.cloud/ports0; response_count:0; response_revision:25190; }","duration":"168.83134ms","start":"2026-07-03T19:40:56.447994Z","end":"2026-07-03T19:40:56.616825Z","steps":["trace[1402717370] 'agreement among raft nodes before linearized reading' (duration: 168.699306ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:40:56.616594Z","caller":"traceutil/trace.go:171","msg":"trace[1984004349] linearizableReadLoop","detail":"{readStateIndex:26020; appliedIndex:26019; }","duration":"168.568743ms","start":"2026-07-03T19:40:56.448002Z","end":"2026-07-03T19:40:56.616571Z","steps":["trace[1984004349] 'read index received' (duration: 168.384658ms)","trace[1984004349] 'applied index is now lower than readState.Index' (duration: 182.955µs)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:41:00.252064Z","caller":"traceutil/trace.go:171","msg":"trace[357916800] linearizableReadLoop","detail":"{readStateIndex:26048; appliedIndex:26047; }","duration":"156.514188ms","start":"2026-07-03T19:41:00.095523Z","end":"2026-07-03T19:41:00.252037Z","steps":["trace[357916800] 'read index received' (duration: 156.267901ms)","trace[357916800] 'applied index is now lower than readState.Index' (duration: 245.027µs)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:41:00.252502Z","caller":"traceutil/trace.go:171","msg":"trace[870402453] transaction","detail":"{read_only:false; response_revision:25217; number_of_response:1; }","duration":"163.730298ms","start":"2026-07-03T19:41:00.088737Z","end":"2026-07-03T19:41:00.252468Z","steps":["trace[870402453] 'process raft request' (duration: 163.08926ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:41:00.252843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.295379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-03T19:41:00.252903Z","caller":"traceutil/trace.go:171","msg":"trace[1601275290] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25217; }","duration":"157.401223ms","start":"2026-07-03T19:41:00.095488Z","end":"2026-07-03T19:41:00.252889Z","steps":["trace[1601275290] 'agreement among raft nodes before linearized reading' (duration: 157.220988ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:41:02.485038Z","caller":"traceutil/trace.go:171","msg":"trace[561654993] transaction","detail":"{read_only:false; response_revision:25232; number_of_response:1; }","duration":"142.738985ms","start":"2026-07-03T19:41:02.342259Z","end":"2026-07-03T19:41:02.484998Z","steps":["trace[561654993] 'process raft request' (duration: 99.00471ms)","trace[561654993] 'compare' (duration: 43.57054ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:41:05.44298Z","caller":"traceutil/trace.go:171","msg":"trace[1967621842] transaction","detail":"{read_only:false; response_revision:25254; number_of_response:1; }","duration":"135.548245ms","start":"2026-07-03T19:41:05.307389Z","end":"2026-07-03T19:41:05.442937Z","steps":["trace[1967621842] 'process raft request' (duration: 135.263087ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:41:05.67913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.858033ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-07-03T19:41:05.679642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.958476ms","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-07-03T19:41:05.679701Z","caller":"traceutil/trace.go:171","msg":"trace[1725082288] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25254; }","duration":"109.074119ms","start":"2026-07-03T19:41:05.570611Z","end":"2026-07-03T19:41:05.679686Z","steps":["trace[1725082288] 'agreement among raft nodes before linearized reading' (duration: 108.822002ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:41:05.679388Z","caller":"traceutil/trace.go:171","msg":"trace[1697355259] linearizableReadLoop","detail":"{readStateIndex:26086; appliedIndex:26085; }","duration":"108.611276ms","start":"2026-07-03T19:41:05.570662Z","end":"2026-07-03T19:41:05.679274Z","steps":["trace[1697355259] 'read index received' (duration: 51.491µs)","trace[1697355259] 'applied index is now lower than readState.Index' (duration: 108.557565ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:41:12.702182Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006620.wal"} {"level":"info","ts":"2026-07-03T19:41:22.707183Z","caller":"traceutil/trace.go:171","msg":"trace[1560648769] linearizableReadLoop","detail":"{readStateIndex:26225; appliedIndex:26224; }","duration":"121.115084ms","start":"2026-07-03T19:41:22.586041Z","end":"2026-07-03T19:41:22.707156Z","steps":["trace[1560648769] 'read index received' (duration: 120.912258ms)","trace[1560648769] 'applied index is now lower than readState.Index' (duration: 201.276µs)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:41:22.707318Z","caller":"traceutil/trace.go:171","msg":"trace[1169565590] transaction","detail":"{read_only:false; response_revision:25389; number_of_response:1; }","duration":"134.078034ms","start":"2026-07-03T19:41:22.573191Z","end":"2026-07-03T19:41:22.707269Z","steps":["trace[1169565590] 'process raft request' (duration: 133.792136ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:41:22.707504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.409743ms","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-07-03T19:41:22.707568Z","caller":"traceutil/trace.go:171","msg":"trace[1110623323] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25389; }","duration":"121.577507ms","start":"2026-07-03T19:41:22.585978Z","end":"2026-07-03T19:41:22.707555Z","steps":["trace[1110623323] 'agreement among raft nodes before linearized reading' (duration: 121.348811ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:41:27.933149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.526776ms","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-07-03T19:41:27.933327Z","caller":"traceutil/trace.go:171","msg":"trace[1704765272] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25428; }","duration":"121.765762ms","start":"2026-07-03T19:41:27.811531Z","end":"2026-07-03T19:41:27.933297Z","steps":["trace[1704765272] 'range keys from in-memory index tree' (duration: 121.225628ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:41:27.933312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.807133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-07-03T19:41:27.933438Z","caller":"traceutil/trace.go:171","msg":"trace[1264159054] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:25428; }","duration":"194.020309ms","start":"2026-07-03T19:41:27.739389Z","end":"2026-07-03T19:41:27.933409Z","steps":["trace[1264159054] 'count revisions from in-memory index tree' (duration: 193.719041ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:41:27.933522Z","caller":"traceutil/trace.go:171","msg":"trace[176782612] transaction","detail":"{read_only:false; response_revision:25429; number_of_response:1; }","duration":"108.435651ms","start":"2026-07-03T19:41:27.825046Z","end":"2026-07-03T19:41:27.933482Z","steps":["trace[176782612] 'process raft request' (duration: 38.237012ms)","trace[176782612] 'compare' (duration: 69.957873ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:41:28.168137Z","caller":"traceutil/trace.go:171","msg":"trace[1130643022] transaction","detail":"{read_only:false; response_revision:25430; number_of_response:1; }","duration":"227.843929ms","start":"2026-07-03T19:41:27.940254Z","end":"2026-07-03T19:41:28.168098Z","steps":["trace[1130643022] 'process raft request' (duration: 151.538339ms)","trace[1130643022] 'compare' (duration: 76.132615ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:41:28.225248Z","caller":"traceutil/trace.go:171","msg":"trace[2079354708] linearizableReadLoop","detail":"{readStateIndex:26268; appliedIndex:26267; }","duration":"107.63153ms","start":"2026-07-03T19:41:28.117583Z","end":"2026-07-03T19:41:28.225215Z","steps":["trace[2079354708] 'read index received' (duration: 107.442565ms)","trace[2079354708] 'applied index is now lower than readState.Index' (duration: 187.355µs)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:41:28.225529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.924688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-03T19:41:28.225595Z","caller":"traceutil/trace.go:171","msg":"trace[1527844820] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:25431; }","duration":"108.071202ms","start":"2026-07-03T19:41:28.117506Z","end":"2026-07-03T19:41:28.225577Z","steps":["trace[1527844820] 'agreement among raft nodes before linearized reading' (duration: 107.832146ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:41:28.22572Z","caller":"traceutil/trace.go:171","msg":"trace[1071880980] transaction","detail":"{read_only:false; response_revision:25431; number_of_response:1; }","duration":"192.446156ms","start":"2026-07-03T19:41:28.033137Z","end":"2026-07-03T19:41:28.225583Z","steps":["trace[1071880980] 'process raft request' (duration: 191.913371ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:41:28.60949Z","caller":"traceutil/trace.go:171","msg":"trace[1602539559] transaction","detail":"{read_only:false; response_revision:25432; number_of_response:1; }","duration":"100.055259ms","start":"2026-07-03T19:41:28.509402Z","end":"2026-07-03T19:41:28.609457Z","steps":["trace[1602539559] 'process raft request' (duration: 99.822562ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:41:28.850634Z","caller":"traceutil/trace.go:171","msg":"trace[1098250312] linearizableReadLoop","detail":"{readStateIndex:26270; appliedIndex:26269; }","duration":"102.329622ms","start":"2026-07-03T19:41:28.748277Z","end":"2026-07-03T19:41:28.850607Z","steps":["trace[1098250312] 'read index received' (duration: 102.168018ms)","trace[1098250312] 'applied index is now lower than readState.Index' (duration: 160.594µs)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:41:28.850703Z","caller":"traceutil/trace.go:171","msg":"trace[1449057009] transaction","detail":"{read_only:false; response_revision:25433; number_of_response:1; }","duration":"109.617975ms","start":"2026-07-03T19:41:28.741036Z","end":"2026-07-03T19:41:28.850654Z","steps":["trace[1449057009] 'process raft request' (duration: 109.379808ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:41:28.85097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.677763ms","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-07-03T19:41:28.851021Z","caller":"traceutil/trace.go:171","msg":"trace[467618759] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25433; }","duration":"102.799876ms","start":"2026-07-03T19:41:28.74821Z","end":"2026-07-03T19:41:28.85101Z","steps":["trace[467618759] 'agreement among raft nodes before linearized reading' (duration: 102.525808ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:41:29.041071Z","caller":"traceutil/trace.go:171","msg":"trace[144157681] transaction","detail":"{read_only:false; response_revision:25435; number_of_response:1; }","duration":"261.572916ms","start":"2026-07-03T19:41:28.77947Z","end":"2026-07-03T19:41:29.041043Z","steps":["trace[144157681] 'process raft request' (duration: 261.478374ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:41:29.041556Z","caller":"traceutil/trace.go:171","msg":"trace[235811202] transaction","detail":"{read_only:false; response_revision:25436; number_of_response:1; }","duration":"257.268307ms","start":"2026-07-03T19:41:28.784253Z","end":"2026-07-03T19:41:29.041522Z","steps":["trace[235811202] 'process raft request' (duration: 256.751322ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:41:29.041465Z","caller":"traceutil/trace.go:171","msg":"trace[81507646] transaction","detail":"{read_only:false; response_revision:25434; number_of_response:1; }","duration":"280.08668ms","start":"2026-07-03T19:41:28.76132Z","end":"2026-07-03T19:41:29.041406Z","steps":["trace[81507646] 'process raft request' (duration: 191.406967ms)","trace[81507646] 'compare' (duration: 88.061666ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:41:29.041906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.108907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-07-03T19:41:29.041929Z","caller":"traceutil/trace.go:171","msg":"trace[1047153516] transaction","detail":"{read_only:false; response_revision:25437; number_of_response:1; }","duration":"186.935052ms","start":"2026-07-03T19:41:28.854975Z","end":"2026-07-03T19:41:29.04191Z","steps":["trace[1047153516] 'process raft request' (duration: 186.537621ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:41:29.041628Z","caller":"traceutil/trace.go:171","msg":"trace[1009793161] linearizableReadLoop","detail":"{readStateIndex:26273; appliedIndex:26270; }","duration":"190.907923ms","start":"2026-07-03T19:41:28.850706Z","end":"2026-07-03T19:41:29.041614Z","steps":["trace[1009793161] 'read index received' (duration: 101.992333ms)","trace[1009793161] 'applied index is now lower than readState.Index' (duration: 88.91389ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:41:29.041963Z","caller":"traceutil/trace.go:171","msg":"trace[724767914] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25436; }","duration":"250.19933ms","start":"2026-07-03T19:41:28.79175Z","end":"2026-07-03T19:41:29.041949Z","steps":["trace[724767914] 'agreement among raft nodes before linearized reading' (duration: 249.904192ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:41:29.041962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.392262ms","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-07-03T19:41:29.042274Z","caller":"traceutil/trace.go:171","msg":"trace[477713082] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25436; }","duration":"247.68934ms","start":"2026-07-03T19:41:28.794531Z","end":"2026-07-03T19:41:29.042221Z","steps":["trace[477713082] 'agreement among raft nodes before linearized reading' (duration: 247.114214ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:41:29.042368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.905649ms","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-07-03T19:41:29.042422Z","caller":"traceutil/trace.go:171","msg":"trace[493161572] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25436; }","duration":"251.011263ms","start":"2026-07-03T19:41:28.791398Z","end":"2026-07-03T19:41:29.042409Z","steps":["trace[493161572] 'agreement among raft nodes before linearized reading' (duration: 250.266723ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:42:16.773192Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23018} {"level":"info","ts":"2026-07-03T19:42:16.919545Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23018,"took":"140.79682ms","hash":1532595241,"current-db-size-bytes":57806848,"current-db-size":"58 MB","current-db-size-in-use-bytes":35147776,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-07-03T19:42:16.919661Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1532595241,"revision":23018,"compact-revision":20010} {"level":"info","ts":"2026-07-03T19:47:16.782095Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25960} {"level":"info","ts":"2026-07-03T19:47:16.921373Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25960,"took":"134.113905ms","hash":1468621462,"current-db-size-bytes":57806848,"current-db-size":"58 MB","current-db-size-in-use-bytes":36732928,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-07-03T19:47:16.921496Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1468621462,"revision":25960,"compact-revision":23018} {"level":"info","ts":"2026-07-03T19:48:13.393959Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"89ba38f964b95aa0","local-member-applied-index":30004,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-03T19:48:13.398651Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30004} {"level":"info","ts":"2026-07-03T19:48:13.398747Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25004} {"level":"info","ts":"2026-07-03T19:52:16.788773Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28655} {"level":"info","ts":"2026-07-03T19:52:16.932832Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28655,"took":"139.370995ms","hash":3087216724,"current-db-size-bytes":57806848,"current-db-size":"58 MB","current-db-size-in-use-bytes":30375936,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-03T19:52:16.932987Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3087216724,"revision":28655,"compact-revision":25960} {"level":"info","ts":"2026-07-03T19:57:16.794609Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30877} {"level":"info","ts":"2026-07-03T19:57:16.908346Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30877,"took":"109.541975ms","hash":142871207,"current-db-size-bytes":57806848,"current-db-size":"58 MB","current-db-size-in-use-bytes":31248384,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-03T19:57:16.908417Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":142871207,"revision":30877,"compact-revision":28655} {"level":"info","ts":"2026-07-03T20:02:16.809942Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33139} {"level":"info","ts":"2026-07-03T20:02:16.971011Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33139,"took":"155.611012ms","hash":592428842,"current-db-size-bytes":58851328,"current-db-size":"59 MB","current-db-size-in-use-bytes":31830016,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-03T20:02:16.971104Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":592428842,"revision":33139,"compact-revision":30877} {"level":"info","ts":"2026-07-03T20:07:16.820861Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35493} {"level":"info","ts":"2026-07-03T20:07:16.971902Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35493,"took":"144.317952ms","hash":2354378968,"current-db-size-bytes":60579840,"current-db-size":"61 MB","current-db-size-in-use-bytes":31993856,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-03T20:07:16.971999Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2354378968,"revision":35493,"compact-revision":33139} {"level":"info","ts":"2026-07-03T20:09:32.913708Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"89ba38f964b95aa0","local-member-applied-index":40005,"local-member-snapshot-index":30004,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-03T20:09:32.92236Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40005} {"level":"info","ts":"2026-07-03T20:09:32.922491Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35005} {"level":"info","ts":"2026-07-03T20:12:16.828689Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37753} {"level":"info","ts":"2026-07-03T20:12:16.951357Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37753,"took":"118.622843ms","hash":1848261180,"current-db-size-bytes":60579840,"current-db-size":"61 MB","current-db-size-in-use-bytes":32219136,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-03T20:12:16.951437Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1848261180,"revision":37753,"compact-revision":35493}