{"level":"warn","ts":"2026-07-02T05:05:05.015467Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-02T05:05:05.015598Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.70: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.70:2380","--initial-cluster=instance=https://199.204.45.70:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.70:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.70: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-07-02T05:05:05.015683Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-02T05:05:05.015696Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.70:2380"]} {"level":"info","ts":"2026-07-02T05:05:05.015732Z","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-07-02T05:05:05.016309Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.70:2379"]} {"level":"info","ts":"2026-07-02T05:05:05.016376Z","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":8,"max-cpu-available":8,"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.70:2380"],"listen-peer-urls":["https://199.204.45.70:2380"],"advertise-client-urls":["https://199.204.45.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.70: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-07-02T05:05:05.022171Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.597264ms"} {"level":"info","ts":"2026-07-02T05:05:05.031965Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f8844a9a4263041d","cluster-id":"4749b7c221ae0648"} {"level":"info","ts":"2026-07-02T05:05:05.032037Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=()"} {"level":"info","ts":"2026-07-02T05:05:05.03206Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became follower at term 0"} {"level":"info","ts":"2026-07-02T05:05:05.032071Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f8844a9a4263041d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-02T05:05:05.032083Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became follower at term 1"} {"level":"info","ts":"2026-07-02T05:05:05.032115Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=(17907520044731139101)"} {"level":"warn","ts":"2026-07-02T05:05:05.051684Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-02T05:05:05.053061Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-02T05:05:05.054621Z","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-07-02T05:05:05.056967Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f8844a9a4263041d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-02T05:05:05.057097Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f8844a9a4263041d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-02T05:05:05.057463Z","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-07-02T05:05:05.057548Z","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-07-02T05:05:05.05758Z","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-07-02T05:05:05.060392Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=(17907520044731139101)"} {"level":"info","ts":"2026-07-02T05:05:05.060545Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4749b7c221ae0648","local-member-id":"f8844a9a4263041d","added-peer-id":"f8844a9a4263041d","added-peer-peer-urls":["https://199.204.45.70:2380"]} {"level":"info","ts":"2026-07-02T05:05:05.062284Z","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-07-02T05:05:05.062364Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.70:2380"} {"level":"info","ts":"2026-07-02T05:05:05.062393Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.70:2380"} {"level":"info","ts":"2026-07-02T05:05:05.062522Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f8844a9a4263041d","initial-advertise-peer-urls":["https://199.204.45.70:2380"],"listen-peer-urls":["https://199.204.45.70:2380"],"advertise-client-urls":["https://199.204.45.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-02T05:05:05.062561Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-02T05:05:05.634583Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d is starting a new election at term 1"} {"level":"info","ts":"2026-07-02T05:05:05.634628Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became pre-candidate at term 1"} {"level":"info","ts":"2026-07-02T05:05:05.634648Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d received MsgPreVoteResp from f8844a9a4263041d at term 1"} {"level":"info","ts":"2026-07-02T05:05:05.634659Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became candidate at term 2"} {"level":"info","ts":"2026-07-02T05:05:05.634664Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d received MsgVoteResp from f8844a9a4263041d at term 2"} {"level":"info","ts":"2026-07-02T05:05:05.634672Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became leader at term 2"} {"level":"info","ts":"2026-07-02T05:05:05.634683Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f8844a9a4263041d elected leader f8844a9a4263041d at term 2"} {"level":"info","ts":"2026-07-02T05:05:05.647065Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T05:05:05.647768Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-02T05:05:05.64777Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f8844a9a4263041d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.70:2379]}","request-path":"/0/members/f8844a9a4263041d/attributes","cluster-id":"4749b7c221ae0648","publish-timeout":"7s"} {"level":"info","ts":"2026-07-02T05:05:05.648047Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-02T05:05:05.648927Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4749b7c221ae0648","local-member-id":"f8844a9a4263041d","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T05:05:05.64903Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T05:05:05.649059Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T05:05:05.649533Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-02T05:05:05.649571Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-02T05:05:05.651081Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.70:2379"} {"level":"info","ts":"2026-07-02T05:05:05.651924Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-02T05:05:33.425654Z","caller":"traceutil/trace.go:171","msg":"trace[29208385] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"155.099901ms","start":"2026-07-02T05:05:33.270539Z","end":"2026-07-02T05:05:33.425638Z","steps":["trace[29208385] 'process raft request' (duration: 154.705726ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:05:40.832924Z","caller":"traceutil/trace.go:171","msg":"trace[2137209143] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"122.075327ms","start":"2026-07-02T05:05:40.710829Z","end":"2026-07-02T05:05:40.832904Z","steps":["trace[2137209143] 'process raft request' (duration: 121.973755ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:05:41.839822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.893904ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T05:05:41.83998Z","caller":"traceutil/trace.go:171","msg":"trace[1642276762] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"312.18663ms","start":"2026-07-02T05:05:41.52777Z","end":"2026-07-02T05:05:41.839957Z","steps":["trace[1642276762] 'process raft request' (duration: 148.408506ms)","trace[1642276762] 'compare' (duration: 162.738182ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:05:41.840065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:05:41.52774Z","time spent":"312.280321ms","remote":"127.0.0.1:41450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29546,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:05:41.840891Z","caller":"traceutil/trace.go:171","msg":"trace[1013238277] linearizableReadLoop","detail":"{readStateIndex:540; appliedIndex:537; }","duration":"203.312037ms","start":"2026-07-02T05:05:41.637563Z","end":"2026-07-02T05:05:41.840875Z","steps":["trace[1013238277] 'read index received' (duration: 38.634203ms)","trace[1013238277] 'applied index is now lower than readState.Index' (duration: 164.676784ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:05:41.840979Z","caller":"traceutil/trace.go:171","msg":"trace[494921942] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"312.546183ms","start":"2026-07-02T05:05:41.528422Z","end":"2026-07-02T05:05:41.840968Z","steps":["trace[494921942] 'process raft request' (duration: 312.129568ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:05:41.84105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:05:41.528397Z","time spent":"312.606933ms","remote":"127.0.0.1:41450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31111,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:05:41.841088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.514779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-02T05:05:41.841136Z","caller":"traceutil/trace.go:171","msg":"trace[1232748868] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:518; }","duration":"203.592161ms","start":"2026-07-02T05:05:41.637528Z","end":"2026-07-02T05:05:41.84112Z","steps":["trace[1232748868] 'agreement among raft nodes before linearized reading' (duration: 203.49533ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:05:41.841379Z","caller":"traceutil/trace.go:171","msg":"trace[951274235] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"307.398313ms","start":"2026-07-02T05:05:41.53397Z","end":"2026-07-02T05:05:41.841368Z","steps":["trace[951274235] 'process raft request' (duration: 306.769896ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:05:41.841434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:05:41.533947Z","time spent":"307.457194ms","remote":"127.0.0.1:41450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T05:05:48.048646Z","caller":"traceutil/trace.go:171","msg":"trace[1778980726] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:609; }","duration":"164.71741ms","start":"2026-07-02T05:05:47.883907Z","end":"2026-07-02T05:05:48.048624Z","steps":["trace[1778980726] 'read index received' (duration: 164.661599ms)","trace[1778980726] 'applied index is now lower than readState.Index' (duration: 55.101µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:05:48.048735Z","caller":"traceutil/trace.go:171","msg":"trace[167794559] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"169.827696ms","start":"2026-07-02T05:05:47.87887Z","end":"2026-07-02T05:05:48.048698Z","steps":["trace[167794559] 'process raft request' (duration: 169.611704ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:05:48.048877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.946182ms","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-07-02T05:05:48.048967Z","caller":"traceutil/trace.go:171","msg":"trace[1673077055] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:587; }","duration":"165.052223ms","start":"2026-07-02T05:05:47.883896Z","end":"2026-07-02T05:05:48.048948Z","steps":["trace[1673077055] 'agreement among raft nodes before linearized reading' (duration: 164.80479ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:05:48.303381Z","caller":"traceutil/trace.go:171","msg":"trace[1317574538] linearizableReadLoop","detail":"{readStateIndex:613; appliedIndex:610; }","duration":"147.733464ms","start":"2026-07-02T05:05:48.15563Z","end":"2026-07-02T05:05:48.303364Z","steps":["trace[1317574538] 'read index received' (duration: 78.860964ms)","trace[1317574538] 'applied index is now lower than readState.Index' (duration: 68.8718ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:05:48.303473Z","caller":"traceutil/trace.go:171","msg":"trace[262036469] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"249.173463ms","start":"2026-07-02T05:05:48.054292Z","end":"2026-07-02T05:05:48.303466Z","steps":["trace[262036469] 'process raft request' (duration: 249.009831ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:05:48.303447Z","caller":"traceutil/trace.go:171","msg":"trace[443570039] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"345.477894ms","start":"2026-07-02T05:05:47.957941Z","end":"2026-07-02T05:05:48.303419Z","steps":["trace[443570039] 'process raft request' (duration: 277.502504ms)","trace[443570039] 'compare' (duration: 67.356274ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:05:48.303615Z","caller":"traceutil/trace.go:171","msg":"trace[378217956] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"246.19612ms","start":"2026-07-02T05:05:48.057413Z","end":"2026-07-02T05:05:48.303609Z","steps":["trace[378217956] 'process raft request' (duration: 245.923547ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:05:48.303647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:05:47.957913Z","time spent":"345.630757ms","remote":"127.0.0.1:41488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:05:48.303765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.135808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-j5spk\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-07-02T05:05:48.303792Z","caller":"traceutil/trace.go:171","msg":"trace[501919054] range","detail":"{range_begin:/registry/pods/kube-system/cilium-j5spk; range_end:; response_count:1; response_revision:590; }","duration":"148.204809ms","start":"2026-07-02T05:05:48.15558Z","end":"2026-07-02T05:05:48.303785Z","steps":["trace[501919054] 'agreement among raft nodes before linearized reading' (duration: 148.100578ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:05:48.520127Z","caller":"traceutil/trace.go:171","msg":"trace[799419545] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"197.847152ms","start":"2026-07-02T05:05:48.322257Z","end":"2026-07-02T05:05:48.520104Z","steps":["trace[799419545] 'process raft request' (duration: 197.655829ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:05:48.993093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.251541ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-02T05:05:48.993185Z","caller":"traceutil/trace.go:171","msg":"trace[1622226709] linearizableReadLoop","detail":"{readStateIndex:616; appliedIndex:614; }","duration":"568.774194ms","start":"2026-07-02T05:05:48.424394Z","end":"2026-07-02T05:05:48.993169Z","steps":["trace[1622226709] 'read index received' (duration: 95.586793ms)","trace[1622226709] 'applied index is now lower than readState.Index' (duration: 473.186171ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:05:48.993264Z","caller":"traceutil/trace.go:171","msg":"trace[269312827] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"640.443145ms","start":"2026-07-02T05:05:48.352811Z","end":"2026-07-02T05:05:48.993254Z","steps":["trace[269312827] 'process raft request' (duration: 377.998522ms)","trace[269312827] 'compare' (duration: 262.11773ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:05:48.993311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:05:48.352784Z","time spent":"640.503627ms","remote":"127.0.0.1:41608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-02T05:05:48.993446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.062027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:05:48.993471Z","caller":"traceutil/trace.go:171","msg":"trace[1533432704] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:593; }","duration":"569.134389ms","start":"2026-07-02T05:05:48.424328Z","end":"2026-07-02T05:05:48.993463Z","steps":["trace[1533432704] 'agreement among raft nodes before linearized reading' (duration: 569.069118ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:05:48.993496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:05:48.424307Z","time spent":"569.183959ms","remote":"127.0.0.1:41382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-02T05:05:48.993565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.600789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.70\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-02T05:05:48.993647Z","caller":"traceutil/trace.go:171","msg":"trace[1022608611] range","detail":"{range_begin:/registry/masterleases/199.204.45.70; range_end:; response_count:1; response_revision:593; }","duration":"437.69852ms","start":"2026-07-02T05:05:48.555929Z","end":"2026-07-02T05:05:48.993627Z","steps":["trace[1022608611] 'agreement among raft nodes before linearized reading' (duration: 437.523487ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:05:48.993692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:05:48.555855Z","time spent":"437.828832ms","remote":"127.0.0.1:41438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.70\" "} {"level":"warn","ts":"2026-07-02T05:05:49.322428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:05:48.99457Z","time spent":"327.852437ms","remote":"127.0.0.1:41438","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-02T05:05:49.322556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.716793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-07-02T05:05:49.322678Z","caller":"traceutil/trace.go:171","msg":"trace[1206296338] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:593; }","duration":"325.878205ms","start":"2026-07-02T05:05:48.996786Z","end":"2026-07-02T05:05:49.322664Z","steps":["trace[1206296338] 'agreement among raft nodes before linearized reading' (duration: 325.700743ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:05:49.322719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:05:48.99677Z","time spent":"325.939085ms","remote":"127.0.0.1:41578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"info","ts":"2026-07-02T05:05:49.322429Z","caller":"traceutil/trace.go:171","msg":"trace[804147781] linearizableReadLoop","detail":"{readStateIndex:617; appliedIndex:616; }","duration":"325.586972ms","start":"2026-07-02T05:05:48.996817Z","end":"2026-07-02T05:05:49.322404Z","steps":["trace[804147781] 'read index received' (duration: 295.786175ms)","trace[804147781] 'applied index is now lower than readState.Index' (duration: 29.790357ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:06:02.375958Z","caller":"traceutil/trace.go:171","msg":"trace[59809715] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"122.74827ms","start":"2026-07-02T05:06:02.253189Z","end":"2026-07-02T05:06:02.375938Z","steps":["trace[59809715] 'process raft request' (duration: 122.628364ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:06:04.988857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.315194ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-02T05:06:04.988938Z","caller":"traceutil/trace.go:171","msg":"trace[1901871909] linearizableReadLoop","detail":"{readStateIndex:784; appliedIndex:783; }","duration":"121.376926ms","start":"2026-07-02T05:06:04.867549Z","end":"2026-07-02T05:06:04.988926Z","steps":["trace[1901871909] 'read index received' (duration: 30.821µs)","trace[1901871909] 'applied index is now lower than readState.Index' (duration: 121.345165ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:06:04.989039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.489619ms","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-07-02T05:06:04.989057Z","caller":"traceutil/trace.go:171","msg":"trace[1443430178] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:756; }","duration":"121.549482ms","start":"2026-07-02T05:06:04.867501Z","end":"2026-07-02T05:06:04.989051Z","steps":["trace[1443430178] 'agreement among raft nodes before linearized reading' (duration: 121.454769ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:06:36.482187Z","caller":"traceutil/trace.go:171","msg":"trace[675364701] linearizableReadLoop","detail":"{readStateIndex:1200; appliedIndex:1199; }","duration":"148.045385ms","start":"2026-07-02T05:06:36.334114Z","end":"2026-07-02T05:06:36.482159Z","steps":["trace[675364701] 'read index received' (duration: 147.897511ms)","trace[675364701] 'applied index is now lower than readState.Index' (duration: 146.684µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:06:36.482284Z","caller":"traceutil/trace.go:171","msg":"trace[207216498] transaction","detail":"{read_only:false; response_revision:1163; number_of_response:1; }","duration":"212.877011ms","start":"2026-07-02T05:06:36.269374Z","end":"2026-07-02T05:06:36.482251Z","steps":["trace[207216498] 'process raft request' (duration: 212.609574ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:06:36.482363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.218379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:06:36.482468Z","caller":"traceutil/trace.go:171","msg":"trace[1191286406] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1163; }","duration":"148.382034ms","start":"2026-07-02T05:06:36.334066Z","end":"2026-07-02T05:06:36.482448Z","steps":["trace[1191286406] 'agreement among raft nodes before linearized reading' (duration: 148.212589ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:07:00.806806Z","caller":"traceutil/trace.go:171","msg":"trace[93274658] transaction","detail":"{read_only:false; response_revision:1430; number_of_response:1; }","duration":"205.520764ms","start":"2026-07-02T05:07:00.601248Z","end":"2026-07-02T05:07:00.806768Z","steps":["trace[93274658] 'process raft request' (duration: 205.298249ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:07:24.32196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.466304ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-02T05:07:24.32207Z","caller":"traceutil/trace.go:171","msg":"trace[157433255] linearizableReadLoop","detail":"{readStateIndex:1906; appliedIndex:1905; }","duration":"383.579985ms","start":"2026-07-02T05:07:23.938473Z","end":"2026-07-02T05:07:24.322053Z","steps":["trace[157433255] 'read index received' (duration: 52.441µs)","trace[157433255] 'applied index is now lower than readState.Index' (duration: 383.526294ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:07:24.322497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.013255ms","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-07-02T05:07:24.322557Z","caller":"traceutil/trace.go:171","msg":"trace[1307402028] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1831; }","duration":"384.117797ms","start":"2026-07-02T05:07:23.938424Z","end":"2026-07-02T05:07:24.322542Z","steps":["trace[1307402028] 'agreement among raft nodes before linearized reading' (duration: 383.695319ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:07:24.322563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.591033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0b92c2f5-724b-493e-8cd7-74588924f41d\" ","response":"range_response_count:1 size:3626"} {"level":"warn","ts":"2026-07-02T05:07:24.322567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.91567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-02T05:07:24.322618Z","caller":"traceutil/trace.go:171","msg":"trace[1990224635] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1831; }","duration":"331.012913ms","start":"2026-07-02T05:07:23.991589Z","end":"2026-07-02T05:07:24.322602Z","steps":["trace[1990224635] 'agreement among raft nodes before linearized reading' (duration: 330.840999ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:07:24.322581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:07:23.938404Z","time spent":"384.171409ms","remote":"127.0.0.1:45266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-07-02T05:07:24.322706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:07:23.991564Z","time spent":"331.128906ms","remote":"127.0.0.1:41642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-02T05:07:24.323009Z","caller":"traceutil/trace.go:171","msg":"trace[968732293] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0b92c2f5-724b-493e-8cd7-74588924f41d; range_end:; response_count:1; response_revision:1831; }","duration":"379.658974ms","start":"2026-07-02T05:07:23.942935Z","end":"2026-07-02T05:07:24.322594Z","steps":["trace[968732293] 'agreement among raft nodes before linearized reading' (duration: 379.495391ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:07:24.323067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:07:23.942926Z","time spent":"380.126664ms","remote":"127.0.0.1:41564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0b92c2f5-724b-493e-8cd7-74588924f41d\" "} {"level":"info","ts":"2026-07-02T05:07:24.899034Z","caller":"traceutil/trace.go:171","msg":"trace[797938135] transaction","detail":"{read_only:false; response_revision:1833; number_of_response:1; }","duration":"262.651994ms","start":"2026-07-02T05:07:24.636355Z","end":"2026-07-02T05:07:24.899007Z","steps":["trace[797938135] 'process raft request' (duration: 262.400269ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:07:39.875608Z","caller":"traceutil/trace.go:171","msg":"trace[1928954731] transaction","detail":"{read_only:false; response_revision:1954; number_of_response:1; }","duration":"226.337143ms","start":"2026-07-02T05:07:39.649241Z","end":"2026-07-02T05:07:39.875578Z","steps":["trace[1928954731] 'process raft request' (duration: 226.111608ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:07:40.456531Z","caller":"traceutil/trace.go:171","msg":"trace[1723017612] transaction","detail":"{read_only:false; response_revision:1958; number_of_response:1; }","duration":"197.281559ms","start":"2026-07-02T05:07:40.259222Z","end":"2026-07-02T05:07:40.456503Z","steps":["trace[1723017612] 'process raft request' (duration: 197.126026ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:08:03.330789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.573747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-02T05:08:03.330914Z","caller":"traceutil/trace.go:171","msg":"trace[814688097] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2091; }","duration":"118.721119ms","start":"2026-07-02T05:08:03.212175Z","end":"2026-07-02T05:08:03.330896Z","steps":["trace[814688097] 'range keys from in-memory index tree' (duration: 118.487976ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:08:03.331528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.681187ms","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-07-02T05:08:03.331612Z","caller":"traceutil/trace.go:171","msg":"trace[551539705] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2091; }","duration":"119.765479ms","start":"2026-07-02T05:08:03.211798Z","end":"2026-07-02T05:08:03.331564Z","steps":["trace[551539705] 'range keys from in-memory index tree' (duration: 118.637798ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:08:04.110402Z","caller":"traceutil/trace.go:171","msg":"trace[923665535] linearizableReadLoop","detail":"{readStateIndex:2179; appliedIndex:2178; }","duration":"216.788385ms","start":"2026-07-02T05:08:03.893581Z","end":"2026-07-02T05:08:04.110369Z","steps":["trace[923665535] 'read index received' (duration: 140.867134ms)","trace[923665535] 'applied index is now lower than readState.Index' (duration: 75.918621ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:08:04.110729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.125181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:08:04.111014Z","caller":"traceutil/trace.go:171","msg":"trace[992010404] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:2098; }","duration":"217.466147ms","start":"2026-07-02T05:08:03.893531Z","end":"2026-07-02T05:08:04.110998Z","steps":["trace[992010404] 'agreement among raft nodes before linearized reading' (duration: 216.925838ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:08:04.86048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.675374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:08:04.86056Z","caller":"traceutil/trace.go:171","msg":"trace[1370756323] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2099; }","duration":"435.811256ms","start":"2026-07-02T05:08:04.424731Z","end":"2026-07-02T05:08:04.860543Z","steps":["trace[1370756323] 'range keys from in-memory index tree' (duration: 435.575812ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:08:04.860562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.080609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-02T05:08:04.860655Z","caller":"traceutil/trace.go:171","msg":"trace[770616781] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2099; }","duration":"283.229202ms","start":"2026-07-02T05:08:04.577401Z","end":"2026-07-02T05:08:04.86063Z","steps":["trace[770616781] 'range keys from in-memory index tree' (duration: 282.866195ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:08:04.860596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:08:04.424711Z","time spent":"435.876808ms","remote":"127.0.0.1:41382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-02T05:08:04.860759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.666053ms","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-07-02T05:08:04.861107Z","caller":"traceutil/trace.go:171","msg":"trace[1960039185] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2099; }","duration":"214.06011ms","start":"2026-07-02T05:08:04.647021Z","end":"2026-07-02T05:08:04.861082Z","steps":["trace[1960039185] 'range keys from in-memory index tree' (duration: 213.47545ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:08:05.426762Z","caller":"traceutil/trace.go:171","msg":"trace[1255102305] linearizableReadLoop","detail":"{readStateIndex:2184; appliedIndex:2183; }","duration":"287.129985ms","start":"2026-07-02T05:08:05.139611Z","end":"2026-07-02T05:08:05.426741Z","steps":["trace[1255102305] 'read index received' (duration: 287.014443ms)","trace[1255102305] 'applied index is now lower than readState.Index' (duration: 114.312µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:08:05.426812Z","caller":"traceutil/trace.go:171","msg":"trace[1288314471] transaction","detail":"{read_only:false; response_revision:2101; number_of_response:1; }","duration":"327.710635ms","start":"2026-07-02T05:08:05.099069Z","end":"2026-07-02T05:08:05.42678Z","steps":["trace[1288314471] 'process raft request' (duration: 327.542162ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:08:05.42695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.329378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-02T05:08:05.426961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:08:05.099045Z","time spent":"327.835797ms","remote":"127.0.0.1:41642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:08:05.426993Z","caller":"traceutil/trace.go:171","msg":"trace[545731333] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2101; }","duration":"287.41654ms","start":"2026-07-02T05:08:05.139565Z","end":"2026-07-02T05:08:05.426981Z","steps":["trace[545731333] 'agreement among raft nodes before linearized reading' (duration: 287.287998ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:09:36.068466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.758446ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T05:09:36.068596Z","caller":"traceutil/trace.go:171","msg":"trace[1777950999] linearizableReadLoop","detail":"{readStateIndex:2801; appliedIndex:2800; }","duration":"176.201004ms","start":"2026-07-02T05:09:35.892377Z","end":"2026-07-02T05:09:36.068578Z","steps":["trace[1777950999] 'read index received' (duration: 40.461µs)","trace[1777950999] 'applied index is now lower than readState.Index' (duration: 176.159003ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:09:36.068833Z","caller":"traceutil/trace.go:171","msg":"trace[660786184] transaction","detail":"{read_only:false; response_revision:2697; number_of_response:1; }","duration":"259.455824ms","start":"2026-07-02T05:09:35.809364Z","end":"2026-07-02T05:09:36.06882Z","steps":["trace[660786184] 'process raft request' (duration: 50.2082ms)","trace[660786184] 'compare' (duration: 208.503873ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:09:36.069128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.751852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-02T05:09:36.069164Z","caller":"traceutil/trace.go:171","msg":"trace[83484754] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2697; }","duration":"176.825843ms","start":"2026-07-02T05:09:35.892325Z","end":"2026-07-02T05:09:36.069151Z","steps":["trace[83484754] 'agreement among raft nodes before linearized reading' (duration: 176.66491ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:09:36.069206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.788941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-02T05:09:36.069458Z","caller":"traceutil/trace.go:171","msg":"trace[1099006848] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2697; }","duration":"167.074396ms","start":"2026-07-02T05:09:35.902359Z","end":"2026-07-02T05:09:36.069433Z","steps":["trace[1099006848] 'agreement among raft nodes before linearized reading' (duration: 166.67968ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:09:36.069685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.202562ms","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-07-02T05:09:36.069767Z","caller":"traceutil/trace.go:171","msg":"trace[833752855] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2697; }","duration":"166.315894ms","start":"2026-07-02T05:09:35.903431Z","end":"2026-07-02T05:09:36.069747Z","steps":["trace[833752855] 'agreement among raft nodes before linearized reading' (duration: 166.056521ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:09:36.489798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.152778ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T05:09:36.489937Z","caller":"traceutil/trace.go:171","msg":"trace[1734552256] transaction","detail":"{read_only:false; response_revision:2703; number_of_response:1; }","duration":"263.566432ms","start":"2026-07-02T05:09:36.226344Z","end":"2026-07-02T05:09:36.48991Z","steps":["trace[1734552256] 'process raft request' (duration: 74.197831ms)","trace[1734552256] 'compare' (duration: 188.997736ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:09:36.640186Z","caller":"traceutil/trace.go:171","msg":"trace[688384588] linearizableReadLoop","detail":"{readStateIndex:2808; appliedIndex:2807; }","duration":"214.182606ms","start":"2026-07-02T05:09:36.425983Z","end":"2026-07-02T05:09:36.640166Z","steps":["trace[688384588] 'read index received' (duration: 186.04512ms)","trace[688384588] 'applied index is now lower than readState.Index' (duration: 28.136616ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:09:36.640453Z","caller":"traceutil/trace.go:171","msg":"trace[1907881870] transaction","detail":"{read_only:false; response_revision:2704; number_of_response:1; }","duration":"225.328415ms","start":"2026-07-02T05:09:36.415109Z","end":"2026-07-02T05:09:36.640437Z","steps":["trace[1907881870] 'process raft request' (duration: 196.971686ms)","trace[1907881870] 'compare' (duration: 28.000923ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:09:36.640642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.645332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:09:36.640682Z","caller":"traceutil/trace.go:171","msg":"trace[445785593] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2704; }","duration":"214.725124ms","start":"2026-07-02T05:09:36.425946Z","end":"2026-07-02T05:09:36.640671Z","steps":["trace[445785593] 'agreement among raft nodes before linearized reading' (duration: 214.655193ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:13:25.412611Z","caller":"traceutil/trace.go:171","msg":"trace[1326930302] transaction","detail":"{read_only:false; response_revision:4677; number_of_response:1; }","duration":"304.086845ms","start":"2026-07-02T05:13:25.1085Z","end":"2026-07-02T05:13:25.412587Z","steps":["trace[1326930302] 'process raft request' (duration: 303.948953ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:13:25.412641Z","caller":"traceutil/trace.go:171","msg":"trace[1987955647] linearizableReadLoop","detail":"{readStateIndex:4854; appliedIndex:4854; }","duration":"269.827767ms","start":"2026-07-02T05:13:25.14279Z","end":"2026-07-02T05:13:25.412617Z","steps":["trace[1987955647] 'read index received' (duration: 269.817317ms)","trace[1987955647] 'applied index is now lower than readState.Index' (duration: 8.52µs)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:13:25.412752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:13:25.108479Z","time spent":"304.207387ms","remote":"127.0.0.1:41472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":922,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-02T05:13:25.41287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.071312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-02T05:13:25.412953Z","caller":"traceutil/trace.go:171","msg":"trace[735107193] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4677; }","duration":"270.188125ms","start":"2026-07-02T05:13:25.14275Z","end":"2026-07-02T05:13:25.412939Z","steps":["trace[735107193] 'agreement among raft nodes before linearized reading' (duration: 269.96147ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:13:25.531817Z","caller":"traceutil/trace.go:171","msg":"trace[2138554036] transaction","detail":"{read_only:false; response_revision:4679; number_of_response:1; }","duration":"113.351083ms","start":"2026-07-02T05:13:25.418444Z","end":"2026-07-02T05:13:25.531795Z","steps":["trace[2138554036] 'process raft request' (duration: 113.312882ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:13:25.532079Z","caller":"traceutil/trace.go:171","msg":"trace[1845343210] transaction","detail":"{read_only:false; response_revision:4678; number_of_response:1; }","duration":"133.360027ms","start":"2026-07-02T05:13:25.398709Z","end":"2026-07-02T05:13:25.532069Z","steps":["trace[1845343210] 'process raft request' (duration: 102.911167ms)","trace[1845343210] 'compare' (duration: 29.885528ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:13:25.532172Z","caller":"traceutil/trace.go:171","msg":"trace[77583044] linearizableReadLoop","detail":"{readStateIndex:4855; appliedIndex:4854; }","duration":"119.354348ms","start":"2026-07-02T05:13:25.41281Z","end":"2026-07-02T05:13:25.532164Z","steps":["trace[77583044] 'read index received' (duration: 88.798176ms)","trace[77583044] 'applied index is now lower than readState.Index' (duration: 30.555452ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:13:25.5323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.461111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:13:25.53233Z","caller":"traceutil/trace.go:171","msg":"trace[1053528714] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:4679; }","duration":"182.534783ms","start":"2026-07-02T05:13:25.349786Z","end":"2026-07-02T05:13:25.532321Z","steps":["trace[1053528714] 'agreement among raft nodes before linearized reading' (duration: 182.477742ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:25.532475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.350045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12973"} {"level":"info","ts":"2026-07-02T05:13:25.53252Z","caller":"traceutil/trace.go:171","msg":"trace[640837111] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4679; }","duration":"189.429996ms","start":"2026-07-02T05:13:25.343082Z","end":"2026-07-02T05:13:25.532512Z","steps":["trace[640837111] 'agreement among raft nodes before linearized reading' (duration: 189.331504ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:25.532657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.900683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-02T05:13:25.532692Z","caller":"traceutil/trace.go:171","msg":"trace[873385644] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4679; }","duration":"363.945593ms","start":"2026-07-02T05:13:25.168741Z","end":"2026-07-02T05:13:25.532687Z","steps":["trace[873385644] 'agreement among raft nodes before linearized reading' (duration: 363.871092ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:25.532716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:13:25.168737Z","time spent":"363.970754ms","remote":"127.0.0.1:41642","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-07-02T05:13:25.532771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.027515ms","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-07-02T05:13:25.532836Z","caller":"traceutil/trace.go:171","msg":"trace[1372324333] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4679; }","duration":"364.111987ms","start":"2026-07-02T05:13:25.168712Z","end":"2026-07-02T05:13:25.532824Z","steps":["trace[1372324333] 'agreement among raft nodes before linearized reading' (duration: 363.951884ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:25.532864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:13:25.168706Z","time spent":"364.152018ms","remote":"127.0.0.1:41642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-02T05:13:25.532979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.702609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-07-02T05:13:25.532997Z","caller":"traceutil/trace.go:171","msg":"trace[797722352] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4679; }","duration":"365.737089ms","start":"2026-07-02T05:13:25.167253Z","end":"2026-07-02T05:13:25.53299Z","steps":["trace[797722352] 'agreement among raft nodes before linearized reading' (duration: 365.671858ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:25.533018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:13:25.167245Z","time spent":"365.76951ms","remote":"127.0.0.1:41642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-07-02T05:13:25.533181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.662279ms","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-07-02T05:13:25.533204Z","caller":"traceutil/trace.go:171","msg":"trace[942918940] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4679; }","duration":"382.723951ms","start":"2026-07-02T05:13:25.150475Z","end":"2026-07-02T05:13:25.533199Z","steps":["trace[942918940] 'agreement among raft nodes before linearized reading' (duration: 382.66471ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:25.53322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:13:25.150458Z","time spent":"382.758812ms","remote":"127.0.0.1:41642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-02T05:13:25.53333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.65231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-07-02T05:13:25.533363Z","caller":"traceutil/trace.go:171","msg":"trace[1706622081] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4679; }","duration":"115.713882ms","start":"2026-07-02T05:13:25.41764Z","end":"2026-07-02T05:13:25.533354Z","steps":["trace[1706622081] 'agreement among raft nodes before linearized reading' (duration: 115.63211ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:25.533469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.306339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:13:25.533486Z","caller":"traceutil/trace.go:171","msg":"trace[1412125829] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4679; }","duration":"109.3362ms","start":"2026-07-02T05:13:25.424144Z","end":"2026-07-02T05:13:25.53348Z","steps":["trace[1412125829] 'agreement among raft nodes before linearized reading' (duration: 109.30114ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:25.533503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.750663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-b7b4c4d95-dzfjh.18be610b75b9d589\" ","response":"range_response_count:1 size:893"} {"level":"info","ts":"2026-07-02T05:13:25.533525Z","caller":"traceutil/trace.go:171","msg":"trace[70070397] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-b7b4c4d95-dzfjh.18be610b75b9d589; range_end:; response_count:1; response_revision:4679; }","duration":"115.787253ms","start":"2026-07-02T05:13:25.417731Z","end":"2026-07-02T05:13:25.533518Z","steps":["trace[70070397] 'agreement among raft nodes before linearized reading' (duration: 115.730482ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:13:25.826808Z","caller":"traceutil/trace.go:171","msg":"trace[289900488] transaction","detail":"{read_only:false; response_revision:4692; number_of_response:1; }","duration":"137.967142ms","start":"2026-07-02T05:13:25.688809Z","end":"2026-07-02T05:13:25.826776Z","steps":["trace[289900488] 'process raft request' (duration: 108.707817ms)","trace[289900488] 'compare' (duration: 29.085861ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:13:37.635279Z","caller":"traceutil/trace.go:171","msg":"trace[1532307264] transaction","detail":"{read_only:false; response_revision:4839; number_of_response:1; }","duration":"165.541002ms","start":"2026-07-02T05:13:37.469713Z","end":"2026-07-02T05:13:37.635254Z","steps":["trace[1532307264] 'process raft request' (duration: 165.321388ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:13:37.762008Z","caller":"traceutil/trace.go:171","msg":"trace[1731996288] transaction","detail":"{read_only:false; response_revision:4841; number_of_response:1; }","duration":"118.025357ms","start":"2026-07-02T05:13:37.643954Z","end":"2026-07-02T05:13:37.761979Z","steps":["trace[1731996288] 'process raft request' (duration: 90.841434ms)","trace[1731996288] 'compare' (duration: 26.962609ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:13:37.762314Z","caller":"traceutil/trace.go:171","msg":"trace[312386531] transaction","detail":"{read_only:false; response_revision:4842; number_of_response:1; }","duration":"118.249012ms","start":"2026-07-02T05:13:37.644033Z","end":"2026-07-02T05:13:37.762282Z","steps":["trace[312386531] 'process raft request' (duration: 117.890565ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:13:37.762431Z","caller":"traceutil/trace.go:171","msg":"trace[862135905] transaction","detail":"{read_only:false; response_revision:4843; number_of_response:1; }","duration":"117.65962ms","start":"2026-07-02T05:13:37.644759Z","end":"2026-07-02T05:13:37.762418Z","steps":["trace[862135905] 'process raft request' (duration: 117.377414ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:13:37.76264Z","caller":"traceutil/trace.go:171","msg":"trace[1017185794] transaction","detail":"{read_only:false; response_revision:4844; number_of_response:1; }","duration":"117.109319ms","start":"2026-07-02T05:13:37.645521Z","end":"2026-07-02T05:13:37.76263Z","steps":["trace[1017185794] 'process raft request' (duration: 116.827453ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:13:37.76262Z","caller":"traceutil/trace.go:171","msg":"trace[889420605] linearizableReadLoop","detail":"{readStateIndex:5027; appliedIndex:5023; }","duration":"114.244731ms","start":"2026-07-02T05:13:37.648336Z","end":"2026-07-02T05:13:37.762581Z","steps":["trace[889420605] 'read index received' (duration: 86.459466ms)","trace[889420605] 'applied index is now lower than readState.Index' (duration: 27.782735ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:13:37.762796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.841446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-07-02T05:13:37.762804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.510259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-07-02T05:13:37.76281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.07869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-8c67d640-e6dc-4ee3-b3e8-1c2fe10189d4\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-07-02T05:13:37.762839Z","caller":"traceutil/trace.go:171","msg":"trace[596173166] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4844; }","duration":"124.919098ms","start":"2026-07-02T05:13:37.637906Z","end":"2026-07-02T05:13:37.762825Z","steps":["trace[596173166] 'agreement among raft nodes before linearized reading' (duration: 124.727864ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:13:37.762846Z","caller":"traceutil/trace.go:171","msg":"trace[1867566979] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4844; }","duration":"126.593921ms","start":"2026-07-02T05:13:37.636242Z","end":"2026-07-02T05:13:37.762836Z","steps":["trace[1867566979] 'agreement among raft nodes before linearized reading' (duration: 126.429708ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:13:37.76287Z","caller":"traceutil/trace.go:171","msg":"trace[878418496] range","detail":"{range_begin:/registry/persistentvolumes/pvc-8c67d640-e6dc-4ee3-b3e8-1c2fe10189d4; range_end:; response_count:1; response_revision:4844; }","duration":"123.190412ms","start":"2026-07-02T05:13:37.639662Z","end":"2026-07-02T05:13:37.762852Z","steps":["trace[878418496] 'agreement among raft nodes before linearized reading' (duration: 122.967608ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:13:37.816341Z","caller":"traceutil/trace.go:171","msg":"trace[1042619714] transaction","detail":"{read_only:false; response_revision:4847; number_of_response:1; }","duration":"163.794656ms","start":"2026-07-02T05:13:37.65252Z","end":"2026-07-02T05:13:37.816314Z","steps":["trace[1042619714] 'process raft request' (duration: 163.742465ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:13:37.816457Z","caller":"traceutil/trace.go:171","msg":"trace[796189969] transaction","detail":"{read_only:false; response_revision:4846; number_of_response:1; }","duration":"164.95519ms","start":"2026-07-02T05:13:37.651467Z","end":"2026-07-02T05:13:37.816423Z","steps":["trace[796189969] 'process raft request' (duration: 164.757347ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:13:37.816513Z","caller":"traceutil/trace.go:171","msg":"trace[371187793] transaction","detail":"{read_only:false; response_revision:4845; number_of_response:1; }","duration":"164.987721ms","start":"2026-07-02T05:13:37.651405Z","end":"2026-07-02T05:13:37.816392Z","steps":["trace[371187793] 'process raft request' (duration: 164.673715ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:15:05.677641Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2854} {"level":"info","ts":"2026-07-02T05:15:05.775269Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2854,"took":"96.391824ms","hash":1294922007,"current-db-size-bytes":54145024,"current-db-size":"54 MB","current-db-size-in-use-bytes":29388800,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-07-02T05:15:05.775317Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1294922007,"revision":2854,"compact-revision":-1} {"level":"info","ts":"2026-07-02T05:20:05.684864Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5573} {"level":"info","ts":"2026-07-02T05:20:05.774456Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5573,"took":"88.351462ms","hash":3958862726,"current-db-size-bytes":54145024,"current-db-size":"54 MB","current-db-size-in-use-bytes":18296832,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-07-02T05:20:05.774505Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3958862726,"revision":5573,"compact-revision":2854}