{"level":"warn","ts":"2026-08-05T21:38:31.763523Z","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-05T21:38:31.763653Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.156:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.156:2380","--initial-cluster=instance=https://199.19.213.156:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.156:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.156: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-05T21:38:31.763748Z","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-05T21:38:31.763762Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.156:2380"]} {"level":"info","ts":"2026-08-05T21:38:31.763784Z","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-05T21:38:31.764631Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.156:2379"]} {"level":"info","ts":"2026-08-05T21:38:31.764728Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.156:2380"],"listen-peer-urls":["https://199.19.213.156:2380"],"advertise-client-urls":["https://199.19.213.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.156: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-05T21:38:31.76708Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.06214ms"} {"level":"info","ts":"2026-08-05T21:38:31.772701Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"163ef968c92247ab","cluster-id":"59d11c119c278888"} {"level":"info","ts":"2026-08-05T21:38:31.772814Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"163ef968c92247ab switched to configuration voters=()"} {"level":"info","ts":"2026-08-05T21:38:31.772837Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"163ef968c92247ab became follower at term 0"} {"level":"info","ts":"2026-08-05T21:38:31.772857Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 163ef968c92247ab [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-05T21:38:31.772865Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"163ef968c92247ab became follower at term 1"} {"level":"info","ts":"2026-08-05T21:38:31.772906Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"163ef968c92247ab switched to configuration voters=(1602992745836857259)"} {"level":"warn","ts":"2026-08-05T21:38:31.782252Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-05T21:38:31.783544Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-05T21:38:31.785156Z","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-05T21:38:31.788129Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"163ef968c92247ab","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-05T21:38:31.788967Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"163ef968c92247ab","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-05T21:38:31.789137Z","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-05T21:38:31.789191Z","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-05T21:38:31.789205Z","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-05T21:38:31.789501Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"163ef968c92247ab switched to configuration voters=(1602992745836857259)"} {"level":"info","ts":"2026-08-05T21:38:31.789621Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"59d11c119c278888","local-member-id":"163ef968c92247ab","added-peer-id":"163ef968c92247ab","added-peer-peer-urls":["https://199.19.213.156:2380"]} {"level":"info","ts":"2026-08-05T21:38:31.793982Z","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-05T21:38:31.79405Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.156:2380"} {"level":"info","ts":"2026-08-05T21:38:31.794098Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.156:2380"} {"level":"info","ts":"2026-08-05T21:38:31.794241Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"163ef968c92247ab","initial-advertise-peer-urls":["https://199.19.213.156:2380"],"listen-peer-urls":["https://199.19.213.156:2380"],"advertise-client-urls":["https://199.19.213.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-05T21:38:31.794287Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-05T21:38:32.373931Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"163ef968c92247ab is starting a new election at term 1"} {"level":"info","ts":"2026-08-05T21:38:32.373985Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"163ef968c92247ab became pre-candidate at term 1"} {"level":"info","ts":"2026-08-05T21:38:32.374035Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"163ef968c92247ab received MsgPreVoteResp from 163ef968c92247ab at term 1"} {"level":"info","ts":"2026-08-05T21:38:32.374058Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"163ef968c92247ab became candidate at term 2"} {"level":"info","ts":"2026-08-05T21:38:32.37407Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"163ef968c92247ab received MsgVoteResp from 163ef968c92247ab at term 2"} {"level":"info","ts":"2026-08-05T21:38:32.374085Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"163ef968c92247ab became leader at term 2"} {"level":"info","ts":"2026-08-05T21:38:32.374099Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 163ef968c92247ab elected leader 163ef968c92247ab at term 2"} {"level":"info","ts":"2026-08-05T21:38:32.375235Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"163ef968c92247ab","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.156:2379]}","request-path":"/0/members/163ef968c92247ab/attributes","cluster-id":"59d11c119c278888","publish-timeout":"7s"} {"level":"info","ts":"2026-08-05T21:38:32.375455Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-05T21:38:32.375507Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-05T21:38:32.375588Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T21:38:32.375763Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-05T21:38:32.37583Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-05T21:38:32.376515Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"59d11c119c278888","local-member-id":"163ef968c92247ab","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T21:38:32.376661Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T21:38:32.376724Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T21:38:32.377176Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-05T21:38:32.37797Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.156:2379"} {"level":"info","ts":"2026-08-05T21:39:10.108406Z","caller":"traceutil/trace.go:171","msg":"trace[1898770803] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"100.596255ms","start":"2026-08-05T21:39:10.007778Z","end":"2026-08-05T21:39:10.108374Z","steps":["trace[1898770803] 'process raft request' (duration: 100.191423ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:39:10.112945Z","caller":"traceutil/trace.go:171","msg":"trace[1939328746] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"104.106647ms","start":"2026-08-05T21:39:10.008818Z","end":"2026-08-05T21:39:10.112925Z","steps":["trace[1939328746] 'process raft request' (duration: 104.023415ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:39:10.113001Z","caller":"traceutil/trace.go:171","msg":"trace[1716223387] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"104.254791ms","start":"2026-08-05T21:39:10.008719Z","end":"2026-08-05T21:39:10.112974Z","steps":["trace[1716223387] 'process raft request' (duration: 104.010984ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:39:10.113282Z","caller":"traceutil/trace.go:171","msg":"trace[533019985] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"100.955465ms","start":"2026-08-05T21:39:10.012302Z","end":"2026-08-05T21:39:10.113258Z","steps":["trace[533019985] 'process raft request' (duration: 100.80975ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:39:10.113468Z","caller":"traceutil/trace.go:171","msg":"trace[586026526] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"104.496168ms","start":"2026-08-05T21:39:10.008932Z","end":"2026-08-05T21:39:10.113428Z","steps":["trace[586026526] 'process raft request' (duration: 103.946992ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:39:16.38809Z","caller":"traceutil/trace.go:171","msg":"trace[726898620] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"187.49864ms","start":"2026-08-05T21:39:16.200559Z","end":"2026-08-05T21:39:16.388058Z","steps":["trace[726898620] 'process raft request' (duration: 187.253833ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:39:16.397441Z","caller":"traceutil/trace.go:171","msg":"trace[1711217509] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"182.175504ms","start":"2026-08-05T21:39:16.215235Z","end":"2026-08-05T21:39:16.39741Z","steps":["trace[1711217509] 'process raft request' (duration: 181.584077ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:39:16.600926Z","caller":"traceutil/trace.go:171","msg":"trace[363357923] linearizableReadLoop","detail":"{readStateIndex:643; appliedIndex:642; }","duration":"112.596765ms","start":"2026-08-05T21:39:16.488304Z","end":"2026-08-05T21:39:16.600901Z","steps":["trace[363357923] 'read index received' (duration: 53.844421ms)","trace[363357923] 'applied index is now lower than readState.Index' (duration: 58.751114ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:39:16.601089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.754319ms","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-05T21:39:16.601159Z","caller":"traceutil/trace.go:171","msg":"trace[664091089] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:623; }","duration":"112.846322ms","start":"2026-08-05T21:39:16.4883Z","end":"2026-08-05T21:39:16.601146Z","steps":["trace[664091089] 'agreement among raft nodes before linearized reading' (duration: 112.693377ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:39:16.60144Z","caller":"traceutil/trace.go:171","msg":"trace[1047559291] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"144.151566ms","start":"2026-08-05T21:39:16.457275Z","end":"2026-08-05T21:39:16.601427Z","steps":["trace[1047559291] 'process raft request' (duration: 84.941429ms)","trace[1047559291] 'compare' (duration: 58.575268ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:39:17.081378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.735721ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-05T21:39:17.081857Z","caller":"traceutil/trace.go:171","msg":"trace[38265652] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"476.230052ms","start":"2026-08-05T21:39:16.605595Z","end":"2026-08-05T21:39:17.081825Z","steps":["trace[38265652] 'process raft request' (duration: 220.581855ms)","trace[38265652] 'compare' (duration: 254.627947ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:39:17.081971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:39:16.605572Z","time spent":"476.343555ms","remote":"127.0.0.1:47818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T21:39:17.083983Z","caller":"traceutil/trace.go:171","msg":"trace[354608138] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:643; }","duration":"209.727018ms","start":"2026-08-05T21:39:16.874242Z","end":"2026-08-05T21:39:17.083969Z","steps":["trace[354608138] 'read index received' (duration: 205.573168ms)","trace[354608138] 'applied index is now lower than readState.Index' (duration: 4.15318ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:39:17.084077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.840911ms","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-05T21:39:17.084072Z","caller":"traceutil/trace.go:171","msg":"trace[195310768] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"267.444161ms","start":"2026-08-05T21:39:16.8166Z","end":"2026-08-05T21:39:17.084044Z","steps":["trace[195310768] 'process raft request' (duration: 265.483774ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:39:17.084107Z","caller":"traceutil/trace.go:171","msg":"trace[154476535] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:625; }","duration":"209.915363ms","start":"2026-08-05T21:39:16.874184Z","end":"2026-08-05T21:39:17.084099Z","steps":["trace[154476535] 'agreement among raft nodes before linearized reading' (duration: 209.831611ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:40:03.645033Z","caller":"traceutil/trace.go:171","msg":"trace[1145420362] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"203.53928ms","start":"2026-08-05T21:40:03.44147Z","end":"2026-08-05T21:40:03.645009Z","steps":["trace[1145420362] 'process raft request' (duration: 203.389495ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:40:54.291066Z","caller":"traceutil/trace.go:171","msg":"trace[306368620] transaction","detail":"{read_only:false; response_revision:1932; number_of_response:1; }","duration":"159.950596ms","start":"2026-08-05T21:40:54.131058Z","end":"2026-08-05T21:40:54.291008Z","steps":["trace[306368620] 'process raft request' (duration: 117.869191ms)","trace[306368620] 'compare' (duration: 41.882589ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:40:56.976735Z","caller":"traceutil/trace.go:171","msg":"trace[618326346] transaction","detail":"{read_only:false; response_revision:1951; number_of_response:1; }","duration":"134.63674ms","start":"2026-08-05T21:40:56.842061Z","end":"2026-08-05T21:40:56.976698Z","steps":["trace[618326346] 'process raft request' (duration: 133.531908ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:41:30.431862Z","caller":"traceutil/trace.go:171","msg":"trace[924175407] linearizableReadLoop","detail":"{readStateIndex:2220; appliedIndex:2219; }","duration":"102.955017ms","start":"2026-08-05T21:41:30.328887Z","end":"2026-08-05T21:41:30.431842Z","steps":["trace[924175407] 'read index received' (duration: 102.755872ms)","trace[924175407] 'applied index is now lower than readState.Index' (duration: 198.375µs)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:41:30.432096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.198415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-08-05T21:41:30.432129Z","caller":"traceutil/trace.go:171","msg":"trace[1008598257] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2146; }","duration":"103.274276ms","start":"2026-08-05T21:41:30.328843Z","end":"2026-08-05T21:41:30.432118Z","steps":["trace[1008598257] 'agreement among raft nodes before linearized reading' (duration: 103.136932ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:41:30.432091Z","caller":"traceutil/trace.go:171","msg":"trace[1252968573] transaction","detail":"{read_only:false; response_revision:2146; number_of_response:1; }","duration":"125.411941ms","start":"2026-08-05T21:41:30.306628Z","end":"2026-08-05T21:41:30.43204Z","steps":["trace[1252968573] 'process raft request' (duration: 125.089052ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:41:30.785287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.58498ms","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-05T21:41:30.785403Z","caller":"traceutil/trace.go:171","msg":"trace[1728978231] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2148; }","duration":"233.760405ms","start":"2026-08-05T21:41:30.551606Z","end":"2026-08-05T21:41:30.785366Z","steps":["trace[1728978231] 'range keys from in-memory index tree' (duration: 233.394915ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:41:31.075331Z","caller":"traceutil/trace.go:171","msg":"trace[203224184] transaction","detail":"{read_only:false; response_revision:2149; number_of_response:1; }","duration":"112.112543ms","start":"2026-08-05T21:41:30.963165Z","end":"2026-08-05T21:41:31.075278Z","steps":["trace[203224184] 'process raft request' (duration: 111.917898ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:42:54.362683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.114723ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-05T21:42:54.362798Z","caller":"traceutil/trace.go:171","msg":"trace[2076447077] linearizableReadLoop","detail":"{readStateIndex:2743; appliedIndex:2742; }","duration":"123.204155ms","start":"2026-08-05T21:42:54.239578Z","end":"2026-08-05T21:42:54.362782Z","steps":["trace[2076447077] 'read index received' (duration: 12.801583ms)","trace[2076447077] 'applied index is now lower than readState.Index' (duration: 110.401182ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:42:54.36293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.363269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18c906c13c318ca6\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-08-05T21:42:54.362952Z","caller":"traceutil/trace.go:171","msg":"trace[766472559] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18c906c13c318ca6; range_end:; response_count:1; response_revision:2650; }","duration":"123.430682ms","start":"2026-08-05T21:42:54.239516Z","end":"2026-08-05T21:42:54.362946Z","steps":["trace[766472559] 'agreement among raft nodes before linearized reading' (duration: 123.303648ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:42:54.363145Z","caller":"traceutil/trace.go:171","msg":"trace[1698944754] transaction","detail":"{read_only:false; response_revision:2650; number_of_response:1; }","duration":"156.581756ms","start":"2026-08-05T21:42:54.206535Z","end":"2026-08-05T21:42:54.363116Z","steps":["trace[1698944754] 'process raft request' (duration: 45.897026ms)","trace[1698944754] 'compare' (duration: 110.00454ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:42:54.484795Z","caller":"traceutil/trace.go:171","msg":"trace[1100917275] transaction","detail":"{read_only:false; response_revision:2651; number_of_response:1; }","duration":"119.469296ms","start":"2026-08-05T21:42:54.365298Z","end":"2026-08-05T21:42:54.484768Z","steps":["trace[1100917275] 'process raft request' (duration: 101.010299ms)","trace[1100917275] 'compare' (duration: 18.344804ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:43:48.438078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.783018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T21:43:48.438198Z","caller":"traceutil/trace.go:171","msg":"trace[5428744] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2932; }","duration":"381.948012ms","start":"2026-08-05T21:43:48.056224Z","end":"2026-08-05T21:43:48.438172Z","steps":["trace[5428744] 'range keys from in-memory index tree' (duration: 381.575032ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:43:48.438265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:43:48.056206Z","time spent":"382.042986ms","remote":"127.0.0.1:48000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":27,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-08-05T21:43:48.766137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.642344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-08-05T21:43:48.766183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.058686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T21:43:48.766255Z","caller":"traceutil/trace.go:171","msg":"trace[1688840222] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:2933; }","duration":"106.19609ms","start":"2026-08-05T21:43:48.660044Z","end":"2026-08-05T21:43:48.76624Z","steps":["trace[1688840222] 'count revisions from in-memory index tree' (duration: 105.956552ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:43:48.766242Z","caller":"traceutil/trace.go:171","msg":"trace[811689887] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2933; }","duration":"139.817608ms","start":"2026-08-05T21:43:48.626401Z","end":"2026-08-05T21:43:48.766219Z","steps":["trace[811689887] 'range keys from in-memory index tree' (duration: 139.412015ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:43:48.766065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.6556ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T21:43:48.76649Z","caller":"traceutil/trace.go:171","msg":"trace[1116456507] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2933; }","duration":"165.084052ms","start":"2026-08-05T21:43:48.601357Z","end":"2026-08-05T21:43:48.766441Z","steps":["trace[1116456507] 'range keys from in-memory index tree' (duration: 164.63318ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:44:45.931735Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5164397130007116456,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-05T21:44:45.967288Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.283240048s","expected-duration":"1s"} {"level":"info","ts":"2026-08-05T21:44:45.96779Z","caller":"traceutil/trace.go:171","msg":"trace[481614120] linearizableReadLoop","detail":"{readStateIndex:3339; appliedIndex:3338; }","duration":"1.275945835s","start":"2026-08-05T21:44:44.691815Z","end":"2026-08-05T21:44:45.967761Z","steps":["trace[481614120] 'read index received' (duration: 1.275802261s)","trace[481614120] 'applied index is now lower than readState.Index' (duration: 141.964µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:44:45.967968Z","caller":"traceutil/trace.go:171","msg":"trace[46891533] transaction","detail":"{read_only:false; response_revision:3223; number_of_response:1; }","duration":"1.283975179s","start":"2026-08-05T21:44:44.683948Z","end":"2026-08-05T21:44:45.967923Z","steps":["trace[46891533] 'process raft request' (duration: 1.283628829s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:44:45.968069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.276210303s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-05T21:44:45.96813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:44:44.683925Z","time spent":"1.284125173s","remote":"127.0.0.1:47818","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-05T21:44:45.968135Z","caller":"traceutil/trace.go:171","msg":"trace[361285344] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3223; }","duration":"1.276348297s","start":"2026-08-05T21:44:44.691771Z","end":"2026-08-05T21:44:45.968119Z","steps":["trace[361285344] 'agreement among raft nodes before linearized reading' (duration: 1.276178122s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:44:45.968187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:44:44.69175Z","time spent":"1.2764261s","remote":"127.0.0.1:47510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-05T21:44:45.968836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.275296787s","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-05T21:44:45.968875Z","caller":"traceutil/trace.go:171","msg":"trace[1500904575] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3224; }","duration":"1.27537408s","start":"2026-08-05T21:44:44.693489Z","end":"2026-08-05T21:44:45.968863Z","steps":["trace[1500904575] 'agreement among raft nodes before linearized reading' (duration: 1.275295917s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:44:45.968901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:44:44.693469Z","time spent":"1.275426822s","remote":"127.0.0.1:47818","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":"info","ts":"2026-08-05T21:45:42.65368Z","caller":"traceutil/trace.go:171","msg":"trace[417903937] linearizableReadLoop","detail":"{readStateIndex:3855; appliedIndex:3854; }","duration":"170.817789ms","start":"2026-08-05T21:45:42.482828Z","end":"2026-08-05T21:45:42.653646Z","steps":["trace[417903937] 'read index received' (duration: 170.592783ms)","trace[417903937] 'applied index is now lower than readState.Index' (duration: 223.896µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:45:42.653729Z","caller":"traceutil/trace.go:171","msg":"trace[649187571] transaction","detail":"{read_only:false; response_revision:3721; number_of_response:1; }","duration":"237.686495ms","start":"2026-08-05T21:45:42.416011Z","end":"2026-08-05T21:45:42.653698Z","steps":["trace[649187571] 'process raft request' (duration: 237.495379ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:45:42.653947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.109198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-05T21:45:42.653988Z","caller":"traceutil/trace.go:171","msg":"trace[559161923] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3721; }","duration":"171.16507ms","start":"2026-08-05T21:45:42.482813Z","end":"2026-08-05T21:45:42.653978Z","steps":["trace[559161923] 'agreement among raft nodes before linearized reading' (duration: 170.997455ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:47:07.054197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.22076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-zgc27-tls\" ","response":"range_response_count:1 size:1967"} {"level":"info","ts":"2026-08-05T21:47:07.054297Z","caller":"traceutil/trace.go:171","msg":"trace[1201020459] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-zgc27-tls; range_end:; response_count:1; response_revision:4878; }","duration":"185.367834ms","start":"2026-08-05T21:47:06.868907Z","end":"2026-08-05T21:47:07.054275Z","steps":["trace[1201020459] 'range keys from in-memory index tree' (duration: 185.049145ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:47:20.996235Z","caller":"traceutil/trace.go:171","msg":"trace[266997020] transaction","detail":"{read_only:false; response_revision:5150; number_of_response:1; }","duration":"113.296732ms","start":"2026-08-05T21:47:20.882893Z","end":"2026-08-05T21:47:20.99619Z","steps":["trace[266997020] 'process raft request' (duration: 113.012674ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:47:20.996347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.235862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T21:47:20.996424Z","caller":"traceutil/trace.go:171","msg":"trace[2003226584] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5150; }","duration":"112.373216ms","start":"2026-08-05T21:47:20.884031Z","end":"2026-08-05T21:47:20.996404Z","steps":["trace[2003226584] 'agreement among raft nodes before linearized reading' (duration: 112.210961ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:47:20.99633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.708615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T21:47:20.9967Z","caller":"traceutil/trace.go:171","msg":"trace[571366487] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:5150; }","duration":"137.097287ms","start":"2026-08-05T21:47:20.859516Z","end":"2026-08-05T21:47:20.996613Z","steps":["trace[571366487] 'agreement among raft nodes before linearized reading' (duration: 136.604182ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:47:21.059595Z","caller":"traceutil/trace.go:171","msg":"trace[1783599263] transaction","detail":"{read_only:false; response_revision:5151; number_of_response:1; }","duration":"143.686019ms","start":"2026-08-05T21:47:20.915872Z","end":"2026-08-05T21:47:21.059558Z","steps":["trace[1783599263] 'process raft request' (duration: 143.38732ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:47:21.059714Z","caller":"traceutil/trace.go:171","msg":"trace[1075507216] transaction","detail":"{read_only:false; response_revision:5152; number_of_response:1; }","duration":"138.92663ms","start":"2026-08-05T21:47:20.920729Z","end":"2026-08-05T21:47:21.059656Z","steps":["trace[1075507216] 'process raft request' (duration: 138.740324ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:47:21.285349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.704529ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-08-05T21:47:21.285531Z","caller":"traceutil/trace.go:171","msg":"trace[2096218495] linearizableReadLoop","detail":"{readStateIndex:5344; appliedIndex:5343; }","duration":"122.470619ms","start":"2026-08-05T21:47:21.16303Z","end":"2026-08-05T21:47:21.2855Z","steps":["trace[2096218495] 'read index received' (duration: 45.621µs)","trace[2096218495] 'applied index is now lower than readState.Index' (duration: 122.422038ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:47:21.285842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.783089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-05T21:47:21.285901Z","caller":"traceutil/trace.go:171","msg":"trace[1596443629] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5156; }","duration":"122.903833ms","start":"2026-08-05T21:47:21.162981Z","end":"2026-08-05T21:47:21.285884Z","steps":["trace[1596443629] 'agreement among raft nodes before linearized reading' (duration: 122.595933ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:47:21.762952Z","caller":"traceutil/trace.go:171","msg":"trace[278702877] transaction","detail":"{read_only:false; response_revision:5158; number_of_response:1; }","duration":"270.183526ms","start":"2026-08-05T21:47:21.492723Z","end":"2026-08-05T21:47:21.762906Z","steps":["trace[278702877] 'process raft request' (duration: 269.97784ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:47:28.073107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.938991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-03998515-36d1-44f5-adb7-527dbf4b944b\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-08-05T21:47:28.073198Z","caller":"traceutil/trace.go:171","msg":"trace[2008989007] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-03998515-36d1-44f5-adb7-527dbf4b944b; range_end:; response_count:1; response_revision:5312; }","duration":"148.061664ms","start":"2026-08-05T21:47:27.925114Z","end":"2026-08-05T21:47:28.073176Z","steps":["trace[2008989007] 'range keys from in-memory index tree' (duration: 147.784647ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:48:32.412572Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2849} {"level":"info","ts":"2026-08-05T21:48:32.487243Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2849,"took":"73.352057ms","hash":2395360932,"current-db-size-bytes":57126912,"current-db-size":"57 MB","current-db-size-in-use-bytes":32890880,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-08-05T21:48:32.48736Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2395360932,"revision":2849,"compact-revision":-1} {"level":"info","ts":"2026-08-05T21:53:20.951835Z","caller":"traceutil/trace.go:171","msg":"trace[844931713] linearizableReadLoop","detail":"{readStateIndex:8339; appliedIndex:8338; }","duration":"259.883816ms","start":"2026-08-05T21:53:20.691932Z","end":"2026-08-05T21:53:20.951816Z","steps":["trace[844931713] 'read index received' (duration: 259.761673ms)","trace[844931713] 'applied index is now lower than readState.Index' (duration: 121.263µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:53:20.951915Z","caller":"traceutil/trace.go:171","msg":"trace[622052471] transaction","detail":"{read_only:false; response_revision:8061; number_of_response:1; }","duration":"269.635098ms","start":"2026-08-05T21:53:20.682234Z","end":"2026-08-05T21:53:20.951869Z","steps":["trace[622052471] 'process raft request' (duration: 269.450903ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:53:20.952067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.115303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T21:53:20.952228Z","caller":"traceutil/trace.go:171","msg":"trace[680427272] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8061; }","duration":"260.319169ms","start":"2026-08-05T21:53:20.691886Z","end":"2026-08-05T21:53:20.952205Z","steps":["trace[680427272] 'agreement among raft nodes before linearized reading' (duration: 260.057881ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:53:21.076618Z","caller":"traceutil/trace.go:171","msg":"trace[1268855934] transaction","detail":"{read_only:false; response_revision:8062; number_of_response:1; }","duration":"120.539255ms","start":"2026-08-05T21:53:20.956045Z","end":"2026-08-05T21:53:21.076584Z","steps":["trace[1268855934] 'process raft request' (duration: 118.688992ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:53:32.478318Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5906} {"level":"warn","ts":"2026-08-05T21:53:32.928647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.355941ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-05T21:53:32.928804Z","caller":"traceutil/trace.go:171","msg":"trace[1992044588] transaction","detail":"{read_only:false; response_revision:8122; number_of_response:1; }","duration":"370.711169ms","start":"2026-08-05T21:53:32.558071Z","end":"2026-08-05T21:53:32.928782Z","steps":["trace[1992044588] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/openstack/messaging-topology-operator-leader-election; req_size:553; } (duration: 368.105363ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:53:32.928868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:53:32.55806Z","time spent":"370.779071ms","remote":"127.0.0.1:47818","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-05T21:53:32.960036Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5906,"took":"479.995647ms","hash":2737531936,"current-db-size-bytes":57126912,"current-db-size":"57 MB","current-db-size-in-use-bytes":26664960,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-08-05T21:53:32.960108Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2737531936,"revision":5906,"compact-revision":2849} {"level":"info","ts":"2026-08-05T21:53:32.960373Z","caller":"traceutil/trace.go:171","msg":"trace[1187450736] transaction","detail":"{read_only:false; response_revision:8123; number_of_response:1; }","duration":"402.269795ms","start":"2026-08-05T21:53:32.558074Z","end":"2026-08-05T21:53:32.960344Z","steps":["trace[1187450736] 'process raft request' (duration: 370.690898ms)","trace[1187450736] 'compare' (duration: 10.638128ms)","trace[1187450736] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/plndr-svcs-lock; req_size:420; } (duration: 20.742221ms)"],"step_count":3} {"level":"warn","ts":"2026-08-05T21:53:32.960503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:53:32.558054Z","time spent":"402.384448ms","remote":"127.0.0.1:47818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T21:53:32.960668Z","caller":"traceutil/trace.go:171","msg":"trace[1540755127] transaction","detail":"{read_only:false; response_revision:8125; number_of_response:1; }","duration":"402.366987ms","start":"2026-08-05T21:53:32.558279Z","end":"2026-08-05T21:53:32.960646Z","steps":["trace[1540755127] 'process raft request' (duration: 402.235423ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:53:32.960662Z","caller":"traceutil/trace.go:171","msg":"trace[1931895852] transaction","detail":"{read_only:false; response_revision:8124; number_of_response:1; }","duration":"402.499071ms","start":"2026-08-05T21:53:32.55813Z","end":"2026-08-05T21:53:32.960629Z","steps":["trace[1931895852] 'process raft request' (duration: 402.156881ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:53:32.960749Z","caller":"traceutil/trace.go:171","msg":"trace[1374035795] transaction","detail":"{read_only:false; response_revision:8126; number_of_response:1; }","duration":"401.10532ms","start":"2026-08-05T21:53:32.559636Z","end":"2026-08-05T21:53:32.960741Z","steps":["trace[1374035795] 'process raft request' (duration: 400.973286ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:53:32.960798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:53:32.559624Z","time spent":"401.151012ms","remote":"127.0.0.1:47818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T21:53:32.960805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:53:32.558268Z","time spent":"402.45678ms","remote":"127.0.0.1:47818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T21:53:32.960819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:53:32.558123Z","time spent":"402.612685ms","remote":"127.0.0.1:47818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T21:54:15.077601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.639564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:5146"} {"level":"info","ts":"2026-08-05T21:54:15.077811Z","caller":"traceutil/trace.go:171","msg":"trace[1686689655] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:8441; }","duration":"131.889192ms","start":"2026-08-05T21:54:14.945896Z","end":"2026-08-05T21:54:15.077785Z","steps":["trace[1686689655] 'range keys from in-memory index tree' (duration: 131.452109ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:55:13.097065Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002479.wal"} {"level":"info","ts":"2026-08-05T21:55:22.36243Z","caller":"traceutil/trace.go:171","msg":"trace[47738204] transaction","detail":"{read_only:false; response_revision:9080; number_of_response:1; }","duration":"101.580237ms","start":"2026-08-05T21:55:22.26083Z","end":"2026-08-05T21:55:22.36241Z","steps":["trace[47738204] 'process raft request' (duration: 101.465484ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:55:22.416664Z","caller":"traceutil/trace.go:171","msg":"trace[944898205] transaction","detail":"{read_only:false; response_revision:9081; number_of_response:1; }","duration":"123.449659ms","start":"2026-08-05T21:55:22.293199Z","end":"2026-08-05T21:55:22.416648Z","steps":["trace[944898205] 'process raft request' (duration: 123.377737ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:56:32.61017Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"163ef968c92247ab","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-05T21:56:32.616813Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-05T21:56:32.616902Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-05T21:58:32.48709Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8120} {"level":"info","ts":"2026-08-05T21:58:32.562977Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8120,"took":"73.692645ms","hash":201569004,"current-db-size-bytes":57126912,"current-db-size":"57 MB","current-db-size-in-use-bytes":25325568,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-08-05T21:58:32.563085Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":201569004,"revision":8120,"compact-revision":5906} {"level":"info","ts":"2026-08-05T21:59:06.55091Z","caller":"traceutil/trace.go:171","msg":"trace[530007185] transaction","detail":"{read_only:false; response_revision:11026; number_of_response:1; }","duration":"239.233696ms","start":"2026-08-05T21:59:06.311647Z","end":"2026-08-05T21:59:06.550881Z","steps":["trace[530007185] 'process raft request' (duration: 239.185334ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:59:06.551278Z","caller":"traceutil/trace.go:171","msg":"trace[1768514637] transaction","detail":"{read_only:false; response_revision:11025; number_of_response:1; }","duration":"244.655561ms","start":"2026-08-05T21:59:06.306488Z","end":"2026-08-05T21:59:06.551144Z","steps":["trace[1768514637] 'process raft request' (duration: 214.625459ms)","trace[1768514637] 'compare' (duration: 29.584679ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T22:03:32.492731Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10652} {"level":"info","ts":"2026-08-05T22:03:32.58668Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10652,"took":"92.25262ms","hash":1121413109,"current-db-size-bytes":57126912,"current-db-size":"57 MB","current-db-size-in-use-bytes":27447296,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-08-05T22:03:32.586738Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1121413109,"revision":10652,"compact-revision":8120} {"level":"warn","ts":"2026-08-05T22:05:24.736902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.933571ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-05T22:05:24.737083Z","caller":"traceutil/trace.go:171","msg":"trace[820237898] transaction","detail":"{read_only:false; response_revision:13613; number_of_response:1; }","duration":"121.252319ms","start":"2026-08-05T22:05:24.615822Z","end":"2026-08-05T22:05:24.737074Z","steps":["trace[820237898] 'process raft request' (duration: 121.195387ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:05:24.737139Z","caller":"traceutil/trace.go:171","msg":"trace[1945782536] transaction","detail":"{read_only:false; response_revision:13611; number_of_response:1; }","duration":"143.596083ms","start":"2026-08-05T22:05:24.593532Z","end":"2026-08-05T22:05:24.737128Z","steps":["trace[1945782536] 'process raft request' (duration: 32.368404ms)","trace[1945782536] 'compare' (duration: 110.830598ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T22:05:24.737192Z","caller":"traceutil/trace.go:171","msg":"trace[1328812805] transaction","detail":"{read_only:false; response_revision:13612; number_of_response:1; }","duration":"131.36792ms","start":"2026-08-05T22:05:24.605711Z","end":"2026-08-05T22:05:24.737079Z","steps":["trace[1328812805] 'process raft request' (duration: 131.262387ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:07:01.450985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.029439ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-08-05T22:07:01.451092Z","caller":"traceutil/trace.go:171","msg":"trace[1298027772] linearizableReadLoop","detail":"{readStateIndex:15288; appliedIndex:15287; }","duration":"391.377414ms","start":"2026-08-05T22:07:01.059684Z","end":"2026-08-05T22:07:01.451062Z","steps":["trace[1298027772] 'read index received' (duration: 136.271893ms)","trace[1298027772] 'applied index is now lower than readState.Index' (duration: 255.104281ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T22:07:01.451357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.663392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-lzrgg\" ","response":"range_response_count:1 size:22125"} {"level":"info","ts":"2026-08-05T22:07:01.451487Z","caller":"traceutil/trace.go:171","msg":"trace[1017817448] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-lzrgg; range_end:; response_count:1; response_revision:14790; }","duration":"391.833647ms","start":"2026-08-05T22:07:01.05963Z","end":"2026-08-05T22:07:01.451464Z","steps":["trace[1017817448] 'agreement among raft nodes before linearized reading' (duration: 391.550909ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:07:01.451546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:07:01.059606Z","time spent":"391.92886ms","remote":"127.0.0.1:47732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22147,"request content":"key:\"/registry/pods/openstack/nova-compute-default-lzrgg\" "} {"level":"info","ts":"2026-08-05T22:07:01.558943Z","caller":"traceutil/trace.go:171","msg":"trace[791513352] transaction","detail":"{read_only:false; response_revision:14791; number_of_response:1; }","duration":"464.128113ms","start":"2026-08-05T22:07:01.094785Z","end":"2026-08-05T22:07:01.558913Z","steps":["trace[791513352] 'process raft request' (duration: 463.831643ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:07:01.559321Z","caller":"traceutil/trace.go:171","msg":"trace[714397366] linearizableReadLoop","detail":"{readStateIndex:15290; appliedIndex:15288; }","duration":"108.177592ms","start":"2026-08-05T22:07:01.451112Z","end":"2026-08-05T22:07:01.559289Z","steps":["trace[714397366] 'read index received' (duration: 107.498221ms)","trace[714397366] 'applied index is now lower than readState.Index' (duration: 676.251µs)"],"step_count":2} {"level":"warn","ts":"2026-08-05T22:07:01.559471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.336455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T22:07:01.559531Z","caller":"traceutil/trace.go:171","msg":"trace[1141285668] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:14792; }","duration":"179.463828ms","start":"2026-08-05T22:07:01.380053Z","end":"2026-08-05T22:07:01.559517Z","steps":["trace[1141285668] 'agreement among raft nodes before linearized reading' (duration: 179.333974ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:07:01.559651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.673037ms","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-05T22:07:01.559695Z","caller":"traceutil/trace.go:171","msg":"trace[2105134171] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14792; }","duration":"230.77921ms","start":"2026-08-05T22:07:01.328904Z","end":"2026-08-05T22:07:01.559683Z","steps":["trace[2105134171] 'agreement among raft nodes before linearized reading' (duration: 230.566214ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:07:01.559748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.695465ms","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-05T22:07:01.559828Z","caller":"traceutil/trace.go:171","msg":"trace[1678156364] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14792; }","duration":"179.834469ms","start":"2026-08-05T22:07:01.379972Z","end":"2026-08-05T22:07:01.559807Z","steps":["trace[1678156364] 'agreement among raft nodes before linearized reading' (duration: 179.672335ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:07:01.559788Z","caller":"traceutil/trace.go:171","msg":"trace[763049375] transaction","detail":"{read_only:false; response_revision:14792; number_of_response:1; }","duration":"459.959792ms","start":"2026-08-05T22:07:01.099734Z","end":"2026-08-05T22:07:01.559694Z","steps":["trace[763049375] 'process raft request' (duration: 459.111297ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:07:01.559837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.174712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-08-05T22:07:01.559929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.075057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-05T22:07:01.559958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.674138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"warn","ts":"2026-08-05T22:07:01.559915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.517969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-08-05T22:07:01.559952Z","caller":"traceutil/trace.go:171","msg":"trace[1490996300] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14792; }","duration":"230.344987ms","start":"2026-08-05T22:07:01.329577Z","end":"2026-08-05T22:07:01.559922Z","steps":["trace[1490996300] 'agreement among raft nodes before linearized reading' (duration: 229.75645ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:07:01.559992Z","caller":"traceutil/trace.go:171","msg":"trace[1567823618] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:14792; }","duration":"229.77192ms","start":"2026-08-05T22:07:01.330208Z","end":"2026-08-05T22:07:01.55998Z","steps":["trace[1567823618] 'agreement among raft nodes before linearized reading' (duration: 229.690558ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:07:01.559993Z","caller":"traceutil/trace.go:171","msg":"trace[587484083] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:14792; }","duration":"310.188861ms","start":"2026-08-05T22:07:01.249788Z","end":"2026-08-05T22:07:01.559977Z","steps":["trace[587484083] 'agreement among raft nodes before linearized reading' (duration: 310.059968ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:07:01.559987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.033254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T22:07:01.560019Z","caller":"traceutil/trace.go:171","msg":"trace[1973685740] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14792; }","duration":"381.666103ms","start":"2026-08-05T22:07:01.178331Z","end":"2026-08-05T22:07:01.559997Z","steps":["trace[1973685740] 'agreement among raft nodes before linearized reading' (duration: 381.390385ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:07:01.560035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:07:01.249764Z","time spent":"310.261443ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":29,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-08-05T22:07:01.560053Z","caller":"traceutil/trace.go:171","msg":"trace[903351399] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:14792; }","duration":"390.133328ms","start":"2026-08-05T22:07:01.169898Z","end":"2026-08-05T22:07:01.560031Z","steps":["trace[903351399] 'agreement among raft nodes before linearized reading' (duration: 390.058576ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:07:01.560057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:07:01.099713Z","time spent":"460.21784ms","remote":"127.0.0.1:47818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T22:07:01.560075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:07:01.178291Z","time spent":"381.774616ms","remote":"127.0.0.1:47796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5545,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"warn","ts":"2026-08-05T22:07:01.560094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:07:01.169815Z","time spent":"390.270231ms","remote":"127.0.0.1:47796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" "} {"level":"warn","ts":"2026-08-05T22:07:01.56006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.187453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-05T22:07:01.560194Z","caller":"traceutil/trace.go:171","msg":"trace[232907029] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14792; }","duration":"238.402949ms","start":"2026-08-05T22:07:01.321763Z","end":"2026-08-05T22:07:01.560166Z","steps":["trace[232907029] 'agreement among raft nodes before linearized reading' (duration: 237.980867ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:07:01.560228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.11178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-08-05T22:07:01.560094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.016724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T22:07:01.560264Z","caller":"traceutil/trace.go:171","msg":"trace[89093683] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14792; }","duration":"230.186502ms","start":"2026-08-05T22:07:01.330068Z","end":"2026-08-05T22:07:01.560254Z","steps":["trace[89093683] 'agreement among raft nodes before linearized reading' (duration: 230.080229ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:07:01.560273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.534326ms","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-05T22:07:01.559754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.930918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-lzrgg\" ","response":"range_response_count:1 size:22125"} {"level":"info","ts":"2026-08-05T22:07:01.5603Z","caller":"traceutil/trace.go:171","msg":"trace[455377445] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:14792; }","duration":"180.254131ms","start":"2026-08-05T22:07:01.380027Z","end":"2026-08-05T22:07:01.560281Z","steps":["trace[455377445] 'agreement among raft nodes before linearized reading' (duration: 180.037875ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:07:01.56031Z","caller":"traceutil/trace.go:171","msg":"trace[1688602172] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14792; }","duration":"410.617799ms","start":"2026-08-05T22:07:01.149681Z","end":"2026-08-05T22:07:01.560299Z","steps":["trace[1688602172] 'agreement among raft nodes before linearized reading' (duration: 410.475185ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:07:01.559862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.412711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-05T22:07:01.560343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:07:01.149651Z","time spent":"410.68501ms","remote":"127.0.0.1:47796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5545,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"info","ts":"2026-08-05T22:07:01.560366Z","caller":"traceutil/trace.go:171","msg":"trace[1616284429] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:14792; }","duration":"387.048658ms","start":"2026-08-05T22:07:01.173308Z","end":"2026-08-05T22:07:01.560357Z","steps":["trace[1616284429] 'agreement among raft nodes before linearized reading' (duration: 386.528403ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:07:01.560409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:07:01.173288Z","time spent":"387.1104ms","remote":"127.0.0.1:47796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/openstack/nova-ks-user\" "} {"level":"warn","ts":"2026-08-05T22:07:01.560478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.462512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-05T22:07:01.560494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.549181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-08-05T22:07:01.560519Z","caller":"traceutil/trace.go:171","msg":"trace[995419913] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-lzrgg; range_end:; response_count:1; response_revision:14792; }","duration":"498.561867ms","start":"2026-08-05T22:07:01.061753Z","end":"2026-08-05T22:07:01.560315Z","steps":["trace[995419913] 'agreement among raft nodes before linearized reading' (duration: 497.797175ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:07:01.56053Z","caller":"traceutil/trace.go:171","msg":"trace[1253645653] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14792; }","duration":"387.402649ms","start":"2026-08-05T22:07:01.173115Z","end":"2026-08-05T22:07:01.560518Z","steps":["trace[1253645653] 'agreement among raft nodes before linearized reading' (duration: 386.39066ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:07:01.560543Z","caller":"traceutil/trace.go:171","msg":"trace[2096895796] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14792; }","duration":"179.611843ms","start":"2026-08-05T22:07:01.38092Z","end":"2026-08-05T22:07:01.560531Z","steps":["trace[2096895796] 'agreement among raft nodes before linearized reading' (duration: 178.825681ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:07:01.560546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.962647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-08-05T22:07:01.560569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:07:01.173096Z","time spent":"387.46408ms","remote":"127.0.0.1:47796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "} {"level":"warn","ts":"2026-08-05T22:07:01.560558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:07:01.061722Z","time spent":"498.828954ms","remote":"127.0.0.1:47732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22147,"request content":"key:\"/registry/pods/openstack/nova-compute-default-lzrgg\" "} {"level":"info","ts":"2026-08-05T22:07:01.560607Z","caller":"traceutil/trace.go:171","msg":"trace[860626002] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:14792; }","duration":"377.04212ms","start":"2026-08-05T22:07:01.183545Z","end":"2026-08-05T22:07:01.560587Z","steps":["trace[860626002] 'agreement among raft nodes before linearized reading' (duration: 376.877895ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:07:01.560644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:07:01.183533Z","time spent":"377.103141ms","remote":"127.0.0.1:47818","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":"warn","ts":"2026-08-05T22:07:01.56499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.892487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:79 size:759642"} {"level":"info","ts":"2026-08-05T22:07:01.566057Z","caller":"traceutil/trace.go:171","msg":"trace[626881423] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:79; response_revision:14792; }","duration":"106.013139ms","start":"2026-08-05T22:07:01.460025Z","end":"2026-08-05T22:07:01.566038Z","steps":["trace[626881423] 'agreement among raft nodes before linearized reading' (duration: 99.622685ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:07:01.568166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:07:01.094764Z","time spent":"464.588406ms","remote":"127.0.0.1:47818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T22:08:32.499407Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12850} {"level":"info","ts":"2026-08-05T22:08:32.575021Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12850,"took":"73.200942ms","hash":3960674095,"current-db-size-bytes":57126912,"current-db-size":"57 MB","current-db-size-in-use-bytes":30752768,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-05T22:08:32.575077Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3960674095,"revision":12850,"compact-revision":10652} {"level":"info","ts":"2026-08-05T22:12:55.663558Z","caller":"traceutil/trace.go:171","msg":"trace[2005223678] linearizableReadLoop","detail":"{readStateIndex:18162; appliedIndex:18161; }","duration":"196.339577ms","start":"2026-08-05T22:12:55.46719Z","end":"2026-08-05T22:12:55.663529Z","steps":["trace[2005223678] 'read index received' (duration: 195.607256ms)","trace[2005223678] 'applied index is now lower than readState.Index' (duration: 731.191µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T22:12:55.663661Z","caller":"traceutil/trace.go:171","msg":"trace[276988251] transaction","detail":"{read_only:false; response_revision:17577; number_of_response:1; }","duration":"198.269253ms","start":"2026-08-05T22:12:55.465354Z","end":"2026-08-05T22:12:55.663624Z","steps":["trace[276988251] 'process raft request' (duration: 197.955634ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:12:55.663844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.625715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-08-05T22:12:55.663911Z","caller":"traceutil/trace.go:171","msg":"trace[1011411421] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:17577; }","duration":"196.725148ms","start":"2026-08-05T22:12:55.467171Z","end":"2026-08-05T22:12:55.663896Z","steps":["trace[1011411421] 'agreement among raft nodes before linearized reading' (duration: 196.525742ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:12:55.665426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.678197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-08-05T22:12:55.665478Z","caller":"traceutil/trace.go:171","msg":"trace[1619910801] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:17577; }","duration":"196.761839ms","start":"2026-08-05T22:12:55.468701Z","end":"2026-08-05T22:12:55.665463Z","steps":["trace[1619910801] 'agreement among raft nodes before linearized reading' (duration: 196.617335ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:12:55.665577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.555361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:574"} {"level":"warn","ts":"2026-08-05T22:12:55.665611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.430976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-05T22:12:55.665707Z","caller":"traceutil/trace.go:171","msg":"trace[1078845702] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:17577; }","duration":"190.741186ms","start":"2026-08-05T22:12:55.474935Z","end":"2026-08-05T22:12:55.665676Z","steps":["trace[1078845702] 'agreement among raft nodes before linearized reading' (duration: 190.401907ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:12:55.665723Z","caller":"traceutil/trace.go:171","msg":"trace[580435886] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:17577; }","duration":"191.57612ms","start":"2026-08-05T22:12:55.474118Z","end":"2026-08-05T22:12:55.665694Z","steps":["trace[580435886] 'agreement among raft nodes before linearized reading' (duration: 191.245641ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:12:55.665823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.221121ms","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-05T22:12:55.665862Z","caller":"traceutil/trace.go:171","msg":"trace[1203062568] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:17577; }","duration":"190.274123ms","start":"2026-08-05T22:12:55.475578Z","end":"2026-08-05T22:12:55.665852Z","steps":["trace[1203062568] 'agreement among raft nodes before linearized reading' (duration: 190.1729ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:12:55.861465Z","caller":"traceutil/trace.go:171","msg":"trace[1495929531] transaction","detail":"{read_only:false; response_revision:17578; number_of_response:1; }","duration":"191.495337ms","start":"2026-08-05T22:12:55.669943Z","end":"2026-08-05T22:12:55.861438Z","steps":["trace[1495929531] 'process raft request' (duration: 168.514806ms)","trace[1495929531] 'compare' (duration: 22.781466ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T22:12:55.868596Z","caller":"traceutil/trace.go:171","msg":"trace[126543918] linearizableReadLoop","detail":"{readStateIndex:18167; appliedIndex:18162; }","duration":"177.64299ms","start":"2026-08-05T22:12:55.690933Z","end":"2026-08-05T22:12:55.868576Z","steps":["trace[126543918] 'read index received' (duration: 147.538984ms)","trace[126543918] 'applied index is now lower than readState.Index' (duration: 30.102356ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T22:12:55.868704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.766414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T22:12:55.868726Z","caller":"traceutil/trace.go:171","msg":"trace[606997488] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17582; }","duration":"177.837766ms","start":"2026-08-05T22:12:55.690882Z","end":"2026-08-05T22:12:55.868719Z","steps":["trace[606997488] 'agreement among raft nodes before linearized reading' (duration: 177.768234ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:12:55.868814Z","caller":"traceutil/trace.go:171","msg":"trace[1059062315] transaction","detail":"{read_only:false; response_revision:17580; number_of_response:1; }","duration":"197.998445ms","start":"2026-08-05T22:12:55.670783Z","end":"2026-08-05T22:12:55.868782Z","steps":["trace[1059062315] 'process raft request' (duration: 197.702676ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:12:55.868899Z","caller":"traceutil/trace.go:171","msg":"trace[1133279662] transaction","detail":"{read_only:false; response_revision:17581; number_of_response:1; }","duration":"197.777429ms","start":"2026-08-05T22:12:55.671114Z","end":"2026-08-05T22:12:55.868891Z","steps":["trace[1133279662] 'process raft request' (duration: 197.400888ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:12:55.868963Z","caller":"traceutil/trace.go:171","msg":"trace[875528642] transaction","detail":"{read_only:false; response_revision:17582; number_of_response:1; }","duration":"196.930144ms","start":"2026-08-05T22:12:55.672027Z","end":"2026-08-05T22:12:55.868958Z","steps":["trace[875528642] 'process raft request' (duration: 196.516642ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:12:55.869007Z","caller":"traceutil/trace.go:171","msg":"trace[1056596271] transaction","detail":"{read_only:false; response_revision:17579; number_of_response:1; }","duration":"198.469739ms","start":"2026-08-05T22:12:55.670526Z","end":"2026-08-05T22:12:55.868996Z","steps":["trace[1056596271] 'process raft request' (duration: 197.869382ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:13:32.505611Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15393} {"level":"info","ts":"2026-08-05T22:13:32.60404Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15393,"took":"95.000465ms","hash":305448194,"current-db-size-bytes":57126912,"current-db-size":"57 MB","current-db-size-in-use-bytes":32919552,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-08-05T22:13:32.604146Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":305448194,"revision":15393,"compact-revision":12850} {"level":"info","ts":"2026-08-05T22:16:10.917832Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"163ef968c92247ab","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-05T22:16:10.923303Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-05T22:16:10.923419Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-05T22:18:32.511142Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17931} {"level":"info","ts":"2026-08-05T22:18:32.634448Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17931,"took":"120.683776ms","hash":1494959341,"current-db-size-bytes":57126912,"current-db-size":"57 MB","current-db-size-in-use-bytes":31592448,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-05T22:18:32.634516Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1494959341,"revision":17931,"compact-revision":15393} {"level":"info","ts":"2026-08-05T22:19:08.375533Z","caller":"traceutil/trace.go:171","msg":"trace[1750686809] transaction","detail":"{read_only:false; response_revision:20630; number_of_response:1; }","duration":"251.763671ms","start":"2026-08-05T22:19:08.123747Z","end":"2026-08-05T22:19:08.375511Z","steps":["trace[1750686809] 'process raft request' (duration: 244.800722ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:19:08.394409Z","caller":"traceutil/trace.go:171","msg":"trace[196352275] linearizableReadLoop","detail":"{readStateIndex:21314; appliedIndex:21312; }","duration":"254.816078ms","start":"2026-08-05T22:19:08.139567Z","end":"2026-08-05T22:19:08.394383Z","steps":["trace[196352275] 'read index received' (duration: 229.126821ms)","trace[196352275] 'applied index is now lower than readState.Index' (duration: 25.687937ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T22:19:08.394626Z","caller":"traceutil/trace.go:171","msg":"trace[347047801] transaction","detail":"{read_only:false; response_revision:20632; number_of_response:1; }","duration":"103.673588ms","start":"2026-08-05T22:19:08.290934Z","end":"2026-08-05T22:19:08.394608Z","steps":["trace[347047801] 'process raft request' (duration: 103.382909ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:19:08.394481Z","caller":"traceutil/trace.go:171","msg":"trace[1430143202] transaction","detail":"{read_only:false; response_revision:20631; number_of_response:1; }","duration":"262.576422ms","start":"2026-08-05T22:19:08.131856Z","end":"2026-08-05T22:19:08.394433Z","steps":["trace[1430143202] 'process raft request' (duration: 262.320924ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:19:08.394918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.332123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-08-05T22:19:08.394973Z","caller":"traceutil/trace.go:171","msg":"trace[1570779279] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:20632; }","duration":"255.426075ms","start":"2026-08-05T22:19:08.139531Z","end":"2026-08-05T22:19:08.394957Z","steps":["trace[1570779279] 'agreement among raft nodes before linearized reading' (duration: 255.260601ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:19:08.874883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.203905ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T22:19:08.874994Z","caller":"traceutil/trace.go:171","msg":"trace[2119369774] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:20634; }","duration":"274.351909ms","start":"2026-08-05T22:19:08.600625Z","end":"2026-08-05T22:19:08.874977Z","steps":["trace[2119369774] 'range keys from in-memory index tree' (duration: 274.185105ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:19:08.875656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.940633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T22:19:08.875779Z","caller":"traceutil/trace.go:171","msg":"trace[1838270694] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20634; }","duration":"184.139929ms","start":"2026-08-05T22:19:08.691612Z","end":"2026-08-05T22:19:08.875752Z","steps":["trace[1838270694] 'range keys from in-memory index tree' (duration: 183.764418ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:19:24.20917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.592851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-08-05T22:19:24.209157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.485028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:573"} {"level":"warn","ts":"2026-08-05T22:19:24.209197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.884385ms","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-05T22:19:24.209247Z","caller":"traceutil/trace.go:171","msg":"trace[1955251704] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20709; }","duration":"122.716195ms","start":"2026-08-05T22:19:24.086511Z","end":"2026-08-05T22:19:24.209228Z","steps":["trace[1955251704] 'range keys from in-memory index tree' (duration: 122.442267ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:19:24.20926Z","caller":"traceutil/trace.go:171","msg":"trace[1138726568] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:20709; }","duration":"122.635892ms","start":"2026-08-05T22:19:24.086605Z","end":"2026-08-05T22:19:24.209241Z","steps":["trace[1138726568] 'range keys from in-memory index tree' (duration: 122.349094ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:19:24.209309Z","caller":"traceutil/trace.go:171","msg":"trace[1618603040] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20709; }","duration":"118.0494ms","start":"2026-08-05T22:19:24.091228Z","end":"2026-08-05T22:19:24.209277Z","steps":["trace[1618603040] 'range keys from in-memory index tree' (duration: 117.69336ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:19:24.209304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.67842ms","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-05T22:19:24.20942Z","caller":"traceutil/trace.go:171","msg":"trace[79682058] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20709; }","duration":"124.840524ms","start":"2026-08-05T22:19:24.084548Z","end":"2026-08-05T22:19:24.209389Z","steps":["trace[79682058] 'range keys from in-memory index tree' (duration: 124.457693ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:19:24.326262Z","caller":"traceutil/trace.go:171","msg":"trace[1587910231] transaction","detail":"{read_only:false; response_revision:20713; number_of_response:1; }","duration":"112.067548ms","start":"2026-08-05T22:19:24.214175Z","end":"2026-08-05T22:19:24.326242Z","steps":["trace[1587910231] 'process raft request' (duration: 112.032447ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:19:24.326282Z","caller":"traceutil/trace.go:171","msg":"trace[2048547127] transaction","detail":"{read_only:false; response_revision:20711; number_of_response:1; }","duration":"112.46003ms","start":"2026-08-05T22:19:24.213794Z","end":"2026-08-05T22:19:24.326254Z","steps":["trace[2048547127] 'process raft request' (duration: 112.284705ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:19:24.326313Z","caller":"traceutil/trace.go:171","msg":"trace[257911115] transaction","detail":"{read_only:false; response_revision:20712; number_of_response:1; }","duration":"112.271715ms","start":"2026-08-05T22:19:24.21401Z","end":"2026-08-05T22:19:24.326282Z","steps":["trace[257911115] 'process raft request' (duration: 112.166362ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:19:46.059212Z","caller":"traceutil/trace.go:171","msg":"trace[614674097] transaction","detail":"{read_only:false; response_revision:20818; number_of_response:1; }","duration":"225.013592ms","start":"2026-08-05T22:19:45.834115Z","end":"2026-08-05T22:19:46.059129Z","steps":["trace[614674097] 'process raft request' (duration: 224.767865ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:19:46.261214Z","caller":"traceutil/trace.go:171","msg":"trace[713566342] linearizableReadLoop","detail":"{readStateIndex:21509; appliedIndex:21508; }","duration":"149.657848ms","start":"2026-08-05T22:19:46.11153Z","end":"2026-08-05T22:19:46.261188Z","steps":["trace[713566342] 'read index received' (duration: 88.042859ms)","trace[713566342] 'applied index is now lower than readState.Index' (duration: 61.613399ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T22:19:46.26142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.886694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-05T22:19:46.261461Z","caller":"traceutil/trace.go:171","msg":"trace[358691206] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20818; }","duration":"149.976917ms","start":"2026-08-05T22:19:46.111473Z","end":"2026-08-05T22:19:46.26145Z","steps":["trace[358691206] 'agreement among raft nodes before linearized reading' (duration: 149.817192ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:19:46.5681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.586124ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-05T22:19:46.568242Z","caller":"traceutil/trace.go:171","msg":"trace[1313503349] linearizableReadLoop","detail":"{readStateIndex:21511; appliedIndex:21510; }","duration":"274.096212ms","start":"2026-08-05T22:19:46.294121Z","end":"2026-08-05T22:19:46.568218Z","steps":["trace[1313503349] 'read index received' (duration: 130.262331ms)","trace[1313503349] 'applied index is now lower than readState.Index' (duration: 143.832571ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T22:19:46.568441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.311428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"} {"level":"info","ts":"2026-08-05T22:19:46.568495Z","caller":"traceutil/trace.go:171","msg":"trace[1535906058] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:20820; }","duration":"274.392931ms","start":"2026-08-05T22:19:46.294087Z","end":"2026-08-05T22:19:46.56848Z","steps":["trace[1535906058] 'agreement among raft nodes before linearized reading' (duration: 274.218556ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:19:46.568492Z","caller":"traceutil/trace.go:171","msg":"trace[1479606282] transaction","detail":"{read_only:false; response_revision:20820; number_of_response:1; }","duration":"301.54553ms","start":"2026-08-05T22:19:46.266916Z","end":"2026-08-05T22:19:46.568461Z","steps":["trace[1479606282] 'process raft request' (duration: 157.490502ms)","trace[1479606282] 'compare' (duration: 143.430439ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T22:19:46.568688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:19:46.266902Z","time spent":"301.713405ms","remote":"127.0.0.1:47818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T22:19:46.585345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.768378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-08-05T22:19:46.585391Z","caller":"traceutil/trace.go:171","msg":"trace[572077148] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20822; }","duration":"168.85324ms","start":"2026-08-05T22:19:46.416526Z","end":"2026-08-05T22:19:46.58538Z","steps":["trace[572077148] 'agreement among raft nodes before linearized reading' (duration: 168.667245ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:19:46.585401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.780012ms","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-05T22:19:46.585441Z","caller":"traceutil/trace.go:171","msg":"trace[1904945993] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20822; }","duration":"148.854394ms","start":"2026-08-05T22:19:46.436576Z","end":"2026-08-05T22:19:46.58543Z","steps":["trace[1904945993] 'agreement among raft nodes before linearized reading' (duration: 148.756522ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:19:46.585564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.984531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:574"} {"level":"warn","ts":"2026-08-05T22:19:46.58556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.658267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-08-05T22:19:46.585645Z","caller":"traceutil/trace.go:171","msg":"trace[1149217503] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20822; }","duration":"142.777081ms","start":"2026-08-05T22:19:46.442848Z","end":"2026-08-05T22:19:46.585625Z","steps":["trace[1149217503] 'agreement among raft nodes before linearized reading' (duration: 142.328698ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:19:46.585658Z","caller":"traceutil/trace.go:171","msg":"trace[867697377] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:20822; }","duration":"140.084664ms","start":"2026-08-05T22:19:46.445527Z","end":"2026-08-05T22:19:46.585612Z","steps":["trace[867697377] 'agreement among raft nodes before linearized reading' (duration: 139.850847ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:23:19.242379Z","caller":"traceutil/trace.go:171","msg":"trace[600936465] transaction","detail":"{read_only:false; response_revision:23168; number_of_response:1; }","duration":"125.232454ms","start":"2026-08-05T22:23:19.117115Z","end":"2026-08-05T22:23:19.242348Z","steps":["trace[600936465] 'process raft request' (duration: 124.690999ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:23:32.518564Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20419} {"level":"info","ts":"2026-08-05T22:23:32.627647Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20419,"took":"104.524711ms","hash":1781817616,"current-db-size-bytes":57126912,"current-db-size":"57 MB","current-db-size-in-use-bytes":42999808,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-08-05T22:23:32.627694Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1781817616,"revision":20419,"compact-revision":17931} {"level":"warn","ts":"2026-08-05T22:26:34.397411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.96955ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-05T22:26:34.397587Z","caller":"traceutil/trace.go:171","msg":"trace[1880544896] linearizableReadLoop","detail":"{readStateIndex:26047; appliedIndex:26045; }","duration":"320.303226ms","start":"2026-08-05T22:26:34.077271Z","end":"2026-08-05T22:26:34.397574Z","steps":["trace[1880544896] 'read index received' (duration: 200.161421ms)","trace[1880544896] 'applied index is now lower than readState.Index' (duration: 120.140935ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T22:26:34.397641Z","caller":"traceutil/trace.go:171","msg":"trace[684639311] transaction","detail":"{read_only:false; response_revision:25236; number_of_response:1; }","duration":"375.257216ms","start":"2026-08-05T22:26:34.022375Z","end":"2026-08-05T22:26:34.397632Z","steps":["trace[684639311] 'process raft request' (duration: 254.989469ms)","trace[684639311] 'compare' (duration: 119.013131ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T22:26:34.39768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:26:34.022349Z","time spent":"375.308587ms","remote":"127.0.0.1:47818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T22:26:34.397829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.564363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T22:26:34.397854Z","caller":"traceutil/trace.go:171","msg":"trace[1399346821] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:25237; }","duration":"320.669746ms","start":"2026-08-05T22:26:34.077177Z","end":"2026-08-05T22:26:34.397847Z","steps":["trace[1399346821] 'agreement among raft nodes before linearized reading' (duration: 320.612495ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:26:34.397876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:26:34.077149Z","time spent":"320.721178ms","remote":"127.0.0.1:47968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"info","ts":"2026-08-05T22:26:34.397823Z","caller":"traceutil/trace.go:171","msg":"trace[746547364] transaction","detail":"{read_only:false; response_revision:25237; number_of_response:1; }","duration":"374.656989ms","start":"2026-08-05T22:26:34.023036Z","end":"2026-08-05T22:26:34.397693Z","steps":["trace[746547364] 'process raft request' (duration: 374.471634ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:26:34.398137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:26:34.023019Z","time spent":"374.925886ms","remote":"127.0.0.1:47818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T22:26:34.488609Z","caller":"traceutil/trace.go:171","msg":"trace[1801530966] transaction","detail":"{read_only:false; response_revision:25238; number_of_response:1; }","duration":"357.269272ms","start":"2026-08-05T22:26:34.131315Z","end":"2026-08-05T22:26:34.488585Z","steps":["trace[1801530966] 'process raft request' (duration: 357.114767ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:26:34.488758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:26:34.131286Z","time spent":"357.388215ms","remote":"127.0.0.1:47818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T22:26:34.789612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.135822ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-05T22:26:34.789727Z","caller":"traceutil/trace.go:171","msg":"trace[257921044] linearizableReadLoop","detail":"{readStateIndex:26049; appliedIndex:26047; }","duration":"392.102027ms","start":"2026-08-05T22:26:34.397607Z","end":"2026-08-05T22:26:34.789709Z","steps":["trace[257921044] 'read index received' (duration: 90.780794ms)","trace[257921044] 'applied index is now lower than readState.Index' (duration: 301.319853ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T22:26:34.789774Z","caller":"traceutil/trace.go:171","msg":"trace[1447413522] transaction","detail":"{read_only:false; response_revision:25239; number_of_response:1; }","duration":"477.91031ms","start":"2026-08-05T22:26:34.311842Z","end":"2026-08-05T22:26:34.789752Z","steps":["trace[1447413522] 'process raft request' (duration: 278.548032ms)","trace[1447413522] 'compare' (duration: 199.018498ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T22:26:34.789845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.15347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-05T22:26:34.789872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:26:34.311816Z","time spent":"478.014273ms","remote":"127.0.0.1:47818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T22:26:34.789882Z","caller":"traceutil/trace.go:171","msg":"trace[1362491224] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:25239; }","duration":"546.232122ms","start":"2026-08-05T22:26:34.24364Z","end":"2026-08-05T22:26:34.789872Z","steps":["trace[1362491224] 'agreement among raft nodes before linearized reading' (duration: 546.164191ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:26:34.789917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:26:34.243619Z","time spent":"546.288975ms","remote":"127.0.0.1:45856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-08-05T22:26:34.790315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.391785ms","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-05T22:26:34.790462Z","caller":"traceutil/trace.go:171","msg":"trace[684723500] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25239; }","duration":"590.607201ms","start":"2026-08-05T22:26:34.199817Z","end":"2026-08-05T22:26:34.790424Z","steps":["trace[684723500] 'agreement among raft nodes before linearized reading' (duration: 590.245361ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:26:34.790528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:26:34.199787Z","time spent":"590.725225ms","remote":"127.0.0.1:47818","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-05T22:26:40.18449Z","caller":"traceutil/trace.go:171","msg":"trace[2020697954] transaction","detail":"{read_only:false; response_revision:25281; number_of_response:1; }","duration":"120.333089ms","start":"2026-08-05T22:26:40.06413Z","end":"2026-08-05T22:26:40.184463Z","steps":["trace[2020697954] 'process raft request' (duration: 119.931948ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:26:40.842943Z","caller":"traceutil/trace.go:171","msg":"trace[504046892] transaction","detail":"{read_only:false; response_revision:25282; number_of_response:1; }","duration":"427.143378ms","start":"2026-08-05T22:26:40.415773Z","end":"2026-08-05T22:26:40.842917Z","steps":["trace[504046892] 'process raft request' (duration: 426.976744ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:26:40.843114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:26:40.415754Z","time spent":"427.271291ms","remote":"127.0.0.1:47818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T22:26:40.857622Z","caller":"traceutil/trace.go:171","msg":"trace[958367094] transaction","detail":"{read_only:false; response_revision:25283; number_of_response:1; }","duration":"441.386646ms","start":"2026-08-05T22:26:40.4162Z","end":"2026-08-05T22:26:40.857587Z","steps":["trace[958367094] 'process raft request' (duration: 441.027706ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:26:40.857767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:26:40.41618Z","time spent":"441.51646ms","remote":"127.0.0.1:47818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T22:26:40.857904Z","caller":"traceutil/trace.go:171","msg":"trace[1926882816] transaction","detail":"{read_only:false; response_revision:25284; number_of_response:1; }","duration":"348.503312ms","start":"2026-08-05T22:26:40.509352Z","end":"2026-08-05T22:26:40.857855Z","steps":["trace[1926882816] 'process raft request' (duration: 348.0859ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:26:40.85819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:26:40.509328Z","time spent":"348.77879ms","remote":"127.0.0.1:47818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T22:26:40.997365Z","caller":"traceutil/trace.go:171","msg":"trace[212538239] transaction","detail":"{read_only:false; response_revision:25286; number_of_response:1; }","duration":"135.37865ms","start":"2026-08-05T22:26:40.861955Z","end":"2026-08-05T22:26:40.997333Z","steps":["trace[212538239] 'process raft request' (duration: 131.739315ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:26:41.209004Z","caller":"traceutil/trace.go:171","msg":"trace[1065667288] transaction","detail":"{read_only:false; response_revision:25287; number_of_response:1; }","duration":"287.817147ms","start":"2026-08-05T22:26:40.921142Z","end":"2026-08-05T22:26:41.208959Z","steps":["trace[1065667288] 'process raft request' (duration: 203.516337ms)","trace[1065667288] 'compare' (duration: 84.012892ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T22:26:41.209067Z","caller":"traceutil/trace.go:171","msg":"trace[1224519810] linearizableReadLoop","detail":"{readStateIndex:26098; appliedIndex:26097; }","duration":"211.756642ms","start":"2026-08-05T22:26:40.997278Z","end":"2026-08-05T22:26:41.209035Z","steps":["trace[1224519810] 'read index received' (duration: 127.391511ms)","trace[1224519810] 'applied index is now lower than readState.Index' (duration: 84.360671ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T22:26:41.209132Z","caller":"traceutil/trace.go:171","msg":"trace[741574199] transaction","detail":"{read_only:false; response_revision:25288; number_of_response:1; }","duration":"206.751299ms","start":"2026-08-05T22:26:41.002363Z","end":"2026-08-05T22:26:41.209114Z","steps":["trace[741574199] 'process raft request' (duration: 206.583225ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:26:41.20939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.496894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-08-05T22:26:41.209457Z","caller":"traceutil/trace.go:171","msg":"trace[1115239663] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25288; }","duration":"282.592248ms","start":"2026-08-05T22:26:40.926849Z","end":"2026-08-05T22:26:41.209442Z","steps":["trace[1115239663] 'agreement among raft nodes before linearized reading' (duration: 282.296559ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:26:41.209439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.0007ms","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":"warn","ts":"2026-08-05T22:26:41.209459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.8508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-05T22:26:41.209539Z","caller":"traceutil/trace.go:171","msg":"trace[1419945110] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25288; }","duration":"282.141583ms","start":"2026-08-05T22:26:40.927367Z","end":"2026-08-05T22:26:41.209509Z","steps":["trace[1419945110] 'agreement among raft nodes before linearized reading' (duration: 281.797674ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:26:41.209438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.121864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-08-05T22:26:41.209519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.207966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:574"} {"level":"info","ts":"2026-08-05T22:26:41.209622Z","caller":"traceutil/trace.go:171","msg":"trace[225476977] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:25288; }","duration":"282.335649ms","start":"2026-08-05T22:26:40.927263Z","end":"2026-08-05T22:26:41.209598Z","steps":["trace[225476977] 'agreement among raft nodes before linearized reading' (duration: 281.937608ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:26:41.209612Z","caller":"traceutil/trace.go:171","msg":"trace[1979086937] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25288; }","duration":"289.35554ms","start":"2026-08-05T22:26:40.920236Z","end":"2026-08-05T22:26:41.209591Z","steps":["trace[1979086937] 'agreement among raft nodes before linearized reading' (duration: 288.924538ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:26:41.209545Z","caller":"traceutil/trace.go:171","msg":"trace[1164977012] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25288; }","duration":"285.972234ms","start":"2026-08-05T22:26:40.923549Z","end":"2026-08-05T22:26:41.209521Z","steps":["trace[1164977012] 'agreement among raft nodes before linearized reading' (duration: 285.599793ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:26:41.209839Z","caller":"traceutil/trace.go:171","msg":"trace[1989208498] transaction","detail":"{read_only:false; response_revision:25289; number_of_response:1; }","duration":"117.513889ms","start":"2026-08-05T22:26:41.09231Z","end":"2026-08-05T22:26:41.209824Z","steps":["trace[1989208498] 'process raft request' (duration: 116.755298ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:26:41.209895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.036481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:674"} {"level":"info","ts":"2026-08-05T22:26:41.209944Z","caller":"traceutil/trace.go:171","msg":"trace[16136698] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25289; }","duration":"169.134294ms","start":"2026-08-05T22:26:41.040794Z","end":"2026-08-05T22:26:41.209929Z","steps":["trace[16136698] 'agreement among raft nodes before linearized reading' (duration: 169.001571ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:26:41.210102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.334091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:674"} {"level":"info","ts":"2026-08-05T22:26:41.210163Z","caller":"traceutil/trace.go:171","msg":"trace[1813738828] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25289; }","duration":"169.443903ms","start":"2026-08-05T22:26:41.040704Z","end":"2026-08-05T22:26:41.210148Z","steps":["trace[1813738828] 'agreement among raft nodes before linearized reading' (duration: 169.136924ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:26:41.210244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.220455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:674"} {"level":"info","ts":"2026-08-05T22:26:41.210312Z","caller":"traceutil/trace.go:171","msg":"trace[1316618107] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25289; }","duration":"171.312657ms","start":"2026-08-05T22:26:41.038968Z","end":"2026-08-05T22:26:41.210281Z","steps":["trace[1316618107] 'agreement among raft nodes before linearized reading' (duration: 170.819382ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:26:41.561967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.966815ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-05T22:26:41.562321Z","caller":"traceutil/trace.go:171","msg":"trace[1800402608] transaction","detail":"{read_only:false; response_revision:25291; number_of_response:1; }","duration":"348.336126ms","start":"2026-08-05T22:26:41.213951Z","end":"2026-08-05T22:26:41.562287Z","steps":["trace[1800402608] 'process raft request' (duration: 246.967549ms)","trace[1800402608] 'compare' (duration: 100.706918ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T22:26:41.562548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:26:41.213925Z","time spent":"348.529862ms","remote":"127.0.0.1:47818","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-05T22:26:41.562558Z","caller":"traceutil/trace.go:171","msg":"trace[738163694] transaction","detail":"{read_only:false; response_revision:25293; number_of_response:1; }","duration":"345.799824ms","start":"2026-08-05T22:26:41.21674Z","end":"2026-08-05T22:26:41.56254Z","steps":["trace[738163694] 'process raft request' (duration: 345.706461ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:26:41.562562Z","caller":"traceutil/trace.go:171","msg":"trace[1248994769] transaction","detail":"{read_only:false; response_revision:25292; number_of_response:1; }","duration":"347.629786ms","start":"2026-08-05T22:26:41.214908Z","end":"2026-08-05T22:26:41.562538Z","steps":["trace[1248994769] 'process raft request' (duration: 347.154812ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:26:41.562656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:26:41.216732Z","time spent":"345.884276ms","remote":"127.0.0.1:47818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T22:26:41.562694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:26:41.214892Z","time spent":"347.725319ms","remote":"127.0.0.1:47818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T22:26:41.562726Z","caller":"traceutil/trace.go:171","msg":"trace[63962716] transaction","detail":"{read_only:false; response_revision:25294; number_of_response:1; }","duration":"345.852114ms","start":"2026-08-05T22:26:41.216815Z","end":"2026-08-05T22:26:41.562667Z","steps":["trace[63962716] 'process raft request' (duration: 345.6812ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:26:41.562811Z","caller":"traceutil/trace.go:171","msg":"trace[1961694254] linearizableReadLoop","detail":"{readStateIndex:26102; appliedIndex:26100; }","duration":"348.781888ms","start":"2026-08-05T22:26:41.213989Z","end":"2026-08-05T22:26:41.56277Z","steps":["trace[1961694254] 'read index received' (duration: 33.757505ms)","trace[1961694254] 'applied index is now lower than readState.Index' (duration: 315.020073ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T22:26:41.562957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:26:41.216803Z","time spent":"346.045451ms","remote":"127.0.0.1:47818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T22:26:41.625911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.891212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-8159d8f5-f74e-4ef1-b70d-968a1e93f439\" ","response":"range_response_count:1 size:1323"} {"level":"info","ts":"2026-08-05T22:26:41.626003Z","caller":"traceutil/trace.go:171","msg":"trace[1901326125] range","detail":"{range_begin:/registry/persistentvolumes/pvc-8159d8f5-f74e-4ef1-b70d-968a1e93f439; range_end:; response_count:1; response_revision:25294; }","duration":"412.027316ms","start":"2026-08-05T22:26:41.213951Z","end":"2026-08-05T22:26:41.625978Z","steps":["trace[1901326125] 'agreement among raft nodes before linearized reading' (duration: 348.923652ms)","trace[1901326125] 'range keys from in-memory index tree' (duration: 62.843546ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T22:26:41.62605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:26:41.213938Z","time spent":"412.103069ms","remote":"127.0.0.1:47678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1345,"request content":"key:\"/registry/persistentvolumes/pvc-8159d8f5-f74e-4ef1-b70d-968a1e93f439\" "} {"level":"info","ts":"2026-08-05T22:26:42.326619Z","caller":"traceutil/trace.go:171","msg":"trace[1244630708] transaction","detail":"{read_only:false; response_revision:25295; number_of_response:1; }","duration":"133.601899ms","start":"2026-08-05T22:26:42.192992Z","end":"2026-08-05T22:26:42.326594Z","steps":["trace[1244630708] 'process raft request' (duration: 133.443034ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:26:42.984111Z","caller":"traceutil/trace.go:171","msg":"trace[1878660354] transaction","detail":"{read_only:false; response_revision:25296; number_of_response:1; }","duration":"134.511304ms","start":"2026-08-05T22:26:42.849569Z","end":"2026-08-05T22:26:42.98408Z","steps":["trace[1878660354] 'process raft request' (duration: 134.34844ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:26:43.19431Z","caller":"traceutil/trace.go:171","msg":"trace[1040645930] transaction","detail":"{read_only:false; response_revision:25297; number_of_response:1; }","duration":"331.964639ms","start":"2026-08-05T22:26:42.86231Z","end":"2026-08-05T22:26:43.194275Z","steps":["trace[1040645930] 'process raft request' (duration: 263.7954ms)","trace[1040645930] 'compare' (duration: 67.582023ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T22:26:43.194338Z","caller":"traceutil/trace.go:171","msg":"trace[1360475863] transaction","detail":"{read_only:false; response_revision:25298; number_of_response:1; }","duration":"332.00032ms","start":"2026-08-05T22:26:42.862319Z","end":"2026-08-05T22:26:43.194319Z","steps":["trace[1360475863] 'process raft request' (duration: 331.869556ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:26:43.194461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:26:42.862312Z","time spent":"332.079713ms","remote":"127.0.0.1:47818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T22:26:43.194459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:26:42.862292Z","time spent":"332.090152ms","remote":"127.0.0.1:47818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T22:26:43.194445Z","caller":"traceutil/trace.go:171","msg":"trace[855787812] linearizableReadLoop","detail":"{readStateIndex:26111; appliedIndex:26108; }","duration":"193.176262ms","start":"2026-08-05T22:26:43.00125Z","end":"2026-08-05T22:26:43.194426Z","steps":["trace[855787812] 'read index received' (duration: 124.852248ms)","trace[855787812] 'applied index is now lower than readState.Index' (duration: 68.323164ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T22:26:43.19459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.336216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-05T22:26:43.194627Z","caller":"traceutil/trace.go:171","msg":"trace[1331916530] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25299; }","duration":"193.416018ms","start":"2026-08-05T22:26:43.001203Z","end":"2026-08-05T22:26:43.194619Z","steps":["trace[1331916530] 'agreement among raft nodes before linearized reading' (duration: 193.280374ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:26:43.194697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.148017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/services/\" range_end:\"/registry/openstack.k-orc.cloud/services0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T22:26:43.194726Z","caller":"traceutil/trace.go:171","msg":"trace[2029579006] transaction","detail":"{read_only:false; response_revision:25299; number_of_response:1; }","duration":"331.415862ms","start":"2026-08-05T22:26:42.863305Z","end":"2026-08-05T22:26:43.19472Z","steps":["trace[2029579006] 'process raft request' (duration: 330.955109ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:26:43.194742Z","caller":"traceutil/trace.go:171","msg":"trace[1482870866] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/services/; range_end:/registry/openstack.k-orc.cloud/services0; response_count:0; response_revision:25299; }","duration":"160.221319ms","start":"2026-08-05T22:26:43.034508Z","end":"2026-08-05T22:26:43.194729Z","steps":["trace[1482870866] 'agreement among raft nodes before linearized reading' (duration: 160.131107ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:26:43.194792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:26:42.863287Z","time spent":"331.458224ms","remote":"127.0.0.1:47818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T22:26:43.1948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.889544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T22:26:43.194882Z","caller":"traceutil/trace.go:171","msg":"trace[402450565] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:25299; }","duration":"143.013308ms","start":"2026-08-05T22:26:43.051849Z","end":"2026-08-05T22:26:43.194862Z","steps":["trace[402450565] 'agreement among raft nodes before linearized reading' (duration: 142.876074ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:26:43.335838Z","caller":"traceutil/trace.go:171","msg":"trace[390617263] transaction","detail":"{read_only:false; response_revision:25301; number_of_response:1; }","duration":"120.194675ms","start":"2026-08-05T22:26:43.215623Z","end":"2026-08-05T22:26:43.335818Z","steps":["trace[390617263] 'process raft request' (duration: 120.155134ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:26:43.335894Z","caller":"traceutil/trace.go:171","msg":"trace[2116576499] transaction","detail":"{read_only:false; response_revision:25300; number_of_response:1; }","duration":"137.697526ms","start":"2026-08-05T22:26:43.198164Z","end":"2026-08-05T22:26:43.335861Z","steps":["trace[2116576499] 'process raft request' (duration: 124.407767ms)","trace[2116576499] 'compare' (duration: 13.080973ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T22:26:43.335927Z","caller":"traceutil/trace.go:171","msg":"trace[1609507750] linearizableReadLoop","detail":"{readStateIndex:26112; appliedIndex:26111; }","duration":"121.317897ms","start":"2026-08-05T22:26:43.21458Z","end":"2026-08-05T22:26:43.335898Z","steps":["trace[1609507750] 'read index received' (duration: 108.070269ms)","trace[1609507750] 'applied index is now lower than readState.Index' (duration: 13.243028ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T22:26:43.336166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.673799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-08-05T22:26:43.336167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.363839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:674"} {"level":"info","ts":"2026-08-05T22:26:43.336196Z","caller":"traceutil/trace.go:171","msg":"trace[1697452752] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25301; }","duration":"119.743442ms","start":"2026-08-05T22:26:43.216445Z","end":"2026-08-05T22:26:43.336188Z","steps":["trace[1697452752] 'agreement among raft nodes before linearized reading' (duration: 119.591508ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:26:43.336201Z","caller":"traceutil/trace.go:171","msg":"trace[1493212099] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25301; }","duration":"121.452151ms","start":"2026-08-05T22:26:43.21474Z","end":"2026-08-05T22:26:43.336192Z","steps":["trace[1493212099] 'agreement among raft nodes before linearized reading' (duration: 121.306127ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:26:43.336186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.566794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:674"} {"level":"info","ts":"2026-08-05T22:26:43.336254Z","caller":"traceutil/trace.go:171","msg":"trace[932645377] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25301; }","duration":"121.686747ms","start":"2026-08-05T22:26:43.214552Z","end":"2026-08-05T22:26:43.336239Z","steps":["trace[932645377] 'agreement among raft nodes before linearized reading' (duration: 121.485802ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:26:43.336426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.842693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:674"} {"level":"info","ts":"2026-08-05T22:26:43.336461Z","caller":"traceutil/trace.go:171","msg":"trace[110325225] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25301; }","duration":"121.912945ms","start":"2026-08-05T22:26:43.214539Z","end":"2026-08-05T22:26:43.336452Z","steps":["trace[110325225] 'agreement among raft nodes before linearized reading' (duration: 121.442351ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:26:43.749587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.241563ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-05T22:26:43.749866Z","caller":"traceutil/trace.go:171","msg":"trace[1357063128] linearizableReadLoop","detail":"{readStateIndex:26115; appliedIndex:26113; }","duration":"183.524746ms","start":"2026-08-05T22:26:43.566324Z","end":"2026-08-05T22:26:43.749849Z","steps":["trace[1357063128] 'read index received' (duration: 168.378603ms)","trace[1357063128] 'applied index is now lower than readState.Index' (duration: 15.144852ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T22:26:43.750035Z","caller":"traceutil/trace.go:171","msg":"trace[1045532331] transaction","detail":"{read_only:false; response_revision:25302; number_of_response:1; }","duration":"409.764231ms","start":"2026-08-05T22:26:43.340255Z","end":"2026-08-05T22:26:43.750019Z","steps":["trace[1045532331] 'process raft request' (duration: 124.020064ms)","trace[1045532331] 'compare' (duration: 285.12879ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T22:26:43.750052Z","caller":"traceutil/trace.go:171","msg":"trace[860755592] transaction","detail":"{read_only:false; response_revision:25303; number_of_response:1; }","duration":"409.389641ms","start":"2026-08-05T22:26:43.340633Z","end":"2026-08-05T22:26:43.750023Z","steps":["trace[860755592] 'process raft request' (duration: 409.144725ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:26:43.750097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:26:43.340236Z","time spent":"409.829534ms","remote":"127.0.0.1:47818","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-05T22:26:43.750134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.453203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-08-05T22:26:43.75015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.807653ms","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-05T22:26:43.750188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.530767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:574"} {"level":"warn","ts":"2026-08-05T22:26:43.750205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:26:43.340614Z","time spent":"409.497314ms","remote":"127.0.0.1:47818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T22:26:43.750234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.462923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-08-05T22:26:43.750262Z","caller":"traceutil/trace.go:171","msg":"trace[1961117191] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:25303; }","duration":"182.611749ms","start":"2026-08-05T22:26:43.567616Z","end":"2026-08-05T22:26:43.750228Z","steps":["trace[1961117191] 'agreement among raft nodes before linearized reading' (duration: 182.426194ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:26:43.750305Z","caller":"traceutil/trace.go:171","msg":"trace[989854210] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25303; }","duration":"183.597347ms","start":"2026-08-05T22:26:43.56669Z","end":"2026-08-05T22:26:43.750288Z","steps":["trace[989854210] 'agreement among raft nodes before linearized reading' (duration: 183.35511ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:26:43.75017Z","caller":"traceutil/trace.go:171","msg":"trace[746553895] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25303; }","duration":"183.515985ms","start":"2026-08-05T22:26:43.566643Z","end":"2026-08-05T22:26:43.750159Z","steps":["trace[746553895] 'agreement among raft nodes before linearized reading' (duration: 183.380471ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:26:43.750449Z","caller":"traceutil/trace.go:171","msg":"trace[1571576738] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25303; }","duration":"183.898416ms","start":"2026-08-05T22:26:43.566316Z","end":"2026-08-05T22:26:43.750214Z","steps":["trace[1571576738] 'agreement among raft nodes before linearized reading' (duration: 183.68834ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:26:44.013557Z","caller":"traceutil/trace.go:171","msg":"trace[1015824208] transaction","detail":"{read_only:false; response_revision:25305; number_of_response:1; }","duration":"258.852598ms","start":"2026-08-05T22:26:43.754684Z","end":"2026-08-05T22:26:44.013537Z","steps":["trace[1015824208] 'process raft request' (duration: 193.429339ms)","trace[1015824208] 'compare' (duration: 65.250674ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T22:26:44.014002Z","caller":"traceutil/trace.go:171","msg":"trace[299294048] transaction","detail":"{read_only:false; response_revision:25306; number_of_response:1; }","duration":"259.27539ms","start":"2026-08-05T22:26:43.7547Z","end":"2026-08-05T22:26:44.013975Z","steps":["trace[299294048] 'process raft request' (duration: 258.807876ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:26:44.014177Z","caller":"traceutil/trace.go:171","msg":"trace[140605577] linearizableReadLoop","detail":"{readStateIndex:26119; appliedIndex:26116; }","duration":"225.537765ms","start":"2026-08-05T22:26:43.788611Z","end":"2026-08-05T22:26:44.014149Z","steps":["trace[140605577] 'read index received' (duration: 159.419686ms)","trace[140605577] 'applied index is now lower than readState.Index' (duration: 66.116899ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T22:26:44.014354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.726342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-08-05T22:26:44.014389Z","caller":"traceutil/trace.go:171","msg":"trace[1037131503] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25307; }","duration":"225.806824ms","start":"2026-08-05T22:26:43.788573Z","end":"2026-08-05T22:26:44.01438Z","steps":["trace[1037131503] 'agreement among raft nodes before linearized reading' (duration: 225.634428ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:26:44.014512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.541901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T22:26:44.014507Z","caller":"traceutil/trace.go:171","msg":"trace[87631609] transaction","detail":"{read_only:false; response_revision:25307; number_of_response:1; }","duration":"259.645652ms","start":"2026-08-05T22:26:43.754789Z","end":"2026-08-05T22:26:44.014435Z","steps":["trace[87631609] 'process raft request' (duration: 259.086245ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:26:44.014568Z","caller":"traceutil/trace.go:171","msg":"trace[1888862004] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:25307; }","duration":"215.611783ms","start":"2026-08-05T22:26:43.79894Z","end":"2026-08-05T22:26:44.014552Z","steps":["trace[1888862004] 'agreement among raft nodes before linearized reading' (duration: 215.465649ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:26:55.338278Z","caller":"traceutil/trace.go:171","msg":"trace[1885961698] transaction","detail":"{read_only:false; response_revision:25384; number_of_response:1; }","duration":"303.758903ms","start":"2026-08-05T22:26:55.03449Z","end":"2026-08-05T22:26:55.338249Z","steps":["trace[1885961698] 'process raft request' (duration: 303.569748ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:26:55.338447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:26:55.034468Z","time spent":"303.886247ms","remote":"127.0.0.1:47818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T22:26:55.695952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.298313ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-05T22:26:55.696172Z","caller":"traceutil/trace.go:171","msg":"trace[349492772] transaction","detail":"{read_only:false; response_revision:25385; number_of_response:1; }","duration":"449.971632ms","start":"2026-08-05T22:26:55.246171Z","end":"2026-08-05T22:26:55.696143Z","steps":["trace[349492772] 'process raft request' (duration: 192.384639ms)","trace[349492772] 'compare' (duration: 257.022976ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T22:26:55.696233Z","caller":"traceutil/trace.go:171","msg":"trace[53123618] transaction","detail":"{read_only:false; response_revision:25388; number_of_response:1; }","duration":"310.731342ms","start":"2026-08-05T22:26:55.385485Z","end":"2026-08-05T22:26:55.696216Z","steps":["trace[53123618] 'process raft request' (duration: 310.690811ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:26:55.69623Z","caller":"traceutil/trace.go:171","msg":"trace[1871443951] transaction","detail":"{read_only:false; response_revision:25386; number_of_response:1; }","duration":"448.351415ms","start":"2026-08-05T22:26:55.247863Z","end":"2026-08-05T22:26:55.696214Z","steps":["trace[1871443951] 'process raft request' (duration: 448.1932ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:26:55.69631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:26:55.385469Z","time spent":"310.799853ms","remote":"127.0.0.1:47818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T22:26:55.696368Z","caller":"traceutil/trace.go:171","msg":"trace[619487271] linearizableReadLoop","detail":"{readStateIndex:26201; appliedIndex:26198; }","duration":"325.67191ms","start":"2026-08-05T22:26:55.370682Z","end":"2026-08-05T22:26:55.696354Z","steps":["trace[619487271] 'read index received' (duration: 67.888761ms)","trace[619487271] 'applied index is now lower than readState.Index' (duration: 257.781829ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T22:26:55.696394Z","caller":"traceutil/trace.go:171","msg":"trace[85685485] transaction","detail":"{read_only:false; response_revision:25387; number_of_response:1; }","duration":"446.366639ms","start":"2026-08-05T22:26:55.249983Z","end":"2026-08-05T22:26:55.69635Z","steps":["trace[85685485] 'process raft request' (duration: 446.149893ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:26:55.696576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:26:55.24997Z","time spent":"446.495923ms","remote":"127.0.0.1:47818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T22:26:55.696635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.845774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:674"} {"level":"warn","ts":"2026-08-05T22:26:55.69667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.950418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:674"} {"level":"info","ts":"2026-08-05T22:26:55.696691Z","caller":"traceutil/trace.go:171","msg":"trace[838422375] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25388; }","duration":"326.04566ms","start":"2026-08-05T22:26:55.370633Z","end":"2026-08-05T22:26:55.696679Z","steps":["trace[838422375] 'agreement among raft nodes before linearized reading' (duration: 325.795603ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:26:55.696722Z","caller":"traceutil/trace.go:171","msg":"trace[294346360] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25388; }","duration":"325.02023ms","start":"2026-08-05T22:26:55.371688Z","end":"2026-08-05T22:26:55.696708Z","steps":["trace[294346360] 'agreement among raft nodes before linearized reading' (duration: 324.783703ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:26:55.696746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:26:55.370611Z","time spent":"326.125162ms","remote":"127.0.0.1:47706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":696,"request content":"key:\"/registry/services/endpoints/openstack/octavia-api\" "} {"level":"warn","ts":"2026-08-05T22:26:55.696764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:26:55.371677Z","time spent":"325.078832ms","remote":"127.0.0.1:47706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":696,"request content":"key:\"/registry/services/endpoints/openstack/octavia-api\" "} {"level":"warn","ts":"2026-08-05T22:26:55.696884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.036251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-08-05T22:26:55.696902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.0258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:674"} {"level":"info","ts":"2026-08-05T22:26:55.696927Z","caller":"traceutil/trace.go:171","msg":"trace[1442336110] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25388; }","duration":"303.105383ms","start":"2026-08-05T22:26:55.39381Z","end":"2026-08-05T22:26:55.696916Z","steps":["trace[1442336110] 'agreement among raft nodes before linearized reading' (duration: 302.964949ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:26:55.696934Z","caller":"traceutil/trace.go:171","msg":"trace[1544489625] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25388; }","duration":"311.069611ms","start":"2026-08-05T22:26:55.385856Z","end":"2026-08-05T22:26:55.696925Z","steps":["trace[1544489625] 'agreement among raft nodes before linearized reading' (duration: 310.980618ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:26:55.696317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:26:55.247839Z","time spent":"448.441388ms","remote":"127.0.0.1:47818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T22:26:55.696961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:26:55.385851Z","time spent":"311.103253ms","remote":"127.0.0.1:47706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":696,"request content":"key:\"/registry/services/endpoints/openstack/octavia-api\" "} {"level":"warn","ts":"2026-08-05T22:26:55.696961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:26:55.393793Z","time spent":"303.158324ms","remote":"127.0.0.1:47818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-05T22:26:55.696318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:26:55.246142Z","time spent":"450.097425ms","remote":"127.0.0.1:47818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T22:26:56.297224Z","caller":"traceutil/trace.go:171","msg":"trace[2060212960] linearizableReadLoop","detail":"{readStateIndex:26203; appliedIndex:26202; }","duration":"486.696542ms","start":"2026-08-05T22:26:55.810509Z","end":"2026-08-05T22:26:56.297206Z","steps":["trace[2060212960] 'read index received' (duration: 486.659051ms)","trace[2060212960] 'applied index is now lower than readState.Index' (duration: 36.701µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T22:26:56.297373Z","caller":"traceutil/trace.go:171","msg":"trace[1403472935] transaction","detail":"{read_only:false; response_revision:25389; number_of_response:1; }","duration":"593.488333ms","start":"2026-08-05T22:26:55.703807Z","end":"2026-08-05T22:26:56.297295Z","steps":["trace[1403472935] 'process raft request' (duration: 593.238936ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:26:56.29762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:26:55.703789Z","time spent":"593.747411ms","remote":"127.0.0.1:47818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T22:26:56.298518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.3384ms","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-05T22:26:56.298572Z","caller":"traceutil/trace.go:171","msg":"trace[1469965831] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25389; }","duration":"488.078541ms","start":"2026-08-05T22:26:55.810477Z","end":"2026-08-05T22:26:56.298556Z","steps":["trace[1469965831] 'agreement among raft nodes before linearized reading' (duration: 486.857065ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:26:56.298601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:26:55.810464Z","time spent":"488.126003ms","remote":"127.0.0.1:47818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-05T22:26:56.305868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.234487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-05T22:26:56.305925Z","caller":"traceutil/trace.go:171","msg":"trace[1817563994] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25390; }","duration":"226.306388ms","start":"2026-08-05T22:26:56.079605Z","end":"2026-08-05T22:26:56.305912Z","steps":["trace[1817563994] 'agreement among raft nodes before linearized reading' (duration: 226.102413ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:26:56.305891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.057964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-08-05T22:26:56.306033Z","caller":"traceutil/trace.go:171","msg":"trace[513692576] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25390; }","duration":"485.257669ms","start":"2026-08-05T22:26:55.820755Z","end":"2026-08-05T22:26:56.306013Z","steps":["trace[513692576] 'agreement among raft nodes before linearized reading' (duration: 484.93254ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:26:56.306039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.058371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-05T22:26:56.306079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:26:55.820735Z","time spent":"485.335122ms","remote":"127.0.0.1:47818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-05T22:26:56.306101Z","caller":"traceutil/trace.go:171","msg":"trace[582205359] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:25390; }","duration":"304.126862ms","start":"2026-08-05T22:26:56.00196Z","end":"2026-08-05T22:26:56.306087Z","steps":["trace[582205359] 'agreement among raft nodes before linearized reading' (duration: 304.017489ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:26:56.306136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:26:56.001906Z","time spent":"304.218054ms","remote":"127.0.0.1:47648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":126,"response count":0,"response size":28,"request content":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true "} {"level":"warn","ts":"2026-08-05T22:26:56.306121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.129535ms","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-05T22:26:56.306175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.502424ms","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-05T22:26:56.306198Z","caller":"traceutil/trace.go:171","msg":"trace[823154291] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25390; }","duration":"465.236998ms","start":"2026-08-05T22:26:55.84093Z","end":"2026-08-05T22:26:56.306167Z","steps":["trace[823154291] 'agreement among raft nodes before linearized reading' (duration: 465.055243ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:26:56.306237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:26:55.840917Z","time spent":"465.313689ms","remote":"127.0.0.1:47818","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-05T22:26:56.306208Z","caller":"traceutil/trace.go:171","msg":"trace[490595746] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25390; }","duration":"226.568526ms","start":"2026-08-05T22:26:56.07963Z","end":"2026-08-05T22:26:56.306199Z","steps":["trace[490595746] 'agreement among raft nodes before linearized reading' (duration: 226.471273ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:26:56.306279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.663517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs/\" range_end:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T22:26:56.30632Z","caller":"traceutil/trace.go:171","msg":"trace[1489515234] range","detail":"{range_begin:/registry/runtime.cluster.x-k8s.io/extensionconfigs/; range_end:/registry/runtime.cluster.x-k8s.io/extensionconfigs0; response_count:0; response_revision:25390; }","duration":"397.756479ms","start":"2026-08-05T22:26:55.908555Z","end":"2026-08-05T22:26:56.306312Z","steps":["trace[1489515234] 'agreement among raft nodes before linearized reading' (duration: 397.693797ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:26:56.306362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:26:55.90853Z","time spent":"397.822331ms","remote":"127.0.0.1:47724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs/\" range_end:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs0\" count_only:true "} {"level":"warn","ts":"2026-08-05T22:26:56.30648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.216892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:573"} {"level":"warn","ts":"2026-08-05T22:26:56.30652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.995556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.156\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-08-05T22:26:56.30656Z","caller":"traceutil/trace.go:171","msg":"trace[1937663205] range","detail":"{range_begin:/registry/masterleases/199.19.213.156; range_end:; response_count:1; response_revision:25390; }","duration":"228.118169ms","start":"2026-08-05T22:26:56.078431Z","end":"2026-08-05T22:26:56.30655Z","steps":["trace[1937663205] 'agreement among raft nodes before linearized reading' (duration: 228.020617ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:26:56.307493Z","caller":"traceutil/trace.go:171","msg":"trace[997140870] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:25390; }","duration":"228.337385ms","start":"2026-08-05T22:26:56.078208Z","end":"2026-08-05T22:26:56.306546Z","steps":["trace[997140870] 'agreement among raft nodes before linearized reading' (duration: 228.055937ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:26:56.308748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.692022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/projects/\" range_end:\"/registry/openstack.k-orc.cloud/projects0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T22:26:56.308829Z","caller":"traceutil/trace.go:171","msg":"trace[182357109] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/projects/; range_end:/registry/openstack.k-orc.cloud/projects0; response_count:0; response_revision:25390; }","duration":"182.820435ms","start":"2026-08-05T22:26:56.125992Z","end":"2026-08-05T22:26:56.308812Z","steps":["trace[182357109] 'agreement among raft nodes before linearized reading' (duration: 182.527437ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:26:56.313777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.920526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-08-05T22:26:56.313851Z","caller":"traceutil/trace.go:171","msg":"trace[513232503] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:25390; }","duration":"488.035639ms","start":"2026-08-05T22:26:55.8258Z","end":"2026-08-05T22:26:56.313836Z","steps":["trace[513232503] 'agreement among raft nodes before linearized reading' (duration: 480.526214ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:26:56.313885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:26:55.825784Z","time spent":"488.094211ms","remote":"127.0.0.1:48082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":2771,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-08-05T22:26:56.617286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.279323ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-05T22:26:56.61762Z","caller":"traceutil/trace.go:171","msg":"trace[708217250] transaction","detail":"{read_only:false; response_revision:25391; number_of_response:1; }","duration":"314.229291ms","start":"2026-08-05T22:26:56.303375Z","end":"2026-08-05T22:26:56.617604Z","steps":["trace[708217250] 'process raft request' (duration: 155.757442ms)","trace[708217250] 'compare' (duration: 157.970285ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T22:26:56.617735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:26:56.30336Z","time spent":"314.328685ms","remote":"127.0.0.1:47818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T22:26:57.1863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.581552ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-08-05T22:26:57.187058Z","caller":"traceutil/trace.go:171","msg":"trace[1495650616] transaction","detail":"{read_only:false; response_revision:25392; number_of_response:1; }","duration":"876.747209ms","start":"2026-08-05T22:26:56.310279Z","end":"2026-08-05T22:26:57.187026Z","steps":["trace[1495650616] 'process raft request' (duration: 876.121762ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:26:57.187139Z","caller":"traceutil/trace.go:171","msg":"trace[717552360] transaction","detail":"{read_only:false; response_revision:25393; number_of_response:1; }","duration":"876.476881ms","start":"2026-08-05T22:26:56.310624Z","end":"2026-08-05T22:26:57.187101Z","steps":["trace[717552360] 'process raft request' (duration: 875.929876ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:26:57.187281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:26:56.31026Z","time spent":"876.929305ms","remote":"127.0.0.1:47818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T22:26:57.186632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:26:56.307664Z","time spent":"878.960733ms","remote":"127.0.0.1:47556","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-08-05T22:26:57.187447Z","caller":"traceutil/trace.go:171","msg":"trace[1028072066] transaction","detail":"{read_only:false; response_revision:25394; number_of_response:1; }","duration":"875.239337ms","start":"2026-08-05T22:26:56.312087Z","end":"2026-08-05T22:26:57.187327Z","steps":["trace[1028072066] 'process raft request' (duration: 874.515006ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:26:57.187555Z","caller":"traceutil/trace.go:171","msg":"trace[1853641001] linearizableReadLoop","detail":"{readStateIndex:26211; appliedIndex:26204; }","duration":"823.044375ms","start":"2026-08-05T22:26:56.364495Z","end":"2026-08-05T22:26:57.187539Z","steps":["trace[1853641001] 'read index received' (duration: 94.655806ms)","trace[1853641001] 'applied index is now lower than readState.Index' (duration: 728.387339ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T22:26:57.187555Z","caller":"traceutil/trace.go:171","msg":"trace[137698808] transaction","detail":"{read_only:false; response_revision:25395; number_of_response:1; }","duration":"872.963992ms","start":"2026-08-05T22:26:56.314548Z","end":"2026-08-05T22:26:57.187512Z","steps":["trace[137698808] 'process raft request' (duration: 872.097957ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:26:57.187594Z","caller":"traceutil/trace.go:171","msg":"trace[461526628] transaction","detail":"{read_only:false; response_revision:25396; number_of_response:1; }","duration":"871.55407ms","start":"2026-08-05T22:26:56.316009Z","end":"2026-08-05T22:26:57.187563Z","steps":["trace[461526628] 'process raft request' (duration: 871.402156ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:26:57.187648Z","caller":"traceutil/trace.go:171","msg":"trace[439513562] transaction","detail":"{read_only:false; response_revision:25397; number_of_response:1; }","duration":"775.582618ms","start":"2026-08-05T22:26:56.41203Z","end":"2026-08-05T22:26:57.187613Z","steps":["trace[439513562] 'process raft request' (duration: 775.467654ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:26:57.18769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"823.188268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T22:26:57.187732Z","caller":"traceutil/trace.go:171","msg":"trace[2016205688] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:25397; }","duration":"823.311032ms","start":"2026-08-05T22:26:56.36441Z","end":"2026-08-05T22:26:57.187721Z","steps":["trace[2016205688] 'agreement among raft nodes before linearized reading' (duration: 823.226919ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:26:57.187753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:26:56.411996Z","time spent":"775.708141ms","remote":"127.0.0.1:47818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T22:26:57.187745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:26:56.314518Z","time spent":"873.124506ms","remote":"127.0.0.1:47818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T22:26:57.18777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.286806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-05T22:26:57.187768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:26:56.364374Z","time spent":"823.382994ms","remote":"127.0.0.1:38052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true "} {"level":"warn","ts":"2026-08-05T22:26:57.187567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:26:56.310589Z","time spent":"876.626666ms","remote":"127.0.0.1:47818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T22:26:57.187769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.402699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T22:26:57.187847Z","caller":"traceutil/trace.go:171","msg":"trace[1193128908] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25397; }","duration":"495.361779ms","start":"2026-08-05T22:26:56.692472Z","end":"2026-08-05T22:26:57.187834Z","steps":["trace[1193128908] 'agreement among raft nodes before linearized reading' (duration: 495.209975ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:26:57.187861Z","caller":"traceutil/trace.go:171","msg":"trace[1223819519] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:0; response_revision:25397; }","duration":"312.542753ms","start":"2026-08-05T22:26:56.875298Z","end":"2026-08-05T22:26:57.187841Z","steps":["trace[1223819519] 'agreement among raft nodes before linearized reading' (duration: 312.404019ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:26:57.187838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:26:56.315995Z","time spent":"871.703995ms","remote":"127.0.0.1:47818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T22:26:57.187908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:26:56.875276Z","time spent":"312.620416ms","remote":"127.0.0.1:49418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-08-05T22:26:57.187931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:26:56.692413Z","time spent":"495.504022ms","remote":"127.0.0.1:47520","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-05T22:26:57.188105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.336798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs/\" range_end:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T22:26:57.188164Z","caller":"traceutil/trace.go:171","msg":"trace[1008134747] range","detail":"{range_begin:/registry/runtime.cluster.x-k8s.io/extensionconfigs/; range_end:/registry/runtime.cluster.x-k8s.io/extensionconfigs0; response_count:0; response_revision:25397; }","duration":"452.435571ms","start":"2026-08-05T22:26:56.735714Z","end":"2026-08-05T22:26:57.18815Z","steps":["trace[1008134747] 'agreement among raft nodes before linearized reading' (duration: 452.312607ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:26:57.188313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:26:56.735692Z","time spent":"452.577395ms","remote":"127.0.0.1:47718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs/\" range_end:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs0\" count_only:true "} {"level":"warn","ts":"2026-08-05T22:26:57.188357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:26:56.312077Z","time spent":"875.494134ms","remote":"127.0.0.1:47818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T22:26:57.70619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.491346ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-05T22:26:57.706373Z","caller":"traceutil/trace.go:171","msg":"trace[170916519] transaction","detail":"{read_only:false; response_revision:25399; number_of_response:1; }","duration":"362.708137ms","start":"2026-08-05T22:26:57.343646Z","end":"2026-08-05T22:26:57.706354Z","steps":["trace[170916519] 'process raft request' (duration: 143.969075ms)","trace[170916519] 'compare' (duration: 218.126104ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T22:26:57.706434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:26:57.343623Z","time spent":"362.780569ms","remote":"127.0.0.1:47818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T22:26:57.757788Z","caller":"traceutil/trace.go:171","msg":"trace[1294402536] linearizableReadLoop","detail":"{readStateIndex:26215; appliedIndex:26214; }","duration":"253.740602ms","start":"2026-08-05T22:26:57.504022Z","end":"2026-08-05T22:26:57.757762Z","steps":["trace[1294402536] 'read index received' (duration: 253.635809ms)","trace[1294402536] 'applied index is now lower than readState.Index' (duration: 103.593µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T22:26:57.758035Z","caller":"traceutil/trace.go:171","msg":"trace[1721545455] transaction","detail":"{read_only:false; response_revision:25400; number_of_response:1; }","duration":"313.052908ms","start":"2026-08-05T22:26:57.444929Z","end":"2026-08-05T22:26:57.757982Z","steps":["trace[1721545455] 'process raft request' (duration: 312.671176ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:26:57.75827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:26:57.444911Z","time spent":"313.201143ms","remote":"127.0.0.1:47818","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-05T22:26:57.758334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.290098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-05T22:26:57.758462Z","caller":"traceutil/trace.go:171","msg":"trace[1582196809] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:25400; }","duration":"254.464433ms","start":"2026-08-05T22:26:57.503981Z","end":"2026-08-05T22:26:57.758445Z","steps":["trace[1582196809] 'agreement among raft nodes before linearized reading' (duration: 254.00351ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:27:01.330025Z","caller":"traceutil/trace.go:171","msg":"trace[1736999586] transaction","detail":"{read_only:false; response_revision:25481; number_of_response:1; }","duration":"126.885058ms","start":"2026-08-05T22:27:01.203115Z","end":"2026-08-05T22:27:01.33Z","steps":["trace[1736999586] 'process raft request' (duration: 126.706623ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:27:01.330058Z","caller":"traceutil/trace.go:171","msg":"trace[382649073] linearizableReadLoop","detail":"{readStateIndex:26296; appliedIndex:26296; }","duration":"126.344061ms","start":"2026-08-05T22:27:01.203678Z","end":"2026-08-05T22:27:01.330022Z","steps":["trace[382649073] 'read index received' (duration: 126.327821ms)","trace[382649073] 'applied index is now lower than readState.Index' (duration: 13.35µs)"],"step_count":2} {"level":"warn","ts":"2026-08-05T22:27:01.330373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.66754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-05T22:27:01.330407Z","caller":"traceutil/trace.go:171","msg":"trace[2096367482] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25481; }","duration":"126.727482ms","start":"2026-08-05T22:27:01.203669Z","end":"2026-08-05T22:27:01.330396Z","steps":["trace[2096367482] 'agreement among raft nodes before linearized reading' (duration: 126.495885ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:27:01.561246Z","caller":"traceutil/trace.go:171","msg":"trace[1888405948] transaction","detail":"{read_only:false; response_revision:25482; number_of_response:1; }","duration":"353.333399ms","start":"2026-08-05T22:27:01.207885Z","end":"2026-08-05T22:27:01.561218Z","steps":["trace[1888405948] 'process raft request' (duration: 260.111384ms)","trace[1888405948] 'compare' (duration: 92.922796ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T22:27:01.561408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:27:01.207871Z","time spent":"353.444721ms","remote":"127.0.0.1:47818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T22:27:01.561453Z","caller":"traceutil/trace.go:171","msg":"trace[1408618544] transaction","detail":"{read_only:false; response_revision:25483; number_of_response:1; }","duration":"247.559915ms","start":"2026-08-05T22:27:01.313864Z","end":"2026-08-05T22:27:01.561424Z","steps":["trace[1408618544] 'process raft request' (duration: 247.265197ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:27:01.564269Z","caller":"traceutil/trace.go:171","msg":"trace[2120820093] transaction","detail":"{read_only:false; response_revision:25484; number_of_response:1; }","duration":"229.453268ms","start":"2026-08-05T22:27:01.334756Z","end":"2026-08-05T22:27:01.564209Z","steps":["trace[2120820093] 'process raft request' (duration: 229.322694ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:27:01.56449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.599788ms","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-05T22:27:01.564549Z","caller":"traceutil/trace.go:171","msg":"trace[1332876856] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25484; }","duration":"359.69036ms","start":"2026-08-05T22:27:01.204847Z","end":"2026-08-05T22:27:01.564537Z","steps":["trace[1332876856] 'agreement among raft nodes before linearized reading' (duration: 359.536326ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:27:01.564287Z","caller":"traceutil/trace.go:171","msg":"trace[1550703879] linearizableReadLoop","detail":"{readStateIndex:26299; appliedIndex:26296; }","duration":"234.124452ms","start":"2026-08-05T22:27:01.330136Z","end":"2026-08-05T22:27:01.56426Z","steps":["trace[1550703879] 'read index received' (duration: 137.875041ms)","trace[1550703879] 'applied index is now lower than readState.Index' (duration: 96.2325ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T22:27:01.564635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.705029ms","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-05T22:27:01.564734Z","caller":"traceutil/trace.go:171","msg":"trace[1972276561] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25484; }","duration":"360.913855ms","start":"2026-08-05T22:27:01.203792Z","end":"2026-08-05T22:27:01.564706Z","steps":["trace[1972276561] 'agreement among raft nodes before linearized reading' (duration: 360.536915ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:27:01.564813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:27:01.203771Z","time spent":"361.028578ms","remote":"127.0.0.1:47818","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-05T22:27:01.564898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.011318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:573"} {"level":"warn","ts":"2026-08-05T22:27:01.564885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.720231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-01a96048-e0b1-4b39-aa56-33af1800ca8c\" ","response":"range_response_count:1 size:3934"} {"level":"info","ts":"2026-08-05T22:27:01.564934Z","caller":"traceutil/trace.go:171","msg":"trace[2059942037] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:25484; }","duration":"361.049218ms","start":"2026-08-05T22:27:01.203872Z","end":"2026-08-05T22:27:01.564921Z","steps":["trace[2059942037] 'agreement among raft nodes before linearized reading' (duration: 360.980166ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:27:01.564948Z","caller":"traceutil/trace.go:171","msg":"trace[1816705166] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-01a96048-e0b1-4b39-aa56-33af1800ca8c; range_end:; response_count:1; response_revision:25484; }","duration":"105.848005ms","start":"2026-08-05T22:27:01.459081Z","end":"2026-08-05T22:27:01.564929Z","steps":["trace[1816705166] 'agreement among raft nodes before linearized reading' (duration: 105.635869ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:27:01.56498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:27:01.203865Z","time spent":"361.105612ms","remote":"127.0.0.1:47818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":595,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-08-05T22:27:01.564762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:27:01.204835Z","time spent":"359.737461ms","remote":"127.0.0.1:47818","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":"warn","ts":"2026-08-05T22:27:01.564824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.012403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-05T22:27:01.565261Z","caller":"traceutil/trace.go:171","msg":"trace[1176756601] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:25484; }","duration":"287.452467ms","start":"2026-08-05T22:27:01.2778Z","end":"2026-08-05T22:27:01.565253Z","steps":["trace[1176756601] 'agreement among raft nodes before linearized reading' (duration: 286.982682ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:27:04.809538Z","caller":"traceutil/trace.go:171","msg":"trace[1802601428] linearizableReadLoop","detail":"{readStateIndex:26342; appliedIndex:26341; }","duration":"117.57456ms","start":"2026-08-05T22:27:04.691938Z","end":"2026-08-05T22:27:04.809512Z","steps":["trace[1802601428] 'read index received' (duration: 117.317633ms)","trace[1802601428] 'applied index is now lower than readState.Index' (duration: 255.047µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T22:27:04.809844Z","caller":"traceutil/trace.go:171","msg":"trace[1066880494] transaction","detail":"{read_only:false; response_revision:25525; number_of_response:1; }","duration":"146.683333ms","start":"2026-08-05T22:27:04.663129Z","end":"2026-08-05T22:27:04.809813Z","steps":["trace[1066880494] 'process raft request' (duration: 146.204469ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:27:04.809872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.910711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T22:27:04.810025Z","caller":"traceutil/trace.go:171","msg":"trace[1284813775] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25525; }","duration":"118.131816ms","start":"2026-08-05T22:27:04.69188Z","end":"2026-08-05T22:27:04.810012Z","steps":["trace[1284813775] 'agreement among raft nodes before linearized reading' (duration: 117.720105ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:27:36.204064Z","caller":"traceutil/trace.go:171","msg":"trace[861032202] linearizableReadLoop","detail":"{readStateIndex:26605; appliedIndex:26601; }","duration":"121.802601ms","start":"2026-08-05T22:27:36.082229Z","end":"2026-08-05T22:27:36.204031Z","steps":["trace[861032202] 'read index received' (duration: 94.756738ms)","trace[861032202] 'applied index is now lower than readState.Index' (duration: 27.044743ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T22:27:36.204243Z","caller":"traceutil/trace.go:171","msg":"trace[30910045] transaction","detail":"{read_only:false; response_revision:25779; number_of_response:1; }","duration":"319.773701ms","start":"2026-08-05T22:27:35.884448Z","end":"2026-08-05T22:27:36.204221Z","steps":["trace[30910045] 'process raft request' (duration: 292.573243ms)","trace[30910045] 'compare' (duration: 26.563609ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T22:27:36.204361Z","caller":"traceutil/trace.go:171","msg":"trace[686176790] transaction","detail":"{read_only:false; response_revision:25782; number_of_response:1; }","duration":"318.204575ms","start":"2026-08-05T22:27:35.886066Z","end":"2026-08-05T22:27:36.20427Z","steps":["trace[686176790] 'process raft request' (duration: 317.912006ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:27:36.204387Z","caller":"traceutil/trace.go:171","msg":"trace[1078302406] transaction","detail":"{read_only:false; response_revision:25780; number_of_response:1; }","duration":"319.806682ms","start":"2026-08-05T22:27:35.884482Z","end":"2026-08-05T22:27:36.204288Z","steps":["trace[1078302406] 'process raft request' (duration: 319.318417ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:27:36.204484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:27:35.886058Z","time spent":"318.354889ms","remote":"127.0.0.1:47818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T22:27:36.204471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:27:35.884422Z","time spent":"319.861733ms","remote":"127.0.0.1:47818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T22:27:36.204459Z","caller":"traceutil/trace.go:171","msg":"trace[712293705] transaction","detail":"{read_only:false; response_revision:25781; number_of_response:1; }","duration":"319.42565ms","start":"2026-08-05T22:27:35.884978Z","end":"2026-08-05T22:27:36.204404Z","steps":["trace[712293705] 'process raft request' (duration: 318.940215ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:27:36.204531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:27:35.884457Z","time spent":"320.001167ms","remote":"127.0.0.1:47818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T22:27:36.204606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.425456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"warn","ts":"2026-08-05T22:27:36.204644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.407629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.156\" ","response":"range_response_count:1 size:140"} {"level":"warn","ts":"2026-08-05T22:27:36.204643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:27:35.88497Z","time spent":"319.583054ms","remote":"127.0.0.1:47818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T22:27:36.204684Z","caller":"traceutil/trace.go:171","msg":"trace[804645759] range","detail":"{range_begin:/registry/masterleases/199.19.213.156; range_end:; response_count:1; response_revision:25782; }","duration":"122.499602ms","start":"2026-08-05T22:27:36.082172Z","end":"2026-08-05T22:27:36.204672Z","steps":["trace[804645759] 'agreement among raft nodes before linearized reading' (duration: 122.367608ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:27:36.20469Z","caller":"traceutil/trace.go:171","msg":"trace[113314865] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0; range_end:; response_count:1; response_revision:25782; }","duration":"118.519059ms","start":"2026-08-05T22:27:36.086149Z","end":"2026-08-05T22:27:36.204668Z","steps":["trace[113314865] 'agreement among raft nodes before linearized reading' (duration: 118.301763ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:27:36.485486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.718421ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-08-05T22:27:36.485575Z","caller":"traceutil/trace.go:171","msg":"trace[787335506] linearizableReadLoop","detail":"{readStateIndex:26606; appliedIndex:26605; }","duration":"277.850462ms","start":"2026-08-05T22:27:36.207703Z","end":"2026-08-05T22:27:36.485553Z","steps":["trace[787335506] 'read index received' (duration: 136.032168ms)","trace[787335506] 'applied index is now lower than readState.Index' (duration: 141.817224ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T22:27:36.486074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.346096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-ada498a1-fc1e-4f72-9c6a-4959e8269004\" ","response":"range_response_count:1 size:1324"} {"level":"info","ts":"2026-08-05T22:27:36.486152Z","caller":"traceutil/trace.go:171","msg":"trace[1807345848] range","detail":"{range_begin:/registry/persistentvolumes/pvc-ada498a1-fc1e-4f72-9c6a-4959e8269004; range_end:; response_count:1; response_revision:25782; }","duration":"278.449318ms","start":"2026-08-05T22:27:36.207682Z","end":"2026-08-05T22:27:36.486131Z","steps":["trace[1807345848] 'agreement among raft nodes before linearized reading' (duration: 278.222232ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:27:37.923817Z","caller":"traceutil/trace.go:171","msg":"trace[1898440765] transaction","detail":"{read_only:false; response_revision:25788; number_of_response:1; }","duration":"161.31358ms","start":"2026-08-05T22:27:37.762484Z","end":"2026-08-05T22:27:37.923798Z","steps":["trace[1898440765] 'process raft request' (duration: 161.210987ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:27:37.929914Z","caller":"traceutil/trace.go:171","msg":"trace[890195193] transaction","detail":"{read_only:false; response_revision:25789; number_of_response:1; }","duration":"165.815871ms","start":"2026-08-05T22:27:37.764067Z","end":"2026-08-05T22:27:37.929883Z","steps":["trace[890195193] 'process raft request' (duration: 165.587613ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:27:37.92996Z","caller":"traceutil/trace.go:171","msg":"trace[485782742] transaction","detail":"{read_only:false; response_revision:25790; number_of_response:1; }","duration":"165.341685ms","start":"2026-08-05T22:27:37.764608Z","end":"2026-08-05T22:27:37.929949Z","steps":["trace[485782742] 'process raft request' (duration: 165.165559ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:27:37.930333Z","caller":"traceutil/trace.go:171","msg":"trace[664373640] transaction","detail":"{read_only:false; response_revision:25791; number_of_response:1; }","duration":"164.640736ms","start":"2026-08-05T22:27:37.765674Z","end":"2026-08-05T22:27:37.930315Z","steps":["trace[664373640] 'process raft request' (duration: 164.144252ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:27:37.930473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.682746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-08-05T22:27:37.930504Z","caller":"traceutil/trace.go:171","msg":"trace[1918025665] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25791; }","duration":"165.747047ms","start":"2026-08-05T22:27:37.764749Z","end":"2026-08-05T22:27:37.930496Z","steps":["trace[1918025665] 'agreement among raft nodes before linearized reading' (duration: 165.628483ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:27:37.930219Z","caller":"traceutil/trace.go:171","msg":"trace[1261345583] linearizableReadLoop","detail":"{readStateIndex:26614; appliedIndex:26612; }","duration":"165.410288ms","start":"2026-08-05T22:27:37.764794Z","end":"2026-08-05T22:27:37.930204Z","steps":["trace[1261345583] 'read index received' (duration: 159.030215ms)","trace[1261345583] 'applied index is now lower than readState.Index' (duration: 6.378863ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T22:27:37.931011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.359479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-08-05T22:27:37.931084Z","caller":"traceutil/trace.go:171","msg":"trace[534398101] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:25791; }","duration":"135.500743ms","start":"2026-08-05T22:27:37.795564Z","end":"2026-08-05T22:27:37.931065Z","steps":["trace[534398101] 'agreement among raft nodes before linearized reading' (duration: 135.194344ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:27:38.397387Z","caller":"traceutil/trace.go:171","msg":"trace[599065682] transaction","detail":"{read_only:false; response_revision:25799; number_of_response:1; }","duration":"118.199928ms","start":"2026-08-05T22:27:38.279166Z","end":"2026-08-05T22:27:38.397366Z","steps":["trace[599065682] 'process raft request' (duration: 117.18997ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:27:39.208078Z","caller":"traceutil/trace.go:171","msg":"trace[1902637435] transaction","detail":"{read_only:false; response_revision:25803; number_of_response:1; }","duration":"142.141013ms","start":"2026-08-05T22:27:39.06591Z","end":"2026-08-05T22:27:39.208051Z","steps":["trace[1902637435] 'process raft request' (duration: 141.951087ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:27:40.98711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.352182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T22:27:40.987204Z","caller":"traceutil/trace.go:171","msg":"trace[563508884] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25815; }","duration":"295.502576ms","start":"2026-08-05T22:27:40.691681Z","end":"2026-08-05T22:27:40.987183Z","steps":["trace[563508884] 'range keys from in-memory index tree' (duration: 295.27523ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:27:40.987368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.003105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-05T22:27:40.987478Z","caller":"traceutil/trace.go:171","msg":"trace[1883964962] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:25815; }","duration":"293.18672ms","start":"2026-08-05T22:27:40.694272Z","end":"2026-08-05T22:27:40.987458Z","steps":["trace[1883964962] 'count revisions from in-memory index tree' (duration: 292.910612ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:27:41.570388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.680639ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-05T22:27:41.570497Z","caller":"traceutil/trace.go:171","msg":"trace[160511632] linearizableReadLoop","detail":"{readStateIndex:26641; appliedIndex:26640; }","duration":"250.114039ms","start":"2026-08-05T22:27:41.320366Z","end":"2026-08-05T22:27:41.57048Z","steps":["trace[160511632] 'read index received' (duration: 73.150281ms)","trace[160511632] 'applied index is now lower than readState.Index' (duration: 176.962208ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T22:27:41.570599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.236902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/roles/\" range_end:\"/registry/openstack.k-orc.cloud/roles0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T22:27:41.57064Z","caller":"traceutil/trace.go:171","msg":"trace[691505479] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/roles/; range_end:/registry/openstack.k-orc.cloud/roles0; response_count:0; response_revision:25817; }","duration":"250.323974ms","start":"2026-08-05T22:27:41.320299Z","end":"2026-08-05T22:27:41.570623Z","steps":["trace[691505479] 'agreement among raft nodes before linearized reading' (duration: 250.229021ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:27:41.570768Z","caller":"traceutil/trace.go:171","msg":"trace[636915202] transaction","detail":"{read_only:false; response_revision:25817; number_of_response:1; }","duration":"299.779379ms","start":"2026-08-05T22:27:41.270936Z","end":"2026-08-05T22:27:41.570715Z","steps":["trace[636915202] 'process raft request' (duration: 122.670947ms)","trace[636915202] 'compare' (duration: 176.511775ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T22:27:41.668816Z","caller":"traceutil/trace.go:171","msg":"trace[1093896169] transaction","detail":"{read_only:false; response_revision:25818; number_of_response:1; }","duration":"192.860983ms","start":"2026-08-05T22:27:41.475936Z","end":"2026-08-05T22:27:41.668797Z","steps":["trace[1093896169] 'process raft request' (duration: 192.74767ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:27:41.971921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.509037ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-05T22:27:41.972798Z","caller":"traceutil/trace.go:171","msg":"trace[428231276] linearizableReadLoop","detail":"{readStateIndex:26644; appliedIndex:26642; }","duration":"148.3382ms","start":"2026-08-05T22:27:41.824442Z","end":"2026-08-05T22:27:41.972781Z","steps":["trace[428231276] 'read index received' (duration: 79.252µs)","trace[428231276] 'applied index is now lower than readState.Index' (duration: 148.257958ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T22:27:41.972807Z","caller":"traceutil/trace.go:171","msg":"trace[2090947088] transaction","detail":"{read_only:false; response_revision:25819; number_of_response:1; }","duration":"359.138135ms","start":"2026-08-05T22:27:41.613641Z","end":"2026-08-05T22:27:41.972779Z","steps":["trace[2090947088] 'process raft request' (duration: 358.353992ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:27:41.972952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:27:41.613614Z","time spent":"359.25594ms","remote":"127.0.0.1:47818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T22:27:41.979299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.841385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T22:27:41.97936Z","caller":"traceutil/trace.go:171","msg":"trace[49801070] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:25819; }","duration":"154.914117ms","start":"2026-08-05T22:27:41.824431Z","end":"2026-08-05T22:27:41.979345Z","steps":["trace[49801070] 'agreement among raft nodes before linearized reading' (duration: 148.423532ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:27:42.138031Z","caller":"traceutil/trace.go:171","msg":"trace[529939567] transaction","detail":"{read_only:false; response_revision:25822; number_of_response:1; }","duration":"153.994882ms","start":"2026-08-05T22:27:41.98402Z","end":"2026-08-05T22:27:42.138015Z","steps":["trace[529939567] 'process raft request' (duration: 153.768195ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:27:42.138171Z","caller":"traceutil/trace.go:171","msg":"trace[1863647999] transaction","detail":"{read_only:false; response_revision:25823; number_of_response:1; }","duration":"154.076514ms","start":"2026-08-05T22:27:41.984086Z","end":"2026-08-05T22:27:42.138163Z","steps":["trace[1863647999] 'process raft request' (duration: 153.891359ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:27:42.138455Z","caller":"traceutil/trace.go:171","msg":"trace[816446640] transaction","detail":"{read_only:false; response_revision:25824; number_of_response:1; }","duration":"153.93606ms","start":"2026-08-05T22:27:41.984497Z","end":"2026-08-05T22:27:42.138433Z","steps":["trace[816446640] 'process raft request' (duration: 153.634281ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:27:42.138518Z","caller":"traceutil/trace.go:171","msg":"trace[1509408967] linearizableReadLoop","detail":"{readStateIndex:26650; appliedIndex:26645; }","duration":"143.845461ms","start":"2026-08-05T22:27:41.994666Z","end":"2026-08-05T22:27:42.138511Z","steps":["trace[1509408967] 'read index received' (duration: 7.637037ms)","trace[1509408967] 'applied index is now lower than readState.Index' (duration: 136.207814ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T22:27:42.138573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.896843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T22:27:42.138597Z","caller":"traceutil/trace.go:171","msg":"trace[164206675] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:25825; }","duration":"143.957884ms","start":"2026-08-05T22:27:41.994632Z","end":"2026-08-05T22:27:42.13859Z","steps":["trace[164206675] 'agreement among raft nodes before linearized reading' (duration: 143.901802ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:27:42.138761Z","caller":"traceutil/trace.go:171","msg":"trace[1148609852] transaction","detail":"{read_only:false; response_revision:25825; number_of_response:1; }","duration":"154.119475ms","start":"2026-08-05T22:27:41.98463Z","end":"2026-08-05T22:27:42.13875Z","steps":["trace[1148609852] 'process raft request' (duration: 153.730084ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:27:42.561541Z","caller":"traceutil/trace.go:171","msg":"trace[871032615] transaction","detail":"{read_only:false; response_revision:25827; number_of_response:1; }","duration":"339.077111ms","start":"2026-08-05T22:27:42.222433Z","end":"2026-08-05T22:27:42.56151Z","steps":["trace[871032615] 'process raft request' (duration: 326.196133ms)","trace[871032615] 'compare' (duration: 12.703393ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T22:27:42.56171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:27:42.222409Z","time spent":"339.213495ms","remote":"127.0.0.1:47818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T22:27:42.561814Z","caller":"traceutil/trace.go:171","msg":"trace[569427142] transaction","detail":"{read_only:false; response_revision:25828; number_of_response:1; }","duration":"337.054143ms","start":"2026-08-05T22:27:42.224743Z","end":"2026-08-05T22:27:42.561797Z","steps":["trace[569427142] 'process raft request' (duration: 336.732274ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:27:42.561863Z","caller":"traceutil/trace.go:171","msg":"trace[1083276980] transaction","detail":"{read_only:false; response_revision:25829; number_of_response:1; }","duration":"336.276361ms","start":"2026-08-05T22:27:42.225555Z","end":"2026-08-05T22:27:42.561832Z","steps":["trace[1083276980] 'process raft request' (duration: 336.080936ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:27:42.561892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:27:42.22473Z","time spent":"337.123615ms","remote":"127.0.0.1:47818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T22:27:42.562025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:27:42.22554Z","time spent":"336.398845ms","remote":"127.0.0.1:47818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T22:27:43.846925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.561397ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-05T22:27:43.847035Z","caller":"traceutil/trace.go:171","msg":"trace[1606535560] transaction","detail":"{read_only:false; response_revision:25832; number_of_response:1; }","duration":"172.804139ms","start":"2026-08-05T22:27:43.674212Z","end":"2026-08-05T22:27:43.847016Z","steps":["trace[1606535560] 'process raft request' (duration: 31.054107ms)","trace[1606535560] 'compare' (duration: 141.294999ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T22:27:44.142326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.912454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:674"} {"level":"info","ts":"2026-08-05T22:27:44.14239Z","caller":"traceutil/trace.go:171","msg":"trace[615803370] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25832; }","duration":"158.024127ms","start":"2026-08-05T22:27:43.98435Z","end":"2026-08-05T22:27:44.142374Z","steps":["trace[615803370] 'range keys from in-memory index tree' (duration: 157.740849ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:27:44.14311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.775084ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-05T22:27:44.143358Z","caller":"traceutil/trace.go:171","msg":"trace[1653616632] linearizableReadLoop","detail":"{readStateIndex:26658; appliedIndex:26657; }","duration":"157.338548ms","start":"2026-08-05T22:27:43.986Z","end":"2026-08-05T22:27:44.143339Z","steps":["trace[1653616632] 'read index received' (duration: 38.171571ms)","trace[1653616632] 'applied index is now lower than readState.Index' (duration: 119.165117ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T22:27:44.14349Z","caller":"traceutil/trace.go:171","msg":"trace[1558032281] transaction","detail":"{read_only:false; response_revision:25833; number_of_response:1; }","duration":"158.396758ms","start":"2026-08-05T22:27:43.985082Z","end":"2026-08-05T22:27:44.143478Z","steps":["trace[1558032281] 'process raft request' (duration: 39.142099ms)","trace[1558032281] 'compare' (duration: 118.363753ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T22:27:44.143765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.74497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:674"} {"level":"warn","ts":"2026-08-05T22:27:44.143825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.824762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:674"} {"level":"info","ts":"2026-08-05T22:27:44.143834Z","caller":"traceutil/trace.go:171","msg":"trace[943760224] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25833; }","duration":"157.869424ms","start":"2026-08-05T22:27:43.985945Z","end":"2026-08-05T22:27:44.143814Z","steps":["trace[943760224] 'agreement among raft nodes before linearized reading' (duration: 157.681588ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:27:44.143856Z","caller":"traceutil/trace.go:171","msg":"trace[789721096] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25833; }","duration":"157.901264ms","start":"2026-08-05T22:27:43.985946Z","end":"2026-08-05T22:27:44.143847Z","steps":["trace[789721096] 'agreement among raft nodes before linearized reading' (duration: 157.834583ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:27:44.144103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.791138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-08-05T22:27:44.144127Z","caller":"traceutil/trace.go:171","msg":"trace[391269407] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25833; }","duration":"137.86021ms","start":"2026-08-05T22:27:44.006259Z","end":"2026-08-05T22:27:44.144119Z","steps":["trace[391269407] 'agreement among raft nodes before linearized reading' (duration: 137.790708ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:27:44.337621Z","caller":"traceutil/trace.go:171","msg":"trace[1622273687] transaction","detail":"{read_only:false; response_revision:25834; number_of_response:1; }","duration":"187.407747ms","start":"2026-08-05T22:27:44.150187Z","end":"2026-08-05T22:27:44.337594Z","steps":["trace[1622273687] 'process raft request' (duration: 156.562125ms)","trace[1622273687] 'compare' (duration: 30.655127ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T22:27:44.339674Z","caller":"traceutil/trace.go:171","msg":"trace[473082536] linearizableReadLoop","detail":"{readStateIndex:26662; appliedIndex:26658; }","duration":"188.692504ms","start":"2026-08-05T22:27:44.150968Z","end":"2026-08-05T22:27:44.33966Z","steps":["trace[473082536] 'read index received' (duration: 155.864976ms)","trace[473082536] 'applied index is now lower than readState.Index' (duration: 32.826568ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T22:27:44.339782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.794817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:574"} {"level":"info","ts":"2026-08-05T22:27:44.339821Z","caller":"traceutil/trace.go:171","msg":"trace[956166694] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:25837; }","duration":"188.861548ms","start":"2026-08-05T22:27:44.150947Z","end":"2026-08-05T22:27:44.339809Z","steps":["trace[956166694] 'agreement among raft nodes before linearized reading' (duration: 188.771726ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:27:44.340062Z","caller":"traceutil/trace.go:171","msg":"trace[1183994326] transaction","detail":"{read_only:false; response_revision:25835; number_of_response:1; }","duration":"189.330192ms","start":"2026-08-05T22:27:44.15072Z","end":"2026-08-05T22:27:44.340051Z","steps":["trace[1183994326] 'process raft request' (duration: 188.808078ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:27:44.340194Z","caller":"traceutil/trace.go:171","msg":"trace[482998948] transaction","detail":"{read_only:false; response_revision:25836; number_of_response:1; }","duration":"189.364503ms","start":"2026-08-05T22:27:44.150821Z","end":"2026-08-05T22:27:44.340186Z","steps":["trace[482998948] 'process raft request' (duration: 188.771776ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:27:44.340316Z","caller":"traceutil/trace.go:171","msg":"trace[2038167418] transaction","detail":"{read_only:false; response_revision:25837; number_of_response:1; }","duration":"189.392684ms","start":"2026-08-05T22:27:44.150914Z","end":"2026-08-05T22:27:44.340307Z","steps":["trace[2038167418] 'process raft request' (duration: 188.714224ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:27:44.530844Z","caller":"traceutil/trace.go:171","msg":"trace[1952475956] transaction","detail":"{read_only:false; response_revision:25839; number_of_response:1; }","duration":"183.643879ms","start":"2026-08-05T22:27:44.347169Z","end":"2026-08-05T22:27:44.530813Z","steps":["trace[1952475956] 'process raft request' (duration: 123.202411ms)","trace[1952475956] 'compare' (duration: 60.333435ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T22:27:44.794441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.247221ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-05T22:27:44.795142Z","caller":"traceutil/trace.go:171","msg":"trace[142868531] linearizableReadLoop","detail":"{readStateIndex:26667; appliedIndex:26664; }","duration":"215.84633ms","start":"2026-08-05T22:27:44.579277Z","end":"2026-08-05T22:27:44.795123Z","steps":["trace[142868531] 'read index received' (duration: 97.816536ms)","trace[142868531] 'applied index is now lower than readState.Index' (duration: 118.028524ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T22:27:44.795257Z","caller":"traceutil/trace.go:171","msg":"trace[1143137425] transaction","detail":"{read_only:false; response_revision:25841; number_of_response:1; }","duration":"227.362738ms","start":"2026-08-05T22:27:44.567879Z","end":"2026-08-05T22:27:44.795242Z","steps":["trace[1143137425] 'process raft request' (duration: 227.08736ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:27:44.795314Z","caller":"traceutil/trace.go:171","msg":"trace[501778955] transaction","detail":"{read_only:false; response_revision:25840; number_of_response:1; }","duration":"228.676946ms","start":"2026-08-05T22:27:44.566624Z","end":"2026-08-05T22:27:44.795301Z","steps":["trace[501778955] 'process raft request' (duration: 110.471147ms)","trace[501778955] 'compare' (duration: 116.645465ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T22:27:44.79574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.439748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T22:27:44.795839Z","caller":"traceutil/trace.go:171","msg":"trace[1701230340] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25842; }","duration":"102.52969ms","start":"2026-08-05T22:27:44.693288Z","end":"2026-08-05T22:27:44.795818Z","steps":["trace[1701230340] 'agreement among raft nodes before linearized reading' (duration: 102.373456ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:27:44.795389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.103877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-05T22:27:44.796207Z","caller":"traceutil/trace.go:171","msg":"trace[932283130] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25842; }","duration":"216.947972ms","start":"2026-08-05T22:27:44.579239Z","end":"2026-08-05T22:27:44.796187Z","steps":["trace[932283130] 'agreement among raft nodes before linearized reading' (duration: 216.106637ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:27:44.795373Z","caller":"traceutil/trace.go:171","msg":"trace[1030774418] transaction","detail":"{read_only:false; response_revision:25842; number_of_response:1; }","duration":"225.639959ms","start":"2026-08-05T22:27:44.569709Z","end":"2026-08-05T22:27:44.795349Z","steps":["trace[1030774418] 'process raft request' (duration: 225.359971ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:27:45.973879Z","caller":"traceutil/trace.go:171","msg":"trace[167191812] transaction","detail":"{read_only:false; response_revision:25845; number_of_response:1; }","duration":"117.651133ms","start":"2026-08-05T22:27:45.856204Z","end":"2026-08-05T22:27:45.973855Z","steps":["trace[167191812] 'process raft request' (duration: 117.495008ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:27:46.081473Z","caller":"traceutil/trace.go:171","msg":"trace[1706082813] transaction","detail":"{read_only:false; response_revision:25846; number_of_response:1; }","duration":"178.974416ms","start":"2026-08-05T22:27:45.902447Z","end":"2026-08-05T22:27:46.081421Z","steps":["trace[1706082813] 'process raft request' (duration: 156.135564ms)","trace[1706082813] 'compare' (duration: 22.568974ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T22:27:46.365511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.164945ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-08-05T22:27:46.365734Z","caller":"traceutil/trace.go:171","msg":"trace[2146620061] transaction","detail":"{read_only:false; response_revision:25847; number_of_response:1; }","duration":"216.25571ms","start":"2026-08-05T22:27:46.149461Z","end":"2026-08-05T22:27:46.365717Z","steps":["trace[2146620061] 'process raft request' (duration: 216.141037ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:27:46.365787Z","caller":"traceutil/trace.go:171","msg":"trace[1101890257] linearizableReadLoop","detail":"{readStateIndex:26672; appliedIndex:26671; }","duration":"216.313423ms","start":"2026-08-05T22:27:46.149445Z","end":"2026-08-05T22:27:46.365758Z","steps":["trace[1101890257] 'read index received' (duration: 67.911891ms)","trace[1101890257] 'applied index is now lower than readState.Index' (duration: 148.396091ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T22:27:46.366014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.559979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:674"} {"level":"info","ts":"2026-08-05T22:27:46.366039Z","caller":"traceutil/trace.go:171","msg":"trace[1502754816] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25847; }","duration":"216.60635ms","start":"2026-08-05T22:27:46.149426Z","end":"2026-08-05T22:27:46.366033Z","steps":["trace[1502754816] 'agreement among raft nodes before linearized reading' (duration: 216.456136ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:27:46.366087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.451626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:674"} {"level":"info","ts":"2026-08-05T22:27:46.366161Z","caller":"traceutil/trace.go:171","msg":"trace[1146804676] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25847; }","duration":"216.58171ms","start":"2026-08-05T22:27:46.149563Z","end":"2026-08-05T22:27:46.366145Z","steps":["trace[1146804676] 'agreement among raft nodes before linearized reading' (duration: 216.372045ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:27:46.366269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.012244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:674"} {"level":"info","ts":"2026-08-05T22:27:46.366342Z","caller":"traceutil/trace.go:171","msg":"trace[376811992] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25847; }","duration":"216.109456ms","start":"2026-08-05T22:27:46.150216Z","end":"2026-08-05T22:27:46.366325Z","steps":["trace[376811992] 'agreement among raft nodes before linearized reading' (duration: 215.852729ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:27:46.555812Z","caller":"traceutil/trace.go:171","msg":"trace[885783810] transaction","detail":"{read_only:false; response_revision:25849; number_of_response:1; }","duration":"185.312797ms","start":"2026-08-05T22:27:46.370463Z","end":"2026-08-05T22:27:46.555775Z","steps":["trace[885783810] 'process raft request' (duration: 179.552772ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:27:46.555935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.050061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-05T22:27:46.555969Z","caller":"traceutil/trace.go:171","msg":"trace[184685417] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:25853; }","duration":"142.149673ms","start":"2026-08-05T22:27:46.413813Z","end":"2026-08-05T22:27:46.555963Z","steps":["trace[184685417] 'agreement among raft nodes before linearized reading' (duration: 142.05039ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:27:46.556102Z","caller":"traceutil/trace.go:171","msg":"trace[678650467] transaction","detail":"{read_only:false; response_revision:25850; number_of_response:1; }","duration":"185.304156ms","start":"2026-08-05T22:27:46.370792Z","end":"2026-08-05T22:27:46.556096Z","steps":["trace[678650467] 'process raft request' (duration: 184.915795ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:27:46.555829Z","caller":"traceutil/trace.go:171","msg":"trace[579313509] linearizableReadLoop","detail":"{readStateIndex:26679; appliedIndex:26673; }","duration":"141.921797ms","start":"2026-08-05T22:27:46.41389Z","end":"2026-08-05T22:27:46.555812Z","steps":["trace[579313509] 'read index received' (duration: 5.462797ms)","trace[579313509] 'applied index is now lower than readState.Index' (duration: 136.45795ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T22:27:46.556155Z","caller":"traceutil/trace.go:171","msg":"trace[554009503] transaction","detail":"{read_only:false; response_revision:25851; number_of_response:1; }","duration":"185.142872ms","start":"2026-08-05T22:27:46.371008Z","end":"2026-08-05T22:27:46.556151Z","steps":["trace[554009503] 'process raft request' (duration: 184.73288ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:27:46.556221Z","caller":"traceutil/trace.go:171","msg":"trace[1389037437] transaction","detail":"{read_only:false; response_revision:25853; number_of_response:1; }","duration":"145.468058ms","start":"2026-08-05T22:27:46.41072Z","end":"2026-08-05T22:27:46.556188Z","steps":["trace[1389037437] 'process raft request' (duration: 145.058956ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:27:46.55641Z","caller":"traceutil/trace.go:171","msg":"trace[1586274061] transaction","detail":"{read_only:false; response_revision:25852; number_of_response:1; }","duration":"184.869725ms","start":"2026-08-05T22:27:46.37153Z","end":"2026-08-05T22:27:46.5564Z","steps":["trace[1586274061] 'process raft request' (duration: 184.230666ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:27:46.556419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.150553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"} {"level":"info","ts":"2026-08-05T22:27:46.556497Z","caller":"traceutil/trace.go:171","msg":"trace[2070002795] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:25853; }","duration":"135.264966ms","start":"2026-08-05T22:27:46.421213Z","end":"2026-08-05T22:27:46.556478Z","steps":["trace[2070002795] 'agreement among raft nodes before linearized reading' (duration: 135.003178ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:27:46.969858Z","caller":"traceutil/trace.go:171","msg":"trace[2061298558] linearizableReadLoop","detail":"{readStateIndex:26683; appliedIndex:26681; }","duration":"108.633444ms","start":"2026-08-05T22:27:46.861206Z","end":"2026-08-05T22:27:46.96984Z","steps":["trace[2061298558] 'read index received' (duration: 96.654302ms)","trace[2061298558] 'applied index is now lower than readState.Index' (duration: 11.978472ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T22:27:46.970011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.80538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-05T22:27:46.970039Z","caller":"traceutil/trace.go:171","msg":"trace[307382033] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25857; }","duration":"108.899382ms","start":"2026-08-05T22:27:46.861132Z","end":"2026-08-05T22:27:46.970031Z","steps":["trace[307382033] 'agreement among raft nodes before linearized reading' (duration: 108.787779ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:27:46.970023Z","caller":"traceutil/trace.go:171","msg":"trace[1968098594] transaction","detail":"{read_only:false; response_revision:25856; number_of_response:1; }","duration":"168.61238ms","start":"2026-08-05T22:27:46.80138Z","end":"2026-08-05T22:27:46.969992Z","steps":["trace[1968098594] 'process raft request' (duration: 156.608517ms)","trace[1968098594] 'compare' (duration: 11.447097ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T22:27:46.970226Z","caller":"traceutil/trace.go:171","msg":"trace[577180332] transaction","detail":"{read_only:false; response_revision:25857; number_of_response:1; }","duration":"167.9541ms","start":"2026-08-05T22:27:46.802255Z","end":"2026-08-05T22:27:46.970209Z","steps":["trace[577180332] 'process raft request' (duration: 167.499257ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:27:47.121554Z","caller":"traceutil/trace.go:171","msg":"trace[1797263523] transaction","detail":"{read_only:false; response_revision:25859; number_of_response:1; }","duration":"139.846417ms","start":"2026-08-05T22:27:46.981673Z","end":"2026-08-05T22:27:47.121519Z","steps":["trace[1797263523] 'process raft request' (duration: 93.303586ms)","trace[1797263523] 'compare' (duration: 46.207192ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T22:27:48.68828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.943268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-08-05T22:27:48.688296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.553187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-08-05T22:27:48.688346Z","caller":"traceutil/trace.go:171","msg":"trace[191980513] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25864; }","duration":"127.051981ms","start":"2026-08-05T22:27:48.561279Z","end":"2026-08-05T22:27:48.688331Z","steps":["trace[191980513] 'agreement among raft nodes before linearized reading' (duration: 126.856676ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:27:48.688385Z","caller":"traceutil/trace.go:171","msg":"trace[1571084043] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25864; }","duration":"126.67222ms","start":"2026-08-05T22:27:48.561688Z","end":"2026-08-05T22:27:48.68836Z","steps":["trace[1571084043] 'agreement among raft nodes before linearized reading' (duration: 126.429093ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:27:48.688313Z","caller":"traceutil/trace.go:171","msg":"trace[1556582006] transaction","detail":"{read_only:false; response_revision:25864; number_of_response:1; }","duration":"126.720192ms","start":"2026-08-05T22:27:48.561569Z","end":"2026-08-05T22:27:48.688289Z","steps":["trace[1556582006] 'process raft request' (duration: 126.268749ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:27:48.689942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.738323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-08-05T22:27:48.689984Z","caller":"traceutil/trace.go:171","msg":"trace[1307526323] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25864; }","duration":"126.798975ms","start":"2026-08-05T22:27:48.563172Z","end":"2026-08-05T22:27:48.689971Z","steps":["trace[1307526323] 'agreement among raft nodes before linearized reading' (duration: 124.91369ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:27:48.820189Z","caller":"traceutil/trace.go:171","msg":"trace[1629165454] linearizableReadLoop","detail":"{readStateIndex:26691; appliedIndex:26690; }","duration":"132.121106ms","start":"2026-08-05T22:27:48.688039Z","end":"2026-08-05T22:27:48.82016Z","steps":["trace[1629165454] 'read index received' (duration: 67.623683ms)","trace[1629165454] 'applied index is now lower than readState.Index' (duration: 64.495893ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T22:27:48.82038Z","caller":"traceutil/trace.go:171","msg":"trace[1096149876] transaction","detail":"{read_only:false; response_revision:25865; number_of_response:1; }","duration":"203.289651ms","start":"2026-08-05T22:27:48.617067Z","end":"2026-08-05T22:27:48.820357Z","steps":["trace[1096149876] 'process raft request' (duration: 138.574081ms)","trace[1096149876] 'compare' (duration: 64.270757ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T22:27:48.820635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.396249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:574"} {"level":"info","ts":"2026-08-05T22:27:48.820697Z","caller":"traceutil/trace.go:171","msg":"trace[1873831618] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:25865; }","duration":"185.510052ms","start":"2026-08-05T22:27:48.635173Z","end":"2026-08-05T22:27:48.820683Z","steps":["trace[1873831618] 'agreement among raft nodes before linearized reading' (duration: 185.270475ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:27:48.89834Z","caller":"traceutil/trace.go:171","msg":"trace[1039440380] transaction","detail":"{read_only:false; response_revision:25867; number_of_response:1; }","duration":"203.240839ms","start":"2026-08-05T22:27:48.695068Z","end":"2026-08-05T22:27:48.898309Z","steps":["trace[1039440380] 'process raft request' (duration: 203.110665ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:27:48.898375Z","caller":"traceutil/trace.go:171","msg":"trace[1955555214] transaction","detail":"{read_only:false; response_revision:25868; number_of_response:1; }","duration":"203.173898ms","start":"2026-08-05T22:27:48.695166Z","end":"2026-08-05T22:27:48.89834Z","steps":["trace[1955555214] 'process raft request' (duration: 203.061415ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:27:48.898399Z","caller":"traceutil/trace.go:171","msg":"trace[1193755058] transaction","detail":"{read_only:false; response_revision:25866; number_of_response:1; }","duration":"205.149283ms","start":"2026-08-05T22:27:48.693209Z","end":"2026-08-05T22:27:48.898358Z","steps":["trace[1193755058] 'process raft request' (duration: 204.796603ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:27:48.899059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.20981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T22:27:48.899111Z","caller":"traceutil/trace.go:171","msg":"trace[1556837664] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25868; }","duration":"209.289202ms","start":"2026-08-05T22:27:48.689809Z","end":"2026-08-05T22:27:48.899098Z","steps":["trace[1556837664] 'agreement among raft nodes before linearized reading' (duration: 208.480009ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:27:57.072129Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006894.wal"} {"level":"info","ts":"2026-08-05T22:28:32.525394Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23326} {"level":"info","ts":"2026-08-05T22:28:32.659968Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23326,"took":"129.640525ms","hash":765273469,"current-db-size-bytes":58392576,"current-db-size":"58 MB","current-db-size-in-use-bytes":41291776,"current-db-size-in-use":"41 MB"} {"level":"info","ts":"2026-08-05T22:28:32.660026Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":765273469,"revision":23326,"compact-revision":20419} {"level":"warn","ts":"2026-08-05T22:31:16.111885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.034216ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-05T22:31:16.111966Z","caller":"traceutil/trace.go:171","msg":"trace[602416384] transaction","detail":"{read_only:false; response_revision:28072; number_of_response:1; }","duration":"141.786222ms","start":"2026-08-05T22:31:15.970167Z","end":"2026-08-05T22:31:16.111953Z","steps":["trace[602416384] 'process raft request' (duration: 41.626793ms)","trace[602416384] 'compare' (duration: 99.957334ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T22:31:16.129612Z","caller":"traceutil/trace.go:171","msg":"trace[1146790289] transaction","detail":"{read_only:false; response_revision:28073; number_of_response:1; }","duration":"157.811521ms","start":"2026-08-05T22:31:15.971761Z","end":"2026-08-05T22:31:16.129573Z","steps":["trace[1146790289] 'process raft request' (duration: 157.624225ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:31:16.129621Z","caller":"traceutil/trace.go:171","msg":"trace[836601887] transaction","detail":"{read_only:false; response_revision:28074; number_of_response:1; }","duration":"155.989577ms","start":"2026-08-05T22:31:15.973598Z","end":"2026-08-05T22:31:16.129588Z","steps":["trace[836601887] 'process raft request' (duration: 155.900354ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:33:29.939794Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"163ef968c92247ab","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-05T22:33:29.945175Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-08-05T22:33:29.945265Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-08-05T22:33:32.53172Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26375} {"level":"info","ts":"2026-08-05T22:33:32.675231Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26375,"took":"140.070782ms","hash":169712572,"current-db-size-bytes":59236352,"current-db-size":"59 MB","current-db-size-in-use-bytes":35917824,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-08-05T22:33:32.675287Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":169712572,"revision":26375,"compact-revision":23326} {"level":"info","ts":"2026-08-05T22:38:32.541374Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":29111} {"level":"info","ts":"2026-08-05T22:38:32.672604Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":29111,"took":"127.566513ms","hash":1656249250,"current-db-size-bytes":59236352,"current-db-size":"59 MB","current-db-size-in-use-bytes":31076352,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-05T22:38:32.672675Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1656249250,"revision":29111,"compact-revision":26375} {"level":"info","ts":"2026-08-05T22:43:32.548939Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31375} {"level":"info","ts":"2026-08-05T22:43:32.684896Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31375,"took":"122.905497ms","hash":3778907168,"current-db-size-bytes":59236352,"current-db-size":"59 MB","current-db-size-in-use-bytes":31145984,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-05T22:43:32.684966Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3778907168,"revision":31375,"compact-revision":29111} {"level":"info","ts":"2026-08-05T22:48:32.556774Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33685} {"level":"info","ts":"2026-08-05T22:48:32.705112Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33685,"took":"142.706688ms","hash":572384374,"current-db-size-bytes":60280832,"current-db-size":"60 MB","current-db-size-in-use-bytes":32608256,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-08-05T22:48:32.705193Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":572384374,"revision":33685,"compact-revision":31375} {"level":"info","ts":"2026-08-05T22:53:32.563098Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":36015} {"level":"info","ts":"2026-08-05T22:53:32.712015Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":36015,"took":"145.861958ms","hash":2956658343,"current-db-size-bytes":61214720,"current-db-size":"61 MB","current-db-size-in-use-bytes":32038912,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-05T22:53:32.712077Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2956658343,"revision":36015,"compact-revision":33685} {"level":"info","ts":"2026-08-05T22:54:38.980835Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"163ef968c92247ab","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-05T22:54:38.985145Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-08-05T22:54:38.985231Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-08-05T22:58:32.569341Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":38281} {"level":"info","ts":"2026-08-05T22:58:32.692928Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":38281,"took":"119.858167ms","hash":99684612,"current-db-size-bytes":61661184,"current-db-size":"62 MB","current-db-size-in-use-bytes":31907840,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-05T22:58:32.69299Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":99684612,"revision":38281,"compact-revision":36015} {"level":"info","ts":"2026-08-05T23:03:32.577746Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":40611} {"level":"info","ts":"2026-08-05T23:03:32.708195Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":40611,"took":"125.615082ms","hash":3195108741,"current-db-size-bytes":62083072,"current-db-size":"62 MB","current-db-size-in-use-bytes":32354304,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-05T23:03:32.708271Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3195108741,"revision":40611,"compact-revision":38281}