{"level":"warn","ts":"2026-06-26T10:20:31.15888Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-26T10:20:31.158972Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.243:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.243:2380","--initial-cluster=instance=https://199.19.213.243:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.243:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.243:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-06-26T10:20:31.15905Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-26T10:20:31.159067Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.243:2380"]} {"level":"info","ts":"2026-06-26T10:20:31.159089Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-06-26T10:20:31.159698Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.243:2379"]} {"level":"info","ts":"2026-06-26T10:20:31.159763Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.243:2380"],"listen-peer-urls":["https://199.19.213.243:2380"],"advertise-client-urls":["https://199.19.213.243:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.243:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.243:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-06-26T10:20:31.163026Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.05488ms"} {"level":"info","ts":"2026-06-26T10:20:31.179753Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"864e14457b828f9c","cluster-id":"6c0ae60a355554b9"} {"level":"info","ts":"2026-06-26T10:20:31.179904Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c switched to configuration voters=()"} {"level":"info","ts":"2026-06-26T10:20:31.179961Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c became follower at term 0"} {"level":"info","ts":"2026-06-26T10:20:31.179983Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 864e14457b828f9c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-26T10:20:31.179997Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c became follower at term 1"} {"level":"info","ts":"2026-06-26T10:20:31.180102Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c switched to configuration voters=(9677694937923227548)"} {"level":"warn","ts":"2026-06-26T10:20:31.187246Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-26T10:20:31.189182Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-26T10:20:31.192615Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-06-26T10:20:31.195585Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"864e14457b828f9c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-26T10:20:31.196205Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"864e14457b828f9c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-26T10:20:31.196347Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-26T10:20:31.196798Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-26T10:20:31.196864Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-26T10:20:31.19748Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c switched to configuration voters=(9677694937923227548)"} {"level":"info","ts":"2026-06-26T10:20:31.197726Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6c0ae60a355554b9","local-member-id":"864e14457b828f9c","added-peer-id":"864e14457b828f9c","added-peer-peer-urls":["https://199.19.213.243:2380"]} {"level":"info","ts":"2026-06-26T10:20:31.201441Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-06-26T10:20:31.201705Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"864e14457b828f9c","initial-advertise-peer-urls":["https://199.19.213.243:2380"],"listen-peer-urls":["https://199.19.213.243:2380"],"advertise-client-urls":["https://199.19.213.243:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.243:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-26T10:20:31.201751Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-26T10:20:31.202287Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.243:2380"} {"level":"info","ts":"2026-06-26T10:20:31.202331Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.243:2380"} {"level":"info","ts":"2026-06-26T10:20:31.281448Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c is starting a new election at term 1"} {"level":"info","ts":"2026-06-26T10:20:31.281513Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c became pre-candidate at term 1"} {"level":"info","ts":"2026-06-26T10:20:31.281553Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c received MsgPreVoteResp from 864e14457b828f9c at term 1"} {"level":"info","ts":"2026-06-26T10:20:31.281569Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c became candidate at term 2"} {"level":"info","ts":"2026-06-26T10:20:31.28158Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c received MsgVoteResp from 864e14457b828f9c at term 2"} {"level":"info","ts":"2026-06-26T10:20:31.281591Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c became leader at term 2"} {"level":"info","ts":"2026-06-26T10:20:31.2816Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 864e14457b828f9c elected leader 864e14457b828f9c at term 2"} {"level":"info","ts":"2026-06-26T10:20:31.282378Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-26T10:20:31.283212Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"864e14457b828f9c","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.243:2379]}","request-path":"/0/members/864e14457b828f9c/attributes","cluster-id":"6c0ae60a355554b9","publish-timeout":"7s"} {"level":"info","ts":"2026-06-26T10:20:31.283222Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-26T10:20:31.283367Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-26T10:20:31.283506Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-26T10:20:31.283533Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-26T10:20:31.284131Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6c0ae60a355554b9","local-member-id":"864e14457b828f9c","cluster-version":"3.5"} {"level":"info","ts":"2026-06-26T10:20:31.284329Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-26T10:20:31.284379Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-26T10:20:31.285689Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-26T10:20:31.287434Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.243:2379"} {"level":"info","ts":"2026-06-26T10:21:09.309304Z","caller":"traceutil/trace.go:171","msg":"trace[1153335706] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"211.331761ms","start":"2026-06-26T10:21:09.097939Z","end":"2026-06-26T10:21:09.309271Z","steps":["trace[1153335706] 'process raft request' (duration: 199.475404ms)","trace[1153335706] 'compare' (duration: 11.622241ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T10:21:09.309417Z","caller":"traceutil/trace.go:171","msg":"trace[110827936] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"201.279807ms","start":"2026-06-26T10:21:09.108096Z","end":"2026-06-26T10:21:09.309375Z","steps":["trace[110827936] 'process raft request' (duration: 201.093822ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:21:09.309453Z","caller":"traceutil/trace.go:171","msg":"trace[345014084] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"165.714203ms","start":"2026-06-26T10:21:09.143722Z","end":"2026-06-26T10:21:09.309436Z","steps":["trace[345014084] 'process raft request' (duration: 165.6142ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:21:09.309472Z","caller":"traceutil/trace.go:171","msg":"trace[757732679] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"183.859416ms","start":"2026-06-26T10:21:09.125589Z","end":"2026-06-26T10:21:09.309449Z","steps":["trace[757732679] 'process raft request' (duration: 183.676651ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:21:15.735578Z","caller":"traceutil/trace.go:171","msg":"trace[624381043] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"187.707299ms","start":"2026-06-26T10:21:15.547833Z","end":"2026-06-26T10:21:15.735541Z","steps":["trace[624381043] 'process raft request' (duration: 186.267536ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:21:15.895232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.40941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-26T10:21:15.89537Z","caller":"traceutil/trace.go:171","msg":"trace[1746506055] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:588; }","duration":"127.592115ms","start":"2026-06-26T10:21:15.767758Z","end":"2026-06-26T10:21:15.89535Z","steps":["trace[1746506055] 'range keys from in-memory index tree' (duration: 127.310398ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:21:15.895425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.646375ms","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-06-26T10:21:15.895679Z","caller":"traceutil/trace.go:171","msg":"trace[1786557964] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:588; }","duration":"129.908772ms","start":"2026-06-26T10:21:15.765719Z","end":"2026-06-26T10:21:15.895628Z","steps":["trace[1786557964] 'range keys from in-memory index tree' (duration: 129.401328ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:21:16.430054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.716296ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-26T10:21:16.430181Z","caller":"traceutil/trace.go:171","msg":"trace[429053835] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"464.942465ms","start":"2026-06-26T10:21:15.965221Z","end":"2026-06-26T10:21:16.430163Z","steps":["trace[429053835] 'process raft request' (duration: 302.357323ms)","trace[429053835] 'compare' (duration: 161.564352ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T10:21:16.430251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:21:15.965196Z","time spent":"465.016487ms","remote":"127.0.0.1:44188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-26T10:22:46.061075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.950585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-26T10:22:46.06127Z","caller":"traceutil/trace.go:171","msg":"trace[184463052] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1830; }","duration":"115.229913ms","start":"2026-06-26T10:22:45.946009Z","end":"2026-06-26T10:22:46.061239Z","steps":["trace[184463052] 'range keys from in-memory index tree' (duration: 114.631676ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:22:46.061233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.326703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-26T10:22:46.061423Z","caller":"traceutil/trace.go:171","msg":"trace[1554147756] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1830; }","duration":"292.562079ms","start":"2026-06-26T10:22:45.768819Z","end":"2026-06-26T10:22:46.061381Z","steps":["trace[1554147756] 'range keys from in-memory index tree' (duration: 292.141067ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:22:46.53449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.182661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-26T10:22:46.534484Z","caller":"traceutil/trace.go:171","msg":"trace[2110590912] transaction","detail":"{read_only:false; response_revision:1834; number_of_response:1; }","duration":"188.779982ms","start":"2026-06-26T10:22:46.345663Z","end":"2026-06-26T10:22:46.534443Z","steps":["trace[2110590912] 'process raft request' (duration: 185.322501ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:22:46.534585Z","caller":"traceutil/trace.go:171","msg":"trace[55394553] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1833; }","duration":"194.344836ms","start":"2026-06-26T10:22:46.34022Z","end":"2026-06-26T10:22:46.534565Z","steps":["trace[55394553] 'count revisions from in-memory index tree' (duration: 193.971435ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:22:46.542385Z","caller":"traceutil/trace.go:171","msg":"trace[1323078893] transaction","detail":"{read_only:false; response_revision:1835; number_of_response:1; }","duration":"180.009585ms","start":"2026-06-26T10:22:46.362349Z","end":"2026-06-26T10:22:46.542358Z","steps":["trace[1323078893] 'process raft request' (duration: 179.83187ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:22:46.542744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.57659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2fbcd602-4799-441a-91f2-5671e206a612\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-06-26T10:22:46.54289Z","caller":"traceutil/trace.go:171","msg":"trace[775432140] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2fbcd602-4799-441a-91f2-5671e206a612; range_end:; response_count:1; response_revision:1835; }","duration":"168.783846ms","start":"2026-06-26T10:22:46.37408Z","end":"2026-06-26T10:22:46.542863Z","steps":["trace[775432140] 'agreement among raft nodes before linearized reading' (duration: 168.417615ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:22:46.542744Z","caller":"traceutil/trace.go:171","msg":"trace[2097101864] linearizableReadLoop","detail":"{readStateIndex:1911; appliedIndex:1909; }","duration":"168.58999ms","start":"2026-06-26T10:22:46.374129Z","end":"2026-06-26T10:22:46.542719Z","steps":["trace[2097101864] 'read index received' (duration: 156.954888ms)","trace[2097101864] 'applied index is now lower than readState.Index' (duration: 11.22997ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T10:22:46.54324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.358605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-26T10:22:46.543415Z","caller":"traceutil/trace.go:171","msg":"trace[475217173] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1835; }","duration":"133.463628ms","start":"2026-06-26T10:22:46.409818Z","end":"2026-06-26T10:22:46.543282Z","steps":["trace[475217173] 'agreement among raft nodes before linearized reading' (duration: 133.288753ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:22:59.368391Z","caller":"traceutil/trace.go:171","msg":"trace[1056677205] transaction","detail":"{read_only:false; response_revision:1942; number_of_response:1; }","duration":"152.060484ms","start":"2026-06-26T10:22:59.216298Z","end":"2026-06-26T10:22:59.368359Z","steps":["trace[1056677205] 'process raft request' (duration: 151.847178ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:27:39.926981Z","caller":"traceutil/trace.go:171","msg":"trace[375114436] transaction","detail":"{read_only:false; response_revision:3844; number_of_response:1; }","duration":"105.610556ms","start":"2026-06-26T10:27:39.821344Z","end":"2026-06-26T10:27:39.926954Z","steps":["trace[375114436] 'process raft request' (duration: 105.458702ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:27:40.03817Z","caller":"traceutil/trace.go:171","msg":"trace[2088843944] transaction","detail":"{read_only:false; response_revision:3845; number_of_response:1; }","duration":"194.3496ms","start":"2026-06-26T10:27:39.843794Z","end":"2026-06-26T10:27:40.038144Z","steps":["trace[2088843944] 'process raft request' (duration: 194.184245ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:29:04.623455Z","caller":"traceutil/trace.go:171","msg":"trace[452350181] transaction","detail":"{read_only:false; response_revision:4862; number_of_response:1; }","duration":"104.23318ms","start":"2026-06-26T10:29:04.519198Z","end":"2026-06-26T10:29:04.623431Z","steps":["trace[452350181] 'process raft request' (duration: 104.086846ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:29:07.991493Z","caller":"traceutil/trace.go:171","msg":"trace[1744203739] transaction","detail":"{read_only:false; response_revision:5024; number_of_response:1; }","duration":"134.786591ms","start":"2026-06-26T10:29:07.85668Z","end":"2026-06-26T10:29:07.991466Z","steps":["trace[1744203739] 'process raft request' (duration: 134.605195ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:29:08.16921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.600755ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-26T10:29:08.169331Z","caller":"traceutil/trace.go:171","msg":"trace[1874137560] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5024; }","duration":"112.720928ms","start":"2026-06-26T10:29:08.056585Z","end":"2026-06-26T10:29:08.169306Z","steps":["trace[1874137560] 'range keys from in-memory index tree' (duration: 112.589074ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:29:08.16957Z","caller":"traceutil/trace.go:171","msg":"trace[1979083223] transaction","detail":"{read_only:false; response_revision:5025; number_of_response:1; }","duration":"167.730462ms","start":"2026-06-26T10:29:08.001831Z","end":"2026-06-26T10:29:08.169561Z","steps":["trace[1979083223] 'process raft request' (duration: 126.808348ms)","trace[1979083223] 'compare' (duration: 40.265064ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T10:29:08.169757Z","caller":"traceutil/trace.go:171","msg":"trace[380398077] transaction","detail":"{read_only:false; response_revision:5026; number_of_response:1; }","duration":"165.63955ms","start":"2026-06-26T10:29:08.004111Z","end":"2026-06-26T10:29:08.16975Z","steps":["trace[380398077] 'process raft request' (duration: 165.419714ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:29:22.164432Z","caller":"traceutil/trace.go:171","msg":"trace[1757576401] transaction","detail":"{read_only:false; response_revision:5293; number_of_response:1; }","duration":"110.842902ms","start":"2026-06-26T10:29:22.053557Z","end":"2026-06-26T10:29:22.1644Z","steps":["trace[1757576401] 'process raft request' (duration: 110.618636ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:29:22.360595Z","caller":"traceutil/trace.go:171","msg":"trace[1379583392] linearizableReadLoop","detail":"{readStateIndex:5500; appliedIndex:5498; }","duration":"124.334876ms","start":"2026-06-26T10:29:22.236243Z","end":"2026-06-26T10:29:22.360578Z","steps":["trace[1379583392] 'read index received' (duration: 123.235585ms)","trace[1379583392] 'applied index is now lower than readState.Index' (duration: 1.098351ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T10:29:22.360755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.498201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-26T10:29:22.360771Z","caller":"traceutil/trace.go:171","msg":"trace[888525896] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5296; }","duration":"124.555473ms","start":"2026-06-26T10:29:22.236211Z","end":"2026-06-26T10:29:22.360766Z","steps":["trace[888525896] 'agreement among raft nodes before linearized reading' (duration: 124.44697ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:29:22.360939Z","caller":"traceutil/trace.go:171","msg":"trace[240141726] transaction","detail":"{read_only:false; response_revision:5295; number_of_response:1; }","duration":"135.215223ms","start":"2026-06-26T10:29:22.225716Z","end":"2026-06-26T10:29:22.360931Z","steps":["trace[240141726] 'process raft request' (duration: 133.749621ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:29:22.361005Z","caller":"traceutil/trace.go:171","msg":"trace[685711718] transaction","detail":"{read_only:false; response_revision:5296; number_of_response:1; }","duration":"135.12727ms","start":"2026-06-26T10:29:22.225872Z","end":"2026-06-26T10:29:22.360999Z","steps":["trace[685711718] 'process raft request' (duration: 134.645497ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:29:30.556398Z","caller":"traceutil/trace.go:171","msg":"trace[1609979684] transaction","detail":"{read_only:false; response_revision:5468; number_of_response:1; }","duration":"116.579392ms","start":"2026-06-26T10:29:30.439784Z","end":"2026-06-26T10:29:30.556363Z","steps":["trace[1609979684] 'process raft request' (duration: 108.01536ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:30:31.704824Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2977} {"level":"info","ts":"2026-06-26T10:30:31.776352Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2977,"took":"70.051376ms","hash":3011466656,"current-db-size-bytes":58929152,"current-db-size":"59 MB","current-db-size-in-use-bytes":33808384,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-26T10:30:31.77641Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3011466656,"revision":2977,"compact-revision":-1} {"level":"info","ts":"2026-06-26T10:35:31.711407Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6037} {"level":"info","ts":"2026-06-26T10:35:31.814116Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6037,"took":"101.176429ms","hash":751239519,"current-db-size-bytes":58929152,"current-db-size":"59 MB","current-db-size-in-use-bytes":21725184,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-06-26T10:35:31.814195Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":751239519,"revision":6037,"compact-revision":2977} {"level":"warn","ts":"2026-06-26T10:35:52.977717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.837894ms","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-06-26T10:35:52.977828Z","caller":"traceutil/trace.go:171","msg":"trace[1395432734] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8369; }","duration":"224.015319ms","start":"2026-06-26T10:35:52.753791Z","end":"2026-06-26T10:35:52.977806Z","steps":["trace[1395432734] 'range keys from in-memory index tree' (duration: 223.608527ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:35:53.111098Z","caller":"traceutil/trace.go:171","msg":"trace[745542643] transaction","detail":"{read_only:false; response_revision:8370; number_of_response:1; }","duration":"128.29585ms","start":"2026-06-26T10:35:52.982776Z","end":"2026-06-26T10:35:53.111072Z","steps":["trace[745542643] 'process raft request' (duration: 128.168206ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:36:04.194143Z","caller":"traceutil/trace.go:171","msg":"trace[1015788958] transaction","detail":"{read_only:false; response_revision:8425; number_of_response:1; }","duration":"274.492935ms","start":"2026-06-26T10:36:03.919617Z","end":"2026-06-26T10:36:04.19411Z","steps":["trace[1015788958] 'process raft request' (duration: 274.283849ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:36:04.198635Z","caller":"traceutil/trace.go:171","msg":"trace[1739247384] transaction","detail":"{read_only:false; response_revision:8427; number_of_response:1; }","duration":"241.954121ms","start":"2026-06-26T10:36:03.956657Z","end":"2026-06-26T10:36:04.198611Z","steps":["trace[1739247384] 'process raft request' (duration: 241.92081ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:36:04.198722Z","caller":"traceutil/trace.go:171","msg":"trace[517438361] transaction","detail":"{read_only:false; response_revision:8426; number_of_response:1; }","duration":"257.053379ms","start":"2026-06-26T10:36:03.941626Z","end":"2026-06-26T10:36:04.19868Z","steps":["trace[517438361] 'process raft request' (duration: 256.859803ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:36:04.89029Z","caller":"traceutil/trace.go:171","msg":"trace[641739402] linearizableReadLoop","detail":"{readStateIndex:8733; appliedIndex:8732; }","duration":"123.977633ms","start":"2026-06-26T10:36:04.766287Z","end":"2026-06-26T10:36:04.890264Z","steps":["trace[641739402] 'read index received' (duration: 123.798927ms)","trace[641739402] 'applied index is now lower than readState.Index' (duration: 178.096µs)"],"step_count":2} {"level":"info","ts":"2026-06-26T10:36:04.890345Z","caller":"traceutil/trace.go:171","msg":"trace[6030471] transaction","detail":"{read_only:false; response_revision:8430; number_of_response:1; }","duration":"250.838429ms","start":"2026-06-26T10:36:04.639489Z","end":"2026-06-26T10:36:04.890327Z","steps":["trace[6030471] 'process raft request' (duration: 250.656063ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:36:04.890432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.138718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-26T10:36:04.890467Z","caller":"traceutil/trace.go:171","msg":"trace[1404148809] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8430; }","duration":"124.277171ms","start":"2026-06-26T10:36:04.766181Z","end":"2026-06-26T10:36:04.890459Z","steps":["trace[1404148809] 'agreement among raft nodes before linearized reading' (duration: 124.206019ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:36:32.482828Z","caller":"traceutil/trace.go:171","msg":"trace[455823135] transaction","detail":"{read_only:false; response_revision:8632; number_of_response:1; }","duration":"194.558052ms","start":"2026-06-26T10:36:32.288251Z","end":"2026-06-26T10:36:32.482809Z","steps":["trace[455823135] 'process raft request' (duration: 194.430778ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:36:32.484064Z","caller":"traceutil/trace.go:171","msg":"trace[1573699480] linearizableReadLoop","detail":"{readStateIndex:8945; appliedIndex:8942; }","duration":"125.29522ms","start":"2026-06-26T10:36:32.358706Z","end":"2026-06-26T10:36:32.484001Z","steps":["trace[1573699480] 'read index received' (duration: 124.005782ms)","trace[1573699480] 'applied index is now lower than readState.Index' (duration: 1.288058ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T10:36:32.484284Z","caller":"traceutil/trace.go:171","msg":"trace[875219774] transaction","detail":"{read_only:false; response_revision:8633; number_of_response:1; }","duration":"194.924624ms","start":"2026-06-26T10:36:32.289349Z","end":"2026-06-26T10:36:32.484274Z","steps":["trace[875219774] 'process raft request' (duration: 194.536213ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:36:32.484429Z","caller":"traceutil/trace.go:171","msg":"trace[369660879] transaction","detail":"{read_only:false; response_revision:8634; number_of_response:1; }","duration":"189.405653ms","start":"2026-06-26T10:36:32.295014Z","end":"2026-06-26T10:36:32.48442Z","steps":["trace[369660879] 'process raft request' (duration: 188.95046ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:36:32.484588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.875158ms","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-06-26T10:36:32.484619Z","caller":"traceutil/trace.go:171","msg":"trace[1710069537] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8634; }","duration":"125.94472ms","start":"2026-06-26T10:36:32.358667Z","end":"2026-06-26T10:36:32.484612Z","steps":["trace[1710069537] 'agreement among raft nodes before linearized reading' (duration: 125.840277ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:36:45.392505Z","caller":"traceutil/trace.go:171","msg":"trace[791324959] linearizableReadLoop","detail":"{readStateIndex:9062; appliedIndex:9061; }","duration":"149.844853ms","start":"2026-06-26T10:36:45.242634Z","end":"2026-06-26T10:36:45.392479Z","steps":["trace[791324959] 'read index received' (duration: 149.453372ms)","trace[791324959] 'applied index is now lower than readState.Index' (duration: 390.411µs)"],"step_count":2} {"level":"info","ts":"2026-06-26T10:36:45.393046Z","caller":"traceutil/trace.go:171","msg":"trace[931805953] transaction","detail":"{read_only:false; response_revision:8744; number_of_response:1; }","duration":"150.473122ms","start":"2026-06-26T10:36:45.242558Z","end":"2026-06-26T10:36:45.393031Z","steps":["trace[931805953] 'process raft request' (duration: 149.587755ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:36:45.393629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.978747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-k4ln2\" ","response":"range_response_count:1 size:4648"} {"level":"info","ts":"2026-06-26T10:36:45.393775Z","caller":"traceutil/trace.go:171","msg":"trace[825790952] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-k4ln2; range_end:; response_count:1; response_revision:8744; }","duration":"151.09527ms","start":"2026-06-26T10:36:45.242589Z","end":"2026-06-26T10:36:45.393684Z","steps":["trace[825790952] 'agreement among raft nodes before linearized reading' (duration: 150.819262ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:36:45.717918Z","caller":"traceutil/trace.go:171","msg":"trace[269989018] transaction","detail":"{read_only:false; response_revision:8746; number_of_response:1; }","duration":"310.522022ms","start":"2026-06-26T10:36:45.407364Z","end":"2026-06-26T10:36:45.717886Z","steps":["trace[269989018] 'process raft request' (duration: 310.341067ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:36:45.718174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:36:45.407347Z","time spent":"310.662406ms","remote":"127.0.0.1:44188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4638,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-26T10:36:45.841668Z","caller":"traceutil/trace.go:171","msg":"trace[1440511799] linearizableReadLoop","detail":"{readStateIndex:9066; appliedIndex:9063; }","duration":"141.461501ms","start":"2026-06-26T10:36:45.700174Z","end":"2026-06-26T10:36:45.841635Z","steps":["trace[1440511799] 'read index received' (duration: 17.307423ms)","trace[1440511799] 'applied index is now lower than readState.Index' (duration: 124.152818ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T10:36:45.841725Z","caller":"traceutil/trace.go:171","msg":"trace[568354029] transaction","detail":"{read_only:false; response_revision:8747; number_of_response:1; }","duration":"415.338147ms","start":"2026-06-26T10:36:45.426335Z","end":"2026-06-26T10:36:45.841673Z","steps":["trace[568354029] 'process raft request' (duration: 409.052184ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:36:45.841805Z","caller":"traceutil/trace.go:171","msg":"trace[1801174102] transaction","detail":"{read_only:false; response_revision:8748; number_of_response:1; }","duration":"414.010459ms","start":"2026-06-26T10:36:45.427779Z","end":"2026-06-26T10:36:45.84179Z","steps":["trace[1801174102] 'process raft request' (duration: 413.804853ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:36:45.841936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:36:45.42775Z","time spent":"414.089412ms","remote":"127.0.0.1:44254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-26T10:36:45.84194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:36:45.426308Z","time spent":"415.507662ms","remote":"127.0.0.1:44254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-26T10:36:45.842309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.14184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-x9tx9\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-06-26T10:36:45.842352Z","caller":"traceutil/trace.go:171","msg":"trace[525390580] range","detail":"{range_begin:/registry/pods/openstack/keepalived-x9tx9; range_end:; response_count:1; response_revision:8748; }","duration":"142.184911ms","start":"2026-06-26T10:36:45.700157Z","end":"2026-06-26T10:36:45.842341Z","steps":["trace[525390580] 'agreement among raft nodes before linearized reading' (duration: 142.034257ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:36:46.71599Z","caller":"traceutil/trace.go:171","msg":"trace[1197580483] transaction","detail":"{read_only:false; response_revision:8751; number_of_response:1; }","duration":"132.706015ms","start":"2026-06-26T10:36:46.583255Z","end":"2026-06-26T10:36:46.715961Z","steps":["trace[1197580483] 'process raft request' (duration: 82.558917ms)","trace[1197580483] 'compare' (duration: 49.89089ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T10:36:46.71613Z","caller":"traceutil/trace.go:171","msg":"trace[56714978] linearizableReadLoop","detail":"{readStateIndex:9070; appliedIndex:9067; }","duration":"104.363062ms","start":"2026-06-26T10:36:46.611738Z","end":"2026-06-26T10:36:46.716101Z","steps":["trace[56714978] 'read index received' (duration: 3.79881ms)","trace[56714978] 'applied index is now lower than readState.Index' (duration: 100.561582ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T10:36:46.716191Z","caller":"traceutil/trace.go:171","msg":"trace[1145633423] transaction","detail":"{read_only:false; response_revision:8752; number_of_response:1; }","duration":"132.063596ms","start":"2026-06-26T10:36:46.58409Z","end":"2026-06-26T10:36:46.716154Z","steps":["trace[1145633423] 'process raft request' (duration: 131.819259ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:36:46.716408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.658421ms","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-06-26T10:36:46.716454Z","caller":"traceutil/trace.go:171","msg":"trace[1937021918] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8752; }","duration":"104.775734ms","start":"2026-06-26T10:36:46.611668Z","end":"2026-06-26T10:36:46.716443Z","steps":["trace[1937021918] 'agreement among raft nodes before linearized reading' (duration: 104.577218ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:36:49.867524Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002393.wal"} {"level":"info","ts":"2026-06-26T10:38:21.939648Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"864e14457b828f9c","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-26T10:38:21.949317Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-06-26T10:38:21.949409Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-06-26T10:40:31.718263Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8087} {"level":"info","ts":"2026-06-26T10:40:31.782266Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8087,"took":"61.532345ms","hash":2208463412,"current-db-size-bytes":58929152,"current-db-size":"59 MB","current-db-size-in-use-bytes":28295168,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-06-26T10:40:31.78242Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2208463412,"revision":8087,"compact-revision":6037} {"level":"warn","ts":"2026-06-26T10:43:40.917504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.222447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-26T10:43:40.917681Z","caller":"traceutil/trace.go:171","msg":"trace[1227875589] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11931; }","duration":"149.404442ms","start":"2026-06-26T10:43:40.768252Z","end":"2026-06-26T10:43:40.917656Z","steps":["trace[1227875589] 'range keys from in-memory index tree' (duration: 149.120405ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:43:41.935496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.646973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-26T10:43:41.93561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.994193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-26T10:43:41.935615Z","caller":"traceutil/trace.go:171","msg":"trace[1615891938] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11937; }","duration":"244.802948ms","start":"2026-06-26T10:43:41.690784Z","end":"2026-06-26T10:43:41.935587Z","steps":["trace[1615891938] 'range keys from in-memory index tree' (duration: 244.534131ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:43:41.935628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.17686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/cinder-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-06-26T10:43:41.935664Z","caller":"traceutil/trace.go:171","msg":"trace[282994903] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:11937; }","duration":"245.099007ms","start":"2026-06-26T10:43:41.690552Z","end":"2026-06-26T10:43:41.935651Z","steps":["trace[282994903] 'range keys from in-memory index tree' (duration: 244.85769ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:43:41.935699Z","caller":"traceutil/trace.go:171","msg":"trace[1728963052] range","detail":"{range_begin:/registry/services/endpoints/openstack/cinder-api; range_end:; response_count:1; response_revision:11937; }","duration":"245.283823ms","start":"2026-06-26T10:43:41.690395Z","end":"2026-06-26T10:43:41.935679Z","steps":["trace[1728963052] 'range keys from in-memory index tree' (duration: 244.936562ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:43:41.935738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.053946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-26T10:43:41.935827Z","caller":"traceutil/trace.go:171","msg":"trace[1440525966] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:11937; }","duration":"245.180209ms","start":"2026-06-26T10:43:41.690627Z","end":"2026-06-26T10:43:41.935807Z","steps":["trace[1440525966] 'range keys from in-memory index tree' (duration: 244.983453ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:43:41.935969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.578634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5077"} {"level":"info","ts":"2026-06-26T10:43:41.936131Z","caller":"traceutil/trace.go:171","msg":"trace[1654959935] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:11937; }","duration":"242.76617ms","start":"2026-06-26T10:43:41.693328Z","end":"2026-06-26T10:43:41.936094Z","steps":["trace[1654959935] 'range keys from in-memory index tree' (duration: 242.389288ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:43:41.936728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.243151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-26T10:43:41.936783Z","caller":"traceutil/trace.go:171","msg":"trace[2038259165] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11937; }","duration":"246.339113ms","start":"2026-06-26T10:43:41.690431Z","end":"2026-06-26T10:43:41.93677Z","steps":["trace[2038259165] 'range keys from in-memory index tree' (duration: 246.146456ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:45:31.724435Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10487} {"level":"info","ts":"2026-06-26T10:45:31.809505Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10487,"took":"83.263198ms","hash":19134869,"current-db-size-bytes":58929152,"current-db-size":"59 MB","current-db-size-in-use-bytes":26247168,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-06-26T10:45:31.809575Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":19134869,"revision":10487,"compact-revision":8087} {"level":"warn","ts":"2026-06-26T10:50:28.402368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.698633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:63 size:594922"} {"level":"info","ts":"2026-06-26T10:50:28.402483Z","caller":"traceutil/trace.go:171","msg":"trace[1050597527] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:63; response_revision:14641; }","duration":"123.868456ms","start":"2026-06-26T10:50:28.278592Z","end":"2026-06-26T10:50:28.40246Z","steps":["trace[1050597527] 'range keys from in-memory index tree' (duration: 122.887777ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:50:29.62727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.660485ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-26T10:50:29.62773Z","caller":"traceutil/trace.go:171","msg":"trace[678425153] transaction","detail":"{read_only:false; response_revision:14645; number_of_response:1; }","duration":"499.659566ms","start":"2026-06-26T10:50:29.128005Z","end":"2026-06-26T10:50:29.627665Z","steps":["trace[678425153] 'process raft request' (duration: 263.717004ms)","trace[678425153] 'compare' (duration: 234.513611ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T10:50:29.627763Z","caller":"traceutil/trace.go:171","msg":"trace[1725877898] transaction","detail":"{read_only:false; response_revision:14646; number_of_response:1; }","duration":"290.561869ms","start":"2026-06-26T10:50:29.337159Z","end":"2026-06-26T10:50:29.627721Z","steps":["trace[1725877898] 'process raft request' (duration: 290.277371ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:50:29.627866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:50:29.127967Z","time spent":"499.834741ms","remote":"127.0.0.1:44254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-26T10:50:29.627887Z","caller":"traceutil/trace.go:171","msg":"trace[1618298718] linearizableReadLoop","detail":"{readStateIndex:15161; appliedIndex:15159; }","duration":"193.467625ms","start":"2026-06-26T10:50:29.434388Z","end":"2026-06-26T10:50:29.627855Z","steps":["trace[1618298718] 'read index received' (duration: 141.337801ms)","trace[1618298718] 'applied index is now lower than readState.Index' (duration: 52.128424ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T10:50:29.62811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.704583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-06-26T10:50:29.628156Z","caller":"traceutil/trace.go:171","msg":"trace[707918532] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14646; }","duration":"193.795695ms","start":"2026-06-26T10:50:29.434347Z","end":"2026-06-26T10:50:29.628142Z","steps":["trace[707918532] 'agreement among raft nodes before linearized reading' (duration: 193.61959ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:50:31.844332Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12674} {"level":"info","ts":"2026-06-26T10:50:31.947963Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12674,"took":"100.924224ms","hash":3066825722,"current-db-size-bytes":58929152,"current-db-size":"59 MB","current-db-size-in-use-bytes":27422720,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-06-26T10:50:31.948112Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3066825722,"revision":12674,"compact-revision":10487} {"level":"info","ts":"2026-06-26T10:50:38.846958Z","caller":"traceutil/trace.go:171","msg":"trace[1382124038] linearizableReadLoop","detail":"{readStateIndex:15331; appliedIndex:15330; }","duration":"177.311379ms","start":"2026-06-26T10:50:38.669621Z","end":"2026-06-26T10:50:38.846932Z","steps":["trace[1382124038] 'read index received' (duration: 177.108023ms)","trace[1382124038] 'applied index is now lower than readState.Index' (duration: 202.456µs)"],"step_count":2} {"level":"info","ts":"2026-06-26T10:50:38.84701Z","caller":"traceutil/trace.go:171","msg":"trace[1462691549] transaction","detail":"{read_only:false; response_revision:14812; number_of_response:1; }","duration":"183.766536ms","start":"2026-06-26T10:50:38.6632Z","end":"2026-06-26T10:50:38.846966Z","steps":["trace[1462691549] 'process raft request' (duration: 183.599741ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:50:38.847399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.766102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4581eabc-f9ea-44a2-9f2b-6d55cc020d23\" ","response":"range_response_count:1 size:3607"} {"level":"info","ts":"2026-06-26T10:50:38.847502Z","caller":"traceutil/trace.go:171","msg":"trace[843490255] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4581eabc-f9ea-44a2-9f2b-6d55cc020d23; range_end:; response_count:1; response_revision:14812; }","duration":"177.916406ms","start":"2026-06-26T10:50:38.669561Z","end":"2026-06-26T10:50:38.847478Z","steps":["trace[843490255] 'agreement among raft nodes before linearized reading' (duration: 177.485914ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:52:07.752725Z","caller":"traceutil/trace.go:171","msg":"trace[440396933] transaction","detail":"{read_only:false; response_revision:15844; number_of_response:1; }","duration":"124.633079ms","start":"2026-06-26T10:52:07.627995Z","end":"2026-06-26T10:52:07.752628Z","steps":["trace[440396933] 'process raft request' (duration: 124.406902ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:52:08.692873Z","caller":"traceutil/trace.go:171","msg":"trace[244124591] transaction","detail":"{read_only:false; response_revision:15852; number_of_response:1; }","duration":"144.455921ms","start":"2026-06-26T10:52:08.548388Z","end":"2026-06-26T10:52:08.692844Z","steps":["trace[244124591] 'process raft request' (duration: 144.246125ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:52:08.856676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.629545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-06-26T10:52:08.856762Z","caller":"traceutil/trace.go:171","msg":"trace[140420204] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15852; }","duration":"109.757169ms","start":"2026-06-26T10:52:08.746984Z","end":"2026-06-26T10:52:08.856741Z","steps":["trace[140420204] 'range keys from in-memory index tree' (duration: 109.483021ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:52:08.856855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.605248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-06-26T10:52:08.856912Z","caller":"traceutil/trace.go:171","msg":"trace[160990305] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15852; }","duration":"105.6899ms","start":"2026-06-26T10:52:08.751208Z","end":"2026-06-26T10:52:08.856898Z","steps":["trace[160990305] 'range keys from in-memory index tree' (duration: 105.465804ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:52:08.856896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.377356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-26T10:52:08.857021Z","caller":"traceutil/trace.go:171","msg":"trace[1626800556] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15852; }","duration":"110.549092ms","start":"2026-06-26T10:52:08.746445Z","end":"2026-06-26T10:52:08.856994Z","steps":["trace[1626800556] 'range keys from in-memory index tree' (duration: 110.248802ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:55:31.851021Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14667} {"level":"info","ts":"2026-06-26T10:55:31.933542Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14667,"took":"78.860203ms","hash":2784379156,"current-db-size-bytes":58929152,"current-db-size":"59 MB","current-db-size-in-use-bytes":33873920,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-26T10:55:31.933618Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2784379156,"revision":14667,"compact-revision":12674} {"level":"warn","ts":"2026-06-26T10:56:37.599921Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10348320880937077194,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-26T10:56:38.049235Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.095377387s","expected-duration":"1s"} {"level":"info","ts":"2026-06-26T10:56:38.050232Z","caller":"traceutil/trace.go:171","msg":"trace[376016357] transaction","detail":"{read_only:false; response_revision:17819; number_of_response:1; }","duration":"1.096469209s","start":"2026-06-26T10:56:36.953729Z","end":"2026-06-26T10:56:38.050198Z","steps":["trace[376016357] 'process raft request' (duration: 1.095872682s)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:56:38.050656Z","caller":"traceutil/trace.go:171","msg":"trace[1007303237] linearizableReadLoop","detail":"{readStateIndex:18434; appliedIndex:18431; }","duration":"951.663157ms","start":"2026-06-26T10:56:37.098978Z","end":"2026-06-26T10:56:38.050641Z","steps":["trace[1007303237] 'read index received' (duration: 950.642968ms)","trace[1007303237] 'applied index is now lower than readState.Index' (duration: 1.019219ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T10:56:38.050748Z","caller":"traceutil/trace.go:171","msg":"trace[1319773385] transaction","detail":"{read_only:false; response_revision:17820; number_of_response:1; }","duration":"759.22446ms","start":"2026-06-26T10:56:37.291514Z","end":"2026-06-26T10:56:38.050738Z","steps":["trace[1319773385] 'process raft request' (duration: 758.939882ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:56:38.050814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:56:37.291482Z","time spent":"759.293642ms","remote":"127.0.0.1:44254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-26T10:56:38.051093Z","caller":"traceutil/trace.go:171","msg":"trace[400528239] transaction","detail":"{read_only:false; response_revision:17821; number_of_response:1; }","duration":"759.147259ms","start":"2026-06-26T10:56:37.291891Z","end":"2026-06-26T10:56:38.051038Z","steps":["trace[400528239] 'process raft request' (duration: 758.632533ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:56:38.051181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.395975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"info","ts":"2026-06-26T10:56:38.051173Z","caller":"traceutil/trace.go:171","msg":"trace[899942345] transaction","detail":"{read_only:false; response_revision:17822; number_of_response:1; }","duration":"294.525276ms","start":"2026-06-26T10:56:37.756605Z","end":"2026-06-26T10:56:38.05113Z","steps":["trace[899942345] 'process raft request' (duration: 293.954259ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:56:38.05124Z","caller":"traceutil/trace.go:171","msg":"trace[548150794] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:17823; }","duration":"531.510978ms","start":"2026-06-26T10:56:37.519715Z","end":"2026-06-26T10:56:38.051226Z","steps":["trace[548150794] 'agreement among raft nodes before linearized reading' (duration: 531.388035ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:56:38.051219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"817.132955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-06-26T10:56:38.051272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:56:37.51968Z","time spent":"531.58726ms","remote":"127.0.0.1:44172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":726,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"info","ts":"2026-06-26T10:56:38.051306Z","caller":"traceutil/trace.go:171","msg":"trace[2076835035] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:17823; }","duration":"817.29003ms","start":"2026-06-26T10:56:37.233993Z","end":"2026-06-26T10:56:38.051283Z","steps":["trace[2076835035] 'agreement among raft nodes before linearized reading' (duration: 817.107885ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:56:38.05136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:56:37.233967Z","time spent":"817.379743ms","remote":"127.0.0.1:53664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-06-26T10:56:38.051393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:56:37.29188Z","time spent":"759.321924ms","remote":"127.0.0.1:44254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-26T10:56:38.051496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"952.529883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-06-26T10:56:38.051497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.084586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-26T10:56:38.051516Z","caller":"traceutil/trace.go:171","msg":"trace[2019004900] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17823; }","duration":"952.588404ms","start":"2026-06-26T10:56:37.098922Z","end":"2026-06-26T10:56:38.05151Z","steps":["trace[2019004900] 'agreement among raft nodes before linearized reading' (duration: 952.520592ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:56:38.051532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:56:37.098892Z","time spent":"952.637366ms","remote":"127.0.0.1:44254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-26T10:56:38.051537Z","caller":"traceutil/trace.go:171","msg":"trace[1350970702] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:17823; }","duration":"575.156378ms","start":"2026-06-26T10:56:37.476366Z","end":"2026-06-26T10:56:38.051523Z","steps":["trace[1350970702] 'agreement among raft nodes before linearized reading' (duration: 574.996683ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:56:38.051568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:56:37.476352Z","time spent":"575.20883ms","remote":"127.0.0.1:44254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-26T10:56:38.051709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.902952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-06-26T10:56:38.051722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.931053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-4289m\" ","response":"range_response_count:1 size:22094"} {"level":"info","ts":"2026-06-26T10:56:38.051739Z","caller":"traceutil/trace.go:171","msg":"trace[903360682] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17823; }","duration":"572.943334ms","start":"2026-06-26T10:56:37.478788Z","end":"2026-06-26T10:56:38.051731Z","steps":["trace[903360682] 'agreement among raft nodes before linearized reading' (duration: 572.862531ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:56:38.051759Z","caller":"traceutil/trace.go:171","msg":"trace[1918930641] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-4289m; range_end:; response_count:1; response_revision:17823; }","duration":"767.972375ms","start":"2026-06-26T10:56:37.283775Z","end":"2026-06-26T10:56:38.051748Z","steps":["trace[1918930641] 'agreement among raft nodes before linearized reading' (duration: 767.829971ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:56:38.051767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:56:37.478779Z","time spent":"572.983015ms","remote":"127.0.0.1:44254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-26T10:56:38.05199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:56:37.283765Z","time spent":"768.216982ms","remote":"127.0.0.1:44188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22117,"request content":"key:\"/registry/pods/openstack/nova-compute-default-4289m\" "} {"level":"warn","ts":"2026-06-26T10:56:38.05204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.546648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"info","ts":"2026-06-26T10:56:38.052064Z","caller":"traceutil/trace.go:171","msg":"trace[108661926] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:17823; }","duration":"489.638401ms","start":"2026-06-26T10:56:37.562419Z","end":"2026-06-26T10:56:38.052058Z","steps":["trace[108661926] 'agreement among raft nodes before linearized reading' (duration: 489.562979ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:56:38.052083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:56:37.562393Z","time spent":"489.684892ms","remote":"127.0.0.1:44172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":726,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"warn","ts":"2026-06-26T10:56:38.052069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.57565ms","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-06-26T10:56:38.052133Z","caller":"traceutil/trace.go:171","msg":"trace[1720658877] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:17823; }","duration":"575.733665ms","start":"2026-06-26T10:56:37.476379Z","end":"2026-06-26T10:56:38.052112Z","steps":["trace[1720658877] 'agreement among raft nodes before linearized reading' (duration: 575.330703ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:56:38.052188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:56:37.476343Z","time spent":"575.832918ms","remote":"127.0.0.1:44254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-26T10:56:38.052331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"811.990135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-4289m\" ","response":"range_response_count:1 size:22094"} {"level":"info","ts":"2026-06-26T10:56:38.052412Z","caller":"traceutil/trace.go:171","msg":"trace[926220856] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-4289m; range_end:; response_count:1; response_revision:17823; }","duration":"812.100539ms","start":"2026-06-26T10:56:37.240292Z","end":"2026-06-26T10:56:38.052392Z","steps":["trace[926220856] 'agreement among raft nodes before linearized reading' (duration: 811.855612ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:56:38.052445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:56:37.240275Z","time spent":"812.16336ms","remote":"127.0.0.1:44188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22117,"request content":"key:\"/registry/pods/openstack/nova-compute-default-4289m\" "} {"level":"warn","ts":"2026-06-26T10:56:38.052331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:56:36.953702Z","time spent":"1.096596623s","remote":"127.0.0.1:44254","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-06-26T10:57:16.024121Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10348320880937078189,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-26T10:57:16.024513Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.130494151s","expected-duration":"1s"} {"level":"info","ts":"2026-06-26T10:57:16.024735Z","caller":"traceutil/trace.go:171","msg":"trace[851112150] linearizableReadLoop","detail":"{readStateIndex:18717; appliedIndex:18716; }","duration":"1.036466466s","start":"2026-06-26T10:57:14.988249Z","end":"2026-06-26T10:57:16.024715Z","steps":["trace[851112150] 'read index received' (duration: 1.036356143s)","trace[851112150] 'applied index is now lower than readState.Index' (duration: 109.083µs)"],"step_count":2} {"level":"warn","ts":"2026-06-26T10:57:16.024782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:57:14.893901Z","time spent":"1.130878372s","remote":"127.0.0.1:44042","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-06-26T10:57:16.024981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.036719833s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-pjw6p\" ","response":"range_response_count:1 size:20638"} {"level":"info","ts":"2026-06-26T10:57:16.024999Z","caller":"traceutil/trace.go:171","msg":"trace[808537726] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-pjw6p; range_end:; response_count:1; response_revision:18096; }","duration":"1.036793685s","start":"2026-06-26T10:57:14.988198Z","end":"2026-06-26T10:57:16.024992Z","steps":["trace[808537726] 'agreement among raft nodes before linearized reading' (duration: 1.036687192s)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:57:16.025015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:57:14.988175Z","time spent":"1.036835727s","remote":"127.0.0.1:44188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":20661,"request content":"key:\"/registry/pods/openstack/libvirt-libvirt-default-pjw6p\" "} {"level":"warn","ts":"2026-06-26T10:57:16.530589Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10348320880937078193,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-26T10:57:17.0312Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10348320880937078193,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-26T10:57:17.29646Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.269330167s","expected-duration":"1s"} {"level":"info","ts":"2026-06-26T10:57:17.300076Z","caller":"traceutil/trace.go:171","msg":"trace[293991746] transaction","detail":"{read_only:false; response_revision:18097; number_of_response:1; }","duration":"1.272928791s","start":"2026-06-26T10:57:16.027035Z","end":"2026-06-26T10:57:17.299964Z","steps":["trace[293991746] 'process raft request' (duration: 1.269608625s)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:57:17.300378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:57:16.027019Z","time spent":"1.273202699s","remote":"127.0.0.1:44042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-26T10:57:17.303142Z","caller":"traceutil/trace.go:171","msg":"trace[857676327] transaction","detail":"{read_only:false; response_revision:18098; number_of_response:1; }","duration":"1.09166713s","start":"2026-06-26T10:57:16.211452Z","end":"2026-06-26T10:57:17.303119Z","steps":["trace[857676327] 'process raft request' (duration: 1.091455284s)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:57:17.303148Z","caller":"traceutil/trace.go:171","msg":"trace[896824077] transaction","detail":"{read_only:false; response_revision:18100; number_of_response:1; }","duration":"1.090807664s","start":"2026-06-26T10:57:16.212316Z","end":"2026-06-26T10:57:17.303124Z","steps":["trace[896824077] 'process raft request' (duration: 1.090735972s)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:57:17.303271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:57:16.212292Z","time spent":"1.090911507s","remote":"127.0.0.1:44254","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-06-26T10:57:17.303159Z","caller":"traceutil/trace.go:171","msg":"trace[904895927] transaction","detail":"{read_only:false; response_revision:18099; number_of_response:1; }","duration":"1.09167822s","start":"2026-06-26T10:57:16.211464Z","end":"2026-06-26T10:57:17.303142Z","steps":["trace[904895927] 'process raft request' (duration: 1.091552567s)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:57:17.30339Z","caller":"traceutil/trace.go:171","msg":"trace[1029933100] linearizableReadLoop","detail":"{readStateIndex:18722; appliedIndex:18717; }","duration":"1.273789846s","start":"2026-06-26T10:57:16.029591Z","end":"2026-06-26T10:57:17.303381Z","steps":["trace[1029933100] 'read index received' (duration: 1.267073941s)","trace[1029933100] 'applied index is now lower than readState.Index' (duration: 6.714585ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T10:57:17.30349Z","caller":"traceutil/trace.go:171","msg":"trace[303880467] transaction","detail":"{read_only:false; response_revision:18101; number_of_response:1; }","duration":"857.942102ms","start":"2026-06-26T10:57:16.445539Z","end":"2026-06-26T10:57:17.303481Z","steps":["trace[303880467] 'process raft request' (duration: 857.549971ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:57:17.303486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:57:16.211442Z","time spent":"1.091932047s","remote":"127.0.0.1:44254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-26T10:57:17.303327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:57:16.211425Z","time spent":"1.091768233s","remote":"127.0.0.1:44254","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-06-26T10:57:17.303619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:57:16.445517Z","time spent":"857.993413ms","remote":"127.0.0.1:44254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-26T10:57:17.303712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.273562179s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-ovn-metadata-agent-default-rr8qh\" ","response":"range_response_count:1 size:15425"} {"level":"info","ts":"2026-06-26T10:57:17.303757Z","caller":"traceutil/trace.go:171","msg":"trace[2128180508] range","detail":"{range_begin:/registry/pods/openstack/neutron-ovn-metadata-agent-default-rr8qh; range_end:; response_count:1; response_revision:18101; }","duration":"1.273617921s","start":"2026-06-26T10:57:16.030127Z","end":"2026-06-26T10:57:17.303745Z","steps":["trace[2128180508] 'agreement among raft nodes before linearized reading' (duration: 1.273491367s)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:57:17.303768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.073960214s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-06-26T10:57:17.303762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.041646544s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-06-26T10:57:17.303794Z","caller":"traceutil/trace.go:171","msg":"trace[2052799790] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18101; }","duration":"1.074069737s","start":"2026-06-26T10:57:16.229717Z","end":"2026-06-26T10:57:17.303787Z","steps":["trace[2052799790] 'agreement among raft nodes before linearized reading' (duration: 1.073980474s)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:57:17.303808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.274218708s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-26T10:57:17.303822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:57:16.229693Z","time spent":"1.074122869s","remote":"127.0.0.1:44254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-26T10:57:17.303815Z","caller":"traceutil/trace.go:171","msg":"trace[1999561544] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18101; }","duration":"1.041756797s","start":"2026-06-26T10:57:16.262042Z","end":"2026-06-26T10:57:17.303798Z","steps":["trace[1999561544] 'agreement among raft nodes before linearized reading' (duration: 1.041589302s)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:57:17.303833Z","caller":"traceutil/trace.go:171","msg":"trace[1595974979] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18101; }","duration":"1.274262709s","start":"2026-06-26T10:57:16.029563Z","end":"2026-06-26T10:57:17.303825Z","steps":["trace[1595974979] 'agreement among raft nodes before linearized reading' (duration: 1.274224338s)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:57:17.303858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:57:16.262012Z","time spent":"1.04183535s","remote":"127.0.0.1:44254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-26T10:57:17.303877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:57:16.029544Z","time spent":"1.274327541s","remote":"127.0.0.1:44030","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-06-26T10:57:17.30389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.136146413s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"info","ts":"2026-06-26T10:57:17.304146Z","caller":"traceutil/trace.go:171","msg":"trace[1672014322] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18101; }","duration":"1.136440162s","start":"2026-06-26T10:57:16.167688Z","end":"2026-06-26T10:57:17.304128Z","steps":["trace[1672014322] 'agreement among raft nodes before linearized reading' (duration: 1.136122252s)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:57:17.304198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.045509518s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-ks-user\" ","response":"range_response_count:1 size:7382"} {"level":"warn","ts":"2026-06-26T10:57:17.304223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:57:16.167659Z","time spent":"1.136546575s","remote":"127.0.0.1:44172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":726,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"info","ts":"2026-06-26T10:57:17.304247Z","caller":"traceutil/trace.go:171","msg":"trace[1612076809] range","detail":"{range_begin:/registry/jobs/openstack/neutron-ks-user; range_end:; response_count:1; response_revision:18101; }","duration":"1.04556168s","start":"2026-06-26T10:57:16.258671Z","end":"2026-06-26T10:57:17.304233Z","steps":["trace[1612076809] 'agreement among raft nodes before linearized reading' (duration: 1.045421576s)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:57:17.304273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.143117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-26T10:57:17.3043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"666.679529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-m8lc4\" ","response":"range_response_count:1 size:9789"} {"level":"info","ts":"2026-06-26T10:57:17.304348Z","caller":"traceutil/trace.go:171","msg":"trace[1977497999] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-m8lc4; range_end:; response_count:1; response_revision:18101; }","duration":"666.774142ms","start":"2026-06-26T10:57:16.637564Z","end":"2026-06-26T10:57:17.304338Z","steps":["trace[1977497999] 'agreement among raft nodes before linearized reading' (duration: 666.609207ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:57:17.304375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:57:16.637539Z","time spent":"666.830484ms","remote":"127.0.0.1:44188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9812,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-m8lc4\" "} {"level":"warn","ts":"2026-06-26T10:57:17.304396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.964523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-x9tx9\" ","response":"range_response_count:1 size:6525"} {"level":"info","ts":"2026-06-26T10:57:17.30444Z","caller":"traceutil/trace.go:171","msg":"trace[295980867] range","detail":"{range_begin:/registry/pods/openstack/keepalived-x9tx9; range_end:; response_count:1; response_revision:18101; }","duration":"520.062095ms","start":"2026-06-26T10:57:16.784367Z","end":"2026-06-26T10:57:17.304429Z","steps":["trace[295980867] 'agreement among raft nodes before linearized reading' (duration: 519.928831ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:57:17.304467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:57:16.784349Z","time spent":"520.112807ms","remote":"127.0.0.1:44188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6548,"request content":"key:\"/registry/pods/openstack/keepalived-x9tx9\" "} {"level":"warn","ts":"2026-06-26T10:57:17.303748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.701294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-ovn-vpn-agent-default-r486r\" ","response":"range_response_count:1 size:15433"} {"level":"warn","ts":"2026-06-26T10:57:17.304299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:57:16.258635Z","time spent":"1.045653142s","remote":"127.0.0.1:44236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":7405,"request content":"key:\"/registry/jobs/openstack/neutron-ks-user\" "} {"level":"info","ts":"2026-06-26T10:57:17.304624Z","caller":"traceutil/trace.go:171","msg":"trace[1203496234] range","detail":"{range_begin:/registry/pods/openstack/neutron-ovn-vpn-agent-default-r486r; range_end:; response_count:1; response_revision:18101; }","duration":"563.619123ms","start":"2026-06-26T10:57:16.740994Z","end":"2026-06-26T10:57:17.304613Z","steps":["trace[1203496234] 'agreement among raft nodes before linearized reading' (duration: 562.675644ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:57:17.304651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:57:16.74097Z","time spent":"563.674084ms","remote":"127.0.0.1:44188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":15456,"request content":"key:\"/registry/pods/openstack/neutron-ovn-vpn-agent-default-r486r\" "} {"level":"info","ts":"2026-06-26T10:57:17.304319Z","caller":"traceutil/trace.go:171","msg":"trace[707554536] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:18101; }","duration":"194.215659ms","start":"2026-06-26T10:57:17.110076Z","end":"2026-06-26T10:57:17.304292Z","steps":["trace[707554536] 'agreement among raft nodes before linearized reading' (duration: 194.169798ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:57:17.303792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:57:16.030121Z","time spent":"1.273664482s","remote":"127.0.0.1:44188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":15448,"request content":"key:\"/registry/pods/openstack/neutron-ovn-metadata-agent-default-rr8qh\" "} {"level":"warn","ts":"2026-06-26T10:57:17.305661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.068768943s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-06-26T10:57:17.30572Z","caller":"traceutil/trace.go:171","msg":"trace[1232753572] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18101; }","duration":"1.068865116s","start":"2026-06-26T10:57:16.23684Z","end":"2026-06-26T10:57:17.305705Z","steps":["trace[1232753572] 'agreement among raft nodes before linearized reading' (duration: 1.068587588s)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:57:17.305767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:57:16.236819Z","time spent":"1.068937617s","remote":"127.0.0.1:44254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-26T10:57:17.308275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.141740696s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-ks-user\" ","response":"range_response_count:1 size:7382"} {"level":"info","ts":"2026-06-26T10:57:17.308312Z","caller":"traceutil/trace.go:171","msg":"trace[1383610666] range","detail":"{range_begin:/registry/jobs/openstack/neutron-ks-user; range_end:; response_count:1; response_revision:18101; }","duration":"1.141813679s","start":"2026-06-26T10:57:16.166491Z","end":"2026-06-26T10:57:17.308305Z","steps":["trace[1383610666] 'agreement among raft nodes before linearized reading' (duration: 1.141707636s)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:57:17.308493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:57:16.16647Z","time spent":"1.142004734s","remote":"127.0.0.1:44236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":7405,"request content":"key:\"/registry/jobs/openstack/neutron-ks-user\" "} {"level":"warn","ts":"2026-06-26T10:57:17.308522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.268514323s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-06-26T10:57:17.308578Z","caller":"traceutil/trace.go:171","msg":"trace[1319374026] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:18101; }","duration":"1.268590295s","start":"2026-06-26T10:57:16.039973Z","end":"2026-06-26T10:57:17.308563Z","steps":["trace[1319374026] 'agreement among raft nodes before linearized reading' (duration: 1.268245325s)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:57:17.308634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:57:16.039965Z","time spent":"1.268660138s","remote":"127.0.0.1:44188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-06-26T10:57:17.308689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.076408955s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-26T10:57:17.308751Z","caller":"traceutil/trace.go:171","msg":"trace[1229726892] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18101; }","duration":"1.076482098s","start":"2026-06-26T10:57:16.232245Z","end":"2026-06-26T10:57:17.308727Z","steps":["trace[1229726892] 'agreement among raft nodes before linearized reading' (duration: 1.076348804s)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:57:17.308796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:57:16.232235Z","time spent":"1.07655342s","remote":"127.0.0.1:44254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-26T10:57:17.308811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"751.777794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-4289m\" ","response":"range_response_count:1 size:22094"} {"level":"warn","ts":"2026-06-26T10:57:17.30869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"953.809649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"info","ts":"2026-06-26T10:57:17.308877Z","caller":"traceutil/trace.go:171","msg":"trace[1756937849] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18101; }","duration":"954.039687ms","start":"2026-06-26T10:57:16.354822Z","end":"2026-06-26T10:57:17.308861Z","steps":["trace[1756937849] 'agreement among raft nodes before linearized reading' (duration: 953.776559ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:57:17.308845Z","caller":"traceutil/trace.go:171","msg":"trace[1502355671] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-4289m; range_end:; response_count:1; response_revision:18101; }","duration":"751.854216ms","start":"2026-06-26T10:57:16.556981Z","end":"2026-06-26T10:57:17.308835Z","steps":["trace[1502355671] 'agreement among raft nodes before linearized reading' (duration: 751.760593ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:57:17.308914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:57:16.354793Z","time spent":"954.110168ms","remote":"127.0.0.1:44172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":726,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"warn","ts":"2026-06-26T10:57:17.308984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.073164711s","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-06-26T10:57:17.309019Z","caller":"traceutil/trace.go:171","msg":"trace[1752196847] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18102; }","duration":"1.073217663s","start":"2026-06-26T10:57:16.235792Z","end":"2026-06-26T10:57:17.30901Z","steps":["trace[1752196847] 'agreement among raft nodes before linearized reading' (duration: 1.07312127s)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:57:17.308918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:57:16.556959Z","time spent":"751.950209ms","remote":"127.0.0.1:44188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22117,"request content":"key:\"/registry/pods/openstack/nova-compute-default-4289m\" "} {"level":"warn","ts":"2026-06-26T10:57:17.309353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.278007889s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:95 size:940196"} {"level":"info","ts":"2026-06-26T10:57:17.309414Z","caller":"traceutil/trace.go:171","msg":"trace[1031555249] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:95; response_revision:18101; }","duration":"1.278090321s","start":"2026-06-26T10:57:16.031313Z","end":"2026-06-26T10:57:17.309403Z","steps":["trace[1031555249] 'agreement among raft nodes before linearized reading' (duration: 1.277254267s)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:57:17.309448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:57:16.031297Z","time spent":"1.278141093s","remote":"127.0.0.1:44188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":95,"response size":940219,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-06-26T10:57:17.309051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:57:16.235781Z","time spent":"1.073263214s","remote":"127.0.0.1:44254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-26T10:57:17.31277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"745.564064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-4289m\" ","response":"range_response_count:1 size:22094"} {"level":"info","ts":"2026-06-26T10:57:17.312843Z","caller":"traceutil/trace.go:171","msg":"trace[914035842] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-4289m; range_end:; response_count:1; response_revision:18102; }","duration":"745.668538ms","start":"2026-06-26T10:57:16.567157Z","end":"2026-06-26T10:57:17.312826Z","steps":["trace[914035842] 'agreement among raft nodes before linearized reading' (duration: 745.441281ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:57:17.312873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:57:16.567144Z","time spent":"745.722339ms","remote":"127.0.0.1:44188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22117,"request content":"key:\"/registry/pods/openstack/nova-compute-default-4289m\" "} {"level":"warn","ts":"2026-06-26T10:57:17.313534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.147775182s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"info","ts":"2026-06-26T10:57:17.313573Z","caller":"traceutil/trace.go:171","msg":"trace[1274280933] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18101; }","duration":"1.147838864s","start":"2026-06-26T10:57:16.165724Z","end":"2026-06-26T10:57:17.313563Z","steps":["trace[1274280933] 'agreement among raft nodes before linearized reading' (duration: 1.138582845s)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:57:17.313595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:57:16.165705Z","time spent":"1.147883674s","remote":"127.0.0.1:44172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":726,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"info","ts":"2026-06-26T10:57:55.605711Z","caller":"traceutil/trace.go:171","msg":"trace[728332423] transaction","detail":"{read_only:false; response_revision:18372; number_of_response:1; }","duration":"100.779822ms","start":"2026-06-26T10:57:55.504856Z","end":"2026-06-26T10:57:55.605635Z","steps":["trace[728332423] 'process raft request' (duration: 37.550752ms)","trace[728332423] 'compare' (duration: 63.035754ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T10:57:55.606101Z","caller":"traceutil/trace.go:171","msg":"trace[1070321034] transaction","detail":"{read_only:false; response_revision:18373; number_of_response:1; }","duration":"100.648078ms","start":"2026-06-26T10:57:55.505413Z","end":"2026-06-26T10:57:55.606061Z","steps":["trace[1070321034] 'process raft request' (duration: 100.174434ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:58:01.756093Z","caller":"traceutil/trace.go:171","msg":"trace[896498785] transaction","detail":"{read_only:false; response_revision:18412; number_of_response:1; }","duration":"256.549661ms","start":"2026-06-26T10:58:01.499518Z","end":"2026-06-26T10:58:01.756067Z","steps":["trace[896498785] 'process raft request' (duration: 256.443338ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:58:01.756356Z","caller":"traceutil/trace.go:171","msg":"trace[1894926682] linearizableReadLoop","detail":"{readStateIndex:19047; appliedIndex:19043; }","duration":"232.76319ms","start":"2026-06-26T10:58:01.523556Z","end":"2026-06-26T10:58:01.756319Z","steps":["trace[1894926682] 'read index received' (duration: 24.777351ms)","trace[1894926682] 'applied index is now lower than readState.Index' (duration: 207.984709ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T10:58:01.756487Z","caller":"traceutil/trace.go:171","msg":"trace[263257682] transaction","detail":"{read_only:false; response_revision:18413; number_of_response:1; }","duration":"255.949433ms","start":"2026-06-26T10:58:01.500523Z","end":"2026-06-26T10:58:01.756472Z","steps":["trace[263257682] 'process raft request' (duration: 255.47751ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:58:01.756584Z","caller":"traceutil/trace.go:171","msg":"trace[1028462284] transaction","detail":"{read_only:false; response_revision:18411; number_of_response:1; }","duration":"278.211371ms","start":"2026-06-26T10:58:01.478331Z","end":"2026-06-26T10:58:01.756542Z","steps":["trace[1028462284] 'process raft request' (duration: 277.453709ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:58:01.756836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.253365ms","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-06-26T10:58:01.756893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.571757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-06-26T10:58:01.756909Z","caller":"traceutil/trace.go:171","msg":"trace[1673102139] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18413; }","duration":"233.339957ms","start":"2026-06-26T10:58:01.523552Z","end":"2026-06-26T10:58:01.756892Z","steps":["trace[1673102139] 'agreement among raft nodes before linearized reading' (duration: 233.137271ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:58:01.756945Z","caller":"traceutil/trace.go:171","msg":"trace[537492417] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18413; }","duration":"121.681639ms","start":"2026-06-26T10:58:01.635249Z","end":"2026-06-26T10:58:01.756931Z","steps":["trace[537492417] 'agreement among raft nodes before linearized reading' (duration: 121.528315ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:58:01.757189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.281731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-26T10:58:01.757264Z","caller":"traceutil/trace.go:171","msg":"trace[832112321] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18413; }","duration":"107.373514ms","start":"2026-06-26T10:58:01.649848Z","end":"2026-06-26T10:58:01.757221Z","steps":["trace[832112321] 'agreement among raft nodes before linearized reading' (duration: 107.25628ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:58:01.757411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.514708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-06-26T10:58:01.75746Z","caller":"traceutil/trace.go:171","msg":"trace[2096276021] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18413; }","duration":"118.60017ms","start":"2026-06-26T10:58:01.63885Z","end":"2026-06-26T10:58:01.75745Z","steps":["trace[2096276021] 'agreement among raft nodes before linearized reading' (duration: 118.454896ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:58:01.876212Z","caller":"traceutil/trace.go:171","msg":"trace[1876385385] transaction","detail":"{read_only:false; response_revision:18415; number_of_response:1; }","duration":"114.575522ms","start":"2026-06-26T10:58:01.761613Z","end":"2026-06-26T10:58:01.876189Z","steps":["trace[1876385385] 'process raft request' (duration: 114.49308ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:58:01.876349Z","caller":"traceutil/trace.go:171","msg":"trace[1259783363] transaction","detail":"{read_only:false; response_revision:18416; number_of_response:1; }","duration":"114.477899ms","start":"2026-06-26T10:58:01.761859Z","end":"2026-06-26T10:58:01.876337Z","steps":["trace[1259783363] 'process raft request' (duration: 114.291024ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:58:01.876459Z","caller":"traceutil/trace.go:171","msg":"trace[1522164591] transaction","detail":"{read_only:false; response_revision:18414; number_of_response:1; }","duration":"115.88158ms","start":"2026-06-26T10:58:01.760542Z","end":"2026-06-26T10:58:01.876424Z","steps":["trace[1522164591] 'process raft request' (duration: 102.80797ms)","trace[1522164591] 'compare' (duration: 12.615687ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T10:58:01.876543Z","caller":"traceutil/trace.go:171","msg":"trace[183760640] transaction","detail":"{read_only:false; response_revision:18417; number_of_response:1; }","duration":"114.083717ms","start":"2026-06-26T10:58:01.762449Z","end":"2026-06-26T10:58:01.876532Z","steps":["trace[183760640] 'process raft request' (duration: 113.84124ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:58:01.876703Z","caller":"traceutil/trace.go:171","msg":"trace[2116184105] linearizableReadLoop","detail":"{readStateIndex:19052; appliedIndex:19047; }","duration":"109.865775ms","start":"2026-06-26T10:58:01.766822Z","end":"2026-06-26T10:58:01.876688Z","steps":["trace[2116184105] 'read index received' (duration: 96.538489ms)","trace[2116184105] 'applied index is now lower than readState.Index' (duration: 13.325856ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T10:58:01.876796Z","caller":"traceutil/trace.go:171","msg":"trace[35104793] transaction","detail":"{read_only:false; response_revision:18418; number_of_response:1; }","duration":"114.140929ms","start":"2026-06-26T10:58:01.762646Z","end":"2026-06-26T10:58:01.876787Z","steps":["trace[35104793] 'process raft request' (duration: 113.843441ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:58:01.876929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.078971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-26T10:58:01.876966Z","caller":"traceutil/trace.go:171","msg":"trace[1773832608] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18418; }","duration":"110.160724ms","start":"2026-06-26T10:58:01.766796Z","end":"2026-06-26T10:58:01.876957Z","steps":["trace[1773832608] 'agreement among raft nodes before linearized reading' (duration: 110.077692ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:58:02.235716Z","caller":"traceutil/trace.go:171","msg":"trace[1153127349] linearizableReadLoop","detail":"{readStateIndex:19053; appliedIndex:19052; }","duration":"343.282744ms","start":"2026-06-26T10:58:01.892402Z","end":"2026-06-26T10:58:02.235685Z","steps":["trace[1153127349] 'read index received' (duration: 342.760168ms)","trace[1153127349] 'applied index is now lower than readState.Index' (duration: 520.076µs)"],"step_count":2} {"level":"info","ts":"2026-06-26T10:58:02.235788Z","caller":"traceutil/trace.go:171","msg":"trace[1036001849] transaction","detail":"{read_only:false; response_revision:18419; number_of_response:1; }","duration":"344.940263ms","start":"2026-06-26T10:58:01.890804Z","end":"2026-06-26T10:58:02.235744Z","steps":["trace[1036001849] 'process raft request' (duration: 344.395346ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:58:02.236008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:58:01.890773Z","time spent":"345.112948ms","remote":"127.0.0.1:44188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20857,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-26T10:58:02.236304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.886912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-m8lc4\" ","response":"range_response_count:1 size:9789"} {"level":"warn","ts":"2026-06-26T10:58:02.236545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.412598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/neutron-server-84bd67bd9f-b2n66.18bc9c5cda4af078\" ","response":"range_response_count:1 size:805"} {"level":"info","ts":"2026-06-26T10:58:02.236635Z","caller":"traceutil/trace.go:171","msg":"trace[413666926] range","detail":"{range_begin:/registry/events/openstack/neutron-server-84bd67bd9f-b2n66.18bc9c5cda4af078; range_end:; response_count:1; response_revision:18419; }","duration":"147.543132ms","start":"2026-06-26T10:58:02.089071Z","end":"2026-06-26T10:58:02.236614Z","steps":["trace[413666926] 'agreement among raft nodes before linearized reading' (duration: 147.298275ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:58:02.236401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.051119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-4289m\" ","response":"range_response_count:1 size:22094"} {"level":"info","ts":"2026-06-26T10:58:02.237196Z","caller":"traceutil/trace.go:171","msg":"trace[1186657643] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-4289m; range_end:; response_count:1; response_revision:18419; }","duration":"177.530333ms","start":"2026-06-26T10:58:02.059278Z","end":"2026-06-26T10:58:02.236808Z","steps":["trace[1186657643] 'agreement among raft nodes before linearized reading' (duration: 176.810183ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:58:02.236383Z","caller":"traceutil/trace.go:171","msg":"trace[326314732] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-m8lc4; range_end:; response_count:1; response_revision:18419; }","duration":"344.021526ms","start":"2026-06-26T10:58:01.892351Z","end":"2026-06-26T10:58:02.236373Z","steps":["trace[326314732] 'agreement among raft nodes before linearized reading' (duration: 343.501201ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:58:02.238363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:58:01.892327Z","time spent":"346.008862ms","remote":"127.0.0.1:44188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9812,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-m8lc4\" "} {"level":"info","ts":"2026-06-26T11:00:28.096691Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"864e14457b828f9c","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-26T11:00:28.103125Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-06-26T11:00:28.103358Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-06-26T11:00:31.858352Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17430} {"level":"info","ts":"2026-06-26T11:00:31.958264Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17430,"took":"95.805477ms","hash":2152520211,"current-db-size-bytes":58929152,"current-db-size":"59 MB","current-db-size-in-use-bytes":31375360,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-26T11:00:31.958333Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2152520211,"revision":17430,"compact-revision":14667} {"level":"info","ts":"2026-06-26T11:05:31.864876Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19352} {"level":"info","ts":"2026-06-26T11:05:31.951016Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19352,"took":"82.84835ms","hash":3317465560,"current-db-size-bytes":58929152,"current-db-size":"59 MB","current-db-size-in-use-bytes":26017792,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-06-26T11:05:31.951061Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3317465560,"revision":19352,"compact-revision":17430}