{"level":"warn","ts":"2026-04-11T17:47:48.478601Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-11T17:47:48.478694Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.62: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.62:2380","--initial-cluster=instance=https://199.19.213.62:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.62:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.62: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-04-11T17:47:48.478766Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-11T17:47:48.478779Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.62:2380"]} {"level":"info","ts":"2026-04-11T17:47:48.478796Z","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-04-11T17:47:48.479501Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.62:2379"]} {"level":"info","ts":"2026-04-11T17:47:48.479617Z","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.19.213.62:2380"],"listen-peer-urls":["https://199.19.213.62:2380"],"advertise-client-urls":["https://199.19.213.62:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.62:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.62: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-04-11T17:47:48.4838Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.937309ms"} {"level":"info","ts":"2026-04-11T17:47:48.492311Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2451868e402db19e","cluster-id":"1953d1daf63e3757"} {"level":"info","ts":"2026-04-11T17:47:48.492465Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2451868e402db19e switched to configuration voters=()"} {"level":"info","ts":"2026-04-11T17:47:48.492547Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2451868e402db19e became follower at term 0"} {"level":"info","ts":"2026-04-11T17:47:48.492585Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2451868e402db19e [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-11T17:47:48.492617Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2451868e402db19e became follower at term 1"} {"level":"info","ts":"2026-04-11T17:47:48.492679Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2451868e402db19e switched to configuration voters=(2617020803999183262)"} {"level":"warn","ts":"2026-04-11T17:47:48.49934Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-11T17:47:48.500741Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-11T17:47:48.501903Z","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-04-11T17:47:48.503918Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2451868e402db19e","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-11T17:47:48.504117Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2451868e402db19e","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-11T17:47:48.504196Z","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-04-11T17:47:48.504696Z","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-04-11T17:47:48.504753Z","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-04-11T17:47:48.506039Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2451868e402db19e switched to configuration voters=(2617020803999183262)"} {"level":"info","ts":"2026-04-11T17:47:48.506351Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"1953d1daf63e3757","local-member-id":"2451868e402db19e","added-peer-id":"2451868e402db19e","added-peer-peer-urls":["https://199.19.213.62:2380"]} {"level":"info","ts":"2026-04-11T17:47:48.507732Z","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-04-11T17:47:48.507797Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.62:2380"} {"level":"info","ts":"2026-04-11T17:47:48.507874Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.62:2380"} {"level":"info","ts":"2026-04-11T17:47:48.507979Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2451868e402db19e","initial-advertise-peer-urls":["https://199.19.213.62:2380"],"listen-peer-urls":["https://199.19.213.62:2380"],"advertise-client-urls":["https://199.19.213.62:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.62:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-11T17:47:48.508026Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-11T17:47:49.493151Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2451868e402db19e is starting a new election at term 1"} {"level":"info","ts":"2026-04-11T17:47:49.493217Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2451868e402db19e became pre-candidate at term 1"} {"level":"info","ts":"2026-04-11T17:47:49.493253Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2451868e402db19e received MsgPreVoteResp from 2451868e402db19e at term 1"} {"level":"info","ts":"2026-04-11T17:47:49.493272Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2451868e402db19e became candidate at term 2"} {"level":"info","ts":"2026-04-11T17:47:49.493285Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2451868e402db19e received MsgVoteResp from 2451868e402db19e at term 2"} {"level":"info","ts":"2026-04-11T17:47:49.4933Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2451868e402db19e became leader at term 2"} {"level":"info","ts":"2026-04-11T17:47:49.493313Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2451868e402db19e elected leader 2451868e402db19e at term 2"} {"level":"info","ts":"2026-04-11T17:47:49.495726Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2451868e402db19e","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.62:2379]}","request-path":"/0/members/2451868e402db19e/attributes","cluster-id":"1953d1daf63e3757","publish-timeout":"7s"} {"level":"info","ts":"2026-04-11T17:47:49.495754Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-11T17:47:49.495745Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-11T17:47:49.495878Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-11T17:47:49.496105Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-11T17:47:49.496243Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-11T17:47:49.496834Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"1953d1daf63e3757","local-member-id":"2451868e402db19e","cluster-version":"3.5"} {"level":"info","ts":"2026-04-11T17:47:49.496956Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-11T17:47:49.497026Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-11T17:47:49.499216Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.62:2379"} {"level":"info","ts":"2026-04-11T17:47:49.499793Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-11T17:48:26.527953Z","caller":"traceutil/trace.go:171","msg":"trace[188570598] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"199.083943ms","start":"2026-04-11T17:48:26.328842Z","end":"2026-04-11T17:48:26.527926Z","steps":["trace[188570598] 'process raft request' (duration: 198.93645ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:48:27.299021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.717408ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-11T17:48:27.299299Z","caller":"traceutil/trace.go:171","msg":"trace[708520323] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"766.41496ms","start":"2026-04-11T17:48:26.532846Z","end":"2026-04-11T17:48:27.299261Z","steps":["trace[708520323] 'process raft request' (duration: 330.832121ms)","trace[708520323] 'compare' (duration: 434.520212ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:48:27.299403Z","caller":"traceutil/trace.go:171","msg":"trace[984446095] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:645; }","duration":"745.235657ms","start":"2026-04-11T17:48:26.554146Z","end":"2026-04-11T17:48:27.299381Z","steps":["trace[984446095] 'read index received' (duration: 12.191417ms)","trace[984446095] 'applied index is now lower than readState.Index' (duration: 733.04303ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:48:27.299466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:48:26.532821Z","time spent":"766.569184ms","remote":"127.0.0.1:54396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-11T17:48:27.299556Z","caller":"traceutil/trace.go:171","msg":"trace[1868074606] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"763.118587ms","start":"2026-04-11T17:48:26.536413Z","end":"2026-04-11T17:48:27.299531Z","steps":["trace[1868074606] 'process raft request' (duration: 762.85936ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:48:27.299735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"745.569425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-98ph6\" ","response":"range_response_count:1 size:16961"} {"level":"warn","ts":"2026-04-11T17:48:27.299715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.537451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:48:27.299797Z","caller":"traceutil/trace.go:171","msg":"trace[489838051] range","detail":"{range_begin:/registry/pods/kube-system/cilium-98ph6; range_end:; response_count:1; response_revision:630; }","duration":"745.672708ms","start":"2026-04-11T17:48:26.554107Z","end":"2026-04-11T17:48:27.29978Z","steps":["trace[489838051] 'agreement among raft nodes before linearized reading' (duration: 745.490693ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:48:27.299838Z","caller":"traceutil/trace.go:171","msg":"trace[999292663] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:630; }","duration":"372.88484ms","start":"2026-04-11T17:48:26.926915Z","end":"2026-04-11T17:48:27.299799Z","steps":["trace[999292663] 'agreement among raft nodes before linearized reading' (duration: 372.671035ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:48:27.299733Z","caller":"traceutil/trace.go:171","msg":"trace[1575820905] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"765.422394ms","start":"2026-04-11T17:48:26.53429Z","end":"2026-04-11T17:48:27.299712Z","steps":["trace[1575820905] 'process raft request' (duration: 764.86954ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:48:27.299877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:48:26.554095Z","time spent":"745.77268ms","remote":"127.0.0.1:54490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16983,"request content":"key:\"/registry/pods/kube-system/cilium-98ph6\" "} {"level":"warn","ts":"2026-04-11T17:48:27.299866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:48:26.536404Z","time spent":"763.193498ms","remote":"127.0.0.1:54490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-11T17:48:27.299898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:48:26.92689Z","time spent":"372.997372ms","remote":"127.0.0.1:54300","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-04-11T17:48:27.299946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:48:26.534278Z","time spent":"765.607869ms","remote":"127.0.0.1:54530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T17:48:27.487242Z","caller":"traceutil/trace.go:171","msg":"trace[1696599520] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"182.757232ms","start":"2026-04-11T17:48:27.304455Z","end":"2026-04-11T17:48:27.487212Z","steps":["trace[1696599520] 'process raft request' (duration: 172.685358ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:48:27.494823Z","caller":"traceutil/trace.go:171","msg":"trace[49645176] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:649; }","duration":"178.134706ms","start":"2026-04-11T17:48:27.316664Z","end":"2026-04-11T17:48:27.494798Z","steps":["trace[49645176] 'read index received' (duration: 160.471341ms)","trace[49645176] 'applied index is now lower than readState.Index' (duration: 17.662355ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:48:27.495264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.581297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-11T17:48:27.495279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.753151ms","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-04-11T17:48:27.495321Z","caller":"traceutil/trace.go:171","msg":"trace[848619798] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:632; }","duration":"178.68754ms","start":"2026-04-11T17:48:27.316611Z","end":"2026-04-11T17:48:27.495298Z","steps":["trace[848619798] 'agreement among raft nodes before linearized reading' (duration: 178.334261ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:48:27.495344Z","caller":"traceutil/trace.go:171","msg":"trace[174544513] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:632; }","duration":"118.868903ms","start":"2026-04-11T17:48:27.376461Z","end":"2026-04-11T17:48:27.49533Z","steps":["trace[174544513] 'agreement among raft nodes before linearized reading' (duration: 118.509694ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:48:27.495276Z","caller":"traceutil/trace.go:171","msg":"trace[1436768298] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"181.625053ms","start":"2026-04-11T17:48:27.3133Z","end":"2026-04-11T17:48:27.494925Z","steps":["trace[1436768298] 'process raft request' (duration: 181.357577ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:48:42.558252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.942699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.62\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-11T17:48:42.558343Z","caller":"traceutil/trace.go:171","msg":"trace[351201351] range","detail":"{range_begin:/registry/masterleases/199.19.213.62; range_end:; response_count:1; response_revision:787; }","duration":"108.084142ms","start":"2026-04-11T17:48:42.450239Z","end":"2026-04-11T17:48:42.558323Z","steps":["trace[351201351] 'range keys from in-memory index tree' (duration: 107.685462ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:35.097388Z","caller":"traceutil/trace.go:171","msg":"trace[581703322] transaction","detail":"{read_only:false; response_revision:1454; number_of_response:1; }","duration":"138.881542ms","start":"2026-04-11T17:49:34.958481Z","end":"2026-04-11T17:49:35.097362Z","steps":["trace[581703322] 'process raft request' (duration: 138.736537ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:35.122776Z","caller":"traceutil/trace.go:171","msg":"trace[1573842367] transaction","detail":"{read_only:false; response_revision:1455; number_of_response:1; }","duration":"126.438741ms","start":"2026-04-11T17:49:34.996318Z","end":"2026-04-11T17:49:35.122756Z","steps":["trace[1573842367] 'process raft request' (duration: 126.360459ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:58.128057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.891189ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-11T17:49:58.128196Z","caller":"traceutil/trace.go:171","msg":"trace[228148889] linearizableReadLoop","detail":"{readStateIndex:1918; appliedIndex:1917; }","duration":"319.40853ms","start":"2026-04-11T17:49:57.808765Z","end":"2026-04-11T17:49:58.128173Z","steps":["trace[228148889] 'read index received' (duration: 12.303856ms)","trace[228148889] 'applied index is now lower than readState.Index' (duration: 307.102624ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:49:58.128252Z","caller":"traceutil/trace.go:171","msg":"trace[760113494] transaction","detail":"{read_only:false; response_revision:1845; number_of_response:1; }","duration":"379.987971ms","start":"2026-04-11T17:49:57.748206Z","end":"2026-04-11T17:49:58.128194Z","steps":["trace[760113494] 'process raft request' (duration: 72.841406ms)","trace[760113494] 'compare' (duration: 306.749395ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:49:58.128432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.247753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-11T17:49:58.128459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:49:57.748174Z","time spent":"380.176166ms","remote":"127.0.0.1:54548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-11T17:49:58.128446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.685047ms","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-04-11T17:49:58.128544Z","caller":"traceutil/trace.go:171","msg":"trace[1744157963] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1845; }","duration":"319.82779ms","start":"2026-04-11T17:49:57.808705Z","end":"2026-04-11T17:49:58.128533Z","steps":["trace[1744157963] 'agreement among raft nodes before linearized reading' (duration: 319.569554ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:58.128552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.572611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-11T17:49:58.128538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.317988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18a55ebd8e760a01\" ","response":"range_response_count:1 size:982"} {"level":"info","ts":"2026-04-11T17:49:58.128583Z","caller":"traceutil/trace.go:171","msg":"trace[1128005920] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1845; }","duration":"180.645302ms","start":"2026-04-11T17:49:57.947931Z","end":"2026-04-11T17:49:58.128576Z","steps":["trace[1128005920] 'agreement among raft nodes before linearized reading' (duration: 180.56608ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:58.128603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:49:57.808681Z","time spent":"319.912343ms","remote":"127.0.0.1:54980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5466,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-04-11T17:49:58.12864Z","caller":"traceutil/trace.go:171","msg":"trace[1081596395] range","detail":"{range_begin:/registry/events/openstack/datadir-percona-xtradb-pxc-0.18a55ebd8e760a01; range_end:; response_count:1; response_revision:1845; }","duration":"132.478651ms","start":"2026-04-11T17:49:57.996141Z","end":"2026-04-11T17:49:58.12862Z","steps":["trace[1081596395] 'agreement among raft nodes before linearized reading' (duration: 132.202225ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:58.128633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.476038ms","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-04-11T17:49:58.128473Z","caller":"traceutil/trace.go:171","msg":"trace[1060239946] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1845; }","duration":"186.325854ms","start":"2026-04-11T17:49:57.942135Z","end":"2026-04-11T17:49:58.128461Z","steps":["trace[1060239946] 'agreement among raft nodes before linearized reading' (duration: 186.188191ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:58.128748Z","caller":"traceutil/trace.go:171","msg":"trace[150366767] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1845; }","duration":"180.601161ms","start":"2026-04-11T17:49:57.948122Z","end":"2026-04-11T17:49:58.128723Z","steps":["trace[150366767] 'agreement among raft nodes before linearized reading' (duration: 180.282543ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:58.30206Z","caller":"traceutil/trace.go:171","msg":"trace[861538869] transaction","detail":"{read_only:false; response_revision:1846; number_of_response:1; }","duration":"169.141366ms","start":"2026-04-11T17:49:58.132876Z","end":"2026-04-11T17:49:58.302018Z","steps":["trace[861538869] 'process raft request' (duration: 161.013454ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:58.520956Z","caller":"traceutil/trace.go:171","msg":"trace[606430352] transaction","detail":"{read_only:false; response_revision:1847; number_of_response:1; }","duration":"387.507948ms","start":"2026-04-11T17:49:58.133417Z","end":"2026-04-11T17:49:58.520924Z","steps":["trace[606430352] 'process raft request' (duration: 296.476229ms)","trace[606430352] 'compare' (duration: 90.765212ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:49:58.52106Z","caller":"traceutil/trace.go:171","msg":"trace[1283542889] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"387.448116ms","start":"2026-04-11T17:49:58.133576Z","end":"2026-04-11T17:49:58.521024Z","steps":["trace[1283542889] 'process raft request' (duration: 387.266492ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:58.521091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:49:58.133405Z","time spent":"387.61569ms","remote":"127.0.0.1:54548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-11T17:49:58.521201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:49:58.133499Z","time spent":"387.627061ms","remote":"127.0.0.1:54548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T17:49:58.521209Z","caller":"traceutil/trace.go:171","msg":"trace[901646873] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"387.521478ms","start":"2026-04-11T17:49:58.133668Z","end":"2026-04-11T17:49:58.521189Z","steps":["trace[901646873] 'process raft request' (duration: 387.238231ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:58.521294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:49:58.133629Z","time spent":"387.620611ms","remote":"127.0.0.1:54548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T17:49:58.521287Z","caller":"traceutil/trace.go:171","msg":"trace[1266272317] linearizableReadLoop","detail":"{readStateIndex:1923; appliedIndex:1919; }","duration":"176.014157ms","start":"2026-04-11T17:49:58.34519Z","end":"2026-04-11T17:49:58.521204Z","steps":["trace[1266272317] 'read index received' (duration: 84.764982ms)","trace[1266272317] 'applied index is now lower than readState.Index' (duration: 91.247705ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:49:58.521388Z","caller":"traceutil/trace.go:171","msg":"trace[2068903179] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"384.80652ms","start":"2026-04-11T17:49:58.136537Z","end":"2026-04-11T17:49:58.521344Z","steps":["trace[2068903179] 'process raft request' (duration: 384.423421ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:58.521498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.307804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-95786632-dfa5-4459-b75a-9e99e5d2e8ce\" ","response":"range_response_count:1 size:3746"} {"level":"info","ts":"2026-04-11T17:49:58.521572Z","caller":"traceutil/trace.go:171","msg":"trace[509456449] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-95786632-dfa5-4459-b75a-9e99e5d2e8ce; range_end:; response_count:1; response_revision:1850; }","duration":"176.434667ms","start":"2026-04-11T17:49:58.345118Z","end":"2026-04-11T17:49:58.521553Z","steps":["trace[509456449] 'agreement among raft nodes before linearized reading' (duration: 176.321924ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:58.521598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:49:58.136514Z","time spent":"384.975394ms","remote":"127.0.0.1:54490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3731,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-11T17:50:07.394157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.947775ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-11T17:50:07.394332Z","caller":"traceutil/trace.go:171","msg":"trace[1994131241] transaction","detail":"{read_only:false; response_revision:1938; number_of_response:1; }","duration":"135.614699ms","start":"2026-04-11T17:50:07.258694Z","end":"2026-04-11T17:50:07.394309Z","steps":["trace[1994131241] 'process raft request' (duration: 28.395117ms)","trace[1994131241] 'compare' (duration: 106.800332ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:50:13.382745Z","caller":"traceutil/trace.go:171","msg":"trace[725045269] linearizableReadLoop","detail":"{readStateIndex:2046; appliedIndex:2045; }","duration":"139.837935ms","start":"2026-04-11T17:50:13.242865Z","end":"2026-04-11T17:50:13.382703Z","steps":["trace[725045269] 'read index received' (duration: 139.726113ms)","trace[725045269] 'applied index is now lower than readState.Index' (duration: 110.602µs)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:50:13.382971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.093241ms","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-04-11T17:50:13.383017Z","caller":"traceutil/trace.go:171","msg":"trace[2069746274] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1970; }","duration":"140.201975ms","start":"2026-04-11T17:50:13.242802Z","end":"2026-04-11T17:50:13.383004Z","steps":["trace[2069746274] 'agreement among raft nodes before linearized reading' (duration: 140.02197ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:50:13.383318Z","caller":"traceutil/trace.go:171","msg":"trace[1142042039] transaction","detail":"{read_only:false; response_revision:1970; number_of_response:1; }","duration":"365.195081ms","start":"2026-04-11T17:50:13.018084Z","end":"2026-04-11T17:50:13.383279Z","steps":["trace[1142042039] 'process raft request' (duration: 364.469894ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:50:13.383544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:50:13.018055Z","time spent":"365.359126ms","remote":"127.0.0.1:54548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T17:50:13.464842Z","caller":"traceutil/trace.go:171","msg":"trace[1354619414] transaction","detail":"{read_only:false; response_revision:1971; number_of_response:1; }","duration":"210.598408ms","start":"2026-04-11T17:50:13.254219Z","end":"2026-04-11T17:50:13.464817Z","steps":["trace[1354619414] 'process raft request' (duration: 210.476165ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:50:42.622963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.654235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.62\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-11T17:50:42.62306Z","caller":"traceutil/trace.go:171","msg":"trace[1355236778] range","detail":"{range_begin:/registry/masterleases/199.19.213.62; range_end:; response_count:1; response_revision:2141; }","duration":"161.814958ms","start":"2026-04-11T17:50:42.461226Z","end":"2026-04-11T17:50:42.623041Z","steps":["trace[1355236778] 'range keys from in-memory index tree' (duration: 161.45874ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:38.078062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.385229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1988"} {"level":"info","ts":"2026-04-11T17:51:38.078159Z","caller":"traceutil/trace.go:171","msg":"trace[1131412816] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:2615; }","duration":"172.533083ms","start":"2026-04-11T17:51:37.905602Z","end":"2026-04-11T17:51:38.078136Z","steps":["trace[1131412816] 'range keys from in-memory index tree' (duration: 172.187515ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:38.078272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.224573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-11T17:51:38.078289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.782475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-11T17:51:38.078334Z","caller":"traceutil/trace.go:171","msg":"trace[905633096] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2615; }","duration":"165.333626ms","start":"2026-04-11T17:51:37.912986Z","end":"2026-04-11T17:51:38.078319Z","steps":["trace[905633096] 'range keys from in-memory index tree' (duration: 164.807433ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:51:38.078375Z","caller":"traceutil/trace.go:171","msg":"trace[313760116] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2615; }","duration":"160.912698ms","start":"2026-04-11T17:51:37.917441Z","end":"2026-04-11T17:51:38.078354Z","steps":["trace[313760116] 'range keys from in-memory index tree' (duration: 160.433667ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:52:11.491441Z","caller":"traceutil/trace.go:171","msg":"trace[1467640543] transaction","detail":"{read_only:false; response_revision:2820; number_of_response:1; }","duration":"251.164332ms","start":"2026-04-11T17:52:11.24025Z","end":"2026-04-11T17:52:11.491414Z","steps":["trace[1467640543] 'process raft request' (duration: 251.121601ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:52:11.491808Z","caller":"traceutil/trace.go:171","msg":"trace[1372744697] transaction","detail":"{read_only:false; response_revision:2818; number_of_response:1; }","duration":"251.877329ms","start":"2026-04-11T17:52:11.239886Z","end":"2026-04-11T17:52:11.491764Z","steps":["trace[1372744697] 'process raft request' (duration: 251.314335ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:52:11.491833Z","caller":"traceutil/trace.go:171","msg":"trace[430304777] transaction","detail":"{read_only:false; response_revision:2819; number_of_response:1; }","duration":"251.827978ms","start":"2026-04-11T17:52:11.239987Z","end":"2026-04-11T17:52:11.491815Z","steps":["trace[430304777] 'process raft request' (duration: 251.348037ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:56:02.646294Z","caller":"traceutil/trace.go:171","msg":"trace[28486093] linearizableReadLoop","detail":"{readStateIndex:5186; appliedIndex:5184; }","duration":"117.824725ms","start":"2026-04-11T17:56:02.528447Z","end":"2026-04-11T17:56:02.646272Z","steps":["trace[28486093] 'read index received' (duration: 103.572604ms)","trace[28486093] 'applied index is now lower than readState.Index' (duration: 14.251011ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:56:02.64661Z","caller":"traceutil/trace.go:171","msg":"trace[278733298] transaction","detail":"{read_only:false; response_revision:4997; number_of_response:1; }","duration":"132.134388ms","start":"2026-04-11T17:56:02.514452Z","end":"2026-04-11T17:56:02.646587Z","steps":["trace[278733298] 'process raft request' (duration: 131.708528ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:56:02.646668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.205784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-04-11T17:56:02.646764Z","caller":"traceutil/trace.go:171","msg":"trace[960964330] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4997; }","duration":"118.335977ms","start":"2026-04-11T17:56:02.528403Z","end":"2026-04-11T17:56:02.646739Z","steps":["trace[960964330] 'agreement among raft nodes before linearized reading' (duration: 118.03468ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:56:20.320787Z","caller":"traceutil/trace.go:171","msg":"trace[657447295] transaction","detail":"{read_only:false; response_revision:5329; number_of_response:1; }","duration":"194.830012ms","start":"2026-04-11T17:56:20.125931Z","end":"2026-04-11T17:56:20.320761Z","steps":["trace[657447295] 'process raft request' (duration: 194.676429ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:56:20.335441Z","caller":"traceutil/trace.go:171","msg":"trace[99490308] transaction","detail":"{read_only:false; response_revision:5330; number_of_response:1; }","duration":"198.094679ms","start":"2026-04-11T17:56:20.137318Z","end":"2026-04-11T17:56:20.335413Z","steps":["trace[99490308] 'process raft request' (duration: 197.787761ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:56:20.959672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.847883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:56:20.959787Z","caller":"traceutil/trace.go:171","msg":"trace[988165934] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5331; }","duration":"316.022408ms","start":"2026-04-11T17:56:20.643739Z","end":"2026-04-11T17:56:20.959761Z","steps":["trace[988165934] 'range keys from in-memory index tree' (duration: 315.746541ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:56:20.959843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:56:20.643717Z","time spent":"316.105399ms","remote":"127.0.0.1:54296","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-04-11T17:56:20.959671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.100952ms","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-04-11T17:56:20.960296Z","caller":"traceutil/trace.go:171","msg":"trace[1722885042] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5331; }","duration":"141.785779ms","start":"2026-04-11T17:56:20.818491Z","end":"2026-04-11T17:56:20.960277Z","steps":["trace[1722885042] 'range keys from in-memory index tree' (duration: 140.876317ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:56:20.960735Z","caller":"traceutil/trace.go:171","msg":"trace[2010073467] transaction","detail":"{read_only:false; response_revision:5332; number_of_response:1; }","duration":"101.641377ms","start":"2026-04-11T17:56:20.859061Z","end":"2026-04-11T17:56:20.960703Z","steps":["trace[2010073467] 'process raft request' (duration: 100.328796ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:56:20.96104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.156909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-qchc9\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-04-11T17:56:20.961103Z","caller":"traceutil/trace.go:171","msg":"trace[2101393431] range","detail":"{range_begin:/registry/pods/openstack/keepalived-qchc9; range_end:; response_count:1; response_revision:5331; }","duration":"210.285583ms","start":"2026-04-11T17:56:20.750804Z","end":"2026-04-11T17:56:20.961089Z","steps":["trace[2101393431] 'range keys from in-memory index tree' (duration: 208.723014ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:56:31.793162Z","caller":"traceutil/trace.go:171","msg":"trace[683893192] transaction","detail":"{read_only:false; response_revision:5525; number_of_response:1; }","duration":"161.842931ms","start":"2026-04-11T17:56:31.63128Z","end":"2026-04-11T17:56:31.793123Z","steps":["trace[683893192] 'process raft request' (duration: 136.238287ms)","trace[683893192] 'compare' (duration: 25.385358ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:56:31.793343Z","caller":"traceutil/trace.go:171","msg":"trace[1812199884] transaction","detail":"{read_only:false; response_revision:5526; number_of_response:1; }","duration":"137.348453ms","start":"2026-04-11T17:56:31.655964Z","end":"2026-04-11T17:56:31.793313Z","steps":["trace[1812199884] 'process raft request' (duration: 137.110827ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:57:49.523518Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3027} {"level":"info","ts":"2026-04-11T17:57:49.603935Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3027,"took":"78.85278ms","hash":630699795,"current-db-size-bytes":58884096,"current-db-size":"59 MB","current-db-size-in-use-bytes":32014336,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-11T17:57:49.604002Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":630699795,"revision":3027,"compact-revision":-1} {"level":"info","ts":"2026-04-11T18:02:21.561772Z","caller":"traceutil/trace.go:171","msg":"trace[1861219026] transaction","detail":"{read_only:false; response_revision:8180; number_of_response:1; }","duration":"179.16608ms","start":"2026-04-11T18:02:21.38258Z","end":"2026-04-11T18:02:21.561747Z","steps":["trace[1861219026] 'process raft request' (duration: 178.989455ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T18:02:22.49578Z","caller":"traceutil/trace.go:171","msg":"trace[801247444] linearizableReadLoop","detail":"{readStateIndex:8468; appliedIndex:8467; }","duration":"166.928647ms","start":"2026-04-11T18:02:22.328818Z","end":"2026-04-11T18:02:22.495747Z","steps":["trace[801247444] 'read index received' (duration: 166.655731ms)","trace[801247444] 'applied index is now lower than readState.Index' (duration: 271.756µs)"],"step_count":2} {"level":"info","ts":"2026-04-11T18:02:22.495925Z","caller":"traceutil/trace.go:171","msg":"trace[1778361732] transaction","detail":"{read_only:false; response_revision:8184; number_of_response:1; }","duration":"239.893484ms","start":"2026-04-11T18:02:22.256016Z","end":"2026-04-11T18:02:22.495909Z","steps":["trace[1778361732] 'process raft request' (duration: 239.545486ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T18:02:22.496153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.304876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-11T18:02:22.496195Z","caller":"traceutil/trace.go:171","msg":"trace[2136525667] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:8184; }","duration":"167.402978ms","start":"2026-04-11T18:02:22.328783Z","end":"2026-04-11T18:02:22.496186Z","steps":["trace[2136525667] 'agreement among raft nodes before linearized reading' (duration: 167.301696ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T18:02:22.496206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.726471ms","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-04-11T18:02:22.496315Z","caller":"traceutil/trace.go:171","msg":"trace[196047869] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8184; }","duration":"100.899106ms","start":"2026-04-11T18:02:22.395389Z","end":"2026-04-11T18:02:22.496288Z","steps":["trace[196047869] 'agreement among raft nodes before linearized reading' (duration: 100.575638ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T18:02:23.780547Z","caller":"traceutil/trace.go:171","msg":"trace[1053467314] transaction","detail":"{read_only:false; response_revision:8192; number_of_response:1; }","duration":"126.332434ms","start":"2026-04-11T18:02:23.654167Z","end":"2026-04-11T18:02:23.7805Z","steps":["trace[1053467314] 'process raft request' (duration: 91.116142ms)","trace[1053467314] 'compare' (duration: 34.788392ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T18:02:49.529893Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6161} {"level":"info","ts":"2026-04-11T18:02:49.627429Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6161,"took":"95.469476ms","hash":1845246080,"current-db-size-bytes":58884096,"current-db-size":"59 MB","current-db-size-in-use-bytes":23502848,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-04-11T18:02:49.627503Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1845246080,"revision":6161,"compact-revision":3027} {"level":"info","ts":"2026-04-11T18:02:50.820937Z","caller":"traceutil/trace.go:171","msg":"trace[553943177] linearizableReadLoop","detail":"{readStateIndex:8685; appliedIndex:8684; }","duration":"141.401316ms","start":"2026-04-11T18:02:50.679507Z","end":"2026-04-11T18:02:50.820908Z","steps":["trace[553943177] 'read index received' (duration: 141.198792ms)","trace[553943177] 'applied index is now lower than readState.Index' (duration: 201.344µs)"],"step_count":2} {"level":"info","ts":"2026-04-11T18:02:50.82129Z","caller":"traceutil/trace.go:171","msg":"trace[1204665604] transaction","detail":"{read_only:false; response_revision:8391; number_of_response:1; }","duration":"223.204444ms","start":"2026-04-11T18:02:50.598069Z","end":"2026-04-11T18:02:50.821274Z","steps":["trace[1204665604] 'process raft request' (duration: 222.682182ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T18:02:50.8216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.086002ms","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-04-11T18:02:50.821667Z","caller":"traceutil/trace.go:171","msg":"trace[105123552] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8391; }","duration":"142.180435ms","start":"2026-04-11T18:02:50.679454Z","end":"2026-04-11T18:02:50.821634Z","steps":["trace[105123552] 'agreement among raft nodes before linearized reading' (duration: 142.036881ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T18:02:51.034668Z","caller":"traceutil/trace.go:171","msg":"trace[1957237344] linearizableReadLoop","detail":"{readStateIndex:8686; appliedIndex:8685; }","duration":"210.001958ms","start":"2026-04-11T18:02:50.824639Z","end":"2026-04-11T18:02:51.034641Z","steps":["trace[1957237344] 'read index received' (duration: 209.732682ms)","trace[1957237344] 'applied index is now lower than readState.Index' (duration: 268.266µs)"],"step_count":2} {"level":"info","ts":"2026-04-11T18:02:51.034917Z","caller":"traceutil/trace.go:171","msg":"trace[481921432] transaction","detail":"{read_only:false; response_revision:8392; number_of_response:1; }","duration":"210.438779ms","start":"2026-04-11T18:02:50.824466Z","end":"2026-04-11T18:02:51.034904Z","steps":["trace[481921432] 'process raft request' (duration: 209.995778ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T18:02:51.035414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.759236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:53 size:76517"} {"level":"info","ts":"2026-04-11T18:02:51.03546Z","caller":"traceutil/trace.go:171","msg":"trace[1844666025] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:53; response_revision:8392; }","duration":"210.837647ms","start":"2026-04-11T18:02:50.824611Z","end":"2026-04-11T18:02:51.035448Z","steps":["trace[1844666025] 'agreement among raft nodes before linearized reading' (duration: 210.470249ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T18:02:51.03569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.774114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-04-11T18:02:51.035724Z","caller":"traceutil/trace.go:171","msg":"trace[534940159] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:8392; }","duration":"207.838736ms","start":"2026-04-11T18:02:50.827877Z","end":"2026-04-11T18:02:51.035716Z","steps":["trace[534940159] 'agreement among raft nodes before linearized reading' (duration: 207.750324ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T18:02:51.036034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.648483ms","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-04-11T18:02:51.036077Z","caller":"traceutil/trace.go:171","msg":"trace[1598205472] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8392; }","duration":"106.716845ms","start":"2026-04-11T18:02:50.929348Z","end":"2026-04-11T18:02:51.036064Z","steps":["trace[1598205472] 'agreement among raft nodes before linearized reading' (duration: 106.616673ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T18:02:51.036259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.544673ms","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-04-11T18:02:51.036293Z","caller":"traceutil/trace.go:171","msg":"trace[840771363] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8392; }","duration":"137.602994ms","start":"2026-04-11T18:02:50.898682Z","end":"2026-04-11T18:02:51.036285Z","steps":["trace[840771363] 'agreement among raft nodes before linearized reading' (duration: 137.524412ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T18:03:02.89275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.599066ms","expected-duration":"100ms","prefix":"","request":"header: txn: success: > failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-11T18:03:02.892869Z","caller":"traceutil/trace.go:171","msg":"trace[319020158] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8496; }","duration":"221.458202ms","start":"2026-04-11T18:03:02.671392Z","end":"2026-04-11T18:03:02.89285Z","steps":["trace[319020158] 'process raft request' (duration: 75.60451ms)","trace[319020158] 'compare' (duration: 145.485303ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T18:03:02.899741Z","caller":"traceutil/trace.go:171","msg":"trace[1619645305] linearizableReadLoop","detail":"{readStateIndex:8797; appliedIndex:8796; }","duration":"134.993073ms","start":"2026-04-11T18:03:02.764731Z","end":"2026-04-11T18:03:02.899724Z","steps":["trace[1619645305] 'read index received' (duration: 134.922102ms)","trace[1619645305] 'applied index is now lower than readState.Index' (duration: 70.211µs)"],"step_count":2} {"level":"info","ts":"2026-04-11T18:03:02.899829Z","caller":"traceutil/trace.go:171","msg":"trace[1422236235] transaction","detail":"{read_only:false; response_revision:8497; number_of_response:1; }","duration":"226.941854ms","start":"2026-04-11T18:03:02.672854Z","end":"2026-04-11T18:03:02.899795Z","steps":["trace[1422236235] 'process raft request' (duration: 226.78897ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T18:03:02.900584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.841113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2210982"} {"level":"info","ts":"2026-04-11T18:03:02.900648Z","caller":"traceutil/trace.go:171","msg":"trace[1209058357] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:8497; }","duration":"135.922095ms","start":"2026-04-11T18:03:02.764713Z","end":"2026-04-11T18:03:02.900635Z","steps":["trace[1209058357] 'agreement among raft nodes before linearized reading' (duration: 135.090325ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T18:03:03.481348Z","caller":"traceutil/trace.go:171","msg":"trace[67536501] transaction","detail":"{read_only:false; response_revision:8505; number_of_response:1; }","duration":"112.112664ms","start":"2026-04-11T18:03:03.369208Z","end":"2026-04-11T18:03:03.481321Z","steps":["trace[67536501] 'process raft request' (duration: 111.92352ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T18:03:04.320629Z","caller":"traceutil/trace.go:171","msg":"trace[1600174624] transaction","detail":"{read_only:false; response_revision:8511; number_of_response:1; }","duration":"163.394783ms","start":"2026-04-11T18:03:04.157202Z","end":"2026-04-11T18:03:04.320597Z","steps":["trace[1600174624] 'process raft request' (duration: 163.175227ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T18:03:52.242732Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000244a.wal"} {"level":"info","ts":"2026-04-11T18:04:05.074696Z","caller":"traceutil/trace.go:171","msg":"trace[1151172481] transaction","detail":"{read_only:false; response_revision:9150; number_of_response:1; }","duration":"106.461399ms","start":"2026-04-11T18:04:04.968184Z","end":"2026-04-11T18:04:05.074645Z","steps":["trace[1151172481] 'process raft request' (duration: 106.004868ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T18:04:17.564882Z","caller":"traceutil/trace.go:171","msg":"trace[1659924742] transaction","detail":"{read_only:false; response_revision:9241; number_of_response:1; }","duration":"148.977168ms","start":"2026-04-11T18:04:17.415869Z","end":"2026-04-11T18:04:17.564846Z","steps":["trace[1659924742] 'process raft request' (duration: 148.642349ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T18:05:16.452866Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2451868e402db19e","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-11T18:05:16.458223Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-11T18:05:16.458328Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-11T18:07:49.538741Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8387} {"level":"info","ts":"2026-04-11T18:07:49.622542Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8387,"took":"81.649392ms","hash":1275679688,"current-db-size-bytes":58884096,"current-db-size":"59 MB","current-db-size-in-use-bytes":24604672,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-11T18:07:49.622616Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1275679688,"revision":8387,"compact-revision":6161} {"level":"info","ts":"2026-04-11T18:12:49.545387Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10734} {"level":"info","ts":"2026-04-11T18:12:49.618938Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10734,"took":"71.561585ms","hash":3737811854,"current-db-size-bytes":58884096,"current-db-size":"59 MB","current-db-size-in-use-bytes":20529152,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-04-11T18:12:49.618995Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3737811854,"revision":10734,"compact-revision":8387}