{"level":"warn","ts":"2026-06-19T18:04:51.781272Z","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-19T18:04:51.781397Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.177: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.177:2380","--initial-cluster=instance=https://199.204.45.177:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.177:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.177: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-19T18:04:51.781488Z","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-19T18:04:51.781508Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.177:2380"]} {"level":"info","ts":"2026-06-19T18:04:51.781567Z","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-19T18:04:51.782416Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.177:2379"]} {"level":"info","ts":"2026-06-19T18:04:51.782514Z","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.177:2380"],"listen-peer-urls":["https://199.204.45.177:2380"],"advertise-client-urls":["https://199.204.45.177:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.177:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.177: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-19T18:04:51.786621Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.749592ms"} {"level":"info","ts":"2026-06-19T18:04:51.792777Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"7c53a18098324dc9","cluster-id":"4717e8aa221973c8"} {"level":"info","ts":"2026-06-19T18:04:51.792882Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 switched to configuration voters=()"} {"level":"info","ts":"2026-06-19T18:04:51.792907Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became follower at term 0"} {"level":"info","ts":"2026-06-19T18:04:51.792925Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 7c53a18098324dc9 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-19T18:04:51.792934Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became follower at term 1"} {"level":"info","ts":"2026-06-19T18:04:51.792976Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 switched to configuration voters=(8958681657451367881)"} {"level":"warn","ts":"2026-06-19T18:04:51.798889Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-19T18:04:51.802289Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-19T18:04:51.803656Z","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-19T18:04:51.806753Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"7c53a18098324dc9","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-19T18:04:51.806941Z","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-19T18:04:51.807092Z","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-19T18:04:51.807104Z","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-19T18:04:51.807198Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"7c53a18098324dc9","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-19T18:04:51.808053Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 switched to configuration voters=(8958681657451367881)"} {"level":"info","ts":"2026-06-19T18:04:51.808602Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4717e8aa221973c8","local-member-id":"7c53a18098324dc9","added-peer-id":"7c53a18098324dc9","added-peer-peer-urls":["https://199.204.45.177:2380"]} {"level":"info","ts":"2026-06-19T18:04:51.81226Z","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-19T18:04:51.812654Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"7c53a18098324dc9","initial-advertise-peer-urls":["https://199.204.45.177:2380"],"listen-peer-urls":["https://199.204.45.177:2380"],"advertise-client-urls":["https://199.204.45.177:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.177:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-19T18:04:51.812739Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-19T18:04:51.812845Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.177:2380"} {"level":"info","ts":"2026-06-19T18:04:51.812874Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.177:2380"} {"level":"info","ts":"2026-06-19T18:04:52.193854Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 is starting a new election at term 1"} {"level":"info","ts":"2026-06-19T18:04:52.193921Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-19T18:04:52.193974Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 received MsgPreVoteResp from 7c53a18098324dc9 at term 1"} {"level":"info","ts":"2026-06-19T18:04:52.193995Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became candidate at term 2"} {"level":"info","ts":"2026-06-19T18:04:52.194008Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 received MsgVoteResp from 7c53a18098324dc9 at term 2"} {"level":"info","ts":"2026-06-19T18:04:52.194035Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became leader at term 2"} {"level":"info","ts":"2026-06-19T18:04:52.194056Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 7c53a18098324dc9 elected leader 7c53a18098324dc9 at term 2"} {"level":"info","ts":"2026-06-19T18:04:52.195183Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-19T18:04:52.196465Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-19T18:04:52.196569Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-19T18:04:52.196454Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"7c53a18098324dc9","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.177:2379]}","request-path":"/0/members/7c53a18098324dc9/attributes","cluster-id":"4717e8aa221973c8","publish-timeout":"7s"} {"level":"info","ts":"2026-06-19T18:04:52.196841Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-19T18:04:52.196989Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-19T18:04:52.197074Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4717e8aa221973c8","local-member-id":"7c53a18098324dc9","cluster-version":"3.5"} {"level":"info","ts":"2026-06-19T18:04:52.197253Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-19T18:04:52.197323Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-19T18:04:52.198956Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.177:2379"} {"level":"info","ts":"2026-06-19T18:04:52.200377Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-19T18:05:30.24131Z","caller":"traceutil/trace.go:171","msg":"trace[252448329] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"148.759237ms","start":"2026-06-19T18:05:30.092517Z","end":"2026-06-19T18:05:30.241277Z","steps":["trace[252448329] 'process raft request' (duration: 142.624704ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:05:30.241416Z","caller":"traceutil/trace.go:171","msg":"trace[472733438] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"125.242103ms","start":"2026-06-19T18:05:30.11613Z","end":"2026-06-19T18:05:30.241372Z","steps":["trace[472733438] 'process raft request' (duration: 125.019537ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:05:36.355658Z","caller":"traceutil/trace.go:171","msg":"trace[1183959025] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"121.393826ms","start":"2026-06-19T18:05:36.234227Z","end":"2026-06-19T18:05:36.355621Z","steps":["trace[1183959025] 'process raft request' (duration: 121.15706ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:05:36.776112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.025265ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-19T18:05:36.776262Z","caller":"traceutil/trace.go:171","msg":"trace[2073885183] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"207.708701ms","start":"2026-06-19T18:05:36.568534Z","end":"2026-06-19T18:05:36.776242Z","steps":["trace[2073885183] 'process raft request' (duration: 37.073171ms)","trace[2073885183] 'compare' (duration: 169.881562ms)"],"step_count":2} {"level":"warn","ts":"2026-06-19T18:05:37.315643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.154172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-19T18:05:37.315743Z","caller":"traceutil/trace.go:171","msg":"trace[2033547617] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:593; }","duration":"313.317506ms","start":"2026-06-19T18:05:37.002403Z","end":"2026-06-19T18:05:37.315721Z","steps":["trace[2033547617] 'range keys from in-memory index tree' (duration: 312.954217ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:05:37.315793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T18:05:37.002378Z","time spent":"313.404708ms","remote":"127.0.0.1:39528","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-19T18:05:37.316666Z","caller":"traceutil/trace.go:171","msg":"trace[1618269892] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"134.469491ms","start":"2026-06-19T18:05:37.182135Z","end":"2026-06-19T18:05:37.316605Z","steps":["trace[1618269892] 'process raft request' (duration: 127.588341ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:06:21.699768Z","caller":"traceutil/trace.go:171","msg":"trace[1050952161] transaction","detail":"{read_only:false; response_revision:1149; number_of_response:1; }","duration":"137.906892ms","start":"2026-06-19T18:06:21.561831Z","end":"2026-06-19T18:06:21.699738Z","steps":["trace[1050952161] 'process raft request' (duration: 137.731507ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:06:21.75518Z","caller":"traceutil/trace.go:171","msg":"trace[500216986] transaction","detail":"{read_only:false; response_revision:1150; number_of_response:1; }","duration":"155.726909ms","start":"2026-06-19T18:06:21.599414Z","end":"2026-06-19T18:06:21.755141Z","steps":["trace[500216986] 'process raft request' (duration: 155.499884ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:07:12.178046Z","caller":"traceutil/trace.go:171","msg":"trace[1041644621] linearizableReadLoop","detail":"{readStateIndex:1914; appliedIndex:1912; }","duration":"155.803392ms","start":"2026-06-19T18:07:12.022215Z","end":"2026-06-19T18:07:12.178019Z","steps":["trace[1041644621] 'read index received' (duration: 80.533748ms)","trace[1041644621] 'applied index is now lower than readState.Index' (duration: 75.268194ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T18:07:12.178059Z","caller":"traceutil/trace.go:171","msg":"trace[1218036537] transaction","detail":"{read_only:false; response_revision:1838; number_of_response:1; }","duration":"278.607143ms","start":"2026-06-19T18:07:11.899393Z","end":"2026-06-19T18:07:12.178Z","steps":["trace[1218036537] 'process raft request' (duration: 203.340648ms)","trace[1218036537] 'compare' (duration: 74.357591ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T18:07:12.178114Z","caller":"traceutil/trace.go:171","msg":"trace[361737063] transaction","detail":"{read_only:false; response_revision:1839; number_of_response:1; }","duration":"167.433864ms","start":"2026-06-19T18:07:12.010628Z","end":"2026-06-19T18:07:12.178062Z","steps":["trace[361737063] 'process raft request' (duration: 167.206999ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:07:12.178321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.084409ms","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-19T18:07:12.178619Z","caller":"traceutil/trace.go:171","msg":"trace[1241231608] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1840; }","duration":"156.442198ms","start":"2026-06-19T18:07:12.022159Z","end":"2026-06-19T18:07:12.178601Z","steps":["trace[1241231608] 'agreement among raft nodes before linearized reading' (duration: 155.969696ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:07:12.178799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.934114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e4af21ec-2bcd-4f33-8248-105c9d44d0fe\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-06-19T18:07:12.17892Z","caller":"traceutil/trace.go:171","msg":"trace[767684077] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e4af21ec-2bcd-4f33-8248-105c9d44d0fe; range_end:; response_count:1; response_revision:1840; }","duration":"148.109948ms","start":"2026-06-19T18:07:12.030795Z","end":"2026-06-19T18:07:12.178905Z","steps":["trace[767684077] 'agreement among raft nodes before linearized reading' (duration: 147.729009ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:07:20.532827Z","caller":"traceutil/trace.go:171","msg":"trace[484740807] transaction","detail":"{read_only:false; response_revision:1913; number_of_response:1; }","duration":"323.751667ms","start":"2026-06-19T18:07:20.209035Z","end":"2026-06-19T18:07:20.532786Z","steps":["trace[484740807] 'process raft request' (duration: 230.181184ms)","trace[484740807] 'compare' (duration: 93.154213ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T18:07:20.532884Z","caller":"traceutil/trace.go:171","msg":"trace[926257076] linearizableReadLoop","detail":"{readStateIndex:1990; appliedIndex:1987; }","duration":"308.059528ms","start":"2026-06-19T18:07:20.224725Z","end":"2026-06-19T18:07:20.532785Z","steps":["trace[926257076] 'read index received' (duration: 3.804884ms)","trace[926257076] 'applied index is now lower than readState.Index' (duration: 304.253634ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T18:07:20.53287Z","caller":"traceutil/trace.go:171","msg":"trace[233185468] transaction","detail":"{read_only:false; response_revision:1914; number_of_response:1; }","duration":"320.935657ms","start":"2026-06-19T18:07:20.211906Z","end":"2026-06-19T18:07:20.532841Z","steps":["trace[233185468] 'process raft request' (duration: 320.772333ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:07:20.533246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.461759ms","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-19T18:07:20.533362Z","caller":"traceutil/trace.go:171","msg":"trace[872402842] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1914; }","duration":"308.663234ms","start":"2026-06-19T18:07:20.224666Z","end":"2026-06-19T18:07:20.533329Z","steps":["trace[872402842] 'agreement among raft nodes before linearized reading' (duration: 308.282274ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:07:20.533413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T18:07:20.224648Z","time spent":"308.753266ms","remote":"127.0.0.1:39528","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-19T18:07:20.533675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T18:07:20.209019Z","time spent":"323.917471ms","remote":"127.0.0.1:39528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-19T18:07:20.533794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T18:07:20.211876Z","time spent":"321.094001ms","remote":"127.0.0.1:39528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-19T18:07:20.542636Z","caller":"traceutil/trace.go:171","msg":"trace[1912943753] transaction","detail":"{read_only:false; response_revision:1915; number_of_response:1; }","duration":"270.178392ms","start":"2026-06-19T18:07:20.272435Z","end":"2026-06-19T18:07:20.542613Z","steps":["trace[1912943753] 'process raft request' (duration: 270.032168ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:07:20.845568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.533002ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-06-19T18:07:20.854552Z","caller":"traceutil/trace.go:171","msg":"trace[1495768940] linearizableReadLoop","detail":"{readStateIndex:1992; appliedIndex:1990; }","duration":"321.570283ms","start":"2026-06-19T18:07:20.53295Z","end":"2026-06-19T18:07:20.85452Z","steps":["trace[1495768940] 'read index received' (duration: 9.586407ms)","trace[1495768940] 'applied index is now lower than readState.Index' (duration: 311.982176ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T18:07:20.854721Z","caller":"traceutil/trace.go:171","msg":"trace[1733222690] transaction","detail":"{read_only:false; response_revision:1916; number_of_response:1; }","duration":"316.659811ms","start":"2026-06-19T18:07:20.538043Z","end":"2026-06-19T18:07:20.854702Z","steps":["trace[1733222690] 'process raft request' (duration: 307.624518ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:07:20.854829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T18:07:20.538021Z","time spent":"316.733453ms","remote":"127.0.0.1:39528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-19T18:07:20.926149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.382237ms","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-19T18:07:20.926237Z","caller":"traceutil/trace.go:171","msg":"trace[585489187] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1916; }","duration":"232.535951ms","start":"2026-06-19T18:07:20.693677Z","end":"2026-06-19T18:07:20.926213Z","steps":["trace[585489187] 'agreement among raft nodes before linearized reading' (duration: 161.449624ms)","trace[585489187] 'range keys from in-memory index tree' (duration: 70.877483ms)"],"step_count":2} {"level":"warn","ts":"2026-06-19T18:07:20.926267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.518516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-19T18:07:20.926361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.596075ms","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-19T18:07:20.926398Z","caller":"traceutil/trace.go:171","msg":"trace[1111480946] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1916; }","duration":"620.685691ms","start":"2026-06-19T18:07:20.305679Z","end":"2026-06-19T18:07:20.926364Z","steps":["trace[1111480946] 'agreement among raft nodes before linearized reading' (duration: 549.368327ms)","trace[1111480946] 'range keys from in-memory index tree' (duration: 71.17229ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T18:07:20.926431Z","caller":"traceutil/trace.go:171","msg":"trace[728593905] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1916; }","duration":"224.722198ms","start":"2026-06-19T18:07:20.70169Z","end":"2026-06-19T18:07:20.926412Z","steps":["trace[728593905] 'agreement among raft nodes before linearized reading' (duration: 153.467555ms)","trace[728593905] 'range keys from in-memory index tree' (duration: 71.115819ms)"],"step_count":2} {"level":"warn","ts":"2026-06-19T18:07:20.926468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.713521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-06-19T18:07:20.926481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T18:07:20.305661Z","time spent":"620.800053ms","remote":"127.0.0.1:39244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-19T18:07:20.926498Z","caller":"traceutil/trace.go:171","msg":"trace[1597772886] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1916; }","duration":"267.779753ms","start":"2026-06-19T18:07:20.65871Z","end":"2026-06-19T18:07:20.92649Z","steps":["trace[1597772886] 'agreement among raft nodes before linearized reading' (duration: 196.360996ms)","trace[1597772886] 'range keys from in-memory index tree' (duration: 71.336605ms)"],"step_count":2} {"level":"warn","ts":"2026-06-19T18:07:24.621653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.629014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-cluster-operator-54f767cff8-9krzj\" ","response":"range_response_count:1 size:5855"} {"level":"info","ts":"2026-06-19T18:07:24.621751Z","caller":"traceutil/trace.go:171","msg":"trace[1566153140] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-cluster-operator-54f767cff8-9krzj; range_end:; response_count:1; response_revision:1936; }","duration":"175.786758ms","start":"2026-06-19T18:07:24.445938Z","end":"2026-06-19T18:07:24.621724Z","steps":["trace[1566153140] 'range keys from in-memory index tree' (duration: 175.370868ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:07:24.622653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.761853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11989"} {"level":"info","ts":"2026-06-19T18:07:24.622762Z","caller":"traceutil/trace.go:171","msg":"trace[195782198] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1936; }","duration":"267.922136ms","start":"2026-06-19T18:07:24.354818Z","end":"2026-06-19T18:07:24.62274Z","steps":["trace[195782198] 'range keys from in-memory index tree' (duration: 266.619225ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:07:57.297022Z","caller":"traceutil/trace.go:171","msg":"trace[1421025447] linearizableReadLoop","detail":"{readStateIndex:2216; appliedIndex:2215; }","duration":"142.188707ms","start":"2026-06-19T18:07:57.154798Z","end":"2026-06-19T18:07:57.296987Z","steps":["trace[1421025447] 'read index received' (duration: 56.351804ms)","trace[1421025447] 'applied index is now lower than readState.Index' (duration: 85.835473ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T18:07:57.297256Z","caller":"traceutil/trace.go:171","msg":"trace[76443581] transaction","detail":"{read_only:false; response_revision:2132; number_of_response:1; }","duration":"142.852473ms","start":"2026-06-19T18:07:57.154375Z","end":"2026-06-19T18:07:57.297227Z","steps":["trace[76443581] 'process raft request' (duration: 56.693052ms)","trace[76443581] 'compare' (duration: 85.766041ms)"],"step_count":2} {"level":"warn","ts":"2026-06-19T18:07:57.297427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.599577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-06-19T18:07:57.297446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.677194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-19T18:07:57.297476Z","caller":"traceutil/trace.go:171","msg":"trace[814117220] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2132; }","duration":"142.691629ms","start":"2026-06-19T18:07:57.154773Z","end":"2026-06-19T18:07:57.297464Z","steps":["trace[814117220] 'agreement among raft nodes before linearized reading' (duration: 142.461973ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:07:57.297481Z","caller":"traceutil/trace.go:171","msg":"trace[311939147] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2132; }","duration":"139.763467ms","start":"2026-06-19T18:07:57.157707Z","end":"2026-06-19T18:07:57.297471Z","steps":["trace[311939147] 'agreement among raft nodes before linearized reading' (duration: 139.558232ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:08:03.67732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.342703ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-19T18:08:03.677582Z","caller":"traceutil/trace.go:171","msg":"trace[740011928] linearizableReadLoop","detail":"{readStateIndex:2253; appliedIndex:2251; }","duration":"211.231763ms","start":"2026-06-19T18:08:03.466323Z","end":"2026-06-19T18:08:03.677555Z","steps":["trace[740011928] 'read index received' (duration: 78.538942ms)","trace[740011928] 'applied index is now lower than readState.Index' (duration: 132.690431ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T18:08:03.677687Z","caller":"traceutil/trace.go:171","msg":"trace[834528772] transaction","detail":"{read_only:false; response_revision:2167; number_of_response:1; }","duration":"265.102746ms","start":"2026-06-19T18:08:03.412564Z","end":"2026-06-19T18:08:03.677667Z","steps":["trace[834528772] 'process raft request' (duration: 264.922422ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:08:03.677731Z","caller":"traceutil/trace.go:171","msg":"trace[1003627671] transaction","detail":"{read_only:false; response_revision:2166; number_of_response:1; }","duration":"266.373338ms","start":"2026-06-19T18:08:03.411293Z","end":"2026-06-19T18:08:03.677666Z","steps":["trace[1003627671] 'process raft request' (duration: 133.548004ms)","trace[1003627671] 'compare' (duration: 132.183368ms)"],"step_count":2} {"level":"warn","ts":"2026-06-19T18:08:03.677836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.50174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-19T18:08:03.677894Z","caller":"traceutil/trace.go:171","msg":"trace[1961475562] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:2167; }","duration":"211.577822ms","start":"2026-06-19T18:08:03.466299Z","end":"2026-06-19T18:08:03.677877Z","steps":["trace[1961475562] 'agreement among raft nodes before linearized reading' (duration: 211.339236ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:08:03.974842Z","caller":"traceutil/trace.go:171","msg":"trace[25579512] transaction","detail":"{read_only:false; response_revision:2168; number_of_response:1; }","duration":"169.90181ms","start":"2026-06-19T18:08:03.804897Z","end":"2026-06-19T18:08:03.974799Z","steps":["trace[25579512] 'process raft request' (duration: 169.646954ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:09:27.267237Z","caller":"traceutil/trace.go:171","msg":"trace[1319297524] transaction","detail":"{read_only:false; response_revision:2812; number_of_response:1; }","duration":"142.457957ms","start":"2026-06-19T18:09:27.124711Z","end":"2026-06-19T18:09:27.267169Z","steps":["trace[1319297524] 'process raft request' (duration: 112.478011ms)","trace[1319297524] 'compare' (duration: 29.73022ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T18:09:27.26747Z","caller":"traceutil/trace.go:171","msg":"trace[1142887589] transaction","detail":"{read_only:false; response_revision:2813; number_of_response:1; }","duration":"137.935256ms","start":"2026-06-19T18:09:27.129487Z","end":"2026-06-19T18:09:27.267423Z","steps":["trace[1142887589] 'process raft request' (duration: 137.632369ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:09:27.749311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.017553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-19T18:09:27.749396Z","caller":"traceutil/trace.go:171","msg":"trace[1962829887] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:2813; }","duration":"247.158797ms","start":"2026-06-19T18:09:27.50222Z","end":"2026-06-19T18:09:27.749379Z","steps":["trace[1962829887] 'count revisions from in-memory index tree' (duration: 246.777167ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:13:41.112314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.020173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-06-19T18:13:41.112433Z","caller":"traceutil/trace.go:171","msg":"trace[167084813] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4931; }","duration":"118.209987ms","start":"2026-06-19T18:13:40.994175Z","end":"2026-06-19T18:13:41.112385Z","steps":["trace[167084813] 'range keys from in-memory index tree' (duration: 117.803117ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:13:43.741714Z","caller":"traceutil/trace.go:171","msg":"trace[649955066] transaction","detail":"{read_only:false; response_revision:5074; number_of_response:1; }","duration":"205.85236ms","start":"2026-06-19T18:13:43.535825Z","end":"2026-06-19T18:13:43.741678Z","steps":["trace[649955066] 'process raft request' (duration: 169.447095ms)","trace[649955066] 'compare' (duration: 36.267562ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T18:13:53.200251Z","caller":"traceutil/trace.go:171","msg":"trace[262332704] transaction","detail":"{read_only:false; response_revision:5274; number_of_response:1; }","duration":"100.570719ms","start":"2026-06-19T18:13:53.099649Z","end":"2026-06-19T18:13:53.200219Z","steps":["trace[262332704] 'process raft request' (duration: 100.399875ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:13:53.207485Z","caller":"traceutil/trace.go:171","msg":"trace[717443092] transaction","detail":"{read_only:false; response_revision:5276; number_of_response:1; }","duration":"107.460357ms","start":"2026-06-19T18:13:53.100005Z","end":"2026-06-19T18:13:53.207465Z","steps":["trace[717443092] 'process raft request' (duration: 107.349084ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:13:53.207577Z","caller":"traceutil/trace.go:171","msg":"trace[1631973843] transaction","detail":"{read_only:false; response_revision:5275; number_of_response:1; }","duration":"107.720484ms","start":"2026-06-19T18:13:53.099811Z","end":"2026-06-19T18:13:53.207531Z","steps":["trace[1631973843] 'process raft request' (duration: 107.428427ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:13:53.207732Z","caller":"traceutil/trace.go:171","msg":"trace[1425532990] transaction","detail":"{read_only:false; response_revision:5277; number_of_response:1; }","duration":"107.711993ms","start":"2026-06-19T18:13:53.100002Z","end":"2026-06-19T18:13:53.207714Z","steps":["trace[1425532990] 'process raft request' (duration: 107.430116ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:13:53.208168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.141549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-06-19T18:13:53.208247Z","caller":"traceutil/trace.go:171","msg":"trace[1411719261] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5278; }","duration":"100.231061ms","start":"2026-06-19T18:13:53.107994Z","end":"2026-06-19T18:13:53.208225Z","steps":["trace[1411719261] 'agreement among raft nodes before linearized reading' (duration: 99.871093ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:13:58.085945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.822766ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-19T18:13:58.086283Z","caller":"traceutil/trace.go:171","msg":"trace[1972909385] transaction","detail":"{read_only:false; response_revision:5357; number_of_response:1; }","duration":"246.961356ms","start":"2026-06-19T18:13:57.839288Z","end":"2026-06-19T18:13:58.08625Z","steps":["trace[1972909385] 'process raft request' (duration: 122.728009ms)","trace[1972909385] 'compare' (duration: 123.644082ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T18:13:58.08638Z","caller":"traceutil/trace.go:171","msg":"trace[673560014] linearizableReadLoop","detail":"{readStateIndex:5566; appliedIndex:5563; }","duration":"140.597505ms","start":"2026-06-19T18:13:57.945733Z","end":"2026-06-19T18:13:58.08633Z","steps":["trace[673560014] 'read index received' (duration: 16.239765ms)","trace[673560014] 'applied index is now lower than readState.Index' (duration: 124.35613ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T18:13:58.086413Z","caller":"traceutil/trace.go:171","msg":"trace[1922096630] transaction","detail":"{read_only:false; response_revision:5358; number_of_response:1; }","duration":"246.982586ms","start":"2026-06-19T18:13:57.839402Z","end":"2026-06-19T18:13:58.086384Z","steps":["trace[1922096630] 'process raft request' (duration: 246.757651ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:13:58.086432Z","caller":"traceutil/trace.go:171","msg":"trace[1110298818] transaction","detail":"{read_only:false; response_revision:5359; number_of_response:1; }","duration":"244.336943ms","start":"2026-06-19T18:13:57.842058Z","end":"2026-06-19T18:13:58.086395Z","steps":["trace[1110298818] 'process raft request' (duration: 244.186569ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:13:58.086581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.029949ms","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-19T18:13:58.086646Z","caller":"traceutil/trace.go:171","msg":"trace[341828870] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5359; }","duration":"135.158673ms","start":"2026-06-19T18:13:57.951471Z","end":"2026-06-19T18:13:58.08663Z","steps":["trace[341828870] 'agreement among raft nodes before linearized reading' (duration: 135.03759ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:13:58.086766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.996885ms","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-19T18:13:58.086882Z","caller":"traceutil/trace.go:171","msg":"trace[349547994] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5359; }","duration":"141.18738ms","start":"2026-06-19T18:13:57.94567Z","end":"2026-06-19T18:13:58.086858Z","steps":["trace[349547994] 'agreement among raft nodes before linearized reading' (duration: 140.809911ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:14:06.321761Z","caller":"traceutil/trace.go:171","msg":"trace[1694616996] linearizableReadLoop","detail":"{readStateIndex:5729; appliedIndex:5728; }","duration":"135.612403ms","start":"2026-06-19T18:14:06.186112Z","end":"2026-06-19T18:14:06.321725Z","steps":["trace[1694616996] 'read index received' (duration: 135.424079ms)","trace[1694616996] 'applied index is now lower than readState.Index' (duration: 186.854µs)"],"step_count":2} {"level":"info","ts":"2026-06-19T18:14:06.321794Z","caller":"traceutil/trace.go:171","msg":"trace[486615291] transaction","detail":"{read_only:false; response_revision:5520; number_of_response:1; }","duration":"152.535835ms","start":"2026-06-19T18:14:06.169226Z","end":"2026-06-19T18:14:06.321761Z","steps":["trace[486615291] 'process raft request' (duration: 152.262939ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:14:06.32212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.972931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-19T18:14:06.322188Z","caller":"traceutil/trace.go:171","msg":"trace[1565007261] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5520; }","duration":"136.119816ms","start":"2026-06-19T18:14:06.186055Z","end":"2026-06-19T18:14:06.322175Z","steps":["trace[1565007261] 'agreement among raft nodes before linearized reading' (duration: 135.803857ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:14:06.325494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.011215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-54808889-d28c-43b8-b665-719c393290fd\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-06-19T18:14:06.325544Z","caller":"traceutil/trace.go:171","msg":"trace[268057929] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-54808889-d28c-43b8-b665-719c393290fd; range_end:; response_count:1; response_revision:5521; }","duration":"118.136887ms","start":"2026-06-19T18:14:06.207395Z","end":"2026-06-19T18:14:06.325532Z","steps":["trace[268057929] 'agreement among raft nodes before linearized reading' (duration: 117.621165ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:14:06.447414Z","caller":"traceutil/trace.go:171","msg":"trace[1491190345] transaction","detail":"{read_only:false; response_revision:5523; number_of_response:1; }","duration":"114.718644ms","start":"2026-06-19T18:14:06.332661Z","end":"2026-06-19T18:14:06.447379Z","steps":["trace[1491190345] 'process raft request' (duration: 54.305573ms)","trace[1491190345] 'compare' (duration: 59.991581ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T18:14:52.232327Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2950} {"level":"info","ts":"2026-06-19T18:14:52.313493Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2950,"took":"79.340352ms","hash":4202722722,"current-db-size-bytes":58953728,"current-db-size":"59 MB","current-db-size-in-use-bytes":33710080,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-19T18:14:52.313567Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4202722722,"revision":2950,"compact-revision":-1} {"level":"info","ts":"2026-06-19T18:19:52.239281Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5985} {"level":"info","ts":"2026-06-19T18:19:52.335335Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5985,"took":"94.114964ms","hash":3583451388,"current-db-size-bytes":58953728,"current-db-size":"59 MB","current-db-size-in-use-bytes":21938176,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-06-19T18:19:52.33552Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3583451388,"revision":5985,"compact-revision":2950} {"level":"info","ts":"2026-06-19T18:20:23.430842Z","caller":"traceutil/trace.go:171","msg":"trace[494270744] transaction","detail":"{read_only:false; response_revision:8405; number_of_response:1; }","duration":"140.48207ms","start":"2026-06-19T18:20:23.290327Z","end":"2026-06-19T18:20:23.430809Z","steps":["trace[494270744] 'process raft request' (duration: 140.336726ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:20:23.467886Z","caller":"traceutil/trace.go:171","msg":"trace[402813422] transaction","detail":"{read_only:false; response_revision:8406; number_of_response:1; }","duration":"177.17646ms","start":"2026-06-19T18:20:23.290679Z","end":"2026-06-19T18:20:23.467855Z","steps":["trace[402813422] 'process raft request' (duration: 177.024716ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:20:35.639086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.710142ms","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-06-19T18:20:35.639199Z","caller":"traceutil/trace.go:171","msg":"trace[237576051] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8468; }","duration":"158.878667ms","start":"2026-06-19T18:20:35.480303Z","end":"2026-06-19T18:20:35.639182Z","steps":["trace[237576051] 'agreement among raft nodes before linearized reading' (duration: 158.59253ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:20:35.638856Z","caller":"traceutil/trace.go:171","msg":"trace[216005765] linearizableReadLoop","detail":"{readStateIndex:8773; appliedIndex:8772; }","duration":"158.474257ms","start":"2026-06-19T18:20:35.480347Z","end":"2026-06-19T18:20:35.638821Z","steps":["trace[216005765] 'read index received' (duration: 158.424996ms)","trace[216005765] 'applied index is now lower than readState.Index' (duration: 46.381µs)"],"step_count":2} {"level":"info","ts":"2026-06-19T18:20:35.671476Z","caller":"traceutil/trace.go:171","msg":"trace[32191982] transaction","detail":"{read_only:false; response_revision:8469; number_of_response:1; }","duration":"114.937059ms","start":"2026-06-19T18:20:35.55652Z","end":"2026-06-19T18:20:35.671457Z","steps":["trace[32191982] 'process raft request' (duration: 114.760585ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:20:35.671608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.211356ms","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-19T18:20:35.671681Z","caller":"traceutil/trace.go:171","msg":"trace[396306379] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8469; }","duration":"157.317619ms","start":"2026-06-19T18:20:35.514347Z","end":"2026-06-19T18:20:35.671665Z","steps":["trace[396306379] 'agreement among raft nodes before linearized reading' (duration: 157.114243ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:20:35.809758Z","caller":"traceutil/trace.go:171","msg":"trace[1332743507] linearizableReadLoop","detail":"{readStateIndex:8778; appliedIndex:8776; }","duration":"109.266362ms","start":"2026-06-19T18:20:35.700467Z","end":"2026-06-19T18:20:35.809734Z","steps":["trace[1332743507] 'read index received' (duration: 32.279653ms)","trace[1332743507] 'applied index is now lower than readState.Index' (duration: 76.985889ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T18:20:35.809817Z","caller":"traceutil/trace.go:171","msg":"trace[777422975] transaction","detail":"{read_only:false; response_revision:8473; number_of_response:1; }","duration":"116.997299ms","start":"2026-06-19T18:20:35.692802Z","end":"2026-06-19T18:20:35.809799Z","steps":["trace[777422975] 'process raft request' (duration: 116.882207ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:20:35.809816Z","caller":"traceutil/trace.go:171","msg":"trace[867566824] transaction","detail":"{read_only:false; response_revision:8472; number_of_response:1; }","duration":"134.726729ms","start":"2026-06-19T18:20:35.675058Z","end":"2026-06-19T18:20:35.809785Z","steps":["trace[867566824] 'process raft request' (duration: 57.622957ms)","trace[867566824] 'compare' (duration: 76.869766ms)"],"step_count":2} {"level":"warn","ts":"2026-06-19T18:20:35.810062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.566029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-06-19T18:20:35.810121Z","caller":"traceutil/trace.go:171","msg":"trace[1303068839] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:8473; }","duration":"109.661522ms","start":"2026-06-19T18:20:35.700447Z","end":"2026-06-19T18:20:35.810109Z","steps":["trace[1303068839] 'agreement among raft nodes before linearized reading' (duration: 109.396445ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:20:36.075597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.969181ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-19T18:20:36.075797Z","caller":"traceutil/trace.go:171","msg":"trace[171161329] transaction","detail":"{read_only:false; response_revision:8475; number_of_response:1; }","duration":"231.833007ms","start":"2026-06-19T18:20:35.843945Z","end":"2026-06-19T18:20:36.075778Z","steps":["trace[171161329] 'process raft request' (duration: 231.761315ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:20:36.075788Z","caller":"traceutil/trace.go:171","msg":"trace[1973868794] transaction","detail":"{read_only:false; response_revision:8474; number_of_response:1; }","duration":"372.956131ms","start":"2026-06-19T18:20:35.702807Z","end":"2026-06-19T18:20:36.075764Z","steps":["trace[1973868794] 'process raft request' (duration: 264.663673ms)","trace[1973868794] 'compare' (duration: 107.838747ms)"],"step_count":2} {"level":"warn","ts":"2026-06-19T18:20:36.075926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T18:20:35.702797Z","time spent":"373.051123ms","remote":"127.0.0.1:39528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-19T18:20:36.076049Z","caller":"traceutil/trace.go:171","msg":"trace[1583874082] linearizableReadLoop","detail":"{readStateIndex:8779; appliedIndex:8778; }","duration":"266.215181ms","start":"2026-06-19T18:20:35.809821Z","end":"2026-06-19T18:20:36.076036Z","steps":["trace[1583874082] 'read index received' (duration: 157.667076ms)","trace[1583874082] 'applied index is now lower than readState.Index' (duration: 108.547135ms)"],"step_count":2} {"level":"warn","ts":"2026-06-19T18:20:36.076199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.858712ms","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-19T18:20:36.076234Z","caller":"traceutil/trace.go:171","msg":"trace[1373267028] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8475; }","duration":"291.933314ms","start":"2026-06-19T18:20:35.784292Z","end":"2026-06-19T18:20:36.076225Z","steps":["trace[1373267028] 'agreement among raft nodes before linearized reading' (duration: 291.803331ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:20:36.100857Z","caller":"traceutil/trace.go:171","msg":"trace[703281013] transaction","detail":"{read_only:false; response_revision:8476; number_of_response:1; }","duration":"130.938528ms","start":"2026-06-19T18:20:35.969892Z","end":"2026-06-19T18:20:36.100831Z","steps":["trace[703281013] 'process raft request' (duration: 130.806895ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:20:36.100949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.26766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-06-19T18:20:36.101048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.488787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-19T18:20:36.101077Z","caller":"traceutil/trace.go:171","msg":"trace[1198494956] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:8476; }","duration":"289.368542ms","start":"2026-06-19T18:20:35.81165Z","end":"2026-06-19T18:20:36.101019Z","steps":["trace[1198494956] 'agreement among raft nodes before linearized reading' (duration: 289.165647ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:20:36.101172Z","caller":"traceutil/trace.go:171","msg":"trace[1026682009] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:8476; }","duration":"156.59116ms","start":"2026-06-19T18:20:35.944505Z","end":"2026-06-19T18:20:36.101096Z","steps":["trace[1026682009] 'agreement among raft nodes before linearized reading' (duration: 156.429056ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:20:36.977899Z","caller":"traceutil/trace.go:171","msg":"trace[1515365549] transaction","detail":"{read_only:false; response_revision:8480; number_of_response:1; }","duration":"110.973714ms","start":"2026-06-19T18:20:36.866898Z","end":"2026-06-19T18:20:36.977872Z","steps":["trace[1515365549] 'process raft request' (duration: 110.726338ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:20:42.798585Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002277.wal"} {"level":"info","ts":"2026-06-19T18:21:04.395221Z","caller":"traceutil/trace.go:171","msg":"trace[827627293] linearizableReadLoop","detail":"{readStateIndex:8993; appliedIndex:8992; }","duration":"214.107947ms","start":"2026-06-19T18:21:04.181089Z","end":"2026-06-19T18:21:04.395197Z","steps":["trace[827627293] 'read index received' (duration: 214.027495ms)","trace[827627293] 'applied index is now lower than readState.Index' (duration: 79.422µs)"],"step_count":2} {"level":"info","ts":"2026-06-19T18:21:04.395294Z","caller":"traceutil/trace.go:171","msg":"trace[192057392] transaction","detail":"{read_only:false; response_revision:8681; number_of_response:1; }","duration":"228.294502ms","start":"2026-06-19T18:21:04.16697Z","end":"2026-06-19T18:21:04.395264Z","steps":["trace[192057392] 'process raft request' (duration: 228.077296ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:21:04.395431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.322772ms","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-19T18:21:04.395496Z","caller":"traceutil/trace.go:171","msg":"trace[1229857048] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8681; }","duration":"214.431845ms","start":"2026-06-19T18:21:04.181052Z","end":"2026-06-19T18:21:04.395484Z","steps":["trace[1229857048] 'agreement among raft nodes before linearized reading' (duration: 214.23956ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:21:04.606529Z","caller":"traceutil/trace.go:171","msg":"trace[506540319] transaction","detail":"{read_only:false; response_revision:8682; number_of_response:1; }","duration":"421.626482ms","start":"2026-06-19T18:21:04.184883Z","end":"2026-06-19T18:21:04.60651Z","steps":["trace[506540319] 'process raft request' (duration: 346.759326ms)","trace[506540319] 'compare' (duration: 74.708622ms)"],"step_count":2} {"level":"warn","ts":"2026-06-19T18:21:04.606664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T18:21:04.184871Z","time spent":"421.725005ms","remote":"127.0.0.1:39528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-19T18:21:04.60676Z","caller":"traceutil/trace.go:171","msg":"trace[537870179] transaction","detail":"{read_only:false; response_revision:8683; number_of_response:1; }","duration":"301.370964ms","start":"2026-06-19T18:21:04.305383Z","end":"2026-06-19T18:21:04.606754Z","steps":["trace[537870179] 'process raft request' (duration: 301.096857ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:21:04.606787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T18:21:04.305363Z","time spent":"301.410804ms","remote":"127.0.0.1:39528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-19T18:21:04.606783Z","caller":"traceutil/trace.go:171","msg":"trace[1371349421] linearizableReadLoop","detail":"{readStateIndex:8995; appliedIndex:8993; }","duration":"211.442391ms","start":"2026-06-19T18:21:04.39529Z","end":"2026-06-19T18:21:04.606733Z","steps":["trace[1371349421] 'read index received' (duration: 136.36419ms)","trace[1371349421] 'applied index is now lower than readState.Index' (duration: 75.074351ms)"],"step_count":2} {"level":"warn","ts":"2026-06-19T18:21:04.606885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.077806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T18:21:04.606971Z","caller":"traceutil/trace.go:171","msg":"trace[744479492] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8683; }","duration":"301.19743ms","start":"2026-06-19T18:21:04.305752Z","end":"2026-06-19T18:21:04.606949Z","steps":["trace[744479492] 'agreement among raft nodes before linearized reading' (duration: 301.032876ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:21:04.607029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T18:21:04.305741Z","time spent":"301.280922ms","remote":"127.0.0.1:39244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-19T18:21:04.607208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.364167ms","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-19T18:21:04.607297Z","caller":"traceutil/trace.go:171","msg":"trace[732517758] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8683; }","duration":"353.50556ms","start":"2026-06-19T18:21:04.253772Z","end":"2026-06-19T18:21:04.607278Z","steps":["trace[732517758] 'agreement among raft nodes before linearized reading' (duration: 353.132401ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:21:04.607343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T18:21:04.253751Z","time spent":"353.583282ms","remote":"127.0.0.1:39528","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":"warn","ts":"2026-06-19T18:21:04.608996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.450061ms","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-19T18:21:04.609078Z","caller":"traceutil/trace.go:171","msg":"trace[558164119] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8684; }","duration":"157.560514ms","start":"2026-06-19T18:21:04.451497Z","end":"2026-06-19T18:21:04.609057Z","steps":["trace[558164119] 'agreement among raft nodes before linearized reading' (duration: 157.331289ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:21:04.609613Z","caller":"traceutil/trace.go:171","msg":"trace[353912641] transaction","detail":"{read_only:false; response_revision:8684; number_of_response:1; }","duration":"209.681718ms","start":"2026-06-19T18:21:04.3999Z","end":"2026-06-19T18:21:04.609581Z","steps":["trace[353912641] 'process raft request' (duration: 208.828237ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:21:07.969293Z","caller":"traceutil/trace.go:171","msg":"trace[1708550954] linearizableReadLoop","detail":"{readStateIndex:9014; appliedIndex:9013; }","duration":"119.857049ms","start":"2026-06-19T18:21:07.849411Z","end":"2026-06-19T18:21:07.969268Z","steps":["trace[1708550954] 'read index received' (duration: 119.739356ms)","trace[1708550954] 'applied index is now lower than readState.Index' (duration: 116.663µs)"],"step_count":2} {"level":"info","ts":"2026-06-19T18:21:07.969457Z","caller":"traceutil/trace.go:171","msg":"trace[1679602560] transaction","detail":"{read_only:false; response_revision:8700; number_of_response:1; }","duration":"189.420307ms","start":"2026-06-19T18:21:07.779906Z","end":"2026-06-19T18:21:07.969327Z","steps":["trace[1679602560] 'process raft request' (duration: 189.188952ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:21:07.969595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.166167ms","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-06-19T18:21:07.969686Z","caller":"traceutil/trace.go:171","msg":"trace[1152522185] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8700; }","duration":"120.300909ms","start":"2026-06-19T18:21:07.849364Z","end":"2026-06-19T18:21:07.969665Z","steps":["trace[1152522185] 'agreement among raft nodes before linearized reading' (duration: 120.029773ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:21:08.161709Z","caller":"traceutil/trace.go:171","msg":"trace[201215780] transaction","detail":"{read_only:false; response_revision:8703; number_of_response:1; }","duration":"145.891261ms","start":"2026-06-19T18:21:08.015788Z","end":"2026-06-19T18:21:08.161679Z","steps":["trace[201215780] 'process raft request' (duration: 54.484613ms)","trace[201215780] 'compare' (duration: 91.136351ms)"],"step_count":2} {"level":"warn","ts":"2026-06-19T18:21:16.999489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.389928ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-19T18:21:16.999629Z","caller":"traceutil/trace.go:171","msg":"trace[737676114] transaction","detail":"{read_only:false; response_revision:8794; number_of_response:1; }","duration":"237.928454ms","start":"2026-06-19T18:21:16.761679Z","end":"2026-06-19T18:21:16.999607Z","steps":["trace[737676114] 'process raft request' (duration: 106.31749ms)","trace[737676114] 'compare' (duration: 131.221295ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T18:21:17.054269Z","caller":"traceutil/trace.go:171","msg":"trace[2084232359] linearizableReadLoop","detail":"{readStateIndex:9113; appliedIndex:9112; }","duration":"149.917559ms","start":"2026-06-19T18:21:16.904322Z","end":"2026-06-19T18:21:17.05424Z","steps":["trace[2084232359] 'read index received' (duration: 149.732295ms)","trace[2084232359] 'applied index is now lower than readState.Index' (duration: 184.044µs)"],"step_count":2} {"level":"info","ts":"2026-06-19T18:21:17.054475Z","caller":"traceutil/trace.go:171","msg":"trace[1530432352] transaction","detail":"{read_only:false; response_revision:8795; number_of_response:1; }","duration":"284.67066ms","start":"2026-06-19T18:21:16.769662Z","end":"2026-06-19T18:21:17.054332Z","steps":["trace[1530432352] 'process raft request' (duration: 284.425714ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:21:17.05514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.7912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-19T18:21:17.055205Z","caller":"traceutil/trace.go:171","msg":"trace[1797472682] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:8795; }","duration":"150.922643ms","start":"2026-06-19T18:21:16.904267Z","end":"2026-06-19T18:21:17.05519Z","steps":["trace[1797472682] 'agreement among raft nodes before linearized reading' (duration: 150.162415ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:22:16.412321Z","caller":"traceutil/trace.go:171","msg":"trace[1593673097] linearizableReadLoop","detail":"{readStateIndex:9757; appliedIndex:9756; }","duration":"108.926703ms","start":"2026-06-19T18:22:16.303375Z","end":"2026-06-19T18:22:16.412302Z","steps":["trace[1593673097] 'read index received' (duration: 81.969388ms)","trace[1593673097] 'applied index is now lower than readState.Index' (duration: 26.956325ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T18:22:16.412398Z","caller":"traceutil/trace.go:171","msg":"trace[1909381765] transaction","detail":"{read_only:false; response_revision:9421; number_of_response:1; }","duration":"122.984544ms","start":"2026-06-19T18:22:16.289371Z","end":"2026-06-19T18:22:16.412355Z","steps":["trace[1909381765] 'process raft request' (duration: 96.02686ms)","trace[1909381765] 'compare' (duration: 26.826141ms)"],"step_count":2} {"level":"warn","ts":"2026-06-19T18:22:16.412512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.094038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T18:22:16.412599Z","caller":"traceutil/trace.go:171","msg":"trace[1343508474] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9421; }","duration":"109.24267ms","start":"2026-06-19T18:22:16.303336Z","end":"2026-06-19T18:22:16.412579Z","steps":["trace[1343508474] 'agreement among raft nodes before linearized reading' (duration: 109.073516ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:22:29.132269Z","caller":"traceutil/trace.go:171","msg":"trace[1301904802] transaction","detail":"{read_only:false; response_revision:9521; number_of_response:1; }","duration":"105.94201ms","start":"2026-06-19T18:22:29.026286Z","end":"2026-06-19T18:22:29.132228Z","steps":["trace[1301904802] 'process raft request' (duration: 70.679556ms)","trace[1301904802] 'compare' (duration: 35.012068ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T18:22:45.205443Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"7c53a18098324dc9","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-19T18:22:45.211434Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-06-19T18:22:45.211508Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-06-19T18:24:52.247597Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8069} {"level":"info","ts":"2026-06-19T18:24:52.331358Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8069,"took":"79.50301ms","hash":827317867,"current-db-size-bytes":58953728,"current-db-size":"59 MB","current-db-size-in-use-bytes":28725248,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-06-19T18:24:52.331469Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":827317867,"revision":8069,"compact-revision":5985} {"level":"info","ts":"2026-06-19T18:28:03.726996Z","caller":"traceutil/trace.go:171","msg":"trace[792252255] transaction","detail":"{read_only:false; response_revision:11931; number_of_response:1; }","duration":"126.056234ms","start":"2026-06-19T18:28:03.600904Z","end":"2026-06-19T18:28:03.72696Z","steps":["trace[792252255] 'process raft request' (duration: 89.825819ms)","trace[792252255] 'compare' (duration: 36.084612ms)"],"step_count":2} {"level":"warn","ts":"2026-06-19T18:28:04.916728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.674049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-19T18:28:04.916763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.190068ms","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-19T18:28:04.916823Z","caller":"traceutil/trace.go:171","msg":"trace[635997305] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:11940; }","duration":"172.821643ms","start":"2026-06-19T18:28:04.743977Z","end":"2026-06-19T18:28:04.916798Z","steps":["trace[635997305] 'count revisions from in-memory index tree' (duration: 172.574977ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:28:04.916854Z","caller":"traceutil/trace.go:171","msg":"trace[717610752] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:11940; }","duration":"172.312651ms","start":"2026-06-19T18:28:04.744514Z","end":"2026-06-19T18:28:04.916826Z","steps":["trace[717610752] 'range keys from in-memory index tree' (duration: 172.029984ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:28:04.916737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.739495ms","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-06-19T18:28:04.91703Z","caller":"traceutil/trace.go:171","msg":"trace[2135023152] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:11940; }","duration":"162.058363ms","start":"2026-06-19T18:28:04.754945Z","end":"2026-06-19T18:28:04.917003Z","steps":["trace[2135023152] 'range keys from in-memory index tree' (duration: 161.550411ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:28:04.916891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.854867ms","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-19T18:28:04.917287Z","caller":"traceutil/trace.go:171","msg":"trace[488454303] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:11940; }","duration":"162.254699ms","start":"2026-06-19T18:28:04.75499Z","end":"2026-06-19T18:28:04.917244Z","steps":["trace[488454303] 'range keys from in-memory index tree' (duration: 161.709784ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:29:52.255448Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10480} {"level":"info","ts":"2026-06-19T18:29:52.336859Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10480,"took":"79.004377ms","hash":3415185710,"current-db-size-bytes":58953728,"current-db-size":"59 MB","current-db-size-in-use-bytes":26730496,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-06-19T18:29:52.336921Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3415185710,"revision":10480,"compact-revision":8069} {"level":"info","ts":"2026-06-19T18:34:52.259926Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12671} {"level":"info","ts":"2026-06-19T18:34:52.330318Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12671,"took":"68.654436ms","hash":1893003725,"current-db-size-bytes":58953728,"current-db-size":"59 MB","current-db-size-in-use-bytes":27508736,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-06-19T18:34:52.330394Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1893003725,"revision":12671,"compact-revision":10480} {"level":"info","ts":"2026-06-19T18:35:08.47019Z","caller":"traceutil/trace.go:171","msg":"trace[139729809] linearizableReadLoop","detail":"{readStateIndex:15451; appliedIndex:15449; }","duration":"103.021093ms","start":"2026-06-19T18:35:08.36714Z","end":"2026-06-19T18:35:08.470161Z","steps":["trace[139729809] 'read index received' (duration: 17.439541ms)","trace[139729809] 'applied index is now lower than readState.Index' (duration: 85.580602ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T18:35:08.47036Z","caller":"traceutil/trace.go:171","msg":"trace[93250232] transaction","detail":"{read_only:false; response_revision:14926; number_of_response:1; }","duration":"145.222411ms","start":"2026-06-19T18:35:08.325103Z","end":"2026-06-19T18:35:08.470326Z","steps":["trace[93250232] 'process raft request' (duration: 59.466164ms)","trace[93250232] 'compare' (duration: 85.271325ms)"],"step_count":2} {"level":"warn","ts":"2026-06-19T18:35:08.470428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.26483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-edfbc23d-0397-41a4-938a-4c8c6c1b7e48\" ","response":"range_response_count:1 size:3637"} {"level":"info","ts":"2026-06-19T18:35:08.470473Z","caller":"traceutil/trace.go:171","msg":"trace[2074007915] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-edfbc23d-0397-41a4-938a-4c8c6c1b7e48; range_end:; response_count:1; response_revision:14927; }","duration":"103.353761ms","start":"2026-06-19T18:35:08.367105Z","end":"2026-06-19T18:35:08.470459Z","steps":["trace[2074007915] 'agreement among raft nodes before linearized reading' (duration: 103.187397ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:35:08.470469Z","caller":"traceutil/trace.go:171","msg":"trace[249112790] transaction","detail":"{read_only:false; response_revision:14927; number_of_response:1; }","duration":"145.251602ms","start":"2026-06-19T18:35:08.325191Z","end":"2026-06-19T18:35:08.470443Z","steps":["trace[249112790] 'process raft request' (duration: 144.871253ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T18:36:25.149564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.987475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-19T18:36:25.149679Z","caller":"traceutil/trace.go:171","msg":"trace[1694407504] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:15684; }","duration":"201.163419ms","start":"2026-06-19T18:36:24.948496Z","end":"2026-06-19T18:36:25.14966Z","steps":["trace[1694407504] 'count revisions from in-memory index tree' (duration: 200.846502ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T18:39:52.266843Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14664} {"level":"info","ts":"2026-06-19T18:39:52.342937Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14664,"took":"73.227561ms","hash":468161482,"current-db-size-bytes":58953728,"current-db-size":"59 MB","current-db-size-in-use-bytes":34402304,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-19T18:39:52.343014Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":468161482,"revision":14664,"compact-revision":12671} {"level":"info","ts":"2026-06-19T18:44:52.273683Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17322} {"level":"info","ts":"2026-06-19T18:44:52.389754Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17322,"took":"109.91281ms","hash":4266276525,"current-db-size-bytes":58953728,"current-db-size":"59 MB","current-db-size-in-use-bytes":30519296,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-19T18:44:52.389835Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4266276525,"revision":17322,"compact-revision":14664} {"level":"info","ts":"2026-06-19T18:45:36.329364Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"7c53a18098324dc9","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-19T18:45:36.334454Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-06-19T18:45:36.334663Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-06-19T18:49:52.280108Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19098} {"level":"info","ts":"2026-06-19T18:49:52.375041Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19098,"took":"91.009284ms","hash":1327153852,"current-db-size-bytes":58953728,"current-db-size":"59 MB","current-db-size-in-use-bytes":23851008,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-06-19T18:49:52.375173Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1327153852,"revision":19098,"compact-revision":17322}