{"level":"warn","ts":"2026-03-13T09:52:52.169968Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-13T09:52:52.170099Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.123: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.123:2380","--initial-cluster=instance=https://199.19.213.123:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.123:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.123: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-03-13T09:52:52.170176Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-13T09:52:52.170187Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.123:2380"]} {"level":"info","ts":"2026-03-13T09:52:52.170209Z","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-03-13T09:52:52.171131Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.123:2379"]} {"level":"info","ts":"2026-03-13T09:52:52.171227Z","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.123:2380"],"listen-peer-urls":["https://199.19.213.123:2380"],"advertise-client-urls":["https://199.19.213.123:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.123:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.123: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-03-13T09:52:52.175331Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.861165ms"} {"level":"info","ts":"2026-03-13T09:52:52.181808Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3e4829a3cabe4fce","cluster-id":"b469cfed817d8605"} {"level":"info","ts":"2026-03-13T09:52:52.181909Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3e4829a3cabe4fce switched to configuration voters=()"} {"level":"info","ts":"2026-03-13T09:52:52.181937Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3e4829a3cabe4fce became follower at term 0"} {"level":"info","ts":"2026-03-13T09:52:52.181952Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3e4829a3cabe4fce [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-13T09:52:52.181964Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3e4829a3cabe4fce became follower at term 1"} {"level":"info","ts":"2026-03-13T09:52:52.182007Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3e4829a3cabe4fce switched to configuration voters=(4487882812132577230)"} {"level":"warn","ts":"2026-03-13T09:52:52.190118Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-13T09:52:52.192661Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-13T09:52:52.194155Z","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-03-13T09:52:52.19702Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3e4829a3cabe4fce","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-13T09:52:52.197342Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3e4829a3cabe4fce","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-13T09:52:52.197487Z","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-03-13T09:52:52.19768Z","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-03-13T09:52:52.197779Z","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-03-13T09:52:52.198595Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3e4829a3cabe4fce switched to configuration voters=(4487882812132577230)"} {"level":"info","ts":"2026-03-13T09:52:52.201074Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b469cfed817d8605","local-member-id":"3e4829a3cabe4fce","added-peer-id":"3e4829a3cabe4fce","added-peer-peer-urls":["https://199.19.213.123:2380"]} {"level":"info","ts":"2026-03-13T09:52:52.203234Z","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-03-13T09:52:52.203385Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.123:2380"} {"level":"info","ts":"2026-03-13T09:52:52.20343Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.123:2380"} {"level":"info","ts":"2026-03-13T09:52:52.203504Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3e4829a3cabe4fce","initial-advertise-peer-urls":["https://199.19.213.123:2380"],"listen-peer-urls":["https://199.19.213.123:2380"],"advertise-client-urls":["https://199.19.213.123:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.123:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-13T09:52:52.203553Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-13T09:52:52.382903Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3e4829a3cabe4fce is starting a new election at term 1"} {"level":"info","ts":"2026-03-13T09:52:52.383015Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3e4829a3cabe4fce became pre-candidate at term 1"} {"level":"info","ts":"2026-03-13T09:52:52.383085Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3e4829a3cabe4fce received MsgPreVoteResp from 3e4829a3cabe4fce at term 1"} {"level":"info","ts":"2026-03-13T09:52:52.383113Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3e4829a3cabe4fce became candidate at term 2"} {"level":"info","ts":"2026-03-13T09:52:52.383131Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3e4829a3cabe4fce received MsgVoteResp from 3e4829a3cabe4fce at term 2"} {"level":"info","ts":"2026-03-13T09:52:52.38315Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3e4829a3cabe4fce became leader at term 2"} {"level":"info","ts":"2026-03-13T09:52:52.383166Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3e4829a3cabe4fce elected leader 3e4829a3cabe4fce at term 2"} {"level":"info","ts":"2026-03-13T09:52:52.38417Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-13T09:52:52.384967Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3e4829a3cabe4fce","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.123:2379]}","request-path":"/0/members/3e4829a3cabe4fce/attributes","cluster-id":"b469cfed817d8605","publish-timeout":"7s"} {"level":"info","ts":"2026-03-13T09:52:52.384967Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-13T09:52:52.385049Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-13T09:52:52.385467Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-13T09:52:52.385552Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-13T09:52:52.385661Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b469cfed817d8605","local-member-id":"3e4829a3cabe4fce","cluster-version":"3.5"} {"level":"info","ts":"2026-03-13T09:52:52.38585Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-13T09:52:52.38591Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-13T09:52:52.387527Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.123:2379"} {"level":"info","ts":"2026-03-13T09:52:52.388247Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-03-13T09:53:11.478057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.871024ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-13T09:53:11.478214Z","caller":"traceutil/trace.go:171","msg":"trace[751247879] linearizableReadLoop","detail":"{readStateIndex:464; appliedIndex:463; }","duration":"135.600183ms","start":"2026-03-13T09:53:11.342594Z","end":"2026-03-13T09:53:11.478195Z","steps":["trace[751247879] 'read index received' (duration: 52.641µs)","trace[751247879] 'applied index is now lower than readState.Index' (duration: 135.546102ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:53:11.478369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.753546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-13T09:53:11.478468Z","caller":"traceutil/trace.go:171","msg":"trace[1010276062] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:446; }","duration":"135.91401ms","start":"2026-03-13T09:53:11.342536Z","end":"2026-03-13T09:53:11.47845Z","steps":["trace[1010276062] 'agreement among raft nodes before linearized reading' (duration: 135.713255ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:53:32.42561Z","caller":"traceutil/trace.go:171","msg":"trace[996560098] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"150.741225ms","start":"2026-03-13T09:53:32.274835Z","end":"2026-03-13T09:53:32.425576Z","steps":["trace[996560098] 'process raft request' (duration: 150.514209ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:53:38.345519Z","caller":"traceutil/trace.go:171","msg":"trace[1128672245] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"225.924834ms","start":"2026-03-13T09:53:38.119564Z","end":"2026-03-13T09:53:38.345489Z","steps":["trace[1128672245] 'process raft request' (duration: 225.76325ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:53:38.354168Z","caller":"traceutil/trace.go:171","msg":"trace[1901137807] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"160.272676ms","start":"2026-03-13T09:53:38.193877Z","end":"2026-03-13T09:53:38.35415Z","steps":["trace[1901137807] 'process raft request' (duration: 160.179403ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:53:38.918548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.914679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-13T09:53:38.918571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.631409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T09:53:38.918624Z","caller":"traceutil/trace.go:171","msg":"trace[882803124] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:689; }","duration":"225.037602ms","start":"2026-03-13T09:53:38.693572Z","end":"2026-03-13T09:53:38.91861Z","steps":["trace[882803124] 'range keys from in-memory index tree' (duration: 224.665543ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:53:38.918649Z","caller":"traceutil/trace.go:171","msg":"trace[291184416] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:689; }","duration":"216.760771ms","start":"2026-03-13T09:53:38.701869Z","end":"2026-03-13T09:53:38.91863Z","steps":["trace[291184416] 'range keys from in-memory index tree' (duration: 216.554576ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:53:39.05894Z","caller":"traceutil/trace.go:171","msg":"trace[1200907572] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"136.720946ms","start":"2026-03-13T09:53:38.922189Z","end":"2026-03-13T09:53:39.05891Z","steps":["trace[1200907572] 'process raft request' (duration: 136.546612ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:53:58.406185Z","caller":"traceutil/trace.go:171","msg":"trace[1005150946] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"120.438863ms","start":"2026-03-13T09:53:58.285685Z","end":"2026-03-13T09:53:58.406124Z","steps":["trace[1005150946] 'process raft request' (duration: 120.206237ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:53:58.565887Z","caller":"traceutil/trace.go:171","msg":"trace[1263564771] linearizableReadLoop","detail":"{readStateIndex:955; appliedIndex:954; }","duration":"147.842596ms","start":"2026-03-13T09:53:58.418028Z","end":"2026-03-13T09:53:58.565871Z","steps":["trace[1263564771] 'read index received' (duration: 145.818527ms)","trace[1263564771] 'applied index is now lower than readState.Index' (duration: 2.023169ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:53:58.566001Z","caller":"traceutil/trace.go:171","msg":"trace[745664766] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"152.545539ms","start":"2026-03-13T09:53:58.413433Z","end":"2026-03-13T09:53:58.565979Z","steps":["trace[745664766] 'process raft request' (duration: 150.402857ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:53:58.566247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.210744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-03-13T09:53:58.566324Z","caller":"traceutil/trace.go:171","msg":"trace[671734810] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:926; }","duration":"148.298057ms","start":"2026-03-13T09:53:58.418005Z","end":"2026-03-13T09:53:58.566303Z","steps":["trace[671734810] 'agreement among raft nodes before linearized reading' (duration: 148.107673ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:53:58.567705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.998985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-13T09:53:58.5679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.697238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T09:53:58.567915Z","caller":"traceutil/trace.go:171","msg":"trace[1954535616] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"147.330073ms","start":"2026-03-13T09:53:58.420515Z","end":"2026-03-13T09:53:58.567845Z","steps":["trace[1954535616] 'process raft request' (duration: 146.940364ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:53:58.567959Z","caller":"traceutil/trace.go:171","msg":"trace[691300067] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:927; }","duration":"127.775829ms","start":"2026-03-13T09:53:58.440168Z","end":"2026-03-13T09:53:58.567943Z","steps":["trace[691300067] 'agreement among raft nodes before linearized reading' (duration: 127.686767ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:53:58.567952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.196199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T09:53:58.568159Z","caller":"traceutil/trace.go:171","msg":"trace[2023307307] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:927; }","duration":"128.422505ms","start":"2026-03-13T09:53:58.439678Z","end":"2026-03-13T09:53:58.5681Z","steps":["trace[2023307307] 'agreement among raft nodes before linearized reading' (duration: 128.22939ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:53:58.568257Z","caller":"traceutil/trace.go:171","msg":"trace[1206248344] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:927; }","duration":"128.159339ms","start":"2026-03-13T09:53:58.439633Z","end":"2026-03-13T09:53:58.567792Z","steps":["trace[1206248344] 'agreement among raft nodes before linearized reading' (duration: 127.964814ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:55:41.847428Z","caller":"traceutil/trace.go:171","msg":"trace[764905157] transaction","detail":"{read_only:false; response_revision:1898; number_of_response:1; }","duration":"125.079378ms","start":"2026-03-13T09:55:41.722312Z","end":"2026-03-13T09:55:41.847392Z","steps":["trace[764905157] 'process raft request' (duration: 124.846763ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:55:47.756688Z","caller":"traceutil/trace.go:171","msg":"trace[169138555] transaction","detail":"{read_only:false; response_revision:1926; number_of_response:1; }","duration":"356.936807ms","start":"2026-03-13T09:55:47.399729Z","end":"2026-03-13T09:55:47.756666Z","steps":["trace[169138555] 'process raft request' (duration: 344.63115ms)","trace[169138555] 'compare' (duration: 11.895448ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:55:47.75718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:55:47.399691Z","time spent":"357.059329ms","remote":"127.0.0.1:49866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T09:55:47.763161Z","caller":"traceutil/trace.go:171","msg":"trace[863762321] transaction","detail":"{read_only:false; response_revision:1927; number_of_response:1; }","duration":"341.112557ms","start":"2026-03-13T09:55:47.422028Z","end":"2026-03-13T09:55:47.763141Z","steps":["trace[863762321] 'process raft request' (duration: 341.012165ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:47.763302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:55:47.422005Z","time spent":"341.21508ms","remote":"127.0.0.1:49866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T09:55:50.935104Z","caller":"traceutil/trace.go:171","msg":"trace[1654254096] linearizableReadLoop","detail":"{readStateIndex:2019; appliedIndex:2018; }","duration":"116.272423ms","start":"2026-03-13T09:55:50.8188Z","end":"2026-03-13T09:55:50.935072Z","steps":["trace[1654254096] 'read index received' (duration: 39.573167ms)","trace[1654254096] 'applied index is now lower than readState.Index' (duration: 76.698386ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:55:50.935211Z","caller":"traceutil/trace.go:171","msg":"trace[1557208431] transaction","detail":"{read_only:false; response_revision:1942; number_of_response:1; }","duration":"116.469877ms","start":"2026-03-13T09:55:50.818684Z","end":"2026-03-13T09:55:50.935154Z","steps":["trace[1557208431] 'process raft request' (duration: 39.75265ms)","trace[1557208431] 'compare' (duration: 76.493552ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:55:50.935268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.440907ms","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-03-13T09:55:50.935307Z","caller":"traceutil/trace.go:171","msg":"trace[1937177116] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:1942; }","duration":"116.516819ms","start":"2026-03-13T09:55:50.818779Z","end":"2026-03-13T09:55:50.935296Z","steps":["trace[1937177116] 'agreement among raft nodes before linearized reading' (duration: 116.405766ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:51.541967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.949157ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-13T09:55:51.54221Z","caller":"traceutil/trace.go:171","msg":"trace[1636981663] linearizableReadLoop","detail":"{readStateIndex:2020; appliedIndex:2019; }","duration":"131.591631ms","start":"2026-03-13T09:55:51.410594Z","end":"2026-03-13T09:55:51.542185Z","steps":["trace[1636981663] 'read index received' (duration: 20.424248ms)","trace[1636981663] 'applied index is now lower than readState.Index' (duration: 111.164743ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:55:51.542272Z","caller":"traceutil/trace.go:171","msg":"trace[1719122156] transaction","detail":"{read_only:false; response_revision:1943; number_of_response:1; }","duration":"111.885639ms","start":"2026-03-13T09:55:51.430369Z","end":"2026-03-13T09:55:51.542255Z","steps":["trace[1719122156] 'process raft request' (duration: 111.731566ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:51.54273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.094233ms","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":"info","ts":"2026-03-13T09:55:51.54286Z","caller":"traceutil/trace.go:171","msg":"trace[1479171992] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1943; }","duration":"132.269247ms","start":"2026-03-13T09:55:51.410561Z","end":"2026-03-13T09:55:51.54283Z","steps":["trace[1479171992] 'agreement among raft nodes before linearized reading' (duration: 131.810896ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:51.779291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.640175ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T09:55:51.779481Z","caller":"traceutil/trace.go:171","msg":"trace[1198404457] transaction","detail":"{read_only:false; response_revision:1944; number_of_response:1; }","duration":"233.052857ms","start":"2026-03-13T09:55:51.546409Z","end":"2026-03-13T09:55:51.779462Z","steps":["trace[1198404457] 'process raft request' (duration: 118.148477ms)","trace[1198404457] 'compare' (duration: 114.489241ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:55:54.083802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.213466ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T09:55:54.083955Z","caller":"traceutil/trace.go:171","msg":"trace[1854440966] linearizableReadLoop","detail":"{readStateIndex:2032; appliedIndex:2031; }","duration":"117.86678ms","start":"2026-03-13T09:55:53.966061Z","end":"2026-03-13T09:55:54.083928Z","steps":["trace[1854440966] 'read index received' (duration: 47.831µs)","trace[1854440966] 'applied index is now lower than readState.Index' (duration: 117.817319ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:55:54.084204Z","caller":"traceutil/trace.go:171","msg":"trace[1562298241] transaction","detail":"{read_only:false; response_revision:1954; number_of_response:1; }","duration":"238.420463ms","start":"2026-03-13T09:55:53.845671Z","end":"2026-03-13T09:55:54.084091Z","steps":["trace[1562298241] 'process raft request' (duration: 108.800538ms)","trace[1562298241] 'compare' (duration: 129.00639ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:55:54.084309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.255319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-13T09:55:54.084455Z","caller":"traceutil/trace.go:171","msg":"trace[1178456511] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1954; }","duration":"118.460774ms","start":"2026-03-13T09:55:53.965981Z","end":"2026-03-13T09:55:54.084442Z","steps":["trace[1178456511] 'agreement among raft nodes before linearized reading' (duration: 118.013743ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:55:54.253086Z","caller":"traceutil/trace.go:171","msg":"trace[16378536] transaction","detail":"{read_only:false; response_revision:1955; number_of_response:1; }","duration":"164.088912ms","start":"2026-03-13T09:55:54.088975Z","end":"2026-03-13T09:55:54.253064Z","steps":["trace[16378536] 'process raft request' (duration: 124.409243ms)","trace[16378536] 'compare' (duration: 39.375222ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:55:55.776152Z","caller":"traceutil/trace.go:171","msg":"trace[1259366072] linearizableReadLoop","detail":"{readStateIndex:2038; appliedIndex:2037; }","duration":"100.954994ms","start":"2026-03-13T09:55:55.675171Z","end":"2026-03-13T09:55:55.776126Z","steps":["trace[1259366072] 'read index received' (duration: 77.783032ms)","trace[1259366072] 'applied index is now lower than readState.Index' (duration: 23.170572ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:55:55.776349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.166508ms","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-03-13T09:55:55.776395Z","caller":"traceutil/trace.go:171","msg":"trace[789116664] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1960; }","duration":"101.272591ms","start":"2026-03-13T09:55:55.675106Z","end":"2026-03-13T09:55:55.776379Z","steps":["trace[789116664] 'agreement among raft nodes before linearized reading' (duration: 101.115497ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:55:55.776374Z","caller":"traceutil/trace.go:171","msg":"trace[1227736137] transaction","detail":"{read_only:false; response_revision:1960; number_of_response:1; }","duration":"207.346815ms","start":"2026-03-13T09:55:55.568981Z","end":"2026-03-13T09:55:55.776328Z","steps":["trace[1227736137] 'process raft request' (duration: 184.000809ms)","trace[1227736137] 'compare' (duration: 23.030918ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:55:56.273437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.252866ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T09:55:56.273596Z","caller":"traceutil/trace.go:171","msg":"trace[645468030] transaction","detail":"{read_only:false; response_revision:1961; number_of_response:1; }","duration":"423.382235ms","start":"2026-03-13T09:55:55.850188Z","end":"2026-03-13T09:55:56.27357Z","steps":["trace[645468030] 'process raft request' (duration: 135.915053ms)","trace[645468030] 'compare' (duration: 287.144123ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:55:56.273786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:55:55.850161Z","time spent":"423.493758ms","remote":"127.0.0.1:49866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T09:55:56.273548Z","caller":"traceutil/trace.go:171","msg":"trace[591337372] linearizableReadLoop","detail":"{readStateIndex:2039; appliedIndex:2038; }","duration":"412.346387ms","start":"2026-03-13T09:55:55.861186Z","end":"2026-03-13T09:55:56.273532Z","steps":["trace[591337372] 'read index received' (duration: 124.890086ms)","trace[591337372] 'applied index is now lower than readState.Index' (duration: 287.45515ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:55:56.273984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.762346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-13T09:55:56.274098Z","caller":"traceutil/trace.go:171","msg":"trace[262546986] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1961; }","duration":"412.93828ms","start":"2026-03-13T09:55:55.861129Z","end":"2026-03-13T09:55:56.274067Z","steps":["trace[262546986] 'agreement among raft nodes before linearized reading' (duration: 412.726466ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:56.274113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.495061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.123\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-13T09:55:56.274145Z","caller":"traceutil/trace.go:171","msg":"trace[180225843] range","detail":"{range_begin:/registry/masterleases/199.19.213.123; range_end:; response_count:1; response_revision:1961; }","duration":"240.591274ms","start":"2026-03-13T09:55:56.033544Z","end":"2026-03-13T09:55:56.274135Z","steps":["trace[180225843] 'agreement among raft nodes before linearized reading' (duration: 240.476471ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:56.274142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:55:55.861108Z","time spent":"413.024993ms","remote":"127.0.0.1:49866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":13,"response size":30,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-03-13T09:55:56.274204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.282702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-03-13T09:55:56.274031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.651379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T09:55:56.274246Z","caller":"traceutil/trace.go:171","msg":"trace[1267420377] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1961; }","duration":"373.391024ms","start":"2026-03-13T09:55:55.900843Z","end":"2026-03-13T09:55:56.274234Z","steps":["trace[1267420377] 'agreement among raft nodes before linearized reading' (duration: 373.21422ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:55:56.274294Z","caller":"traceutil/trace.go:171","msg":"trace[559136330] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:1961; }","duration":"399.919125ms","start":"2026-03-13T09:55:55.874361Z","end":"2026-03-13T09:55:56.274281Z","steps":["trace[559136330] 'agreement among raft nodes before linearized reading' (duration: 399.625369ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:56.274317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.658127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-03-13T09:55:56.27432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:55:55.874301Z","time spent":"400.012477ms","remote":"127.0.0.1:36468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true "} {"level":"info","ts":"2026-03-13T09:55:56.274345Z","caller":"traceutil/trace.go:171","msg":"trace[178073339] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1961; }","duration":"185.72398ms","start":"2026-03-13T09:55:56.088614Z","end":"2026-03-13T09:55:56.274338Z","steps":["trace[178073339] 'agreement among raft nodes before linearized reading' (duration: 185.660678ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:56.274311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:55:55.900811Z","time spent":"373.485397ms","remote":"127.0.0.1:49866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-13T09:55:56.274526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.787253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-13T09:55:56.274619Z","caller":"traceutil/trace.go:171","msg":"trace[295752267] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1961; }","duration":"253.918296ms","start":"2026-03-13T09:55:56.020677Z","end":"2026-03-13T09:55:56.274596Z","steps":["trace[295752267] 'agreement among raft nodes before linearized reading' (duration: 253.770902ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:56.274829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.160573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12386"} {"level":"info","ts":"2026-03-13T09:55:56.275045Z","caller":"traceutil/trace.go:171","msg":"trace[646562636] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1961; }","duration":"319.313907ms","start":"2026-03-13T09:55:55.955598Z","end":"2026-03-13T09:55:56.274912Z","steps":["trace[646562636] 'agreement among raft nodes before linearized reading' (duration: 318.425126ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:56.275086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:55:55.955576Z","time spent":"319.500262ms","remote":"127.0.0.1:49794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12409,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-03-13T09:55:56.484909Z","caller":"traceutil/trace.go:171","msg":"trace[494974429] transaction","detail":"{read_only:false; response_revision:1962; number_of_response:1; }","duration":"206.149278ms","start":"2026-03-13T09:55:56.278706Z","end":"2026-03-13T09:55:56.484855Z","steps":["trace[494974429] 'process raft request' (duration: 205.921923ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:55:56.484995Z","caller":"traceutil/trace.go:171","msg":"trace[817324234] transaction","detail":"{read_only:false; response_revision:1963; number_of_response:1; }","duration":"205.778238ms","start":"2026-03-13T09:55:56.279184Z","end":"2026-03-13T09:55:56.484963Z","steps":["trace[817324234] 'process raft request' (duration: 205.622405ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:55:56.48507Z","caller":"traceutil/trace.go:171","msg":"trace[1380126193] linearizableReadLoop","detail":"{readStateIndex:2043; appliedIndex:2039; }","duration":"200.166737ms","start":"2026-03-13T09:55:56.284893Z","end":"2026-03-13T09:55:56.48506Z","steps":["trace[1380126193] 'read index received' (duration: 101.143088ms)","trace[1380126193] 'applied index is now lower than readState.Index' (duration: 99.022659ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:55:56.485185Z","caller":"traceutil/trace.go:171","msg":"trace[1792155626] transaction","detail":"{read_only:false; response_revision:1964; number_of_response:1; }","duration":"205.775488ms","start":"2026-03-13T09:55:56.279399Z","end":"2026-03-13T09:55:56.485175Z","steps":["trace[1792155626] 'process raft request' (duration: 205.447001ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:56.485253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.34159ms","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-03-13T09:55:56.485345Z","caller":"traceutil/trace.go:171","msg":"trace[845206348] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:1964; }","duration":"200.490434ms","start":"2026-03-13T09:55:56.284834Z","end":"2026-03-13T09:55:56.485324Z","steps":["trace[845206348] 'agreement among raft nodes before linearized reading' (duration: 200.32909ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:55:56.839324Z","caller":"traceutil/trace.go:171","msg":"trace[2060739960] transaction","detail":"{read_only:false; response_revision:1966; number_of_response:1; }","duration":"348.576652ms","start":"2026-03-13T09:55:56.490712Z","end":"2026-03-13T09:55:56.839289Z","steps":["trace[2060739960] 'process raft request' (duration: 265.810964ms)","trace[2060739960] 'compare' (duration: 82.512242ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:55:56.839354Z","caller":"traceutil/trace.go:171","msg":"trace[435392135] transaction","detail":"{read_only:false; response_revision:1967; number_of_response:1; }","duration":"150.712168ms","start":"2026-03-13T09:55:56.688608Z","end":"2026-03-13T09:55:56.83932Z","steps":["trace[435392135] 'process raft request' (duration: 150.638217ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:56.839479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:55:56.490687Z","time spent":"348.712965ms","remote":"127.0.0.1:49866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T09:55:56.839534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.548792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-03-13T09:55:56.839374Z","caller":"traceutil/trace.go:171","msg":"trace[993916645] linearizableReadLoop","detail":"{readStateIndex:2045; appliedIndex:2044; }","duration":"310.380487ms","start":"2026-03-13T09:55:56.528961Z","end":"2026-03-13T09:55:56.839342Z","steps":["trace[993916645] 'read index received' (duration: 227.578199ms)","trace[993916645] 'applied index is now lower than readState.Index' (duration: 82.798918ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:55:56.839605Z","caller":"traceutil/trace.go:171","msg":"trace[53340789] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1967; }","duration":"310.720707ms","start":"2026-03-13T09:55:56.52887Z","end":"2026-03-13T09:55:56.839591Z","steps":["trace[53340789] 'agreement among raft nodes before linearized reading' (duration: 310.559233ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:56.839806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.52863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-13T09:55:56.839823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:55:56.528847Z","time spent":"310.920661ms","remote":"127.0.0.1:49768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-03-13T09:55:56.839864Z","caller":"traceutil/trace.go:171","msg":"trace[1566343690] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1967; }","duration":"134.622433ms","start":"2026-03-13T09:55:56.705225Z","end":"2026-03-13T09:55:56.839847Z","steps":["trace[1566343690] 'agreement among raft nodes before linearized reading' (duration: 134.531131ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:56.840087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.608346ms","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-03-13T09:55:56.840225Z","caller":"traceutil/trace.go:171","msg":"trace[1423236765] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1967; }","duration":"302.78281ms","start":"2026-03-13T09:55:56.537418Z","end":"2026-03-13T09:55:56.8402Z","steps":["trace[1423236765] 'agreement among raft nodes before linearized reading' (duration: 302.37376ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:56.840346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:55:56.537398Z","time spent":"302.925754ms","remote":"127.0.0.1:36856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-03-13T09:55:57.096042Z","caller":"traceutil/trace.go:171","msg":"trace[1991082871] transaction","detail":"{read_only:false; response_revision:1968; number_of_response:1; }","duration":"168.132607ms","start":"2026-03-13T09:55:56.927862Z","end":"2026-03-13T09:55:57.095995Z","steps":["trace[1991082871] 'process raft request' (duration: 136.904735ms)","trace[1991082871] 'compare' (duration: 31.065868ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:55:58.068998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.681859ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T09:55:58.069123Z","caller":"traceutil/trace.go:171","msg":"trace[1542264841] linearizableReadLoop","detail":"{readStateIndex:2050; appliedIndex:2049; }","duration":"221.119157ms","start":"2026-03-13T09:55:57.847981Z","end":"2026-03-13T09:55:58.069101Z","steps":["trace[1542264841] 'read index received' (duration: 96.08565ms)","trace[1542264841] 'applied index is now lower than readState.Index' (duration: 125.032297ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:55:58.069279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.302072ms","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-03-13T09:55:58.069322Z","caller":"traceutil/trace.go:171","msg":"trace[1908093073] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1971; }","duration":"221.373784ms","start":"2026-03-13T09:55:57.847932Z","end":"2026-03-13T09:55:58.069306Z","steps":["trace[1908093073] 'agreement among raft nodes before linearized reading' (duration: 221.229269ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:55:58.069289Z","caller":"traceutil/trace.go:171","msg":"trace[1869986735] transaction","detail":"{read_only:false; response_revision:1971; number_of_response:1; }","duration":"239.228782ms","start":"2026-03-13T09:55:57.830026Z","end":"2026-03-13T09:55:58.069255Z","steps":["trace[1869986735] 'process raft request' (duration: 114.196294ms)","trace[1869986735] 'compare' (duration: 124.549276ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:55:58.800397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.389138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-13T09:55:58.80054Z","caller":"traceutil/trace.go:171","msg":"trace[1602785076] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1975; }","duration":"167.614725ms","start":"2026-03-13T09:55:58.632899Z","end":"2026-03-13T09:55:58.800513Z","steps":["trace[1602785076] 'count revisions from in-memory index tree' (duration: 167.271856ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:58.800419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.673788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-03-13T09:55:58.800703Z","caller":"traceutil/trace.go:171","msg":"trace[263544658] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:1975; }","duration":"110.034017ms","start":"2026-03-13T09:55:58.690643Z","end":"2026-03-13T09:55:58.800677Z","steps":["trace[263544658] 'range keys from in-memory index tree' (duration: 109.461333ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:59.218795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.290398ms","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-03-13T09:55:59.219149Z","caller":"traceutil/trace.go:171","msg":"trace[691576544] transaction","detail":"{read_only:false; response_revision:1978; number_of_response:1; }","duration":"116.135468ms","start":"2026-03-13T09:55:59.102986Z","end":"2026-03-13T09:55:59.219121Z","steps":["trace[691576544] 'process raft request' (duration: 96.341765ms)","trace[691576544] 'compare' (duration: 19.589799ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:55:59.219353Z","caller":"traceutil/trace.go:171","msg":"trace[306314139] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1977; }","duration":"141.425221ms","start":"2026-03-13T09:55:59.077428Z","end":"2026-03-13T09:55:59.218854Z","steps":["trace[306314139] 'range keys from in-memory index tree' (duration: 141.108964ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:56:00.659612Z","caller":"traceutil/trace.go:171","msg":"trace[994750965] linearizableReadLoop","detail":"{readStateIndex:2062; appliedIndex:2061; }","duration":"109.071424ms","start":"2026-03-13T09:56:00.550497Z","end":"2026-03-13T09:56:00.659569Z","steps":["trace[994750965] 'read index received' (duration: 108.805268ms)","trace[994750965] 'applied index is now lower than readState.Index' (duration: 265.026µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:56:00.659753Z","caller":"traceutil/trace.go:171","msg":"trace[1863045118] transaction","detail":"{read_only:false; response_revision:1983; number_of_response:1; }","duration":"110.328393ms","start":"2026-03-13T09:56:00.549412Z","end":"2026-03-13T09:56:00.659741Z","steps":["trace[1863045118] 'process raft request' (duration: 109.979045ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:56:00.660108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.591196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.189c5e043c24779a\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-03-13T09:56:00.660159Z","caller":"traceutil/trace.go:171","msg":"trace[1594996801] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.189c5e043c24779a; range_end:; response_count:1; response_revision:1983; }","duration":"109.727419ms","start":"2026-03-13T09:56:00.55042Z","end":"2026-03-13T09:56:00.660148Z","steps":["trace[1594996801] 'agreement among raft nodes before linearized reading' (duration: 109.511144ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:57:23.295616Z","caller":"traceutil/trace.go:171","msg":"trace[1694568934] transaction","detail":"{read_only:false; response_revision:2581; number_of_response:1; }","duration":"135.759235ms","start":"2026-03-13T09:57:23.15983Z","end":"2026-03-13T09:57:23.29559Z","steps":["trace[1694568934] 'process raft request' (duration: 135.549329ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:57:23.476455Z","caller":"traceutil/trace.go:171","msg":"trace[1878621756] linearizableReadLoop","detail":"{readStateIndex:2686; appliedIndex:2684; }","duration":"256.791366ms","start":"2026-03-13T09:57:23.219637Z","end":"2026-03-13T09:57:23.476428Z","steps":["trace[1878621756] 'read index received' (duration: 75.780103ms)","trace[1878621756] 'applied index is now lower than readState.Index' (duration: 181.010153ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:57:23.476492Z","caller":"traceutil/trace.go:171","msg":"trace[1757927137] transaction","detail":"{read_only:false; response_revision:2582; number_of_response:1; }","duration":"316.552482ms","start":"2026-03-13T09:57:23.15991Z","end":"2026-03-13T09:57:23.476463Z","steps":["trace[1757927137] 'process raft request' (duration: 255.156864ms)","trace[1757927137] 'compare' (duration: 61.237994ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:57:23.47662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:57:23.159884Z","time spent":"316.666834ms","remote":"127.0.0.1:49866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T09:57:23.476662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.014051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-03-13T09:57:23.476717Z","caller":"traceutil/trace.go:171","msg":"trace[1097891157] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2582; }","duration":"257.126274ms","start":"2026-03-13T09:57:23.219573Z","end":"2026-03-13T09:57:23.476699Z","steps":["trace[1097891157] 'agreement among raft nodes before linearized reading' (duration: 256.955549ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:59:58.492496Z","caller":"traceutil/trace.go:171","msg":"trace[1037393820] transaction","detail":"{read_only:false; response_revision:3524; number_of_response:1; }","duration":"154.106753ms","start":"2026-03-13T09:59:58.338361Z","end":"2026-03-13T09:59:58.492467Z","steps":["trace[1037393820] 'process raft request' (duration: 153.952419ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T10:01:27.70992Z","caller":"traceutil/trace.go:171","msg":"trace[434278360] linearizableReadLoop","detail":"{readStateIndex:4709; appliedIndex:4708; }","duration":"140.98086ms","start":"2026-03-13T10:01:27.568851Z","end":"2026-03-13T10:01:27.709831Z","steps":["trace[434278360] 'read index received' (duration: 126.717202ms)","trace[434278360] 'applied index is now lower than readState.Index' (duration: 14.260128ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T10:01:27.710088Z","caller":"traceutil/trace.go:171","msg":"trace[597337150] transaction","detail":"{read_only:false; response_revision:4528; number_of_response:1; }","duration":"174.219952ms","start":"2026-03-13T10:01:27.535826Z","end":"2026-03-13T10:01:27.710046Z","steps":["trace[597337150] 'process raft request' (duration: 159.870612ms)","trace[597337150] 'compare' (duration: 13.827118ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T10:01:27.710402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.534744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:524463"} {"level":"info","ts":"2026-03-13T10:01:27.710477Z","caller":"traceutil/trace.go:171","msg":"trace[826464722] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:4528; }","duration":"141.643047ms","start":"2026-03-13T10:01:27.568816Z","end":"2026-03-13T10:01:27.710459Z","steps":["trace[826464722] 'agreement among raft nodes before linearized reading' (duration: 141.240087ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T10:01:27.939025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.09159ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T10:01:27.939304Z","caller":"traceutil/trace.go:171","msg":"trace[631433759] transaction","detail":"{read_only:false; response_revision:4529; number_of_response:1; }","duration":"212.357034ms","start":"2026-03-13T10:01:27.726905Z","end":"2026-03-13T10:01:27.939262Z","steps":["trace[631433759] 'process raft request' (duration: 89.870935ms)","trace[631433759] 'compare' (duration: 121.846403ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T10:01:27.939375Z","caller":"traceutil/trace.go:171","msg":"trace[1583801917] linearizableReadLoop","detail":"{readStateIndex:4711; appliedIndex:4709; }","duration":"211.361309ms","start":"2026-03-13T10:01:27.727994Z","end":"2026-03-13T10:01:27.939355Z","steps":["trace[1583801917] 'read index received' (duration: 88.792338ms)","trace[1583801917] 'applied index is now lower than readState.Index' (duration: 122.567531ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T10:01:27.939432Z","caller":"traceutil/trace.go:171","msg":"trace[1922173034] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4529; }","duration":"211.981014ms","start":"2026-03-13T10:01:27.727419Z","end":"2026-03-13T10:01:27.9394Z","steps":["trace[1922173034] 'process raft request' (duration: 211.771999ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T10:01:27.939659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.651646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1131"} {"level":"info","ts":"2026-03-13T10:01:27.9397Z","caller":"traceutil/trace.go:171","msg":"trace[1970368912] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4529; }","duration":"211.745798ms","start":"2026-03-13T10:01:27.727943Z","end":"2026-03-13T10:01:27.939688Z","steps":["trace[1970368912] 'agreement among raft nodes before linearized reading' (duration: 211.561963ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T10:01:27.939939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.276412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-03-13T10:01:27.939972Z","caller":"traceutil/trace.go:171","msg":"trace[1251770697] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4529; }","duration":"196.357974ms","start":"2026-03-13T10:01:27.743605Z","end":"2026-03-13T10:01:27.939963Z","steps":["trace[1251770697] 'agreement among raft nodes before linearized reading' (duration: 196.105726ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T10:01:31.148991Z","caller":"traceutil/trace.go:171","msg":"trace[2112673341] transaction","detail":"{read_only:false; response_revision:4691; number_of_response:1; }","duration":"150.550094ms","start":"2026-03-13T10:01:30.9984Z","end":"2026-03-13T10:01:31.14895Z","steps":["trace[2112673341] 'process raft request' (duration: 150.310559ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T10:01:31.148981Z","caller":"traceutil/trace.go:171","msg":"trace[827827867] linearizableReadLoop","detail":"{readStateIndex:4875; appliedIndex:4874; }","duration":"113.885271ms","start":"2026-03-13T10:01:31.035028Z","end":"2026-03-13T10:01:31.148913Z","steps":["trace[827827867] 'read index received' (duration: 113.697306ms)","trace[827827867] 'applied index is now lower than readState.Index' (duration: 186.485µs)"],"step_count":2} {"level":"warn","ts":"2026-03-13T10:01:31.149284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.109516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-w82pc\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-03-13T10:01:31.149314Z","caller":"traceutil/trace.go:171","msg":"trace[461219469] range","detail":"{range_begin:/registry/pods/openstack/keepalived-w82pc; range_end:; response_count:1; response_revision:4691; }","duration":"114.176667ms","start":"2026-03-13T10:01:31.03513Z","end":"2026-03-13T10:01:31.149307Z","steps":["trace[461219469] 'agreement among raft nodes before linearized reading' (duration: 114.035454ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T10:01:31.149311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.251169ms","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-03-13T10:01:31.149417Z","caller":"traceutil/trace.go:171","msg":"trace[1736379914] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4691; }","duration":"114.418083ms","start":"2026-03-13T10:01:31.034977Z","end":"2026-03-13T10:01:31.149396Z","steps":["trace[1736379914] 'agreement among raft nodes before linearized reading' (duration: 114.048864ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T10:01:31.521942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.733329ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T10:01:31.522047Z","caller":"traceutil/trace.go:171","msg":"trace[1035177499] linearizableReadLoop","detail":"{readStateIndex:4876; appliedIndex:4875; }","duration":"367.866678ms","start":"2026-03-13T10:01:31.154163Z","end":"2026-03-13T10:01:31.52203Z","steps":["trace[1035177499] 'read index received' (duration: 158.117529ms)","trace[1035177499] 'applied index is now lower than readState.Index' (duration: 209.747599ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T10:01:31.522116Z","caller":"traceutil/trace.go:171","msg":"trace[1146285580] transaction","detail":"{read_only:false; response_revision:4692; number_of_response:1; }","duration":"368.7733ms","start":"2026-03-13T10:01:31.153332Z","end":"2026-03-13T10:01:31.522105Z","steps":["trace[1146285580] 'process raft request' (duration: 158.784225ms)","trace[1146285580] 'compare' (duration: 209.522164ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T10:01:31.522169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T10:01:31.153302Z","time spent":"368.838642ms","remote":"127.0.0.1:49866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T10:01:31.522383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.186197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:7 size:62869"} {"level":"info","ts":"2026-03-13T10:01:31.522462Z","caller":"traceutil/trace.go:171","msg":"trace[702032405] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:7; response_revision:4692; }","duration":"368.32661ms","start":"2026-03-13T10:01:31.154118Z","end":"2026-03-13T10:01:31.522444Z","steps":["trace[702032405] 'agreement among raft nodes before linearized reading' (duration: 368.023833ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T10:01:31.522507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T10:01:31.1541Z","time spent":"368.395541ms","remote":"127.0.0.1:49794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":7,"response size":62892,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-03-13T10:01:31.619781Z","caller":"traceutil/trace.go:171","msg":"trace[637723519] transaction","detail":"{read_only:false; response_revision:4693; number_of_response:1; }","duration":"415.112612ms","start":"2026-03-13T10:01:31.20463Z","end":"2026-03-13T10:01:31.619742Z","steps":["trace[637723519] 'process raft request' (duration: 414.874687ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T10:01:31.620005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T10:01:31.204603Z","time spent":"415.289776ms","remote":"127.0.0.1:49866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T10:01:31.699525Z","caller":"traceutil/trace.go:171","msg":"trace[1636786201] transaction","detail":"{read_only:false; response_revision:4694; number_of_response:1; }","duration":"301.659604ms","start":"2026-03-13T10:01:31.397839Z","end":"2026-03-13T10:01:31.699499Z","steps":["trace[1636786201] 'process raft request' (duration: 301.446208ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T10:01:31.699647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T10:01:31.397819Z","time spent":"301.772896ms","remote":"127.0.0.1:37866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3746,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-13T10:01:31.699506Z","caller":"traceutil/trace.go:171","msg":"trace[2146540514] linearizableReadLoop","detail":"{readStateIndex:4879; appliedIndex:4876; }","duration":"177.395401ms","start":"2026-03-13T10:01:31.522072Z","end":"2026-03-13T10:01:31.699467Z","steps":["trace[2146540514] 'read index received' (duration: 97.50302ms)","trace[2146540514] 'applied index is now lower than readState.Index' (duration: 79.890821ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T10:01:31.69972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.474069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/storage-loki-0.189c5e5902d1c14a\" ","response":"range_response_count:1 size:943"} {"level":"info","ts":"2026-03-13T10:01:31.699778Z","caller":"traceutil/trace.go:171","msg":"trace[167347839] range","detail":"{range_begin:/registry/events/monitoring/storage-loki-0.189c5e5902d1c14a; range_end:; response_count:1; response_revision:4694; }","duration":"183.562641ms","start":"2026-03-13T10:01:31.516197Z","end":"2026-03-13T10:01:31.69976Z","steps":["trace[167347839] 'agreement among raft nodes before linearized reading' (duration: 183.340006ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T10:01:31.69997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.126825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-519b7412-357a-4b00-be61-1c363e2c6fd1\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-03-13T10:01:31.700023Z","caller":"traceutil/trace.go:171","msg":"trace[142552315] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-519b7412-357a-4b00-be61-1c363e2c6fd1; range_end:; response_count:1; response_revision:4694; }","duration":"148.236468ms","start":"2026-03-13T10:01:31.551771Z","end":"2026-03-13T10:01:31.700007Z","steps":["trace[142552315] 'agreement among raft nodes before linearized reading' (duration: 148.004812ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T10:01:31.700358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.597809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:719292"} {"level":"info","ts":"2026-03-13T10:01:31.700435Z","caller":"traceutil/trace.go:171","msg":"trace[1523697127] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:4694; }","duration":"500.762403ms","start":"2026-03-13T10:01:31.199655Z","end":"2026-03-13T10:01:31.700418Z","steps":["trace[1523697127] 'agreement among raft nodes before linearized reading' (duration: 499.922233ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T10:01:31.700488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T10:01:31.199622Z","time spent":"500.854675ms","remote":"127.0.0.1:49702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":24,"response size":719315,"request content":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" "} {"level":"warn","ts":"2026-03-13T10:01:43.040116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.939302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-03-13T10:01:43.040208Z","caller":"traceutil/trace.go:171","msg":"trace[1649728048] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4914; }","duration":"171.080966ms","start":"2026-03-13T10:01:42.869107Z","end":"2026-03-13T10:01:43.040188Z","steps":["trace[1649728048] 'range keys from in-memory index tree' (duration: 170.684956ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T10:01:43.040116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.716938ms","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-03-13T10:01:43.040289Z","caller":"traceutil/trace.go:171","msg":"trace[334489768] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4914; }","duration":"193.934724ms","start":"2026-03-13T10:01:42.846343Z","end":"2026-03-13T10:01:43.040277Z","steps":["trace[334489768] 'range keys from in-memory index tree' (duration: 193.580575ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T10:01:43.040407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.747742ms","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-03-13T10:01:43.040489Z","caller":"traceutil/trace.go:171","msg":"trace[1842447814] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4914; }","duration":"180.844074ms","start":"2026-03-13T10:01:42.859625Z","end":"2026-03-13T10:01:43.040469Z","steps":["trace[1842447814] 'range keys from in-memory index tree' (duration: 180.635319ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T10:01:51.498347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.471255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ef5234bb-39e5-4c79-9331-1e5f70d00009\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-03-13T10:01:51.498425Z","caller":"traceutil/trace.go:171","msg":"trace[1257185724] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ef5234bb-39e5-4c79-9331-1e5f70d00009; range_end:; response_count:1; response_revision:5063; }","duration":"109.569027ms","start":"2026-03-13T10:01:51.388842Z","end":"2026-03-13T10:01:51.498411Z","steps":["trace[1257185724] 'range keys from in-memory index tree' (duration: 109.29939ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T10:01:51.498555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.585837ms","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-03-13T10:01:51.498623Z","caller":"traceutil/trace.go:171","msg":"trace[601073619] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:5063; }","duration":"178.70227ms","start":"2026-03-13T10:01:51.319908Z","end":"2026-03-13T10:01:51.49861Z","steps":["trace[601073619] 'count revisions from in-memory index tree' (duration: 178.440084ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T10:01:51.62752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.530519ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-03-13T10:01:52.019183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.962959ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T10:01:52.019308Z","caller":"traceutil/trace.go:171","msg":"trace[1053777564] transaction","detail":"{read_only:false; response_revision:5067; number_of_response:1; }","duration":"174.315893ms","start":"2026-03-13T10:01:51.844968Z","end":"2026-03-13T10:01:52.019284Z","steps":["trace[1053777564] 'process raft request' (duration: 52.166239ms)","trace[1053777564] 'compare' (duration: 121.840046ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T10:01:53.918701Z","caller":"traceutil/trace.go:171","msg":"trace[1558216360] transaction","detail":"{read_only:false; response_revision:5102; number_of_response:1; }","duration":"104.328179ms","start":"2026-03-13T10:01:53.814349Z","end":"2026-03-13T10:01:53.918677Z","steps":["trace[1558216360] 'process raft request' (duration: 92.296497ms)","trace[1558216360] 'compare' (duration: 11.792596ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T10:02:04.0636Z","caller":"traceutil/trace.go:171","msg":"trace[1421291728] transaction","detail":"{read_only:false; response_revision:5252; number_of_response:1; }","duration":"130.152098ms","start":"2026-03-13T10:02:03.933386Z","end":"2026-03-13T10:02:04.063538Z","steps":["trace[1421291728] 'process raft request' (duration: 129.83497ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T10:02:04.173695Z","caller":"traceutil/trace.go:171","msg":"trace[385433000] transaction","detail":"{read_only:false; response_revision:5255; number_of_response:1; }","duration":"102.414003ms","start":"2026-03-13T10:02:04.071247Z","end":"2026-03-13T10:02:04.173661Z","steps":["trace[385433000] 'process raft request' (duration: 102.120976ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T10:02:04.173857Z","caller":"traceutil/trace.go:171","msg":"trace[1244609605] transaction","detail":"{read_only:false; response_revision:5256; number_of_response:1; }","duration":"102.240028ms","start":"2026-03-13T10:02:04.07158Z","end":"2026-03-13T10:02:04.17382Z","steps":["trace[1244609605] 'process raft request' (duration: 102.000633ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T10:02:05.176305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.883762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T10:02:05.176428Z","caller":"traceutil/trace.go:171","msg":"trace[1607169745] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:5259; }","duration":"137.145618ms","start":"2026-03-13T10:02:05.039252Z","end":"2026-03-13T10:02:05.176397Z","steps":["trace[1607169745] 'count revisions from in-memory index tree' (duration: 136.685777ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T10:02:05.379472Z","caller":"traceutil/trace.go:171","msg":"trace[1544957600] transaction","detail":"{read_only:false; response_revision:5262; number_of_response:1; }","duration":"120.320729ms","start":"2026-03-13T10:02:05.259111Z","end":"2026-03-13T10:02:05.379431Z","steps":["trace[1544957600] 'process raft request' (duration: 38.664761ms)","trace[1544957600] 'compare' (duration: 81.391101ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T10:02:05.379795Z","caller":"traceutil/trace.go:171","msg":"trace[421448680] transaction","detail":"{read_only:false; response_revision:5263; number_of_response:1; }","duration":"116.427153ms","start":"2026-03-13T10:02:05.263335Z","end":"2026-03-13T10:02:05.379762Z","steps":["trace[421448680] 'process raft request' (duration: 116.024263ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T10:02:29.629139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.551778ms","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-03-13T10:02:29.629224Z","caller":"traceutil/trace.go:171","msg":"trace[1169086893] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5404; }","duration":"116.710841ms","start":"2026-03-13T10:02:29.512498Z","end":"2026-03-13T10:02:29.629209Z","steps":["trace[1169086893] 'range keys from in-memory index tree' (duration: 116.337311ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T10:02:41.611465Z","caller":"traceutil/trace.go:171","msg":"trace[1953067869] transaction","detail":"{read_only:false; response_revision:5561; number_of_response:1; }","duration":"106.38717ms","start":"2026-03-13T10:02:41.505035Z","end":"2026-03-13T10:02:41.611422Z","steps":["trace[1953067869] 'process raft request' (duration: 64.356527ms)","trace[1953067869] 'compare' (duration: 41.821568ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T10:02:43.093393Z","caller":"traceutil/trace.go:171","msg":"trace[2065009378] linearizableReadLoop","detail":"{readStateIndex:5779; appliedIndex:5777; }","duration":"157.381111ms","start":"2026-03-13T10:02:42.935976Z","end":"2026-03-13T10:02:43.093357Z","steps":["trace[2065009378] 'read index received' (duration: 2.154312ms)","trace[2065009378] 'applied index is now lower than readState.Index' (duration: 155.225569ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T10:02:43.093584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.583095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T10:02:43.093659Z","caller":"traceutil/trace.go:171","msg":"trace[1028957090] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:5568; }","duration":"157.719748ms","start":"2026-03-13T10:02:42.935922Z","end":"2026-03-13T10:02:43.093641Z","steps":["trace[1028957090] 'agreement among raft nodes before linearized reading' (duration: 157.562835ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T10:02:43.093812Z","caller":"traceutil/trace.go:171","msg":"trace[984169198] transaction","detail":"{read_only:false; response_revision:5568; number_of_response:1; }","duration":"196.656077ms","start":"2026-03-13T10:02:42.897137Z","end":"2026-03-13T10:02:43.093793Z","steps":["trace[984169198] 'process raft request' (duration: 185.018174ms)","trace[984169198] 'compare' (duration: 10.954527ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T10:02:52.898965Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2729} {"level":"info","ts":"2026-03-13T10:02:52.994475Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2729,"took":"93.146537ms","hash":2424463995,"current-db-size-bytes":40329216,"current-db-size":"40 MB","current-db-size-in-use-bytes":30302208,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-03-13T10:02:52.994584Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2424463995,"revision":2729,"compact-revision":-1} {"level":"warn","ts":"2026-03-13T10:05:12.666657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.544547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T10:05:12.666754Z","caller":"traceutil/trace.go:171","msg":"trace[1294104513] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:6465; }","duration":"101.68406ms","start":"2026-03-13T10:05:12.565052Z","end":"2026-03-13T10:05:12.666736Z","steps":["trace[1294104513] 'agreement among raft nodes before linearized reading' (duration: 101.529657ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T10:05:12.666648Z","caller":"traceutil/trace.go:171","msg":"trace[1695721668] transaction","detail":"{read_only:false; response_revision:6465; number_of_response:1; }","duration":"105.737177ms","start":"2026-03-13T10:05:12.560863Z","end":"2026-03-13T10:05:12.666601Z","steps":["trace[1695721668] 'process raft request' (duration: 105.561943ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T10:07:52.90752Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5646} {"level":"info","ts":"2026-03-13T10:07:53.011298Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5646,"took":"102.003261ms","hash":2421614523,"current-db-size-bytes":42434560,"current-db-size":"42 MB","current-db-size-in-use-bytes":23736320,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-03-13T10:07:53.011368Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2421614523,"revision":5646,"compact-revision":2729} {"level":"info","ts":"2026-03-13T10:07:53.801645Z","caller":"traceutil/trace.go:171","msg":"trace[1918880041] transaction","detail":"{read_only:false; response_revision:7722; number_of_response:1; }","duration":"237.749634ms","start":"2026-03-13T10:07:53.563866Z","end":"2026-03-13T10:07:53.801616Z","steps":["trace[1918880041] 'process raft request' (duration: 237.544188ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T10:08:05.882196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.994939ms","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:5"} {"level":"info","ts":"2026-03-13T10:08:05.882287Z","caller":"traceutil/trace.go:171","msg":"trace[1295723394] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:7778; }","duration":"308.138172ms","start":"2026-03-13T10:08:05.574132Z","end":"2026-03-13T10:08:05.88227Z","steps":["trace[1295723394] 'count revisions from in-memory index tree' (duration: 307.824355ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T10:08:05.88233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T10:08:05.574112Z","time spent":"308.205873ms","remote":"127.0.0.1:47286","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":"info","ts":"2026-03-13T10:08:06.069256Z","caller":"traceutil/trace.go:171","msg":"trace[903735067] transaction","detail":"{read_only:false; response_revision:7779; number_of_response:1; }","duration":"183.843725ms","start":"2026-03-13T10:08:05.885395Z","end":"2026-03-13T10:08:06.069238Z","steps":["trace[903735067] 'process raft request' (duration: 183.657431ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T10:08:06.128926Z","caller":"traceutil/trace.go:171","msg":"trace[1646441492] transaction","detail":"{read_only:false; response_revision:7780; number_of_response:1; }","duration":"242.49111ms","start":"2026-03-13T10:08:05.886413Z","end":"2026-03-13T10:08:06.128904Z","steps":["trace[1646441492] 'process raft request' (duration: 199.400881ms)","trace[1646441492] 'compare' (duration: 42.991977ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T10:08:06.128956Z","caller":"traceutil/trace.go:171","msg":"trace[1707735059] linearizableReadLoop","detail":"{readStateIndex:8069; appliedIndex:8067; }","duration":"239.976894ms","start":"2026-03-13T10:08:05.888944Z","end":"2026-03-13T10:08:06.128921Z","steps":["trace[1707735059] 'read index received' (duration: 180.144652ms)","trace[1707735059] 'applied index is now lower than readState.Index' (duration: 59.827892ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T10:08:06.129359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.400433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:223817"} {"level":"info","ts":"2026-03-13T10:08:06.129386Z","caller":"traceutil/trace.go:171","msg":"trace[1099348543] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:7780; }","duration":"240.460005ms","start":"2026-03-13T10:08:05.88892Z","end":"2026-03-13T10:08:06.12938Z","steps":["trace[1099348543] 'agreement among raft nodes before linearized reading' (duration: 240.121038ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T10:08:06.769011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.918017ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T10:08:06.769328Z","caller":"traceutil/trace.go:171","msg":"trace[1651546162] transaction","detail":"{read_only:false; response_revision:7784; number_of_response:1; }","duration":"176.707034ms","start":"2026-03-13T10:08:06.592562Z","end":"2026-03-13T10:08:06.769269Z","steps":["trace[1651546162] 'process raft request' (duration: 53.392208ms)","trace[1651546162] 'compare' (duration: 122.751643ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T10:08:07.012209Z","caller":"traceutil/trace.go:171","msg":"trace[160367026] transaction","detail":"{read_only:false; response_revision:7785; number_of_response:1; }","duration":"238.102602ms","start":"2026-03-13T10:08:06.774083Z","end":"2026-03-13T10:08:07.012185Z","steps":["trace[160367026] 'process raft request' (duration: 237.959519ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T10:08:35.487417Z","caller":"traceutil/trace.go:171","msg":"trace[1239354654] linearizableReadLoop","detail":"{readStateIndex:8271; appliedIndex:8270; }","duration":"227.658549ms","start":"2026-03-13T10:08:35.259736Z","end":"2026-03-13T10:08:35.487394Z","steps":["trace[1239354654] 'read index received' (duration: 223.296721ms)","trace[1239354654] 'applied index is now lower than readState.Index' (duration: 4.361038ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T10:08:35.487528Z","caller":"traceutil/trace.go:171","msg":"trace[206929558] transaction","detail":"{read_only:false; response_revision:7974; number_of_response:1; }","duration":"244.536545ms","start":"2026-03-13T10:08:35.242978Z","end":"2026-03-13T10:08:35.487514Z","steps":["trace[206929558] 'process raft request' (duration: 240.147568ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T10:08:35.48772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.820672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-13T10:08:35.487795Z","caller":"traceutil/trace.go:171","msg":"trace[1999355110] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7974; }","duration":"218.916184ms","start":"2026-03-13T10:08:35.268863Z","end":"2026-03-13T10:08:35.487779Z","steps":["trace[1999355110] 'agreement among raft nodes before linearized reading' (duration: 218.74288ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T10:08:35.487877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.13386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-6cb9f569d7-h8xs2.189c5eb0759f1087\" ","response":"range_response_count:1 size:1135"} {"level":"info","ts":"2026-03-13T10:08:35.487916Z","caller":"traceutil/trace.go:171","msg":"trace[1822130289] range","detail":"{range_begin:/registry/events/openstack/barbican-api-6cb9f569d7-h8xs2.189c5eb0759f1087; range_end:; response_count:1; response_revision:7974; }","duration":"228.221451ms","start":"2026-03-13T10:08:35.259682Z","end":"2026-03-13T10:08:35.487904Z","steps":["trace[1822130289] 'agreement among raft nodes before linearized reading' (duration: 228.097288ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T10:08:49.482313Z","caller":"traceutil/trace.go:171","msg":"trace[9990732] linearizableReadLoop","detail":"{readStateIndex:8388; appliedIndex:8386; }","duration":"290.694713ms","start":"2026-03-13T10:08:49.191597Z","end":"2026-03-13T10:08:49.482292Z","steps":["trace[9990732] 'read index received' (duration: 20.267301ms)","trace[9990732] 'applied index is now lower than readState.Index' (duration: 270.426622ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T10:08:49.48237Z","caller":"traceutil/trace.go:171","msg":"trace[127117005] transaction","detail":"{read_only:false; response_revision:8086; number_of_response:1; }","duration":"292.312339ms","start":"2026-03-13T10:08:49.190028Z","end":"2026-03-13T10:08:49.48234Z","steps":["trace[127117005] 'process raft request' (duration: 292.133695ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T10:08:49.482484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.871337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T10:08:49.482553Z","caller":"traceutil/trace.go:171","msg":"trace[999023273] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:8086; }","duration":"290.97847ms","start":"2026-03-13T10:08:49.191561Z","end":"2026-03-13T10:08:49.482539Z","steps":["trace[999023273] 'agreement among raft nodes before linearized reading' (duration: 290.851707ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T10:08:49.482774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.397922ms","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":"info","ts":"2026-03-13T10:08:49.482879Z","caller":"traceutil/trace.go:171","msg":"trace[179317329] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8086; }","duration":"244.556596ms","start":"2026-03-13T10:08:49.238299Z","end":"2026-03-13T10:08:49.482855Z","steps":["trace[179317329] 'agreement among raft nodes before linearized reading' (duration: 244.259809ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T10:08:50.66598Z","caller":"traceutil/trace.go:171","msg":"trace[2121337283] linearizableReadLoop","detail":"{readStateIndex:8397; appliedIndex:8396; }","duration":"261.458022ms","start":"2026-03-13T10:08:50.404498Z","end":"2026-03-13T10:08:50.665956Z","steps":["trace[2121337283] 'read index received' (duration: 204.194227ms)","trace[2121337283] 'applied index is now lower than readState.Index' (duration: 57.262705ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T10:08:50.666092Z","caller":"traceutil/trace.go:171","msg":"trace[1620317642] transaction","detail":"{read_only:false; response_revision:8095; number_of_response:1; }","duration":"317.807056ms","start":"2026-03-13T10:08:50.348273Z","end":"2026-03-13T10:08:50.66608Z","steps":["trace[1620317642] 'process raft request' (duration: 260.380828ms)","trace[1620317642] 'compare' (duration: 57.205853ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T10:08:50.666196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T10:08:50.348251Z","time spent":"317.863708ms","remote":"127.0.0.1:49866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T10:08:50.666458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.036126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-w82pc\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-03-13T10:08:50.666586Z","caller":"traceutil/trace.go:171","msg":"trace[709556138] range","detail":"{range_begin:/registry/pods/openstack/keepalived-w82pc; range_end:; response_count:1; response_revision:8095; }","duration":"245.241471ms","start":"2026-03-13T10:08:50.421324Z","end":"2026-03-13T10:08:50.666566Z","steps":["trace[709556138] 'agreement among raft nodes before linearized reading' (duration: 244.992645ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T10:08:50.66701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.664213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-03-13T10:08:50.66716Z","caller":"traceutil/trace.go:171","msg":"trace[1579251606] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8095; }","duration":"171.855347ms","start":"2026-03-13T10:08:50.495288Z","end":"2026-03-13T10:08:50.667143Z","steps":["trace[1579251606] 'agreement among raft nodes before linearized reading' (duration: 171.618322ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T10:08:50.667269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.791592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-03-13T10:08:50.667303Z","caller":"traceutil/trace.go:171","msg":"trace[1584392990] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8095; }","duration":"262.882834ms","start":"2026-03-13T10:08:50.404409Z","end":"2026-03-13T10:08:50.667292Z","steps":["trace[1584392990] 'agreement among raft nodes before linearized reading' (duration: 262.807073ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T10:08:50.667194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.124516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2210803"} {"level":"info","ts":"2026-03-13T10:08:50.667683Z","caller":"traceutil/trace.go:171","msg":"trace[825160300] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:8095; }","duration":"169.625977ms","start":"2026-03-13T10:08:50.498036Z","end":"2026-03-13T10:08:50.667662Z","steps":["trace[825160300] 'agreement among raft nodes before linearized reading' (duration: 168.43923ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T10:08:51.051958Z","caller":"traceutil/trace.go:171","msg":"trace[972033133] linearizableReadLoop","detail":"{readStateIndex:8400; appliedIndex:8399; }","duration":"325.236102ms","start":"2026-03-13T10:08:50.726689Z","end":"2026-03-13T10:08:51.051925Z","steps":["trace[972033133] 'read index received' (duration: 292.748408ms)","trace[972033133] 'applied index is now lower than readState.Index' (duration: 32.482984ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T10:08:51.052136Z","caller":"traceutil/trace.go:171","msg":"trace[1715682920] transaction","detail":"{read_only:false; response_revision:8098; number_of_response:1; }","duration":"347.13505ms","start":"2026-03-13T10:08:50.704948Z","end":"2026-03-13T10:08:51.052083Z","steps":["trace[1715682920] 'process raft request' (duration: 314.553426ms)","trace[1715682920] 'compare' (duration: 32.157845ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T10:08:51.052219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.520919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3565"} {"level":"info","ts":"2026-03-13T10:08:51.052263Z","caller":"traceutil/trace.go:171","msg":"trace[285671522] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:8098; }","duration":"325.57485ms","start":"2026-03-13T10:08:50.726672Z","end":"2026-03-13T10:08:51.052247Z","steps":["trace[285671522] 'agreement among raft nodes before linearized reading' (duration: 325.374715ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T10:08:51.052305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T10:08:50.72661Z","time spent":"325.687292ms","remote":"127.0.0.1:49702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":3588,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" "} {"level":"warn","ts":"2026-03-13T10:08:51.052363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T10:08:50.704925Z","time spent":"347.307694ms","remote":"127.0.0.1:49866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T10:08:51.990421Z","caller":"traceutil/trace.go:171","msg":"trace[1180582490] linearizableReadLoop","detail":"{readStateIndex:8405; appliedIndex:8404; }","duration":"207.637173ms","start":"2026-03-13T10:08:51.782764Z","end":"2026-03-13T10:08:51.990402Z","steps":["trace[1180582490] 'read index received' (duration: 207.455589ms)","trace[1180582490] 'applied index is now lower than readState.Index' (duration: 180.854µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T10:08:51.990642Z","caller":"traceutil/trace.go:171","msg":"trace[1010474137] transaction","detail":"{read_only:false; response_revision:8102; number_of_response:1; }","duration":"220.752016ms","start":"2026-03-13T10:08:51.769881Z","end":"2026-03-13T10:08:51.990633Z","steps":["trace[1010474137] 'process raft request' (duration: 220.400228ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T10:08:51.99087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.091143ms","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-03-13T10:08:51.9909Z","caller":"traceutil/trace.go:171","msg":"trace[464048329] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8102; }","duration":"208.134114ms","start":"2026-03-13T10:08:51.782759Z","end":"2026-03-13T10:08:51.990893Z","steps":["trace[464048329] 'agreement among raft nodes before linearized reading' (duration: 208.028982ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T10:08:52.002212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.940555ms","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-03-13T10:08:52.002245Z","caller":"traceutil/trace.go:171","msg":"trace[615048576] transaction","detail":"{read_only:false; response_revision:8103; number_of_response:1; }","duration":"183.333092ms","start":"2026-03-13T10:08:51.818886Z","end":"2026-03-13T10:08:52.002219Z","steps":["trace[615048576] 'process raft request' (duration: 183.159978ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T10:08:52.002274Z","caller":"traceutil/trace.go:171","msg":"trace[257398972] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8103; }","duration":"219.027578ms","start":"2026-03-13T10:08:51.783235Z","end":"2026-03-13T10:08:52.002262Z","steps":["trace[257398972] 'agreement among raft nodes before linearized reading' (duration: 218.890615ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T10:09:02.648356Z","caller":"traceutil/trace.go:171","msg":"trace[357117537] transaction","detail":"{read_only:false; response_revision:8190; number_of_response:1; }","duration":"114.51384ms","start":"2026-03-13T10:09:02.53382Z","end":"2026-03-13T10:09:02.648334Z","steps":["trace[357117537] 'process raft request' (duration: 114.258994ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T10:09:49.831026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.02133ms","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-03-13T10:09:49.83113Z","caller":"traceutil/trace.go:171","msg":"trace[2101032719] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:8690; }","duration":"145.135683ms","start":"2026-03-13T10:09:49.68598Z","end":"2026-03-13T10:09:49.831116Z","steps":["trace[2101032719] 'range keys from in-memory index tree' (duration: 145.005119ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T10:09:49.831124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.782234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T10:09:49.831214Z","caller":"traceutil/trace.go:171","msg":"trace[1186376633] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8690; }","duration":"127.932086ms","start":"2026-03-13T10:09:49.703262Z","end":"2026-03-13T10:09:49.831194Z","steps":["trace[1186376633] 'range keys from in-memory index tree' (duration: 126.448244ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T10:09:50.27102Z","caller":"traceutil/trace.go:171","msg":"trace[1683233762] linearizableReadLoop","detail":"{readStateIndex:9011; appliedIndex:9010; }","duration":"264.16805ms","start":"2026-03-13T10:09:50.006809Z","end":"2026-03-13T10:09:50.270977Z","steps":["trace[1683233762] 'read index received' (duration: 263.952355ms)","trace[1683233762] 'applied index is now lower than readState.Index' (duration: 213.525µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T10:09:50.271148Z","caller":"traceutil/trace.go:171","msg":"trace[194160367] transaction","detail":"{read_only:false; response_revision:8692; number_of_response:1; }","duration":"368.777374ms","start":"2026-03-13T10:09:49.902315Z","end":"2026-03-13T10:09:50.271092Z","steps":["trace[194160367] 'process raft request' (duration: 368.387616ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T10:09:50.271377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T10:09:49.902295Z","time spent":"368.972659ms","remote":"127.0.0.1:49686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":834,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-13T10:09:50.271482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.632782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-13T10:09:50.27168Z","caller":"traceutil/trace.go:171","msg":"trace[1326174023] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:8692; }","duration":"264.903748ms","start":"2026-03-13T10:09:50.006743Z","end":"2026-03-13T10:09:50.271647Z","steps":["trace[1326174023] 'agreement among raft nodes before linearized reading' (duration: 264.413306ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T10:12:12.990846Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3e4829a3cabe4fce","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-13T10:12:12.995873Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-03-13T10:12:12.996006Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-03-13T10:12:52.913299Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7718} {"level":"info","ts":"2026-03-13T10:12:52.974054Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7718,"took":"58.522949ms","hash":1315003659,"current-db-size-bytes":42434560,"current-db-size":"42 MB","current-db-size-in-use-bytes":26644480,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-03-13T10:12:52.974112Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1315003659,"revision":7718,"compact-revision":5646} {"level":"info","ts":"2026-03-13T10:17:52.917942Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9966} {"level":"info","ts":"2026-03-13T10:17:52.991438Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9966,"took":"71.861612ms","hash":2718856521,"current-db-size-bytes":42434560,"current-db-size":"42 MB","current-db-size-in-use-bytes":17240064,"current-db-size-in-use":"17 MB"} {"level":"info","ts":"2026-03-13T10:17:52.991491Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2718856521,"revision":9966,"compact-revision":7718}