{"level":"warn","ts":"2026-06-02T01:05:29.61205Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-02T01:05:29.612145Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.115: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.115:2380","--initial-cluster=instance=https://199.204.45.115:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.115:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.115: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-06-02T01:05:29.612211Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-02T01:05:29.612223Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.115:2380"]} {"level":"info","ts":"2026-06-02T01:05:29.612244Z","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-06-02T01:05:29.612878Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"]} {"level":"info","ts":"2026-06-02T01:05:29.612956Z","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.115:2380"],"listen-peer-urls":["https://199.204.45.115:2380"],"advertise-client-urls":["https://199.204.45.115:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.115: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-06-02T01:05:29.617173Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.012297ms"} {"level":"info","ts":"2026-06-02T01:05:29.62267Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e3aaa5c123e150e0","cluster-id":"61ec519698823541"} {"level":"info","ts":"2026-06-02T01:05:29.622767Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=()"} {"level":"info","ts":"2026-06-02T01:05:29.622792Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became follower at term 0"} {"level":"info","ts":"2026-06-02T01:05:29.622808Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e3aaa5c123e150e0 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-02T01:05:29.622822Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became follower at term 1"} {"level":"info","ts":"2026-06-02T01:05:29.622869Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=(16405106841599693024)"} {"level":"warn","ts":"2026-06-02T01:05:29.630225Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-02T01:05:29.632137Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-02T01:05:29.634747Z","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-06-02T01:05:29.638987Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e3aaa5c123e150e0","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-02T01:05:29.63923Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e3aaa5c123e150e0","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-02T01:05:29.639375Z","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-06-02T01:05:29.63957Z","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-06-02T01:05:29.639614Z","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-06-02T01:05:29.641803Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=(16405106841599693024)"} {"level":"info","ts":"2026-06-02T01:05:29.642012Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"61ec519698823541","local-member-id":"e3aaa5c123e150e0","added-peer-id":"e3aaa5c123e150e0","added-peer-peer-urls":["https://199.204.45.115:2380"]} {"level":"info","ts":"2026-06-02T01:05:29.646184Z","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-06-02T01:05:29.646516Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e3aaa5c123e150e0","initial-advertise-peer-urls":["https://199.204.45.115:2380"],"listen-peer-urls":["https://199.204.45.115:2380"],"advertise-client-urls":["https://199.204.45.115:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-02T01:05:29.64655Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-02T01:05:29.646736Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.115:2380"} {"level":"info","ts":"2026-06-02T01:05:29.646812Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.115:2380"} {"level":"info","ts":"2026-06-02T01:05:29.723282Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 is starting a new election at term 1"} {"level":"info","ts":"2026-06-02T01:05:29.723357Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-02T01:05:29.723388Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 received MsgPreVoteResp from e3aaa5c123e150e0 at term 1"} {"level":"info","ts":"2026-06-02T01:05:29.723475Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became candidate at term 2"} {"level":"info","ts":"2026-06-02T01:05:29.723524Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 received MsgVoteResp from e3aaa5c123e150e0 at term 2"} {"level":"info","ts":"2026-06-02T01:05:29.723552Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became leader at term 2"} {"level":"info","ts":"2026-06-02T01:05:29.72363Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e3aaa5c123e150e0 elected leader e3aaa5c123e150e0 at term 2"} {"level":"info","ts":"2026-06-02T01:05:29.724594Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T01:05:29.725603Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-02T01:05:29.725608Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e3aaa5c123e150e0","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.115:2379]}","request-path":"/0/members/e3aaa5c123e150e0/attributes","cluster-id":"61ec519698823541","publish-timeout":"7s"} {"level":"info","ts":"2026-06-02T01:05:29.725725Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-02T01:05:29.725908Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-02T01:05:29.725986Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-02T01:05:29.726255Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"61ec519698823541","local-member-id":"e3aaa5c123e150e0","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T01:05:29.726602Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T01:05:29.726774Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T01:05:29.728054Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-02T01:05:29.730524Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.115:2379"} {"level":"warn","ts":"2026-06-02T01:05:34.388547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.014306ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-02T01:05:34.388697Z","caller":"traceutil/trace.go:171","msg":"trace[1841569994] linearizableReadLoop","detail":"{readStateIndex:278; appliedIndex:277; }","duration":"140.716772ms","start":"2026-06-02T01:05:34.247947Z","end":"2026-06-02T01:05:34.388664Z","steps":["trace[1841569994] 'read index received' (duration: 36.901µs)","trace[1841569994] 'applied index is now lower than readState.Index' (duration: 140.67847ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T01:05:34.388741Z","caller":"traceutil/trace.go:171","msg":"trace[1393734255] transaction","detail":"{read_only:false; response_revision:272; number_of_response:1; }","duration":"179.679931ms","start":"2026-06-02T01:05:34.209021Z","end":"2026-06-02T01:05:34.388701Z","steps":["trace[1393734255] 'compare' (duration: 177.859481ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T01:05:34.388806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.860075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T01:05:34.388843Z","caller":"traceutil/trace.go:171","msg":"trace[1974664747] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:272; }","duration":"140.950368ms","start":"2026-06-02T01:05:34.247879Z","end":"2026-06-02T01:05:34.38883Z","steps":["trace[1974664747] 'agreement among raft nodes before linearized reading' (duration: 140.868455ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T01:05:42.581233Z","caller":"traceutil/trace.go:171","msg":"trace[2026289754] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"135.767539ms","start":"2026-06-02T01:05:42.445434Z","end":"2026-06-02T01:05:42.581201Z","steps":["trace[2026289754] 'process raft request' (duration: 135.553373ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T01:06:11.094468Z","caller":"traceutil/trace.go:171","msg":"trace[1638750879] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"120.304957ms","start":"2026-06-02T01:06:10.974129Z","end":"2026-06-02T01:06:11.094434Z","steps":["trace[1638750879] 'process raft request' (duration: 120.188124ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T01:06:11.223128Z","caller":"traceutil/trace.go:171","msg":"trace[868001197] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"245.014723ms","start":"2026-06-02T01:06:10.978078Z","end":"2026-06-02T01:06:11.223093Z","steps":["trace[868001197] 'process raft request' (duration: 244.916901ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T01:06:11.223209Z","caller":"traceutil/trace.go:171","msg":"trace[196860368] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"236.559805ms","start":"2026-06-02T01:06:10.986636Z","end":"2026-06-02T01:06:11.223196Z","steps":["trace[196860368] 'process raft request' (duration: 236.446662ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T01:06:11.223195Z","caller":"traceutil/trace.go:171","msg":"trace[890347331] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"236.519393ms","start":"2026-06-02T01:06:10.986627Z","end":"2026-06-02T01:06:11.223146Z","steps":["trace[890347331] 'process raft request' (duration: 236.40993ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T01:06:11.223127Z","caller":"traceutil/trace.go:171","msg":"trace[1787735244] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"245.045515ms","start":"2026-06-02T01:06:10.978034Z","end":"2026-06-02T01:06:11.223079Z","steps":["trace[1787735244] 'process raft request' (duration: 244.834289ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T01:06:11.223265Z","caller":"traceutil/trace.go:171","msg":"trace[901305861] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"236.137924ms","start":"2026-06-02T01:06:10.987074Z","end":"2026-06-02T01:06:11.223212Z","steps":["trace[901305861] 'process raft request' (duration: 236.046382ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T01:06:11.223379Z","caller":"traceutil/trace.go:171","msg":"trace[1908546802] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"234.966412ms","start":"2026-06-02T01:06:10.988323Z","end":"2026-06-02T01:06:11.22329Z","steps":["trace[1908546802] 'process raft request' (duration: 234.845618ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T01:06:11.223554Z","caller":"traceutil/trace.go:171","msg":"trace[57578078] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"224.693844ms","start":"2026-06-02T01:06:10.998781Z","end":"2026-06-02T01:06:11.223474Z","steps":["trace[57578078] 'process raft request' (duration: 224.425947ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T01:06:11.223659Z","caller":"traceutil/trace.go:171","msg":"trace[1686683600] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"209.740571ms","start":"2026-06-02T01:06:11.013906Z","end":"2026-06-02T01:06:11.223646Z","steps":["trace[1686683600] 'process raft request' (duration: 209.573266ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T01:06:11.223686Z","caller":"traceutil/trace.go:171","msg":"trace[816322353] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"178.299812ms","start":"2026-06-02T01:06:11.045368Z","end":"2026-06-02T01:06:11.223668Z","steps":["trace[816322353] 'process raft request' (duration: 178.2058ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T01:06:11.223661Z","caller":"traceutil/trace.go:171","msg":"trace[1387447216] linearizableReadLoop","detail":"{readStateIndex:561; appliedIndex:550; }","duration":"139.65134ms","start":"2026-06-02T01:06:11.083989Z","end":"2026-06-02T01:06:11.22364Z","steps":["trace[1387447216] 'read index received' (duration: 10.397182ms)","trace[1387447216] 'applied index is now lower than readState.Index' (duration: 129.253098ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T01:06:11.223793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.796073ms","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-06-02T01:06:11.223572Z","caller":"traceutil/trace.go:171","msg":"trace[534964215] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"218.370344ms","start":"2026-06-02T01:06:11.005185Z","end":"2026-06-02T01:06:11.223556Z","steps":["trace[534964215] 'process raft request' (duration: 218.099608ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T01:06:11.223847Z","caller":"traceutil/trace.go:171","msg":"trace[425412325] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:545; }","duration":"139.898386ms","start":"2026-06-02T01:06:11.083935Z","end":"2026-06-02T01:06:11.223833Z","steps":["trace[425412325] 'agreement among raft nodes before linearized reading' (duration: 139.767322ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T01:06:11.227463Z","caller":"traceutil/trace.go:171","msg":"trace[1818463193] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"125.730282ms","start":"2026-06-02T01:06:11.101718Z","end":"2026-06-02T01:06:11.227448Z","steps":["trace[1818463193] 'process raft request' (duration: 125.534117ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T01:06:11.227733Z","caller":"traceutil/trace.go:171","msg":"trace[1330356940] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"125.129177ms","start":"2026-06-02T01:06:11.102576Z","end":"2026-06-02T01:06:11.227705Z","steps":["trace[1330356940] 'process raft request' (duration: 124.772306ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T01:06:11.227874Z","caller":"traceutil/trace.go:171","msg":"trace[678985541] transaction","detail":"{read_only:false; number_of_response:1; response_revision:547; }","duration":"125.195529ms","start":"2026-06-02T01:06:11.102657Z","end":"2026-06-02T01:06:11.227853Z","steps":["trace[678985541] 'process raft request' (duration: 124.744497ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T01:06:17.406407Z","caller":"traceutil/trace.go:171","msg":"trace[243870858] linearizableReadLoop","detail":"{readStateIndex:663; appliedIndex:662; }","duration":"135.564109ms","start":"2026-06-02T01:06:17.270813Z","end":"2026-06-02T01:06:17.406377Z","steps":["trace[243870858] 'read index received' (duration: 134.707756ms)","trace[243870858] 'applied index is now lower than readState.Index' (duration: 855.143µs)"],"step_count":2} {"level":"warn","ts":"2026-06-02T01:06:17.406638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.813435ms","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-06-02T01:06:17.406681Z","caller":"traceutil/trace.go:171","msg":"trace[885388181] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:642; }","duration":"135.919688ms","start":"2026-06-02T01:06:17.270749Z","end":"2026-06-02T01:06:17.406669Z","steps":["trace[885388181] 'agreement among raft nodes before linearized reading' (duration: 135.771984ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T01:06:17.406643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.089914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T01:06:17.406628Z","caller":"traceutil/trace.go:171","msg":"trace[1466292504] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"393.542423ms","start":"2026-06-02T01:06:17.013048Z","end":"2026-06-02T01:06:17.40659Z","steps":["trace[1466292504] 'process raft request' (duration: 392.74016ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T01:06:17.40675Z","caller":"traceutil/trace.go:171","msg":"trace[1381619310] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:642; }","duration":"109.232228ms","start":"2026-06-02T01:06:17.297487Z","end":"2026-06-02T01:06:17.406719Z","steps":["trace[1381619310] 'agreement among raft nodes before linearized reading' (duration: 109.066593ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T01:06:17.407166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T01:06:17.013015Z","time spent":"393.842099ms","remote":"127.0.0.1:40136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036277,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T01:06:17.913363Z","caller":"traceutil/trace.go:171","msg":"trace[508824108] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:665; }","duration":"148.047836ms","start":"2026-06-02T01:06:17.765279Z","end":"2026-06-02T01:06:17.913327Z","steps":["trace[508824108] 'read index received' (duration: 147.884601ms)","trace[508824108] 'applied index is now lower than readState.Index' (duration: 161.735µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T01:06:17.913489Z","caller":"traceutil/trace.go:171","msg":"trace[903280030] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"153.791131ms","start":"2026-06-02T01:06:17.75967Z","end":"2026-06-02T01:06:17.913462Z","steps":["trace[903280030] 'process raft request' (duration: 153.437302ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T01:06:17.913601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.301233ms","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-06-02T01:06:17.913651Z","caller":"traceutil/trace.go:171","msg":"trace[1839010600] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:645; }","duration":"148.417185ms","start":"2026-06-02T01:06:17.765221Z","end":"2026-06-02T01:06:17.913638Z","steps":["trace[1839010600] 'agreement among raft nodes before linearized reading' (duration: 148.23905ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T01:06:18.679187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.754816ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T01:06:18.679436Z","caller":"traceutil/trace.go:171","msg":"trace[164990372] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"761.831995ms","start":"2026-06-02T01:06:17.917576Z","end":"2026-06-02T01:06:18.679408Z","steps":["trace[164990372] 'process raft request' (duration: 761.727312ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T01:06:18.679437Z","caller":"traceutil/trace.go:171","msg":"trace[730584338] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"780.354135ms","start":"2026-06-02T01:06:17.899014Z","end":"2026-06-02T01:06:18.679368Z","steps":["trace[730584338] 'process raft request' (duration: 585.331981ms)","trace[730584338] 'compare' (duration: 194.360897ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T01:06:18.679588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T01:06:17.917561Z","time spent":"761.946368ms","remote":"127.0.0.1:40298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T01:06:18.679609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T01:06:17.898987Z","time spent":"780.534189ms","remote":"127.0.0.1:40136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036301,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T01:06:18.705108Z","caller":"traceutil/trace.go:171","msg":"trace[397099948] linearizableReadLoop","detail":"{readStateIndex:668; appliedIndex:666; }","duration":"681.23655ms","start":"2026-06-02T01:06:18.023829Z","end":"2026-06-02T01:06:18.705065Z","steps":["trace[397099948] 'read index received' (duration: 460.309656ms)","trace[397099948] 'applied index is now lower than readState.Index' (duration: 220.924524ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T01:06:18.705358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"681.527717ms","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-06-02T01:06:18.705405Z","caller":"traceutil/trace.go:171","msg":"trace[1894871915] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:647; }","duration":"681.612429ms","start":"2026-06-02T01:06:18.023783Z","end":"2026-06-02T01:06:18.705395Z","steps":["trace[1894871915] 'agreement among raft nodes before linearized reading' (duration: 681.405243ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T01:06:18.705441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T01:06:18.023765Z","time spent":"681.669072ms","remote":"127.0.0.1:40298","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-06-02T01:06:32.872749Z","caller":"traceutil/trace.go:171","msg":"trace[951626703] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"123.965196ms","start":"2026-06-02T01:06:32.748756Z","end":"2026-06-02T01:06:32.872722Z","steps":["trace[951626703] 'process raft request' (duration: 123.814192ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T01:06:32.872819Z","caller":"traceutil/trace.go:171","msg":"trace[292819944] linearizableReadLoop","detail":"{readStateIndex:828; appliedIndex:828; }","duration":"107.326958ms","start":"2026-06-02T01:06:32.765459Z","end":"2026-06-02T01:06:32.872786Z","steps":["trace[292819944] 'read index received' (duration: 107.305707ms)","trace[292819944] 'applied index is now lower than readState.Index' (duration: 18.761µs)"],"step_count":2} {"level":"warn","ts":"2026-06-02T01:06:32.873082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.604995ms","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-06-02T01:06:32.873128Z","caller":"traceutil/trace.go:171","msg":"trace[460823783] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:803; }","duration":"107.696347ms","start":"2026-06-02T01:06:32.765421Z","end":"2026-06-02T01:06:32.873117Z","steps":["trace[460823783] 'agreement among raft nodes before linearized reading' (duration: 107.498681ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T01:06:32.945799Z","caller":"traceutil/trace.go:171","msg":"trace[1623966356] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"135.899398ms","start":"2026-06-02T01:06:32.809875Z","end":"2026-06-02T01:06:32.945775Z","steps":["trace[1623966356] 'process raft request' (duration: 135.310172ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T01:06:32.94586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.479287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-06-02T01:06:32.945913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.264192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-wbr5q\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-06-02T01:06:32.945937Z","caller":"traceutil/trace.go:171","msg":"trace[532144651] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:804; }","duration":"125.568209ms","start":"2026-06-02T01:06:32.820349Z","end":"2026-06-02T01:06:32.945917Z","steps":["trace[532144651] 'agreement among raft nodes before linearized reading' (duration: 125.361434ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T01:06:32.945966Z","caller":"traceutil/trace.go:171","msg":"trace[1943614623] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-wbr5q; range_end:; response_count:1; response_revision:804; }","duration":"108.345994ms","start":"2026-06-02T01:06:32.837602Z","end":"2026-06-02T01:06:32.945948Z","steps":["trace[1943614623] 'agreement among raft nodes before linearized reading' (duration: 108.19058ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T01:07:38.710032Z","caller":"traceutil/trace.go:171","msg":"trace[854022935] transaction","detail":"{read_only:false; response_revision:1743; number_of_response:1; }","duration":"117.985725ms","start":"2026-06-02T01:07:38.592018Z","end":"2026-06-02T01:07:38.710003Z","steps":["trace[854022935] 'process raft request' (duration: 86.842874ms)","trace[854022935] 'compare' (duration: 30.929335ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T01:07:38.71031Z","caller":"traceutil/trace.go:171","msg":"trace[1213988684] transaction","detail":"{read_only:false; response_revision:1744; number_of_response:1; }","duration":"116.967347ms","start":"2026-06-02T01:07:38.593314Z","end":"2026-06-02T01:07:38.710281Z","steps":["trace[1213988684] 'process raft request' (duration: 116.629868ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T01:07:48.419456Z","caller":"traceutil/trace.go:171","msg":"trace[226559697] linearizableReadLoop","detail":"{readStateIndex:1946; appliedIndex:1945; }","duration":"233.515502ms","start":"2026-06-02T01:07:48.185913Z","end":"2026-06-02T01:07:48.419428Z","steps":["trace[226559697] 'read index received' (duration: 233.440171ms)","trace[226559697] 'applied index is now lower than readState.Index' (duration: 74.241µs)"],"step_count":2} {"level":"warn","ts":"2026-06-02T01:07:48.41974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.80409ms","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-06-02T01:07:48.419827Z","caller":"traceutil/trace.go:171","msg":"trace[943331569] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1875; }","duration":"233.942794ms","start":"2026-06-02T01:07:48.185871Z","end":"2026-06-02T01:07:48.419814Z","steps":["trace[943331569] 'agreement among raft nodes before linearized reading' (duration: 233.676158ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T01:07:48.420083Z","caller":"traceutil/trace.go:171","msg":"trace[435727709] transaction","detail":"{read_only:false; response_revision:1875; number_of_response:1; }","duration":"242.450644ms","start":"2026-06-02T01:07:48.177046Z","end":"2026-06-02T01:07:48.419496Z","steps":["trace[435727709] 'process raft request' (duration: 242.215167ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T01:07:48.507378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.630291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-02T01:07:48.507424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.222387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7d8a3c1a-88eb-4380-a297-e598e5b6de70\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-06-02T01:07:48.507464Z","caller":"traceutil/trace.go:171","msg":"trace[993121121] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1875; }","duration":"208.776216ms","start":"2026-06-02T01:07:48.298665Z","end":"2026-06-02T01:07:48.507442Z","steps":["trace[993121121] 'agreement among raft nodes before linearized reading' (duration: 208.615271ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T01:07:48.507499Z","caller":"traceutil/trace.go:171","msg":"trace[753186872] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7d8a3c1a-88eb-4380-a297-e598e5b6de70; range_end:; response_count:1; response_revision:1875; }","duration":"272.310801ms","start":"2026-06-02T01:07:48.235169Z","end":"2026-06-02T01:07:48.50748Z","steps":["trace[753186872] 'agreement among raft nodes before linearized reading' (duration: 272.129465ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T01:07:48.922162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.245988ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T01:07:48.922411Z","caller":"traceutil/trace.go:171","msg":"trace[196946195] transaction","detail":"{read_only:false; response_revision:1879; number_of_response:1; }","duration":"326.767691ms","start":"2026-06-02T01:07:48.595607Z","end":"2026-06-02T01:07:48.922375Z","steps":["trace[196946195] 'process raft request' (duration: 33.996028ms)","trace[196946195] 'compare' (duration: 292.127915ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T01:07:48.922451Z","caller":"traceutil/trace.go:171","msg":"trace[595135156] transaction","detail":"{read_only:false; response_revision:1880; number_of_response:1; }","duration":"326.806331ms","start":"2026-06-02T01:07:48.59563Z","end":"2026-06-02T01:07:48.922436Z","steps":["trace[595135156] 'process raft request' (duration: 326.656637ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T01:07:48.922545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T01:07:48.595595Z","time spent":"326.891194ms","remote":"127.0.0.1:40308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1530,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T01:07:48.922544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T01:07:48.595607Z","time spent":"326.892334ms","remote":"127.0.0.1:40210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":894,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T01:07:48.922693Z","caller":"traceutil/trace.go:171","msg":"trace[1174125054] transaction","detail":"{read_only:false; response_revision:1881; number_of_response:1; }","duration":"326.635586ms","start":"2026-06-02T01:07:48.596045Z","end":"2026-06-02T01:07:48.92268Z","steps":["trace[1174125054] 'process raft request' (duration: 326.338889ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T01:07:48.922772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T01:07:48.596027Z","time spent":"326.703409ms","remote":"127.0.0.1:40308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1472,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T01:07:48.922919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.017712ms","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-06-02T01:07:48.922769Z","caller":"traceutil/trace.go:171","msg":"trace[458162274] linearizableReadLoop","detail":"{readStateIndex:1954; appliedIndex:1950; }","duration":"118.850738ms","start":"2026-06-02T01:07:48.8039Z","end":"2026-06-02T01:07:48.922751Z","steps":["trace[458162274] 'read index received' (duration: 107.744019ms)","trace[458162274] 'applied index is now lower than readState.Index' (duration: 11.105839ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T01:07:48.922958Z","caller":"traceutil/trace.go:171","msg":"trace[712656534] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1882; }","duration":"119.107625ms","start":"2026-06-02T01:07:48.803838Z","end":"2026-06-02T01:07:48.922945Z","steps":["trace[712656534] 'agreement among raft nodes before linearized reading' (duration: 118.971161ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T01:07:48.9231Z","caller":"traceutil/trace.go:171","msg":"trace[819407905] transaction","detail":"{read_only:false; response_revision:1882; number_of_response:1; }","duration":"326.700877ms","start":"2026-06-02T01:07:48.596383Z","end":"2026-06-02T01:07:48.923084Z","steps":["trace[819407905] 'process raft request' (duration: 326.239365ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T01:07:48.923195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T01:07:48.596371Z","time spent":"326.776721ms","remote":"127.0.0.1:40210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":978,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T01:08:33.63188Z","caller":"traceutil/trace.go:171","msg":"trace[1611732505] transaction","detail":"{read_only:false; response_revision:2173; number_of_response:1; }","duration":"114.031015ms","start":"2026-06-02T01:08:33.517821Z","end":"2026-06-02T01:08:33.631852Z","steps":["trace[1611732505] 'process raft request' (duration: 113.875921ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T01:08:39.738864Z","caller":"traceutil/trace.go:171","msg":"trace[790320183] linearizableReadLoop","detail":"{readStateIndex:2289; appliedIndex:2288; }","duration":"260.336886ms","start":"2026-06-02T01:08:39.478503Z","end":"2026-06-02T01:08:39.738839Z","steps":["trace[790320183] 'read index received' (duration: 260.11974ms)","trace[790320183] 'applied index is now lower than readState.Index' (duration: 216.006µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T01:08:39.738918Z","caller":"traceutil/trace.go:171","msg":"trace[1802099174] transaction","detail":"{read_only:false; response_revision:2206; number_of_response:1; }","duration":"305.367973ms","start":"2026-06-02T01:08:39.433526Z","end":"2026-06-02T01:08:39.738894Z","steps":["trace[1802099174] 'process raft request' (duration: 305.176048ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T01:08:39.739036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.506642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-02T01:08:39.739152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T01:08:39.433501Z","time spent":"305.578019ms","remote":"127.0.0.1:40298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T01:08:39.739221Z","caller":"traceutil/trace.go:171","msg":"trace[699692564] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:2206; }","duration":"260.733797ms","start":"2026-06-02T01:08:39.478451Z","end":"2026-06-02T01:08:39.739184Z","steps":["trace[699692564] 'agreement among raft nodes before linearized reading' (duration: 260.511121ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T01:08:40.131114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.808468ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T01:08:40.131285Z","caller":"traceutil/trace.go:171","msg":"trace[1826467721] transaction","detail":"{read_only:false; response_revision:2207; number_of_response:1; }","duration":"387.683319ms","start":"2026-06-02T01:08:39.743558Z","end":"2026-06-02T01:08:40.131241Z","steps":["trace[1826467721] 'process raft request' (duration: 88.624105ms)","trace[1826467721] 'compare' (duration: 298.680514ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T01:08:40.131349Z","caller":"traceutil/trace.go:171","msg":"trace[1382964354] linearizableReadLoop","detail":"{readStateIndex:2290; appliedIndex:2289; }","duration":"385.241344ms","start":"2026-06-02T01:08:39.746084Z","end":"2026-06-02T01:08:40.131325Z","steps":["trace[1382964354] 'read index received' (duration: 86.112018ms)","trace[1382964354] 'applied index is now lower than readState.Index' (duration: 299.127806ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T01:08:40.131629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T01:08:39.743537Z","time spent":"387.819823ms","remote":"127.0.0.1:40298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T01:08:40.132038Z","caller":"traceutil/trace.go:171","msg":"trace[242119688] transaction","detail":"{read_only:false; response_revision:2208; number_of_response:1; }","duration":"373.785277ms","start":"2026-06-02T01:08:39.758234Z","end":"2026-06-02T01:08:40.132019Z","steps":["trace[242119688] 'process raft request' (duration: 373.019276ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T01:08:40.132114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T01:08:39.758218Z","time spent":"373.859209ms","remote":"127.0.0.1:40298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T01:08:40.132201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.712948ms","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-06-02T01:08:40.132271Z","caller":"traceutil/trace.go:171","msg":"trace[2018017986] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2208; }","duration":"319.825491ms","start":"2026-06-02T01:08:39.812432Z","end":"2026-06-02T01:08:40.132258Z","steps":["trace[2018017986] 'agreement among raft nodes before linearized reading' (duration: 319.649656ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T01:08:40.132298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.21807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-02T01:08:40.132312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T01:08:39.812411Z","time spent":"319.889743ms","remote":"127.0.0.1:40298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-02T01:08:40.132336Z","caller":"traceutil/trace.go:171","msg":"trace[600828636] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:2208; }","duration":"386.287362ms","start":"2026-06-02T01:08:39.746039Z","end":"2026-06-02T01:08:40.132327Z","steps":["trace[600828636] 'agreement among raft nodes before linearized reading' (duration: 385.432769ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T01:08:40.132365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T01:08:39.74602Z","time spent":"386.336873ms","remote":"127.0.0.1:38946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-06-02T01:08:40.132285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.842533ms","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-06-02T01:08:40.132532Z","caller":"traceutil/trace.go:171","msg":"trace[110713249] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2208; }","duration":"354.13378ms","start":"2026-06-02T01:08:39.778374Z","end":"2026-06-02T01:08:40.132507Z","steps":["trace[110713249] 'agreement among raft nodes before linearized reading' (duration: 353.611996ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T01:08:40.132667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T01:08:39.778351Z","time spent":"354.216572ms","remote":"127.0.0.1:40298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-02T01:10:08.568272Z","caller":"traceutil/trace.go:171","msg":"trace[426766576] transaction","detail":"{read_only:false; response_revision:2877; number_of_response:1; }","duration":"133.977162ms","start":"2026-06-02T01:10:08.434269Z","end":"2026-06-02T01:10:08.568246Z","steps":["trace[426766576] 'process raft request' (duration: 133.837587ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T01:10:09.016509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.424109ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-02T01:10:09.01686Z","caller":"traceutil/trace.go:171","msg":"trace[669830616] linearizableReadLoop","detail":"{readStateIndex:2988; appliedIndex:2986; }","duration":"432.298503ms","start":"2026-06-02T01:10:08.584538Z","end":"2026-06-02T01:10:09.016836Z","steps":["trace[669830616] 'read index received' (duration: 114.638707ms)","trace[669830616] 'applied index is now lower than readState.Index' (duration: 317.658615ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T01:10:09.016926Z","caller":"traceutil/trace.go:171","msg":"trace[1315608353] transaction","detail":"{read_only:false; response_revision:2878; number_of_response:1; }","duration":"464.429452ms","start":"2026-06-02T01:10:08.552476Z","end":"2026-06-02T01:10:09.016906Z","steps":["trace[1315608353] 'process raft request' (duration: 464.145214ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T01:10:09.017058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T01:10:08.552441Z","time spent":"464.539084ms","remote":"127.0.0.1:40298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T01:10:09.017127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.940265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-06-02T01:10:09.017137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.598592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-06-02T01:10:09.017194Z","caller":"traceutil/trace.go:171","msg":"trace[1066089890] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2879; }","duration":"421.04974ms","start":"2026-06-02T01:10:08.596128Z","end":"2026-06-02T01:10:09.017178Z","steps":["trace[1066089890] 'agreement among raft nodes before linearized reading' (duration: 420.878195ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T01:10:09.017225Z","caller":"traceutil/trace.go:171","msg":"trace[1524809257] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2879; }","duration":"432.725675ms","start":"2026-06-02T01:10:08.584464Z","end":"2026-06-02T01:10:09.01719Z","steps":["trace[1524809257] 'agreement among raft nodes before linearized reading' (duration: 432.496639ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T01:10:09.017248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T01:10:08.596104Z","time spent":"421.131032ms","remote":"127.0.0.1:40298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-02T01:10:09.017268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T01:10:08.584435Z","time spent":"432.822637ms","remote":"127.0.0.1:40298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":553,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-06-02T01:10:09.017387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.063878ms","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-06-02T01:10:09.01744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.401809ms","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-06-02T01:10:09.017475Z","caller":"traceutil/trace.go:171","msg":"trace[789801984] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2879; }","duration":"377.479502ms","start":"2026-06-02T01:10:08.639985Z","end":"2026-06-02T01:10:09.017465Z","steps":["trace[789801984] 'agreement among raft nodes before linearized reading' (duration: 377.382029ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T01:10:09.017509Z","caller":"traceutil/trace.go:171","msg":"trace[1423910481] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2879; }","duration":"388.182841ms","start":"2026-06-02T01:10:08.629264Z","end":"2026-06-02T01:10:09.017447Z","steps":["trace[1423910481] 'agreement among raft nodes before linearized reading' (duration: 387.968236ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T01:10:09.017523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T01:10:08.639967Z","time spent":"377.549274ms","remote":"127.0.0.1:40298","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-06-02T01:10:09.017569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T01:10:08.629244Z","time spent":"388.310054ms","remote":"127.0.0.1:40298","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":"warn","ts":"2026-06-02T01:10:09.01762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.926123ms","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-06-02T01:10:09.017734Z","caller":"traceutil/trace.go:171","msg":"trace[1108446773] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2879; }","duration":"395.098868ms","start":"2026-06-02T01:10:08.6226Z","end":"2026-06-02T01:10:09.017699Z","steps":["trace[1108446773] 'agreement among raft nodes before linearized reading' (duration: 394.734308ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T01:10:09.017787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T01:10:08.622576Z","time spent":"395.20182ms","remote":"127.0.0.1:40298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-02T01:14:06.210584Z","caller":"traceutil/trace.go:171","msg":"trace[353799103] linearizableReadLoop","detail":"{readStateIndex:5267; appliedIndex:5266; }","duration":"280.574816ms","start":"2026-06-02T01:14:05.929984Z","end":"2026-06-02T01:14:06.210559Z","steps":["trace[353799103] 'read index received' (duration: 280.461643ms)","trace[353799103] 'applied index is now lower than readState.Index' (duration: 112.013µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T01:14:06.210638Z","caller":"traceutil/trace.go:171","msg":"trace[1079403039] transaction","detail":"{read_only:false; response_revision:5078; number_of_response:1; }","duration":"364.326542ms","start":"2026-06-02T01:14:05.84627Z","end":"2026-06-02T01:14:06.210597Z","steps":["trace[1079403039] 'process raft request' (duration: 364.133027ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T01:14:06.210817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T01:14:05.846232Z","time spent":"364.487307ms","remote":"127.0.0.1:40298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T01:14:06.210832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.838692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c7c04f9e-50e6-430d-959d-880878282216\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-06-02T01:14:06.210873Z","caller":"traceutil/trace.go:171","msg":"trace[573629785] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c7c04f9e-50e6-430d-959d-880878282216; range_end:; response_count:1; response_revision:5078; }","duration":"280.937895ms","start":"2026-06-02T01:14:05.929922Z","end":"2026-06-02T01:14:06.21086Z","steps":["trace[573629785] 'agreement among raft nodes before linearized reading' (duration: 280.76503ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T01:14:06.623594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.057277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-06-02T01:14:06.623722Z","caller":"traceutil/trace.go:171","msg":"trace[2145390844] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5087; }","duration":"120.255552ms","start":"2026-06-02T01:14:06.503428Z","end":"2026-06-02T01:14:06.623684Z","steps":["trace[2145390844] 'range keys from in-memory index tree' (duration: 119.838591ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T01:14:06.623764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.740888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T01:14:06.623875Z","caller":"traceutil/trace.go:171","msg":"trace[1803908137] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5087; }","duration":"171.900052ms","start":"2026-06-02T01:14:06.451949Z","end":"2026-06-02T01:14:06.623849Z","steps":["trace[1803908137] 'range keys from in-memory index tree' (duration: 171.641526ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T01:14:19.390812Z","caller":"traceutil/trace.go:171","msg":"trace[1706668888] linearizableReadLoop","detail":"{readStateIndex:5523; appliedIndex:5522; }","duration":"205.352216ms","start":"2026-06-02T01:14:19.185423Z","end":"2026-06-02T01:14:19.390775Z","steps":["trace[1706668888] 'read index received' (duration: 205.188261ms)","trace[1706668888] 'applied index is now lower than readState.Index' (duration: 162.324µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T01:14:19.391003Z","caller":"traceutil/trace.go:171","msg":"trace[1876993761] transaction","detail":"{read_only:false; response_revision:5326; number_of_response:1; }","duration":"257.15886ms","start":"2026-06-02T01:14:19.13383Z","end":"2026-06-02T01:14:19.390989Z","steps":["trace[1876993761] 'process raft request' (duration: 256.663677ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T01:14:19.39137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.925421ms","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-06-02T01:14:19.391441Z","caller":"traceutil/trace.go:171","msg":"trace[936928505] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5326; }","duration":"206.058925ms","start":"2026-06-02T01:14:19.185363Z","end":"2026-06-02T01:14:19.391422Z","steps":["trace[936928505] 'agreement among raft nodes before linearized reading' (duration: 205.959362ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T01:14:19.581465Z","caller":"traceutil/trace.go:171","msg":"trace[1245807161] transaction","detail":"{read_only:false; response_revision:5327; number_of_response:1; }","duration":"181.454328ms","start":"2026-06-02T01:14:19.399983Z","end":"2026-06-02T01:14:19.581437Z","steps":["trace[1245807161] 'process raft request' (duration: 180.602465ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T01:14:20.442878Z","caller":"traceutil/trace.go:171","msg":"trace[10961746] transaction","detail":"{read_only:false; response_revision:5347; number_of_response:1; }","duration":"117.702923ms","start":"2026-06-02T01:14:20.325115Z","end":"2026-06-02T01:14:20.442818Z","steps":["trace[10961746] 'process raft request' (duration: 117.349874ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T01:14:20.499945Z","caller":"traceutil/trace.go:171","msg":"trace[1865081629] transaction","detail":"{read_only:false; response_revision:5348; number_of_response:1; }","duration":"164.080794ms","start":"2026-06-02T01:14:20.335831Z","end":"2026-06-02T01:14:20.499912Z","steps":["trace[1865081629] 'process raft request' (duration: 162.402859ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T01:14:27.860426Z","caller":"traceutil/trace.go:171","msg":"trace[2078443017] linearizableReadLoop","detail":"{readStateIndex:5728; appliedIndex:5727; }","duration":"153.11243ms","start":"2026-06-02T01:14:27.707279Z","end":"2026-06-02T01:14:27.860392Z","steps":["trace[2078443017] 'read index received' (duration: 152.856284ms)","trace[2078443017] 'applied index is now lower than readState.Index' (duration: 254.656µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T01:14:27.860498Z","caller":"traceutil/trace.go:171","msg":"trace[497263343] transaction","detail":"{read_only:false; response_revision:5527; number_of_response:1; }","duration":"243.031092ms","start":"2026-06-02T01:14:27.617428Z","end":"2026-06-02T01:14:27.860459Z","steps":["trace[497263343] 'process raft request' (duration: 242.730584ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T01:14:27.860758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.77754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-44fe46ff-d1e9-47d4-9e3b-231240b23a08\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-06-02T01:14:27.860812Z","caller":"traceutil/trace.go:171","msg":"trace[1662780916] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-44fe46ff-d1e9-47d4-9e3b-231240b23a08; range_end:; response_count:1; response_revision:5527; }","duration":"134.889943ms","start":"2026-06-02T01:14:27.725906Z","end":"2026-06-02T01:14:27.860796Z","steps":["trace[1662780916] 'agreement among raft nodes before linearized reading' (duration: 134.684778ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T01:14:27.860855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.548303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8wk95\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-06-02T01:14:27.860979Z","caller":"traceutil/trace.go:171","msg":"trace[559952888] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8wk95; range_end:; response_count:1; response_revision:5527; }","duration":"153.746828ms","start":"2026-06-02T01:14:27.707206Z","end":"2026-06-02T01:14:27.860953Z","steps":["trace[559952888] 'agreement among raft nodes before linearized reading' (duration: 153.374368ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T01:14:28.080064Z","caller":"traceutil/trace.go:171","msg":"trace[1279014207] transaction","detail":"{read_only:false; response_revision:5528; number_of_response:1; }","duration":"134.111422ms","start":"2026-06-02T01:14:27.945918Z","end":"2026-06-02T01:14:28.080029Z","steps":["trace[1279014207] 'process raft request' (duration: 120.784136ms)","trace[1279014207] 'compare' (duration: 13.006338ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T01:14:34.774656Z","caller":"traceutil/trace.go:171","msg":"trace[100289641] transaction","detail":"{read_only:false; response_revision:5623; number_of_response:1; }","duration":"127.031793ms","start":"2026-06-02T01:14:34.64759Z","end":"2026-06-02T01:14:34.774622Z","steps":["trace[100289641] 'process raft request' (duration: 126.756286ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T01:14:34.782629Z","caller":"traceutil/trace.go:171","msg":"trace[453683516] transaction","detail":"{read_only:false; response_revision:5624; number_of_response:1; }","duration":"109.245429ms","start":"2026-06-02T01:14:34.673352Z","end":"2026-06-02T01:14:34.782598Z","steps":["trace[453683516] 'process raft request' (duration: 109.109615ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T01:14:40.042719Z","caller":"traceutil/trace.go:171","msg":"trace[497590324] linearizableReadLoop","detail":"{readStateIndex:5861; appliedIndex:5860; }","duration":"114.15547ms","start":"2026-06-02T01:14:39.928538Z","end":"2026-06-02T01:14:40.042693Z","steps":["trace[497590324] 'read index received' (duration: 113.855361ms)","trace[497590324] 'applied index is now lower than readState.Index' (duration: 299.099µs)"],"step_count":2} {"level":"warn","ts":"2026-06-02T01:14:40.042967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.459747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8wk95\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-06-02T01:14:40.042899Z","caller":"traceutil/trace.go:171","msg":"trace[1570885405] transaction","detail":"{read_only:false; response_revision:5656; number_of_response:1; }","duration":"140.973336ms","start":"2026-06-02T01:14:39.901868Z","end":"2026-06-02T01:14:40.042841Z","steps":["trace[1570885405] 'process raft request' (duration: 140.610336ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T01:14:40.043012Z","caller":"traceutil/trace.go:171","msg":"trace[2065272047] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8wk95; range_end:; response_count:1; response_revision:5656; }","duration":"114.565051ms","start":"2026-06-02T01:14:39.928436Z","end":"2026-06-02T01:14:40.043001Z","steps":["trace[2065272047] 'agreement among raft nodes before linearized reading' (duration: 114.363235ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T01:15:19.322304Z","caller":"traceutil/trace.go:171","msg":"trace[1583166209] transaction","detail":"{read_only:false; response_revision:5991; number_of_response:1; }","duration":"114.378304ms","start":"2026-06-02T01:15:19.207877Z","end":"2026-06-02T01:15:19.322256Z","steps":["trace[1583166209] 'process raft request' (duration: 113.825259ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T01:15:30.073908Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2998} {"level":"info","ts":"2026-06-02T01:15:30.145948Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2998,"took":"70.210205ms","hash":4262977324,"current-db-size-bytes":58286080,"current-db-size":"58 MB","current-db-size-in-use-bytes":33980416,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-02T01:15:30.146036Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4262977324,"revision":2998,"compact-revision":-1} {"level":"info","ts":"2026-06-02T01:20:30.080678Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6077} {"level":"info","ts":"2026-06-02T01:20:30.183239Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6077,"took":"100.894526ms","hash":142662518,"current-db-size-bytes":58286080,"current-db-size":"58 MB","current-db-size-in-use-bytes":22007808,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-06-02T01:20:30.183318Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":142662518,"revision":6077,"compact-revision":2998} {"level":"warn","ts":"2026-06-02T01:20:37.096602Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827832215597114346,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-02T01:20:37.107949Z","caller":"traceutil/trace.go:171","msg":"trace[1004465424] transaction","detail":"{read_only:false; response_revision:8338; number_of_response:1; }","duration":"512.791608ms","start":"2026-06-02T01:20:36.595131Z","end":"2026-06-02T01:20:37.107923Z","steps":["trace[1004465424] 'process raft request' (duration: 512.626073ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T01:20:37.108096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T01:20:36.595115Z","time spent":"512.902831ms","remote":"127.0.0.1:40298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T01:20:37.165979Z","caller":"traceutil/trace.go:171","msg":"trace[1249298306] linearizableReadLoop","detail":"{readStateIndex:8631; appliedIndex:8627; }","duration":"570.341186ms","start":"2026-06-02T01:20:36.595614Z","end":"2026-06-02T01:20:37.165955Z","steps":["trace[1249298306] 'read index received' (duration: 512.202742ms)","trace[1249298306] 'applied index is now lower than readState.Index' (duration: 58.137654ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T01:20:37.166002Z","caller":"traceutil/trace.go:171","msg":"trace[299722053] transaction","detail":"{read_only:false; response_revision:8339; number_of_response:1; }","duration":"570.223053ms","start":"2026-06-02T01:20:36.595743Z","end":"2026-06-02T01:20:37.165966Z","steps":["trace[299722053] 'process raft request' (duration: 570.006037ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T01:20:37.166062Z","caller":"traceutil/trace.go:171","msg":"trace[803940957] transaction","detail":"{read_only:false; response_revision:8340; number_of_response:1; }","duration":"570.090658ms","start":"2026-06-02T01:20:36.595947Z","end":"2026-06-02T01:20:37.166038Z","steps":["trace[803940957] 'process raft request' (duration: 569.952255ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T01:20:37.166183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T01:20:36.595719Z","time spent":"570.354636ms","remote":"127.0.0.1:40298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T01:20:37.166235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.610943ms","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-06-02T01:20:37.166261Z","caller":"traceutil/trace.go:171","msg":"trace[1795731237] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8341; }","duration":"570.689245ms","start":"2026-06-02T01:20:36.595566Z","end":"2026-06-02T01:20:37.166255Z","steps":["trace[1795731237] 'agreement among raft nodes before linearized reading' (duration: 570.534842ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T01:20:37.166293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T01:20:36.595542Z","time spent":"570.741517ms","remote":"127.0.0.1:40298","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-06-02T01:20:37.166379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.672387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-06-02T01:20:37.166428Z","caller":"traceutil/trace.go:171","msg":"trace[1104030308] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8341; }","duration":"361.76002ms","start":"2026-06-02T01:20:36.804657Z","end":"2026-06-02T01:20:37.166417Z","steps":["trace[1104030308] 'agreement among raft nodes before linearized reading' (duration: 361.635067ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T01:20:37.166464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T01:20:36.804639Z","time spent":"361.818271ms","remote":"127.0.0.1:40298","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-06-02T01:20:37.166088Z","caller":"traceutil/trace.go:171","msg":"trace[1938966077] transaction","detail":"{read_only:false; response_revision:8341; number_of_response:1; }","duration":"225.859957ms","start":"2026-06-02T01:20:36.940221Z","end":"2026-06-02T01:20:37.166081Z","steps":["trace[1938966077] 'process raft request' (duration: 225.704212ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T01:20:37.166663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T01:20:36.595933Z","time spent":"570.208091ms","remote":"127.0.0.1:40298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T01:20:50.005911Z","caller":"traceutil/trace.go:171","msg":"trace[1305219161] transaction","detail":"{read_only:false; response_revision:8408; number_of_response:1; }","duration":"127.081507ms","start":"2026-06-02T01:20:49.878801Z","end":"2026-06-02T01:20:50.005883Z","steps":["trace[1305219161] 'process raft request' (duration: 126.885531ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T01:21:18.046721Z","caller":"traceutil/trace.go:171","msg":"trace[2030127892] transaction","detail":"{read_only:false; response_revision:8612; number_of_response:1; }","duration":"398.940065ms","start":"2026-06-02T01:21:17.647743Z","end":"2026-06-02T01:21:18.046683Z","steps":["trace[2030127892] 'process raft request' (duration: 398.702779ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T01:21:18.046999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T01:21:17.647714Z","time spent":"399.136869ms","remote":"127.0.0.1:40298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T01:21:18.237596Z","caller":"traceutil/trace.go:171","msg":"trace[1052954477] transaction","detail":"{read_only:false; response_revision:8613; number_of_response:1; }","duration":"485.146629ms","start":"2026-06-02T01:21:17.752428Z","end":"2026-06-02T01:21:18.237575Z","steps":["trace[1052954477] 'process raft request' (duration: 484.992955ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T01:21:18.237596Z","caller":"traceutil/trace.go:171","msg":"trace[2052476165] linearizableReadLoop","detail":"{readStateIndex:8914; appliedIndex:8913; }","duration":"354.848886ms","start":"2026-06-02T01:21:17.882686Z","end":"2026-06-02T01:21:18.237535Z","steps":["trace[2052476165] 'read index received' (duration: 164.60877ms)","trace[2052476165] 'applied index is now lower than readState.Index' (duration: 190.239346ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T01:21:18.237736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.043331ms","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-06-02T01:21:18.23779Z","caller":"traceutil/trace.go:171","msg":"trace[1484098578] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8613; }","duration":"355.109222ms","start":"2026-06-02T01:21:17.882668Z","end":"2026-06-02T01:21:18.237777Z","steps":["trace[1484098578] 'agreement among raft nodes before linearized reading' (duration: 355.010139ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T01:21:18.237741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T01:21:17.752393Z","time spent":"485.277392ms","remote":"127.0.0.1:40298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T01:21:18.237823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T01:21:17.882606Z","time spent":"355.207175ms","remote":"127.0.0.1:34962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-06-02T01:21:18.238372Z","caller":"traceutil/trace.go:171","msg":"trace[802877801] transaction","detail":"{read_only:false; response_revision:8614; number_of_response:1; }","duration":"122.427643ms","start":"2026-06-02T01:21:18.115934Z","end":"2026-06-02T01:21:18.238362Z","steps":["trace[802877801] 'process raft request' (duration: 122.365771ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T01:21:32.004462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.949856ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T01:21:32.005025Z","caller":"traceutil/trace.go:171","msg":"trace[1805798247] transaction","detail":"{read_only:false; response_revision:8730; number_of_response:1; }","duration":"277.186249ms","start":"2026-06-02T01:21:31.72782Z","end":"2026-06-02T01:21:32.005006Z","steps":["trace[1805798247] 'process raft request' (duration: 276.784998ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T01:21:32.004812Z","caller":"traceutil/trace.go:171","msg":"trace[280694090] transaction","detail":"{read_only:false; response_revision:8729; number_of_response:1; }","duration":"279.09198ms","start":"2026-06-02T01:21:31.725682Z","end":"2026-06-02T01:21:32.004774Z","steps":["trace[280694090] 'process raft request' (duration: 23.719723ms)","trace[280694090] 'compare' (duration: 254.667158ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T01:21:49.695934Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023f8.wal"} {"level":"info","ts":"2026-06-02T01:22:54.679833Z","caller":"traceutil/trace.go:171","msg":"trace[413136538] transaction","detail":"{read_only:false; response_revision:9511; number_of_response:1; }","duration":"247.474693ms","start":"2026-06-02T01:22:54.432325Z","end":"2026-06-02T01:22:54.679799Z","steps":["trace[413136538] 'process raft request' (duration: 247.290198ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T01:22:54.724005Z","caller":"traceutil/trace.go:171","msg":"trace[1316478389] transaction","detail":"{read_only:false; response_revision:9513; number_of_response:1; }","duration":"266.335438ms","start":"2026-06-02T01:22:54.45762Z","end":"2026-06-02T01:22:54.723956Z","steps":["trace[1316478389] 'process raft request' (duration: 266.171663ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T01:22:54.723993Z","caller":"traceutil/trace.go:171","msg":"trace[1276005675] transaction","detail":"{read_only:false; response_revision:9512; number_of_response:1; }","duration":"289.135888ms","start":"2026-06-02T01:22:54.434805Z","end":"2026-06-02T01:22:54.723941Z","steps":["trace[1276005675] 'process raft request' (duration: 288.844619ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T01:23:13.0957Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"e3aaa5c123e150e0","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-02T01:23:13.102681Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-06-02T01:23:13.102753Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-06-02T01:25:30.089616Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8168} {"level":"info","ts":"2026-06-02T01:25:30.155689Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8168,"took":"63.661278ms","hash":3751899502,"current-db-size-bytes":58286080,"current-db-size":"58 MB","current-db-size-in-use-bytes":28442624,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-06-02T01:25:30.155782Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3751899502,"revision":8168,"compact-revision":6077} {"level":"info","ts":"2026-06-02T01:27:03.060959Z","caller":"traceutil/trace.go:171","msg":"trace[1317065057] transaction","detail":"{read_only:false; response_revision:11119; number_of_response:1; }","duration":"119.859746ms","start":"2026-06-02T01:27:02.941068Z","end":"2026-06-02T01:27:03.060928Z","steps":["trace[1317065057] 'process raft request' (duration: 119.626389ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T01:27:03.064264Z","caller":"traceutil/trace.go:171","msg":"trace[936354694] transaction","detail":"{read_only:false; response_revision:11120; number_of_response:1; }","duration":"107.918725ms","start":"2026-06-02T01:27:02.956308Z","end":"2026-06-02T01:27:03.064227Z","steps":["trace[936354694] 'process raft request' (duration: 107.752652ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T01:28:27.189354Z","caller":"traceutil/trace.go:171","msg":"trace[886170269] transaction","detail":"{read_only:false; response_revision:11915; number_of_response:1; }","duration":"106.862517ms","start":"2026-06-02T01:28:27.082457Z","end":"2026-06-02T01:28:27.189319Z","steps":["trace[886170269] 'process raft request' (duration: 106.643991ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T01:28:27.37023Z","caller":"traceutil/trace.go:171","msg":"trace[2138170390] transaction","detail":"{read_only:false; response_revision:11916; number_of_response:1; }","duration":"173.421332ms","start":"2026-06-02T01:28:27.19676Z","end":"2026-06-02T01:28:27.370181Z","steps":["trace[2138170390] 'process raft request' (duration: 88.71037ms)","trace[2138170390] 'compare' (duration: 84.319452ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T01:28:27.628536Z","caller":"traceutil/trace.go:171","msg":"trace[2112459448] transaction","detail":"{read_only:false; response_revision:11918; number_of_response:1; }","duration":"213.324293ms","start":"2026-06-02T01:28:27.415181Z","end":"2026-06-02T01:28:27.628505Z","steps":["trace[2112459448] 'process raft request' (duration: 213.153898ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T01:30:30.097175Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10533} {"level":"info","ts":"2026-06-02T01:30:30.187362Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10533,"took":"87.142478ms","hash":1190750025,"current-db-size-bytes":58286080,"current-db-size":"58 MB","current-db-size-in-use-bytes":26800128,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-06-02T01:30:30.187465Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1190750025,"revision":10533,"compact-revision":8168} {"level":"warn","ts":"2026-06-02T01:35:13.80492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.857308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8wk95\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-06-02T01:35:13.80506Z","caller":"traceutil/trace.go:171","msg":"trace[308271053] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8wk95; range_end:; response_count:1; response_revision:14619; }","duration":"376.101185ms","start":"2026-06-02T01:35:13.428929Z","end":"2026-06-02T01:35:13.80503Z","steps":["trace[308271053] 'range keys from in-memory index tree' (duration: 375.674454ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T01:35:13.80512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T01:35:13.428831Z","time spent":"376.27118ms","remote":"127.0.0.1:40218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6554,"request content":"key:\"/registry/pods/openstack/keepalived-8wk95\" "} {"level":"info","ts":"2026-06-02T01:35:13.805229Z","caller":"traceutil/trace.go:171","msg":"trace[1940266255] transaction","detail":"{read_only:false; response_revision:14620; number_of_response:1; }","duration":"137.723546ms","start":"2026-06-02T01:35:13.667468Z","end":"2026-06-02T01:35:13.805192Z","steps":["trace[1940266255] 'process raft request' (duration: 124.80396ms)","trace[1940266255] 'compare' (duration: 12.33382ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T01:35:13.807506Z","caller":"traceutil/trace.go:171","msg":"trace[1906092190] transaction","detail":"{read_only:false; response_revision:14623; number_of_response:1; }","duration":"111.443742ms","start":"2026-06-02T01:35:13.696028Z","end":"2026-06-02T01:35:13.807471Z","steps":["trace[1906092190] 'process raft request' (duration: 111.061161ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T01:35:13.807547Z","caller":"traceutil/trace.go:171","msg":"trace[1732107950] transaction","detail":"{read_only:false; response_revision:14622; number_of_response:1; }","duration":"113.105896ms","start":"2026-06-02T01:35:13.694378Z","end":"2026-06-02T01:35:13.807484Z","steps":["trace[1732107950] 'process raft request' (duration: 112.666824ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T01:35:13.808533Z","caller":"traceutil/trace.go:171","msg":"trace[1351568773] transaction","detail":"{read_only:false; response_revision:14621; number_of_response:1; }","duration":"115.354636ms","start":"2026-06-02T01:35:13.692264Z","end":"2026-06-02T01:35:13.807618Z","steps":["trace[1351568773] 'process raft request' (duration: 114.620706ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T01:35:30.105254Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12747} {"level":"info","ts":"2026-06-02T01:35:30.196781Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12747,"took":"87.834489ms","hash":1381714805,"current-db-size-bytes":58286080,"current-db-size":"58 MB","current-db-size-in-use-bytes":28151808,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-06-02T01:35:30.196875Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1381714805,"revision":12747,"compact-revision":10533} {"level":"info","ts":"2026-06-02T01:35:37.462093Z","caller":"traceutil/trace.go:171","msg":"trace[1647116868] linearizableReadLoop","detail":"{readStateIndex:15452; appliedIndex:15451; }","duration":"192.405267ms","start":"2026-06-02T01:35:37.269659Z","end":"2026-06-02T01:35:37.462065Z","steps":["trace[1647116868] 'read index received' (duration: 192.381405ms)","trace[1647116868] 'applied index is now lower than readState.Index' (duration: 22.612µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T01:35:37.462192Z","caller":"traceutil/trace.go:171","msg":"trace[640143607] transaction","detail":"{read_only:false; response_revision:14934; number_of_response:1; }","duration":"488.27787ms","start":"2026-06-02T01:35:36.973873Z","end":"2026-06-02T01:35:37.462151Z","steps":["trace[640143607] 'process raft request' (duration: 488.048804ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T01:35:37.462356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.670663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-80c5792d-b3ba-4d0f-876f-8eee637eeacf\" ","response":"range_response_count:1 size:3637"} {"level":"warn","ts":"2026-06-02T01:35:37.462382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T01:35:36.973854Z","time spent":"488.415595ms","remote":"127.0.0.1:40298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T01:35:37.462399Z","caller":"traceutil/trace.go:171","msg":"trace[1906081459] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-80c5792d-b3ba-4d0f-876f-8eee637eeacf; range_end:; response_count:1; response_revision:14934; }","duration":"192.764406ms","start":"2026-06-02T01:35:37.269619Z","end":"2026-06-02T01:35:37.462384Z","steps":["trace[1906081459] 'agreement among raft nodes before linearized reading' (duration: 192.604562ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T01:35:37.468887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.670676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T01:35:37.468963Z","caller":"traceutil/trace.go:171","msg":"trace[1388228573] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14935; }","duration":"169.7842ms","start":"2026-06-02T01:35:37.299162Z","end":"2026-06-02T01:35:37.468946Z","steps":["trace[1388228573] 'agreement among raft nodes before linearized reading' (duration: 169.648235ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T01:35:37.57747Z","caller":"traceutil/trace.go:171","msg":"trace[1802515781] transaction","detail":"{read_only:false; response_revision:14936; number_of_response:1; }","duration":"105.758849ms","start":"2026-06-02T01:35:37.471671Z","end":"2026-06-02T01:35:37.577429Z","steps":["trace[1802515781] 'process raft request' (duration: 101.657689ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T01:37:03.667794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.237613ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T01:37:03.668485Z","caller":"traceutil/trace.go:171","msg":"trace[399033082] transaction","detail":"{read_only:false; response_revision:15739; number_of_response:1; }","duration":"246.059037ms","start":"2026-06-02T01:37:03.422367Z","end":"2026-06-02T01:37:03.668426Z","steps":["trace[399033082] 'process raft request' (duration: 246.010066ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T01:37:03.668702Z","caller":"traceutil/trace.go:171","msg":"trace[1259145380] transaction","detail":"{read_only:false; response_revision:15737; number_of_response:1; }","duration":"311.719589ms","start":"2026-06-02T01:37:03.356948Z","end":"2026-06-02T01:37:03.668667Z","steps":["trace[1259145380] 'process raft request' (duration: 207.390228ms)","trace[1259145380] 'compare' (duration: 102.891623ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T01:37:03.668784Z","caller":"traceutil/trace.go:171","msg":"trace[741390175] linearizableReadLoop","detail":"{readStateIndex:16278; appliedIndex:16276; }","duration":"277.390058ms","start":"2026-06-02T01:37:03.39138Z","end":"2026-06-02T01:37:03.66877Z","steps":["trace[741390175] 'read index received' (duration: 25.221978ms)","trace[741390175] 'applied index is now lower than readState.Index' (duration: 252.16703ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T01:37:03.668879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T01:37:03.356918Z","time spent":"311.866395ms","remote":"127.0.0.1:40298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T01:37:03.668744Z","caller":"traceutil/trace.go:171","msg":"trace[848542363] transaction","detail":"{read_only:false; response_revision:15738; number_of_response:1; }","duration":"259.140088ms","start":"2026-06-02T01:37:03.40959Z","end":"2026-06-02T01:37:03.66873Z","steps":["trace[848542363] 'process raft request' (duration: 258.585243ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T01:37:03.669058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.674607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-06-02T01:37:03.669102Z","caller":"traceutil/trace.go:171","msg":"trace[522934758] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15739; }","duration":"277.772379ms","start":"2026-06-02T01:37:03.391319Z","end":"2026-06-02T01:37:03.669091Z","steps":["trace[522934758] 'agreement among raft nodes before linearized reading' (duration: 277.625025ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T01:37:03.669336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.839761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:682"} {"level":"info","ts":"2026-06-02T01:37:03.669367Z","caller":"traceutil/trace.go:171","msg":"trace[903232041] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:15739; }","duration":"277.894663ms","start":"2026-06-02T01:37:03.391466Z","end":"2026-06-02T01:37:03.669361Z","steps":["trace[903232041] 'agreement among raft nodes before linearized reading' (duration: 277.769708ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T01:37:03.66965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.689033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"warn","ts":"2026-06-02T01:37:03.669655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.297178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"warn","ts":"2026-06-02T01:37:03.669694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.928729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T01:37:03.669708Z","caller":"traceutil/trace.go:171","msg":"trace[1346733341] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15739; }","duration":"275.961381ms","start":"2026-06-02T01:37:03.393743Z","end":"2026-06-02T01:37:03.669705Z","steps":["trace[1346733341] 'agreement among raft nodes before linearized reading' (duration: 275.93896ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T01:37:03.669703Z","caller":"traceutil/trace.go:171","msg":"trace[1971535536] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15739; }","duration":"273.369911ms","start":"2026-06-02T01:37:03.39632Z","end":"2026-06-02T01:37:03.66969Z","steps":["trace[1971535536] 'agreement among raft nodes before linearized reading' (duration: 273.187216ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T01:37:03.66981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.385072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T01:37:03.669827Z","caller":"traceutil/trace.go:171","msg":"trace[1802446952] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:15739; }","duration":"276.415753ms","start":"2026-06-02T01:37:03.393408Z","end":"2026-06-02T01:37:03.669823Z","steps":["trace[1802446952] 'agreement among raft nodes before linearized reading' (duration: 276.389562ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T01:37:03.66987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.480524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T01:37:03.669885Z","caller":"traceutil/trace.go:171","msg":"trace[1790510252] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15739; }","duration":"276.507925ms","start":"2026-06-02T01:37:03.393374Z","end":"2026-06-02T01:37:03.669882Z","steps":["trace[1790510252] 'agreement among raft nodes before linearized reading' (duration: 276.486124ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T01:37:03.669926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.561327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T01:37:03.669942Z","caller":"traceutil/trace.go:171","msg":"trace[2104782179] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15739; }","duration":"276.590337ms","start":"2026-06-02T01:37:03.393348Z","end":"2026-06-02T01:37:03.669939Z","steps":["trace[2104782179] 'agreement among raft nodes before linearized reading' (duration: 276.565506ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T01:37:03.670012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.671629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T01:37:03.67003Z","caller":"traceutil/trace.go:171","msg":"trace[1876888824] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15739; }","duration":"276.702689ms","start":"2026-06-02T01:37:03.393323Z","end":"2026-06-02T01:37:03.670026Z","steps":["trace[1876888824] 'agreement among raft nodes before linearized reading' (duration: 276.675489ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T01:37:03.670102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.785023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T01:37:03.670121Z","caller":"traceutil/trace.go:171","msg":"trace[770585192] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15739; }","duration":"276.805204ms","start":"2026-06-02T01:37:03.393311Z","end":"2026-06-02T01:37:03.670116Z","steps":["trace[770585192] 'agreement among raft nodes before linearized reading' (duration: 276.776472ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T01:37:03.670213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.957158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T01:37:03.670233Z","caller":"traceutil/trace.go:171","msg":"trace[1514479982] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15739; }","duration":"276.994048ms","start":"2026-06-02T01:37:03.393234Z","end":"2026-06-02T01:37:03.670228Z","steps":["trace[1514479982] 'agreement among raft nodes before linearized reading' (duration: 276.963036ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T01:37:03.670346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.558503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"warn","ts":"2026-06-02T01:37:03.670341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.909364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-06-02T01:37:03.670394Z","caller":"traceutil/trace.go:171","msg":"trace[1481296067] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15739; }","duration":"192.025296ms","start":"2026-06-02T01:37:03.478352Z","end":"2026-06-02T01:37:03.670377Z","steps":["trace[1481296067] 'agreement among raft nodes before linearized reading' (duration: 191.901553ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T01:37:03.670488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.727438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-06-02T01:37:03.670545Z","caller":"traceutil/trace.go:171","msg":"trace[888032739] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15739; }","duration":"277.81091ms","start":"2026-06-02T01:37:03.392723Z","end":"2026-06-02T01:37:03.670534Z","steps":["trace[888032739] 'agreement among raft nodes before linearized reading' (duration: 277.672006ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T01:37:03.670627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.741575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T01:37:03.67065Z","caller":"traceutil/trace.go:171","msg":"trace[1510995522] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15739; }","duration":"278.778026ms","start":"2026-06-02T01:37:03.391866Z","end":"2026-06-02T01:37:03.670644Z","steps":["trace[1510995522] 'agreement among raft nodes before linearized reading' (duration: 278.741294ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T01:37:03.670677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.066599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-06-02T01:37:03.670713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.874898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T01:37:03.670723Z","caller":"traceutil/trace.go:171","msg":"trace[2114557539] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15739; }","duration":"195.128809ms","start":"2026-06-02T01:37:03.475584Z","end":"2026-06-02T01:37:03.670713Z","steps":["trace[2114557539] 'agreement among raft nodes before linearized reading' (duration: 194.985716ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T01:37:03.670764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.232928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T01:37:03.670788Z","caller":"traceutil/trace.go:171","msg":"trace[984665528] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:15739; }","duration":"279.275089ms","start":"2026-06-02T01:37:03.391508Z","end":"2026-06-02T01:37:03.670783Z","steps":["trace[984665528] 'agreement among raft nodes before linearized reading' (duration: 279.243958ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T01:37:03.670366Z","caller":"traceutil/trace.go:171","msg":"trace[1568004660] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15739; }","duration":"277.592414ms","start":"2026-06-02T01:37:03.392769Z","end":"2026-06-02T01:37:03.670361Z","steps":["trace[1568004660] 'agreement among raft nodes before linearized reading' (duration: 277.524692ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T01:37:03.67093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.190302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"warn","ts":"2026-06-02T01:37:03.670925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.262614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-06-02T01:37:03.670739Z","caller":"traceutil/trace.go:171","msg":"trace[2145986302] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:15739; }","duration":"278.918979ms","start":"2026-06-02T01:37:03.391815Z","end":"2026-06-02T01:37:03.670734Z","steps":["trace[2145986302] 'agreement among raft nodes before linearized reading' (duration: 278.885338ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T01:37:03.670982Z","caller":"traceutil/trace.go:171","msg":"trace[1751606559] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15739; }","duration":"271.346177ms","start":"2026-06-02T01:37:03.399618Z","end":"2026-06-02T01:37:03.670964Z","steps":["trace[1751606559] 'agreement among raft nodes before linearized reading' (duration: 271.186542ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T01:37:03.671126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.324781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-06-02T01:37:03.671152Z","caller":"traceutil/trace.go:171","msg":"trace[1194793153] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15739; }","duration":"269.365353ms","start":"2026-06-02T01:37:03.401781Z","end":"2026-06-02T01:37:03.671146Z","steps":["trace[1194793153] 'agreement among raft nodes before linearized reading' (duration: 269.302792ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T01:37:03.671147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.352183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-06-02T01:37:03.67118Z","caller":"traceutil/trace.go:171","msg":"trace[405388989] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15739; }","duration":"269.408683ms","start":"2026-06-02T01:37:03.401763Z","end":"2026-06-02T01:37:03.671171Z","steps":["trace[405388989] 'agreement among raft nodes before linearized reading' (duration: 269.322112ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T01:37:03.671196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.068977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-06-02T01:37:03.671214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.929319ms","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-06-02T01:37:03.671257Z","caller":"traceutil/trace.go:171","msg":"trace[2015746837] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15739; }","duration":"202.144218ms","start":"2026-06-02T01:37:03.469096Z","end":"2026-06-02T01:37:03.67124Z","steps":["trace[2015746837] 'agreement among raft nodes before linearized reading' (duration: 202.003764ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T01:37:03.671283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.822251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-02T01:37:03.671293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.121583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-06-02T01:37:03.671313Z","caller":"traceutil/trace.go:171","msg":"trace[545992400] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15739; }","duration":"267.153844ms","start":"2026-06-02T01:37:03.404154Z","end":"2026-06-02T01:37:03.671308Z","steps":["trace[545992400] 'agreement among raft nodes before linearized reading' (duration: 267.103322ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T01:37:03.671313Z","caller":"traceutil/trace.go:171","msg":"trace[933103971] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15739; }","duration":"270.869793ms","start":"2026-06-02T01:37:03.400434Z","end":"2026-06-02T01:37:03.671304Z","steps":["trace[933103971] 'agreement among raft nodes before linearized reading' (duration: 270.828071ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T01:37:03.671242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.235211ms","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-06-02T01:37:03.671357Z","caller":"traceutil/trace.go:171","msg":"trace[1969378182] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15739; }","duration":"208.383225ms","start":"2026-06-02T01:37:03.462964Z","end":"2026-06-02T01:37:03.671347Z","steps":["trace[1969378182] 'agreement among raft nodes before linearized reading' (duration: 208.235912ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T01:37:03.671298Z","caller":"traceutil/trace.go:171","msg":"trace[1822344057] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15739; }","duration":"200.034361ms","start":"2026-06-02T01:37:03.471244Z","end":"2026-06-02T01:37:03.671279Z","steps":["trace[1822344057] 'agreement among raft nodes before linearized reading' (duration: 199.866447ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T01:37:03.671375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.602263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"warn","ts":"2026-06-02T01:37:03.671375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.648713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-06-02T01:37:03.671483Z","caller":"traceutil/trace.go:171","msg":"trace[1716624788] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15739; }","duration":"271.795428ms","start":"2026-06-02T01:37:03.399667Z","end":"2026-06-02T01:37:03.671462Z","steps":["trace[1716624788] 'agreement among raft nodes before linearized reading' (duration: 271.561552ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T01:37:03.671481Z","caller":"traceutil/trace.go:171","msg":"trace[1141881795] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15739; }","duration":"271.766686ms","start":"2026-06-02T01:37:03.399688Z","end":"2026-06-02T01:37:03.671455Z","steps":["trace[1141881795] 'agreement among raft nodes before linearized reading' (duration: 271.469118ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T01:37:03.670966Z","caller":"traceutil/trace.go:171","msg":"trace[1538124829] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15739; }","duration":"274.235053ms","start":"2026-06-02T01:37:03.396712Z","end":"2026-06-02T01:37:03.670948Z","steps":["trace[1538124829] 'agreement among raft nodes before linearized reading' (duration: 274.165322ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T01:37:03.669672Z","caller":"traceutil/trace.go:171","msg":"trace[1111320969] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15739; }","duration":"275.734075ms","start":"2026-06-02T01:37:03.393934Z","end":"2026-06-02T01:37:03.669668Z","steps":["trace[1111320969] 'agreement among raft nodes before linearized reading' (duration: 275.678203ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T01:40:30.112942Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14802} {"level":"info","ts":"2026-06-02T01:40:30.206312Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14802,"took":"90.502019ms","hash":3910291420,"current-db-size-bytes":58286080,"current-db-size":"58 MB","current-db-size-in-use-bytes":30879744,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-02T01:40:30.206406Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3910291420,"revision":14802,"compact-revision":12747} {"level":"info","ts":"2026-06-02T01:45:30.121987Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17363} {"level":"info","ts":"2026-06-02T01:45:30.221825Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17363,"took":"96.91415ms","hash":2775518077,"current-db-size-bytes":58286080,"current-db-size":"58 MB","current-db-size-in-use-bytes":29794304,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-02T01:45:30.221942Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2775518077,"revision":17363,"compact-revision":14802} {"level":"info","ts":"2026-06-02T01:46:08.452098Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"e3aaa5c123e150e0","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-02T01:46:08.457476Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-06-02T01:46:08.457567Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"warn","ts":"2026-06-02T01:47:23.851524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.810806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:94 size:945690"} {"level":"info","ts":"2026-06-02T01:47:23.851652Z","caller":"traceutil/trace.go:171","msg":"trace[1195507689] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:94; response_revision:19720; }","duration":"336.965931ms","start":"2026-06-02T01:47:23.514654Z","end":"2026-06-02T01:47:23.85162Z","steps":["trace[1195507689] 'range keys from bolt db' (duration: 336.64188ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T01:47:23.851691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T01:47:23.514647Z","time spent":"337.034303ms","remote":"127.0.0.1:40218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":94,"response size":945713,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-06-02T01:50:30.12808Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19136} {"level":"info","ts":"2026-06-02T01:50:30.221369Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19136,"took":"89.582808ms","hash":2918868918,"current-db-size-bytes":58286080,"current-db-size":"58 MB","current-db-size-in-use-bytes":23887872,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-06-02T01:50:30.221458Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2918868918,"revision":19136,"compact-revision":17363}