{"level":"warn","ts":"2026-08-19T20:20:26.360716Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-19T20:20:26.360797Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.200: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.19.213.200:2380","--initial-cluster=instance=https://199.19.213.200:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.200:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.200: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-08-19T20:20:26.360854Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-19T20:20:26.360867Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.200:2380"]} {"level":"info","ts":"2026-08-19T20:20:26.360882Z","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-08-19T20:20:26.361525Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.200:2379"]} {"level":"info","ts":"2026-08-19T20:20:26.361619Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.200:2380"],"listen-peer-urls":["https://199.19.213.200:2380"],"advertise-client-urls":["https://199.19.213.200:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.200:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.200: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-08-19T20:20:26.366403Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.565906ms"} {"level":"info","ts":"2026-08-19T20:20:26.373994Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3363378c2227fa42","cluster-id":"7faf957b2e4cf761"} {"level":"info","ts":"2026-08-19T20:20:26.374077Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 switched to configuration voters=()"} {"level":"info","ts":"2026-08-19T20:20:26.374102Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 became follower at term 0"} {"level":"info","ts":"2026-08-19T20:20:26.374112Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3363378c2227fa42 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-19T20:20:26.374123Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 became follower at term 1"} {"level":"info","ts":"2026-08-19T20:20:26.374164Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 switched to configuration voters=(3702864393636674114)"} {"level":"warn","ts":"2026-08-19T20:20:26.379917Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-19T20:20:26.381975Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-19T20:20:26.383239Z","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-08-19T20:20:26.387992Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3363378c2227fa42","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-19T20:20:26.388383Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3363378c2227fa42","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-19T20:20:26.388525Z","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-08-19T20:20:26.388593Z","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-08-19T20:20:26.388605Z","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-08-19T20:20:26.389326Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 switched to configuration voters=(3702864393636674114)"} {"level":"info","ts":"2026-08-19T20:20:26.390241Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"7faf957b2e4cf761","local-member-id":"3363378c2227fa42","added-peer-id":"3363378c2227fa42","added-peer-peer-urls":["https://199.19.213.200:2380"]} {"level":"info","ts":"2026-08-19T20:20:26.394714Z","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-08-19T20:20:26.394815Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.200:2380"} {"level":"info","ts":"2026-08-19T20:20:26.394851Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.200:2380"} {"level":"info","ts":"2026-08-19T20:20:26.395168Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3363378c2227fa42","initial-advertise-peer-urls":["https://199.19.213.200:2380"],"listen-peer-urls":["https://199.19.213.200:2380"],"advertise-client-urls":["https://199.19.213.200:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.200:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-19T20:20:26.39522Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-19T20:20:26.675349Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 is starting a new election at term 1"} {"level":"info","ts":"2026-08-19T20:20:26.675404Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-19T20:20:26.675428Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 received MsgPreVoteResp from 3363378c2227fa42 at term 1"} {"level":"info","ts":"2026-08-19T20:20:26.675441Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 became candidate at term 2"} {"level":"info","ts":"2026-08-19T20:20:26.675448Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 received MsgVoteResp from 3363378c2227fa42 at term 2"} {"level":"info","ts":"2026-08-19T20:20:26.675479Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 became leader at term 2"} {"level":"info","ts":"2026-08-19T20:20:26.675487Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3363378c2227fa42 elected leader 3363378c2227fa42 at term 2"} {"level":"info","ts":"2026-08-19T20:20:26.677677Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3363378c2227fa42","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.200:2379]}","request-path":"/0/members/3363378c2227fa42/attributes","cluster-id":"7faf957b2e4cf761","publish-timeout":"7s"} {"level":"info","ts":"2026-08-19T20:20:26.677838Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-19T20:20:26.677861Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-19T20:20:26.678309Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-19T20:20:26.678955Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"7faf957b2e4cf761","local-member-id":"3363378c2227fa42","cluster-version":"3.5"} {"level":"info","ts":"2026-08-19T20:20:26.679119Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-19T20:20:26.679096Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-19T20:20:26.679203Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-19T20:20:26.679189Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-19T20:20:26.680473Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-19T20:20:26.68174Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.200:2379"} {"level":"info","ts":"2026-08-19T20:20:50.956218Z","caller":"traceutil/trace.go:171","msg":"trace[1527205103] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"147.033335ms","start":"2026-08-19T20:20:50.809166Z","end":"2026-08-19T20:20:50.9562Z","steps":["trace[1527205103] 'process raft request' (duration: 146.939005ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:21:02.523074Z","caller":"traceutil/trace.go:171","msg":"trace[979196691] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"106.687528ms","start":"2026-08-19T20:21:02.416353Z","end":"2026-08-19T20:21:02.52304Z","steps":["trace[979196691] 'process raft request' (duration: 99.045757ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:21:08.889675Z","caller":"traceutil/trace.go:171","msg":"trace[2127618140] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:655; }","duration":"281.602511ms","start":"2026-08-19T20:21:08.608048Z","end":"2026-08-19T20:21:08.889651Z","steps":["trace[2127618140] 'read index received' (duration: 267.987159ms)","trace[2127618140] 'applied index is now lower than readState.Index' (duration: 13.614462ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:21:08.88971Z","caller":"traceutil/trace.go:171","msg":"trace[483745822] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"294.361799ms","start":"2026-08-19T20:21:08.595309Z","end":"2026-08-19T20:21:08.889671Z","steps":["trace[483745822] 'process raft request' (duration: 280.646767ms)","trace[483745822] 'compare' (duration: 13.592511ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:21:08.889902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.801653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-jbrn4\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-08-19T20:21:08.889957Z","caller":"traceutil/trace.go:171","msg":"trace[1065357555] range","detail":"{range_begin:/registry/pods/kube-system/cilium-jbrn4; range_end:; response_count:1; response_revision:633; }","duration":"281.924573ms","start":"2026-08-19T20:21:08.608019Z","end":"2026-08-19T20:21:08.889943Z","steps":["trace[1065357555] 'agreement among raft nodes before linearized reading' (duration: 281.730441ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:21:08.916202Z","caller":"traceutil/trace.go:171","msg":"trace[636349070] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"135.83526ms","start":"2026-08-19T20:21:08.780348Z","end":"2026-08-19T20:21:08.916183Z","steps":["trace[636349070] 'process raft request' (duration: 135.442478ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:21:09.40703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.630252ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-19T20:21:09.407225Z","caller":"traceutil/trace.go:171","msg":"trace[503751494] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"435.199522ms","start":"2026-08-19T20:21:08.971978Z","end":"2026-08-19T20:21:09.407178Z","steps":["trace[503751494] 'process raft request' (duration: 267.561835ms)","trace[503751494] 'compare' (duration: 166.15166ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:21:09.407647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:21:08.97195Z","time spent":"435.466724ms","remote":"127.0.0.1:37830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-19T20:21:09.407676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.000909ms","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-08-19T20:21:09.407747Z","caller":"traceutil/trace.go:171","msg":"trace[2070832755] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:636; }","duration":"374.114159ms","start":"2026-08-19T20:21:09.033614Z","end":"2026-08-19T20:21:09.407728Z","steps":["trace[2070832755] 'agreement among raft nodes before linearized reading' (duration: 373.847168ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:21:09.407746Z","caller":"traceutil/trace.go:171","msg":"trace[1943569965] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:658; }","duration":"373.498326ms","start":"2026-08-19T20:21:09.033651Z","end":"2026-08-19T20:21:09.407149Z","steps":["trace[1943569965] 'read index received' (duration: 205.882209ms)","trace[1943569965] 'applied index is now lower than readState.Index' (duration: 167.614537ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:21:09.40779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:21:09.033596Z","time spent":"374.18656ms","remote":"127.0.0.1:37850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-19T20:21:24.015171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.996946ms","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-08-19T20:21:24.01535Z","caller":"traceutil/trace.go:171","msg":"trace[366096632] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:796; }","duration":"195.230604ms","start":"2026-08-19T20:21:23.820096Z","end":"2026-08-19T20:21:24.015327Z","steps":["trace[366096632] 'range keys from in-memory index tree' (duration: 194.824543ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:21:55.828676Z","caller":"traceutil/trace.go:171","msg":"trace[1692028381] transaction","detail":"{read_only:false; response_revision:1206; number_of_response:1; }","duration":"216.724524ms","start":"2026-08-19T20:21:55.611924Z","end":"2026-08-19T20:21:55.828649Z","steps":["trace[1692028381] 'process raft request' (duration: 216.563426ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:22:40.797989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.837893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ad698bf4-0636-4e48-a3de-b3f5c14fce9c\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-08-19T20:22:40.798097Z","caller":"traceutil/trace.go:171","msg":"trace[1878847956] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ad698bf4-0636-4e48-a3de-b3f5c14fce9c; range_end:; response_count:1; response_revision:1862; }","duration":"172.993878ms","start":"2026-08-19T20:22:40.625076Z","end":"2026-08-19T20:22:40.79807Z","steps":["trace[1878847956] 'range keys from in-memory index tree' (duration: 172.597646ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:22:40.798259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.901952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T20:22:40.798368Z","caller":"traceutil/trace.go:171","msg":"trace[1007612966] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1862; }","duration":"210.072368ms","start":"2026-08-19T20:22:40.588266Z","end":"2026-08-19T20:22:40.798338Z","steps":["trace[1007612966] 'range keys from in-memory index tree' (duration: 209.788538ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:22:40.798526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.077824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-08-19T20:22:40.798673Z","caller":"traceutil/trace.go:171","msg":"trace[970678065] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1862; }","duration":"233.226088ms","start":"2026-08-19T20:22:40.565371Z","end":"2026-08-19T20:22:40.798597Z","steps":["trace[970678065] 'range keys from in-memory index tree' (duration: 232.622749ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:22:41.229405Z","caller":"traceutil/trace.go:171","msg":"trace[492683624] linearizableReadLoop","detail":"{readStateIndex:1938; appliedIndex:1937; }","duration":"169.468993ms","start":"2026-08-19T20:22:41.059904Z","end":"2026-08-19T20:22:41.229373Z","steps":["trace[492683624] 'read index received' (duration: 162.176145ms)","trace[492683624] 'applied index is now lower than readState.Index' (duration: 7.291738ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:22:41.229554Z","caller":"traceutil/trace.go:171","msg":"trace[478285160] transaction","detail":"{read_only:false; response_revision:1864; number_of_response:1; }","duration":"181.431322ms","start":"2026-08-19T20:22:41.048074Z","end":"2026-08-19T20:22:41.229505Z","steps":["trace[478285160] 'process raft request' (duration: 173.956468ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:22:41.229763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.836945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ad698bf4-0636-4e48-a3de-b3f5c14fce9c\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-08-19T20:22:41.229787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.005578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-19T20:22:41.229829Z","caller":"traceutil/trace.go:171","msg":"trace[1535884872] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ad698bf4-0636-4e48-a3de-b3f5c14fce9c; range_end:; response_count:1; response_revision:1864; }","duration":"169.952848ms","start":"2026-08-19T20:22:41.059864Z","end":"2026-08-19T20:22:41.229816Z","steps":["trace[1535884872] 'agreement among raft nodes before linearized reading' (duration: 169.742362ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:22:41.229874Z","caller":"traceutil/trace.go:171","msg":"trace[663620480] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1864; }","duration":"111.146293ms","start":"2026-08-19T20:22:41.118712Z","end":"2026-08-19T20:22:41.229858Z","steps":["trace[663620480] 'agreement among raft nodes before linearized reading' (duration: 111.008428ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:22:41.229874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.286257ms","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-08-19T20:22:41.229966Z","caller":"traceutil/trace.go:171","msg":"trace[1904574176] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1864; }","duration":"127.445403ms","start":"2026-08-19T20:22:41.102502Z","end":"2026-08-19T20:22:41.229948Z","steps":["trace[1904574176] 'agreement among raft nodes before linearized reading' (duration: 127.190254ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:23:21.55916Z","caller":"traceutil/trace.go:171","msg":"trace[55684388] linearizableReadLoop","detail":"{readStateIndex:2221; appliedIndex:2220; }","duration":"134.560046ms","start":"2026-08-19T20:23:21.424567Z","end":"2026-08-19T20:23:21.559127Z","steps":["trace[55684388] 'read index received' (duration: 134.353711ms)","trace[55684388] 'applied index is now lower than readState.Index' (duration: 204.675µs)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:23:21.559308Z","caller":"traceutil/trace.go:171","msg":"trace[1802833627] transaction","detail":"{read_only:false; response_revision:2138; number_of_response:1; }","duration":"136.039192ms","start":"2026-08-19T20:23:21.423226Z","end":"2026-08-19T20:23:21.559265Z","steps":["trace[1802833627] 'process raft request' (duration: 135.655702ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:23:21.559562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.965536ms","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-08-19T20:23:21.559649Z","caller":"traceutil/trace.go:171","msg":"trace[1125758709] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2138; }","duration":"135.090719ms","start":"2026-08-19T20:23:21.424541Z","end":"2026-08-19T20:23:21.559632Z","steps":["trace[1125758709] 'agreement among raft nodes before linearized reading' (duration: 134.71502ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:23:21.57489Z","caller":"traceutil/trace.go:171","msg":"trace[2077375638] transaction","detail":"{read_only:false; response_revision:2139; number_of_response:1; }","duration":"142.445135ms","start":"2026-08-19T20:23:21.432416Z","end":"2026-08-19T20:23:21.574861Z","steps":["trace[2077375638] 'process raft request' (duration: 142.200559ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:24:19.992259Z","caller":"traceutil/trace.go:171","msg":"trace[1776611734] transaction","detail":"{read_only:false; response_revision:2553; number_of_response:1; }","duration":"137.082905ms","start":"2026-08-19T20:24:19.855143Z","end":"2026-08-19T20:24:19.992226Z","steps":["trace[1776611734] 'process raft request' (duration: 136.901161ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:24:20.010726Z","caller":"traceutil/trace.go:171","msg":"trace[475049655] transaction","detail":"{read_only:false; response_revision:2554; number_of_response:1; }","duration":"151.573085ms","start":"2026-08-19T20:24:19.85913Z","end":"2026-08-19T20:24:20.010703Z","steps":["trace[475049655] 'process raft request' (duration: 151.465503ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:24:52.400646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.926508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-19T20:24:52.400724Z","caller":"traceutil/trace.go:171","msg":"trace[424176053] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2730; }","duration":"116.052461ms","start":"2026-08-19T20:24:52.284654Z","end":"2026-08-19T20:24:52.400706Z","steps":["trace[424176053] 'range keys from in-memory index tree' (duration: 115.758996ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:24:52.535947Z","caller":"traceutil/trace.go:171","msg":"trace[700042699] linearizableReadLoop","detail":"{readStateIndex:2835; appliedIndex:2835; }","duration":"128.416183ms","start":"2026-08-19T20:24:52.407499Z","end":"2026-08-19T20:24:52.535916Z","steps":["trace[700042699] 'read index received' (duration: 128.400642ms)","trace[700042699] 'applied index is now lower than readState.Index' (duration: 13.011µs)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:24:52.536129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.615006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:9281"} {"level":"info","ts":"2026-08-19T20:24:52.535934Z","caller":"traceutil/trace.go:171","msg":"trace[778385160] transaction","detail":"{read_only:false; response_revision:2731; number_of_response:1; }","duration":"129.712213ms","start":"2026-08-19T20:24:52.406192Z","end":"2026-08-19T20:24:52.535904Z","steps":["trace[778385160] 'process raft request' (duration: 129.461579ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:24:52.536197Z","caller":"traceutil/trace.go:171","msg":"trace[427671195] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:2731; }","duration":"128.726747ms","start":"2026-08-19T20:24:52.407455Z","end":"2026-08-19T20:24:52.536182Z","steps":["trace[427671195] 'agreement among raft nodes before linearized reading' (duration: 128.566574ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:24:52.550348Z","caller":"traceutil/trace.go:171","msg":"trace[812119609] transaction","detail":"{read_only:false; response_revision:2732; number_of_response:1; }","duration":"142.658495ms","start":"2026-08-19T20:24:52.407671Z","end":"2026-08-19T20:24:52.55033Z","steps":["trace[812119609] 'process raft request' (duration: 142.554854ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:28:43.036909Z","caller":"traceutil/trace.go:171","msg":"trace[1613684777] linearizableReadLoop","detail":"{readStateIndex:4888; appliedIndex:4887; }","duration":"244.796017ms","start":"2026-08-19T20:28:42.792091Z","end":"2026-08-19T20:28:43.036887Z","steps":["trace[1613684777] 'read index received' (duration: 172.496497ms)","trace[1613684777] 'applied index is now lower than readState.Index' (duration: 72.2986ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:28:43.037013Z","caller":"traceutil/trace.go:171","msg":"trace[1765776266] transaction","detail":"{read_only:false; response_revision:4706; number_of_response:1; }","duration":"282.442882ms","start":"2026-08-19T20:28:42.754561Z","end":"2026-08-19T20:28:43.037004Z","steps":["trace[1765776266] 'process raft request' (duration: 210.091731ms)","trace[1765776266] 'compare' (duration: 72.111555ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:28:43.03722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.111416ms","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-08-19T20:28:43.037299Z","caller":"traceutil/trace.go:171","msg":"trace[537583070] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4706; }","duration":"245.242129ms","start":"2026-08-19T20:28:42.792036Z","end":"2026-08-19T20:28:43.037278Z","steps":["trace[537583070] 'agreement among raft nodes before linearized reading' (duration: 244.981823ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:28:43.056922Z","caller":"traceutil/trace.go:171","msg":"trace[1875421048] transaction","detail":"{read_only:false; response_revision:4707; number_of_response:1; }","duration":"105.047787ms","start":"2026-08-19T20:28:42.95185Z","end":"2026-08-19T20:28:43.056898Z","steps":["trace[1875421048] 'process raft request' (duration: 104.910444ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:43.057554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.909293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e5fb847f-f9d8-4d7c-a63b-e49a0de0a380\" ","response":"range_response_count:1 size:3766"} {"level":"info","ts":"2026-08-19T20:28:43.057616Z","caller":"traceutil/trace.go:171","msg":"trace[801351639] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e5fb847f-f9d8-4d7c-a63b-e49a0de0a380; range_end:; response_count:1; response_revision:4707; }","duration":"161.013476ms","start":"2026-08-19T20:28:42.896589Z","end":"2026-08-19T20:28:43.057602Z","steps":["trace[801351639] 'agreement among raft nodes before linearized reading' (duration: 160.882263ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:43.057574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.559801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T20:28:43.057762Z","caller":"traceutil/trace.go:171","msg":"trace[19550195] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:4707; }","duration":"195.770286ms","start":"2026-08-19T20:28:42.861952Z","end":"2026-08-19T20:28:43.057722Z","steps":["trace[19550195] 'agreement among raft nodes before linearized reading' (duration: 195.53439ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:28:56.542558Z","caller":"traceutil/trace.go:171","msg":"trace[900212251] transaction","detail":"{read_only:false; response_revision:4871; number_of_response:1; }","duration":"244.363133ms","start":"2026-08-19T20:28:56.298166Z","end":"2026-08-19T20:28:56.542529Z","steps":["trace[900212251] 'process raft request' (duration: 244.168728ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:30:26.708645Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2912} {"level":"info","ts":"2026-08-19T20:30:26.802945Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2912,"took":"93.054603ms","hash":480854267,"current-db-size-bytes":54829056,"current-db-size":"55 MB","current-db-size-in-use-bytes":30384128,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-19T20:30:26.803005Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":480854267,"revision":2912,"compact-revision":-1} {"level":"info","ts":"2026-08-19T20:35:26.715067Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5634} {"level":"info","ts":"2026-08-19T20:35:26.783353Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5634,"took":"66.941715ms","hash":3569416559,"current-db-size-bytes":54829056,"current-db-size":"55 MB","current-db-size-in-use-bytes":18518016,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-08-19T20:35:26.783403Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3569416559,"revision":5634,"compact-revision":2912}