{"level":"warn","ts":"2026-08-05T11:10:51.605294Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-05T11:10:51.605367Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.116:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.116:2380","--initial-cluster=instance=https://199.204.45.116:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.116:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.116: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-08-05T11:10:51.605432Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-05T11:10:51.60544Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.116:2380"]} {"level":"info","ts":"2026-08-05T11:10:51.605457Z","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-08-05T11:10:51.606012Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"]} {"level":"info","ts":"2026-08-05T11:10:51.606078Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.116:2380"],"listen-peer-urls":["https://199.204.45.116:2380"],"advertise-client-urls":["https://199.204.45.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.116: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-08-05T11:10:51.847089Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"123.358013ms"} {"level":"info","ts":"2026-08-05T11:10:51.861273Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5caa70ef1dd6dc04","cluster-id":"45afccab143d2c82"} {"level":"info","ts":"2026-08-05T11:10:51.861417Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=()"} {"level":"info","ts":"2026-08-05T11:10:51.861459Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became follower at term 0"} {"level":"info","ts":"2026-08-05T11:10:51.86148Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5caa70ef1dd6dc04 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-05T11:10:51.861504Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became follower at term 1"} {"level":"info","ts":"2026-08-05T11:10:51.861562Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=(6677273569830296580)"} {"level":"warn","ts":"2026-08-05T11:10:51.867192Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-05T11:10:51.870656Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-05T11:10:51.872036Z","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-08-05T11:10:51.874617Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5caa70ef1dd6dc04","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-05T11:10:51.875236Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5caa70ef1dd6dc04","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-05T11:10:51.875505Z","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-08-05T11:10:51.875625Z","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-08-05T11:10:51.875648Z","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-08-05T11:10:51.876191Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=(6677273569830296580)"} {"level":"info","ts":"2026-08-05T11:10:51.87634Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"45afccab143d2c82","local-member-id":"5caa70ef1dd6dc04","added-peer-id":"5caa70ef1dd6dc04","added-peer-peer-urls":["https://199.204.45.116:2380"]} {"level":"info","ts":"2026-08-05T11:10:51.877562Z","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-08-05T11:10:51.877626Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.116:2380"} {"level":"info","ts":"2026-08-05T11:10:51.877637Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.116:2380"} {"level":"info","ts":"2026-08-05T11:10:51.878434Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5caa70ef1dd6dc04","initial-advertise-peer-urls":["https://199.204.45.116:2380"],"listen-peer-urls":["https://199.204.45.116:2380"],"advertise-client-urls":["https://199.204.45.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-05T11:10:51.878486Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-05T11:10:52.762607Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 is starting a new election at term 1"} {"level":"info","ts":"2026-08-05T11:10:52.762666Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-05T11:10:52.762704Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 received MsgPreVoteResp from 5caa70ef1dd6dc04 at term 1"} {"level":"info","ts":"2026-08-05T11:10:52.762725Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became candidate at term 2"} {"level":"info","ts":"2026-08-05T11:10:52.762746Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 received MsgVoteResp from 5caa70ef1dd6dc04 at term 2"} {"level":"info","ts":"2026-08-05T11:10:52.762769Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became leader at term 2"} {"level":"info","ts":"2026-08-05T11:10:52.762787Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5caa70ef1dd6dc04 elected leader 5caa70ef1dd6dc04 at term 2"} {"level":"info","ts":"2026-08-05T11:10:52.763804Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T11:10:52.764509Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5caa70ef1dd6dc04","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.116:2379]}","request-path":"/0/members/5caa70ef1dd6dc04/attributes","cluster-id":"45afccab143d2c82","publish-timeout":"7s"} {"level":"info","ts":"2026-08-05T11:10:52.76456Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-05T11:10:52.764651Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-05T11:10:52.764837Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-05T11:10:52.764882Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-05T11:10:52.76534Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"45afccab143d2c82","local-member-id":"5caa70ef1dd6dc04","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T11:10:52.76548Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T11:10:52.76551Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T11:10:52.770422Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.116:2379"} {"level":"info","ts":"2026-08-05T11:10:52.770694Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-08-05T11:11:03.935881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.939016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4429"} {"level":"info","ts":"2026-08-05T11:11:03.936197Z","caller":"traceutil/trace.go:171","msg":"trace[1968208619] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:393; }","duration":"210.07998ms","start":"2026-08-05T11:11:03.725887Z","end":"2026-08-05T11:11:03.935967Z","steps":["trace[1968208619] 'range keys from in-memory index tree' (duration: 209.633278ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:11:04.214716Z","caller":"traceutil/trace.go:171","msg":"trace[2053131491] linearizableReadLoop","detail":"{readStateIndex:406; appliedIndex:405; }","duration":"263.5793ms","start":"2026-08-05T11:11:03.951113Z","end":"2026-08-05T11:11:04.214692Z","steps":["trace[2053131491] 'read index received' (duration: 263.445786ms)","trace[2053131491] 'applied index is now lower than readState.Index' (duration: 132.484µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:11:04.214749Z","caller":"traceutil/trace.go:171","msg":"trace[208175451] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"271.258066ms","start":"2026-08-05T11:11:03.943458Z","end":"2026-08-05T11:11:04.214716Z","steps":["trace[208175451] 'process raft request' (duration: 271.0605ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:11:04.214974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.841787ms","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-08-05T11:11:04.215046Z","caller":"traceutil/trace.go:171","msg":"trace[1686882867] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:394; }","duration":"263.91861ms","start":"2026-08-05T11:11:03.95109Z","end":"2026-08-05T11:11:04.215009Z","steps":["trace[1686882867] 'agreement among raft nodes before linearized reading' (duration: 263.714524ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:11:04.322655Z","caller":"traceutil/trace.go:171","msg":"trace[2127050627] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"101.083565ms","start":"2026-08-05T11:11:04.221509Z","end":"2026-08-05T11:11:04.322593Z","steps":["trace[2127050627] 'process raft request' (duration: 31.789212ms)","trace[2127050627] 'compare' (duration: 68.863041ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:11:43.309123Z","caller":"traceutil/trace.go:171","msg":"trace[512047670] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"234.336901ms","start":"2026-08-05T11:11:43.074742Z","end":"2026-08-05T11:11:43.309079Z","steps":["trace[512047670] 'process raft request' (duration: 233.855437ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:11:43.309418Z","caller":"traceutil/trace.go:171","msg":"trace[1022846604] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"229.32369ms","start":"2026-08-05T11:11:43.080061Z","end":"2026-08-05T11:11:43.309385Z","steps":["trace[1022846604] 'process raft request' (duration: 229.056943ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:11:43.309452Z","caller":"traceutil/trace.go:171","msg":"trace[297023899] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"229.953208ms","start":"2026-08-05T11:11:43.079485Z","end":"2026-08-05T11:11:43.309438Z","steps":["trace[297023899] 'process raft request' (duration: 229.173536ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:11:43.309539Z","caller":"traceutil/trace.go:171","msg":"trace[1671434208] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"228.403134ms","start":"2026-08-05T11:11:43.081088Z","end":"2026-08-05T11:11:43.309491Z","steps":["trace[1671434208] 'process raft request' (duration: 228.121586ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:11:43.309662Z","caller":"traceutil/trace.go:171","msg":"trace[1295183278] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"239.211897ms","start":"2026-08-05T11:11:43.070264Z","end":"2026-08-05T11:11:43.309476Z","steps":["trace[1295183278] 'process raft request' (duration: 238.143527ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:11:43.309784Z","caller":"traceutil/trace.go:171","msg":"trace[1441321739] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"227.683644ms","start":"2026-08-05T11:11:43.082084Z","end":"2026-08-05T11:11:43.309768Z","steps":["trace[1441321739] 'process raft request' (duration: 227.215241ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:11:43.309991Z","caller":"traceutil/trace.go:171","msg":"trace[1675073497] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"225.116312ms","start":"2026-08-05T11:11:43.084857Z","end":"2026-08-05T11:11:43.309973Z","steps":["trace[1675073497] 'process raft request' (duration: 224.806833ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:11:43.310096Z","caller":"traceutil/trace.go:171","msg":"trace[1927551167] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"223.784504ms","start":"2026-08-05T11:11:43.086287Z","end":"2026-08-05T11:11:43.310072Z","steps":["trace[1927551167] 'process raft request' (duration: 223.486676ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:11:43.31045Z","caller":"traceutil/trace.go:171","msg":"trace[293068171] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"219.465134ms","start":"2026-08-05T11:11:43.09097Z","end":"2026-08-05T11:11:43.310435Z","steps":["trace[293068171] 'process raft request' (duration: 219.089523ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:11:43.310425Z","caller":"traceutil/trace.go:171","msg":"trace[1310408178] linearizableReadLoop","detail":"{readStateIndex:593; appliedIndex:584; }","duration":"189.145573ms","start":"2026-08-05T11:11:43.121238Z","end":"2026-08-05T11:11:43.310384Z","steps":["trace[1310408178] 'read index received' (duration: 187.204489ms)","trace[1310408178] 'applied index is now lower than readState.Index' (duration: 1.939654ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:11:43.310723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.466702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-05T11:11:43.310782Z","caller":"traceutil/trace.go:171","msg":"trace[207048495] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:572; }","duration":"189.568215ms","start":"2026-08-05T11:11:43.121197Z","end":"2026-08-05T11:11:43.310765Z","steps":["trace[207048495] 'agreement among raft nodes before linearized reading' (duration: 189.321758ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:11:43.356207Z","caller":"traceutil/trace.go:171","msg":"trace[1971628851] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"232.91184ms","start":"2026-08-05T11:11:43.123265Z","end":"2026-08-05T11:11:43.356176Z","steps":["trace[1971628851] 'process raft request' (duration: 232.667433ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:11:43.356491Z","caller":"traceutil/trace.go:171","msg":"trace[1434422591] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"232.971382ms","start":"2026-08-05T11:11:43.123496Z","end":"2026-08-05T11:11:43.356467Z","steps":["trace[1434422591] 'process raft request' (duration: 232.644723ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:11:43.356933Z","caller":"traceutil/trace.go:171","msg":"trace[829805786] transaction","detail":"{read_only:false; number_of_response:1; response_revision:574; }","duration":"233.307112ms","start":"2026-08-05T11:11:43.123613Z","end":"2026-08-05T11:11:43.35692Z","steps":["trace[829805786] 'process raft request' (duration: 232.799208ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:11:43.357544Z","caller":"traceutil/trace.go:171","msg":"trace[2054784501] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"227.012595ms","start":"2026-08-05T11:11:43.13052Z","end":"2026-08-05T11:11:43.357533Z","steps":["trace[2054784501] 'process raft request' (duration: 226.288415ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:11:43.357735Z","caller":"traceutil/trace.go:171","msg":"trace[1098043019] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"101.538037ms","start":"2026-08-05T11:11:43.256184Z","end":"2026-08-05T11:11:43.357722Z","steps":["trace[1098043019] 'process raft request' (duration: 101.325611ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:11:43.530759Z","caller":"traceutil/trace.go:171","msg":"trace[64335254] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"214.258647ms","start":"2026-08-05T11:11:43.316477Z","end":"2026-08-05T11:11:43.530736Z","steps":["trace[64335254] 'process raft request' (duration: 214.115623ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:11:43.531023Z","caller":"traceutil/trace.go:171","msg":"trace[1801380926] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"214.72138ms","start":"2026-08-05T11:11:43.316292Z","end":"2026-08-05T11:11:43.531014Z","steps":["trace[1801380926] 'process raft request' (duration: 214.177225ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:11:43.531006Z","caller":"traceutil/trace.go:171","msg":"trace[811521480] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"209.925236ms","start":"2026-08-05T11:11:43.32105Z","end":"2026-08-05T11:11:43.530976Z","steps":["trace[811521480] 'process raft request' (duration: 209.629288ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:11:43.531112Z","caller":"traceutil/trace.go:171","msg":"trace[1289988280] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"208.63483ms","start":"2026-08-05T11:11:43.32245Z","end":"2026-08-05T11:11:43.531085Z","steps":["trace[1289988280] 'process raft request' (duration: 208.26011ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:11:43.531197Z","caller":"traceutil/trace.go:171","msg":"trace[1455556593] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"207.971601ms","start":"2026-08-05T11:11:43.323186Z","end":"2026-08-05T11:11:43.531157Z","steps":["trace[1455556593] 'process raft request' (duration: 207.56131ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:11:43.531298Z","caller":"traceutil/trace.go:171","msg":"trace[53090882] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"206.242543ms","start":"2026-08-05T11:11:43.325046Z","end":"2026-08-05T11:11:43.531289Z","steps":["trace[53090882] 'process raft request' (duration: 205.741479ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:11:43.531252Z","caller":"traceutil/trace.go:171","msg":"trace[1837823947] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"214.420762ms","start":"2026-08-05T11:11:43.316718Z","end":"2026-08-05T11:11:43.531139Z","steps":["trace[1837823947] 'process raft request' (duration: 213.930748ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:11:43.531304Z","caller":"traceutil/trace.go:171","msg":"trace[919288397] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"194.597856ms","start":"2026-08-05T11:11:43.33669Z","end":"2026-08-05T11:11:43.531288Z","steps":["trace[919288397] 'process raft request' (duration: 194.339599ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:11:43.531369Z","caller":"traceutil/trace.go:171","msg":"trace[203256182] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"198.490375ms","start":"2026-08-05T11:11:43.332849Z","end":"2026-08-05T11:11:43.53134Z","steps":["trace[203256182] 'process raft request' (duration: 197.995172ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:11:43.535243Z","caller":"traceutil/trace.go:171","msg":"trace[141524036] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"173.829514ms","start":"2026-08-05T11:11:43.361389Z","end":"2026-08-05T11:11:43.535218Z","steps":["trace[141524036] 'process raft request' (duration: 173.549606ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:11:43.535312Z","caller":"traceutil/trace.go:171","msg":"trace[673188366] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"116.425284ms","start":"2026-08-05T11:11:43.418861Z","end":"2026-08-05T11:11:43.535286Z","steps":["trace[673188366] 'process raft request' (duration: 116.385693ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:11:43.535624Z","caller":"traceutil/trace.go:171","msg":"trace[590529622] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"167.899697ms","start":"2026-08-05T11:11:43.367711Z","end":"2026-08-05T11:11:43.535611Z","steps":["trace[590529622] 'process raft request' (duration: 167.464635ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:11:43.535605Z","caller":"traceutil/trace.go:171","msg":"trace[2083703104] transaction","detail":"{read_only:false; number_of_response:1; response_revision:586; }","duration":"170.537051ms","start":"2026-08-05T11:11:43.365043Z","end":"2026-08-05T11:11:43.53558Z","steps":["trace[2083703104] 'process raft request' (duration: 170.064608ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:11:49.445607Z","caller":"traceutil/trace.go:171","msg":"trace[1756754532] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"135.89517ms","start":"2026-08-05T11:11:49.309679Z","end":"2026-08-05T11:11:49.445574Z","steps":["trace[1756754532] 'process raft request' (duration: 135.297564ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:11:49.618915Z","caller":"traceutil/trace.go:171","msg":"trace[1087747267] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"110.310772ms","start":"2026-08-05T11:11:49.508569Z","end":"2026-08-05T11:11:49.61888Z","steps":["trace[1087747267] 'process raft request' (duration: 110.128857ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:11:50.162207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.339897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-jbb4m\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-08-05T11:11:50.162328Z","caller":"traceutil/trace.go:171","msg":"trace[483019064] range","detail":"{range_begin:/registry/pods/kube-system/cilium-jbb4m; range_end:; response_count:1; response_revision:672; }","duration":"394.522001ms","start":"2026-08-05T11:11:49.767784Z","end":"2026-08-05T11:11:50.162306Z","steps":["trace[483019064] 'range keys from in-memory index tree' (duration: 394.159102ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:11:50.162378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:11:49.767762Z","time spent":"394.603344ms","remote":"127.0.0.1:47186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-jbb4m\" "} {"level":"info","ts":"2026-08-05T11:11:50.173899Z","caller":"traceutil/trace.go:171","msg":"trace[244357837] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"129.042118ms","start":"2026-08-05T11:11:50.044836Z","end":"2026-08-05T11:11:50.173878Z","steps":["trace[244357837] 'process raft request' (duration: 128.4242ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:13:15.029881Z","caller":"traceutil/trace.go:171","msg":"trace[1238305966] linearizableReadLoop","detail":"{readStateIndex:1947; appliedIndex:1946; }","duration":"211.958621ms","start":"2026-08-05T11:13:14.817896Z","end":"2026-08-05T11:13:15.029855Z","steps":["trace[1238305966] 'read index received' (duration: 211.754886ms)","trace[1238305966] 'applied index is now lower than readState.Index' (duration: 202.925µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:13:15.029921Z","caller":"traceutil/trace.go:171","msg":"trace[345050673] transaction","detail":"{read_only:false; response_revision:1875; number_of_response:1; }","duration":"313.205295ms","start":"2026-08-05T11:13:14.716688Z","end":"2026-08-05T11:13:15.029893Z","steps":["trace[345050673] 'process raft request' (duration: 313.020269ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:13:15.030082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.623944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-05T11:13:15.03013Z","caller":"traceutil/trace.go:171","msg":"trace[1149038468] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1875; }","duration":"115.736118ms","start":"2026-08-05T11:13:14.914384Z","end":"2026-08-05T11:13:15.03012Z","steps":["trace[1149038468] 'agreement among raft nodes before linearized reading' (duration: 115.620305ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:13:15.030205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.538887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-05T11:13:15.030278Z","caller":"traceutil/trace.go:171","msg":"trace[336996121] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1875; }","duration":"103.662891ms","start":"2026-08-05T11:13:14.926599Z","end":"2026-08-05T11:13:15.030262Z","steps":["trace[336996121] 'agreement among raft nodes before linearized reading' (duration: 103.480046ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:13:15.030511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:13:14.716662Z","time spent":"313.346539ms","remote":"127.0.0.1:47298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T11:13:15.030617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.725702ms","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-08-05T11:13:15.030655Z","caller":"traceutil/trace.go:171","msg":"trace[604137473] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1875; }","duration":"212.803635ms","start":"2026-08-05T11:13:14.817841Z","end":"2026-08-05T11:13:15.030645Z","steps":["trace[604137473] 'agreement among raft nodes before linearized reading' (duration: 212.131486ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:13:15.448401Z","caller":"traceutil/trace.go:171","msg":"trace[1421400463] transaction","detail":"{read_only:false; response_revision:1881; number_of_response:1; }","duration":"184.952287ms","start":"2026-08-05T11:13:15.26342Z","end":"2026-08-05T11:13:15.448372Z","steps":["trace[1421400463] 'process raft request' (duration: 132.313709ms)","trace[1421400463] 'compare' (duration: 52.516495ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:13:23.130627Z","caller":"traceutil/trace.go:171","msg":"trace[493594373] transaction","detail":"{read_only:false; response_revision:1957; number_of_response:1; }","duration":"179.564388ms","start":"2026-08-05T11:13:22.951027Z","end":"2026-08-05T11:13:23.130592Z","steps":["trace[493594373] 'process raft request' (duration: 179.28652ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:13:27.46949Z","caller":"traceutil/trace.go:171","msg":"trace[773149041] transaction","detail":"{read_only:false; response_revision:1991; number_of_response:1; }","duration":"149.749517ms","start":"2026-08-05T11:13:27.319708Z","end":"2026-08-05T11:13:27.469458Z","steps":["trace[773149041] 'process raft request' (duration: 148.043239ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:13:59.974644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.323529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-08-05T11:13:59.97474Z","caller":"traceutil/trace.go:171","msg":"trace[1459027787] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2179; }","duration":"180.478763ms","start":"2026-08-05T11:13:59.794242Z","end":"2026-08-05T11:13:59.974721Z","steps":["trace[1459027787] 'range keys from in-memory index tree' (duration: 180.086792ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:15:28.921647Z","caller":"traceutil/trace.go:171","msg":"trace[1914707042] transaction","detail":"{read_only:false; response_revision:2854; number_of_response:1; }","duration":"210.095433ms","start":"2026-08-05T11:15:28.711521Z","end":"2026-08-05T11:15:28.921616Z","steps":["trace[1914707042] 'process raft request' (duration: 209.187718ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:19:17.186542Z","caller":"traceutil/trace.go:171","msg":"trace[1231993780] transaction","detail":"{read_only:false; response_revision:4846; number_of_response:1; }","duration":"123.335505ms","start":"2026-08-05T11:19:17.063183Z","end":"2026-08-05T11:19:17.186518Z","steps":["trace[1231993780] 'process raft request' (duration: 123.17458ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:19:20.817494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.98832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-08-05T11:19:20.817588Z","caller":"traceutil/trace.go:171","msg":"trace[791281087] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5020; }","duration":"121.155834ms","start":"2026-08-05T11:19:20.696412Z","end":"2026-08-05T11:19:20.817568Z","steps":["trace[791281087] 'range keys from in-memory index tree' (duration: 120.749733ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:19:21.341419Z","caller":"traceutil/trace.go:171","msg":"trace[962602775] transaction","detail":"{read_only:false; response_revision:5026; number_of_response:1; }","duration":"162.416965ms","start":"2026-08-05T11:19:21.17897Z","end":"2026-08-05T11:19:21.341387Z","steps":["trace[962602775] 'process raft request' (duration: 162.159898ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:19:32.488672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.378438ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-05T11:19:32.48891Z","caller":"traceutil/trace.go:171","msg":"trace[2127718889] linearizableReadLoop","detail":"{readStateIndex:5457; appliedIndex:5455; }","duration":"111.977407ms","start":"2026-08-05T11:19:32.376916Z","end":"2026-08-05T11:19:32.488893Z","steps":["trace[2127718889] 'read index received' (duration: 61.668372ms)","trace[2127718889] 'applied index is now lower than readState.Index' (duration: 50.308085ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:19:32.488912Z","caller":"traceutil/trace.go:171","msg":"trace[938643842] transaction","detail":"{read_only:false; response_revision:5256; number_of_response:1; }","duration":"217.319249ms","start":"2026-08-05T11:19:32.271569Z","end":"2026-08-05T11:19:32.488888Z","steps":["trace[938643842] 'process raft request' (duration: 89.473728ms)","trace[938643842] 'compare' (duration: 126.910085ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:19:32.488984Z","caller":"traceutil/trace.go:171","msg":"trace[1147425407] transaction","detail":"{read_only:false; response_revision:5257; number_of_response:1; }","duration":"217.232076ms","start":"2026-08-05T11:19:32.271714Z","end":"2026-08-05T11:19:32.488946Z","steps":["trace[1147425407] 'process raft request' (duration: 217.081572ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:19:32.489009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.08433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/memcached-metrics\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T11:19:32.489084Z","caller":"traceutil/trace.go:171","msg":"trace[1303851102] range","detail":"{range_begin:/registry/services/specs/openstack/memcached-metrics; range_end:; response_count:0; response_revision:5257; }","duration":"112.204524ms","start":"2026-08-05T11:19:32.376861Z","end":"2026-08-05T11:19:32.489065Z","steps":["trace[1303851102] 'agreement among raft nodes before linearized reading' (duration: 112.08365ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:19:42.525482Z","caller":"traceutil/trace.go:171","msg":"trace[1955088027] transaction","detail":"{read_only:false; response_revision:5486; number_of_response:1; }","duration":"124.837417ms","start":"2026-08-05T11:19:42.40062Z","end":"2026-08-05T11:19:42.525458Z","steps":["trace[1955088027] 'process raft request' (duration: 121.959356ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:19:50.76128Z","caller":"traceutil/trace.go:171","msg":"trace[1429327401] transaction","detail":"{read_only:false; response_revision:5574; number_of_response:1; }","duration":"123.396626ms","start":"2026-08-05T11:19:50.637857Z","end":"2026-08-05T11:19:50.761254Z","steps":["trace[1429327401] 'process raft request' (duration: 123.210751ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:19:50.772484Z","caller":"traceutil/trace.go:171","msg":"trace[798436080] transaction","detail":"{read_only:false; response_revision:5575; number_of_response:1; }","duration":"130.527965ms","start":"2026-08-05T11:19:50.641935Z","end":"2026-08-05T11:19:50.772463Z","steps":["trace[798436080] 'process raft request' (duration: 130.399512ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:20:52.799011Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2985} {"level":"info","ts":"2026-08-05T11:20:52.881125Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2985,"took":"80.870769ms","hash":1336911225,"current-db-size-bytes":58249216,"current-db-size":"58 MB","current-db-size-in-use-bytes":33955840,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-05T11:20:52.881214Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1336911225,"revision":2985,"compact-revision":-1} {"level":"warn","ts":"2026-08-05T11:25:46.02166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.826934ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-05T11:25:46.021849Z","caller":"traceutil/trace.go:171","msg":"trace[1116804947] transaction","detail":"{read_only:false; response_revision:8247; number_of_response:1; }","duration":"156.364653ms","start":"2026-08-05T11:25:45.865444Z","end":"2026-08-05T11:25:46.021809Z","steps":["trace[1116804947] 'process raft request' (duration: 17.262151ms)","trace[1116804947] 'compare' (duration: 138.598968ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:25:46.022029Z","caller":"traceutil/trace.go:171","msg":"trace[2091108517] linearizableReadLoop","detail":"{readStateIndex:8537; appliedIndex:8536; }","duration":"126.781727ms","start":"2026-08-05T11:25:45.895217Z","end":"2026-08-05T11:25:46.021998Z","steps":["trace[2091108517] 'read index received' (duration: 28.34µs)","trace[2091108517] 'applied index is now lower than readState.Index' (duration: 126.751057ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:25:46.022229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.988743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T11:25:46.022276Z","caller":"traceutil/trace.go:171","msg":"trace[634745288] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:8248; }","duration":"127.071926ms","start":"2026-08-05T11:25:45.895193Z","end":"2026-08-05T11:25:46.022265Z","steps":["trace[634745288] 'agreement among raft nodes before linearized reading' (duration: 126.968523ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:25:46.052456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.896564ms","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-08-05T11:25:46.052511Z","caller":"traceutil/trace.go:171","msg":"trace[1057732951] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8249; }","duration":"148.973637ms","start":"2026-08-05T11:25:45.903523Z","end":"2026-08-05T11:25:46.052497Z","steps":["trace[1057732951] 'agreement among raft nodes before linearized reading' (duration: 148.845243ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:25:52.806625Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6090} {"level":"info","ts":"2026-08-05T11:25:52.899802Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6090,"took":"91.162743ms","hash":3694831477,"current-db-size-bytes":58249216,"current-db-size":"58 MB","current-db-size-in-use-bytes":23179264,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-08-05T11:25:52.899872Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3694831477,"revision":6090,"compact-revision":2985} {"level":"info","ts":"2026-08-05T11:26:15.385799Z","caller":"traceutil/trace.go:171","msg":"trace[699762553] transaction","detail":"{read_only:false; response_revision:8457; number_of_response:1; }","duration":"326.597541ms","start":"2026-08-05T11:26:15.059183Z","end":"2026-08-05T11:26:15.385781Z","steps":["trace[699762553] 'process raft request' (duration: 326.510039ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:26:15.385823Z","caller":"traceutil/trace.go:171","msg":"trace[461988571] linearizableReadLoop","detail":"{readStateIndex:8756; appliedIndex:8756; }","duration":"320.666026ms","start":"2026-08-05T11:26:15.065133Z","end":"2026-08-05T11:26:15.385799Z","steps":["trace[461988571] 'read index received' (duration: 320.650885ms)","trace[461988571] 'applied index is now lower than readState.Index' (duration: 12.511µs)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:26:15.385912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:26:15.059171Z","time spent":"326.686104ms","remote":"127.0.0.1:47298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T11:26:15.38597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.810371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T11:26:15.385991Z","caller":"traceutil/trace.go:171","msg":"trace[771364914] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:8457; }","duration":"320.858102ms","start":"2026-08-05T11:26:15.065128Z","end":"2026-08-05T11:26:15.385986Z","steps":["trace[771364914] 'agreement among raft nodes before linearized reading' (duration: 320.77939ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:26:15.386015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:26:15.065089Z","time spent":"320.921124ms","remote":"127.0.0.1:50732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-08-05T11:26:15.393964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.215721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-05T11:26:15.393996Z","caller":"traceutil/trace.go:171","msg":"trace[262715824] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8458; }","duration":"133.283503ms","start":"2026-08-05T11:26:15.260706Z","end":"2026-08-05T11:26:15.39399Z","steps":["trace[262715824] 'agreement among raft nodes before linearized reading' (duration: 133.17471ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:26:15.394104Z","caller":"traceutil/trace.go:171","msg":"trace[1119729624] transaction","detail":"{read_only:false; response_revision:8458; number_of_response:1; }","duration":"110.551288ms","start":"2026-08-05T11:26:15.283547Z","end":"2026-08-05T11:26:15.394099Z","steps":["trace[1119729624] 'process raft request' (duration: 109.688874ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:26:27.798591Z","caller":"traceutil/trace.go:171","msg":"trace[233597056] linearizableReadLoop","detail":"{readStateIndex:8874; appliedIndex:8873; }","duration":"134.520387ms","start":"2026-08-05T11:26:27.664034Z","end":"2026-08-05T11:26:27.798554Z","steps":["trace[233597056] 'read index received' (duration: 134.195628ms)","trace[233597056] 'applied index is now lower than readState.Index' (duration: 323.479µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:26:27.798645Z","caller":"traceutil/trace.go:171","msg":"trace[959032736] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8568; }","duration":"189.791551ms","start":"2026-08-05T11:26:27.608803Z","end":"2026-08-05T11:26:27.798594Z","steps":["trace[959032736] 'process raft request' (duration: 189.575695ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:26:27.798831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.752593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-bzw59\" ","response":"range_response_count:1 size:4645"} {"level":"info","ts":"2026-08-05T11:26:27.798981Z","caller":"traceutil/trace.go:171","msg":"trace[2122244831] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-bzw59; range_end:; response_count:1; response_revision:8568; }","duration":"134.918088ms","start":"2026-08-05T11:26:27.664004Z","end":"2026-08-05T11:26:27.798922Z","steps":["trace[2122244831] 'agreement among raft nodes before linearized reading' (duration: 134.667891ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:26:27.799365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.750557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-05T11:26:27.799506Z","caller":"traceutil/trace.go:171","msg":"trace[1849247999] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8568; }","duration":"113.960213ms","start":"2026-08-05T11:26:27.685518Z","end":"2026-08-05T11:26:27.799478Z","steps":["trace[1849247999] 'agreement among raft nodes before linearized reading' (duration: 113.300734ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:26:28.571085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.150002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-krrfx\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-08-05T11:26:28.571175Z","caller":"traceutil/trace.go:171","msg":"trace[974134631] range","detail":"{range_begin:/registry/pods/openstack/keepalived-krrfx; range_end:; response_count:1; response_revision:8572; }","duration":"117.302537ms","start":"2026-08-05T11:26:28.453852Z","end":"2026-08-05T11:26:28.571155Z","steps":["trace[974134631] 'range keys from in-memory index tree' (duration: 116.919406ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:26:28.571535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.485014ms","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-08-05T11:26:28.571623Z","caller":"traceutil/trace.go:171","msg":"trace[367102661] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8572; }","duration":"212.643969ms","start":"2026-08-05T11:26:28.358959Z","end":"2026-08-05T11:26:28.571603Z","steps":["trace[367102661] 'range keys from in-memory index tree' (duration: 212.265189ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:26:28.57168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.151861ms","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-08-05T11:26:28.571725Z","caller":"traceutil/trace.go:171","msg":"trace[1417503975] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8572; }","duration":"219.245733ms","start":"2026-08-05T11:26:28.352469Z","end":"2026-08-05T11:26:28.571715Z","steps":["trace[1417503975] 'range keys from in-memory index tree' (duration: 219.004107ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:26:29.637316Z","caller":"traceutil/trace.go:171","msg":"trace[82406067] transaction","detail":"{read_only:false; response_revision:8576; number_of_response:1; }","duration":"243.487081ms","start":"2026-08-05T11:26:29.39381Z","end":"2026-08-05T11:26:29.637297Z","steps":["trace[82406067] 'process raft request' (duration: 243.341026ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:26:29.648048Z","caller":"traceutil/trace.go:171","msg":"trace[1795804650] linearizableReadLoop","detail":"{readStateIndex:8883; appliedIndex:8881; }","duration":"199.740528ms","start":"2026-08-05T11:26:29.448249Z","end":"2026-08-05T11:26:29.64799Z","steps":["trace[1795804650] 'read index received' (duration: 188.922396ms)","trace[1795804650] 'applied index is now lower than readState.Index' (duration: 10.816932ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:26:29.648173Z","caller":"traceutil/trace.go:171","msg":"trace[298670233] transaction","detail":"{read_only:false; response_revision:8577; number_of_response:1; }","duration":"206.091586ms","start":"2026-08-05T11:26:29.442071Z","end":"2026-08-05T11:26:29.648162Z","steps":["trace[298670233] 'process raft request' (duration: 205.782937ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:26:29.648339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.173323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-08-05T11:26:29.648404Z","caller":"traceutil/trace.go:171","msg":"trace[1975051813] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8577; }","duration":"194.251915ms","start":"2026-08-05T11:26:29.454129Z","end":"2026-08-05T11:26:29.648381Z","steps":["trace[1975051813] 'agreement among raft nodes before linearized reading' (duration: 194.098621ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:26:29.648557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.310235ms","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-08-05T11:26:29.648584Z","caller":"traceutil/trace.go:171","msg":"trace[2092826915] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8577; }","duration":"200.356796ms","start":"2026-08-05T11:26:29.448219Z","end":"2026-08-05T11:26:29.648576Z","steps":["trace[2092826915] 'agreement among raft nodes before linearized reading' (duration: 200.273293ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:26:29.648873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.172055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-05T11:26:29.64897Z","caller":"traceutil/trace.go:171","msg":"trace[413089769] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8577; }","duration":"193.320639ms","start":"2026-08-05T11:26:29.455628Z","end":"2026-08-05T11:26:29.648948Z","steps":["trace[413089769] 'agreement among raft nodes before linearized reading' (duration: 193.028351ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:26:29.784899Z","caller":"traceutil/trace.go:171","msg":"trace[1008752367] transaction","detail":"{read_only:false; response_revision:8579; number_of_response:1; }","duration":"132.336476ms","start":"2026-08-05T11:26:29.652535Z","end":"2026-08-05T11:26:29.784871Z","steps":["trace[1008752367] 'process raft request' (duration: 132.009107ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:26:29.784978Z","caller":"traceutil/trace.go:171","msg":"trace[295247840] transaction","detail":"{read_only:false; response_revision:8580; number_of_response:1; }","duration":"132.362857ms","start":"2026-08-05T11:26:29.652575Z","end":"2026-08-05T11:26:29.784937Z","steps":["trace[295247840] 'process raft request' (duration: 132.152011ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:26:29.785062Z","caller":"traceutil/trace.go:171","msg":"trace[1361795973] transaction","detail":"{read_only:false; response_revision:8581; number_of_response:1; }","duration":"131.092291ms","start":"2026-08-05T11:26:29.653948Z","end":"2026-08-05T11:26:29.785041Z","steps":["trace[1361795973] 'process raft request' (duration: 130.841864ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:27:08.919596Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002430.wal"} {"level":"info","ts":"2026-08-05T11:28:30.117481Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5caa70ef1dd6dc04","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-05T11:28:30.124599Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-05T11:28:30.124965Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-05T11:30:52.81433Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8289} {"level":"info","ts":"2026-08-05T11:30:52.888506Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8289,"took":"71.812071ms","hash":3699152407,"current-db-size-bytes":58249216,"current-db-size":"58 MB","current-db-size-in-use-bytes":25739264,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-05T11:30:52.888577Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3699152407,"revision":8289,"compact-revision":6090} {"level":"info","ts":"2026-08-05T11:35:52.821331Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10820} {"level":"info","ts":"2026-08-05T11:35:52.901567Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10820,"took":"77.766117ms","hash":1000207469,"current-db-size-bytes":58249216,"current-db-size":"58 MB","current-db-size-in-use-bytes":28053504,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-05T11:35:52.901616Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1000207469,"revision":10820,"compact-revision":8289} {"level":"info","ts":"2026-08-05T11:37:29.451982Z","caller":"traceutil/trace.go:171","msg":"trace[1225288636] transaction","detail":"{read_only:false; response_revision:13656; number_of_response:1; }","duration":"113.590081ms","start":"2026-08-05T11:37:29.338352Z","end":"2026-08-05T11:37:29.451943Z","steps":["trace[1225288636] 'process raft request' (duration: 113.278063ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:37:44.864018Z","caller":"traceutil/trace.go:171","msg":"trace[385192294] transaction","detail":"{read_only:false; response_revision:13746; number_of_response:1; }","duration":"134.881657ms","start":"2026-08-05T11:37:44.729116Z","end":"2026-08-05T11:37:44.863997Z","steps":["trace[385192294] 'process raft request' (duration: 134.750763ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:37:45.118636Z","caller":"traceutil/trace.go:171","msg":"trace[2050254163] transaction","detail":"{read_only:false; response_revision:13747; number_of_response:1; }","duration":"301.018115ms","start":"2026-08-05T11:37:44.817591Z","end":"2026-08-05T11:37:45.118609Z","steps":["trace[2050254163] 'process raft request' (duration: 294.357479ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:37:45.118784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:37:44.817568Z","time spent":"301.137129ms","remote":"127.0.0.1:47298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T11:38:07.045173Z","caller":"traceutil/trace.go:171","msg":"trace[1422608322] transaction","detail":"{read_only:false; response_revision:14056; number_of_response:1; }","duration":"368.635833ms","start":"2026-08-05T11:38:06.67651Z","end":"2026-08-05T11:38:07.045146Z","steps":["trace[1422608322] 'process raft request' (duration: 368.475439ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:38:07.04533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:38:06.676493Z","time spent":"368.761697ms","remote":"127.0.0.1:47186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3742,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T11:38:07.111816Z","caller":"traceutil/trace.go:171","msg":"trace[1739885885] linearizableReadLoop","detail":"{readStateIndex:14545; appliedIndex:14543; }","duration":"413.561498ms","start":"2026-08-05T11:38:06.69823Z","end":"2026-08-05T11:38:07.111792Z","steps":["trace[1739885885] 'read index received' (duration: 346.809364ms)","trace[1739885885] 'applied index is now lower than readState.Index' (duration: 66.750984ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:38:07.111947Z","caller":"traceutil/trace.go:171","msg":"trace[389697766] transaction","detail":"{read_only:false; response_revision:14058; number_of_response:1; }","duration":"152.956091ms","start":"2026-08-05T11:38:06.958966Z","end":"2026-08-05T11:38:07.111922Z","steps":["trace[389697766] 'process raft request' (duration: 152.782676ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:38:07.111873Z","caller":"traceutil/trace.go:171","msg":"trace[1380093424] transaction","detail":"{read_only:false; response_revision:14057; number_of_response:1; }","duration":"427.742483ms","start":"2026-08-05T11:38:06.684099Z","end":"2026-08-05T11:38:07.111842Z","steps":["trace[1380093424] 'process raft request' (duration: 427.498787ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:38:07.112102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.821175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3f28551e-dd7a-4449-9964-6007d56d2038\" ","response":"range_response_count:1 size:3757"} {"level":"info","ts":"2026-08-05T11:38:07.112197Z","caller":"traceutil/trace.go:171","msg":"trace[1534231577] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3f28551e-dd7a-4449-9964-6007d56d2038; range_end:; response_count:1; response_revision:14058; }","duration":"413.99254ms","start":"2026-08-05T11:38:06.698182Z","end":"2026-08-05T11:38:07.112175Z","steps":["trace[1534231577] 'agreement among raft nodes before linearized reading' (duration: 413.768453ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:38:07.112253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:38:06.698146Z","time spent":"414.097623ms","remote":"127.0.0.1:47186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3779,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3f28551e-dd7a-4449-9964-6007d56d2038\" "} {"level":"warn","ts":"2026-08-05T11:38:07.112331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:38:06.684075Z","time spent":"428.057502ms","remote":"127.0.0.1:47298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T11:38:58.642438Z","caller":"traceutil/trace.go:171","msg":"trace[1478742729] transaction","detail":"{read_only:false; response_revision:14686; number_of_response:1; }","duration":"118.989182ms","start":"2026-08-05T11:38:58.523417Z","end":"2026-08-05T11:38:58.642406Z","steps":["trace[1478742729] 'process raft request' (duration: 118.689524ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:39:20.97224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.181535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"warn","ts":"2026-08-05T11:39:20.972239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.059622ms","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-08-05T11:39:20.972345Z","caller":"traceutil/trace.go:171","msg":"trace[207063921] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14804; }","duration":"101.325829ms","start":"2026-08-05T11:39:20.870998Z","end":"2026-08-05T11:39:20.972324Z","steps":["trace[207063921] 'range keys from in-memory index tree' (duration: 100.923728ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:39:20.972371Z","caller":"traceutil/trace.go:171","msg":"trace[546106153] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14804; }","duration":"101.245087ms","start":"2026-08-05T11:39:20.871107Z","end":"2026-08-05T11:39:20.972352Z","steps":["trace[546106153] 'range keys from in-memory index tree' (duration: 101.00035ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:39:21.774602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.939617ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-05T11:39:21.775611Z","caller":"traceutil/trace.go:171","msg":"trace[1774008300] linearizableReadLoop","detail":"{readStateIndex:15315; appliedIndex:15314; }","duration":"152.894988ms","start":"2026-08-05T11:39:21.622686Z","end":"2026-08-05T11:39:21.775581Z","steps":["trace[1774008300] 'read index received' (duration: 152.664282ms)","trace[1774008300] 'applied index is now lower than readState.Index' (duration: 229.526µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:39:21.775002Z","caller":"traceutil/trace.go:171","msg":"trace[1439678990] transaction","detail":"{read_only:false; response_revision:14806; number_of_response:1; }","duration":"389.395542ms","start":"2026-08-05T11:39:21.385388Z","end":"2026-08-05T11:39:21.774783Z","steps":["trace[1439678990] 'process raft request' (duration: 163.166086ms)","trace[1439678990] 'compare' (duration: 225.433494ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:39:21.776189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.493384ms","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-08-05T11:39:21.776255Z","caller":"traceutil/trace.go:171","msg":"trace[472294263] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14807; }","duration":"153.606578ms","start":"2026-08-05T11:39:21.62263Z","end":"2026-08-05T11:39:21.776237Z","steps":["trace[472294263] 'agreement among raft nodes before linearized reading' (duration: 153.426833ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:39:21.776267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:39:21.385363Z","time spent":"390.829032ms","remote":"127.0.0.1:47298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T11:39:21.776419Z","caller":"traceutil/trace.go:171","msg":"trace[1771898413] transaction","detail":"{read_only:false; response_revision:14807; number_of_response:1; }","duration":"306.923129ms","start":"2026-08-05T11:39:21.46946Z","end":"2026-08-05T11:39:21.776383Z","steps":["trace[1771898413] 'process raft request' (duration: 305.969763ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:39:21.776582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:39:21.469432Z","time spent":"307.070283ms","remote":"127.0.0.1:47298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T11:40:52.828784Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13019} {"level":"info","ts":"2026-08-05T11:40:52.907616Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13019,"took":"76.004163ms","hash":202873910,"current-db-size-bytes":58249216,"current-db-size":"58 MB","current-db-size-in-use-bytes":30568448,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-05T11:40:52.907691Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":202873910,"revision":13019,"compact-revision":10820} {"level":"warn","ts":"2026-08-05T11:42:26.877128Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853972310906798331,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-05T11:42:27.377864Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853972310906798331,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-05T11:42:27.878729Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853972310906798331,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-05T11:42:28.5093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.632207758s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-05T11:42:28.509413Z","caller":"traceutil/trace.go:171","msg":"trace[1417353971] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.632529437s","start":"2026-08-05T11:42:26.876863Z","end":"2026-08-05T11:42:28.509392Z","steps":["trace[1417353971] 'agreement among raft nodes before linearized reading' (duration: 1.632400693s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:42:28.509462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:42:26.876847Z","time spent":"1.632604449s","remote":"127.0.0.1:46942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/05 11:42:28 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-05T11:42:28.509737Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853972310906798331,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-05T11:42:28.641302Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.732853695s","expected-duration":"1s"} {"level":"warn","ts":"2026-08-05T11:42:28.730691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:42:25.908325Z","time spent":"2.822358885s","remote":"127.0.0.1:47008","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-08-05T11:42:28.790544Z","caller":"traceutil/trace.go:171","msg":"trace[2026919695] transaction","detail":"{read_only:false; response_revision:16101; number_of_response:1; }","duration":"1.912865316s","start":"2026-08-05T11:42:26.877653Z","end":"2026-08-05T11:42:28.790518Z","steps":["trace[2026919695] 'process raft request' (duration: 1.912811764s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:42:28.790679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:42:26.87764Z","time spent":"1.912966059s","remote":"127.0.0.1:47298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T11:42:28.790835Z","caller":"traceutil/trace.go:171","msg":"trace[586249165] transaction","detail":"{read_only:false; response_revision:16102; number_of_response:1; }","duration":"1.907944389s","start":"2026-08-05T11:42:26.882868Z","end":"2026-08-05T11:42:28.790813Z","steps":["trace[586249165] 'process raft request' (duration: 1.90762087s)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:42:28.79085Z","caller":"traceutil/trace.go:171","msg":"trace[1938091041] transaction","detail":"{read_only:false; response_revision:16100; number_of_response:1; }","duration":"1.913579745s","start":"2026-08-05T11:42:26.877224Z","end":"2026-08-05T11:42:28.790804Z","steps":["trace[1938091041] 'process raft request' (duration: 1.913097252s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:42:28.790951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:42:26.882859Z","time spent":"1.908026991s","remote":"127.0.0.1:47298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T11:42:28.790994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:42:26.87721Z","time spent":"1.91371092s","remote":"127.0.0.1:47298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T11:42:28.791106Z","caller":"traceutil/trace.go:171","msg":"trace[1038178077] transaction","detail":"{read_only:false; response_revision:16103; number_of_response:1; }","duration":"1.593829297s","start":"2026-08-05T11:42:27.19727Z","end":"2026-08-05T11:42:28.791099Z","steps":["trace[1038178077] 'process raft request' (duration: 1.593478787s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:42:28.791156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:42:27.197234Z","time spent":"1.593894119s","remote":"127.0.0.1:47298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T11:42:28.791215Z","caller":"traceutil/trace.go:171","msg":"trace[1525801545] linearizableReadLoop","detail":"{readStateIndex:16658; appliedIndex:16653; }","duration":"2.650227196s","start":"2026-08-05T11:42:26.140982Z","end":"2026-08-05T11:42:28.791209Z","steps":["trace[1525801545] 'read index received' (duration: 2.500938767s)","trace[1525801545] 'applied index is now lower than readState.Index' (duration: 149.287689ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:42:28.791249Z","caller":"traceutil/trace.go:171","msg":"trace[1059139601] transaction","detail":"{read_only:false; response_revision:16104; number_of_response:1; }","duration":"193.411632ms","start":"2026-08-05T11:42:28.597825Z","end":"2026-08-05T11:42:28.791236Z","steps":["trace[1059139601] 'process raft request' (duration: 193.236387ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:42:28.791323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.6503461s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18678"} {"level":"info","ts":"2026-08-05T11:42:28.791345Z","caller":"traceutil/trace.go:171","msg":"trace[181753856] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:16104; }","duration":"2.650424401s","start":"2026-08-05T11:42:26.140914Z","end":"2026-08-05T11:42:28.791339Z","steps":["trace[181753856] 'agreement among raft nodes before linearized reading' (duration: 2.650339909s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:42:28.791359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:42:26.140885Z","time spent":"2.650471073s","remote":"127.0.0.1:47170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18700,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-08-05T11:42:28.794024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.911114337s","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-08-05T11:42:28.794088Z","caller":"traceutil/trace.go:171","msg":"trace[574495025] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:16105; }","duration":"1.9112007s","start":"2026-08-05T11:42:26.882872Z","end":"2026-08-05T11:42:28.794073Z","steps":["trace[574495025] 'agreement among raft nodes before linearized reading' (duration: 1.911040125s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:42:28.794084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.601032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-05T11:42:28.79412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:42:26.882861Z","time spent":"1.911251641s","remote":"127.0.0.1:47298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-05T11:42:28.794144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.91122708s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-05T11:42:28.794178Z","caller":"traceutil/trace.go:171","msg":"trace[1778406585] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:16105; }","duration":"1.911272091s","start":"2026-08-05T11:42:26.882898Z","end":"2026-08-05T11:42:28.79417Z","steps":["trace[1778406585] 'agreement among raft nodes before linearized reading' (duration: 1.911180269s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:42:28.794202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:42:26.882893Z","time spent":"1.911302952s","remote":"127.0.0.1:47298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-05T11:42:28.794201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"812.863709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-05T11:42:28.794226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.913388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-08-05T11:42:28.794256Z","caller":"traceutil/trace.go:171","msg":"trace[1389435436] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:16105; }","duration":"105.942129ms","start":"2026-08-05T11:42:28.688306Z","end":"2026-08-05T11:42:28.794248Z","steps":["trace[1389435436] 'agreement among raft nodes before linearized reading' (duration: 105.832045ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:42:28.794297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.911389515s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-52dmz\" ","response":"range_response_count:1 size:22132"} {"level":"info","ts":"2026-08-05T11:42:28.794328Z","caller":"traceutil/trace.go:171","msg":"trace[911599188] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-52dmz; range_end:; response_count:1; response_revision:16105; }","duration":"1.911435046s","start":"2026-08-05T11:42:26.882885Z","end":"2026-08-05T11:42:28.79432Z","steps":["trace[911599188] 'agreement among raft nodes before linearized reading' (duration: 1.911333353s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:42:28.794334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.911403025s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-08-05T11:42:28.794353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:42:26.882882Z","time spent":"1.911465826s","remote":"127.0.0.1:47186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22154,"request content":"key:\"/registry/pods/openstack/nova-compute-default-52dmz\" "} {"level":"info","ts":"2026-08-05T11:42:28.79435Z","caller":"traceutil/trace.go:171","msg":"trace[827708053] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:16105; }","duration":"813.030654ms","start":"2026-08-05T11:42:27.981297Z","end":"2026-08-05T11:42:28.794327Z","steps":["trace[827708053] 'agreement among raft nodes before linearized reading' (duration: 812.872889ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:42:28.794399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.435896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T11:42:28.794428Z","caller":"traceutil/trace.go:171","msg":"trace[852966886] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:16105; }","duration":"196.480846ms","start":"2026-08-05T11:42:28.597941Z","end":"2026-08-05T11:42:28.794422Z","steps":["trace[852966886] 'agreement among raft nodes before linearized reading' (duration: 196.431285ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:42:28.794124Z","caller":"traceutil/trace.go:171","msg":"trace[1044503801] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:16105; }","duration":"436.692775ms","start":"2026-08-05T11:42:28.357421Z","end":"2026-08-05T11:42:28.794114Z","steps":["trace[1044503801] 'agreement among raft nodes before linearized reading' (duration: 436.604102ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:42:28.794487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:42:28.357399Z","time spent":"437.077095ms","remote":"127.0.0.1:47140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-08-05T11:42:28.794495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.911636101s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-mljln\" ","response":"range_response_count:1 size:20660"} {"level":"info","ts":"2026-08-05T11:42:28.794522Z","caller":"traceutil/trace.go:171","msg":"trace[682954015] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-mljln; range_end:; response_count:1; response_revision:16105; }","duration":"1.911690983s","start":"2026-08-05T11:42:26.882823Z","end":"2026-08-05T11:42:28.794514Z","steps":["trace[682954015] 'agreement among raft nodes before linearized reading' (duration: 1.911622521s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:42:28.794547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:42:26.882808Z","time spent":"1.911733834s","remote":"127.0.0.1:47186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":20682,"request content":"key:\"/registry/pods/openstack/libvirt-libvirt-default-mljln\" "} {"level":"warn","ts":"2026-08-05T11:42:28.794551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.822764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T11:42:28.794574Z","caller":"traceutil/trace.go:171","msg":"trace[1553889431] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:16105; }","duration":"284.869825ms","start":"2026-08-05T11:42:28.509696Z","end":"2026-08-05T11:42:28.794566Z","steps":["trace[1553889431] 'agreement among raft nodes before linearized reading' (duration: 284.833054ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:42:28.794121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.596654366s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-05T11:42:28.794628Z","caller":"traceutil/trace.go:171","msg":"trace[116816660] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:16105; }","duration":"1.597231482s","start":"2026-08-05T11:42:27.197388Z","end":"2026-08-05T11:42:28.794619Z","steps":["trace[116816660] 'agreement among raft nodes before linearized reading' (duration: 1.596380888s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:42:28.794486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.409129139s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-4cs8r\" ","response":"range_response_count:1 size:9796"} {"level":"warn","ts":"2026-08-05T11:42:28.794659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:42:27.197375Z","time spent":"1.597276183s","remote":"127.0.0.1:47298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-05T11:42:28.794668Z","caller":"traceutil/trace.go:171","msg":"trace[536768171] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-4cs8r; range_end:; response_count:1; response_revision:16105; }","duration":"1.409343915s","start":"2026-08-05T11:42:27.385315Z","end":"2026-08-05T11:42:28.794658Z","steps":["trace[536768171] 'agreement among raft nodes before linearized reading' (duration: 1.409103628s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:42:28.794699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.911816406s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-52dmz\" ","response":"range_response_count:1 size:22132"} {"level":"warn","ts":"2026-08-05T11:42:28.794399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:42:27.981279Z","time spent":"813.107506ms","remote":"127.0.0.1:50724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":6,"response size":29,"request content":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true "} {"level":"info","ts":"2026-08-05T11:42:28.794727Z","caller":"traceutil/trace.go:171","msg":"trace[302361252] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-52dmz; range_end:; response_count:1; response_revision:16105; }","duration":"1.911852628s","start":"2026-08-05T11:42:26.882868Z","end":"2026-08-05T11:42:28.794721Z","steps":["trace[302361252] 'agreement among raft nodes before linearized reading' (duration: 1.911781766s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:42:28.794754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:42:26.882864Z","time spent":"1.911880918s","remote":"127.0.0.1:47186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22154,"request content":"key:\"/registry/pods/openstack/nova-compute-default-52dmz\" "} {"level":"info","ts":"2026-08-05T11:42:28.794356Z","caller":"traceutil/trace.go:171","msg":"trace[817627554] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:16105; }","duration":"1.911430225s","start":"2026-08-05T11:42:26.882919Z","end":"2026-08-05T11:42:28.794349Z","steps":["trace[817627554] 'agreement among raft nodes before linearized reading' (duration: 1.911371044s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:42:28.794799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:42:26.882914Z","time spent":"1.911878128s","remote":"127.0.0.1:47298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-05T11:42:28.794698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:42:27.385295Z","time spent":"1.409395256s","remote":"127.0.0.1:47186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9818,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-4cs8r\" "} {"level":"warn","ts":"2026-08-05T11:42:28.79484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.596145881s","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-08-05T11:42:28.794868Z","caller":"traceutil/trace.go:171","msg":"trace[1399661224] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:16105; }","duration":"1.596211604s","start":"2026-08-05T11:42:27.198648Z","end":"2026-08-05T11:42:28.79486Z","steps":["trace[1399661224] 'agreement among raft nodes before linearized reading' (duration: 1.596140322s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:42:28.794891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:42:27.198629Z","time spent":"1.596255464s","remote":"127.0.0.1:47298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-05T11:42:28.794774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.585886135s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-05T11:42:28.794991Z","caller":"traceutil/trace.go:171","msg":"trace[1903467752] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:16105; }","duration":"1.586126402s","start":"2026-08-05T11:42:27.208855Z","end":"2026-08-05T11:42:28.794981Z","steps":["trace[1903467752] 'agreement among raft nodes before linearized reading' (duration: 1.585858365s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:42:28.795006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.912389042s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-05T11:42:28.795017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:42:27.20884Z","time spent":"1.586168723s","remote":"127.0.0.1:47298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-08-05T11:42:28.795032Z","caller":"traceutil/trace.go:171","msg":"trace[616810305] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:16105; }","duration":"1.912447984s","start":"2026-08-05T11:42:26.882575Z","end":"2026-08-05T11:42:28.795023Z","steps":["trace[616810305] 'agreement among raft nodes before linearized reading' (duration: 1.912404043s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:42:28.795062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:42:26.882562Z","time spent":"1.912493685s","remote":"127.0.0.1:39784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-08-05T11:42:28.794954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.912183017s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T11:42:28.795202Z","caller":"traceutil/trace.go:171","msg":"trace[1210388277] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagerconfigs/; range_end:/registry/monitoring.coreos.com/alertmanagerconfigs0; response_count:0; response_revision:16105; }","duration":"1.912461325s","start":"2026-08-05T11:42:26.882731Z","end":"2026-08-05T11:42:28.795192Z","steps":["trace[1210388277] 'agreement among raft nodes before linearized reading' (duration: 1.912187747s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:42:28.795232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:42:26.882715Z","time spent":"1.912504896s","remote":"127.0.0.1:50686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":27,"request content":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true "} {"level":"warn","ts":"2026-08-05T11:42:28.795636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.91802309s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-krrfx\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-08-05T11:42:28.795685Z","caller":"traceutil/trace.go:171","msg":"trace[694305883] range","detail":"{range_begin:/registry/pods/openstack/keepalived-krrfx; range_end:; response_count:1; response_revision:16105; }","duration":"1.918196044s","start":"2026-08-05T11:42:26.877476Z","end":"2026-08-05T11:42:28.795672Z","steps":["trace[694305883] 'agreement among raft nodes before linearized reading' (duration: 1.916230079s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:42:28.795722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:42:26.87746Z","time spent":"1.918252496s","remote":"127.0.0.1:47186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6552,"request content":"key:\"/registry/pods/openstack/keepalived-krrfx\" "} {"level":"info","ts":"2026-08-05T11:42:31.420928Z","caller":"traceutil/trace.go:171","msg":"trace[1180070207] transaction","detail":"{read_only:false; response_revision:16282; number_of_response:1; }","duration":"200.663294ms","start":"2026-08-05T11:42:31.220234Z","end":"2026-08-05T11:42:31.420897Z","steps":["trace[1180070207] 'process raft request' (duration: 197.725242ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:42:31.420956Z","caller":"traceutil/trace.go:171","msg":"trace[1669191068] linearizableReadLoop","detail":"{readStateIndex:16839; appliedIndex:16838; }","duration":"192.98279ms","start":"2026-08-05T11:42:31.227945Z","end":"2026-08-05T11:42:31.420928Z","steps":["trace[1669191068] 'read index received' (duration: 190.038668ms)","trace[1669191068] 'applied index is now lower than readState.Index' (duration: 2.941022ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:42:31.426347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.37713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:89 size:896198"} {"level":"info","ts":"2026-08-05T11:42:31.426412Z","caller":"traceutil/trace.go:171","msg":"trace[1418705439] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:89; response_revision:16282; }","duration":"198.474902ms","start":"2026-08-05T11:42:31.227921Z","end":"2026-08-05T11:42:31.426396Z","steps":["trace[1418705439] 'agreement among raft nodes before linearized reading' (duration: 193.088182ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:42:31.426478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.458792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:89 size:896198"} {"level":"info","ts":"2026-08-05T11:42:31.426583Z","caller":"traceutil/trace.go:171","msg":"trace[995098923] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:89; response_revision:16282; }","duration":"198.576186ms","start":"2026-08-05T11:42:31.227978Z","end":"2026-08-05T11:42:31.426554Z","steps":["trace[995098923] 'agreement among raft nodes before linearized reading' (duration: 197.537767ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:45:32.350958Z","caller":"traceutil/trace.go:171","msg":"trace[1322920849] transaction","detail":"{read_only:false; response_revision:17493; number_of_response:1; }","duration":"215.757643ms","start":"2026-08-05T11:45:32.135158Z","end":"2026-08-05T11:45:32.350916Z","steps":["trace[1322920849] 'process raft request' (duration: 215.121986ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:45:32.53712Z","caller":"traceutil/trace.go:171","msg":"trace[337316171] linearizableReadLoop","detail":"{readStateIndex:18092; appliedIndex:18091; }","duration":"373.541138ms","start":"2026-08-05T11:45:32.163557Z","end":"2026-08-05T11:45:32.537098Z","steps":["trace[337316171] 'read index received' (duration: 189.86737ms)","trace[337316171] 'applied index is now lower than readState.Index' (duration: 183.672618ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:45:32.537227Z","caller":"traceutil/trace.go:171","msg":"trace[1523701716] transaction","detail":"{read_only:false; response_revision:17494; number_of_response:1; }","duration":"398.650989ms","start":"2026-08-05T11:45:32.138551Z","end":"2026-08-05T11:45:32.537202Z","steps":["trace[1523701716] 'process raft request' (duration: 398.33071ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:45:32.537361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:45:32.138523Z","time spent":"398.768162ms","remote":"127.0.0.1:47170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T11:45:32.53753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.960449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-08-05T11:45:32.537517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.144973ms","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-08-05T11:45:32.537626Z","caller":"traceutil/trace.go:171","msg":"trace[934524472] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:17494; }","duration":"374.112764ms","start":"2026-08-05T11:45:32.163504Z","end":"2026-08-05T11:45:32.537617Z","steps":["trace[934524472] 'agreement among raft nodes before linearized reading' (duration: 373.743114ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:45:32.537655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:45:32.163483Z","time spent":"374.166225ms","remote":"127.0.0.1:47298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-05T11:45:32.537652Z","caller":"traceutil/trace.go:171","msg":"trace[1009885867] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:17494; }","duration":"360.332599ms","start":"2026-08-05T11:45:32.1773Z","end":"2026-08-05T11:45:32.537633Z","steps":["trace[1009885867] 'agreement among raft nodes before linearized reading' (duration: 360.01441ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:45:32.537694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:45:32.17728Z","time spent":"360.404801ms","remote":"127.0.0.1:47298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-05T11:45:32.537704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.654815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T11:45:32.537731Z","caller":"traceutil/trace.go:171","msg":"trace[994614792] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:17494; }","duration":"313.681256ms","start":"2026-08-05T11:45:32.224041Z","end":"2026-08-05T11:45:32.537722Z","steps":["trace[994614792] 'agreement among raft nodes before linearized reading' (duration: 313.632485ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:45:32.53775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:45:32.223994Z","time spent":"313.751249ms","remote":"127.0.0.1:46842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" count_only:true "} {"level":"warn","ts":"2026-08-05T11:45:32.537728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.38482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-08-05T11:45:32.537807Z","caller":"traceutil/trace.go:171","msg":"trace[395506967] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:17494; }","duration":"360.495864ms","start":"2026-08-05T11:45:32.177289Z","end":"2026-08-05T11:45:32.537785Z","steps":["trace[395506967] 'agreement among raft nodes before linearized reading' (duration: 360.175415ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:45:32.537847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:45:32.177279Z","time spent":"360.561825ms","remote":"127.0.0.1:47298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-05T11:45:32.538808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.392656ms","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-08-05T11:45:32.538858Z","caller":"traceutil/trace.go:171","msg":"trace[1011724646] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:17494; }","duration":"362.481128ms","start":"2026-08-05T11:45:32.176364Z","end":"2026-08-05T11:45:32.538845Z","steps":["trace[1011724646] 'agreement among raft nodes before linearized reading' (duration: 360.922225ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:45:32.538881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:45:32.176343Z","time spent":"362.53258ms","remote":"127.0.0.1:47298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-05T11:45:32.946097Z","caller":"traceutil/trace.go:171","msg":"trace[606661685] transaction","detail":"{read_only:false; response_revision:17496; number_of_response:1; }","duration":"403.01608ms","start":"2026-08-05T11:45:32.543047Z","end":"2026-08-05T11:45:32.946063Z","steps":["trace[606661685] 'process raft request' (duration: 402.776844ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:45:32.946425Z","caller":"traceutil/trace.go:171","msg":"trace[1536136184] transaction","detail":"{read_only:false; response_revision:17497; number_of_response:1; }","duration":"399.325658ms","start":"2026-08-05T11:45:32.547071Z","end":"2026-08-05T11:45:32.946396Z","steps":["trace[1536136184] 'process raft request' (duration: 398.937327ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:45:32.94645Z","caller":"traceutil/trace.go:171","msg":"trace[701492388] linearizableReadLoop","detail":"{readStateIndex:18094; appliedIndex:18093; }","duration":"403.081253ms","start":"2026-08-05T11:45:32.543347Z","end":"2026-08-05T11:45:32.946429Z","steps":["trace[701492388] 'read index received' (duration: 45.783308ms)","trace[701492388] 'applied index is now lower than readState.Index' (duration: 357.296955ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:45:32.946557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:45:32.547038Z","time spent":"399.453931ms","remote":"127.0.0.1:47298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T11:45:32.946677Z","caller":"traceutil/trace.go:171","msg":"trace[1079470832] transaction","detail":"{read_only:false; response_revision:17498; number_of_response:1; }","duration":"397.930158ms","start":"2026-08-05T11:45:32.548735Z","end":"2026-08-05T11:45:32.946665Z","steps":["trace[1079470832] 'process raft request' (duration: 397.571998ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:45:32.94673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.36577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/rook-ceph/rook-ceph-operator-config\" ","response":"range_response_count:1 size:6825"} {"level":"info","ts":"2026-08-05T11:45:32.946774Z","caller":"traceutil/trace.go:171","msg":"trace[1998259471] range","detail":"{range_begin:/registry/configmaps/rook-ceph/rook-ceph-operator-config; range_end:; response_count:1; response_revision:17498; }","duration":"403.439813ms","start":"2026-08-05T11:45:32.543324Z","end":"2026-08-05T11:45:32.946764Z","steps":["trace[1998259471] 'agreement among raft nodes before linearized reading' (duration: 403.297009ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:45:32.946806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:45:32.543317Z","time spent":"403.482334ms","remote":"127.0.0.1:47090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":6847,"request content":"key:\"/registry/configmaps/rook-ceph/rook-ceph-operator-config\" "} {"level":"warn","ts":"2026-08-05T11:45:32.946929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:45:32.548711Z","time spent":"397.99536ms","remote":"127.0.0.1:47298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T11:45:32.946974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:45:32.543022Z","time spent":"403.274328ms","remote":"127.0.0.1:47298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T11:45:33.266393Z","caller":"traceutil/trace.go:171","msg":"trace[1345653814] linearizableReadLoop","detail":"{readStateIndex:18097; appliedIndex:18096; }","duration":"319.821448ms","start":"2026-08-05T11:45:32.946548Z","end":"2026-08-05T11:45:33.266369Z","steps":["trace[1345653814] 'read index received' (duration: 301.325411ms)","trace[1345653814] 'applied index is now lower than readState.Index' (duration: 18.494867ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:45:33.266646Z","caller":"traceutil/trace.go:171","msg":"trace[463866358] transaction","detail":"{read_only:false; response_revision:17499; number_of_response:1; }","duration":"421.034713ms","start":"2026-08-05T11:45:32.84557Z","end":"2026-08-05T11:45:33.266605Z","steps":["trace[463866358] 'process raft request' (duration: 402.361782ms)","trace[463866358] 'compare' (duration: 18.334832ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:45:33.266739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:45:32.845547Z","time spent":"421.129386ms","remote":"127.0.0.1:47298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T11:45:33.266919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.403288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T11:45:33.266954Z","caller":"traceutil/trace.go:171","msg":"trace[397911852] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:17499; }","duration":"323.48096ms","start":"2026-08-05T11:45:32.943464Z","end":"2026-08-05T11:45:33.266945Z","steps":["trace[397911852] 'agreement among raft nodes before linearized reading' (duration: 323.420648ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:45:33.267152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:45:32.943439Z","time spent":"323.706466ms","remote":"127.0.0.1:46712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true "} {"level":"warn","ts":"2026-08-05T11:45:33.267332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.371561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-mljln\" ","response":"range_response_count:1 size:20763"} {"level":"info","ts":"2026-08-05T11:45:33.267362Z","caller":"traceutil/trace.go:171","msg":"trace[387446301] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-mljln; range_end:; response_count:1; response_revision:17499; }","duration":"657.426792ms","start":"2026-08-05T11:45:32.609926Z","end":"2026-08-05T11:45:33.267352Z","steps":["trace[387446301] 'agreement among raft nodes before linearized reading' (duration: 657.33077ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:45:33.267381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:45:32.609909Z","time spent":"657.467564ms","remote":"127.0.0.1:47186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":20785,"request content":"key:\"/registry/pods/openstack/libvirt-libvirt-default-mljln\" "} {"level":"warn","ts":"2026-08-05T11:45:33.26751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.333847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T11:45:33.267538Z","caller":"traceutil/trace.go:171","msg":"trace[1074504944] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17499; }","duration":"673.359627ms","start":"2026-08-05T11:45:32.59417Z","end":"2026-08-05T11:45:33.267529Z","steps":["trace[1074504944] 'agreement among raft nodes before linearized reading' (duration: 673.318966ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:45:33.267556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:45:32.594151Z","time spent":"673.401058ms","remote":"127.0.0.1:46942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-05T11:45:33.267744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.012859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/networkfences.csiaddons.openshift.io\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T11:45:33.267839Z","caller":"traceutil/trace.go:171","msg":"trace[1695337472] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/networkfences.csiaddons.openshift.io; range_end:; response_count:0; response_revision:17499; }","duration":"317.170374ms","start":"2026-08-05T11:45:32.950647Z","end":"2026-08-05T11:45:33.267818Z","steps":["trace[1695337472] 'agreement among raft nodes before linearized reading' (duration: 316.945258ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:45:33.267922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:45:32.950634Z","time spent":"317.246586ms","remote":"127.0.0.1:47024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/networkfences.csiaddons.openshift.io\" "} {"level":"warn","ts":"2026-08-05T11:45:33.268324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.62637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-08-05T11:45:33.268361Z","caller":"traceutil/trace.go:171","msg":"trace[936623088] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:17499; }","duration":"162.696422ms","start":"2026-08-05T11:45:33.105655Z","end":"2026-08-05T11:45:33.268351Z","steps":["trace[936623088] 'agreement among raft nodes before linearized reading' (duration: 162.61238ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:45:33.836915Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853972310906803973,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-05T11:45:33.848201Z","caller":"traceutil/trace.go:171","msg":"trace[1448974460] transaction","detail":"{read_only:false; response_revision:17500; number_of_response:1; }","duration":"557.079702ms","start":"2026-08-05T11:45:33.291091Z","end":"2026-08-05T11:45:33.848171Z","steps":["trace[1448974460] 'process raft request' (duration: 556.886736ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:45:33.848366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:45:33.291059Z","time spent":"557.224326ms","remote":"127.0.0.1:47186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20878,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T11:45:33.923649Z","caller":"traceutil/trace.go:171","msg":"trace[1540563052] linearizableReadLoop","detail":"{readStateIndex:18101; appliedIndex:18097; }","duration":"586.76066ms","start":"2026-08-05T11:45:33.336862Z","end":"2026-08-05T11:45:33.923623Z","steps":["trace[1540563052] 'read index received' (duration: 511.17924ms)","trace[1540563052] 'applied index is now lower than readState.Index' (duration: 75.58054ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:45:33.923926Z","caller":"traceutil/trace.go:171","msg":"trace[732175358] transaction","detail":"{read_only:false; response_revision:17503; number_of_response:1; }","duration":"136.054628ms","start":"2026-08-05T11:45:33.787831Z","end":"2026-08-05T11:45:33.923886Z","steps":["trace[732175358] 'process raft request' (duration: 135.688638ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:45:33.924065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.198522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T11:45:33.924115Z","caller":"traceutil/trace.go:171","msg":"trace[1983743584] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:17503; }","duration":"587.310405ms","start":"2026-08-05T11:45:33.336793Z","end":"2026-08-05T11:45:33.924103Z","steps":["trace[1983743584] 'agreement among raft nodes before linearized reading' (duration: 587.221763ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:45:33.924155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:45:33.336737Z","time spent":"587.410418ms","remote":"127.0.0.1:47478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-08-05T11:45:33.924166Z","caller":"traceutil/trace.go:171","msg":"trace[811688205] transaction","detail":"{read_only:false; response_revision:17501; number_of_response:1; }","duration":"149.259157ms","start":"2026-08-05T11:45:33.7749Z","end":"2026-08-05T11:45:33.924159Z","steps":["trace[811688205] 'process raft request' (duration: 148.444964ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:45:33.924258Z","caller":"traceutil/trace.go:171","msg":"trace[1063282637] transaction","detail":"{read_only:false; response_revision:17502; number_of_response:1; }","duration":"137.269172ms","start":"2026-08-05T11:45:33.786982Z","end":"2026-08-05T11:45:33.924251Z","steps":["trace[1063282637] 'process raft request' (duration: 136.499171ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:45:33.924471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.551767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-52dmz\" ","response":"range_response_count:1 size:22133"} {"level":"info","ts":"2026-08-05T11:45:33.92453Z","caller":"traceutil/trace.go:171","msg":"trace[164246823] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-52dmz; range_end:; response_count:1; response_revision:17503; }","duration":"388.615739ms","start":"2026-08-05T11:45:33.535903Z","end":"2026-08-05T11:45:33.924519Z","steps":["trace[164246823] 'agreement among raft nodes before linearized reading' (duration: 388.438084ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:45:33.92457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:45:33.535841Z","time spent":"388.720062ms","remote":"127.0.0.1:47186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22155,"request content":"key:\"/registry/pods/openstack/nova-compute-default-52dmz\" "} {"level":"warn","ts":"2026-08-05T11:45:33.924647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.382904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T11:45:33.924675Z","caller":"traceutil/trace.go:171","msg":"trace[499432252] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17503; }","duration":"315.474607ms","start":"2026-08-05T11:45:33.609194Z","end":"2026-08-05T11:45:33.924669Z","steps":["trace[499432252] 'agreement among raft nodes before linearized reading' (duration: 315.417205ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:45:33.924696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:45:33.609166Z","time spent":"315.525628ms","remote":"127.0.0.1:46942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-05T11:45:46.527602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.862705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-08-05T11:45:46.527747Z","caller":"traceutil/trace.go:171","msg":"trace[2016137592] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17689; }","duration":"106.0512ms","start":"2026-08-05T11:45:46.421673Z","end":"2026-08-05T11:45:46.527725Z","steps":["trace[2016137592] 'range keys from in-memory index tree' (duration: 105.283848ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:45:46.644827Z","caller":"traceutil/trace.go:171","msg":"trace[1214861492] transaction","detail":"{read_only:false; response_revision:17690; number_of_response:1; }","duration":"132.694744ms","start":"2026-08-05T11:45:46.51211Z","end":"2026-08-05T11:45:46.644805Z","steps":["trace[1214861492] 'process raft request' (duration: 132.505939ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:45:46.681344Z","caller":"traceutil/trace.go:171","msg":"trace[135157537] transaction","detail":"{read_only:false; response_revision:17691; number_of_response:1; }","duration":"147.490097ms","start":"2026-08-05T11:45:46.533784Z","end":"2026-08-05T11:45:46.681274Z","steps":["trace[135157537] 'process raft request' (duration: 147.279951ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:45:47.1702Z","caller":"traceutil/trace.go:171","msg":"trace[951702657] transaction","detail":"{read_only:false; response_revision:17695; number_of_response:1; }","duration":"122.953302ms","start":"2026-08-05T11:45:47.047189Z","end":"2026-08-05T11:45:47.170142Z","steps":["trace[951702657] 'process raft request' (duration: 122.809758ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:45:52.836982Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15399} {"level":"info","ts":"2026-08-05T11:45:52.936815Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15399,"took":"96.934846ms","hash":2712486564,"current-db-size-bytes":58249216,"current-db-size":"58 MB","current-db-size-in-use-bytes":32395264,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-05T11:45:52.936909Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2712486564,"revision":15399,"compact-revision":13019} {"level":"info","ts":"2026-08-05T11:49:22.353874Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5caa70ef1dd6dc04","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-05T11:49:22.359119Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-05T11:49:22.35934Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-05T11:50:52.845021Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17749} {"level":"info","ts":"2026-08-05T11:50:52.949753Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17749,"took":"100.876767ms","hash":3294635659,"current-db-size-bytes":58249216,"current-db-size":"58 MB","current-db-size-in-use-bytes":33382400,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-08-05T11:50:52.949818Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3294635659,"revision":17749,"compact-revision":15399} {"level":"info","ts":"2026-08-05T11:51:05.612203Z","caller":"traceutil/trace.go:171","msg":"trace[827925312] transaction","detail":"{read_only:false; response_revision:20105; number_of_response:1; }","duration":"202.190184ms","start":"2026-08-05T11:51:05.409969Z","end":"2026-08-05T11:51:05.612159Z","steps":["trace[827925312] 'process raft request' (duration: 130.181314ms)","trace[827925312] 'compare' (duration: 71.764243ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:51:05.612417Z","caller":"traceutil/trace.go:171","msg":"trace[627422237] transaction","detail":"{read_only:false; response_revision:20106; number_of_response:1; }","duration":"196.387172ms","start":"2026-08-05T11:51:05.415988Z","end":"2026-08-05T11:51:05.612376Z","steps":["trace[627422237] 'process raft request' (duration: 196.108604ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:51:16.291806Z","caller":"traceutil/trace.go:171","msg":"trace[398178879] linearizableReadLoop","detail":"{readStateIndex:20851; appliedIndex:20850; }","duration":"180.424436ms","start":"2026-08-05T11:51:16.111344Z","end":"2026-08-05T11:51:16.291769Z","steps":["trace[398178879] 'read index received' (duration: 178.836412ms)","trace[398178879] 'applied index is now lower than readState.Index' (duration: 1.586134ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:51:16.291871Z","caller":"traceutil/trace.go:171","msg":"trace[563766857] transaction","detail":"{read_only:false; response_revision:20161; number_of_response:1; }","duration":"182.081053ms","start":"2026-08-05T11:51:16.109739Z","end":"2026-08-05T11:51:16.29182Z","steps":["trace[563766857] 'process raft request' (duration: 180.510909ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:51:16.292128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.758856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-08-05T11:51:16.292213Z","caller":"traceutil/trace.go:171","msg":"trace[1484261889] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:20161; }","duration":"180.887909ms","start":"2026-08-05T11:51:16.111304Z","end":"2026-08-05T11:51:16.292192Z","steps":["trace[1484261889] 'agreement among raft nodes before linearized reading' (duration: 180.585011ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:51:16.89986Z","caller":"traceutil/trace.go:171","msg":"trace[1173470538] linearizableReadLoop","detail":"{readStateIndex:20852; appliedIndex:20851; }","duration":"153.797133ms","start":"2026-08-05T11:51:16.746035Z","end":"2026-08-05T11:51:16.899832Z","steps":["trace[1173470538] 'read index received' (duration: 153.614408ms)","trace[1173470538] 'applied index is now lower than readState.Index' (duration: 181.035µs)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:51:16.90005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.007929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-08-05T11:51:16.900014Z","caller":"traceutil/trace.go:171","msg":"trace[1266245004] transaction","detail":"{read_only:false; response_revision:20162; number_of_response:1; }","duration":"187.233207ms","start":"2026-08-05T11:51:16.712737Z","end":"2026-08-05T11:51:16.89997Z","steps":["trace[1266245004] 'process raft request' (duration: 186.933619ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:51:16.900099Z","caller":"traceutil/trace.go:171","msg":"trace[1382520964] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20162; }","duration":"154.112522ms","start":"2026-08-05T11:51:16.745972Z","end":"2026-08-05T11:51:16.900084Z","steps":["trace[1382520964] 'agreement among raft nodes before linearized reading' (duration: 153.965278ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:51:17.180998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.542034ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-05T11:51:17.181121Z","caller":"traceutil/trace.go:171","msg":"trace[1906060740] linearizableReadLoop","detail":"{readStateIndex:20853; appliedIndex:20852; }","duration":"134.400562ms","start":"2026-08-05T11:51:17.046703Z","end":"2026-08-05T11:51:17.181104Z","steps":["trace[1906060740] 'read index received' (duration: 3.608301ms)","trace[1906060740] 'applied index is now lower than readState.Index' (duration: 130.790901ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:51:17.181215Z","caller":"traceutil/trace.go:171","msg":"trace[1414771792] transaction","detail":"{read_only:false; response_revision:20163; number_of_response:1; }","duration":"275.539011ms","start":"2026-08-05T11:51:16.90566Z","end":"2026-08-05T11:51:17.181199Z","steps":["trace[1414771792] 'process raft request' (duration: 144.709809ms)","trace[1414771792] 'compare' (duration: 130.427101ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:51:17.181812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.117342ms","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-08-05T11:51:17.18187Z","caller":"traceutil/trace.go:171","msg":"trace[392952092] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20163; }","duration":"135.175554ms","start":"2026-08-05T11:51:17.046675Z","end":"2026-08-05T11:51:17.181851Z","steps":["trace[392952092] 'agreement among raft nodes before linearized reading' (duration: 134.977858ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:51:17.392594Z","caller":"traceutil/trace.go:171","msg":"trace[275704089] linearizableReadLoop","detail":"{readStateIndex:20856; appliedIndex:20853; }","duration":"206.088133ms","start":"2026-08-05T11:51:17.186478Z","end":"2026-08-05T11:51:17.392566Z","steps":["trace[275704089] 'read index received' (duration: 54.163642ms)","trace[275704089] 'applied index is now lower than readState.Index' (duration: 151.922351ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:51:17.392693Z","caller":"traceutil/trace.go:171","msg":"trace[12822430] transaction","detail":"{read_only:false; response_revision:20165; number_of_response:1; }","duration":"206.360231ms","start":"2026-08-05T11:51:17.1863Z","end":"2026-08-05T11:51:17.39266Z","steps":["trace[12822430] 'process raft request' (duration: 203.737038ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:51:17.393015Z","caller":"traceutil/trace.go:171","msg":"trace[649169424] transaction","detail":"{read_only:false; response_revision:20166; number_of_response:1; }","duration":"206.620068ms","start":"2026-08-05T11:51:17.186366Z","end":"2026-08-05T11:51:17.392986Z","steps":["trace[649169424] 'process raft request' (duration: 206.130114ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:51:17.392923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.423762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-08-05T11:51:17.393284Z","caller":"traceutil/trace.go:171","msg":"trace[1259679117] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:20166; }","duration":"206.792383ms","start":"2026-08-05T11:51:17.186453Z","end":"2026-08-05T11:51:17.393246Z","steps":["trace[1259679117] 'agreement among raft nodes before linearized reading' (duration: 206.277349ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:55:03.735761Z","caller":"traceutil/trace.go:171","msg":"trace[1058737927] linearizableReadLoop","detail":"{readStateIndex:23236; appliedIndex:23235; }","duration":"124.868923ms","start":"2026-08-05T11:55:03.610862Z","end":"2026-08-05T11:55:03.735731Z","steps":["trace[1058737927] 'read index received' (duration: 124.668468ms)","trace[1058737927] 'applied index is now lower than readState.Index' (duration: 198.995µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:55:03.736197Z","caller":"traceutil/trace.go:171","msg":"trace[1000818150] transaction","detail":"{read_only:false; response_revision:22476; number_of_response:1; }","duration":"126.89997ms","start":"2026-08-05T11:55:03.609276Z","end":"2026-08-05T11:55:03.736176Z","steps":["trace[1000818150] 'process raft request' (duration: 126.246962ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:55:03.736512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.621304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/servergroups/cert-manager-test/\" range_end:\"/registry/openstack.k-orc.cloud/servergroups/cert-manager-test0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T11:55:03.736583Z","caller":"traceutil/trace.go:171","msg":"trace[620487029] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/servergroups/cert-manager-test/; range_end:/registry/openstack.k-orc.cloud/servergroups/cert-manager-test0; response_count:0; response_revision:22476; }","duration":"125.708127ms","start":"2026-08-05T11:55:03.610858Z","end":"2026-08-05T11:55:03.736566Z","steps":["trace[620487029] 'agreement among raft nodes before linearized reading' (duration: 125.589373ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:55:03.747228Z","caller":"traceutil/trace.go:171","msg":"trace[1168324635] transaction","detail":"{read_only:false; response_revision:22477; number_of_response:1; }","duration":"131.958102ms","start":"2026-08-05T11:55:03.615251Z","end":"2026-08-05T11:55:03.747209Z","steps":["trace[1168324635] 'process raft request' (duration: 131.498369ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:55:52.85417Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20039} {"level":"info","ts":"2026-08-05T11:55:52.959589Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20039,"took":"101.485391ms","hash":2941112941,"current-db-size-bytes":59092992,"current-db-size":"59 MB","current-db-size-in-use-bytes":42541056,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-08-05T11:55:52.959706Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2941112941,"revision":20039,"compact-revision":17749} {"level":"info","ts":"2026-08-05T12:00:52.860067Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23064} {"level":"info","ts":"2026-08-05T12:00:52.995372Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23064,"took":"130.900432ms","hash":3400351385,"current-db-size-bytes":59092992,"current-db-size":"59 MB","current-db-size-in-use-bytes":31719424,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-05T12:00:52.995449Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3400351385,"revision":23064,"compact-revision":20039}