{"level":"warn","ts":"2026-04-20T05:52:50.60871Z","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-20T05:52:50.608787Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.10: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.10:2380","--initial-cluster=instance=https://199.19.213.10:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.10:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.10: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-20T05:52:50.60885Z","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-20T05:52:50.608858Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.10:2380"]} {"level":"info","ts":"2026-04-20T05:52:50.608874Z","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-20T05:52:50.609499Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.10:2379"]} {"level":"info","ts":"2026-04-20T05:52:50.609574Z","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.10:2380"],"listen-peer-urls":["https://199.19.213.10:2380"],"advertise-client-urls":["https://199.19.213.10:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.10:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.10: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-20T05:52:50.613236Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.423305ms"} {"level":"info","ts":"2026-04-20T05:52:50.622506Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"93eaf76b69bfff2c","cluster-id":"800615b58ad8280"} {"level":"info","ts":"2026-04-20T05:52:50.622585Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"93eaf76b69bfff2c switched to configuration voters=()"} {"level":"info","ts":"2026-04-20T05:52:50.622607Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"93eaf76b69bfff2c became follower at term 0"} {"level":"info","ts":"2026-04-20T05:52:50.622619Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 93eaf76b69bfff2c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-20T05:52:50.622631Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"93eaf76b69bfff2c became follower at term 1"} {"level":"info","ts":"2026-04-20T05:52:50.62267Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"93eaf76b69bfff2c switched to configuration voters=(10658603508833451820)"} {"level":"warn","ts":"2026-04-20T05:52:50.628496Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-20T05:52:50.631518Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-20T05:52:50.633028Z","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-20T05:52:50.635105Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"93eaf76b69bfff2c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-20T05:52:50.635329Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"93eaf76b69bfff2c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-20T05:52:50.635461Z","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-20T05:52:50.635596Z","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-20T05:52:50.635669Z","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-20T05:52:50.637294Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"93eaf76b69bfff2c switched to configuration voters=(10658603508833451820)"} {"level":"info","ts":"2026-04-20T05:52:50.637692Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"800615b58ad8280","local-member-id":"93eaf76b69bfff2c","added-peer-id":"93eaf76b69bfff2c","added-peer-peer-urls":["https://199.19.213.10:2380"]} {"level":"info","ts":"2026-04-20T05:52:50.639169Z","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-20T05:52:50.639404Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"93eaf76b69bfff2c","initial-advertise-peer-urls":["https://199.19.213.10:2380"],"listen-peer-urls":["https://199.19.213.10:2380"],"advertise-client-urls":["https://199.19.213.10:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.10:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-20T05:52:50.639466Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-20T05:52:50.63959Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.10:2380"} {"level":"info","ts":"2026-04-20T05:52:50.639627Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.10:2380"} {"level":"info","ts":"2026-04-20T05:52:51.523534Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"93eaf76b69bfff2c is starting a new election at term 1"} {"level":"info","ts":"2026-04-20T05:52:51.523613Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"93eaf76b69bfff2c became pre-candidate at term 1"} {"level":"info","ts":"2026-04-20T05:52:51.523652Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"93eaf76b69bfff2c received MsgPreVoteResp from 93eaf76b69bfff2c at term 1"} {"level":"info","ts":"2026-04-20T05:52:51.523673Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"93eaf76b69bfff2c became candidate at term 2"} {"level":"info","ts":"2026-04-20T05:52:51.523702Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"93eaf76b69bfff2c received MsgVoteResp from 93eaf76b69bfff2c at term 2"} {"level":"info","ts":"2026-04-20T05:52:51.523733Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"93eaf76b69bfff2c became leader at term 2"} {"level":"info","ts":"2026-04-20T05:52:51.523752Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 93eaf76b69bfff2c elected leader 93eaf76b69bfff2c at term 2"} {"level":"info","ts":"2026-04-20T05:52:51.524896Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T05:52:51.525449Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T05:52:51.525452Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"93eaf76b69bfff2c","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.10:2379]}","request-path":"/0/members/93eaf76b69bfff2c/attributes","cluster-id":"800615b58ad8280","publish-timeout":"7s"} {"level":"info","ts":"2026-04-20T05:52:51.525479Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T05:52:51.526072Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-20T05:52:51.526098Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-20T05:52:51.52615Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"800615b58ad8280","local-member-id":"93eaf76b69bfff2c","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T05:52:51.526288Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T05:52:51.526339Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T05:52:51.528671Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.10:2379"} {"level":"info","ts":"2026-04-20T05:52:51.529087Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-20T05:53:09.332545Z","caller":"traceutil/trace.go:171","msg":"trace[1112593078] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"109.626751ms","start":"2026-04-20T05:53:09.222894Z","end":"2026-04-20T05:53:09.332521Z","steps":["trace[1112593078] 'process raft request' (duration: 109.502058ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:53:14.644455Z","caller":"traceutil/trace.go:171","msg":"trace[1978187646] linearizableReadLoop","detail":"{readStateIndex:435; appliedIndex:434; }","duration":"116.941325ms","start":"2026-04-20T05:53:14.52749Z","end":"2026-04-20T05:53:14.644431Z","steps":["trace[1978187646] 'read index received' (duration: 116.78018ms)","trace[1978187646] 'applied index is now lower than readState.Index' (duration: 160.025µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T05:53:14.644595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.088789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-20T05:53:14.644727Z","caller":"traceutil/trace.go:171","msg":"trace[1834904663] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:420; }","duration":"117.228553ms","start":"2026-04-20T05:53:14.52744Z","end":"2026-04-20T05:53:14.644669Z","steps":["trace[1834904663] 'agreement among raft nodes before linearized reading' (duration: 117.082058ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:53:14.821007Z","caller":"traceutil/trace.go:171","msg":"trace[1305246060] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"172.512121ms","start":"2026-04-20T05:53:14.648471Z","end":"2026-04-20T05:53:14.820983Z","steps":["trace[1305246060] 'process raft request' (duration: 172.436379ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:53:14.8211Z","caller":"traceutil/trace.go:171","msg":"trace[1564518893] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"175.419092ms","start":"2026-04-20T05:53:14.64562Z","end":"2026-04-20T05:53:14.821039Z","steps":["trace[1564518893] 'process raft request' (duration: 156.084394ms)","trace[1564518893] 'compare' (duration: 19.06031ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:53:22.725951Z","caller":"traceutil/trace.go:171","msg":"trace[1794128988] linearizableReadLoop","detail":"{readStateIndex:566; appliedIndex:565; }","duration":"297.548911ms","start":"2026-04-20T05:53:22.428372Z","end":"2026-04-20T05:53:22.725921Z","steps":["trace[1794128988] 'read index received' (duration: 289.824056ms)","trace[1794128988] 'applied index is now lower than readState.Index' (duration: 7.722745ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:53:22.726011Z","caller":"traceutil/trace.go:171","msg":"trace[1907837612] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"329.317014ms","start":"2026-04-20T05:53:22.39666Z","end":"2026-04-20T05:53:22.725977Z","steps":["trace[1907837612] 'process raft request' (duration: 321.640061ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:53:22.72633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.932761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoyproxies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:570105"} {"level":"info","ts":"2026-04-20T05:53:22.726376Z","caller":"traceutil/trace.go:171","msg":"trace[1838511911] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/envoyproxies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:548; }","duration":"298.019544ms","start":"2026-04-20T05:53:22.428343Z","end":"2026-04-20T05:53:22.726363Z","steps":["trace[1838511911] 'agreement among raft nodes before linearized reading' (duration: 297.693215ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:53:22.726637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:53:22.396643Z","time spent":"329.441008ms","remote":"127.0.0.1:52192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":208470,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T05:53:26.889924Z","caller":"traceutil/trace.go:171","msg":"trace[1980810535] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"130.386628ms","start":"2026-04-20T05:53:26.759497Z","end":"2026-04-20T05:53:26.889884Z","steps":["trace[1980810535] 'process raft request' (duration: 76.19354ms)","trace[1980810535] 'compare' (duration: 54.009723ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:53:27.397222Z","caller":"traceutil/trace.go:171","msg":"trace[312169273] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"251.52445ms","start":"2026-04-20T05:53:27.145668Z","end":"2026-04-20T05:53:27.397193Z","steps":["trace[312169273] 'process raft request' (duration: 251.376995ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:53:27.570362Z","caller":"traceutil/trace.go:171","msg":"trace[409796069] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"423.081484ms","start":"2026-04-20T05:53:27.14725Z","end":"2026-04-20T05:53:27.570332Z","steps":["trace[409796069] 'process raft request' (duration: 422.693623ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:53:27.570567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:53:27.147215Z","time spent":"423.260799ms","remote":"127.0.0.1:48278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T05:53:27.570899Z","caller":"traceutil/trace.go:171","msg":"trace[507559896] linearizableReadLoop","detail":"{readStateIndex:597; appliedIndex:595; }","duration":"234.363252ms","start":"2026-04-20T05:53:27.336515Z","end":"2026-04-20T05:53:27.570878Z","steps":["trace[507559896] 'read index received' (duration: 60.546045ms)","trace[507559896] 'applied index is now lower than readState.Index' (duration: 173.816077ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:53:27.571015Z","caller":"traceutil/trace.go:171","msg":"trace[1262218267] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"231.930584ms","start":"2026-04-20T05:53:27.339048Z","end":"2026-04-20T05:53:27.570979Z","steps":["trace[1262218267] 'process raft request' (duration: 231.274056ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:53:27.571063Z","caller":"traceutil/trace.go:171","msg":"trace[194019752] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"224.867648ms","start":"2026-04-20T05:53:27.346153Z","end":"2026-04-20T05:53:27.571021Z","steps":["trace[194019752] 'process raft request' (duration: 224.660682ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:53:27.571152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.307919ms","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-20T05:53:27.571221Z","caller":"traceutil/trace.go:171","msg":"trace[1458517139] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:580; }","duration":"138.451303ms","start":"2026-04-20T05:53:27.432752Z","end":"2026-04-20T05:53:27.571204Z","steps":["trace[1458517139] 'agreement among raft nodes before linearized reading' (duration: 138.262488ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:53:27.57111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.563618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T05:53:27.571359Z","caller":"traceutil/trace.go:171","msg":"trace[1556080440] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:580; }","duration":"234.820845ms","start":"2026-04-20T05:53:27.336477Z","end":"2026-04-20T05:53:27.571298Z","steps":["trace[1556080440] 'agreement among raft nodes before linearized reading' (duration: 234.521847ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:54:53.64263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.619638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4cb10526-eb37-421b-a2ba-6fd5336736e1\" ","response":"range_response_count:1 size:3746"} {"level":"info","ts":"2026-04-20T05:54:53.642807Z","caller":"traceutil/trace.go:171","msg":"trace[422773345] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4cb10526-eb37-421b-a2ba-6fd5336736e1; range_end:; response_count:1; response_revision:1774; }","duration":"472.874194ms","start":"2026-04-20T05:54:53.16989Z","end":"2026-04-20T05:54:53.642764Z","steps":["trace[422773345] 'range keys from in-memory index tree' (duration: 472.425432ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:54:53.642941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.655183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T05:54:53.643047Z","caller":"traceutil/trace.go:171","msg":"trace[289025102] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1774; }","duration":"470.820058ms","start":"2026-04-20T05:54:53.172204Z","end":"2026-04-20T05:54:53.643024Z","steps":["trace[289025102] 'count revisions from in-memory index tree' (duration: 470.55229ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:54:53.643095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:54:53.172184Z","time spent":"470.899579ms","remote":"127.0.0.1:48498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":11,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-04-20T05:54:53.642878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:54:53.169868Z","time spent":"472.995548ms","remote":"127.0.0.1:52308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3769,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4cb10526-eb37-421b-a2ba-6fd5336736e1\" "} {"level":"warn","ts":"2026-04-20T05:54:53.643145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.262823ms","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-04-20T05:54:53.643225Z","caller":"traceutil/trace.go:171","msg":"trace[112503129] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1774; }","duration":"378.400947ms","start":"2026-04-20T05:54:53.264805Z","end":"2026-04-20T05:54:53.643206Z","steps":["trace[112503129] 'range keys from in-memory index tree' (duration: 377.974925ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:54:53.643533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:54:53.264785Z","time spent":"378.471809ms","remote":"127.0.0.1:48278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-20T05:54:53.643868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.829523ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T05:54:53.644047Z","caller":"traceutil/trace.go:171","msg":"trace[1048838152] linearizableReadLoop","detail":"{readStateIndex:1824; appliedIndex:1823; }","duration":"304.409416ms","start":"2026-04-20T05:54:53.339615Z","end":"2026-04-20T05:54:53.644024Z","steps":["trace[1048838152] 'read index received' (duration: 141.037189ms)","trace[1048838152] 'applied index is now lower than readState.Index' (duration: 163.370187ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:54:53.644082Z","caller":"traceutil/trace.go:171","msg":"trace[173772977] transaction","detail":"{read_only:false; response_revision:1775; number_of_response:1; }","duration":"321.427268ms","start":"2026-04-20T05:54:53.322605Z","end":"2026-04-20T05:54:53.644032Z","steps":["trace[173772977] 'process raft request' (duration: 158.140902ms)","trace[173772977] 'compare' (duration: 161.99424ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T05:54:53.644194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.581311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T05:54:53.644254Z","caller":"traceutil/trace.go:171","msg":"trace[1714347059] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1775; }","duration":"304.696634ms","start":"2026-04-20T05:54:53.339542Z","end":"2026-04-20T05:54:53.644239Z","steps":["trace[1714347059] 'agreement among raft nodes before linearized reading' (duration: 304.586051ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:54:53.644285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:54:53.339516Z","time spent":"304.763106ms","remote":"127.0.0.1:52154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T05:54:53.64428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:54:53.322578Z","time spent":"321.602263ms","remote":"127.0.0.1:48278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T05:54:53.644322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.139802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"warn","ts":"2026-04-20T05:54:53.644336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.362276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-20T05:54:53.64438Z","caller":"traceutil/trace.go:171","msg":"trace[1683970082] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1775; }","duration":"132.238465ms","start":"2026-04-20T05:54:53.512125Z","end":"2026-04-20T05:54:53.644364Z","steps":["trace[1683970082] 'agreement among raft nodes before linearized reading' (duration: 132.06206ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:54:53.644412Z","caller":"traceutil/trace.go:171","msg":"trace[389757403] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1775; }","duration":"203.484229ms","start":"2026-04-20T05:54:53.440909Z","end":"2026-04-20T05:54:53.644393Z","steps":["trace[389757403] 'agreement among raft nodes before linearized reading' (duration: 203.282514ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:54:53.644426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.072534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-20T05:54:53.644465Z","caller":"traceutil/trace.go:171","msg":"trace[1309546780] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1775; }","duration":"231.164046ms","start":"2026-04-20T05:54:53.413292Z","end":"2026-04-20T05:54:53.644456Z","steps":["trace[1309546780] 'agreement among raft nodes before linearized reading' (duration: 230.891709ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:55:00.273528Z","caller":"traceutil/trace.go:171","msg":"trace[1620629264] linearizableReadLoop","detail":"{readStateIndex:1909; appliedIndex:1908; }","duration":"224.891482ms","start":"2026-04-20T05:55:00.048608Z","end":"2026-04-20T05:55:00.273499Z","steps":["trace[1620629264] 'read index received' (duration: 224.634015ms)","trace[1620629264] 'applied index is now lower than readState.Index' (duration: 256.317µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:55:00.273695Z","caller":"traceutil/trace.go:171","msg":"trace[2027144] transaction","detail":"{read_only:false; response_revision:1856; number_of_response:1; }","duration":"226.744244ms","start":"2026-04-20T05:55:00.046912Z","end":"2026-04-20T05:55:00.273656Z","steps":["trace[2027144] 'process raft request' (duration: 226.396644ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:55:00.273779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.146679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-20T05:55:00.273818Z","caller":"traceutil/trace.go:171","msg":"trace[1802956603] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1856; }","duration":"225.231812ms","start":"2026-04-20T05:55:00.048573Z","end":"2026-04-20T05:55:00.273805Z","steps":["trace[1802956603] 'agreement among raft nodes before linearized reading' (duration: 225.073417ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:55:00.27391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.228162ms","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":"warn","ts":"2026-04-20T05:55:00.273944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.482938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T05:55:00.273986Z","caller":"traceutil/trace.go:171","msg":"trace[2104581915] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1856; }","duration":"142.56783ms","start":"2026-04-20T05:55:00.131406Z","end":"2026-04-20T05:55:00.273973Z","steps":["trace[2104581915] 'agreement among raft nodes before linearized reading' (duration: 142.493938ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:55:00.273995Z","caller":"traceutil/trace.go:171","msg":"trace[388212190] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1856; }","duration":"225.334174ms","start":"2026-04-20T05:55:00.048634Z","end":"2026-04-20T05:55:00.273968Z","steps":["trace[388212190] 'agreement among raft nodes before linearized reading' (duration: 225.084837ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:55:00.274066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.876048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-20T05:55:00.274107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.218834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:644"} {"level":"info","ts":"2026-04-20T05:55:00.274179Z","caller":"traceutil/trace.go:171","msg":"trace[1090905372] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1856; }","duration":"157.042802ms","start":"2026-04-20T05:55:00.117115Z","end":"2026-04-20T05:55:00.274157Z","steps":["trace[1090905372] 'agreement among raft nodes before linearized reading' (duration: 156.726064ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:55:00.274206Z","caller":"traceutil/trace.go:171","msg":"trace[1440820298] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1856; }","duration":"215.349428ms","start":"2026-04-20T05:55:00.058834Z","end":"2026-04-20T05:55:00.274184Z","steps":["trace[1440820298] 'agreement among raft nodes before linearized reading' (duration: 215.076951ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:55:36.911141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.850018ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T05:55:36.911282Z","caller":"traceutil/trace.go:171","msg":"trace[437650692] transaction","detail":"{read_only:false; response_revision:2125; number_of_response:1; }","duration":"159.746107ms","start":"2026-04-20T05:55:36.751516Z","end":"2026-04-20T05:55:36.911262Z","steps":["trace[437650692] 'compare' (duration: 155.715895ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:55:37.138718Z","caller":"traceutil/trace.go:171","msg":"trace[220667659] transaction","detail":"{read_only:false; response_revision:2126; number_of_response:1; }","duration":"218.513995ms","start":"2026-04-20T05:55:36.920165Z","end":"2026-04-20T05:55:37.138679Z","steps":["trace[220667659] 'process raft request' (duration: 133.565551ms)","trace[220667659] 'compare' (duration: 84.79066ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:59:03.609785Z","caller":"traceutil/trace.go:171","msg":"trace[226530535] linearizableReadLoop","detail":"{readStateIndex:4626; appliedIndex:4625; }","duration":"138.021152ms","start":"2026-04-20T05:59:03.471742Z","end":"2026-04-20T05:59:03.609763Z","steps":["trace[226530535] 'read index received' (duration: 137.838517ms)","trace[226530535] 'applied index is now lower than readState.Index' (duration: 181.935µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:59:03.609844Z","caller":"traceutil/trace.go:171","msg":"trace[1793989679] transaction","detail":"{read_only:false; response_revision:4442; number_of_response:1; }","duration":"159.65515ms","start":"2026-04-20T05:59:03.450154Z","end":"2026-04-20T05:59:03.60981Z","steps":["trace[1793989679] 'process raft request' (duration: 159.473475ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:59:03.610016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.252818ms","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-20T05:59:03.610063Z","caller":"traceutil/trace.go:171","msg":"trace[406902201] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4442; }","duration":"138.30443ms","start":"2026-04-20T05:59:03.471737Z","end":"2026-04-20T05:59:03.610042Z","steps":["trace[406902201] 'agreement among raft nodes before linearized reading' (duration: 138.149435ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:59:14.474315Z","caller":"traceutil/trace.go:171","msg":"trace[1760620008] transaction","detail":"{read_only:false; response_revision:4635; number_of_response:1; }","duration":"144.762079ms","start":"2026-04-20T05:59:14.329523Z","end":"2026-04-20T05:59:14.474285Z","steps":["trace[1760620008] 'process raft request' (duration: 103.303231ms)","trace[1760620008] 'compare' (duration: 41.155279ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:59:14.474446Z","caller":"traceutil/trace.go:171","msg":"trace[229419794] transaction","detail":"{read_only:false; response_revision:4636; number_of_response:1; }","duration":"144.001188ms","start":"2026-04-20T05:59:14.330421Z","end":"2026-04-20T05:59:14.474422Z","steps":["trace[229419794] 'process raft request' (duration: 143.775451ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:59:14.474546Z","caller":"traceutil/trace.go:171","msg":"trace[821604175] linearizableReadLoop","detail":"{readStateIndex:4828; appliedIndex:4825; }","duration":"137.194339ms","start":"2026-04-20T05:59:14.33733Z","end":"2026-04-20T05:59:14.474524Z","steps":["trace[821604175] 'read index received' (duration: 95.510085ms)","trace[821604175] 'applied index is now lower than readState.Index' (duration: 41.683384ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T05:59:14.474711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.365274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T05:59:14.474762Z","caller":"traceutil/trace.go:171","msg":"trace[1593489969] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4637; }","duration":"137.461167ms","start":"2026-04-20T05:59:14.337289Z","end":"2026-04-20T05:59:14.47475Z","steps":["trace[1593489969] 'agreement among raft nodes before linearized reading' (duration: 137.375064ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:59:14.477294Z","caller":"traceutil/trace.go:171","msg":"trace[188450831] transaction","detail":"{read_only:false; response_revision:4637; number_of_response:1; }","duration":"143.132363ms","start":"2026-04-20T05:59:14.331663Z","end":"2026-04-20T05:59:14.474795Z","steps":["trace[188450831] 'process raft request' (duration: 142.66482ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:59:16.758894Z","caller":"traceutil/trace.go:171","msg":"trace[1946664780] transaction","detail":"{read_only:false; response_revision:4692; number_of_response:1; }","duration":"132.389876ms","start":"2026-04-20T05:59:16.626472Z","end":"2026-04-20T05:59:16.758861Z","steps":["trace[1946664780] 'process raft request' (duration: 108.607987ms)","trace[1946664780] 'compare' (duration: 23.45381ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T05:59:17.10892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.67472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-20T05:59:17.109095Z","caller":"traceutil/trace.go:171","msg":"trace[463600654] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4692; }","duration":"164.912126ms","start":"2026-04-20T05:59:16.944163Z","end":"2026-04-20T05:59:17.109075Z","steps":["trace[463600654] 'range keys from in-memory index tree' (duration: 164.499305ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:59:17.109003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.248896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-20T05:59:17.109206Z","caller":"traceutil/trace.go:171","msg":"trace[711493153] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4692; }","duration":"152.476612ms","start":"2026-04-20T05:59:16.956702Z","end":"2026-04-20T05:59:17.109179Z","steps":["trace[711493153] 'range keys from in-memory index tree' (duration: 152.120902ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:59:34.61095Z","caller":"traceutil/trace.go:171","msg":"trace[282691788] transaction","detail":"{read_only:false; response_revision:4993; number_of_response:1; }","duration":"135.905274ms","start":"2026-04-20T05:59:34.475018Z","end":"2026-04-20T05:59:34.610923Z","steps":["trace[282691788] 'process raft request' (duration: 68.641511ms)","trace[282691788] 'compare' (duration: 66.832961ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T06:00:17.552143Z","caller":"traceutil/trace.go:171","msg":"trace[1220694981] transaction","detail":"{read_only:false; response_revision:5414; number_of_response:1; }","duration":"139.03487ms","start":"2026-04-20T06:00:17.413088Z","end":"2026-04-20T06:00:17.552123Z","steps":["trace[1220694981] 'process raft request' (duration: 138.899846ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:00:17.554308Z","caller":"traceutil/trace.go:171","msg":"trace[45261880] transaction","detail":"{read_only:false; response_revision:5415; number_of_response:1; }","duration":"140.975193ms","start":"2026-04-20T06:00:17.41331Z","end":"2026-04-20T06:00:17.554286Z","steps":["trace[45261880] 'process raft request' (duration: 140.749337ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:00:17.554515Z","caller":"traceutil/trace.go:171","msg":"trace[634823921] transaction","detail":"{read_only:false; response_revision:5416; number_of_response:1; }","duration":"140.774727ms","start":"2026-04-20T06:00:17.413687Z","end":"2026-04-20T06:00:17.554461Z","steps":["trace[634823921] 'process raft request' (duration: 140.534071ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:00:17.554493Z","caller":"traceutil/trace.go:171","msg":"trace[1756634647] linearizableReadLoop","detail":"{readStateIndex:5639; appliedIndex:5636; }","duration":"139.407299ms","start":"2026-04-20T06:00:17.415054Z","end":"2026-04-20T06:00:17.554462Z","steps":["trace[1756634647] 'read index received' (duration: 136.992532ms)","trace[1756634647] 'applied index is now lower than readState.Index' (duration: 2.412347ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T06:00:17.554743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.677497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-72xsh\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-04-20T06:00:17.554823Z","caller":"traceutil/trace.go:171","msg":"trace[901702443] range","detail":"{range_begin:/registry/pods/openstack/keepalived-72xsh; range_end:; response_count:1; response_revision:5416; }","duration":"139.793631ms","start":"2026-04-20T06:00:17.415012Z","end":"2026-04-20T06:00:17.554806Z","steps":["trace[901702443] 'agreement among raft nodes before linearized reading' (duration: 139.593335ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:00:17.906689Z","caller":"traceutil/trace.go:171","msg":"trace[1056783191] linearizableReadLoop","detail":"{readStateIndex:5641; appliedIndex:5640; }","duration":"162.229042ms","start":"2026-04-20T06:00:17.744432Z","end":"2026-04-20T06:00:17.906661Z","steps":["trace[1056783191] 'read index received' (duration: 152.533533ms)","trace[1056783191] 'applied index is now lower than readState.Index' (duration: 9.694108ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T06:00:17.906697Z","caller":"traceutil/trace.go:171","msg":"trace[773476364] transaction","detail":"{read_only:false; response_revision:5418; number_of_response:1; }","duration":"168.156907ms","start":"2026-04-20T06:00:17.738507Z","end":"2026-04-20T06:00:17.906664Z","steps":["trace[773476364] 'process raft request' (duration: 158.500759ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:00:17.906901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.455379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-04-20T06:00:17.906961Z","caller":"traceutil/trace.go:171","msg":"trace[1579550858] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5418; }","duration":"162.5472ms","start":"2026-04-20T06:00:17.744397Z","end":"2026-04-20T06:00:17.906944Z","steps":["trace[1579550858] 'agreement among raft nodes before linearized reading' (duration: 162.366875ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:02:51.548092Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3268} {"level":"info","ts":"2026-04-20T06:02:51.630091Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3268,"took":"80.572571ms","hash":583289554,"current-db-size-bytes":61775872,"current-db-size":"62 MB","current-db-size-in-use-bytes":33918976,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-20T06:02:51.63016Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":583289554,"revision":3268,"compact-revision":-1} {"level":"info","ts":"2026-04-20T06:03:55.596216Z","caller":"traceutil/trace.go:171","msg":"trace[1590005393] linearizableReadLoop","detail":"{readStateIndex:7584; appliedIndex:7583; }","duration":"433.878213ms","start":"2026-04-20T06:03:55.162314Z","end":"2026-04-20T06:03:55.596192Z","steps":["trace[1590005393] 'read index received' (duration: 433.639826ms)","trace[1590005393] 'applied index is now lower than readState.Index' (duration: 237.507µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T06:03:55.596274Z","caller":"traceutil/trace.go:171","msg":"trace[67180707] transaction","detail":"{read_only:false; response_revision:7267; number_of_response:1; }","duration":"482.229819ms","start":"2026-04-20T06:03:55.114012Z","end":"2026-04-20T06:03:55.596241Z","steps":["trace[67180707] 'process raft request' (duration: 482.041234ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:03:55.59644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:03:55.113998Z","time spent":"482.371593ms","remote":"127.0.0.1:36442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2277,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T06:03:55.596534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.192571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-04-20T06:03:55.596581Z","caller":"traceutil/trace.go:171","msg":"trace[236399488] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:7267; }","duration":"434.259813ms","start":"2026-04-20T06:03:55.162308Z","end":"2026-04-20T06:03:55.596568Z","steps":["trace[236399488] 'agreement among raft nodes before linearized reading' (duration: 434.032257ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:03:55.596585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.233374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/barbican-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T06:03:55.596669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.395701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/barbican-db-sync\" ","response":"range_response_count:1 size:5579"} {"level":"warn","ts":"2026-04-20T06:03:55.59669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.085341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-04-20T06:03:55.596684Z","caller":"traceutil/trace.go:171","msg":"trace[1068910027] range","detail":"{range_begin:/registry/jobs/openstack/barbican-ks-user; range_end:; response_count:0; response_revision:7267; }","duration":"370.372338ms","start":"2026-04-20T06:03:55.226287Z","end":"2026-04-20T06:03:55.596659Z","steps":["trace[1068910027] 'agreement among raft nodes before linearized reading' (duration: 370.208813ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:03:55.596719Z","caller":"traceutil/trace.go:171","msg":"trace[788368053] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:7267; }","duration":"310.156693ms","start":"2026-04-20T06:03:55.286555Z","end":"2026-04-20T06:03:55.596712Z","steps":["trace[788368053] 'agreement among raft nodes before linearized reading' (duration: 310.100871ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:03:55.596725Z","caller":"traceutil/trace.go:171","msg":"trace[1887260794] range","detail":"{range_begin:/registry/jobs/openstack/barbican-db-sync; range_end:; response_count:1; response_revision:7267; }","duration":"356.468163ms","start":"2026-04-20T06:03:55.240241Z","end":"2026-04-20T06:03:55.59671Z","steps":["trace[1887260794] 'agreement among raft nodes before linearized reading' (duration: 356.258607ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:03:55.59674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:03:55.226276Z","time spent":"370.450899ms","remote":"127.0.0.1:48256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/barbican-ks-user\" "} {"level":"warn","ts":"2026-04-20T06:03:55.596752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:03:55.286535Z","time spent":"310.211954ms","remote":"127.0.0.1:48278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":548,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"warn","ts":"2026-04-20T06:03:55.596764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:03:55.240235Z","time spent":"356.519344ms","remote":"127.0.0.1:48256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":5602,"request content":"key:\"/registry/jobs/openstack/barbican-db-sync\" "} {"level":"warn","ts":"2026-04-20T06:03:55.596894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.275815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/barbican-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T06:03:55.596905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.045108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/barbican-rabbit-init\" ","response":"range_response_count:1 size:4691"} {"level":"info","ts":"2026-04-20T06:03:55.596933Z","caller":"traceutil/trace.go:171","msg":"trace[1905295358] range","detail":"{range_begin:/registry/jobs/openstack/barbican-ks-endpoints; range_end:; response_count:0; response_revision:7267; }","duration":"370.336156ms","start":"2026-04-20T06:03:55.226585Z","end":"2026-04-20T06:03:55.596921Z","steps":["trace[1905295358] 'agreement among raft nodes before linearized reading' (duration: 370.272604ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:03:55.596945Z","caller":"traceutil/trace.go:171","msg":"trace[969747689] range","detail":"{range_begin:/registry/jobs/openstack/barbican-rabbit-init; range_end:; response_count:1; response_revision:7267; }","duration":"370.1257ms","start":"2026-04-20T06:03:55.226807Z","end":"2026-04-20T06:03:55.596933Z","steps":["trace[969747689] 'agreement among raft nodes before linearized reading' (duration: 369.968976ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:03:55.596986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:03:55.22679Z","time spent":"370.189422ms","remote":"127.0.0.1:48256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":4714,"request content":"key:\"/registry/jobs/openstack/barbican-rabbit-init\" "} {"level":"warn","ts":"2026-04-20T06:03:55.596621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:03:55.162229Z","time spent":"434.379566ms","remote":"127.0.0.1:52246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":683,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-04-20T06:03:55.597056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:03:55.226577Z","time spent":"370.398648ms","remote":"127.0.0.1:48256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/barbican-ks-endpoints\" "} {"level":"info","ts":"2026-04-20T06:05:11.154893Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000020b5.wal"} {"level":"warn","ts":"2026-04-20T06:05:26.869423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.177907ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T06:05:26.869537Z","caller":"traceutil/trace.go:171","msg":"trace[1546884056] transaction","detail":"{read_only:false; response_revision:8130; number_of_response:1; }","duration":"322.639563ms","start":"2026-04-20T06:05:26.546877Z","end":"2026-04-20T06:05:26.869517Z","steps":["trace[1546884056] 'process raft request' (duration: 108.268871ms)","trace[1546884056] 'compare' (duration: 214.086055ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T06:05:26.869606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:05:26.54685Z","time spent":"322.717036ms","remote":"127.0.0.1:48278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":552,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T06:05:27.77484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.370282ms","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-20T06:05:27.774926Z","caller":"traceutil/trace.go:171","msg":"trace[1999834365] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8135; }","duration":"214.504186ms","start":"2026-04-20T06:05:27.560402Z","end":"2026-04-20T06:05:27.774906Z","steps":["trace[1999834365] 'range keys from in-memory index tree' (duration: 214.188997ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:05:27.775162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.03989ms","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-20T06:05:27.775194Z","caller":"traceutil/trace.go:171","msg":"trace[1715706170] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8135; }","duration":"211.106792ms","start":"2026-04-20T06:05:27.564077Z","end":"2026-04-20T06:05:27.775184Z","steps":["trace[1715706170] 'range keys from in-memory index tree' (duration: 210.920557ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:05:54.481264Z","caller":"traceutil/trace.go:171","msg":"trace[248727261] transaction","detail":"{read_only:false; response_revision:8370; number_of_response:1; }","duration":"213.449437ms","start":"2026-04-20T06:05:54.26779Z","end":"2026-04-20T06:05:54.48124Z","steps":["trace[248727261] 'process raft request' (duration: 213.299313ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:06:07.048751Z","caller":"traceutil/trace.go:171","msg":"trace[54554816] linearizableReadLoop","detail":"{readStateIndex:8873; appliedIndex:8872; }","duration":"112.014714ms","start":"2026-04-20T06:06:06.936709Z","end":"2026-04-20T06:06:07.048724Z","steps":["trace[54554816] 'read index received' (duration: 21.626737ms)","trace[54554816] 'applied index is now lower than readState.Index' (duration: 90.386927ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T06:06:07.048899Z","caller":"traceutil/trace.go:171","msg":"trace[1419706409] transaction","detail":"{read_only:false; response_revision:8494; number_of_response:1; }","duration":"114.181175ms","start":"2026-04-20T06:06:06.934696Z","end":"2026-04-20T06:06:07.048877Z","steps":["trace[1419706409] 'process raft request' (duration: 23.660444ms)","trace[1419706409] 'compare' (duration: 90.241843ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T06:06:07.049144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.411455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-vz689\" ","response":"range_response_count:1 size:4652"} {"level":"info","ts":"2026-04-20T06:06:07.049205Z","caller":"traceutil/trace.go:171","msg":"trace[1857457521] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-vz689; range_end:; response_count:1; response_revision:8494; }","duration":"112.503788ms","start":"2026-04-20T06:06:06.936685Z","end":"2026-04-20T06:06:07.049189Z","steps":["trace[1857457521] 'agreement among raft nodes before linearized reading' (duration: 112.388805ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:06:07.049364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.104047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-vz689\" ","response":"range_response_count:1 size:4652"} {"level":"info","ts":"2026-04-20T06:06:07.049491Z","caller":"traceutil/trace.go:171","msg":"trace[544132684] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-vz689; range_end:; response_count:1; response_revision:8494; }","duration":"112.259991ms","start":"2026-04-20T06:06:06.937204Z","end":"2026-04-20T06:06:07.049464Z","steps":["trace[544132684] 'agreement among raft nodes before linearized reading' (duration: 112.044695ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:06:07.277706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.503491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T06:06:07.277779Z","caller":"traceutil/trace.go:171","msg":"trace[1941519433] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:8496; }","duration":"102.625996ms","start":"2026-04-20T06:06:07.175133Z","end":"2026-04-20T06:06:07.277759Z","steps":["trace[1941519433] 'count revisions from in-memory index tree' (duration: 102.416409ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:06:08.152022Z","caller":"traceutil/trace.go:171","msg":"trace[928520777] transaction","detail":"{read_only:false; response_revision:8502; number_of_response:1; }","duration":"404.350381ms","start":"2026-04-20T06:06:07.747652Z","end":"2026-04-20T06:06:08.152002Z","steps":["trace[928520777] 'process raft request' (duration: 404.257768ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:06:08.15214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:06:07.747631Z","time spent":"404.445193ms","remote":"127.0.0.1:52246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T06:06:08.202521Z","caller":"traceutil/trace.go:171","msg":"trace[1971954205] transaction","detail":"{read_only:false; response_revision:8503; number_of_response:1; }","duration":"315.679551ms","start":"2026-04-20T06:06:07.886799Z","end":"2026-04-20T06:06:08.202478Z","steps":["trace[1971954205] 'process raft request' (duration: 315.382543ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:06:08.202704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:06:07.88678Z","time spent":"315.849286ms","remote":"127.0.0.1:52226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":746,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T06:06:08.203003Z","caller":"traceutil/trace.go:171","msg":"trace[288723725] linearizableReadLoop","detail":"{readStateIndex:8882; appliedIndex:8881; }","duration":"315.855427ms","start":"2026-04-20T06:06:07.887097Z","end":"2026-04-20T06:06:08.202952Z","steps":["trace[288723725] 'read index received' (duration: 265.30155ms)","trace[288723725] 'applied index is now lower than readState.Index' (duration: 50.552907ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T06:06:08.203133Z","caller":"traceutil/trace.go:171","msg":"trace[926421019] transaction","detail":"{read_only:false; response_revision:8504; number_of_response:1; }","duration":"305.428658ms","start":"2026-04-20T06:06:07.897692Z","end":"2026-04-20T06:06:08.20312Z","steps":["trace[926421019] 'process raft request' (duration: 304.656557ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:06:08.203264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:06:07.897678Z","time spent":"305.489039ms","remote":"127.0.0.1:48256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2999,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T06:06:08.203313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.195666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-vz689\" ","response":"range_response_count:1 size:4614"} {"level":"info","ts":"2026-04-20T06:06:08.203394Z","caller":"traceutil/trace.go:171","msg":"trace[2065532803] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-vz689; range_end:; response_count:1; response_revision:8504; }","duration":"316.288468ms","start":"2026-04-20T06:06:07.887089Z","end":"2026-04-20T06:06:08.203377Z","steps":["trace[2065532803] 'agreement among raft nodes before linearized reading' (duration: 316.078272ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:06:08.203442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:06:07.887028Z","time spent":"316.404952ms","remote":"127.0.0.1:52308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":4637,"request content":"key:\"/registry/pods/openstack/rook-ceph-detect-version-vz689\" "} {"level":"warn","ts":"2026-04-20T06:06:08.231801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.187343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-20T06:06:08.231904Z","caller":"traceutil/trace.go:171","msg":"trace[369104157] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8505; }","duration":"216.341797ms","start":"2026-04-20T06:06:08.015548Z","end":"2026-04-20T06:06:08.23189Z","steps":["trace[369104157] 'agreement among raft nodes before linearized reading' (duration: 216.115471ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:06:08.231931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.337382ms","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-20T06:06:08.231988Z","caller":"traceutil/trace.go:171","msg":"trace[1260289691] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8505; }","duration":"197.426814ms","start":"2026-04-20T06:06:08.034548Z","end":"2026-04-20T06:06:08.231975Z","steps":["trace[1260289691] 'agreement among raft nodes before linearized reading' (duration: 197.28449ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:07:51.554978Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6631} {"level":"info","ts":"2026-04-20T06:07:51.655093Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6631,"took":"97.888004ms","hash":3471864224,"current-db-size-bytes":61775872,"current-db-size":"62 MB","current-db-size-in-use-bytes":27054080,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-04-20T06:07:51.655158Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3471864224,"revision":6631,"compact-revision":3268} {"level":"info","ts":"2026-04-20T06:07:54.055508Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"93eaf76b69bfff2c","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-20T06:07:54.061582Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-20T06:07:54.061715Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-20T06:12:51.56099Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9546} {"level":"info","ts":"2026-04-20T06:12:51.64774Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9546,"took":"83.444005ms","hash":331906066,"current-db-size-bytes":61775872,"current-db-size":"62 MB","current-db-size-in-use-bytes":25038848,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-20T06:12:51.647799Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":331906066,"revision":9546,"compact-revision":6631} {"level":"info","ts":"2026-04-20T06:15:33.938306Z","caller":"traceutil/trace.go:171","msg":"trace[1750827663] transaction","detail":"{read_only:false; response_revision:12946; number_of_response:1; }","duration":"107.042068ms","start":"2026-04-20T06:15:33.831232Z","end":"2026-04-20T06:15:33.938274Z","steps":["trace[1750827663] 'process raft request' (duration: 106.840943ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:15:34.595231Z","caller":"traceutil/trace.go:171","msg":"trace[1471403596] linearizableReadLoop","detail":"{readStateIndex:13568; appliedIndex:13567; }","duration":"349.800996ms","start":"2026-04-20T06:15:34.245409Z","end":"2026-04-20T06:15:34.59521Z","steps":["trace[1471403596] 'read index received' (duration: 349.599191ms)","trace[1471403596] 'applied index is now lower than readState.Index' (duration: 200.715µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T06:15:34.595287Z","caller":"traceutil/trace.go:171","msg":"trace[414723997] transaction","detail":"{read_only:false; response_revision:12947; number_of_response:1; }","duration":"353.744595ms","start":"2026-04-20T06:15:34.241518Z","end":"2026-04-20T06:15:34.595262Z","steps":["trace[414723997] 'process raft request' (duration: 353.55966ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:15:34.59541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.001591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-04-20T06:15:34.595441Z","caller":"traceutil/trace.go:171","msg":"trace[1808888975] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:1; response_revision:12947; }","duration":"350.036832ms","start":"2026-04-20T06:15:34.245393Z","end":"2026-04-20T06:15:34.59543Z","steps":["trace[1808888975] 'agreement among raft nodes before linearized reading' (duration: 349.926919ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:15:34.595416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:15:34.241499Z","time spent":"353.837027ms","remote":"127.0.0.1:48278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T06:15:34.595465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:15:34.245325Z","time spent":"350.134645ms","remote":"127.0.0.1:52314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":239,"request content":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" "} {"level":"warn","ts":"2026-04-20T06:15:34.595555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.358078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-04-20T06:15:34.595649Z","caller":"traceutil/trace.go:171","msg":"trace[477075549] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:12947; }","duration":"327.522662ms","start":"2026-04-20T06:15:34.268102Z","end":"2026-04-20T06:15:34.595624Z","steps":["trace[477075549] 'agreement among raft nodes before linearized reading' (duration: 327.281475ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:15:34.595698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:15:34.268058Z","time spent":"327.630785ms","remote":"127.0.0.1:52246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":668,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-20T06:15:34.595745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.224725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11496"} {"level":"info","ts":"2026-04-20T06:15:34.59578Z","caller":"traceutil/trace.go:171","msg":"trace[1794812895] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:12947; }","duration":"184.284177ms","start":"2026-04-20T06:15:34.411488Z","end":"2026-04-20T06:15:34.595772Z","steps":["trace[1794812895] 'agreement among raft nodes before linearized reading' (duration: 184.200604ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:15:34.595818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.829944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-20T06:15:34.595866Z","caller":"traceutil/trace.go:171","msg":"trace[65423519] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:12947; }","duration":"187.900066ms","start":"2026-04-20T06:15:34.407954Z","end":"2026-04-20T06:15:34.595854Z","steps":["trace[65423519] 'agreement among raft nodes before linearized reading' (duration: 187.780143ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:15:34.595932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.873036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T06:15:34.595977Z","caller":"traceutil/trace.go:171","msg":"trace[1155708377] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:12947; }","duration":"256.959588ms","start":"2026-04-20T06:15:34.339006Z","end":"2026-04-20T06:15:34.595966Z","steps":["trace[1155708377] 'agreement among raft nodes before linearized reading' (duration: 256.895496ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:15:34.59601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.512124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-20T06:15:34.596041Z","caller":"traceutil/trace.go:171","msg":"trace[2084530932] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:12947; }","duration":"310.598826ms","start":"2026-04-20T06:15:34.285435Z","end":"2026-04-20T06:15:34.596034Z","steps":["trace[2084530932] 'agreement among raft nodes before linearized reading' (duration: 310.525804ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:15:34.596062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:15:34.28541Z","time spent":"310.647718ms","remote":"127.0.0.1:48278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-20T06:15:34.595855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.949427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-20T06:15:34.596126Z","caller":"traceutil/trace.go:171","msg":"trace[1510832011] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:12947; }","duration":"188.267526ms","start":"2026-04-20T06:15:34.407838Z","end":"2026-04-20T06:15:34.596106Z","steps":["trace[1510832011] 'agreement among raft nodes before linearized reading' (duration: 187.706ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:15:34.596177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.630723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-20T06:15:34.596223Z","caller":"traceutil/trace.go:171","msg":"trace[1466506064] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:12947; }","duration":"283.715115ms","start":"2026-04-20T06:15:34.312497Z","end":"2026-04-20T06:15:34.596212Z","steps":["trace[1466506064] 'agreement among raft nodes before linearized reading' (duration: 283.608282ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:15:35.073654Z","caller":"traceutil/trace.go:171","msg":"trace[74597157] linearizableReadLoop","detail":"{readStateIndex:13571; appliedIndex:13569; }","duration":"417.535681ms","start":"2026-04-20T06:15:34.656089Z","end":"2026-04-20T06:15:35.073624Z","steps":["trace[74597157] 'read index received' (duration: 797.322µs)","trace[74597157] 'applied index is now lower than readState.Index' (duration: 416.737379ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T06:15:35.073691Z","caller":"traceutil/trace.go:171","msg":"trace[1796932728] transaction","detail":"{read_only:false; response_revision:12949; number_of_response:1; }","duration":"417.887511ms","start":"2026-04-20T06:15:34.655771Z","end":"2026-04-20T06:15:35.073659Z","steps":["trace[1796932728] 'process raft request' (duration: 417.357557ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:15:35.073855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:15:34.655759Z","time spent":"418.002004ms","remote":"127.0.0.1:48278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T06:15:35.073882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.786849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-04-20T06:15:35.073966Z","caller":"traceutil/trace.go:171","msg":"trace[59562772] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:1; response_revision:12949; }","duration":"417.927842ms","start":"2026-04-20T06:15:34.656017Z","end":"2026-04-20T06:15:35.073945Z","steps":["trace[59562772] 'agreement among raft nodes before linearized reading' (duration: 417.694106ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:15:35.074024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:15:34.655924Z","time spent":"418.085166ms","remote":"127.0.0.1:52314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":239,"request content":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" "} {"level":"info","ts":"2026-04-20T06:15:35.074957Z","caller":"traceutil/trace.go:171","msg":"trace[580973541] transaction","detail":"{read_only:false; response_revision:12951; number_of_response:1; }","duration":"416.614126ms","start":"2026-04-20T06:15:34.658321Z","end":"2026-04-20T06:15:35.074935Z","steps":["trace[580973541] 'process raft request' (duration: 416.505053ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:15:35.075055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:15:34.658311Z","time spent":"416.692938ms","remote":"127.0.0.1:48278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T06:15:35.075153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.805446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11496"} {"level":"info","ts":"2026-04-20T06:15:35.07525Z","caller":"traceutil/trace.go:171","msg":"trace[291557403] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:12952; }","duration":"418.92001ms","start":"2026-04-20T06:15:34.656309Z","end":"2026-04-20T06:15:35.075229Z","steps":["trace[291557403] 'agreement among raft nodes before linearized reading' (duration: 418.628742ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:15:35.0753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:15:34.65629Z","time spent":"419.001031ms","remote":"127.0.0.1:52308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":11519,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-04-20T06:15:35.075591Z","caller":"traceutil/trace.go:171","msg":"trace[996991114] transaction","detail":"{read_only:false; response_revision:12950; number_of_response:1; }","duration":"417.698446ms","start":"2026-04-20T06:15:34.657874Z","end":"2026-04-20T06:15:35.075573Z","steps":["trace[996991114] 'process raft request' (duration: 416.748ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:15:35.075637Z","caller":"traceutil/trace.go:171","msg":"trace[41073747] transaction","detail":"{read_only:false; response_revision:12952; number_of_response:1; }","duration":"416.110921ms","start":"2026-04-20T06:15:34.659514Z","end":"2026-04-20T06:15:35.075625Z","steps":["trace[41073747] 'process raft request' (duration: 415.367341ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:15:35.075669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:15:34.657855Z","time spent":"417.777638ms","remote":"127.0.0.1:48278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T06:15:35.076036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:15:34.659506Z","time spent":"416.456252ms","remote":"127.0.0.1:52246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T06:15:35.076199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.30351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" limit:500 ","response":"range_response_count:4 size:329956"} {"level":"info","ts":"2026-04-20T06:15:35.076269Z","caller":"traceutil/trace.go:171","msg":"trace[373074651] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:12952; }","duration":"419.419153ms","start":"2026-04-20T06:15:34.656831Z","end":"2026-04-20T06:15:35.07625Z","steps":["trace[373074651] 'agreement among raft nodes before linearized reading' (duration: 419.160846ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:15:35.076314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:15:34.65681Z","time spent":"419.489535ms","remote":"127.0.0.1:52240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":4,"response size":329979,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" limit:500 "} {"level":"warn","ts":"2026-04-20T06:15:35.076639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.700297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T06:15:35.076701Z","caller":"traceutil/trace.go:171","msg":"trace[1328098820] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:12952; }","duration":"148.79805ms","start":"2026-04-20T06:15:34.927886Z","end":"2026-04-20T06:15:35.076684Z","steps":["trace[1328098820] 'agreement among raft nodes before linearized reading' (duration: 148.703337ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:15:35.076773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.742755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-04-20T06:15:35.077032Z","caller":"traceutil/trace.go:171","msg":"trace[813803788] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:12952; }","duration":"419.029313ms","start":"2026-04-20T06:15:34.657988Z","end":"2026-04-20T06:15:35.077017Z","steps":["trace[813803788] 'agreement among raft nodes before linearized reading' (duration: 418.602571ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:15:35.077199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:15:34.657979Z","time spent":"419.207037ms","remote":"127.0.0.1:52300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":444,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "}