{"level":"warn","ts":"2026-05-06T01:16:34.025077Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-06T01:16:34.025196Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.240: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.240:2380","--initial-cluster=instance=https://199.204.45.240:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.240:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.240: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-05-06T01:16:34.025293Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-06T01:16:34.025315Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.240:2380"]} {"level":"info","ts":"2026-05-06T01:16:34.025344Z","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-05-06T01:16:34.026357Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"]} {"level":"info","ts":"2026-05-06T01:16:34.02647Z","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.240:2380"],"listen-peer-urls":["https://199.204.45.240:2380"],"advertise-client-urls":["https://199.204.45.240:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.240: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-05-06T01:16:34.03031Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.507835ms"} {"level":"info","ts":"2026-05-06T01:16:34.042137Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e46287fb5aa4785","cluster-id":"cae704afa9813e87"} {"level":"info","ts":"2026-05-06T01:16:34.042243Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=()"} {"level":"info","ts":"2026-05-06T01:16:34.042278Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became follower at term 0"} {"level":"info","ts":"2026-05-06T01:16:34.042294Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e46287fb5aa4785 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-06T01:16:34.042304Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became follower at term 1"} {"level":"info","ts":"2026-05-06T01:16:34.042367Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=(5640240112301918085)"} {"level":"warn","ts":"2026-05-06T01:16:34.048445Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-06T01:16:34.04992Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-06T01:16:34.051029Z","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-05-06T01:16:34.053445Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e46287fb5aa4785","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-06T01:16:34.053663Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e46287fb5aa4785","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-06T01:16:34.053734Z","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-05-06T01:16:34.054637Z","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-05-06T01:16:34.054692Z","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-05-06T01:16:34.056052Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=(5640240112301918085)"} {"level":"info","ts":"2026-05-06T01:16:34.056349Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cae704afa9813e87","local-member-id":"4e46287fb5aa4785","added-peer-id":"4e46287fb5aa4785","added-peer-peer-urls":["https://199.204.45.240:2380"]} {"level":"info","ts":"2026-05-06T01:16:34.059324Z","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-05-06T01:16:34.059465Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.240:2380"} {"level":"info","ts":"2026-05-06T01:16:34.059529Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.240:2380"} {"level":"info","ts":"2026-05-06T01:16:34.059713Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e46287fb5aa4785","initial-advertise-peer-urls":["https://199.204.45.240:2380"],"listen-peer-urls":["https://199.204.45.240:2380"],"advertise-client-urls":["https://199.204.45.240:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-06T01:16:34.059779Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-06T01:16:34.742611Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 is starting a new election at term 1"} {"level":"info","ts":"2026-05-06T01:16:34.742691Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-06T01:16:34.742716Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 received MsgPreVoteResp from 4e46287fb5aa4785 at term 1"} {"level":"info","ts":"2026-05-06T01:16:34.74277Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became candidate at term 2"} {"level":"info","ts":"2026-05-06T01:16:34.742788Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 received MsgVoteResp from 4e46287fb5aa4785 at term 2"} {"level":"info","ts":"2026-05-06T01:16:34.742804Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became leader at term 2"} {"level":"info","ts":"2026-05-06T01:16:34.742824Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e46287fb5aa4785 elected leader 4e46287fb5aa4785 at term 2"} {"level":"info","ts":"2026-05-06T01:16:34.744585Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T01:16:34.7455Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-06T01:16:34.745501Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e46287fb5aa4785","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.240:2379]}","request-path":"/0/members/4e46287fb5aa4785/attributes","cluster-id":"cae704afa9813e87","publish-timeout":"7s"} {"level":"info","ts":"2026-05-06T01:16:34.745544Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-06T01:16:34.745998Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-06T01:16:34.746036Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cae704afa9813e87","local-member-id":"4e46287fb5aa4785","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T01:16:34.74608Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-06T01:16:34.74619Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T01:16:34.746254Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T01:16:34.747928Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-06T01:16:34.749093Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.240:2379"} {"level":"info","ts":"2026-05-06T01:17:10.759249Z","caller":"traceutil/trace.go:171","msg":"trace[947082110] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"141.974964ms","start":"2026-05-06T01:17:10.617238Z","end":"2026-05-06T01:17:10.759213Z","steps":["trace[947082110] 'process raft request' (duration: 138.547132ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:17:10.94398Z","caller":"traceutil/trace.go:171","msg":"trace[1642252683] linearizableReadLoop","detail":"{readStateIndex:590; appliedIndex:588; }","duration":"298.997476ms","start":"2026-05-06T01:17:10.644949Z","end":"2026-05-06T01:17:10.943946Z","steps":["trace[1642252683] 'read index received' (duration: 109.630793ms)","trace[1642252683] 'applied index is now lower than readState.Index' (duration: 189.365233ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:17:10.944138Z","caller":"traceutil/trace.go:171","msg":"trace[1626690694] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"319.367526ms","start":"2026-05-06T01:17:10.624732Z","end":"2026-05-06T01:17:10.9441Z","steps":["trace[1626690694] 'process raft request' (duration: 318.777554ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:17:10.944274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.300842ms","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-05-06T01:17:10.94436Z","caller":"traceutil/trace.go:171","msg":"trace[227146113] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:570; }","duration":"299.465446ms","start":"2026-05-06T01:17:10.644884Z","end":"2026-05-06T01:17:10.944349Z","steps":["trace[227146113] 'agreement among raft nodes before linearized reading' (duration: 299.21467ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:17:10.94494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:17:10.624687Z","time spent":"319.5512ms","remote":"127.0.0.1:58952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T01:17:10.961766Z","caller":"traceutil/trace.go:171","msg":"trace[373027352] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"140.558304ms","start":"2026-05-06T01:17:10.821184Z","end":"2026-05-06T01:17:10.961742Z","steps":["trace[373027352] 'process raft request' (duration: 140.35934ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:17:10.961853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.344182ms","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-05-06T01:17:10.961921Z","caller":"traceutil/trace.go:171","msg":"trace[1476587274] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:571; }","duration":"151.490325ms","start":"2026-05-06T01:17:10.810408Z","end":"2026-05-06T01:17:10.961898Z","steps":["trace[1476587274] 'agreement among raft nodes before linearized reading' (duration: 151.265491ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:17:11.294445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.949256ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T01:17:11.294574Z","caller":"traceutil/trace.go:171","msg":"trace[1662616862] linearizableReadLoop","detail":"{readStateIndex:593; appliedIndex:591; }","duration":"322.459672ms","start":"2026-05-06T01:17:10.972097Z","end":"2026-05-06T01:17:11.294556Z","steps":["trace[1662616862] 'read index received' (duration: 17.481489ms)","trace[1662616862] 'applied index is now lower than readState.Index' (duration: 304.976683ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:17:11.294657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.563634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:17:11.294702Z","caller":"traceutil/trace.go:171","msg":"trace[847573418] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system; range_end:; response_count:0; response_revision:573; }","duration":"322.631326ms","start":"2026-05-06T01:17:10.972059Z","end":"2026-05-06T01:17:11.29469Z","steps":["trace[847573418] 'agreement among raft nodes before linearized reading' (duration: 322.563904ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:17:11.294736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:17:10.972038Z","time spent":"322.689286ms","remote":"127.0.0.1:59280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" "} {"level":"info","ts":"2026-05-06T01:17:11.294741Z","caller":"traceutil/trace.go:171","msg":"trace[2006061117] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"328.792585ms","start":"2026-05-06T01:17:10.965873Z","end":"2026-05-06T01:17:11.294665Z","steps":["trace[2006061117] 'process raft request' (duration: 37.338058ms)","trace[2006061117] 'compare' (duration: 290.799763ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:17:11.294988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:17:10.965851Z","time spent":"329.01101ms","remote":"127.0.0.1:59038","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-05-06T01:18:43.587805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.609201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9eda0849-21c3-4d01-b71b-9f70ca9b9fc5\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-06T01:18:43.587922Z","caller":"traceutil/trace.go:171","msg":"trace[722504098] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9eda0849-21c3-4d01-b71b-9f70ca9b9fc5; range_end:; response_count:1; response_revision:1813; }","duration":"250.804405ms","start":"2026-05-06T01:18:43.337094Z","end":"2026-05-06T01:18:43.587898Z","steps":["trace[722504098] 'range keys from in-memory index tree' (duration: 250.427657ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:18:43.587946Z","caller":"traceutil/trace.go:171","msg":"trace[409794242] linearizableReadLoop","detail":"{readStateIndex:1885; appliedIndex:1884; }","duration":"102.560831ms","start":"2026-05-06T01:18:43.485343Z","end":"2026-05-06T01:18:43.587904Z","steps":["trace[409794242] 'read index received' (duration: 81.680011ms)","trace[409794242] 'applied index is now lower than readState.Index' (duration: 20.87846ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:18:43.588127Z","caller":"traceutil/trace.go:171","msg":"trace[753421573] transaction","detail":"{read_only:false; response_revision:1814; number_of_response:1; }","duration":"158.418859ms","start":"2026-05-06T01:18:43.429674Z","end":"2026-05-06T01:18:43.588093Z","steps":["trace[753421573] 'process raft request' (duration: 137.424987ms)","trace[753421573] 'compare' (duration: 20.477371ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:18:43.588279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.922059ms","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-05-06T01:18:43.588329Z","caller":"traceutil/trace.go:171","msg":"trace[454345322] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1814; }","duration":"103.036081ms","start":"2026-05-06T01:18:43.485278Z","end":"2026-05-06T01:18:43.588314Z","steps":["trace[454345322] 'agreement among raft nodes before linearized reading' (duration: 102.830527ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:18:44.079813Z","caller":"traceutil/trace.go:171","msg":"trace[1746851984] linearizableReadLoop","detail":"{readStateIndex:1889; appliedIndex:1888; }","duration":"103.783107ms","start":"2026-05-06T01:18:43.975975Z","end":"2026-05-06T01:18:44.079758Z","steps":["trace[1746851984] 'read index received' (duration: 96.870031ms)","trace[1746851984] 'applied index is now lower than readState.Index' (duration: 6.911956ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:18:44.080059Z","caller":"traceutil/trace.go:171","msg":"trace[745447161] transaction","detail":"{read_only:false; response_revision:1818; number_of_response:1; }","duration":"157.952378ms","start":"2026-05-06T01:18:43.922071Z","end":"2026-05-06T01:18:44.080024Z","steps":["trace[745447161] 'process raft request' (duration: 150.872169ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:44.080142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.158265ms","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-05-06T01:18:44.080187Z","caller":"traceutil/trace.go:171","msg":"trace[896640604] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1818; }","duration":"104.283217ms","start":"2026-05-06T01:18:43.975891Z","end":"2026-05-06T01:18:44.080174Z","steps":["trace[896640604] 'agreement among raft nodes before linearized reading' (duration: 104.007632ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:18:51.416614Z","caller":"traceutil/trace.go:171","msg":"trace[1129521474] transaction","detail":"{read_only:false; response_revision:1885; number_of_response:1; }","duration":"362.468189ms","start":"2026-05-06T01:18:51.054121Z","end":"2026-05-06T01:18:51.416589Z","steps":["trace[1129521474] 'process raft request' (duration: 362.283655ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:51.416781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:51.054092Z","time spent":"362.604341ms","remote":"127.0.0.1:59038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T01:18:51.756509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.858398ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T01:18:51.756677Z","caller":"traceutil/trace.go:171","msg":"trace[868900727] transaction","detail":"{read_only:false; response_revision:1886; number_of_response:1; }","duration":"473.829046ms","start":"2026-05-06T01:18:51.282805Z","end":"2026-05-06T01:18:51.756634Z","steps":["trace[868900727] 'process raft request' (duration: 239.734953ms)","trace[868900727] 'compare' (duration: 233.666994ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:18:51.756759Z","caller":"traceutil/trace.go:171","msg":"trace[1305538477] transaction","detail":"{read_only:false; response_revision:1887; number_of_response:1; }","duration":"139.187423ms","start":"2026-05-06T01:18:51.617548Z","end":"2026-05-06T01:18:51.756735Z","steps":["trace[1305538477] 'process raft request' (duration: 139.095311ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:18:51.756768Z","caller":"traceutil/trace.go:171","msg":"trace[129996201] linearizableReadLoop","detail":"{readStateIndex:1959; appliedIndex:1957; }","duration":"439.953652ms","start":"2026-05-06T01:18:51.31677Z","end":"2026-05-06T01:18:51.756723Z","steps":["trace[129996201] 'read index received' (duration: 99.62814ms)","trace[129996201] 'applied index is now lower than readState.Index' (duration: 340.320501ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:18:51.756991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.196167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-06T01:18:51.757001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.359403ms","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-05-06T01:18:51.757027Z","caller":"traceutil/trace.go:171","msg":"trace[1468081689] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1887; }","duration":"440.30478ms","start":"2026-05-06T01:18:51.316714Z","end":"2026-05-06T01:18:51.757019Z","steps":["trace[1468081689] 'agreement among raft nodes before linearized reading' (duration: 440.124306ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:18:51.757039Z","caller":"traceutil/trace.go:171","msg":"trace[941511570] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1887; }","duration":"424.483355ms","start":"2026-05-06T01:18:51.33254Z","end":"2026-05-06T01:18:51.757023Z","steps":["trace[941511570] 'agreement among raft nodes before linearized reading' (duration: 424.352312ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:51.757078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:51.316698Z","time spent":"440.34766ms","remote":"127.0.0.1:59038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-06T01:18:51.757071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:51.332519Z","time spent":"424.544567ms","remote":"127.0.0.1:58810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-05-06T01:18:51.756901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:51.282775Z","time spent":"473.980519ms","remote":"127.0.0.1:59038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T01:18:55.634463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.617031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:18:55.634558Z","caller":"traceutil/trace.go:171","msg":"trace[687843990] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1911; }","duration":"100.786234ms","start":"2026-05-06T01:18:55.533752Z","end":"2026-05-06T01:18:55.634538Z","steps":["trace[687843990] 'range keys from in-memory index tree' (duration: 100.448867ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:18:55.914324Z","caller":"traceutil/trace.go:171","msg":"trace[629502939] linearizableReadLoop","detail":"{readStateIndex:1986; appliedIndex:1985; }","duration":"134.795601ms","start":"2026-05-06T01:18:55.779503Z","end":"2026-05-06T01:18:55.914299Z","steps":["trace[629502939] 'read index received' (duration: 134.401292ms)","trace[629502939] 'applied index is now lower than readState.Index' (duration: 392.849µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:18:55.91445Z","caller":"traceutil/trace.go:171","msg":"trace[989158578] transaction","detail":"{read_only:false; response_revision:1912; number_of_response:1; }","duration":"145.514267ms","start":"2026-05-06T01:18:55.768883Z","end":"2026-05-06T01:18:55.914397Z","steps":["trace[989158578] 'process raft request' (duration: 145.25078ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:55.914593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.511001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-06T01:18:55.91459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.080377ms","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-05-06T01:18:55.914639Z","caller":"traceutil/trace.go:171","msg":"trace[1720896446] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1912; }","duration":"130.603792ms","start":"2026-05-06T01:18:55.784024Z","end":"2026-05-06T01:18:55.914628Z","steps":["trace[1720896446] 'agreement among raft nodes before linearized reading' (duration: 130.429549ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:18:55.914681Z","caller":"traceutil/trace.go:171","msg":"trace[1826854678] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1912; }","duration":"135.175299ms","start":"2026-05-06T01:18:55.779484Z","end":"2026-05-06T01:18:55.914659Z","steps":["trace[1826854678] 'agreement among raft nodes before linearized reading' (duration: 134.944094ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:55.914896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.208553ms","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-05-06T01:18:55.915028Z","caller":"traceutil/trace.go:171","msg":"trace[1957662573] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1912; }","duration":"129.408237ms","start":"2026-05-06T01:18:55.785589Z","end":"2026-05-06T01:18:55.914997Z","steps":["trace[1957662573] 'agreement among raft nodes before linearized reading' (duration: 129.008999ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:19:34.354832Z","caller":"traceutil/trace.go:171","msg":"trace[1809822428] linearizableReadLoop","detail":"{readStateIndex:2218; appliedIndex:2217; }","duration":"191.372713ms","start":"2026-05-06T01:19:34.163423Z","end":"2026-05-06T01:19:34.354796Z","steps":["trace[1809822428] 'read index received' (duration: 191.209729ms)","trace[1809822428] 'applied index is now lower than readState.Index' (duration: 161.184µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:19:34.354872Z","caller":"traceutil/trace.go:171","msg":"trace[811785805] transaction","detail":"{read_only:false; response_revision:2136; number_of_response:1; }","duration":"206.21308ms","start":"2026-05-06T01:19:34.148624Z","end":"2026-05-06T01:19:34.354837Z","steps":["trace[811785805] 'process raft request' (duration: 205.997954ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:19:34.35511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.66336ms","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-05-06T01:19:34.355176Z","caller":"traceutil/trace.go:171","msg":"trace[1711007638] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2136; }","duration":"191.746152ms","start":"2026-05-06T01:19:34.163415Z","end":"2026-05-06T01:19:34.355161Z","steps":["trace[1711007638] 'agreement among raft nodes before linearized reading' (duration: 191.530477ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:19:34.609079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.198712ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T01:19:34.609302Z","caller":"traceutil/trace.go:171","msg":"trace[309947129] transaction","detail":"{read_only:false; response_revision:2138; number_of_response:1; }","duration":"250.162416ms","start":"2026-05-06T01:19:34.359119Z","end":"2026-05-06T01:19:34.609282Z","steps":["trace[309947129] 'process raft request' (duration: 250.075495ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:19:34.609289Z","caller":"traceutil/trace.go:171","msg":"trace[74290472] transaction","detail":"{read_only:false; response_revision:2137; number_of_response:1; }","duration":"332.591255ms","start":"2026-05-06T01:19:34.276629Z","end":"2026-05-06T01:19:34.60922Z","steps":["trace[74290472] 'process raft request' (duration: 191.151588ms)","trace[74290472] 'compare' (duration: 140.875135ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:19:34.609538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:19:34.276599Z","time spent":"332.805989ms","remote":"127.0.0.1:59038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T01:19:34.609844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.507252ms","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":"warn","ts":"2026-05-06T01:19:34.609871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.753401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-06T01:19:34.609893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.956768ms","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-05-06T01:19:34.609949Z","caller":"traceutil/trace.go:171","msg":"trace[597505799] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2138; }","duration":"441.03937ms","start":"2026-05-06T01:19:34.168898Z","end":"2026-05-06T01:19:34.609937Z","steps":["trace[597505799] 'agreement among raft nodes before linearized reading' (duration: 440.869047ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:19:34.609974Z","caller":"traceutil/trace.go:171","msg":"trace[1819960874] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2138; }","duration":"281.886324ms","start":"2026-05-06T01:19:34.328045Z","end":"2026-05-06T01:19:34.609932Z","steps":["trace[1819960874] 'agreement among raft nodes before linearized reading' (duration: 281.559756ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:19:34.609935Z","caller":"traceutil/trace.go:171","msg":"trace[1831039285] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2138; }","duration":"413.653476ms","start":"2026-05-06T01:19:34.19626Z","end":"2026-05-06T01:19:34.609913Z","steps":["trace[1831039285] 'agreement among raft nodes before linearized reading' (duration: 413.368029ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:19:34.610043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:19:34.168887Z","time spent":"441.119352ms","remote":"127.0.0.1:59038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-06T01:19:34.610086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:19:34.196239Z","time spent":"413.830689ms","remote":"127.0.0.1:58810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-05-06T01:19:34.61045Z","caller":"traceutil/trace.go:171","msg":"trace[524111797] linearizableReadLoop","detail":"{readStateIndex:2219; appliedIndex:2218; }","duration":"254.521739ms","start":"2026-05-06T01:19:34.354921Z","end":"2026-05-06T01:19:34.609442Z","steps":["trace[524111797] 'read index received' (duration: 112.852547ms)","trace[524111797] 'applied index is now lower than readState.Index' (duration: 141.649011ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:20:59.26279Z","caller":"traceutil/trace.go:171","msg":"trace[1329530745] transaction","detail":"{read_only:false; response_revision:2790; number_of_response:1; }","duration":"177.125861ms","start":"2026-05-06T01:20:59.085639Z","end":"2026-05-06T01:20:59.262765Z","steps":["trace[1329530745] 'process raft request' (duration: 177.05394ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:20:59.262875Z","caller":"traceutil/trace.go:171","msg":"trace[1627652727] transaction","detail":"{read_only:false; response_revision:2791; number_of_response:1; }","duration":"177.194422ms","start":"2026-05-06T01:20:59.085668Z","end":"2026-05-06T01:20:59.262863Z","steps":["trace[1627652727] 'process raft request' (duration: 177.06289ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:20:59.262851Z","caller":"traceutil/trace.go:171","msg":"trace[664806905] transaction","detail":"{read_only:false; response_revision:2789; number_of_response:1; }","duration":"177.170962ms","start":"2026-05-06T01:20:59.085639Z","end":"2026-05-06T01:20:59.26281Z","steps":["trace[664806905] 'process raft request' (duration: 167.536767ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:20:59.586507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.374459ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T01:20:59.586653Z","caller":"traceutil/trace.go:171","msg":"trace[357295205] transaction","detail":"{read_only:false; response_revision:2792; number_of_response:1; }","duration":"190.394744ms","start":"2026-05-06T01:20:59.396233Z","end":"2026-05-06T01:20:59.586627Z","steps":["trace[357295205] 'process raft request' (duration: 33.778819ms)","trace[357295205] 'compare' (duration: 156.211875ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:25:01.535743Z","caller":"traceutil/trace.go:171","msg":"trace[484850178] transaction","detail":"{read_only:false; response_revision:4877; number_of_response:1; }","duration":"170.907658ms","start":"2026-05-06T01:25:01.364811Z","end":"2026-05-06T01:25:01.535719Z","steps":["trace[484850178] 'process raft request' (duration: 170.740644ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:25:01.582381Z","caller":"traceutil/trace.go:171","msg":"trace[525023165] transaction","detail":"{read_only:false; response_revision:4878; number_of_response:1; }","duration":"172.585951ms","start":"2026-05-06T01:25:01.409771Z","end":"2026-05-06T01:25:01.582357Z","steps":["trace[525023165] 'process raft request' (duration: 172.442868ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:25:16.24315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.701149ms","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-05-06T01:25:16.243289Z","caller":"traceutil/trace.go:171","msg":"trace[674443344] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5229; }","duration":"107.920993ms","start":"2026-05-06T01:25:16.13534Z","end":"2026-05-06T01:25:16.243261Z","steps":["trace[674443344] 'range keys from in-memory index tree' (duration: 107.384782ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:25:16.771474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.372568ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T01:25:16.77168Z","caller":"traceutil/trace.go:171","msg":"trace[1349545838] transaction","detail":"{read_only:false; response_revision:5237; number_of_response:1; }","duration":"230.759817ms","start":"2026-05-06T01:25:16.540886Z","end":"2026-05-06T01:25:16.771646Z","steps":["trace[1349545838] 'process raft request' (duration: 126.050081ms)","trace[1349545838] 'compare' (duration: 104.150844ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:25:16.796718Z","caller":"traceutil/trace.go:171","msg":"trace[1217507787] transaction","detail":"{read_only:false; response_revision:5238; number_of_response:1; }","duration":"255.098604ms","start":"2026-05-06T01:25:16.541591Z","end":"2026-05-06T01:25:16.796689Z","steps":["trace[1217507787] 'process raft request' (duration: 254.881699ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:25:16.797102Z","caller":"traceutil/trace.go:171","msg":"trace[1987359479] linearizableReadLoop","detail":"{readStateIndex:5436; appliedIndex:5433; }","duration":"149.44057ms","start":"2026-05-06T01:25:16.647443Z","end":"2026-05-06T01:25:16.796884Z","steps":["trace[1987359479] 'read index received' (duration: 19.500217ms)","trace[1987359479] 'applied index is now lower than readState.Index' (duration: 129.937252ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:25:16.797303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.840507ms","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-05-06T01:25:16.797368Z","caller":"traceutil/trace.go:171","msg":"trace[2004249497] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5239; }","duration":"149.97313ms","start":"2026-05-06T01:25:16.647381Z","end":"2026-05-06T01:25:16.797354Z","steps":["trace[2004249497] 'agreement among raft nodes before linearized reading' (duration: 149.781216ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:25:16.79717Z","caller":"traceutil/trace.go:171","msg":"trace[1125520409] transaction","detail":"{read_only:false; response_revision:5239; number_of_response:1; }","duration":"252.868088ms","start":"2026-05-06T01:25:16.544241Z","end":"2026-05-06T01:25:16.797109Z","steps":["trace[1125520409] 'process raft request' (duration: 252.381117ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:25:16.797598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.661489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-06T01:25:16.797682Z","caller":"traceutil/trace.go:171","msg":"trace[1871738621] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5239; }","duration":"133.801673ms","start":"2026-05-06T01:25:16.663861Z","end":"2026-05-06T01:25:16.797662Z","steps":["trace[1871738621] 'agreement among raft nodes before linearized reading' (duration: 133.526027ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:25:16.797817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.253459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-06T01:25:16.797811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.753945ms","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-05-06T01:25:16.797918Z","caller":"traceutil/trace.go:171","msg":"trace[1708789352] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5239; }","duration":"132.421543ms","start":"2026-05-06T01:25:16.665469Z","end":"2026-05-06T01:25:16.797891Z","steps":["trace[1708789352] 'agreement among raft nodes before linearized reading' (duration: 131.894092ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:25:16.797948Z","caller":"traceutil/trace.go:171","msg":"trace[1346113781] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5239; }","duration":"139.157534ms","start":"2026-05-06T01:25:16.658758Z","end":"2026-05-06T01:25:16.797915Z","steps":["trace[1346113781] 'agreement among raft nodes before linearized reading' (duration: 138.549341ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:25:26.154991Z","caller":"traceutil/trace.go:171","msg":"trace[1870762922] linearizableReadLoop","detail":"{readStateIndex:5634; appliedIndex:5633; }","duration":"151.042453ms","start":"2026-05-06T01:25:26.003922Z","end":"2026-05-06T01:25:26.154965Z","steps":["trace[1870762922] 'read index received' (duration: 150.997662ms)","trace[1870762922] 'applied index is now lower than readState.Index' (duration: 43.381µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:25:26.15506Z","caller":"traceutil/trace.go:171","msg":"trace[1295332844] transaction","detail":"{read_only:false; response_revision:5432; number_of_response:1; }","duration":"189.31001ms","start":"2026-05-06T01:25:25.965723Z","end":"2026-05-06T01:25:26.155033Z","steps":["trace[1295332844] 'process raft request' (duration: 189.083306ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:25:26.155206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.258847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0e5487a6-39c1-4139-b61e-4be0ff55fb31\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-05-06T01:25:26.155253Z","caller":"traceutil/trace.go:171","msg":"trace[247540638] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0e5487a6-39c1-4139-b61e-4be0ff55fb31; range_end:; response_count:1; response_revision:5432; }","duration":"151.358019ms","start":"2026-05-06T01:25:26.003885Z","end":"2026-05-06T01:25:26.155243Z","steps":["trace[247540638] 'agreement among raft nodes before linearized reading' (duration: 151.181135ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:25:26.511455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.124567ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T01:25:26.511618Z","caller":"traceutil/trace.go:171","msg":"trace[1657863164] linearizableReadLoop","detail":"{readStateIndex:5635; appliedIndex:5634; }","duration":"200.355411ms","start":"2026-05-06T01:25:26.311235Z","end":"2026-05-06T01:25:26.511591Z","steps":["trace[1657863164] 'read index received' (duration: 92.03444ms)","trace[1657863164] 'applied index is now lower than readState.Index' (duration: 108.318661ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:25:26.511701Z","caller":"traceutil/trace.go:171","msg":"trace[682624207] transaction","detail":"{read_only:false; response_revision:5433; number_of_response:1; }","duration":"472.677925ms","start":"2026-05-06T01:25:26.038985Z","end":"2026-05-06T01:25:26.511663Z","steps":["trace[682624207] 'process raft request' (duration: 364.233142ms)","trace[682624207] 'compare' (duration: 107.954253ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:25:26.51187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.649127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-06T01:25:26.51191Z","caller":"traceutil/trace.go:171","msg":"trace[297565620] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5433; }","duration":"200.737069ms","start":"2026-05-06T01:25:26.311163Z","end":"2026-05-06T01:25:26.5119Z","steps":["trace[297565620] 'agreement among raft nodes before linearized reading' (duration: 200.561766ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:25:26.511915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:25:26.038948Z","time spent":"472.845738ms","remote":"127.0.0.1:59038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T01:26:34.777239Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2983} {"level":"info","ts":"2026-05-06T01:26:34.845636Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2983,"took":"66.366351ms","hash":4000713519,"current-db-size-bytes":58007552,"current-db-size":"58 MB","current-db-size-in-use-bytes":33431552,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-05-06T01:26:34.845729Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4000713519,"revision":2983,"compact-revision":-1} {"level":"info","ts":"2026-05-06T01:31:34.784195Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6055} {"level":"info","ts":"2026-05-06T01:31:34.88682Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6055,"took":"100.42628ms","hash":4125936025,"current-db-size-bytes":58007552,"current-db-size":"58 MB","current-db-size-in-use-bytes":18403328,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-06T01:31:34.886952Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4125936025,"revision":6055,"compact-revision":2983}