{"level":"warn","ts":"2026-04-15T04:03:00.956866Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-15T04:03:00.957014Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.53:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.53:2380","--initial-cluster=instance=https://199.204.45.53:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.53:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.53: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-04-15T04:03:00.95738Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-15T04:03:00.957398Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-04-15T04:03:00.957541Z","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-04-15T04:03:00.958392Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"]} {"level":"info","ts":"2026-04-15T04:03:00.958589Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.53: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-04-15T04:03:00.963266Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.308237ms"} {"level":"info","ts":"2026-04-15T04:03:00.971113Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c47ca1a7b1fe506b","cluster-id":"95bd21e158fabf07"} {"level":"info","ts":"2026-04-15T04:03:00.971421Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=()"} {"level":"info","ts":"2026-04-15T04:03:00.971542Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 0"} {"level":"info","ts":"2026-04-15T04:03:00.971609Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c47ca1a7b1fe506b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-15T04:03:00.971777Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 1"} {"level":"info","ts":"2026-04-15T04:03:00.972036Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"warn","ts":"2026-04-15T04:03:00.979766Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-15T04:03:00.983053Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-15T04:03:00.984627Z","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-04-15T04:03:00.98694Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c47ca1a7b1fe506b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-15T04:03:00.987205Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c47ca1a7b1fe506b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-15T04:03:00.987288Z","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-04-15T04:03:00.987424Z","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-04-15T04:03:00.987453Z","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-04-15T04:03:00.988079Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"info","ts":"2026-04-15T04:03:00.98823Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","added-peer-id":"c47ca1a7b1fe506b","added-peer-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-04-15T04:03:00.990727Z","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-04-15T04:03:00.99084Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-04-15T04:03:00.990899Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-04-15T04:03:00.991231Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c47ca1a7b1fe506b","initial-advertise-peer-urls":["https://199.204.45.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-15T04:03:00.991299Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-15T04:03:01.373201Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b is starting a new election at term 1"} {"level":"info","ts":"2026-04-15T04:03:01.37329Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became pre-candidate at term 1"} {"level":"info","ts":"2026-04-15T04:03:01.373395Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgPreVoteResp from c47ca1a7b1fe506b at term 1"} {"level":"info","ts":"2026-04-15T04:03:01.373433Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became candidate at term 2"} {"level":"info","ts":"2026-04-15T04:03:01.373488Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgVoteResp from c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-04-15T04:03:01.373555Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became leader at term 2"} {"level":"info","ts":"2026-04-15T04:03:01.373584Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c47ca1a7b1fe506b elected leader c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-04-15T04:03:01.374857Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-15T04:03:01.375872Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c47ca1a7b1fe506b","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.53:2379]}","request-path":"/0/members/c47ca1a7b1fe506b/attributes","cluster-id":"95bd21e158fabf07","publish-timeout":"7s"} {"level":"info","ts":"2026-04-15T04:03:01.375879Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-15T04:03:01.375956Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-15T04:03:01.376418Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-15T04:03:01.376525Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-15T04:03:01.378084Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-15T04:03:01.379477Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.53:2379"} {"level":"info","ts":"2026-04-15T04:03:01.383293Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","cluster-version":"3.5"} {"level":"info","ts":"2026-04-15T04:03:01.383393Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-15T04:03:01.383419Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-15T04:03:27.476973Z","caller":"traceutil/trace.go:171","msg":"trace[1086666275] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"128.837301ms","start":"2026-04-15T04:03:27.348113Z","end":"2026-04-15T04:03:27.476951Z","steps":["trace[1086666275] 'process raft request' (duration: 128.709219ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:03:27.889802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.585392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T04:03:27.889949Z","caller":"traceutil/trace.go:171","msg":"trace[355997814] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:503; }","duration":"170.795587ms","start":"2026-04-15T04:03:27.719132Z","end":"2026-04-15T04:03:27.889927Z","steps":["trace[355997814] 'range keys from in-memory index tree' (duration: 170.47774ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:03:34.764413Z","caller":"traceutil/trace.go:171","msg":"trace[209655266] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"416.833911ms","start":"2026-04-15T04:03:34.347557Z","end":"2026-04-15T04:03:34.764391Z","steps":["trace[209655266] 'process raft request' (duration: 416.543413ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:03:34.764839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T04:03:34.34753Z","time spent":"416.943614ms","remote":"127.0.0.1:53274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-15T04:04:29.480393Z","caller":"traceutil/trace.go:171","msg":"trace[123652390] linearizableReadLoop","detail":"{readStateIndex:1038; appliedIndex:1037; }","duration":"113.505379ms","start":"2026-04-15T04:04:29.366857Z","end":"2026-04-15T04:04:29.480363Z","steps":["trace[123652390] 'read index received' (duration: 113.300128ms)","trace[123652390] 'applied index is now lower than readState.Index' (duration: 203.961µs)"],"step_count":2} {"level":"warn","ts":"2026-04-15T04:04:29.480557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.679358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-15T04:04:29.480475Z","caller":"traceutil/trace.go:171","msg":"trace[626340751] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"128.024223ms","start":"2026-04-15T04:04:29.352393Z","end":"2026-04-15T04:04:29.480417Z","steps":["trace[626340751] 'process raft request' (duration: 127.824312ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:04:29.480607Z","caller":"traceutil/trace.go:171","msg":"trace[2055538568] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1005; }","duration":"113.751181ms","start":"2026-04-15T04:04:29.366839Z","end":"2026-04-15T04:04:29.48059Z","steps":["trace[2055538568] 'agreement among raft nodes before linearized reading' (duration: 113.637766ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:05:01.478191Z","caller":"traceutil/trace.go:171","msg":"trace[1124420735] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"301.898371ms","start":"2026-04-15T04:05:01.176267Z","end":"2026-04-15T04:05:01.478166Z","steps":["trace[1124420735] 'process raft request' (duration: 301.632523ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:05:01.478547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T04:05:01.176251Z","time spent":"302.0193ms","remote":"127.0.0.1:53022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T04:05:18.298293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.649268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-15T04:05:18.298426Z","caller":"traceutil/trace.go:171","msg":"trace[565629386] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1165; }","duration":"104.802318ms","start":"2026-04-15T04:05:18.193577Z","end":"2026-04-15T04:05:18.298379Z","steps":["trace[565629386] 'range keys from in-memory index tree' (duration: 104.474806ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:05:18.298526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.772536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T04:05:18.298671Z","caller":"traceutil/trace.go:171","msg":"trace[1440946923] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1165; }","duration":"126.989341ms","start":"2026-04-15T04:05:18.171652Z","end":"2026-04-15T04:05:18.298641Z","steps":["trace[1440946923] 'range keys from in-memory index tree' (duration: 126.654018ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:09:48.226989Z","caller":"traceutil/trace.go:171","msg":"trace[980659841] linearizableReadLoop","detail":"{readStateIndex:2638; appliedIndex:2637; }","duration":"118.184212ms","start":"2026-04-15T04:09:48.108787Z","end":"2026-04-15T04:09:48.226971Z","steps":["trace[980659841] 'read index received' (duration: 118.028738ms)","trace[980659841] 'applied index is now lower than readState.Index' (duration: 154.824µs)"],"step_count":2} {"level":"info","ts":"2026-04-15T04:09:48.22709Z","caller":"traceutil/trace.go:171","msg":"trace[1463980266] transaction","detail":"{read_only:false; response_revision:2536; number_of_response:1; }","duration":"131.370113ms","start":"2026-04-15T04:09:48.095681Z","end":"2026-04-15T04:09:48.227052Z","steps":["trace[1463980266] 'process raft request' (duration: 131.178198ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:09:48.227179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.373898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-15T04:09:48.227209Z","caller":"traceutil/trace.go:171","msg":"trace[1785505362] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:2536; }","duration":"118.462041ms","start":"2026-04-15T04:09:48.10874Z","end":"2026-04-15T04:09:48.227202Z","steps":["trace[1785505362] 'agreement among raft nodes before linearized reading' (duration: 118.306976ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:09:50.341442Z","caller":"traceutil/trace.go:171","msg":"trace[2104074218] transaction","detail":"{read_only:false; response_revision:2565; number_of_response:1; }","duration":"105.805885ms","start":"2026-04-15T04:09:50.235612Z","end":"2026-04-15T04:09:50.341418Z","steps":["trace[2104074218] 'process raft request' (duration: 105.62248ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:09:51.247298Z","caller":"traceutil/trace.go:171","msg":"trace[1909546776] linearizableReadLoop","detail":"{readStateIndex:2671; appliedIndex:2670; }","duration":"225.689246ms","start":"2026-04-15T04:09:51.021582Z","end":"2026-04-15T04:09:51.247272Z","steps":["trace[1909546776] 'read index received' (duration: 225.509701ms)","trace[1909546776] 'applied index is now lower than readState.Index' (duration: 178.105µs)"],"step_count":2} {"level":"warn","ts":"2026-04-15T04:09:51.247464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.868161ms","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-04-15T04:09:51.247422Z","caller":"traceutil/trace.go:171","msg":"trace[1716150536] transaction","detail":"{read_only:false; response_revision:2567; number_of_response:1; }","duration":"297.448203ms","start":"2026-04-15T04:09:50.949931Z","end":"2026-04-15T04:09:51.24738Z","steps":["trace[1716150536] 'process raft request' (duration: 297.196546ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:09:51.247498Z","caller":"traceutil/trace.go:171","msg":"trace[1246080317] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2567; }","duration":"225.942383ms","start":"2026-04-15T04:09:51.021547Z","end":"2026-04-15T04:09:51.247489Z","steps":["trace[1246080317] 'agreement among raft nodes before linearized reading' (duration: 225.82834ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:09:53.424937Z","caller":"traceutil/trace.go:171","msg":"trace[454929855] transaction","detail":"{read_only:false; response_revision:2581; number_of_response:1; }","duration":"156.316885ms","start":"2026-04-15T04:09:53.268604Z","end":"2026-04-15T04:09:53.424921Z","steps":["trace[454929855] 'process raft request' (duration: 156.082558ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:09:53.424923Z","caller":"traceutil/trace.go:171","msg":"trace[1679307839] linearizableReadLoop","detail":"{readStateIndex:2685; appliedIndex:2684; }","duration":"135.670514ms","start":"2026-04-15T04:09:53.289225Z","end":"2026-04-15T04:09:53.424895Z","steps":["trace[1679307839] 'read index received' (duration: 135.374846ms)","trace[1679307839] 'applied index is now lower than readState.Index' (duration: 294.778µs)"],"step_count":2} {"level":"warn","ts":"2026-04-15T04:09:53.4251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.86332ms","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-04-15T04:09:53.425149Z","caller":"traceutil/trace.go:171","msg":"trace[2125458134] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2581; }","duration":"135.964443ms","start":"2026-04-15T04:09:53.289172Z","end":"2026-04-15T04:09:53.425137Z","steps":["trace[2125458134] 'agreement among raft nodes before linearized reading' (duration: 135.809748ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:10:00.826951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.897373ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-15T04:10:00.827043Z","caller":"traceutil/trace.go:171","msg":"trace[675296345] linearizableReadLoop","detail":"{readStateIndex:2869; appliedIndex:2868; }","duration":"150.847751ms","start":"2026-04-15T04:10:00.676184Z","end":"2026-04-15T04:10:00.827031Z","steps":["trace[675296345] 'read index received' (duration: 34.481µs)","trace[675296345] 'applied index is now lower than readState.Index' (duration: 150.81253ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T04:10:00.827091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.905153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T04:10:00.827108Z","caller":"traceutil/trace.go:171","msg":"trace[355564458] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:2762; }","duration":"150.959235ms","start":"2026-04-15T04:10:00.676143Z","end":"2026-04-15T04:10:00.827102Z","steps":["trace[355564458] 'agreement among raft nodes before linearized reading' (duration: 150.922014ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:10:00.827214Z","caller":"traceutil/trace.go:171","msg":"trace[347166834] transaction","detail":"{read_only:false; response_revision:2762; number_of_response:1; }","duration":"235.223814ms","start":"2026-04-15T04:10:00.59196Z","end":"2026-04-15T04:10:00.827184Z","steps":["trace[347166834] 'process raft request' (duration: 14.92989ms)","trace[347166834] 'compare' (duration: 219.79298ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T04:10:16.613734Z","caller":"traceutil/trace.go:171","msg":"trace[618371562] transaction","detail":"{read_only:false; response_revision:2855; number_of_response:1; }","duration":"148.921165ms","start":"2026-04-15T04:10:16.464788Z","end":"2026-04-15T04:10:16.61371Z","steps":["trace[618371562] 'process raft request' (duration: 148.77131ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:10:17.334416Z","caller":"traceutil/trace.go:171","msg":"trace[1955454802] linearizableReadLoop","detail":"{readStateIndex:2968; appliedIndex:2967; }","duration":"160.447614ms","start":"2026-04-15T04:10:17.173943Z","end":"2026-04-15T04:10:17.33439Z","steps":["trace[1955454802] 'read index received' (duration: 160.260149ms)","trace[1955454802] 'applied index is now lower than readState.Index' (duration: 186.325µs)"],"step_count":2} {"level":"info","ts":"2026-04-15T04:10:17.334459Z","caller":"traceutil/trace.go:171","msg":"trace[1816179721] transaction","detail":"{read_only:false; response_revision:2858; number_of_response:1; }","duration":"182.797663ms","start":"2026-04-15T04:10:17.15164Z","end":"2026-04-15T04:10:17.334438Z","steps":["trace[1816179721] 'process raft request' (duration: 182.529776ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:10:17.334535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.571897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T04:10:17.334564Z","caller":"traceutil/trace.go:171","msg":"trace[1554729897] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2858; }","duration":"160.66635ms","start":"2026-04-15T04:10:17.173889Z","end":"2026-04-15T04:10:17.334555Z","steps":["trace[1554729897] 'agreement among raft nodes before linearized reading' (duration: 160.597308ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:10:18.775392Z","caller":"traceutil/trace.go:171","msg":"trace[105087113] transaction","detail":"{read_only:false; response_revision:2864; number_of_response:1; }","duration":"153.121042ms","start":"2026-04-15T04:10:18.622245Z","end":"2026-04-15T04:10:18.775366Z","steps":["trace[105087113] 'process raft request' (duration: 152.958748ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:10:35.696016Z","caller":"traceutil/trace.go:171","msg":"trace[831439262] transaction","detail":"{read_only:false; response_revision:3328; number_of_response:1; }","duration":"108.842195ms","start":"2026-04-15T04:10:35.587138Z","end":"2026-04-15T04:10:35.69598Z","steps":["trace[831439262] 'process raft request' (duration: 108.740302ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:10:35.696051Z","caller":"traceutil/trace.go:171","msg":"trace[350906176] transaction","detail":"{read_only:false; response_revision:3327; number_of_response:1; }","duration":"108.948348ms","start":"2026-04-15T04:10:35.587067Z","end":"2026-04-15T04:10:35.696015Z","steps":["trace[350906176] 'process raft request' (duration: 108.715901ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:10:35.696109Z","caller":"traceutil/trace.go:171","msg":"trace[1282198293] transaction","detail":"{read_only:false; response_revision:3330; number_of_response:1; }","duration":"108.564697ms","start":"2026-04-15T04:10:35.587497Z","end":"2026-04-15T04:10:35.696062Z","steps":["trace[1282198293] 'process raft request' (duration: 108.421833ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:10:35.696129Z","caller":"traceutil/trace.go:171","msg":"trace[1144872807] transaction","detail":"{read_only:false; response_revision:3329; number_of_response:1; }","duration":"108.906337ms","start":"2026-04-15T04:10:35.587184Z","end":"2026-04-15T04:10:35.696091Z","steps":["trace[1144872807] 'process raft request' (duration: 108.717191ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:10:35.69627Z","caller":"traceutil/trace.go:171","msg":"trace[1884945173] transaction","detail":"{read_only:false; response_revision:3331; number_of_response:1; }","duration":"108.695541ms","start":"2026-04-15T04:10:35.587554Z","end":"2026-04-15T04:10:35.69625Z","steps":["trace[1884945173] 'process raft request' (duration: 108.383482ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:10:41.447295Z","caller":"traceutil/trace.go:171","msg":"trace[475811101] transaction","detail":"{read_only:false; response_revision:3479; number_of_response:1; }","duration":"158.195232ms","start":"2026-04-15T04:10:41.289069Z","end":"2026-04-15T04:10:41.447264Z","steps":["trace[475811101] 'process raft request' (duration: 123.654495ms)","trace[475811101] 'compare' (duration: 34.335392ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T04:10:41.518263Z","caller":"traceutil/trace.go:171","msg":"trace[1432034557] linearizableReadLoop","detail":"{readStateIndex:3629; appliedIndex:3626; }","duration":"211.058655ms","start":"2026-04-15T04:10:41.307177Z","end":"2026-04-15T04:10:41.518236Z","steps":["trace[1432034557] 'read index received' (duration: 105.641685ms)","trace[1432034557] 'applied index is now lower than readState.Index' (duration: 105.41586ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T04:10:41.518316Z","caller":"traceutil/trace.go:171","msg":"trace[1587503641] transaction","detail":"{read_only:false; response_revision:3481; number_of_response:1; }","duration":"224.041606ms","start":"2026-04-15T04:10:41.294238Z","end":"2026-04-15T04:10:41.518279Z","steps":["trace[1587503641] 'process raft request' (duration: 223.890671ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:10:41.51833Z","caller":"traceutil/trace.go:171","msg":"trace[1590204948] transaction","detail":"{read_only:false; response_revision:3480; number_of_response:1; }","duration":"229.104076ms","start":"2026-04-15T04:10:41.289194Z","end":"2026-04-15T04:10:41.518298Z","steps":["trace[1590204948] 'process raft request' (duration: 228.775617ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:10:41.518651Z","caller":"traceutil/trace.go:171","msg":"trace[1235401584] transaction","detail":"{read_only:false; response_revision:3482; number_of_response:1; }","duration":"172.445145ms","start":"2026-04-15T04:10:41.346193Z","end":"2026-04-15T04:10:41.518638Z","steps":["trace[1235401584] 'process raft request' (duration: 171.986943ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:10:41.51874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.547188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T04:10:41.518774Z","caller":"traceutil/trace.go:171","msg":"trace[1981587558] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:3482; }","duration":"211.625331ms","start":"2026-04-15T04:10:41.307138Z","end":"2026-04-15T04:10:41.518764Z","steps":["trace[1981587558] 'agreement among raft nodes before linearized reading' (duration: 211.548559ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:10:41.544066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.710619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-15T04:10:41.544083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.133254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/openstack/libvirt-api-ca\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-15T04:10:41.54412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.305716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/openstack/libvirt-vnc-ca\" ","response":"range_response_count:1 size:2429"} {"level":"info","ts":"2026-04-15T04:10:41.544152Z","caller":"traceutil/trace.go:171","msg":"trace[1063564937] range","detail":"{range_begin:/registry/cert-manager.io/certificates/openstack/libvirt-api-ca; range_end:; response_count:0; response_revision:3482; }","duration":"191.258678ms","start":"2026-04-15T04:10:41.352878Z","end":"2026-04-15T04:10:41.544136Z","steps":["trace[1063564937] 'agreement among raft nodes before linearized reading' (duration: 191.094843ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:10:41.544162Z","caller":"traceutil/trace.go:171","msg":"trace[151561942] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:0; response_revision:3482; }","duration":"235.856533ms","start":"2026-04-15T04:10:41.30827Z","end":"2026-04-15T04:10:41.544127Z","steps":["trace[151561942] 'agreement among raft nodes before linearized reading' (duration: 235.716729ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:10:41.544167Z","caller":"traceutil/trace.go:171","msg":"trace[1477366065] range","detail":"{range_begin:/registry/cert-manager.io/certificates/openstack/libvirt-vnc-ca; range_end:; response_count:1; response_revision:3482; }","duration":"149.400628ms","start":"2026-04-15T04:10:41.394754Z","end":"2026-04-15T04:10:41.544154Z","steps":["trace[1477366065] 'agreement among raft nodes before linearized reading' (duration: 149.302086ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:10:41.544231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.933675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-15T04:10:41.544243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.024115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T04:10:41.544308Z","caller":"traceutil/trace.go:171","msg":"trace[1533285569] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/; range_end:/registry/pxc.percona.com/perconaxtradbclusters/openstack0; response_count:0; response_revision:3482; }","duration":"236.041168ms","start":"2026-04-15T04:10:41.308247Z","end":"2026-04-15T04:10:41.544288Z","steps":["trace[1533285569] 'agreement among raft nodes before linearized reading' (duration: 235.864553ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:10:41.544313Z","caller":"traceutil/trace.go:171","msg":"trace[1175403222] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:3482; }","duration":"237.111787ms","start":"2026-04-15T04:10:41.307185Z","end":"2026-04-15T04:10:41.544297Z","steps":["trace[1175403222] 'agreement among raft nodes before linearized reading' (duration: 236.999254ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:10:42.215998Z","caller":"traceutil/trace.go:171","msg":"trace[1963938261] transaction","detail":"{read_only:false; response_revision:3490; number_of_response:1; }","duration":"616.951948ms","start":"2026-04-15T04:10:41.599012Z","end":"2026-04-15T04:10:42.215964Z","steps":["trace[1963938261] 'process raft request' (duration: 518.157812ms)","trace[1963938261] 'compare' (duration: 98.560299ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T04:10:42.216054Z","caller":"traceutil/trace.go:171","msg":"trace[1603844371] transaction","detail":"{read_only:false; number_of_response:1; response_revision:3491; }","duration":"607.29451ms","start":"2026-04-15T04:10:41.608694Z","end":"2026-04-15T04:10:42.215989Z","steps":["trace[1603844371] 'process raft request' (duration: 607.253009ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:10:42.216122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T04:10:41.599001Z","time spent":"617.064291ms","remote":"127.0.0.1:53022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-15T04:10:42.216202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T04:10:41.608688Z","time spent":"607.430194ms","remote":"127.0.0.1:46318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":67,"response count":0,"response size":1570,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T04:10:42.216326Z","caller":"traceutil/trace.go:171","msg":"trace[307021789] transaction","detail":"{read_only:false; response_revision:3491; number_of_response:1; }","duration":"608.422091ms","start":"2026-04-15T04:10:41.607892Z","end":"2026-04-15T04:10:42.216314Z","steps":["trace[307021789] 'process raft request' (duration: 607.99295ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:10:42.2164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T04:10:41.607865Z","time spent":"608.491634ms","remote":"127.0.0.1:46318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T04:10:42.247878Z","caller":"traceutil/trace.go:171","msg":"trace[823016972] linearizableReadLoop","detail":"{readStateIndex:3642; appliedIndex:3638; }","duration":"508.454803ms","start":"2026-04-15T04:10:41.739395Z","end":"2026-04-15T04:10:42.24785Z","steps":["trace[823016972] 'read index received' (duration: 377.803405ms)","trace[823016972] 'applied index is now lower than readState.Index' (duration: 130.650268ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T04:10:42.248194Z","caller":"traceutil/trace.go:171","msg":"trace[504259882] transaction","detail":"{read_only:false; response_revision:3492; number_of_response:1; }","duration":"575.738917ms","start":"2026-04-15T04:10:41.672443Z","end":"2026-04-15T04:10:42.248182Z","steps":["trace[504259882] 'process raft request' (duration: 575.271223ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:10:42.2483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T04:10:41.672401Z","time spent":"575.829029ms","remote":"127.0.0.1:53022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T04:10:42.248561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.175033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-15T04:10:42.248601Z","caller":"traceutil/trace.go:171","msg":"trace[1276398813] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3492; }","duration":"509.249665ms","start":"2026-04-15T04:10:41.739339Z","end":"2026-04-15T04:10:42.248589Z","steps":["trace[1276398813] 'agreement among raft nodes before linearized reading' (duration: 509.133012ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:10:42.248633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T04:10:41.739317Z","time spent":"509.310727ms","remote":"127.0.0.1:53022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-15T04:10:42.312383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.225686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-04-15T04:10:42.31241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.509605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-15T04:10:42.312448Z","caller":"traceutil/trace.go:171","msg":"trace[1943618638] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:3495; }","duration":"165.325129ms","start":"2026-04-15T04:10:42.147108Z","end":"2026-04-15T04:10:42.312433Z","steps":["trace[1943618638] 'agreement among raft nodes before linearized reading' (duration: 165.162134ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:10:42.312468Z","caller":"traceutil/trace.go:171","msg":"trace[158784792] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3495; }","duration":"443.605918ms","start":"2026-04-15T04:10:41.868848Z","end":"2026-04-15T04:10:42.312454Z","steps":["trace[158784792] 'agreement among raft nodes before linearized reading' (duration: 443.429132ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:10:42.3125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T04:10:41.868831Z","time spent":"443.663079ms","remote":"127.0.0.1:53022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-15T04:10:42.312475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.72647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-nl4sz\" ","response":"range_response_count:1 size:6447"} {"level":"warn","ts":"2026-04-15T04:10:42.312586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.02161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-15T04:10:42.312597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.745479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-698b0354-eaef-40c1-b2fe-ec68d545df13\" ","response":"range_response_count:1 size:3717"} {"level":"info","ts":"2026-04-15T04:10:42.312616Z","caller":"traceutil/trace.go:171","msg":"trace[119441846] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3495; }","duration":"139.070712ms","start":"2026-04-15T04:10:42.173538Z","end":"2026-04-15T04:10:42.312609Z","steps":["trace[119441846] 'agreement among raft nodes before linearized reading' (duration: 139.023411ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:10:42.312602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.156092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:14 size:323442"} {"level":"info","ts":"2026-04-15T04:10:42.31266Z","caller":"traceutil/trace.go:171","msg":"trace[440638028] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-698b0354-eaef-40c1-b2fe-ec68d545df13; range_end:; response_count:1; response_revision:3495; }","duration":"565.831862ms","start":"2026-04-15T04:10:41.746807Z","end":"2026-04-15T04:10:42.312639Z","steps":["trace[440638028] 'agreement among raft nodes before linearized reading' (duration: 565.684138ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:10:42.312668Z","caller":"traceutil/trace.go:171","msg":"trace[2074552699] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:14; response_revision:3495; }","duration":"357.228765ms","start":"2026-04-15T04:10:41.955424Z","end":"2026-04-15T04:10:42.312652Z","steps":["trace[2074552699] 'agreement among raft nodes before linearized reading' (duration: 356.892646ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:10:42.312707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T04:10:41.746795Z","time spent":"565.902254ms","remote":"127.0.0.1:52930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3741,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-698b0354-eaef-40c1-b2fe-ec68d545df13\" "} {"level":"warn","ts":"2026-04-15T04:10:42.312721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T04:10:41.955373Z","time spent":"357.336248ms","remote":"127.0.0.1:52842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":14,"response size":323466,"request content":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" "} {"level":"info","ts":"2026-04-15T04:10:42.312545Z","caller":"traceutil/trace.go:171","msg":"trace[90801789] range","detail":"{range_begin:/registry/pods/openstack/keepalived-nl4sz; range_end:; response_count:1; response_revision:3495; }","duration":"226.836493ms","start":"2026-04-15T04:10:42.085691Z","end":"2026-04-15T04:10:42.312528Z","steps":["trace[90801789] 'agreement among raft nodes before linearized reading' (duration: 226.615857ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:10:42.313035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.947324ms","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-04-15T04:10:42.312771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.990593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-15T04:10:42.313129Z","caller":"traceutil/trace.go:171","msg":"trace[1509144739] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3495; }","duration":"571.639273ms","start":"2026-04-15T04:10:41.741477Z","end":"2026-04-15T04:10:42.313117Z","steps":["trace[1509144739] 'agreement among raft nodes before linearized reading' (duration: 570.866682ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:10:42.313164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T04:10:41.741463Z","time spent":"571.692904ms","remote":"127.0.0.1:53022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-15T04:10:42.313157Z","caller":"traceutil/trace.go:171","msg":"trace[2027394992] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3495; }","duration":"572.496678ms","start":"2026-04-15T04:10:41.740641Z","end":"2026-04-15T04:10:42.313138Z","steps":["trace[2027394992] 'agreement among raft nodes before linearized reading' (duration: 571.797979ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:10:42.313217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T04:10:41.740633Z","time spent":"572.571359ms","remote":"127.0.0.1:53022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-15T04:10:42.624109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.927912ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"warn","ts":"2026-04-15T04:10:42.624384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.331139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T04:10:42.624471Z","caller":"traceutil/trace.go:171","msg":"trace[476084929] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:3496; }","duration":"369.444153ms","start":"2026-04-15T04:10:42.255009Z","end":"2026-04-15T04:10:42.624453Z","steps":["trace[476084929] 'agreement among raft nodes before linearized reading' (duration: 369.303239ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:10:42.62452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T04:10:42.255002Z","time spent":"369.507304ms","remote":"127.0.0.1:42384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true "} {"level":"info","ts":"2026-04-15T04:10:42.624223Z","caller":"traceutil/trace.go:171","msg":"trace[155445773] linearizableReadLoop","detail":"{readStateIndex:3647; appliedIndex:3646; }","duration":"312.030223ms","start":"2026-04-15T04:10:42.312177Z","end":"2026-04-15T04:10:42.624207Z","steps":["trace[155445773] 'read index received' (duration: 13.904155ms)","trace[155445773] 'applied index is now lower than readState.Index' (duration: 298.124788ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T04:10:42.624615Z","caller":"traceutil/trace.go:171","msg":"trace[595175013] transaction","detail":"{read_only:false; response_revision:3496; number_of_response:1; }","duration":"370.678156ms","start":"2026-04-15T04:10:42.253902Z","end":"2026-04-15T04:10:42.62458Z","steps":["trace[595175013] 'process raft request' (duration: 72.168748ms)","trace[595175013] 'compare' (duration: 297.778038ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T04:10:42.624785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T04:10:42.25389Z","time spent":"370.804659ms","remote":"127.0.0.1:53022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T04:10:42.624871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.537795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/openstack/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/openstack0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T04:10:42.625116Z","caller":"traceutil/trace.go:171","msg":"trace[1555173691] transaction","detail":"{read_only:false; response_revision:3497; number_of_response:1; }","duration":"309.676657ms","start":"2026-04-15T04:10:42.315425Z","end":"2026-04-15T04:10:42.625102Z","steps":["trace[1555173691] 'process raft request' (duration: 308.910656ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:10:42.625181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T04:10:42.315404Z","time spent":"309.752338ms","remote":"127.0.0.1:52834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":854,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-15T04:10:42.625135Z","caller":"traceutil/trace.go:171","msg":"trace[1021107916] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/openstack/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups/openstack0; response_count:0; response_revision:3496; }","duration":"369.903105ms","start":"2026-04-15T04:10:42.255205Z","end":"2026-04-15T04:10:42.625108Z","steps":["trace[1021107916] 'agreement among raft nodes before linearized reading' (duration: 369.026621ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:10:42.625303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.919703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T04:10:42.625352Z","caller":"traceutil/trace.go:171","msg":"trace[643628056] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3503; }","duration":"309.988675ms","start":"2026-04-15T04:10:42.315351Z","end":"2026-04-15T04:10:42.62534Z","steps":["trace[643628056] 'agreement among raft nodes before linearized reading' (duration: 309.909293ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:10:42.625321Z","caller":"traceutil/trace.go:171","msg":"trace[1306238857] transaction","detail":"{read_only:false; response_revision:3498; number_of_response:1; }","duration":"309.80419ms","start":"2026-04-15T04:10:42.315481Z","end":"2026-04-15T04:10:42.625285Z","steps":["trace[1306238857] 'process raft request' (duration: 309.367308ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:10:42.625391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T04:10:42.315334Z","time spent":"310.050026ms","remote":"127.0.0.1:52764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-15T04:10:42.625554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T04:10:42.315453Z","time spent":"309.948574ms","remote":"127.0.0.1:52834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":731,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-15T04:10:42.625585Z","caller":"traceutil/trace.go:171","msg":"trace[173633477] transaction","detail":"{read_only:false; response_revision:3499; number_of_response:1; }","duration":"308.310029ms","start":"2026-04-15T04:10:42.317265Z","end":"2026-04-15T04:10:42.625575Z","steps":["trace[173633477] 'process raft request' (duration: 307.815895ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:10:42.625636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T04:10:42.317251Z","time spent":"308.35912ms","remote":"127.0.0.1:52834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-15T04:10:42.625819Z","caller":"traceutil/trace.go:171","msg":"trace[1535114136] transaction","detail":"{read_only:false; response_revision:3500; number_of_response:1; }","duration":"308.084963ms","start":"2026-04-15T04:10:42.317709Z","end":"2026-04-15T04:10:42.625794Z","steps":["trace[1535114136] 'process raft request' (duration: 307.406904ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:10:42.625962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T04:10:42.3177Z","time spent":"308.149725ms","remote":"127.0.0.1:53022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":542,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T04:10:42.625299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T04:10:42.255199Z","time spent":"370.040018ms","remote":"127.0.0.1:42384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":135,"response count":0,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/openstack/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/openstack0\" limit:500 "} {"level":"warn","ts":"2026-04-15T04:10:42.626327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.15446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:7 size:47938"} {"level":"warn","ts":"2026-04-15T04:10:42.626343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.560951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/openstack/libvirt-api-ca\" ","response":"range_response_count:1 size:2083"} {"level":"info","ts":"2026-04-15T04:10:42.626363Z","caller":"traceutil/trace.go:171","msg":"trace[1071245004] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:7; response_revision:3503; }","duration":"306.204491ms","start":"2026-04-15T04:10:42.320148Z","end":"2026-04-15T04:10:42.626353Z","steps":["trace[1071245004] 'agreement among raft nodes before linearized reading' (duration: 306.026536ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:10:42.626396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T04:10:42.320144Z","time spent":"306.243773ms","remote":"127.0.0.1:52930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":7,"response size":47962,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-04-15T04:10:42.626397Z","caller":"traceutil/trace.go:171","msg":"trace[251864793] range","detail":"{range_begin:/registry/cert-manager.io/certificates/openstack/libvirt-api-ca; range_end:; response_count:1; response_revision:3503; }","duration":"306.614452ms","start":"2026-04-15T04:10:42.319765Z","end":"2026-04-15T04:10:42.626379Z","steps":["trace[251864793] 'agreement among raft nodes before linearized reading' (duration: 306.419117ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:10:42.626408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.523811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:14 size:323442"} {"level":"warn","ts":"2026-04-15T04:10:42.626432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T04:10:42.31973Z","time spent":"306.694875ms","remote":"127.0.0.1:46318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":2107,"request content":"key:\"/registry/cert-manager.io/certificates/openstack/libvirt-api-ca\" "} {"level":"info","ts":"2026-04-15T04:10:42.626459Z","caller":"traceutil/trace.go:171","msg":"trace[1089029362] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:14; response_revision:3503; }","duration":"297.596683ms","start":"2026-04-15T04:10:42.328848Z","end":"2026-04-15T04:10:42.626444Z","steps":["trace[1089029362] 'agreement among raft nodes before linearized reading' (duration: 297.170771ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:10:42.625038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.196193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T04:10:42.626602Z","caller":"traceutil/trace.go:171","msg":"trace[1113724184] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:3497; }","duration":"371.766477ms","start":"2026-04-15T04:10:42.254803Z","end":"2026-04-15T04:10:42.626569Z","steps":["trace[1113724184] 'agreement among raft nodes before linearized reading' (duration: 369.871294ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:10:42.626737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T04:10:42.254788Z","time spent":"371.917371ms","remote":"127.0.0.1:42384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":115,"response count":0,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" limit:10000 "} {"level":"info","ts":"2026-04-15T04:10:42.626778Z","caller":"traceutil/trace.go:171","msg":"trace[1684148340] transaction","detail":"{read_only:false; response_revision:3501; number_of_response:1; }","duration":"308.987608ms","start":"2026-04-15T04:10:42.317778Z","end":"2026-04-15T04:10:42.626766Z","steps":["trace[1684148340] 'process raft request' (duration: 307.387943ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:10:42.628403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T04:10:42.317769Z","time spent":"310.602563ms","remote":"127.0.0.1:53022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T04:10:42.626807Z","caller":"traceutil/trace.go:171","msg":"trace[532182221] transaction","detail":"{read_only:false; response_revision:3502; number_of_response:1; }","duration":"308.662609ms","start":"2026-04-15T04:10:42.318128Z","end":"2026-04-15T04:10:42.62679Z","steps":["trace[532182221] 'process raft request' (duration: 307.070394ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:10:42.628536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T04:10:42.318118Z","time spent":"310.394296ms","remote":"127.0.0.1:53022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T04:10:42.626856Z","caller":"traceutil/trace.go:171","msg":"trace[109616573] transaction","detail":"{read_only:false; response_revision:3503; number_of_response:1; }","duration":"308.666368ms","start":"2026-04-15T04:10:42.318178Z","end":"2026-04-15T04:10:42.626844Z","steps":["trace[109616573] 'process raft request' (duration: 307.047423ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:10:42.628671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T04:10:42.318161Z","time spent":"310.492209ms","remote":"127.0.0.1:53022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T04:10:42.626867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.98582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/openstack/libvirt-vnc\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T04:10:42.628776Z","caller":"traceutil/trace.go:171","msg":"trace[2046979456] range","detail":"{range_begin:/registry/cert-manager.io/issuers/openstack/libvirt-vnc; range_end:; response_count:0; response_revision:3503; }","duration":"248.940044ms","start":"2026-04-15T04:10:42.379828Z","end":"2026-04-15T04:10:42.628768Z","steps":["trace[2046979456] 'agreement among raft nodes before linearized reading' (duration: 246.97133ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:10:42.626925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.835548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:118431"} {"level":"info","ts":"2026-04-15T04:10:42.6289Z","caller":"traceutil/trace.go:171","msg":"trace[83921591] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:3503; }","duration":"204.848914ms","start":"2026-04-15T04:10:42.424037Z","end":"2026-04-15T04:10:42.628885Z","steps":["trace[83921591] 'agreement among raft nodes before linearized reading' (duration: 202.737545ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:10:42.792775Z","caller":"traceutil/trace.go:171","msg":"trace[1740784149] transaction","detail":"{read_only:false; response_revision:3504; number_of_response:1; }","duration":"161.640037ms","start":"2026-04-15T04:10:42.631107Z","end":"2026-04-15T04:10:42.792747Z","steps":["trace[1740784149] 'process raft request' (duration: 159.530498ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:10:42.804468Z","caller":"traceutil/trace.go:171","msg":"trace[1163052073] transaction","detail":"{read_only:false; response_revision:3505; number_of_response:1; }","duration":"166.514592ms","start":"2026-04-15T04:10:42.637936Z","end":"2026-04-15T04:10:42.804451Z","steps":["trace[1163052073] 'process raft request' (duration: 166.217794ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:10:42.804726Z","caller":"traceutil/trace.go:171","msg":"trace[1024944933] transaction","detail":"{read_only:false; response_revision:3506; number_of_response:1; }","duration":"165.433682ms","start":"2026-04-15T04:10:42.639284Z","end":"2026-04-15T04:10:42.804718Z","steps":["trace[1024944933] 'process raft request' (duration: 164.962329ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:10:44.402672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.103174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:10328"} {"level":"info","ts":"2026-04-15T04:10:44.402806Z","caller":"traceutil/trace.go:171","msg":"trace[1363301841] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3630; }","duration":"113.273518ms","start":"2026-04-15T04:10:44.289488Z","end":"2026-04-15T04:10:44.402761Z","steps":["trace[1363301841] 'range keys from in-memory index tree' (duration: 112.847956ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:10:54.676891Z","caller":"traceutil/trace.go:171","msg":"trace[1858369269] transaction","detail":"{read_only:false; response_revision:3805; number_of_response:1; }","duration":"239.696099ms","start":"2026-04-15T04:10:54.437157Z","end":"2026-04-15T04:10:54.676853Z","steps":["trace[1858369269] 'process raft request' (duration: 239.505343ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:10:54.833247Z","caller":"traceutil/trace.go:171","msg":"trace[983238775] transaction","detail":"{read_only:false; response_revision:3806; number_of_response:1; }","duration":"369.269679ms","start":"2026-04-15T04:10:54.463945Z","end":"2026-04-15T04:10:54.833215Z","steps":["trace[983238775] 'process raft request' (duration: 366.651886ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:10:54.833371Z","caller":"traceutil/trace.go:171","msg":"trace[1230222739] linearizableReadLoop","detail":"{readStateIndex:3964; appliedIndex:3962; }","duration":"328.173049ms","start":"2026-04-15T04:10:54.505174Z","end":"2026-04-15T04:10:54.833347Z","steps":["trace[1230222739] 'read index received' (duration: 171.245443ms)","trace[1230222739] 'applied index is now lower than readState.Index' (duration: 156.924596ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T04:10:54.833424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T04:10:54.463914Z","time spent":"369.412982ms","remote":"127.0.0.1:60862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":414145,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T04:10:54.833793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.292656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-15T04:10:54.833872Z","caller":"traceutil/trace.go:171","msg":"trace[949409451] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3808; }","duration":"140.402279ms","start":"2026-04-15T04:10:54.693447Z","end":"2026-04-15T04:10:54.833849Z","steps":["trace[949409451] 'agreement among raft nodes before linearized reading' (duration: 140.185923ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:10:54.833867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.185845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-nl4sz\" ","response":"range_response_count:1 size:6447"} {"level":"info","ts":"2026-04-15T04:10:54.833938Z","caller":"traceutil/trace.go:171","msg":"trace[1211350480] range","detail":"{range_begin:/registry/pods/openstack/keepalived-nl4sz; range_end:; response_count:1; response_revision:3808; }","duration":"135.272277ms","start":"2026-04-15T04:10:54.698649Z","end":"2026-04-15T04:10:54.833922Z","steps":["trace[1211350480] 'agreement among raft nodes before linearized reading' (duration: 135.092582ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:10:54.833968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.100794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-15T04:10:54.834028Z","caller":"traceutil/trace.go:171","msg":"trace[565435526] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3808; }","duration":"147.189486ms","start":"2026-04-15T04:10:54.686825Z","end":"2026-04-15T04:10:54.834015Z","steps":["trace[565435526] 'agreement among raft nodes before linearized reading' (duration: 147.049223ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:10:54.834109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.951803ms","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-04-15T04:10:54.834156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.177989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-15T04:10:54.834186Z","caller":"traceutil/trace.go:171","msg":"trace[937858527] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3808; }","duration":"141.057267ms","start":"2026-04-15T04:10:54.693113Z","end":"2026-04-15T04:10:54.834171Z","steps":["trace[937858527] 'agreement among raft nodes before linearized reading' (duration: 140.875872ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:10:54.834205Z","caller":"traceutil/trace.go:171","msg":"trace[1505934269] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3808; }","duration":"150.268931ms","start":"2026-04-15T04:10:54.683922Z","end":"2026-04-15T04:10:54.834191Z","steps":["trace[1505934269] 'agreement among raft nodes before linearized reading' (duration: 150.129497ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:10:54.834333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.452672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-04-15T04:10:54.834374Z","caller":"traceutil/trace.go:171","msg":"trace[61825501] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:3808; }","duration":"239.531494ms","start":"2026-04-15T04:10:54.594833Z","end":"2026-04-15T04:10:54.834364Z","steps":["trace[61825501] 'agreement among raft nodes before linearized reading' (duration: 238.790234ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:10:54.833586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.409886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/loki-0\" ","response":"range_response_count:1 size:5587"} {"level":"info","ts":"2026-04-15T04:10:54.834717Z","caller":"traceutil/trace.go:171","msg":"trace[1331197261] range","detail":"{range_begin:/registry/pods/monitoring/loki-0; range_end:; response_count:1; response_revision:3808; }","duration":"329.578209ms","start":"2026-04-15T04:10:54.505122Z","end":"2026-04-15T04:10:54.8347Z","steps":["trace[1331197261] 'agreement among raft nodes before linearized reading' (duration: 328.336594ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:10:54.834775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T04:10:54.505101Z","time spent":"329.657141ms","remote":"127.0.0.1:52930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":5611,"request content":"key:\"/registry/pods/monitoring/loki-0\" "} {"level":"info","ts":"2026-04-15T04:11:01.290592Z","caller":"traceutil/trace.go:171","msg":"trace[867195299] transaction","detail":"{read_only:false; response_revision:3866; number_of_response:1; }","duration":"373.34616ms","start":"2026-04-15T04:11:00.917218Z","end":"2026-04-15T04:11:01.290564Z","steps":["trace[867195299] 'process raft request' (duration: 345.513169ms)","trace[867195299] 'compare' (duration: 27.658137ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T04:11:01.290753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T04:11:00.917183Z","time spent":"373.500685ms","remote":"127.0.0.1:53022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T04:11:01.307224Z","caller":"traceutil/trace.go:171","msg":"trace[1332210507] transaction","detail":"{read_only:false; response_revision:3867; number_of_response:1; }","duration":"357.849191ms","start":"2026-04-15T04:11:00.949332Z","end":"2026-04-15T04:11:01.307181Z","steps":["trace[1332210507] 'process raft request' (duration: 357.666046ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:11:01.307419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T04:11:00.949309Z","time spent":"358.013826ms","remote":"127.0.0.1:53022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T04:11:01.307489Z","caller":"traceutil/trace.go:171","msg":"trace[1533792722] transaction","detail":"{read_only:false; response_revision:3868; number_of_response:1; }","duration":"357.451ms","start":"2026-04-15T04:11:00.950015Z","end":"2026-04-15T04:11:01.307466Z","steps":["trace[1533792722] 'process raft request' (duration: 357.123731ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:11:01.307645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T04:11:00.949995Z","time spent":"357.560203ms","remote":"127.0.0.1:53022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T04:11:01.307978Z","caller":"traceutil/trace.go:171","msg":"trace[1427206369] transaction","detail":"{read_only:false; response_revision:3869; number_of_response:1; }","duration":"357.07899ms","start":"2026-04-15T04:11:00.950886Z","end":"2026-04-15T04:11:01.307965Z","steps":["trace[1427206369] 'process raft request' (duration: 356.500554ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:11:01.307962Z","caller":"traceutil/trace.go:171","msg":"trace[1334452183] linearizableReadLoop","detail":"{readStateIndex:4028; appliedIndex:4025; }","duration":"357.83572ms","start":"2026-04-15T04:11:00.950093Z","end":"2026-04-15T04:11:01.307929Z","steps":["trace[1334452183] 'read index received' (duration: 312.728411ms)","trace[1334452183] 'applied index is now lower than readState.Index' (duration: 45.106189ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T04:11:01.308001Z","caller":"traceutil/trace.go:171","msg":"trace[311137775] transaction","detail":"{read_only:false; response_revision:3870; number_of_response:1; }","duration":"172.62641ms","start":"2026-04-15T04:11:01.135351Z","end":"2026-04-15T04:11:01.307977Z","steps":["trace[311137775] 'process raft request' (duration: 172.471036ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:11:01.308061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T04:11:00.95086Z","time spent":"357.156341ms","remote":"127.0.0.1:53022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T04:11:01.308264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.333247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-15T04:11:01.30831Z","caller":"traceutil/trace.go:171","msg":"trace[1099099523] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:3870; }","duration":"127.419779ms","start":"2026-04-15T04:11:01.180875Z","end":"2026-04-15T04:11:01.308295Z","steps":["trace[1099099523] 'agreement among raft nodes before linearized reading' (duration: 127.267815ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:11:01.308266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.868926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-15T04:11:01.308404Z","caller":"traceutil/trace.go:171","msg":"trace[510311625] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3870; }","duration":"221.074432ms","start":"2026-04-15T04:11:01.087314Z","end":"2026-04-15T04:11:01.308388Z","steps":["trace[510311625] 'agreement among raft nodes before linearized reading' (duration: 220.808555ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:11:01.308419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.302003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:11 size:108878"} {"level":"info","ts":"2026-04-15T04:11:01.308501Z","caller":"traceutil/trace.go:171","msg":"trace[863683003] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:11; response_revision:3870; }","duration":"358.412186ms","start":"2026-04-15T04:11:00.950071Z","end":"2026-04-15T04:11:01.308483Z","steps":["trace[863683003] 'agreement among raft nodes before linearized reading' (duration: 358.006725ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:11:01.308545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T04:11:00.95006Z","time spent":"358.474248ms","remote":"127.0.0.1:52930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":11,"response size":108902,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-04-15T04:11:44.327233Z","caller":"traceutil/trace.go:171","msg":"trace[971355172] linearizableReadLoop","detail":"{readStateIndex:4317; appliedIndex:4316; }","duration":"256.106142ms","start":"2026-04-15T04:11:44.071109Z","end":"2026-04-15T04:11:44.327215Z","steps":["trace[971355172] 'read index received' (duration: 255.968839ms)","trace[971355172] 'applied index is now lower than readState.Index' (duration: 136.653µs)"],"step_count":2} {"level":"info","ts":"2026-04-15T04:11:44.327325Z","caller":"traceutil/trace.go:171","msg":"trace[1400183403] transaction","detail":"{read_only:false; response_revision:4149; number_of_response:1; }","duration":"272.610953ms","start":"2026-04-15T04:11:44.054677Z","end":"2026-04-15T04:11:44.327288Z","steps":["trace[1400183403] 'process raft request' (duration: 272.418148ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:11:44.327422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.297677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-15T04:11:44.327468Z","caller":"traceutil/trace.go:171","msg":"trace[111968324] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:4149; }","duration":"256.417531ms","start":"2026-04-15T04:11:44.071041Z","end":"2026-04-15T04:11:44.327459Z","steps":["trace[111968324] 'agreement among raft nodes before linearized reading' (duration: 256.279517ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:11:50.388622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.289258ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-15T04:11:50.388773Z","caller":"traceutil/trace.go:171","msg":"trace[1663276949] linearizableReadLoop","detail":"{readStateIndex:4363; appliedIndex:4362; }","duration":"214.855729ms","start":"2026-04-15T04:11:50.173902Z","end":"2026-04-15T04:11:50.388758Z","steps":["trace[1663276949] 'read index received' (duration: 36.358206ms)","trace[1663276949] 'applied index is now lower than readState.Index' (duration: 178.496563ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T04:11:50.388918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.000164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T04:11:50.389029Z","caller":"traceutil/trace.go:171","msg":"trace[18791881] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4192; }","duration":"215.155638ms","start":"2026-04-15T04:11:50.173851Z","end":"2026-04-15T04:11:50.389006Z","steps":["trace[18791881] 'agreement among raft nodes before linearized reading' (duration: 214.984363ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:11:50.388887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.163906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-15T04:11:50.389602Z","caller":"traceutil/trace.go:171","msg":"trace[1331481039] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4192; }","duration":"187.927558ms","start":"2026-04-15T04:11:50.201664Z","end":"2026-04-15T04:11:50.389591Z","steps":["trace[1331481039] 'agreement among raft nodes before linearized reading' (duration: 187.181318ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:12:50.140978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.121014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T04:12:50.141076Z","caller":"traceutil/trace.go:171","msg":"trace[1261340356] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:4570; }","duration":"192.265857ms","start":"2026-04-15T04:12:49.948789Z","end":"2026-04-15T04:12:50.141055Z","steps":["trace[1261340356] 'count revisions from in-memory index tree' (duration: 192.054481ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:13:01.676808Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":1702} {"level":"info","ts":"2026-04-15T04:13:01.720273Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":1702,"took":"42.394651ms","hash":2389467878,"current-db-size-bytes":40407040,"current-db-size":"40 MB","current-db-size-in-use-bytes":23072768,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-15T04:13:01.720384Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2389467878,"revision":1702,"compact-revision":-1} {"level":"info","ts":"2026-04-15T04:13:24.079444Z","caller":"traceutil/trace.go:171","msg":"trace[958537540] linearizableReadLoop","detail":"{readStateIndex:4944; appliedIndex:4943; }","duration":"175.469067ms","start":"2026-04-15T04:13:23.903947Z","end":"2026-04-15T04:13:24.079416Z","steps":["trace[958537540] 'read index received' (duration: 175.22327ms)","trace[958537540] 'applied index is now lower than readState.Index' (duration: 244.927µs)"],"step_count":2} {"level":"info","ts":"2026-04-15T04:13:24.079553Z","caller":"traceutil/trace.go:171","msg":"trace[1056920600] transaction","detail":"{read_only:false; response_revision:4753; number_of_response:1; }","duration":"199.740238ms","start":"2026-04-15T04:13:23.879787Z","end":"2026-04-15T04:13:24.079527Z","steps":["trace[1056920600] 'process raft request' (duration: 199.45585ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:13:24.080321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.361592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-04-15T04:13:24.080383Z","caller":"traceutil/trace.go:171","msg":"trace[1331651878] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:4753; }","duration":"176.471725ms","start":"2026-04-15T04:13:23.903896Z","end":"2026-04-15T04:13:24.080368Z","steps":["trace[1331651878] 'agreement among raft nodes before linearized reading' (duration: 175.668583ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:13:24.212353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.119525ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-15T04:13:24.212544Z","caller":"traceutil/trace.go:171","msg":"trace[924672980] transaction","detail":"{read_only:false; response_revision:4755; number_of_response:1; }","duration":"127.965522ms","start":"2026-04-15T04:13:24.084551Z","end":"2026-04-15T04:13:24.212517Z","steps":["trace[924672980] 'compare' (duration: 118.99067ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:13:24.212671Z","caller":"traceutil/trace.go:171","msg":"trace[1611390840] linearizableReadLoop","detail":"{readStateIndex:4949; appliedIndex:4944; }","duration":"126.253735ms","start":"2026-04-15T04:13:24.086396Z","end":"2026-04-15T04:13:24.21265Z","steps":["trace[1611390840] 'read index received' (duration: 3.642093ms)","trace[1611390840] 'applied index is now lower than readState.Index' (duration: 122.610502ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T04:13:24.212681Z","caller":"traceutil/trace.go:171","msg":"trace[1502170720] transaction","detail":"{read_only:false; response_revision:4756; number_of_response:1; }","duration":"127.805328ms","start":"2026-04-15T04:13:24.084853Z","end":"2026-04-15T04:13:24.212658Z","steps":["trace[1502170720] 'process raft request' (duration: 127.622503ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:13:24.212782Z","caller":"traceutil/trace.go:171","msg":"trace[14454835] transaction","detail":"{read_only:false; response_revision:4757; number_of_response:1; }","duration":"127.322034ms","start":"2026-04-15T04:13:24.085444Z","end":"2026-04-15T04:13:24.212766Z","steps":["trace[14454835] 'process raft request' (duration: 127.107358ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:13:24.212962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.045761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-nl4sz\" ","response":"range_response_count:1 size:6447"} {"level":"info","ts":"2026-04-15T04:13:24.212994Z","caller":"traceutil/trace.go:171","msg":"trace[1573038359] range","detail":"{range_begin:/registry/pods/openstack/keepalived-nl4sz; range_end:; response_count:1; response_revision:4758; }","duration":"125.118473ms","start":"2026-04-15T04:13:24.087868Z","end":"2026-04-15T04:13:24.212987Z","steps":["trace[1573038359] 'agreement among raft nodes before linearized reading' (duration: 125.00282ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:13:24.213072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.656417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1317"} {"level":"info","ts":"2026-04-15T04:13:24.213178Z","caller":"traceutil/trace.go:171","msg":"trace[1796371330] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:4758; }","duration":"126.814421ms","start":"2026-04-15T04:13:24.086345Z","end":"2026-04-15T04:13:24.21316Z","steps":["trace[1796371330] 'agreement among raft nodes before linearized reading' (duration: 126.44536ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:13:24.21333Z","caller":"traceutil/trace.go:171","msg":"trace[5392679] transaction","detail":"{read_only:false; response_revision:4758; number_of_response:1; }","duration":"127.629134ms","start":"2026-04-15T04:13:24.085544Z","end":"2026-04-15T04:13:24.213173Z","steps":["trace[5392679] 'process raft request' (duration: 127.058958ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:15:20.128206Z","caller":"traceutil/trace.go:171","msg":"trace[983695134] transaction","detail":"{read_only:false; response_revision:5512; number_of_response:1; }","duration":"119.431874ms","start":"2026-04-15T04:15:20.008541Z","end":"2026-04-15T04:15:20.127973Z","steps":["trace[983695134] 'process raft request' (duration: 119.262139ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:15:31.953947Z","caller":"traceutil/trace.go:171","msg":"trace[859846507] transaction","detail":"{read_only:false; response_revision:5608; number_of_response:1; }","duration":"112.916176ms","start":"2026-04-15T04:15:31.840997Z","end":"2026-04-15T04:15:31.953913Z","steps":["trace[859846507] 'process raft request' (duration: 106.513636ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:15:31.956488Z","caller":"traceutil/trace.go:171","msg":"trace[1160159672] transaction","detail":"{read_only:false; response_revision:5609; number_of_response:1; }","duration":"111.465855ms","start":"2026-04-15T04:15:31.844996Z","end":"2026-04-15T04:15:31.956462Z","steps":["trace[1160159672] 'process raft request' (duration: 111.321861ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:17:12.157882Z","caller":"traceutil/trace.go:171","msg":"trace[767659656] transaction","detail":"{read_only:false; response_revision:6989; number_of_response:1; }","duration":"109.040537ms","start":"2026-04-15T04:17:12.048813Z","end":"2026-04-15T04:17:12.157853Z","steps":["trace[767659656] 'process raft request' (duration: 107.755521ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:17:17.418387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.753492ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-15T04:17:17.418554Z","caller":"traceutil/trace.go:171","msg":"trace[993201624] transaction","detail":"{read_only:false; response_revision:7042; number_of_response:1; }","duration":"201.905548ms","start":"2026-04-15T04:17:17.216627Z","end":"2026-04-15T04:17:17.418533Z","steps":["trace[993201624] 'process raft request' (duration: 41.921189ms)","trace[993201624] 'compare' (duration: 159.610338ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T04:17:17.551415Z","caller":"traceutil/trace.go:171","msg":"trace[1618785253] transaction","detail":"{read_only:false; response_revision:7043; number_of_response:1; }","duration":"128.42033ms","start":"2026-04-15T04:17:17.422967Z","end":"2026-04-15T04:17:17.551387Z","steps":["trace[1618785253] 'process raft request' (duration: 108.626004ms)","trace[1618785253] 'compare' (duration: 19.615391ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T04:17:17.551608Z","caller":"traceutil/trace.go:171","msg":"trace[1225036048] linearizableReadLoop","detail":"{readStateIndex:7312; appliedIndex:7310; }","duration":"123.695908ms","start":"2026-04-15T04:17:17.427903Z","end":"2026-04-15T04:17:17.551598Z","steps":["trace[1225036048] 'read index received' (duration: 103.704846ms)","trace[1225036048] 'applied index is now lower than readState.Index' (duration: 19.990212ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T04:17:17.551725Z","caller":"traceutil/trace.go:171","msg":"trace[1174571775] transaction","detail":"{read_only:false; response_revision:7044; number_of_response:1; }","duration":"128.618757ms","start":"2026-04-15T04:17:17.42307Z","end":"2026-04-15T04:17:17.551689Z","steps":["trace[1174571775] 'process raft request' (duration: 128.274556ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:17:17.55184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.920435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-15T04:17:17.551874Z","caller":"traceutil/trace.go:171","msg":"trace[1746154687] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7044; }","duration":"123.994698ms","start":"2026-04-15T04:17:17.42787Z","end":"2026-04-15T04:17:17.551865Z","steps":["trace[1746154687] 'agreement among raft nodes before linearized reading' (duration: 123.801511ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:17:18.061518Z","caller":"traceutil/trace.go:171","msg":"trace[1305549669] transaction","detail":"{read_only:false; response_revision:7051; number_of_response:1; }","duration":"121.430154ms","start":"2026-04-15T04:17:17.940033Z","end":"2026-04-15T04:17:18.061463Z","steps":["trace[1305549669] 'process raft request' (duration: 121.206168ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:17:18.061743Z","caller":"traceutil/trace.go:171","msg":"trace[371550916] transaction","detail":"{read_only:false; response_revision:7052; number_of_response:1; }","duration":"121.655042ms","start":"2026-04-15T04:17:17.940053Z","end":"2026-04-15T04:17:18.061709Z","steps":["trace[371550916] 'process raft request' (duration: 121.316382ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:17:18.061797Z","caller":"traceutil/trace.go:171","msg":"trace[1293362040] linearizableReadLoop","detail":"{readStateIndex:7322; appliedIndex:7317; }","duration":"116.575319ms","start":"2026-04-15T04:17:17.945194Z","end":"2026-04-15T04:17:18.061769Z","steps":["trace[1293362040] 'read index received' (duration: 23.724187ms)","trace[1293362040] 'applied index is now lower than readState.Index' (duration: 92.849842ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T04:17:18.061807Z","caller":"traceutil/trace.go:171","msg":"trace[1520023120] transaction","detail":"{read_only:false; response_revision:7053; number_of_response:1; }","duration":"121.655882ms","start":"2026-04-15T04:17:17.940126Z","end":"2026-04-15T04:17:18.061781Z","steps":["trace[1520023120] 'process raft request' (duration: 121.480317ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:17:18.062047Z","caller":"traceutil/trace.go:171","msg":"trace[265645243] transaction","detail":"{read_only:false; response_revision:7054; number_of_response:1; }","duration":"117.35296ms","start":"2026-04-15T04:17:17.944611Z","end":"2026-04-15T04:17:18.061964Z","steps":["trace[265645243] 'process raft request' (duration: 117.088963ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:17:18.06205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.835786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-04-15T04:17:18.062397Z","caller":"traceutil/trace.go:171","msg":"trace[1578041950] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:7054; }","duration":"117.191596ms","start":"2026-04-15T04:17:17.945161Z","end":"2026-04-15T04:17:18.062353Z","steps":["trace[1578041950] 'agreement among raft nodes before linearized reading' (duration: 116.770104ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:17:54.287998Z","caller":"traceutil/trace.go:171","msg":"trace[1410562577] linearizableReadLoop","detail":"{readStateIndex:7611; appliedIndex:7610; }","duration":"211.461478ms","start":"2026-04-15T04:17:54.076511Z","end":"2026-04-15T04:17:54.287972Z","steps":["trace[1410562577] 'read index received' (duration: 211.320964ms)","trace[1410562577] 'applied index is now lower than readState.Index' (duration: 139.904µs)"],"step_count":2} {"level":"info","ts":"2026-04-15T04:17:54.288032Z","caller":"traceutil/trace.go:171","msg":"trace[1488840327] transaction","detail":"{read_only:false; response_revision:7333; number_of_response:1; }","duration":"213.115805ms","start":"2026-04-15T04:17:54.074902Z","end":"2026-04-15T04:17:54.288018Z","steps":["trace[1488840327] 'process raft request' (duration: 212.96691ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:17:54.288174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.721931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T04:17:54.288205Z","caller":"traceutil/trace.go:171","msg":"trace[422122833] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7333; }","duration":"114.798884ms","start":"2026-04-15T04:17:54.173397Z","end":"2026-04-15T04:17:54.288196Z","steps":["trace[422122833] 'agreement among raft nodes before linearized reading' (duration: 114.703391ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:17:54.288283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.770057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:414535"} {"level":"info","ts":"2026-04-15T04:17:54.288324Z","caller":"traceutil/trace.go:171","msg":"trace[693141583] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:7333; }","duration":"211.837919ms","start":"2026-04-15T04:17:54.076476Z","end":"2026-04-15T04:17:54.288314Z","steps":["trace[693141583] 'agreement among raft nodes before linearized reading' (duration: 211.615023ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:18:01.681933Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":4636} {"level":"info","ts":"2026-04-15T04:18:01.790721Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":4636,"took":"107.411566ms","hash":321142395,"current-db-size-bytes":45252608,"current-db-size":"45 MB","current-db-size-in-use-bytes":33173504,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-04-15T04:18:01.790773Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":321142395,"revision":4636,"compact-revision":1702} {"level":"info","ts":"2026-04-15T04:19:19.116186Z","caller":"traceutil/trace.go:171","msg":"trace[1129618611] transaction","detail":"{read_only:false; response_revision:8511; number_of_response:1; }","duration":"107.496039ms","start":"2026-04-15T04:19:19.008667Z","end":"2026-04-15T04:19:19.116163Z","steps":["trace[1129618611] 'process raft request' (duration: 107.147429ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:19:21.482173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.648471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4784"} {"level":"info","ts":"2026-04-15T04:19:21.482276Z","caller":"traceutil/trace.go:171","msg":"trace[1330248945] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:8533; }","duration":"108.796456ms","start":"2026-04-15T04:19:21.373455Z","end":"2026-04-15T04:19:21.482252Z","steps":["trace[1330248945] 'range keys from in-memory index tree' (duration: 108.435485ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:19:23.15585Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002298.wal"} {"level":"info","ts":"2026-04-15T04:19:26.822161Z","caller":"traceutil/trace.go:171","msg":"trace[2067481213] transaction","detail":"{read_only:false; response_revision:8624; number_of_response:1; }","duration":"155.697752ms","start":"2026-04-15T04:19:26.666429Z","end":"2026-04-15T04:19:26.822127Z","steps":["trace[2067481213] 'process raft request' (duration: 155.504607ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:19:26.822209Z","caller":"traceutil/trace.go:171","msg":"trace[2005027687] linearizableReadLoop","detail":"{readStateIndex:8934; appliedIndex:8931; }","duration":"126.821816ms","start":"2026-04-15T04:19:26.69537Z","end":"2026-04-15T04:19:26.822192Z","steps":["trace[2005027687] 'read index received' (duration: 126.721683ms)","trace[2005027687] 'applied index is now lower than readState.Index' (duration: 99.213µs)"],"step_count":2} {"level":"info","ts":"2026-04-15T04:19:26.822464Z","caller":"traceutil/trace.go:171","msg":"trace[2085465366] transaction","detail":"{read_only:false; response_revision:8625; number_of_response:1; }","duration":"155.555809ms","start":"2026-04-15T04:19:26.666894Z","end":"2026-04-15T04:19:26.82245Z","steps":["trace[2085465366] 'process raft request' (duration: 155.175789ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:19:26.822649Z","caller":"traceutil/trace.go:171","msg":"trace[1712652967] transaction","detail":"{read_only:false; response_revision:8626; number_of_response:1; }","duration":"155.076016ms","start":"2026-04-15T04:19:26.66756Z","end":"2026-04-15T04:19:26.822636Z","steps":["trace[1712652967] 'process raft request' (duration: 154.558231ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:19:26.822844Z","caller":"traceutil/trace.go:171","msg":"trace[1603454831] transaction","detail":"{read_only:false; response_revision:8627; number_of_response:1; }","duration":"149.24971ms","start":"2026-04-15T04:19:26.673584Z","end":"2026-04-15T04:19:26.822834Z","steps":["trace[1603454831] 'process raft request' (duration: 148.563601ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:19:26.823095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.735463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-neutron-server-0\" ","response":"range_response_count:1 size:10688"} {"level":"info","ts":"2026-04-15T04:19:26.823139Z","caller":"traceutil/trace.go:171","msg":"trace[36161979] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-neutron-server-0; range_end:; response_count:1; response_revision:8627; }","duration":"155.832106ms","start":"2026-04-15T04:19:26.667296Z","end":"2026-04-15T04:19:26.823128Z","steps":["trace[36161979] 'agreement among raft nodes before linearized reading' (duration: 155.665442ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:19:26.823157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.12766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/openstack/identity\" ","response":"range_response_count:1 size:903"} {"level":"info","ts":"2026-04-15T04:19:26.823235Z","caller":"traceutil/trace.go:171","msg":"trace[1356956571] range","detail":"{range_begin:/registry/ingress/openstack/identity; range_end:; response_count:1; response_revision:8627; }","duration":"159.232503ms","start":"2026-04-15T04:19:26.663979Z","end":"2026-04-15T04:19:26.823211Z","steps":["trace[1356956571] 'agreement among raft nodes before linearized reading' (duration: 159.073918ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:19:26.825534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.756924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-314112db-6476-4a70-b125-b877fc0a2c05\" ","response":"range_response_count:1 size:1320"} {"level":"info","ts":"2026-04-15T04:19:26.825725Z","caller":"traceutil/trace.go:171","msg":"trace[1625543126] range","detail":"{range_begin:/registry/persistentvolumes/pvc-314112db-6476-4a70-b125-b877fc0a2c05; range_end:; response_count:1; response_revision:8629; }","duration":"127.003721ms","start":"2026-04-15T04:19:26.698691Z","end":"2026-04-15T04:19:26.825695Z","steps":["trace[1625543126] 'agreement among raft nodes before linearized reading' (duration: 126.60214ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:19:54.675727Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c47ca1a7b1fe506b","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-15T04:19:54.685681Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-15T04:19:54.685777Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-15T04:19:55.04373Z","caller":"traceutil/trace.go:171","msg":"trace[1095336749] transaction","detail":"{read_only:false; response_revision:9685; number_of_response:1; }","duration":"163.293478ms","start":"2026-04-15T04:19:54.880417Z","end":"2026-04-15T04:19:55.043711Z","steps":["trace[1095336749] 'process raft request' (duration: 98.120736ms)","trace[1095336749] 'compare' (duration: 64.807682ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T04:19:55.043851Z","caller":"traceutil/trace.go:171","msg":"trace[1291196749] linearizableReadLoop","detail":"{readStateIndex:10005; appliedIndex:10004; }","duration":"159.948192ms","start":"2026-04-15T04:19:54.883875Z","end":"2026-04-15T04:19:55.043823Z","steps":["trace[1291196749] 'read index received' (duration: 94.662537ms)","trace[1291196749] 'applied index is now lower than readState.Index' (duration: 65.284145ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T04:19:55.043871Z","caller":"traceutil/trace.go:171","msg":"trace[1981633160] transaction","detail":"{read_only:false; response_revision:9687; number_of_response:1; }","duration":"103.818815ms","start":"2026-04-15T04:19:54.940025Z","end":"2026-04-15T04:19:55.043844Z","steps":["trace[1981633160] 'process raft request' (duration: 103.767513ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:19:55.043949Z","caller":"traceutil/trace.go:171","msg":"trace[1417481988] transaction","detail":"{read_only:false; response_revision:9686; number_of_response:1; }","duration":"116.157255ms","start":"2026-04-15T04:19:54.927787Z","end":"2026-04-15T04:19:55.043944Z","steps":["trace[1417481988] 'process raft request' (duration: 115.910048ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:19:55.044062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.173038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-04-15T04:19:55.044144Z","caller":"traceutil/trace.go:171","msg":"trace[681104851] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:9687; }","duration":"160.263461ms","start":"2026-04-15T04:19:54.88387Z","end":"2026-04-15T04:19:55.044134Z","steps":["trace[681104851] 'agreement among raft nodes before linearized reading' (duration: 160.073716ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:20:00.339517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.983397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T04:20:00.33958Z","caller":"traceutil/trace.go:171","msg":"trace[503672281] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9755; }","duration":"106.09417ms","start":"2026-04-15T04:20:00.23347Z","end":"2026-04-15T04:20:00.339564Z","steps":["trace[503672281] 'agreement among raft nodes before linearized reading' (duration: 105.980167ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:20:37.420077Z","caller":"traceutil/trace.go:171","msg":"trace[6385412] transaction","detail":"{read_only:false; response_revision:10203; number_of_response:1; }","duration":"102.649233ms","start":"2026-04-15T04:20:37.317406Z","end":"2026-04-15T04:20:37.420055Z","steps":["trace[6385412] 'process raft request' (duration: 76.570175ms)","trace[6385412] 'compare' (duration: 25.932414ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T04:21:48.741183Z","caller":"traceutil/trace.go:171","msg":"trace[1501149931] transaction","detail":"{read_only:false; response_revision:11096; number_of_response:1; }","duration":"142.729396ms","start":"2026-04-15T04:21:48.598366Z","end":"2026-04-15T04:21:48.741096Z","steps":["trace[1501149931] 'process raft request' (duration: 142.543831ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:21:48.741167Z","caller":"traceutil/trace.go:171","msg":"trace[105993657] transaction","detail":"{read_only:false; response_revision:11095; number_of_response:1; }","duration":"164.583004ms","start":"2026-04-15T04:21:48.576524Z","end":"2026-04-15T04:21:48.741107Z","steps":["trace[105993657] 'process raft request' (duration: 67.290813ms)","trace[105993657] 'compare' (duration: 96.435387ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T04:22:54.665161Z","caller":"traceutil/trace.go:171","msg":"trace[850727080] linearizableReadLoop","detail":"{readStateIndex:12230; appliedIndex:12229; }","duration":"145.273586ms","start":"2026-04-15T04:22:54.519863Z","end":"2026-04-15T04:22:54.665136Z","steps":["trace[850727080] 'read index received' (duration: 144.800123ms)","trace[850727080] 'applied index is now lower than readState.Index' (duration: 472.453µs)"],"step_count":2} {"level":"warn","ts":"2026-04-15T04:22:54.665323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.444962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T04:22:54.665377Z","caller":"traceutil/trace.go:171","msg":"trace[1647038233] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:11862; }","duration":"145.522404ms","start":"2026-04-15T04:22:54.519841Z","end":"2026-04-15T04:22:54.665363Z","steps":["trace[1647038233] 'agreement among raft nodes before linearized reading' (duration: 145.424091ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:22:54.665409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.890512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-15T04:22:54.665453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.914973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T04:22:54.665486Z","caller":"traceutil/trace.go:171","msg":"trace[965756609] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:0; response_revision:11862; }","duration":"136.017646ms","start":"2026-04-15T04:22:54.529453Z","end":"2026-04-15T04:22:54.66547Z","steps":["trace[965756609] 'agreement among raft nodes before linearized reading' (duration: 135.866782ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:22:54.665531Z","caller":"traceutil/trace.go:171","msg":"trace[449831651] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:0; response_revision:11862; }","duration":"136.038086ms","start":"2026-04-15T04:22:54.529473Z","end":"2026-04-15T04:22:54.665512Z","steps":["trace[449831651] 'agreement among raft nodes before linearized reading' (duration: 135.889582ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:22:54.665308Z","caller":"traceutil/trace.go:171","msg":"trace[968082804] transaction","detail":"{read_only:false; response_revision:11862; number_of_response:1; }","duration":"211.012967ms","start":"2026-04-15T04:22:54.454261Z","end":"2026-04-15T04:22:54.665273Z","steps":["trace[968082804] 'process raft request' (duration: 210.529112ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:23:00.822934Z","caller":"traceutil/trace.go:171","msg":"trace[324960185] transaction","detail":"{read_only:false; response_revision:11900; number_of_response:1; }","duration":"112.79602ms","start":"2026-04-15T04:23:00.710111Z","end":"2026-04-15T04:23:00.822907Z","steps":["trace[324960185] 'process raft request' (duration: 112.579544ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:23:01.43616Z","caller":"traceutil/trace.go:171","msg":"trace[1529718257] transaction","detail":"{read_only:false; response_revision:11901; number_of_response:1; }","duration":"153.436038ms","start":"2026-04-15T04:23:01.282682Z","end":"2026-04-15T04:23:01.436118Z","steps":["trace[1529718257] 'process raft request' (duration: 153.147969ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:23:01.786271Z","caller":"traceutil/trace.go:171","msg":"trace[14731989] linearizableReadLoop","detail":"{readStateIndex:12272; appliedIndex:12271; }","duration":"254.091612ms","start":"2026-04-15T04:23:01.532151Z","end":"2026-04-15T04:23:01.786243Z","steps":["trace[14731989] 'read index received' (duration: 211.361045ms)","trace[14731989] 'applied index is now lower than readState.Index' (duration: 42.728737ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T04:23:01.786376Z","caller":"traceutil/trace.go:171","msg":"trace[487953235] transaction","detail":"{read_only:false; response_revision:11902; number_of_response:1; }","duration":"345.28212ms","start":"2026-04-15T04:23:01.440983Z","end":"2026-04-15T04:23:01.786266Z","steps":["trace[487953235] 'process raft request' (duration: 302.523482ms)","trace[487953235] 'compare' (duration: 42.515951ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T04:23:01.786526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T04:23:01.440961Z","time spent":"345.483547ms","remote":"127.0.0.1:53022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T04:23:01.786951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.784332ms","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-04-15T04:23:01.78706Z","caller":"traceutil/trace.go:171","msg":"trace[28603576] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:11902; }","duration":"254.908516ms","start":"2026-04-15T04:23:01.532138Z","end":"2026-04-15T04:23:01.787046Z","steps":["trace[28603576] 'agreement among raft nodes before linearized reading' (duration: 254.187866ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:23:01.931817Z","caller":"traceutil/trace.go:171","msg":"trace[1528037975] transaction","detail":"{read_only:false; response_revision:11903; number_of_response:1; }","duration":"346.856854ms","start":"2026-04-15T04:23:01.584934Z","end":"2026-04-15T04:23:01.931791Z","steps":["trace[1528037975] 'process raft request' (duration: 306.538344ms)","trace[1528037975] 'compare' (duration: 40.105384ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T04:23:01.932106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T04:23:01.584914Z","time spent":"347.121503ms","remote":"127.0.0.1:53022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T04:23:01.932129Z","caller":"traceutil/trace.go:171","msg":"trace[1388987581] transaction","detail":"{read_only:false; response_revision:11905; number_of_response:1; }","duration":"155.436355ms","start":"2026-04-15T04:23:01.776678Z","end":"2026-04-15T04:23:01.932114Z","steps":["trace[1388987581] 'process raft request' (duration: 155.280521ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:23:01.93226Z","caller":"traceutil/trace.go:171","msg":"trace[1630643258] transaction","detail":"{read_only:false; response_revision:11906; number_of_response:1; }","duration":"142.037306ms","start":"2026-04-15T04:23:01.790216Z","end":"2026-04-15T04:23:01.932253Z","steps":["trace[1630643258] 'process raft request' (duration: 141.83631ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:23:01.932102Z","caller":"traceutil/trace.go:171","msg":"trace[418343097] transaction","detail":"{read_only:false; response_revision:11904; number_of_response:1; }","duration":"248.068143ms","start":"2026-04-15T04:23:01.684006Z","end":"2026-04-15T04:23:01.932074Z","steps":["trace[418343097] 'process raft request' (duration: 247.747183ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:23:01.932802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.141745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-15T04:23:01.932826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.561242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T04:23:01.932877Z","caller":"traceutil/trace.go:171","msg":"trace[82465938] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:11907; }","duration":"320.658015ms","start":"2026-04-15T04:23:01.61221Z","end":"2026-04-15T04:23:01.932868Z","steps":["trace[82465938] 'agreement among raft nodes before linearized reading' (duration: 320.553262ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:23:01.932884Z","caller":"traceutil/trace.go:171","msg":"trace[193085369] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:11907; }","duration":"294.228657ms","start":"2026-04-15T04:23:01.638613Z","end":"2026-04-15T04:23:01.932842Z","steps":["trace[193085369] 'agreement among raft nodes before linearized reading' (duration: 294.140965ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:23:01.93292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T04:23:01.612199Z","time spent":"320.711826ms","remote":"127.0.0.1:53066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-15T04:23:01.932939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.268922ms","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-04-15T04:23:01.933024Z","caller":"traceutil/trace.go:171","msg":"trace[1209645453] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:11907; }","duration":"396.369836ms","start":"2026-04-15T04:23:01.53663Z","end":"2026-04-15T04:23:01.932999Z","steps":["trace[1209645453] 'agreement among raft nodes before linearized reading' (duration: 396.14736ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:23:01.93302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.478824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/cinder-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-04-15T04:23:01.933133Z","caller":"traceutil/trace.go:171","msg":"trace[404258313] range","detail":"{range_begin:/registry/services/endpoints/openstack/cinder-api; range_end:; response_count:1; response_revision:11907; }","duration":"150.614598ms","start":"2026-04-15T04:23:01.782472Z","end":"2026-04-15T04:23:01.933086Z","steps":["trace[404258313] 'agreement among raft nodes before linearized reading' (duration: 150.347091ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:23:01.933155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T04:23:01.536619Z","time spent":"396.447748ms","remote":"127.0.0.1:53022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-15T04:23:01.932739Z","caller":"traceutil/trace.go:171","msg":"trace[567873] linearizableReadLoop","detail":"{readStateIndex:12275; appliedIndex:12272; }","duration":"146.380848ms","start":"2026-04-15T04:23:01.786335Z","end":"2026-04-15T04:23:01.932716Z","steps":["trace[567873] 'read index received' (duration: 105.150952ms)","trace[567873] 'applied index is now lower than readState.Index' (duration: 41.228556ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T04:23:01.933182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.623989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-15T04:23:01.933274Z","caller":"traceutil/trace.go:171","msg":"trace[1538768064] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:11907; }","duration":"398.792263ms","start":"2026-04-15T04:23:01.534459Z","end":"2026-04-15T04:23:01.933251Z","steps":["trace[1538768064] 'agreement among raft nodes before linearized reading' (duration: 398.454943ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:23:01.933322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T04:23:01.534433Z","time spent":"398.881136ms","remote":"127.0.0.1:53022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-15T04:23:01.933456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.365987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/cinder-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-04-15T04:23:01.933518Z","caller":"traceutil/trace.go:171","msg":"trace[554759626] range","detail":"{range_begin:/registry/services/endpoints/openstack/cinder-api; range_end:; response_count:1; response_revision:11907; }","duration":"106.48386ms","start":"2026-04-15T04:23:01.827022Z","end":"2026-04-15T04:23:01.933506Z","steps":["trace[554759626] 'agreement among raft nodes before linearized reading' (duration: 106.279424ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:23:02.002579Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7371} {"level":"info","ts":"2026-04-15T04:23:02.137065Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7371,"took":"131.516678ms","hash":3733726809,"current-db-size-bytes":59564032,"current-db-size":"60 MB","current-db-size-in-use-bytes":41222144,"current-db-size-in-use":"41 MB"} {"level":"info","ts":"2026-04-15T04:23:02.137119Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3733726809,"revision":7371,"compact-revision":4636} {"level":"info","ts":"2026-04-15T04:23:19.331107Z","caller":"traceutil/trace.go:171","msg":"trace[737624279] transaction","detail":"{read_only:false; response_revision:12150; number_of_response:1; }","duration":"155.157566ms","start":"2026-04-15T04:23:19.175924Z","end":"2026-04-15T04:23:19.331081Z","steps":["trace[737624279] 'process raft request' (duration: 154.989711ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:23:19.668165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.987132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-15T04:23:19.668249Z","caller":"traceutil/trace.go:171","msg":"trace[132072905] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:12150; }","duration":"149.119395ms","start":"2026-04-15T04:23:19.519112Z","end":"2026-04-15T04:23:19.668231Z","steps":["trace[132072905] 'range keys from in-memory index tree' (duration: 148.814527ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T04:23:19.668378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.595986ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-15T04:23:19.668481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.050024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-04-15T04:23:19.668539Z","caller":"traceutil/trace.go:171","msg":"trace[549859347] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:12150; }","duration":"149.147655ms","start":"2026-04-15T04:23:19.519377Z","end":"2026-04-15T04:23:19.668524Z","steps":["trace[549859347] 'range keys from in-memory index tree' (duration: 148.906939ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:23:19.668551Z","caller":"traceutil/trace.go:171","msg":"trace[942276195] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:12150; }","duration":"197.781661ms","start":"2026-04-15T04:23:19.470739Z","end":"2026-04-15T04:23:19.66852Z","steps":["trace[942276195] 'range keys from in-memory index tree' (duration: 197.564275ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T04:28:02.008724Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":11904} {"level":"info","ts":"2026-04-15T04:28:02.140653Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":11904,"took":"128.614955ms","hash":1138432780,"current-db-size-bytes":59564032,"current-db-size":"60 MB","current-db-size-in-use-bytes":26755072,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-04-15T04:28:02.14074Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1138432780,"revision":11904,"compact-revision":7371}