{"level":"warn","ts":"2026-06-04T11:59:10.176068Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-04T11:59:10.176163Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.22:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.22:2380","--initial-cluster=instance=https://199.204.45.22:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.22:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.22:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-06-04T11:59:10.176235Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-04T11:59:10.176248Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.22:2380"]} {"level":"info","ts":"2026-06-04T11:59:10.176266Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-06-04T11:59:10.176905Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"]} {"level":"info","ts":"2026-06-04T11:59:10.176989Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.22:2380"],"listen-peer-urls":["https://199.204.45.22:2380"],"advertise-client-urls":["https://199.204.45.22:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.22:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-06-04T11:59:10.179387Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.16666ms"} {"level":"info","ts":"2026-06-04T11:59:10.1857Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fed19d9291630e09","cluster-id":"6ebf1e01320cc017"} {"level":"info","ts":"2026-06-04T11:59:10.18583Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=()"} {"level":"info","ts":"2026-06-04T11:59:10.185872Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became follower at term 0"} {"level":"info","ts":"2026-06-04T11:59:10.18591Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fed19d9291630e09 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-04T11:59:10.185971Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became follower at term 1"} {"level":"info","ts":"2026-06-04T11:59:10.186045Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=(18361630408596196873)"} {"level":"warn","ts":"2026-06-04T11:59:10.190844Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-04T11:59:10.192205Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-04T11:59:10.193272Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-06-04T11:59:10.195684Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fed19d9291630e09","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-04T11:59:10.195932Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fed19d9291630e09","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-04T11:59:10.196017Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-04T11:59:10.196138Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-04T11:59:10.196162Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-04T11:59:10.197606Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=(18361630408596196873)"} {"level":"info","ts":"2026-06-04T11:59:10.197788Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6ebf1e01320cc017","local-member-id":"fed19d9291630e09","added-peer-id":"fed19d9291630e09","added-peer-peer-urls":["https://199.204.45.22:2380"]} {"level":"info","ts":"2026-06-04T11:59:10.201913Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-06-04T11:59:10.202078Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.22:2380"} {"level":"info","ts":"2026-06-04T11:59:10.202254Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.22:2380"} {"level":"info","ts":"2026-06-04T11:59:10.202818Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-04T11:59:10.203765Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fed19d9291630e09","initial-advertise-peer-urls":["https://199.204.45.22:2380"],"listen-peer-urls":["https://199.204.45.22:2380"],"advertise-client-urls":["https://199.204.45.22:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-04T11:59:10.586431Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 is starting a new election at term 1"} {"level":"info","ts":"2026-06-04T11:59:10.586486Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-04T11:59:10.586511Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 received MsgPreVoteResp from fed19d9291630e09 at term 1"} {"level":"info","ts":"2026-06-04T11:59:10.586524Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became candidate at term 2"} {"level":"info","ts":"2026-06-04T11:59:10.586532Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 received MsgVoteResp from fed19d9291630e09 at term 2"} {"level":"info","ts":"2026-06-04T11:59:10.586542Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became leader at term 2"} {"level":"info","ts":"2026-06-04T11:59:10.58655Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fed19d9291630e09 elected leader fed19d9291630e09 at term 2"} {"level":"info","ts":"2026-06-04T11:59:10.587404Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-04T11:59:10.588066Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fed19d9291630e09","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.22:2379]}","request-path":"/0/members/fed19d9291630e09/attributes","cluster-id":"6ebf1e01320cc017","publish-timeout":"7s"} {"level":"info","ts":"2026-06-04T11:59:10.588105Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-04T11:59:10.588478Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-04T11:59:10.588524Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6ebf1e01320cc017","local-member-id":"fed19d9291630e09","cluster-version":"3.5"} {"level":"info","ts":"2026-06-04T11:59:10.588636Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-04T11:59:10.588678Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-04T11:59:10.591371Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-04T11:59:10.591417Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-04T11:59:10.591571Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.22:2379"} {"level":"info","ts":"2026-06-04T11:59:10.591638Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-04T11:59:25.35856Z","caller":"traceutil/trace.go:171","msg":"trace[898695847] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"128.325889ms","start":"2026-06-04T11:59:25.230211Z","end":"2026-06-04T11:59:25.358537Z","steps":["trace[898695847] 'process raft request' (duration: 128.171924ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T11:59:47.241363Z","caller":"traceutil/trace.go:171","msg":"trace[1500107876] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"199.374993ms","start":"2026-06-04T11:59:47.04196Z","end":"2026-06-04T11:59:47.241335Z","steps":["trace[1500107876] 'process raft request' (duration: 199.145827ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T11:59:47.241613Z","caller":"traceutil/trace.go:171","msg":"trace[2053048897] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"197.214114ms","start":"2026-06-04T11:59:47.044364Z","end":"2026-06-04T11:59:47.241578Z","steps":["trace[2053048897] 'process raft request' (duration: 196.907406ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T11:59:47.242946Z","caller":"traceutil/trace.go:171","msg":"trace[211560578] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"193.582255ms","start":"2026-06-04T11:59:47.049289Z","end":"2026-06-04T11:59:47.242871Z","steps":["trace[211560578] 'process raft request' (duration: 192.414042ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T11:59:47.243236Z","caller":"traceutil/trace.go:171","msg":"trace[1280131562] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"197.563414ms","start":"2026-06-04T11:59:47.045109Z","end":"2026-06-04T11:59:47.242672Z","steps":["trace[1280131562] 'process raft request' (duration: 196.398632ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T11:59:47.242851Z","caller":"traceutil/trace.go:171","msg":"trace[446748543] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"196.059932ms","start":"2026-06-04T11:59:47.04571Z","end":"2026-06-04T11:59:47.24177Z","steps":["trace[446748543] 'process raft request' (duration: 195.899628ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T11:59:47.247063Z","caller":"traceutil/trace.go:171","msg":"trace[544512434] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"196.733291ms","start":"2026-06-04T11:59:47.050303Z","end":"2026-06-04T11:59:47.247036Z","steps":["trace[544512434] 'process raft request' (duration: 192.630998ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T11:59:47.247738Z","caller":"traceutil/trace.go:171","msg":"trace[2057555590] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"160.847534ms","start":"2026-06-04T11:59:47.086877Z","end":"2026-06-04T11:59:47.247724Z","steps":["trace[2057555590] 'process raft request' (duration: 160.71459ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T11:59:47.247757Z","caller":"traceutil/trace.go:171","msg":"trace[575857861] linearizableReadLoop","detail":"{readStateIndex:523; appliedIndex:517; }","duration":"199.343592ms","start":"2026-06-04T11:59:47.048365Z","end":"2026-06-04T11:59:47.247708Z","steps":["trace[575857861] 'read index received' (duration: 39.286181ms)","trace[575857861] 'applied index is now lower than readState.Index' (duration: 160.056361ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T11:59:47.247995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.600459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:10078"} {"level":"info","ts":"2026-06-04T11:59:47.248109Z","caller":"traceutil/trace.go:171","msg":"trace[244022538] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:507; }","duration":"199.750584ms","start":"2026-06-04T11:59:47.048337Z","end":"2026-06-04T11:59:47.248088Z","steps":["trace[244022538] 'agreement among raft nodes before linearized reading' (duration: 199.488407ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T11:59:47.250411Z","caller":"traceutil/trace.go:171","msg":"trace[141617623] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"154.266363ms","start":"2026-06-04T11:59:47.096122Z","end":"2026-06-04T11:59:47.250388Z","steps":["trace[141617623] 'process raft request' (duration: 153.80684ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T11:59:47.250498Z","caller":"traceutil/trace.go:171","msg":"trace[52950334] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"149.655456ms","start":"2026-06-04T11:59:47.100836Z","end":"2026-06-04T11:59:47.250492Z","steps":["trace[52950334] 'process raft request' (duration: 149.224294ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T11:59:53.099711Z","caller":"traceutil/trace.go:171","msg":"trace[827948866] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"222.027396ms","start":"2026-06-04T11:59:52.87764Z","end":"2026-06-04T11:59:53.099668Z","steps":["trace[827948866] 'process raft request' (duration: 221.733028ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T11:59:54.225446Z","caller":"traceutil/trace.go:171","msg":"trace[841983369] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"432.00627ms","start":"2026-06-04T11:59:53.793408Z","end":"2026-06-04T11:59:54.225414Z","steps":["trace[841983369] 'process raft request' (duration: 412.383501ms)","trace[841983369] 'compare' (duration: 19.235899ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T11:59:54.226957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T11:59:53.793358Z","time spent":"432.187865ms","remote":"127.0.0.1:56182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-04T12:00:11.185493Z","caller":"traceutil/trace.go:171","msg":"trace[1862537783] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"325.196774ms","start":"2026-06-04T12:00:10.860263Z","end":"2026-06-04T12:00:11.18546Z","steps":["trace[1862537783] 'process raft request' (duration: 324.895236ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:00:11.185807Z","caller":"traceutil/trace.go:171","msg":"trace[1076731199] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"323.426616ms","start":"2026-06-04T12:00:10.862345Z","end":"2026-06-04T12:00:11.185771Z","steps":["trace[1076731199] 'process raft request' (duration: 323.038955ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:00:11.18603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:00:10.860237Z","time spent":"325.376599ms","remote":"127.0.0.1:56258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1290,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T12:00:11.186061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:00:10.862316Z","time spent":"323.635362ms","remote":"127.0.0.1:56468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3852,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T12:01:28.13541Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011513943378327724,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-04T12:01:28.241377Z","caller":"traceutil/trace.go:171","msg":"trace[1083565429] linearizableReadLoop","detail":"{readStateIndex:1907; appliedIndex:1906; }","duration":"606.026535ms","start":"2026-06-04T12:01:27.635331Z","end":"2026-06-04T12:01:28.241358Z","steps":["trace[1083565429] 'read index received' (duration: 605.86171ms)","trace[1083565429] 'applied index is now lower than readState.Index' (duration: 164.155µs)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:01:28.241438Z","caller":"traceutil/trace.go:171","msg":"trace[1388342220] transaction","detail":"{read_only:false; response_revision:1836; number_of_response:1; }","duration":"653.774602ms","start":"2026-06-04T12:01:27.587648Z","end":"2026-06-04T12:01:28.241423Z","steps":["trace[1388342220] 'process raft request' (duration: 653.603597ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:01:28.241488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.303416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T12:01:28.241509Z","caller":"traceutil/trace.go:171","msg":"trace[63593890] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1836; }","duration":"424.390429ms","start":"2026-06-04T12:01:27.81711Z","end":"2026-06-04T12:01:28.241501Z","steps":["trace[63593890] 'agreement among raft nodes before linearized reading' (duration: 424.340268ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:01:28.241525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:01:27.817086Z","time spent":"424.43598ms","remote":"127.0.0.1:56002","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-06-04T12:01:28.241529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:01:27.58762Z","time spent":"653.845554ms","remote":"127.0.0.1:56256","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-06-04T12:01:28.241658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.242466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-06-04T12:01:28.241694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.610767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-04T12:01:28.241752Z","caller":"traceutil/trace.go:171","msg":"trace[591711606] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1836; }","duration":"312.669968ms","start":"2026-06-04T12:01:27.929068Z","end":"2026-06-04T12:01:28.241738Z","steps":["trace[591711606] 'agreement among raft nodes before linearized reading' (duration: 312.534485ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:01:28.241672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.290343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-06-04T12:01:28.241765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.445544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-04T12:01:28.242011Z","caller":"traceutil/trace.go:171","msg":"trace[1466607614] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1836; }","duration":"398.797064ms","start":"2026-06-04T12:01:27.843202Z","end":"2026-06-04T12:01:28.241999Z","steps":["trace[1466607614] 'agreement among raft nodes before linearized reading' (duration: 398.436934ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:01:28.241805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:01:27.928994Z","time spent":"312.800912ms","remote":"127.0.0.1:56256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-04T12:01:28.242132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:01:27.843179Z","time spent":"398.909496ms","remote":"127.0.0.1:56256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-04T12:01:28.241789Z","caller":"traceutil/trace.go:171","msg":"trace[142819338] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1836; }","duration":"222.37999ms","start":"2026-06-04T12:01:28.019349Z","end":"2026-06-04T12:01:28.241729Z","steps":["trace[142819338] 'agreement among raft nodes before linearized reading' (duration: 222.183085ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:01:28.24256Z","caller":"traceutil/trace.go:171","msg":"trace[1644413280] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1836; }","duration":"606.506039ms","start":"2026-06-04T12:01:27.635294Z","end":"2026-06-04T12:01:28.2418Z","steps":["trace[1644413280] 'agreement among raft nodes before linearized reading' (duration: 606.16225ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:01:28.24276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:01:27.635274Z","time spent":"607.465825ms","remote":"127.0.0.1:56256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-04T12:01:36.40847Z","caller":"traceutil/trace.go:171","msg":"trace[1251127726] transaction","detail":"{read_only:false; response_revision:1914; number_of_response:1; }","duration":"102.069234ms","start":"2026-06-04T12:01:36.30638Z","end":"2026-06-04T12:01:36.408449Z","steps":["trace[1251127726] 'process raft request' (duration: 102.031143ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:01:36.408833Z","caller":"traceutil/trace.go:171","msg":"trace[660937718] transaction","detail":"{read_only:false; response_revision:1913; number_of_response:1; }","duration":"102.610599ms","start":"2026-06-04T12:01:36.306158Z","end":"2026-06-04T12:01:36.408769Z","steps":["trace[660937718] 'process raft request' (duration: 102.193897ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:01:40.428698Z","caller":"traceutil/trace.go:171","msg":"trace[184296563] transaction","detail":"{read_only:false; response_revision:1937; number_of_response:1; }","duration":"109.782347ms","start":"2026-06-04T12:01:40.318878Z","end":"2026-06-04T12:01:40.42866Z","steps":["trace[184296563] 'process raft request' (duration: 109.570641ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:02:18.832237Z","caller":"traceutil/trace.go:171","msg":"trace[946923552] linearizableReadLoop","detail":"{readStateIndex:2244; appliedIndex:2241; }","duration":"108.151652ms","start":"2026-06-04T12:02:18.724066Z","end":"2026-06-04T12:02:18.832218Z","steps":["trace[946923552] 'read index received' (duration: 85.709453ms)","trace[946923552] 'applied index is now lower than readState.Index' (duration: 22.441249ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:02:18.832333Z","caller":"traceutil/trace.go:171","msg":"trace[1030827446] transaction","detail":"{read_only:false; response_revision:2163; number_of_response:1; }","duration":"122.082376ms","start":"2026-06-04T12:02:18.710243Z","end":"2026-06-04T12:02:18.832325Z","steps":["trace[1030827446] 'process raft request' (duration: 121.943702ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:02:18.832319Z","caller":"traceutil/trace.go:171","msg":"trace[114041437] transaction","detail":"{read_only:false; response_revision:2162; number_of_response:1; }","duration":"124.606755ms","start":"2026-06-04T12:02:18.707683Z","end":"2026-06-04T12:02:18.83229Z","steps":["trace[114041437] 'process raft request' (duration: 124.381818ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:02:18.83255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.4561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-04T12:02:18.832686Z","caller":"traceutil/trace.go:171","msg":"trace[187200286] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2163; }","duration":"108.614545ms","start":"2026-06-04T12:02:18.724034Z","end":"2026-06-04T12:02:18.832648Z","steps":["trace[187200286] 'agreement among raft nodes before linearized reading' (duration: 108.320286ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:02:18.833451Z","caller":"traceutil/trace.go:171","msg":"trace[517215672] transaction","detail":"{read_only:false; response_revision:2161; number_of_response:1; }","duration":"124.77467ms","start":"2026-06-04T12:02:18.707546Z","end":"2026-06-04T12:02:18.832321Z","steps":["trace[517215672] 'process raft request' (duration: 102.216337ms)","trace[517215672] 'compare' (duration: 21.984597ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:02:19.088244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.354911ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-04T12:02:19.376584Z","caller":"traceutil/trace.go:171","msg":"trace[1935685341] transaction","detail":"{read_only:false; response_revision:2165; number_of_response:1; }","duration":"247.612906ms","start":"2026-06-04T12:02:19.128945Z","end":"2026-06-04T12:02:19.376558Z","steps":["trace[1935685341] 'process raft request' (duration: 247.420121ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:02:19.595687Z","caller":"traceutil/trace.go:171","msg":"trace[1633081232] linearizableReadLoop","detail":"{readStateIndex:2248; appliedIndex:2246; }","duration":"229.99048ms","start":"2026-06-04T12:02:19.365671Z","end":"2026-06-04T12:02:19.595661Z","steps":["trace[1633081232] 'read index received' (duration: 10.755126ms)","trace[1633081232] 'applied index is now lower than readState.Index' (duration: 219.234384ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:02:19.595682Z","caller":"traceutil/trace.go:171","msg":"trace[1488252094] transaction","detail":"{read_only:false; response_revision:2166; number_of_response:1; }","duration":"253.266111ms","start":"2026-06-04T12:02:19.342375Z","end":"2026-06-04T12:02:19.595641Z","steps":["trace[1488252094] 'process raft request' (duration: 161.267905ms)","trace[1488252094] 'compare' (duration: 91.868683ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:02:19.595973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.281148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-04T12:02:19.596004Z","caller":"traceutil/trace.go:171","msg":"trace[1225137814] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2167; }","duration":"230.36089ms","start":"2026-06-04T12:02:19.365635Z","end":"2026-06-04T12:02:19.595996Z","steps":["trace[1225137814] 'agreement among raft nodes before linearized reading' (duration: 230.099723ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:02:19.596035Z","caller":"traceutil/trace.go:171","msg":"trace[1414492532] transaction","detail":"{read_only:false; response_revision:2167; number_of_response:1; }","duration":"215.49709ms","start":"2026-06-04T12:02:19.380499Z","end":"2026-06-04T12:02:19.595996Z","steps":["trace[1414492532] 'process raft request' (duration: 215.137ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:03:13.963856Z","caller":"traceutil/trace.go:171","msg":"trace[1825431813] linearizableReadLoop","detail":"{readStateIndex:2731; appliedIndex:2730; }","duration":"145.800055ms","start":"2026-06-04T12:03:13.818031Z","end":"2026-06-04T12:03:13.963832Z","steps":["trace[1825431813] 'read index received' (duration: 145.64188ms)","trace[1825431813] 'applied index is now lower than readState.Index' (duration: 157.165µs)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:03:13.963933Z","caller":"traceutil/trace.go:171","msg":"trace[1798660042] transaction","detail":"{read_only:false; response_revision:2630; number_of_response:1; }","duration":"184.931035ms","start":"2026-06-04T12:03:13.778956Z","end":"2026-06-04T12:03:13.963887Z","steps":["trace[1798660042] 'process raft request' (duration: 184.719489ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:03:13.96401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.96195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T12:03:13.964078Z","caller":"traceutil/trace.go:171","msg":"trace[902290436] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2630; }","duration":"146.090393ms","start":"2026-06-04T12:03:13.817973Z","end":"2026-06-04T12:03:13.964064Z","steps":["trace[902290436] 'agreement among raft nodes before linearized reading' (duration: 145.945829ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:03:13.964661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.617247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-04T12:03:13.964754Z","caller":"traceutil/trace.go:171","msg":"trace[363300237] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2631; }","duration":"134.765141ms","start":"2026-06-04T12:03:13.829968Z","end":"2026-06-04T12:03:13.964733Z","steps":["trace[363300237] 'agreement among raft nodes before linearized reading' (duration: 134.532604ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:03:13.964846Z","caller":"traceutil/trace.go:171","msg":"trace[1437882166] transaction","detail":"{read_only:false; response_revision:2631; number_of_response:1; }","duration":"140.742025ms","start":"2026-06-04T12:03:13.824074Z","end":"2026-06-04T12:03:13.964816Z","steps":["trace[1437882166] 'process raft request' (duration: 140.286542ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:03:13.964985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.040503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-06-04T12:03:13.965044Z","caller":"traceutil/trace.go:171","msg":"trace[603449568] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2631; }","duration":"112.170737ms","start":"2026-06-04T12:03:13.852857Z","end":"2026-06-04T12:03:13.965027Z","steps":["trace[603449568] 'agreement among raft nodes before linearized reading' (duration: 111.958891ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:07:39.068858Z","caller":"traceutil/trace.go:171","msg":"trace[368705776] transaction","detail":"{read_only:false; response_revision:4729; number_of_response:1; }","duration":"110.913751ms","start":"2026-06-04T12:07:38.957918Z","end":"2026-06-04T12:07:39.068832Z","steps":["trace[368705776] 'process raft request' (duration: 46.294114ms)","trace[368705776] 'compare' (duration: 64.459183ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:07:39.080072Z","caller":"traceutil/trace.go:171","msg":"trace[1779246318] transaction","detail":"{read_only:false; response_revision:4730; number_of_response:1; }","duration":"121.844592ms","start":"2026-06-04T12:07:38.95821Z","end":"2026-06-04T12:07:39.080055Z","steps":["trace[1779246318] 'process raft request' (duration: 121.720889ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:07:39.080171Z","caller":"traceutil/trace.go:171","msg":"trace[2142774222] transaction","detail":"{read_only:false; response_revision:4732; number_of_response:1; }","duration":"121.230875ms","start":"2026-06-04T12:07:38.958922Z","end":"2026-06-04T12:07:39.080153Z","steps":["trace[2142774222] 'process raft request' (duration: 121.120002ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:07:39.080199Z","caller":"traceutil/trace.go:171","msg":"trace[634534077] transaction","detail":"{read_only:false; response_revision:4731; number_of_response:1; }","duration":"121.828341ms","start":"2026-06-04T12:07:38.958305Z","end":"2026-06-04T12:07:39.080133Z","steps":["trace[634534077] 'process raft request' (duration: 121.702758ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:07:47.158933Z","caller":"traceutil/trace.go:171","msg":"trace[1486433002] linearizableReadLoop","detail":"{readStateIndex:5210; appliedIndex:5208; }","duration":"147.182289ms","start":"2026-06-04T12:07:47.01173Z","end":"2026-06-04T12:07:47.158912Z","steps":["trace[1486433002] 'read index received' (duration: 7.081225ms)","trace[1486433002] 'applied index is now lower than readState.Index' (duration: 140.099834ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:07:47.159032Z","caller":"traceutil/trace.go:171","msg":"trace[486853664] transaction","detail":"{read_only:false; response_revision:5021; number_of_response:1; }","duration":"154.366816ms","start":"2026-06-04T12:07:47.004583Z","end":"2026-06-04T12:07:47.15895Z","steps":["trace[486853664] 'process raft request' (duration: 119.316601ms)","trace[486853664] 'compare' (duration: 34.927871ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:07:47.159255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.506657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2e22a480-8c50-44f6-9188-dc4f5f734a59\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-06-04T12:07:47.159305Z","caller":"traceutil/trace.go:171","msg":"trace[1544784246] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2e22a480-8c50-44f6-9188-dc4f5f734a59; range_end:; response_count:1; response_revision:5021; }","duration":"147.60313ms","start":"2026-06-04T12:07:47.01169Z","end":"2026-06-04T12:07:47.159293Z","steps":["trace[1544784246] 'agreement among raft nodes before linearized reading' (duration: 147.379104ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:07:59.159759Z","caller":"traceutil/trace.go:171","msg":"trace[1839204189] transaction","detail":"{read_only:false; response_revision:5240; number_of_response:1; }","duration":"108.222656ms","start":"2026-06-04T12:07:59.051522Z","end":"2026-06-04T12:07:59.159745Z","steps":["trace[1839204189] 'process raft request' (duration: 73.261375ms)","trace[1839204189] 'compare' (duration: 34.688244ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:08:00.289992Z","caller":"traceutil/trace.go:171","msg":"trace[1162978335] transaction","detail":"{read_only:false; response_revision:5287; number_of_response:1; }","duration":"106.994044ms","start":"2026-06-04T12:08:00.18297Z","end":"2026-06-04T12:08:00.289964Z","steps":["trace[1162978335] 'process raft request' (duration: 49.160233ms)","trace[1162978335] 'compare' (duration: 57.518672ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:08:01.072314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.635275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T12:08:01.072378Z","caller":"traceutil/trace.go:171","msg":"trace[671032947] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5293; }","duration":"254.745028ms","start":"2026-06-04T12:08:00.817619Z","end":"2026-06-04T12:08:01.072365Z","steps":["trace[671032947] 'range keys from in-memory index tree' (duration: 254.565483ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:08:01.072558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.468245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"info","ts":"2026-06-04T12:08:01.072629Z","caller":"traceutil/trace.go:171","msg":"trace[1839197465] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:5293; }","duration":"368.538697ms","start":"2026-06-04T12:08:00.704073Z","end":"2026-06-04T12:08:01.072612Z","steps":["trace[1839197465] 'range keys from in-memory index tree' (duration: 368.27975ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:08:01.07267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:08:00.704043Z","time spent":"368.616959ms","remote":"127.0.0.1:52810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5674,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-06-04T12:08:08.38635Z","caller":"traceutil/trace.go:171","msg":"trace[908360371] transaction","detail":"{read_only:false; response_revision:5452; number_of_response:1; }","duration":"100.549156ms","start":"2026-06-04T12:08:08.285748Z","end":"2026-06-04T12:08:08.386298Z","steps":["trace[908360371] 'process raft request' (duration: 69.673917ms)","trace[908360371] 'compare' (duration: 30.526619ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:08:08.555766Z","caller":"traceutil/trace.go:171","msg":"trace[461571004] transaction","detail":"{read_only:false; response_revision:5455; number_of_response:1; }","duration":"159.141697ms","start":"2026-06-04T12:08:08.396598Z","end":"2026-06-04T12:08:08.55574Z","steps":["trace[461571004] 'process raft request' (duration: 126.226982ms)","trace[461571004] 'compare' (duration: 32.789251ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:09:10.610966Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2963} {"level":"info","ts":"2026-06-04T12:09:10.675275Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2963,"took":"62.855599ms","hash":2465580558,"current-db-size-bytes":57225216,"current-db-size":"57 MB","current-db-size-in-use-bytes":33882112,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-04T12:09:10.675329Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2465580558,"revision":2963,"compact-revision":-1} {"level":"warn","ts":"2026-06-04T12:13:31.882581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.410298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-06-04T12:13:31.88272Z","caller":"traceutil/trace.go:171","msg":"trace[1068362474] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:7850; }","duration":"309.575022ms","start":"2026-06-04T12:13:31.573116Z","end":"2026-06-04T12:13:31.882691Z","steps":["trace[1068362474] 'range keys from in-memory index tree' (duration: 309.16284ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:13:31.88273Z","caller":"traceutil/trace.go:171","msg":"trace[1175043277] transaction","detail":"{read_only:false; response_revision:7851; number_of_response:1; }","duration":"304.562666ms","start":"2026-06-04T12:13:31.578148Z","end":"2026-06-04T12:13:31.882711Z","steps":["trace[1175043277] 'process raft request' (duration: 302.222813ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:13:31.882777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:13:31.573108Z","time spent":"309.655695ms","remote":"127.0.0.1:56256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":581,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-06-04T12:13:31.882846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:13:31.578127Z","time spent":"304.656978ms","remote":"127.0.0.1:56256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T12:13:31.882665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.822921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T12:13:31.883063Z","caller":"traceutil/trace.go:171","msg":"trace[1184842209] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:7850; }","duration":"458.247242ms","start":"2026-06-04T12:13:31.424759Z","end":"2026-06-04T12:13:31.883006Z","steps":["trace[1184842209] 'count revisions from in-memory index tree' (duration: 457.675316ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:13:31.883143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:13:31.424744Z","time spent":"458.378035ms","remote":"127.0.0.1:52564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"warn","ts":"2026-06-04T12:13:32.818267Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011513943378342568,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-04T12:13:33.321549Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011513943378342568,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-04T12:13:33.821963Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011513943378342568,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-04T12:13:34.322331Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011513943378342568,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-04T12:13:34.542235Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.250194472s","expected-duration":"1s"} {"level":"info","ts":"2026-06-04T12:13:34.542544Z","caller":"traceutil/trace.go:171","msg":"trace[416162956] transaction","detail":"{read_only:false; response_revision:7855; number_of_response:1; }","duration":"2.250609134s","start":"2026-06-04T12:13:32.291914Z","end":"2026-06-04T12:13:34.542524Z","steps":["trace[416162956] 'process raft request' (duration: 2.2504763s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:13:34.542678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:13:32.291888Z","time spent":"2.250718886s","remote":"127.0.0.1:56256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:13:34.546602Z","caller":"traceutil/trace.go:171","msg":"trace[1361514554] transaction","detail":"{read_only:false; response_revision:7859; number_of_response:1; }","duration":"658.569675ms","start":"2026-06-04T12:13:33.888003Z","end":"2026-06-04T12:13:34.546573Z","steps":["trace[1361514554] 'process raft request' (duration: 658.490253ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:13:34.546685Z","caller":"traceutil/trace.go:171","msg":"trace[201043410] transaction","detail":"{read_only:false; response_revision:7856; number_of_response:1; }","duration":"2.212408715s","start":"2026-06-04T12:13:32.334248Z","end":"2026-06-04T12:13:34.546657Z","steps":["trace[201043410] 'process raft request' (duration: 2.211995114s)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:13:34.546767Z","caller":"traceutil/trace.go:171","msg":"trace[1872916705] transaction","detail":"{read_only:false; response_revision:7857; number_of_response:1; }","duration":"2.169995303s","start":"2026-06-04T12:13:32.376732Z","end":"2026-06-04T12:13:34.546727Z","steps":["trace[1872916705] 'process raft request' (duration: 2.169657874s)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:13:34.546873Z","caller":"traceutil/trace.go:171","msg":"trace[1543326491] linearizableReadLoop","detail":"{readStateIndex:8138; appliedIndex:8133; }","duration":"2.22914339s","start":"2026-06-04T12:13:32.317694Z","end":"2026-06-04T12:13:34.546837Z","steps":["trace[1543326491] 'read index received' (duration: 2.22507231s)","trace[1543326491] 'applied index is now lower than readState.Index' (duration: 4.06677ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:13:34.546962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:13:32.376714Z","time spent":"2.170155048s","remote":"127.0.0.1:56256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:13:34.547019Z","caller":"traceutil/trace.go:171","msg":"trace[842919371] transaction","detail":"{read_only:false; response_revision:7860; number_of_response:1; }","duration":"653.784905ms","start":"2026-06-04T12:13:33.893218Z","end":"2026-06-04T12:13:34.547003Z","steps":["trace[842919371] 'process raft request' (duration: 653.314462ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:13:34.546805Z","caller":"traceutil/trace.go:171","msg":"trace[527534668] transaction","detail":"{read_only:false; response_revision:7858; number_of_response:1; }","duration":"1.050375101s","start":"2026-06-04T12:13:33.496406Z","end":"2026-06-04T12:13:34.546781Z","steps":["trace[527534668] 'process raft request' (duration: 1.050038282s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:13:34.547106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:13:32.33423Z","time spent":"2.212511239s","remote":"127.0.0.1:56212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12692,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T12:13:34.547113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:13:33.893198Z","time spent":"653.858147ms","remote":"127.0.0.1:56256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T12:13:34.54716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:13:33.496377Z","time spent":"1.050753031s","remote":"127.0.0.1:56256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T12:13:34.547336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"890.21168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-04T12:13:34.547357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.121267259s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-04T12:13:34.54734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:13:33.887994Z","time spent":"658.678768ms","remote":"127.0.0.1:56256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:13:34.547414Z","caller":"traceutil/trace.go:171","msg":"trace[963990568] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:7860; }","duration":"2.121347211s","start":"2026-06-04T12:13:32.426049Z","end":"2026-06-04T12:13:34.547396Z","steps":["trace[963990568] 'agreement among raft nodes before linearized reading' (duration: 2.121257219s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:13:34.547466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:13:32.426039Z","time spent":"2.121412743s","remote":"127.0.0.1:46912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":28,"request content":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true "} {"level":"warn","ts":"2026-06-04T12:13:34.547486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.229793799s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/barbican-ks-endpoints-4tbx9\" ","response":"range_response_count:1 size:15778"} {"level":"info","ts":"2026-06-04T12:13:34.547512Z","caller":"traceutil/trace.go:171","msg":"trace[776608983] range","detail":"{range_begin:/registry/pods/openstack/barbican-ks-endpoints-4tbx9; range_end:; response_count:1; response_revision:7860; }","duration":"2.22986042s","start":"2026-06-04T12:13:32.317644Z","end":"2026-06-04T12:13:34.547504Z","steps":["trace[776608983] 'agreement among raft nodes before linearized reading' (duration: 2.229759978s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:13:34.547538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:13:32.317622Z","time spent":"2.229910191s","remote":"127.0.0.1:56182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":15801,"request content":"key:\"/registry/pods/openstack/barbican-ks-endpoints-4tbx9\" "} {"level":"info","ts":"2026-06-04T12:13:34.547372Z","caller":"traceutil/trace.go:171","msg":"trace[451810749] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:7860; }","duration":"890.272771ms","start":"2026-06-04T12:13:33.657086Z","end":"2026-06-04T12:13:34.547359Z","steps":["trace[451810749] 'agreement among raft nodes before linearized reading' (duration: 890.21775ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:13:34.547632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:13:33.657071Z","time spent":"890.533398ms","remote":"127.0.0.1:52632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"warn","ts":"2026-06-04T12:13:34.547748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.636347323s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-04T12:13:34.547767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"986.423273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.22\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-06-04T12:13:34.54779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.568777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T12:13:34.547795Z","caller":"traceutil/trace.go:171","msg":"trace[1112071094] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:7860; }","duration":"1.636442915s","start":"2026-06-04T12:13:32.911338Z","end":"2026-06-04T12:13:34.547781Z","steps":["trace[1112071094] 'agreement among raft nodes before linearized reading' (duration: 1.636344182s)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:13:34.547825Z","caller":"traceutil/trace.go:171","msg":"trace[521392888] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:7860; }","duration":"187.637529ms","start":"2026-06-04T12:13:34.360179Z","end":"2026-06-04T12:13:34.547816Z","steps":["trace[521392888] 'agreement among raft nodes before linearized reading' (duration: 187.584897ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:13:34.547818Z","caller":"traceutil/trace.go:171","msg":"trace[31540813] range","detail":"{range_begin:/registry/masterleases/199.204.45.22; range_end:; response_count:1; response_revision:7860; }","duration":"986.513525ms","start":"2026-06-04T12:13:33.561288Z","end":"2026-06-04T12:13:34.547802Z","steps":["trace[31540813] 'agreement among raft nodes before linearized reading' (duration: 986.251608ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:13:34.547862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:13:32.911324Z","time spent":"1.636501667s","remote":"127.0.0.1:39650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-06-04T12:13:34.547839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.499451323s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-06-04T12:13:34.547879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:13:33.561269Z","time spent":"986.598317ms","remote":"127.0.0.1:56040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.22\" "} {"level":"info","ts":"2026-06-04T12:13:34.547931Z","caller":"traceutil/trace.go:171","msg":"trace[492899533] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7860; }","duration":"1.499584586s","start":"2026-06-04T12:13:33.048323Z","end":"2026-06-04T12:13:34.547907Z","steps":["trace[492899533] 'agreement among raft nodes before linearized reading' (duration: 1.499215646s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:13:34.54799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:13:33.048308Z","time spent":"1.499668999s","remote":"127.0.0.1:56256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-04T12:13:34.548015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.419447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-gf8m5\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-06-04T12:13:34.548046Z","caller":"traceutil/trace.go:171","msg":"trace[1364300955] range","detail":"{range_begin:/registry/pods/openstack/keepalived-gf8m5; range_end:; response_count:1; response_revision:7860; }","duration":"518.481169ms","start":"2026-06-04T12:13:34.029555Z","end":"2026-06-04T12:13:34.548037Z","steps":["trace[1364300955] 'agreement among raft nodes before linearized reading' (duration: 518.393026ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:13:34.54807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:13:34.029541Z","time spent":"518.52429ms","remote":"127.0.0.1:56182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6543,"request content":"key:\"/registry/pods/openstack/keepalived-gf8m5\" "} {"level":"warn","ts":"2026-06-04T12:13:34.54808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.043207578s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-04T12:13:34.548131Z","caller":"traceutil/trace.go:171","msg":"trace[2142982280] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:7860; }","duration":"2.043321181s","start":"2026-06-04T12:13:32.504795Z","end":"2026-06-04T12:13:34.548116Z","steps":["trace[2142982280] 'agreement among raft nodes before linearized reading' (duration: 2.043235189s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:13:34.548152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.19270147s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"warn","ts":"2026-06-04T12:13:34.548177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:13:32.504774Z","time spent":"2.043388893s","remote":"127.0.0.1:56478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":4,"response size":30,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-06-04T12:13:34.548203Z","caller":"traceutil/trace.go:171","msg":"trace[1148622169] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:7860; }","duration":"2.192802713s","start":"2026-06-04T12:13:32.355387Z","end":"2026-06-04T12:13:34.54819Z","steps":["trace[1148622169] 'agreement among raft nodes before linearized reading' (duration: 2.191866898s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:13:34.548249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:13:32.355365Z","time spent":"2.192875394s","remote":"127.0.0.1:56182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-06-04T12:13:34.548244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.615873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-06-04T12:13:34.548257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.167309991s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-06-04T12:13:34.548291Z","caller":"traceutil/trace.go:171","msg":"trace[2118660579] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7860; }","duration":"574.692205ms","start":"2026-06-04T12:13:33.973585Z","end":"2026-06-04T12:13:34.548277Z","steps":["trace[2118660579] 'agreement among raft nodes before linearized reading' (duration: 574.599793ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:13:34.5483Z","caller":"traceutil/trace.go:171","msg":"trace[1385123031] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7860; }","duration":"2.167373093s","start":"2026-06-04T12:13:32.380913Z","end":"2026-06-04T12:13:34.548286Z","steps":["trace[1385123031] 'agreement among raft nodes before linearized reading' (duration: 2.167247229s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:13:34.548333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:13:32.380902Z","time spent":"2.167423124s","remote":"127.0.0.1:56256","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-06-04T12:13:34.548333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:13:33.973568Z","time spent":"574.752808ms","remote":"127.0.0.1:56256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-04T12:13:34.548426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.12534345s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-04T12:13:34.548437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.485031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-04T12:13:34.548456Z","caller":"traceutil/trace.go:171","msg":"trace[494573045] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:7860; }","duration":"2.125476694s","start":"2026-06-04T12:13:32.422968Z","end":"2026-06-04T12:13:34.548445Z","steps":["trace[494573045] 'agreement among raft nodes before linearized reading' (duration: 2.125430973s)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:13:34.548466Z","caller":"traceutil/trace.go:171","msg":"trace[1449107163] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:7860; }","duration":"623.552373ms","start":"2026-06-04T12:13:33.924904Z","end":"2026-06-04T12:13:34.548456Z","steps":["trace[1449107163] 'agreement among raft nodes before linearized reading' (duration: 623.503682ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:13:34.548489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:13:32.422952Z","time spent":"2.125528435s","remote":"127.0.0.1:56210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-06-04T12:13:34.548502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:13:33.924888Z","time spent":"623.601935ms","remote":"127.0.0.1:56382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":30,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-06-04T12:13:34.548548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.167913546s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-06-04T12:13:34.548583Z","caller":"traceutil/trace.go:171","msg":"trace[470914715] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7860; }","duration":"2.167982538s","start":"2026-06-04T12:13:32.380591Z","end":"2026-06-04T12:13:34.548574Z","steps":["trace[470914715] 'agreement among raft nodes before linearized reading' (duration: 2.167879166s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:13:34.548608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:13:32.380574Z","time spent":"2.16802787s","remote":"127.0.0.1:56256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-04T12:13:34.548842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.457127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/barbican-ks-endpoints\" ","response":"range_response_count:1 size:12707"} {"level":"info","ts":"2026-06-04T12:13:34.548886Z","caller":"traceutil/trace.go:171","msg":"trace[2011584388] range","detail":"{range_begin:/registry/jobs/openstack/barbican-ks-endpoints; range_end:; response_count:1; response_revision:7860; }","duration":"656.51739ms","start":"2026-06-04T12:13:33.892356Z","end":"2026-06-04T12:13:34.548874Z","steps":["trace[2011584388] 'agreement among raft nodes before linearized reading' (duration: 656.384486ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:13:34.548922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:13:33.892349Z","time spent":"656.564801ms","remote":"127.0.0.1:56212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":12730,"request content":"key:\"/registry/jobs/openstack/barbican-ks-endpoints\" "} {"level":"warn","ts":"2026-06-04T12:13:34.548948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.064503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/barbican-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T12:13:34.549001Z","caller":"traceutil/trace.go:171","msg":"trace[87055290] range","detail":"{range_begin:/registry/jobs/openstack/barbican-ks-user; range_end:; response_count:0; response_revision:7860; }","duration":"661.171516ms","start":"2026-06-04T12:13:33.887821Z","end":"2026-06-04T12:13:34.548992Z","steps":["trace[87055290] 'agreement among raft nodes before linearized reading' (duration: 661.091194ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:13:34.549028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:13:33.887804Z","time spent":"661.215697ms","remote":"127.0.0.1:56212","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-06-04T12:13:34.549085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"731.952279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T12:13:34.549126Z","caller":"traceutil/trace.go:171","msg":"trace[1988929897] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7860; }","duration":"732.053932ms","start":"2026-06-04T12:13:33.81706Z","end":"2026-06-04T12:13:34.549114Z","steps":["trace[1988929897] 'agreement among raft nodes before linearized reading' (duration: 731.96735ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:13:34.549154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:13:33.817041Z","time spent":"732.106203ms","remote":"127.0.0.1:56002","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-06-04T12:13:34.549939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.169399287s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-06-04T12:13:34.550008Z","caller":"traceutil/trace.go:171","msg":"trace[419824421] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7860; }","duration":"2.170707163s","start":"2026-06-04T12:13:32.379282Z","end":"2026-06-04T12:13:34.549989Z","steps":["trace[419824421] 'agreement among raft nodes before linearized reading' (duration: 2.169272794s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:13:34.550054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:13:32.379242Z","time spent":"2.170798555s","remote":"127.0.0.1:56256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-04T12:14:10.617938Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6034} {"level":"info","ts":"2026-06-04T12:14:10.716406Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6034,"took":"95.537923ms","hash":3745679070,"current-db-size-bytes":57225216,"current-db-size":"57 MB","current-db-size-in-use-bytes":21311488,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-06-04T12:14:10.716461Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3745679070,"revision":6034,"compact-revision":2963} {"level":"info","ts":"2026-06-04T12:14:24.058487Z","caller":"traceutil/trace.go:171","msg":"trace[729379436] linearizableReadLoop","detail":"{readStateIndex:8619; appliedIndex:8618; }","duration":"494.085284ms","start":"2026-06-04T12:14:23.564382Z","end":"2026-06-04T12:14:24.058467Z","steps":["trace[729379436] 'read index received' (duration: 493.876098ms)","trace[729379436] 'applied index is now lower than readState.Index' (duration: 208.506µs)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:14:24.058588Z","caller":"traceutil/trace.go:171","msg":"trace[884845466] transaction","detail":"{read_only:false; response_revision:8325; number_of_response:1; }","duration":"829.413186ms","start":"2026-06-04T12:14:23.229166Z","end":"2026-06-04T12:14:24.058579Z","steps":["trace[884845466] 'process raft request' (duration: 829.171469ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:14:24.058672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:14:23.229153Z","time spent":"829.454728ms","remote":"127.0.0.1:56082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1116,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T12:14:24.058971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.936218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T12:14:24.059078Z","caller":"traceutil/trace.go:171","msg":"trace[1537877945] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8325; }","duration":"242.082312ms","start":"2026-06-04T12:14:23.81698Z","end":"2026-06-04T12:14:24.059062Z","steps":["trace[1537877945] 'agreement among raft nodes before linearized reading' (duration: 241.919428ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:14:24.059185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.781243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.22\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-06-04T12:14:24.059272Z","caller":"traceutil/trace.go:171","msg":"trace[948171127] range","detail":"{range_begin:/registry/masterleases/199.204.45.22; range_end:; response_count:1; response_revision:8325; }","duration":"494.892656ms","start":"2026-06-04T12:14:23.564346Z","end":"2026-06-04T12:14:24.059239Z","steps":["trace[948171127] 'agreement among raft nodes before linearized reading' (duration: 494.533456ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:14:24.059318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:14:23.56433Z","time spent":"494.979098ms","remote":"127.0.0.1:56040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.22\" "} {"level":"info","ts":"2026-06-04T12:14:32.961669Z","caller":"traceutil/trace.go:171","msg":"trace[1897462728] transaction","detail":"{read_only:false; response_revision:8368; number_of_response:1; }","duration":"191.22875ms","start":"2026-06-04T12:14:32.770422Z","end":"2026-06-04T12:14:32.96165Z","steps":["trace[1897462728] 'process raft request' (duration: 191.087646ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:14:33.015735Z","caller":"traceutil/trace.go:171","msg":"trace[1760132254] transaction","detail":"{read_only:false; response_revision:8369; number_of_response:1; }","duration":"231.531973ms","start":"2026-06-04T12:14:32.784182Z","end":"2026-06-04T12:14:33.015714Z","steps":["trace[1760132254] 'process raft request' (duration: 231.368789ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:14:33.016024Z","caller":"traceutil/trace.go:171","msg":"trace[315894581] transaction","detail":"{read_only:false; response_revision:8370; number_of_response:1; }","duration":"231.376089ms","start":"2026-06-04T12:14:32.784628Z","end":"2026-06-04T12:14:33.016004Z","steps":["trace[315894581] 'process raft request' (duration: 231.05238ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:14:33.016089Z","caller":"traceutil/trace.go:171","msg":"trace[108445782] linearizableReadLoop","detail":"{readStateIndex:8667; appliedIndex:8663; }","duration":"213.023777ms","start":"2026-06-04T12:14:32.803038Z","end":"2026-06-04T12:14:33.016062Z","steps":["trace[108445782] 'read index received' (duration: 158.485625ms)","trace[108445782] 'applied index is now lower than readState.Index' (duration: 54.536852ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:14:33.016133Z","caller":"traceutil/trace.go:171","msg":"trace[1350710419] transaction","detail":"{read_only:false; response_revision:8371; number_of_response:1; }","duration":"229.042105ms","start":"2026-06-04T12:14:32.787062Z","end":"2026-06-04T12:14:33.016104Z","steps":["trace[1350710419] 'process raft request' (duration: 228.88213ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:14:33.016294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.250273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-04T12:14:33.016339Z","caller":"traceutil/trace.go:171","msg":"trace[952544979] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8371; }","duration":"213.348746ms","start":"2026-06-04T12:14:32.802965Z","end":"2026-06-04T12:14:33.016314Z","steps":["trace[952544979] 'agreement among raft nodes before linearized reading' (duration: 213.211502ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:14:33.016351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.340444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-06-04T12:14:33.01636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.624411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-06-04T12:14:33.016403Z","caller":"traceutil/trace.go:171","msg":"trace[1301895221] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8371; }","duration":"172.452448ms","start":"2026-06-04T12:14:32.843936Z","end":"2026-06-04T12:14:33.016388Z","steps":["trace[1301895221] 'agreement among raft nodes before linearized reading' (duration: 172.276753ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:14:33.016412Z","caller":"traceutil/trace.go:171","msg":"trace[295899058] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8371; }","duration":"210.699273ms","start":"2026-06-04T12:14:32.805699Z","end":"2026-06-04T12:14:33.016398Z","steps":["trace[295899058] 'agreement among raft nodes before linearized reading' (duration: 210.512248ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:14:33.016433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.719438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-06-04T12:14:33.016467Z","caller":"traceutil/trace.go:171","msg":"trace[1448482631] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8371; }","duration":"178.818652ms","start":"2026-06-04T12:14:32.837642Z","end":"2026-06-04T12:14:33.01646Z","steps":["trace[1448482631] 'agreement among raft nodes before linearized reading' (duration: 178.679598ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:14:33.016537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.509759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-06-04T12:14:33.016578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.424355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T12:14:33.016595Z","caller":"traceutil/trace.go:171","msg":"trace[27024241] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8371; }","duration":"172.590181ms","start":"2026-06-04T12:14:32.84399Z","end":"2026-06-04T12:14:33.01658Z","steps":["trace[27024241] 'agreement among raft nodes before linearized reading' (duration: 172.435627ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:14:33.016626Z","caller":"traceutil/trace.go:171","msg":"trace[1192194654] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8371; }","duration":"199.548319ms","start":"2026-06-04T12:14:32.817063Z","end":"2026-06-04T12:14:33.016611Z","steps":["trace[1192194654] 'agreement among raft nodes before linearized reading' (duration: 199.472297ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:14:54.066199Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011513943378344190,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-04T12:14:54.47348Z","caller":"traceutil/trace.go:171","msg":"trace[599577026] linearizableReadLoop","detail":"{readStateIndex:8789; appliedIndex:8788; }","duration":"907.574115ms","start":"2026-06-04T12:14:53.565885Z","end":"2026-06-04T12:14:54.473459Z","steps":["trace[599577026] 'read index received' (duration: 907.39228ms)","trace[599577026] 'applied index is now lower than readState.Index' (duration: 180.925µs)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:14:54.473593Z","caller":"traceutil/trace.go:171","msg":"trace[876478335] transaction","detail":"{read_only:false; response_revision:8489; number_of_response:1; }","duration":"919.45712ms","start":"2026-06-04T12:14:53.554101Z","end":"2026-06-04T12:14:54.473558Z","steps":["trace[876478335] 'process raft request' (duration: 919.233304ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:14:54.473694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"907.798942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.22\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-06-04T12:14:54.473733Z","caller":"traceutil/trace.go:171","msg":"trace[2119007871] range","detail":"{range_begin:/registry/masterleases/199.204.45.22; range_end:; response_count:1; response_revision:8489; }","duration":"907.885464ms","start":"2026-06-04T12:14:53.565837Z","end":"2026-06-04T12:14:54.473722Z","steps":["trace[2119007871] 'agreement among raft nodes before linearized reading' (duration: 907.74415ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:14:54.47377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:14:53.565821Z","time spent":"907.940876ms","remote":"127.0.0.1:56040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.22\" "} {"level":"warn","ts":"2026-06-04T12:14:54.47377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:14:53.554068Z","time spent":"919.617025ms","remote":"127.0.0.1:56170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":19367,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T12:14:54.473994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"813.628906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-04T12:14:54.474055Z","caller":"traceutil/trace.go:171","msg":"trace[605715706] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:8489; }","duration":"813.723738ms","start":"2026-06-04T12:14:53.660316Z","end":"2026-06-04T12:14:54.474039Z","steps":["trace[605715706] 'agreement among raft nodes before linearized reading' (duration: 813.604285ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:14:54.47409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:14:53.660286Z","time spent":"813.79389ms","remote":"127.0.0.1:56508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":43,"response size":30,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-06-04T12:14:54.474158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.688475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T12:14:54.474211Z","caller":"traceutil/trace.go:171","msg":"trace[1969838306] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8489; }","duration":"659.764336ms","start":"2026-06-04T12:14:53.814432Z","end":"2026-06-04T12:14:54.474197Z","steps":["trace[1969838306] 'agreement among raft nodes before linearized reading' (duration: 659.693715ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:14:54.47426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:14:53.814412Z","time spent":"659.833619ms","remote":"127.0.0.1:56002","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-06-04T12:14:59.646594Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011513943378344403,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-04T12:15:00.147678Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011513943378344403,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-04T12:15:00.422431Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.280238157s","expected-duration":"1s"} {"level":"warn","ts":"2026-06-04T12:15:00.947267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.521967ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-04T12:15:00.947456Z","caller":"traceutil/trace.go:171","msg":"trace[1698108351] transaction","detail":"{read_only:false; response_revision:8551; number_of_response:1; }","duration":"279.458394ms","start":"2026-06-04T12:15:00.66798Z","end":"2026-06-04T12:15:00.947439Z","steps":["trace[1698108351] 'process raft request' (duration: 279.426493ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:15:00.947473Z","caller":"traceutil/trace.go:171","msg":"trace[1108490360] transaction","detail":"{read_only:false; response_revision:8549; number_of_response:1; }","duration":"1.805326621s","start":"2026-06-04T12:14:59.142095Z","end":"2026-06-04T12:15:00.947422Z","steps":["trace[1108490360] 'process raft request' (duration: 1.280558337s)","trace[1108490360] 'compare' (duration: 524.416184ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:15:00.947518Z","caller":"traceutil/trace.go:171","msg":"trace[1956227841] transaction","detail":"{read_only:false; response_revision:8550; number_of_response:1; }","duration":"1.805185837s","start":"2026-06-04T12:14:59.142283Z","end":"2026-06-04T12:15:00.947469Z","steps":["trace[1956227841] 'process raft request' (duration: 1.805075104s)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:15:00.94761Z","caller":"traceutil/trace.go:171","msg":"trace[1681026407] linearizableReadLoop","detail":"{readStateIndex:8854; appliedIndex:8852; }","duration":"1.802042941s","start":"2026-06-04T12:14:59.145558Z","end":"2026-06-04T12:15:00.947601Z","steps":["trace[1681026407] 'read index received' (duration: 1.277110692s)","trace[1681026407] 'applied index is now lower than readState.Index' (duration: 524.931409ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:15:00.947642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:14:59.142071Z","time spent":"1.805484705s","remote":"127.0.0.1:56256","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-06-04T12:15:00.94771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:14:59.142264Z","time spent":"1.805340581s","remote":"127.0.0.1:56256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T12:15:00.947726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.790437124s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T12:15:00.94775Z","caller":"traceutil/trace.go:171","msg":"trace[223527962] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8551; }","duration":"1.790486125s","start":"2026-06-04T12:14:59.157257Z","end":"2026-06-04T12:15:00.947743Z","steps":["trace[223527962] 'agreement among raft nodes before linearized reading' (duration: 1.790424274s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:15:00.947772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:14:59.157246Z","time spent":"1.790518696s","remote":"127.0.0.1:56002","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-06-04T12:15:00.947841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.557112832s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-detect-version\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-04T12:15:00.947885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.028139213s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T12:15:00.94792Z","caller":"traceutil/trace.go:171","msg":"trace[1660810883] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:8551; }","duration":"1.028207505s","start":"2026-06-04T12:14:59.9197Z","end":"2026-06-04T12:15:00.947907Z","steps":["trace[1660810883] 'agreement among raft nodes before linearized reading' (duration: 1.028133183s)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:15:00.947916Z","caller":"traceutil/trace.go:171","msg":"trace[197784611] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-detect-version; range_end:; response_count:0; response_revision:8551; }","duration":"1.557231355s","start":"2026-06-04T12:14:59.390666Z","end":"2026-06-04T12:15:00.947897Z","steps":["trace[197784611] 'agreement among raft nodes before linearized reading' (duration: 1.557094141s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:15:00.947951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:14:59.919682Z","time spent":"1.028262207s","remote":"127.0.0.1:45746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true "} {"level":"warn","ts":"2026-06-04T12:15:00.947957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:14:59.390649Z","time spent":"1.557302196s","remote":"127.0.0.1:56112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":0,"response size":28,"request content":"key:\"/registry/configmaps/openstack/rook-ceph-detect-version\" "} {"level":"warn","ts":"2026-06-04T12:15:00.947985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.757976156s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rook-ceph-mon\" ","response":"range_response_count:1 size:713"} {"level":"info","ts":"2026-06-04T12:15:00.948013Z","caller":"traceutil/trace.go:171","msg":"trace[165840319] range","detail":"{range_begin:/registry/secrets/openstack/rook-ceph-mon; range_end:; response_count:1; response_revision:8551; }","duration":"1.758028447s","start":"2026-06-04T12:14:59.189976Z","end":"2026-06-04T12:15:00.948005Z","steps":["trace[165840319] 'agreement among raft nodes before linearized reading' (duration: 1.757966405s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:15:00.948032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:14:59.18996Z","time spent":"1.758067978s","remote":"127.0.0.1:56104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":736,"request content":"key:\"/registry/secrets/openstack/rook-ceph-mon\" "} {"level":"warn","ts":"2026-06-04T12:15:00.948158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.130457362s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-04T12:15:00.948192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.911531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T12:15:00.948206Z","caller":"traceutil/trace.go:171","msg":"trace[37326774] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8551; }","duration":"1.130539094s","start":"2026-06-04T12:14:59.81766Z","end":"2026-06-04T12:15:00.948199Z","steps":["trace[37326774] 'agreement among raft nodes before linearized reading' (duration: 1.130477242s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:15:00.948225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:14:59.81764Z","time spent":"1.130581456s","remote":"127.0.0.1:55996","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-06-04T12:15:00.94824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.765157092s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-04T12:15:00.948264Z","caller":"traceutil/trace.go:171","msg":"trace[162074556] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8551; }","duration":"1.765228075s","start":"2026-06-04T12:14:59.183029Z","end":"2026-06-04T12:15:00.948257Z","steps":["trace[162074556] 'agreement among raft nodes before linearized reading' (duration: 1.765159793s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:15:00.948269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.013703309s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-04T12:15:00.948293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:14:59.183003Z","time spent":"1.765285945s","remote":"127.0.0.1:56256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-04T12:15:00.948304Z","caller":"traceutil/trace.go:171","msg":"trace[300939925] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:8551; }","duration":"1.013779221s","start":"2026-06-04T12:14:59.934516Z","end":"2026-06-04T12:15:00.948295Z","steps":["trace[300939925] 'agreement among raft nodes before linearized reading' (duration: 1.013725089s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:15:00.948331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:14:59.934494Z","time spent":"1.013827791s","remote":"127.0.0.1:52580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-06-04T12:15:00.948367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.735612484s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-gf8m5\" ","response":"range_response_count:1 size:6520"} {"level":"warn","ts":"2026-06-04T12:15:00.948391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.357939104s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-06-04T12:15:00.94841Z","caller":"traceutil/trace.go:171","msg":"trace[928520258] range","detail":"{range_begin:/registry/pods/openstack/keepalived-gf8m5; range_end:; response_count:1; response_revision:8551; }","duration":"1.735676195s","start":"2026-06-04T12:14:59.212722Z","end":"2026-06-04T12:15:00.948398Z","steps":["trace[928520258] 'agreement among raft nodes before linearized reading' (duration: 1.735522031s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:15:00.948362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.740760275s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-06-04T12:15:00.948454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.456991073s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-04T12:15:00.948454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:14:59.21271Z","time spent":"1.735737188s","remote":"127.0.0.1:56182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6543,"request content":"key:\"/registry/pods/openstack/keepalived-gf8m5\" "} {"level":"info","ts":"2026-06-04T12:15:00.948473Z","caller":"traceutil/trace.go:171","msg":"trace[850111835] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:8551; }","duration":"1.457025383s","start":"2026-06-04T12:14:59.491442Z","end":"2026-06-04T12:15:00.948468Z","steps":["trace[850111835] 'agreement among raft nodes before linearized reading' (duration: 1.456988092s)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:15:00.948415Z","caller":"traceutil/trace.go:171","msg":"trace[801423435] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:8551; }","duration":"1.357980066s","start":"2026-06-04T12:14:59.590428Z","end":"2026-06-04T12:15:00.948409Z","steps":["trace[801423435] 'agreement among raft nodes before linearized reading' (duration: 1.357886833s)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:15:00.948504Z","caller":"traceutil/trace.go:171","msg":"trace[451668299] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8551; }","duration":"1.740933919s","start":"2026-06-04T12:14:59.207533Z","end":"2026-06-04T12:15:00.948467Z","steps":["trace[451668299] 'agreement among raft nodes before linearized reading' (duration: 1.740437466s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:15:00.948585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:14:59.207521Z","time spent":"1.741048193s","remote":"127.0.0.1:56256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-04T12:15:00.94822Z","caller":"traceutil/trace.go:171","msg":"trace[275301129] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8551; }","duration":"504.973582ms","start":"2026-06-04T12:15:00.443238Z","end":"2026-06-04T12:15:00.948212Z","steps":["trace[275301129] 'agreement among raft nodes before linearized reading' (duration: 504.917061ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:15:00.948678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:15:00.443217Z","time spent":"505.454626ms","remote":"127.0.0.1:56002","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-06-04T12:15:00.948524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:14:59.59042Z","time spent":"1.358095509s","remote":"127.0.0.1:56256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":553,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-06-04T12:15:00.948697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.74609552s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-04T12:15:00.948719Z","caller":"traceutil/trace.go:171","msg":"trace[1184524397] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8551; }","duration":"1.746142102s","start":"2026-06-04T12:14:59.202571Z","end":"2026-06-04T12:15:00.948713Z","steps":["trace[1184524397] 'agreement among raft nodes before linearized reading' (duration: 1.74607563s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:15:00.948745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:14:59.202559Z","time spent":"1.746180713s","remote":"127.0.0.1:56256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-04T12:15:00.947928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.755193399s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-06-04T12:15:00.948498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:14:59.491435Z","time spent":"1.457058044s","remote":"127.0.0.1:56466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":10,"response size":30,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-06-04T12:15:00.948777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.750060309s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-04T12:15:00.948866Z","caller":"traceutil/trace.go:171","msg":"trace[371108903] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8551; }","duration":"1.756073814s","start":"2026-06-04T12:14:59.192729Z","end":"2026-06-04T12:15:00.948802Z","steps":["trace[371108903] 'agreement among raft nodes before linearized reading' (duration: 1.755136068s)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:15:00.948987Z","caller":"traceutil/trace.go:171","msg":"trace[1424143024] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8551; }","duration":"1.750408609s","start":"2026-06-04T12:14:59.198517Z","end":"2026-06-04T12:15:00.948925Z","steps":["trace[1424143024] 'agreement among raft nodes before linearized reading' (duration: 1.74935222s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:15:00.949002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:14:59.192685Z","time spent":"1.756278239s","remote":"127.0.0.1:56256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-04T12:15:00.94854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.802954305s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3684"} {"level":"info","ts":"2026-06-04T12:15:00.949126Z","caller":"traceutil/trace.go:171","msg":"trace[1662937952] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:8551; }","duration":"1.803579563s","start":"2026-06-04T12:14:59.145532Z","end":"2026-06-04T12:15:00.949112Z","steps":["trace[1662937952] 'agreement among raft nodes before linearized reading' (duration: 1.802143094s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:15:00.949163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:14:59.145525Z","time spent":"1.803628214s","remote":"127.0.0.1:56104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":3707,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" "} {"level":"warn","ts":"2026-06-04T12:15:00.949099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:14:59.198499Z","time spent":"1.750584313s","remote":"127.0.0.1:56256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-04T12:15:07.132308Z","caller":"traceutil/trace.go:171","msg":"trace[423582187] linearizableReadLoop","detail":"{readStateIndex:8906; appliedIndex:8905; }","duration":"120.145366ms","start":"2026-06-04T12:15:07.01213Z","end":"2026-06-04T12:15:07.132276Z","steps":["trace[423582187] 'read index received' (duration: 109.932917ms)","trace[423582187] 'applied index is now lower than readState.Index' (duration: 10.211139ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:15:07.13244Z","caller":"traceutil/trace.go:171","msg":"trace[1586323313] transaction","detail":"{read_only:false; response_revision:8600; number_of_response:1; }","duration":"120.928777ms","start":"2026-06-04T12:15:07.011482Z","end":"2026-06-04T12:15:07.13241Z","steps":["trace[1586323313] 'process raft request' (duration: 110.571144ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:15:07.132723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.573958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228830"} {"level":"info","ts":"2026-06-04T12:15:07.132769Z","caller":"traceutil/trace.go:171","msg":"trace[985521460] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8600; }","duration":"120.63528ms","start":"2026-06-04T12:15:07.012124Z","end":"2026-06-04T12:15:07.132759Z","steps":["trace[985521460] 'agreement among raft nodes before linearized reading' (duration: 120.27458ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:15:07.148226Z","caller":"traceutil/trace.go:171","msg":"trace[1695192228] transaction","detail":"{read_only:false; response_revision:8601; number_of_response:1; }","duration":"136.021101ms","start":"2026-06-04T12:15:07.012187Z","end":"2026-06-04T12:15:07.148208Z","steps":["trace[1695192228] 'process raft request' (duration: 135.898037ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:15:07.148492Z","caller":"traceutil/trace.go:171","msg":"trace[276806966] transaction","detail":"{read_only:false; response_revision:8602; number_of_response:1; }","duration":"136.154284ms","start":"2026-06-04T12:15:07.012303Z","end":"2026-06-04T12:15:07.148457Z","steps":["trace[276806966] 'process raft request' (duration: 135.875727ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:15:07.148539Z","caller":"traceutil/trace.go:171","msg":"trace[2111996810] transaction","detail":"{read_only:false; response_revision:8603; number_of_response:1; }","duration":"135.828265ms","start":"2026-06-04T12:15:07.01269Z","end":"2026-06-04T12:15:07.148519Z","steps":["trace[2111996810] 'process raft request' (duration: 135.63253ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:15:07.148588Z","caller":"traceutil/trace.go:171","msg":"trace[580585409] transaction","detail":"{read_only:false; response_revision:8604; number_of_response:1; }","duration":"135.792545ms","start":"2026-06-04T12:15:07.012782Z","end":"2026-06-04T12:15:07.148574Z","steps":["trace[580585409] 'process raft request' (duration: 135.663401ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:15:17.283842Z","caller":"traceutil/trace.go:171","msg":"trace[1294078255] transaction","detail":"{read_only:false; response_revision:8696; number_of_response:1; }","duration":"106.301417ms","start":"2026-06-04T12:15:17.177483Z","end":"2026-06-04T12:15:17.283785Z","steps":["trace[1294078255] 'process raft request' (duration: 105.999529ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:15:17.439555Z","caller":"traceutil/trace.go:171","msg":"trace[692504287] transaction","detail":"{read_only:false; response_revision:8697; number_of_response:1; }","duration":"248.2637ms","start":"2026-06-04T12:15:17.191259Z","end":"2026-06-04T12:15:17.439522Z","steps":["trace[692504287] 'process raft request' (duration: 183.621663ms)","trace[692504287] 'compare' (duration: 64.219826ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:15:17.439836Z","caller":"traceutil/trace.go:171","msg":"trace[399302732] linearizableReadLoop","detail":"{readStateIndex:9010; appliedIndex:9007; }","duration":"156.112089ms","start":"2026-06-04T12:15:17.283687Z","end":"2026-06-04T12:15:17.439799Z","steps":["trace[399302732] 'read index received' (duration: 91.130162ms)","trace[399302732] 'applied index is now lower than readState.Index' (duration: 64.980507ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:15:17.440118Z","caller":"traceutil/trace.go:171","msg":"trace[1818443937] transaction","detail":"{read_only:false; response_revision:8699; number_of_response:1; }","duration":"248.25387ms","start":"2026-06-04T12:15:17.191794Z","end":"2026-06-04T12:15:17.440047Z","steps":["trace[1818443937] 'process raft request' (duration: 247.91391ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:15:17.440194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.855779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-04T12:15:17.440753Z","caller":"traceutil/trace.go:171","msg":"trace[1506929584] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8699; }","duration":"244.429505ms","start":"2026-06-04T12:15:17.196271Z","end":"2026-06-04T12:15:17.440701Z","steps":["trace[1506929584] 'agreement among raft nodes before linearized reading' (duration: 243.728626ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:15:17.440192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.911738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-04T12:15:17.440963Z","caller":"traceutil/trace.go:171","msg":"trace[1908739664] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8699; }","duration":"249.72054ms","start":"2026-06-04T12:15:17.191215Z","end":"2026-06-04T12:15:17.440936Z","steps":["trace[1908739664] 'agreement among raft nodes before linearized reading' (duration: 248.839966ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:15:17.440227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.034051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-84c9b\" ","response":"range_response_count:1 size:4644"} {"level":"info","ts":"2026-06-04T12:15:17.441111Z","caller":"traceutil/trace.go:171","msg":"trace[1320143162] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-84c9b; range_end:; response_count:1; response_revision:8699; }","duration":"249.956566ms","start":"2026-06-04T12:15:17.191141Z","end":"2026-06-04T12:15:17.441097Z","steps":["trace[1320143162] 'agreement among raft nodes before linearized reading' (duration: 248.967919ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:15:17.440215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.377563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-04T12:15:17.441327Z","caller":"traceutil/trace.go:171","msg":"trace[1595421157] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8699; }","duration":"245.521055ms","start":"2026-06-04T12:15:17.195789Z","end":"2026-06-04T12:15:17.44131Z","steps":["trace[1595421157] 'agreement among raft nodes before linearized reading' (duration: 244.24485ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:15:17.440297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.505482ms","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-06-04T12:15:17.4415Z","caller":"traceutil/trace.go:171","msg":"trace[947356331] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:8699; }","duration":"126.723696ms","start":"2026-06-04T12:15:17.31473Z","end":"2026-06-04T12:15:17.441453Z","steps":["trace[947356331] 'agreement among raft nodes before linearized reading' (duration: 125.522203ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:15:17.440277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.273058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-04T12:15:17.44161Z","caller":"traceutil/trace.go:171","msg":"trace[568884022] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8699; }","duration":"246.641106ms","start":"2026-06-04T12:15:17.194947Z","end":"2026-06-04T12:15:17.441588Z","steps":["trace[568884022] 'agreement among raft nodes before linearized reading' (duration: 245.032472ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:15:17.440307Z","caller":"traceutil/trace.go:171","msg":"trace[189265562] transaction","detail":"{read_only:false; response_revision:8698; number_of_response:1; }","duration":"248.325102ms","start":"2026-06-04T12:15:17.191473Z","end":"2026-06-04T12:15:17.439798Z","steps":["trace[189265562] 'process raft request' (duration: 247.985112ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:15:17.440416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.790341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228740"} {"level":"info","ts":"2026-06-04T12:15:17.44192Z","caller":"traceutil/trace.go:171","msg":"trace[1561931119] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8699; }","duration":"153.316653ms","start":"2026-06-04T12:15:17.288578Z","end":"2026-06-04T12:15:17.441895Z","steps":["trace[1561931119] 'agreement among raft nodes before linearized reading' (duration: 151.479243ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:15:17.580954Z","caller":"traceutil/trace.go:171","msg":"trace[2092805758] transaction","detail":"{read_only:false; response_revision:8700; number_of_response:1; }","duration":"134.407807ms","start":"2026-06-04T12:15:17.446512Z","end":"2026-06-04T12:15:17.58092Z","steps":["trace[2092805758] 'process raft request' (duration: 121.628647ms)","trace[2092805758] 'compare' (duration: 12.643626ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:15:17.599749Z","caller":"traceutil/trace.go:171","msg":"trace[1549396284] transaction","detail":"{read_only:false; response_revision:8702; number_of_response:1; }","duration":"153.119378ms","start":"2026-06-04T12:15:17.446606Z","end":"2026-06-04T12:15:17.599725Z","steps":["trace[1549396284] 'process raft request' (duration: 153.055596ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:15:17.599789Z","caller":"traceutil/trace.go:171","msg":"trace[2048309203] transaction","detail":"{read_only:false; response_revision:8701; number_of_response:1; }","duration":"153.18478ms","start":"2026-06-04T12:15:17.446572Z","end":"2026-06-04T12:15:17.599757Z","steps":["trace[2048309203] 'process raft request' (duration: 152.980034ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:15:17.599969Z","caller":"traceutil/trace.go:171","msg":"trace[1879782620] transaction","detail":"{read_only:false; response_revision:8703; number_of_response:1; }","duration":"153.302144ms","start":"2026-06-04T12:15:17.446658Z","end":"2026-06-04T12:15:17.599961Z","steps":["trace[1879782620] 'process raft request' (duration: 153.031626ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:15:17.60006Z","caller":"traceutil/trace.go:171","msg":"trace[1530651185] transaction","detail":"{read_only:false; response_revision:8704; number_of_response:1; }","duration":"151.120803ms","start":"2026-06-04T12:15:17.448931Z","end":"2026-06-04T12:15:17.600052Z","steps":["trace[1530651185] 'process raft request' (duration: 150.976669ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:15:18.588772Z","caller":"traceutil/trace.go:171","msg":"trace[814464033] transaction","detail":"{read_only:false; response_revision:8706; number_of_response:1; }","duration":"260.625279ms","start":"2026-06-04T12:15:18.32812Z","end":"2026-06-04T12:15:18.588745Z","steps":["trace[814464033] 'process raft request' (duration: 260.466385ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:15:34.289654Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023f2.wal"} {"level":"info","ts":"2026-06-04T12:16:33.429535Z","caller":"traceutil/trace.go:171","msg":"trace[1278578353] transaction","detail":"{read_only:false; response_revision:9445; number_of_response:1; }","duration":"110.053623ms","start":"2026-06-04T12:16:33.319461Z","end":"2026-06-04T12:16:33.429514Z","steps":["trace[1278578353] 'process raft request' (duration: 109.906479ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:16:33.440062Z","caller":"traceutil/trace.go:171","msg":"trace[860378976] transaction","detail":"{read_only:false; response_revision:9446; number_of_response:1; }","duration":"104.778068ms","start":"2026-06-04T12:16:33.335265Z","end":"2026-06-04T12:16:33.440043Z","steps":["trace[860378976] 'process raft request' (duration: 104.689646ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:17:03.833697Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"fed19d9291630e09","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-04T12:17:03.839256Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-06-04T12:17:03.839359Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-06-04T12:19:10.626745Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8115} {"level":"info","ts":"2026-06-04T12:19:10.689729Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8115,"took":"61.081985ms","hash":2258640928,"current-db-size-bytes":57225216,"current-db-size":"57 MB","current-db-size-in-use-bytes":29016064,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-06-04T12:19:10.689809Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2258640928,"revision":8115,"compact-revision":6034} {"level":"info","ts":"2026-06-04T12:20:18.357355Z","caller":"traceutil/trace.go:171","msg":"trace[1960540311] transaction","detail":"{read_only:false; response_revision:11303; number_of_response:1; }","duration":"123.396324ms","start":"2026-06-04T12:20:18.233931Z","end":"2026-06-04T12:20:18.357327Z","steps":["trace[1960540311] 'process raft request' (duration: 98.756618ms)","trace[1960540311] 'compare' (duration: 24.491152ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:20:18.801665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.575827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-04T12:20:18.801717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.326482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T12:20:18.801752Z","caller":"traceutil/trace.go:171","msg":"trace[1001487316] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:11305; }","duration":"159.712621ms","start":"2026-06-04T12:20:18.642025Z","end":"2026-06-04T12:20:18.801738Z","steps":["trace[1001487316] 'count revisions from in-memory index tree' (duration: 159.490664ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:20:18.801769Z","caller":"traceutil/trace.go:171","msg":"trace[1062935832] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:11305; }","duration":"135.427484ms","start":"2026-06-04T12:20:18.666328Z","end":"2026-06-04T12:20:18.801755Z","steps":["trace[1062935832] 'count revisions from in-memory index tree' (duration: 135.214059ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:20:43.072265Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011513943378352235,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-04T12:20:43.573422Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011513943378352235,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-04T12:20:44.074601Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011513943378352235,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-04T12:20:44.575005Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011513943378352235,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-04T12:20:44.994382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.17657012s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-06-04T12:20:44.99461Z","caller":"traceutil/trace.go:171","msg":"trace[1529775420] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.176832707s","start":"2026-06-04T12:20:42.817753Z","end":"2026-06-04T12:20:44.994586Z","steps":["trace[1529775420] 'agreement among raft nodes before linearized reading' (duration: 2.176594261s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:20:44.994686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:20:42.817739Z","time spent":"2.176933971s","remote":"127.0.0.1:56002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-04T12:20:45.03081Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.580424125s","expected-duration":"1s"} {"level":"info","ts":"2026-06-04T12:20:45.031218Z","caller":"traceutil/trace.go:171","msg":"trace[1280971947] transaction","detail":"{read_only:false; response_revision:11477; number_of_response:1; }","duration":"2.580937109s","start":"2026-06-04T12:20:42.450261Z","end":"2026-06-04T12:20:45.031199Z","steps":["trace[1280971947] 'process raft request' (duration: 2.580798685s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:20:45.03134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:20:42.450239Z","time spent":"2.581033882s","remote":"127.0.0.1:56256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:20:45.035172Z","caller":"traceutil/trace.go:171","msg":"trace[1208728321] linearizableReadLoop","detail":"{readStateIndex:11871; appliedIndex:11866; }","duration":"2.463585651s","start":"2026-06-04T12:20:42.57156Z","end":"2026-06-04T12:20:45.035146Z","steps":["trace[1208728321] 'read index received' (duration: 2.459493209s)","trace[1208728321] 'applied index is now lower than readState.Index' (duration: 4.091592ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:20:45.035177Z","caller":"traceutil/trace.go:171","msg":"trace[229133096] transaction","detail":"{read_only:false; response_revision:11478; number_of_response:1; }","duration":"2.417783734s","start":"2026-06-04T12:20:42.61736Z","end":"2026-06-04T12:20:45.035144Z","steps":["trace[229133096] 'process raft request' (duration: 2.417578029s)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:20:45.035244Z","caller":"traceutil/trace.go:171","msg":"trace[1148004004] transaction","detail":"{read_only:false; response_revision:11479; number_of_response:1; }","duration":"2.360320529s","start":"2026-06-04T12:20:42.674917Z","end":"2026-06-04T12:20:45.035237Z","steps":["trace[1148004004] 'process raft request' (duration: 2.360152044s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:20:45.035338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:20:42.674892Z","time spent":"2.3603726s","remote":"127.0.0.1:56256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:20:45.035451Z","caller":"traceutil/trace.go:171","msg":"trace[1636644946] transaction","detail":"{read_only:false; response_revision:11480; number_of_response:1; }","duration":"2.122965939s","start":"2026-06-04T12:20:42.912478Z","end":"2026-06-04T12:20:45.035444Z","steps":["trace[1636644946] 'process raft request' (duration: 2.12261502s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:20:45.035488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:20:42.912451Z","time spent":"2.123020031s","remote":"127.0.0.1:56256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T12:20:45.035467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:20:42.617343Z","time spent":"2.417894118s","remote":"127.0.0.1:56256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T12:20:45.035661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.488157502s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T12:20:45.035687Z","caller":"traceutil/trace.go:171","msg":"trace[167052019] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11481; }","duration":"1.488202943s","start":"2026-06-04T12:20:43.547475Z","end":"2026-06-04T12:20:45.035678Z","steps":["trace[167052019] 'agreement among raft nodes before linearized reading' (duration: 1.488147601s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:20:45.035669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.292016206s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-04T12:20:45.035706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:20:43.547461Z","time spent":"1.488240964s","remote":"127.0.0.1:56212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/cinder-ks-user\" "} {"level":"info","ts":"2026-06-04T12:20:45.035735Z","caller":"traceutil/trace.go:171","msg":"trace[566465092] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:11481; }","duration":"2.292129709s","start":"2026-06-04T12:20:42.743587Z","end":"2026-06-04T12:20:45.035717Z","steps":["trace[566465092] 'agreement among raft nodes before linearized reading' (duration: 2.292014826s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:20:45.035773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:20:42.743571Z","time spent":"2.29219176s","remote":"127.0.0.1:56240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":30,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-06-04T12:20:45.035795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.829503963s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T12:20:45.035818Z","caller":"traceutil/trace.go:171","msg":"trace[42210581] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11481; }","duration":"1.829563824s","start":"2026-06-04T12:20:43.206247Z","end":"2026-06-04T12:20:45.035811Z","steps":["trace[42210581] 'agreement among raft nodes before linearized reading' (duration: 1.829526443s)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:20:45.035826Z","caller":"traceutil/trace.go:171","msg":"trace[1963549945] transaction","detail":"{read_only:false; response_revision:11481; number_of_response:1; }","duration":"696.077831ms","start":"2026-06-04T12:20:44.339741Z","end":"2026-06-04T12:20:45.035819Z","steps":["trace[1963549945] 'process raft request' (duration: 695.374941ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:20:45.035906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.491525474s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-04T12:20:45.035921Z","caller":"traceutil/trace.go:171","msg":"trace[580923432] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:11481; }","duration":"1.491584505s","start":"2026-06-04T12:20:43.544332Z","end":"2026-06-04T12:20:45.035917Z","steps":["trace[580923432] 'agreement among raft nodes before linearized reading' (duration: 1.491520934s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:20:45.035933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:20:43.544301Z","time spent":"1.491628947s","remote":"127.0.0.1:56256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-04T12:20:45.03603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.793018982s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-06-04T12:20:45.036028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.83851391s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/cinder-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-06-04T12:20:45.036045Z","caller":"traceutil/trace.go:171","msg":"trace[1302939787] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:11481; }","duration":"1.793080604s","start":"2026-06-04T12:20:43.242961Z","end":"2026-06-04T12:20:45.036041Z","steps":["trace[1302939787] 'agreement among raft nodes before linearized reading' (duration: 1.793030512s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:20:45.036059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:20:43.242949Z","time spent":"1.793107125s","remote":"127.0.0.1:56256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-04T12:20:45.036065Z","caller":"traceutil/trace.go:171","msg":"trace[1960774992] range","detail":"{range_begin:/registry/services/endpoints/openstack/cinder-api; range_end:; response_count:1; response_revision:11481; }","duration":"1.838577302s","start":"2026-06-04T12:20:43.197478Z","end":"2026-06-04T12:20:45.036055Z","steps":["trace[1960774992] 'agreement among raft nodes before linearized reading' (duration: 1.838459639s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:20:45.036091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:20:43.197464Z","time spent":"1.838621443s","remote":"127.0.0.1:56168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":690,"request content":"key:\"/registry/services/endpoints/openstack/cinder-api\" "} {"level":"warn","ts":"2026-06-04T12:20:45.036137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.79366674s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-04T12:20:45.03615Z","caller":"traceutil/trace.go:171","msg":"trace[794256932] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:11481; }","duration":"1.793700371s","start":"2026-06-04T12:20:43.242445Z","end":"2026-06-04T12:20:45.036146Z","steps":["trace[794256932] 'agreement among raft nodes before linearized reading' (duration: 1.79366497s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:20:45.036161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:20:43.242432Z","time spent":"1.793727172s","remote":"127.0.0.1:56256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-04T12:20:45.036221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.828499806s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T12:20:45.036232Z","caller":"traceutil/trace.go:171","msg":"trace[1979668259] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11481; }","duration":"1.828532707s","start":"2026-06-04T12:20:43.207696Z","end":"2026-06-04T12:20:45.036229Z","steps":["trace[1979668259] 'agreement among raft nodes before linearized reading' (duration: 1.828513766s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:20:45.036244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:20:43.207686Z","time spent":"1.828555527s","remote":"127.0.0.1:56212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/cinder-storage-init\" "} {"level":"warn","ts":"2026-06-04T12:20:45.036261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.839411775s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T12:20:45.036291Z","caller":"traceutil/trace.go:171","msg":"trace[270007594] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:11481; }","duration":"1.839481556s","start":"2026-06-04T12:20:43.196801Z","end":"2026-06-04T12:20:45.036282Z","steps":["trace[270007594] 'agreement among raft nodes before linearized reading' (duration: 1.839430265s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:20:45.036312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.829348839s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T12:20:45.036324Z","caller":"traceutil/trace.go:171","msg":"trace[1746774443] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:11481; }","duration":"1.82938938s","start":"2026-06-04T12:20:43.206931Z","end":"2026-06-04T12:20:45.036321Z","steps":["trace[1746774443] 'agreement among raft nodes before linearized reading' (duration: 1.82936905s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:20:45.03632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:20:43.196782Z","time spent":"1.839532258s","remote":"127.0.0.1:56212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" "} {"level":"warn","ts":"2026-06-04T12:20:45.036336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:20:43.206917Z","time spent":"1.829416131s","remote":"127.0.0.1:56212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" "} {"level":"warn","ts":"2026-06-04T12:20:45.036517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.188136164s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-04T12:20:45.036525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.049933057s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-06-04T12:20:45.036535Z","caller":"traceutil/trace.go:171","msg":"trace[502495128] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:11481; }","duration":"1.188190346s","start":"2026-06-04T12:20:43.848337Z","end":"2026-06-04T12:20:45.036528Z","steps":["trace[502495128] 'agreement among raft nodes before linearized reading' (duration: 1.188161465s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:20:45.036554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:20:43.848314Z","time spent":"1.188233507s","remote":"127.0.0.1:56382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":30,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"info","ts":"2026-06-04T12:20:45.036551Z","caller":"traceutil/trace.go:171","msg":"trace[1835015656] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:11481; }","duration":"2.049997789s","start":"2026-06-04T12:20:42.986545Z","end":"2026-06-04T12:20:45.036543Z","steps":["trace[1835015656] 'agreement among raft nodes before linearized reading' (duration: 2.049912946s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:20:45.036575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:20:42.986526Z","time spent":"2.050043669s","remote":"127.0.0.1:56256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-04T12:20:45.036675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.465115903s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-06-04T12:20:45.03669Z","caller":"traceutil/trace.go:171","msg":"trace[781524016] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:11481; }","duration":"2.465158143s","start":"2026-06-04T12:20:42.571528Z","end":"2026-06-04T12:20:45.036686Z","steps":["trace[781524016] 'agreement among raft nodes before linearized reading' (duration: 2.465106842s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:20:45.036703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:20:42.571512Z","time spent":"2.465188095s","remote":"127.0.0.1:56182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-06-04T12:20:45.036698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.292611123s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T12:20:45.036727Z","caller":"traceutil/trace.go:171","msg":"trace[1651691271] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:11481; }","duration":"2.292667393s","start":"2026-06-04T12:20:42.744049Z","end":"2026-06-04T12:20:45.036717Z","steps":["trace[1651691271] 'agreement among raft nodes before linearized reading' (duration: 2.292622432s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:20:45.036755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:20:42.744035Z","time spent":"2.292711615s","remote":"127.0.0.1:52638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-06-04T12:20:45.036927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.292846309s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-04T12:20:45.036955Z","caller":"traceutil/trace.go:171","msg":"trace[996235497] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:11481; }","duration":"2.29289908s","start":"2026-06-04T12:20:42.744048Z","end":"2026-06-04T12:20:45.036947Z","steps":["trace[996235497] 'agreement among raft nodes before linearized reading' (duration: 2.292843498s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:20:45.03698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:20:42.744037Z","time spent":"2.292935841s","remote":"127.0.0.1:45738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":5,"response size":30,"request content":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true "} {"level":"warn","ts":"2026-06-04T12:20:45.037044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.30566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:15 size:143752"} {"level":"info","ts":"2026-06-04T12:20:45.037063Z","caller":"traceutil/trace.go:171","msg":"trace[1757565519] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:15; response_revision:11481; }","duration":"454.348951ms","start":"2026-06-04T12:20:44.582708Z","end":"2026-06-04T12:20:45.037057Z","steps":["trace[1757565519] 'agreement among raft nodes before linearized reading' (duration: 454.216577ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:20:45.037076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:20:44.582695Z","time spent":"454.377782ms","remote":"127.0.0.1:56450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":15,"response size":143775,"request content":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" "} {"level":"warn","ts":"2026-06-04T12:20:45.03727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"948.862462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-04T12:20:45.037304Z","caller":"traceutil/trace.go:171","msg":"trace[1378674190] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:11481; }","duration":"948.941004ms","start":"2026-06-04T12:20:44.088354Z","end":"2026-06-04T12:20:45.037295Z","steps":["trace[1378674190] 'agreement among raft nodes before linearized reading' (duration: 948.810421ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:20:45.037328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:20:44.088335Z","time spent":"948.985556ms","remote":"127.0.0.1:56198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":150,"response size":31,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-06-04T12:20:45.037336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.488225894s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T12:20:45.037366Z","caller":"traceutil/trace.go:171","msg":"trace[1763840825] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11481; }","duration":"1.488280105s","start":"2026-06-04T12:20:43.549077Z","end":"2026-06-04T12:20:45.037357Z","steps":["trace[1763840825] 'agreement among raft nodes before linearized reading' (duration: 1.488234874s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:20:45.03739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:20:43.549061Z","time spent":"1.488322957s","remote":"127.0.0.1:56212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/cinder-storage-init\" "} {"level":"warn","ts":"2026-06-04T12:20:45.037549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.488546833s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-06-04T12:20:45.037589Z","caller":"traceutil/trace.go:171","msg":"trace[1540600151] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:11481; }","duration":"1.488621775s","start":"2026-06-04T12:20:43.548957Z","end":"2026-06-04T12:20:45.037579Z","steps":["trace[1540600151] 'agreement among raft nodes before linearized reading' (duration: 1.488536233s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:20:45.037608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:20:43.548939Z","time spent":"1.488663726s","remote":"127.0.0.1:56256","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-06-04T12:20:45.037631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.279397487s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-gf8m5\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-06-04T12:20:45.037662Z","caller":"traceutil/trace.go:171","msg":"trace[145390027] range","detail":"{range_begin:/registry/pods/openstack/keepalived-gf8m5; range_end:; response_count:1; response_revision:11481; }","duration":"1.279465379s","start":"2026-06-04T12:20:43.758187Z","end":"2026-06-04T12:20:45.037653Z","steps":["trace[145390027] 'agreement among raft nodes before linearized reading' (duration: 1.279307855s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:20:45.037687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:20:43.758165Z","time spent":"1.27951604s","remote":"127.0.0.1:56182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6543,"request content":"key:\"/registry/pods/openstack/keepalived-gf8m5\" "} {"level":"warn","ts":"2026-06-04T12:20:45.037701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.281170426s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.22\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-06-04T12:20:45.035833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:20:43.20623Z","time spent":"1.829600276s","remote":"127.0.0.1:56212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/cinder-ks-user\" "} {"level":"info","ts":"2026-06-04T12:20:45.037761Z","caller":"traceutil/trace.go:171","msg":"trace[488934343] range","detail":"{range_begin:/registry/masterleases/199.204.45.22; range_end:; response_count:1; response_revision:11481; }","duration":"1.281281188s","start":"2026-06-04T12:20:43.756463Z","end":"2026-06-04T12:20:45.037744Z","steps":["trace[488934343] 'agreement among raft nodes before linearized reading' (duration: 1.281112904s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:20:45.037793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:20:43.756439Z","time spent":"1.281348221s","remote":"127.0.0.1:56040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.22\" "} {"level":"warn","ts":"2026-06-04T12:20:45.039361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:20:44.339724Z","time spent":"696.121171ms","remote":"127.0.0.1:56256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:24:10.632967Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10638} {"level":"info","ts":"2026-06-04T12:24:10.71802Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10638,"took":"82.693845ms","hash":2824114339,"current-db-size-bytes":57225216,"current-db-size":"57 MB","current-db-size-in-use-bytes":27312128,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-06-04T12:24:10.718135Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2824114339,"revision":10638,"compact-revision":8115} {"level":"warn","ts":"2026-06-04T12:26:20.934806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.374942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T12:26:20.93491Z","caller":"traceutil/trace.go:171","msg":"trace[2091887807] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13751; }","duration":"118.522196ms","start":"2026-06-04T12:26:20.816366Z","end":"2026-06-04T12:26:20.934888Z","steps":["trace[2091887807] 'range keys from in-memory index tree' (duration: 118.28618ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:26:59.376119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.849637ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-04T12:26:59.376246Z","caller":"traceutil/trace.go:171","msg":"trace[1597686017] linearizableReadLoop","detail":"{readStateIndex:14444; appliedIndex:14443; }","duration":"347.940187ms","start":"2026-06-04T12:26:59.028287Z","end":"2026-06-04T12:26:59.376227Z","steps":["trace[1597686017] 'read index received' (duration: 131.890985ms)","trace[1597686017] 'applied index is now lower than readState.Index' (duration: 216.047422ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:26:59.376456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.710736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-06-04T12:26:59.376463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.167474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-04T12:26:59.376499Z","caller":"traceutil/trace.go:171","msg":"trace[200305090] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:13963; }","duration":"345.789348ms","start":"2026-06-04T12:26:59.0307Z","end":"2026-06-04T12:26:59.37649Z","steps":["trace[200305090] 'agreement among raft nodes before linearized reading' (duration: 345.629584ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:26:59.376534Z","caller":"traceutil/trace.go:171","msg":"trace[619632622] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:13963; }","duration":"348.291357ms","start":"2026-06-04T12:26:59.028225Z","end":"2026-06-04T12:26:59.376517Z","steps":["trace[619632622] 'agreement among raft nodes before linearized reading' (duration: 348.086811ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:26:59.37654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:26:59.030683Z","time spent":"345.84729ms","remote":"127.0.0.1:56256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-04T12:26:59.376536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.989366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-04T12:26:59.376643Z","caller":"traceutil/trace.go:171","msg":"trace[780775970] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13963; }","duration":"345.127701ms","start":"2026-06-04T12:26:59.031494Z","end":"2026-06-04T12:26:59.376622Z","steps":["trace[780775970] 'agreement among raft nodes before linearized reading' (duration: 344.859973ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:26:59.376584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:26:59.0282Z","time spent":"348.375499ms","remote":"127.0.0.1:56256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-04T12:26:59.376694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:26:59.031484Z","time spent":"345.200142ms","remote":"127.0.0.1:56256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-04T12:26:59.376883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.125177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-04T12:26:59.376974Z","caller":"traceutil/trace.go:171","msg":"trace[65501936] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:13963; }","duration":"346.253301ms","start":"2026-06-04T12:26:59.0307Z","end":"2026-06-04T12:26:59.376953Z","steps":["trace[65501936] 'agreement among raft nodes before linearized reading' (duration: 345.704586ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:26:59.377056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:26:59.030682Z","time spent":"346.365214ms","remote":"127.0.0.1:56256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-04T12:27:08.512742Z","caller":"traceutil/trace.go:171","msg":"trace[1376509539] transaction","detail":"{read_only:false; response_revision:14133; number_of_response:1; }","duration":"112.416942ms","start":"2026-06-04T12:27:08.400292Z","end":"2026-06-04T12:27:08.512709Z","steps":["trace[1376509539] 'process raft request' (duration: 112.124284ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:27:08.597411Z","caller":"traceutil/trace.go:171","msg":"trace[2089312433] transaction","detail":"{read_only:false; response_revision:14134; number_of_response:1; }","duration":"109.372548ms","start":"2026-06-04T12:27:08.487987Z","end":"2026-06-04T12:27:08.59736Z","steps":["trace[2089312433] 'process raft request' (duration: 109.092871ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:28:07.541368Z","caller":"traceutil/trace.go:171","msg":"trace[1564519727] transaction","detail":"{read_only:false; response_revision:14981; number_of_response:1; }","duration":"155.43895ms","start":"2026-06-04T12:28:07.385904Z","end":"2026-06-04T12:28:07.541343Z","steps":["trace[1564519727] 'process raft request' (duration: 155.243725ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:28:07.54136Z","caller":"traceutil/trace.go:171","msg":"trace[457231913] linearizableReadLoop","detail":"{readStateIndex:15491; appliedIndex:15490; }","duration":"121.032387ms","start":"2026-06-04T12:28:07.420281Z","end":"2026-06-04T12:28:07.541314Z","steps":["trace[457231913] 'read index received' (duration: 120.793821ms)","trace[457231913] 'applied index is now lower than readState.Index' (duration: 237.476µs)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:28:07.541546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.253314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-fqqzc\" ","response":"range_response_count:1 size:22089"} {"level":"info","ts":"2026-06-04T12:28:07.541597Z","caller":"traceutil/trace.go:171","msg":"trace[1498128751] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-fqqzc; range_end:; response_count:1; response_revision:14981; }","duration":"121.364937ms","start":"2026-06-04T12:28:07.420221Z","end":"2026-06-04T12:28:07.541586Z","steps":["trace[1498128751] 'agreement among raft nodes before linearized reading' (duration: 121.193422ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:28:43.1944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.148937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T12:28:43.194502Z","caller":"traceutil/trace.go:171","msg":"trace[267462570] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15196; }","duration":"134.26773ms","start":"2026-06-04T12:28:43.060213Z","end":"2026-06-04T12:28:43.194481Z","steps":["trace[267462570] 'range keys from in-memory index tree' (duration: 134.037175ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:28:43.194618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.351863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-06-04T12:28:43.19478Z","caller":"traceutil/trace.go:171","msg":"trace[1259913167] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15196; }","duration":"134.58365ms","start":"2026-06-04T12:28:43.060167Z","end":"2026-06-04T12:28:43.19475Z","steps":["trace[1259913167] 'range keys from in-memory index tree' (duration: 134.048484ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:29:10.638999Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12794} {"level":"info","ts":"2026-06-04T12:29:10.71417Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12794,"took":"72.693042ms","hash":3210748268,"current-db-size-bytes":57225216,"current-db-size":"57 MB","current-db-size-in-use-bytes":30097408,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-04T12:29:10.714252Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3210748268,"revision":12794,"compact-revision":10638} {"level":"info","ts":"2026-06-04T12:34:10.649096Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15338} {"level":"info","ts":"2026-06-04T12:34:10.746217Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15338,"took":"93.640197ms","hash":2698658962,"current-db-size-bytes":57225216,"current-db-size":"57 MB","current-db-size-in-use-bytes":31535104,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-04T12:34:10.74629Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2698658962,"revision":15338,"compact-revision":12794} {"level":"info","ts":"2026-06-04T12:34:44.303346Z","caller":"traceutil/trace.go:171","msg":"trace[1667614017] transaction","detail":"{read_only:false; response_revision:17831; number_of_response:1; }","duration":"119.024291ms","start":"2026-06-04T12:34:44.184245Z","end":"2026-06-04T12:34:44.303269Z","steps":["trace[1667614017] 'process raft request' (duration: 106.067856ms)","trace[1667614017] 'compare' (duration: 12.602795ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:34:44.738424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.051891ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-04T12:34:44.738572Z","caller":"traceutil/trace.go:171","msg":"trace[2024473261] transaction","detail":"{read_only:false; response_revision:17833; number_of_response:1; }","duration":"403.638634ms","start":"2026-06-04T12:34:44.334906Z","end":"2026-06-04T12:34:44.738545Z","steps":["trace[2024473261] 'process raft request' (duration: 229.361667ms)","trace[2024473261] 'compare' (duration: 173.775423ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:34:44.73866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:34:44.334874Z","time spent":"403.747397ms","remote":"127.0.0.1:56256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:34:44.857093Z","caller":"traceutil/trace.go:171","msg":"trace[1898605966] linearizableReadLoop","detail":"{readStateIndex:18440; appliedIndex:18438; }","duration":"427.935641ms","start":"2026-06-04T12:34:44.429129Z","end":"2026-06-04T12:34:44.857065Z","steps":["trace[1898605966] 'read index received' (duration: 135.188536ms)","trace[1898605966] 'applied index is now lower than readState.Index' (duration: 292.746085ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:34:44.857282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.718736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-04T12:34:44.857271Z","caller":"traceutil/trace.go:171","msg":"trace[2024532153] transaction","detail":"{read_only:false; response_revision:17834; number_of_response:1; }","duration":"519.978914ms","start":"2026-06-04T12:34:44.337236Z","end":"2026-06-04T12:34:44.857215Z","steps":["trace[2024532153] 'process raft request' (duration: 505.065795ms)","trace[2024532153] 'compare' (duration: 14.642001ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:34:44.857329Z","caller":"traceutil/trace.go:171","msg":"trace[1446027048] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:17834; }","duration":"334.847738ms","start":"2026-06-04T12:34:44.522466Z","end":"2026-06-04T12:34:44.857314Z","steps":["trace[1446027048] 'agreement among raft nodes before linearized reading' (duration: 334.748636ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:34:44.857434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.30649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-fqqzc\" ","response":"range_response_count:1 size:22090"} {"level":"info","ts":"2026-06-04T12:34:44.857511Z","caller":"traceutil/trace.go:171","msg":"trace[1491859663] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-fqqzc; range_end:; response_count:1; response_revision:17834; }","duration":"428.439394ms","start":"2026-06-04T12:34:44.429054Z","end":"2026-06-04T12:34:44.857494Z","steps":["trace[1491859663] 'agreement among raft nodes before linearized reading' (duration: 428.152967ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:34:44.857558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:34:44.429027Z","time spent":"428.519876ms","remote":"127.0.0.1:56182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22113,"request content":"key:\"/registry/pods/openstack/nova-compute-default-fqqzc\" "} {"level":"warn","ts":"2026-06-04T12:34:44.857546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:34:44.337217Z","time spent":"520.181139ms","remote":"127.0.0.1:56256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T12:34:44.857555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:34:44.522426Z","time spent":"335.074216ms","remote":"127.0.0.1:52810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-06-04T12:38:08.703348Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011513943378376671,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-04T12:38:09.859614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.042688986s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-06-04T12:38:09.859679Z","caller":"traceutil/trace.go:171","msg":"trace[2023483226] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.042801478s","start":"2026-06-04T12:38:07.816862Z","end":"2026-06-04T12:38:09.859663Z","steps":["trace[2023483226] 'agreement among raft nodes before linearized reading' (duration: 2.042721546s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:38:09.859706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:38:07.81684Z","time spent":"2.0428601s","remote":"127.0.0.1:56002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-04T12:38:09.860596Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011513943378376671,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-04T12:38:10.345795Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.868611275s","expected-duration":"1s"} {"level":"info","ts":"2026-06-04T12:38:10.34616Z","caller":"traceutil/trace.go:171","msg":"trace[1644107942] transaction","detail":"{read_only:false; response_revision:18980; number_of_response:1; }","duration":"2.869046707s","start":"2026-06-04T12:38:07.477093Z","end":"2026-06-04T12:38:10.34614Z","steps":["trace[1644107942] 'process raft request' (duration: 2.868903653s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:38:10.346281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:38:07.477075Z","time spent":"2.86913795s","remote":"127.0.0.1:56256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:38:11.33966Z","caller":"traceutil/trace.go:171","msg":"trace[210941022] transaction","detail":"{read_only:false; response_revision:18981; number_of_response:1; }","duration":"3.787413187s","start":"2026-06-04T12:38:07.552223Z","end":"2026-06-04T12:38:11.339636Z","steps":["trace[210941022] 'process raft request' (duration: 3.786716538s)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:38:11.339698Z","caller":"traceutil/trace.go:171","msg":"trace[1767044926] transaction","detail":"{read_only:false; response_revision:18982; number_of_response:1; }","duration":"3.756238182s","start":"2026-06-04T12:38:07.583435Z","end":"2026-06-04T12:38:11.339674Z","steps":["trace[1767044926] 'process raft request' (duration: 3.755952515s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:38:11.339834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:38:07.583419Z","time spent":"3.756348786s","remote":"127.0.0.1:56256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T12:38:11.339892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:38:07.552201Z","time spent":"3.787526891s","remote":"127.0.0.1:56256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:38:11.341765Z","caller":"traceutil/trace.go:171","msg":"trace[776490117] transaction","detail":"{read_only:false; response_revision:18983; number_of_response:1; }","duration":"1.479200528s","start":"2026-06-04T12:38:09.862552Z","end":"2026-06-04T12:38:11.341752Z","steps":["trace[776490117] 'process raft request' (duration: 1.479019073s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:38:11.341824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:38:09.862535Z","time spent":"1.479260701s","remote":"127.0.0.1:56256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:38:11.341987Z","caller":"traceutil/trace.go:171","msg":"trace[691951097] transaction","detail":"{read_only:false; response_revision:18984; number_of_response:1; }","duration":"1.475302011s","start":"2026-06-04T12:38:09.86666Z","end":"2026-06-04T12:38:11.341962Z","steps":["trace[691951097] 'process raft request' (duration: 1.475072765s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:38:11.342129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:38:09.866648Z","time spent":"1.475404675s","remote":"127.0.0.1:56256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:38:11.342179Z","caller":"traceutil/trace.go:171","msg":"trace[1005858713] linearizableReadLoop","detail":"{readStateIndex:19636; appliedIndex:19632; }","duration":"3.525258167s","start":"2026-06-04T12:38:07.816912Z","end":"2026-06-04T12:38:11.34217Z","steps":["trace[1005858713] 'read index received' (duration: 2.529146494s)","trace[1005858713] 'applied index is now lower than readState.Index' (duration: 996.110893ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:38:11.342244Z","caller":"traceutil/trace.go:171","msg":"trace[1221117221] transaction","detail":"{read_only:false; response_revision:18985; number_of_response:1; }","duration":"1.465996615s","start":"2026-06-04T12:38:09.876241Z","end":"2026-06-04T12:38:11.342238Z","steps":["trace[1221117221] 'process raft request' (duration: 1.465668996s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:38:11.34229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:38:09.876209Z","time spent":"1.466053836s","remote":"127.0.0.1:56256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:38:11.342301Z","caller":"traceutil/trace.go:171","msg":"trace[595452803] transaction","detail":"{read_only:false; response_revision:18986; number_of_response:1; }","duration":"148.072402ms","start":"2026-06-04T12:38:11.194219Z","end":"2026-06-04T12:38:11.342292Z","steps":["trace[595452803] 'process raft request' (duration: 147.929028ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:38:11.342472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.335929014s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-04T12:38:11.342514Z","caller":"traceutil/trace.go:171","msg":"trace[935775449] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:18986; }","duration":"3.336007276s","start":"2026-06-04T12:38:08.006498Z","end":"2026-06-04T12:38:11.342506Z","steps":["trace[935775449] 'agreement among raft nodes before linearized reading' (duration: 3.335869232s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:38:11.34254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:38:08.006477Z","time spent":"3.336057977s","remote":"127.0.0.1:56196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":74,"response size":31,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-06-04T12:38:11.342576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.476781222s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-04T12:38:11.3426Z","caller":"traceutil/trace.go:171","msg":"trace[1041434674] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18986; }","duration":"1.476821983s","start":"2026-06-04T12:38:09.865771Z","end":"2026-06-04T12:38:11.342593Z","steps":["trace[1041434674] 'agreement among raft nodes before linearized reading' (duration: 1.476736171s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:38:11.342601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.481917243s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-04T12:38:11.342619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:38:09.865763Z","time spent":"1.476851744s","remote":"127.0.0.1:56256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-04T12:38:11.342634Z","caller":"traceutil/trace.go:171","msg":"trace[1577041114] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18986; }","duration":"1.481958424s","start":"2026-06-04T12:38:09.860666Z","end":"2026-06-04T12:38:11.342624Z","steps":["trace[1577041114] 'agreement among raft nodes before linearized reading' (duration: 1.481902273s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:38:11.342661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:38:09.860661Z","time spent":"1.481991475s","remote":"127.0.0.1:55996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-04T12:38:11.342707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.144807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-04T12:38:11.342726Z","caller":"traceutil/trace.go:171","msg":"trace[1537467347] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:18986; }","duration":"306.197889ms","start":"2026-06-04T12:38:11.036521Z","end":"2026-06-04T12:38:11.342719Z","steps":["trace[1537467347] 'agreement among raft nodes before linearized reading' (duration: 306.167108ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:38:11.342748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:38:11.036502Z","time spent":"306.241521ms","remote":"127.0.0.1:52594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"warn","ts":"2026-06-04T12:38:11.342764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.482852089s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-04T12:38:11.342787Z","caller":"traceutil/trace.go:171","msg":"trace[451585429] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:18986; }","duration":"1.48288974s","start":"2026-06-04T12:38:09.859889Z","end":"2026-06-04T12:38:11.342779Z","steps":["trace[451585429] 'agreement among raft nodes before linearized reading' (duration: 1.482850949s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:38:11.342806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:38:09.859879Z","time spent":"1.482921381s","remote":"127.0.0.1:52500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-06-04T12:38:11.342859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.477145922s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:701"} {"level":"warn","ts":"2026-06-04T12:38:11.342875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"808.491747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-06-04T12:38:11.342887Z","caller":"traceutil/trace.go:171","msg":"trace[514450201] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18986; }","duration":"1.477203203s","start":"2026-06-04T12:38:09.865675Z","end":"2026-06-04T12:38:11.342879Z","steps":["trace[514450201] 'agreement among raft nodes before linearized reading' (duration: 1.47709739s)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:38:11.342894Z","caller":"traceutil/trace.go:171","msg":"trace[1307522398] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:18986; }","duration":"808.546338ms","start":"2026-06-04T12:38:10.534342Z","end":"2026-06-04T12:38:11.342888Z","steps":["trace[1307522398] 'agreement among raft nodes before linearized reading' (duration: 808.484837ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:38:11.342899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.635296916s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-04T12:38:11.342913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:38:10.534323Z","time spent":"808.587059ms","remote":"127.0.0.1:56182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-06-04T12:38:11.342912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:38:09.865658Z","time spent":"1.477248535s","remote":"127.0.0.1:56168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":724,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"info","ts":"2026-06-04T12:38:11.342924Z","caller":"traceutil/trace.go:171","msg":"trace[1576959463] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:18986; }","duration":"2.635347347s","start":"2026-06-04T12:38:08.707569Z","end":"2026-06-04T12:38:11.342917Z","steps":["trace[1576959463] 'agreement among raft nodes before linearized reading' (duration: 2.635310986s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:38:11.342944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:38:08.707555Z","time spent":"2.635383948s","remote":"127.0.0.1:52626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true "} {"level":"warn","ts":"2026-06-04T12:38:11.343076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.049312712s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-04T12:38:11.343105Z","caller":"traceutil/trace.go:171","msg":"trace[554189027] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:18986; }","duration":"1.049379474s","start":"2026-06-04T12:38:10.293718Z","end":"2026-06-04T12:38:11.343098Z","steps":["trace[554189027] 'agreement among raft nodes before linearized reading' (duration: 1.049335843s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:38:11.343129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:38:10.293698Z","time spent":"1.049423716s","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-06-04T12:38:11.34318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.479880557s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-06-04T12:38:11.343222Z","caller":"traceutil/trace.go:171","msg":"trace[1919664299] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:18986; }","duration":"1.479933738s","start":"2026-06-04T12:38:09.863277Z","end":"2026-06-04T12:38:11.343211Z","steps":["trace[1919664299] 'agreement among raft nodes before linearized reading' (duration: 1.479836826s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:38:11.343249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.247742745s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-04T12:38:11.343273Z","caller":"traceutil/trace.go:171","msg":"trace[1939120250] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:18986; }","duration":"1.247800307s","start":"2026-06-04T12:38:10.095467Z","end":"2026-06-04T12:38:11.343267Z","steps":["trace[1939120250] 'agreement among raft nodes before linearized reading' (duration: 1.247748285s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:38:11.343253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:38:09.863269Z","time spent":"1.47997506s","remote":"127.0.0.1:56164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1889,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-06-04T12:38:11.343293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:38:10.095448Z","time spent":"1.247840528s","remote":"127.0.0.1:53824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true "} {"level":"warn","ts":"2026-06-04T12:38:11.343432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.461685126s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-06-04T12:38:11.343452Z","caller":"traceutil/trace.go:171","msg":"trace[1924857374] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18986; }","duration":"1.461738158s","start":"2026-06-04T12:38:09.881709Z","end":"2026-06-04T12:38:11.343447Z","steps":["trace[1924857374] 'agreement among raft nodes before linearized reading' (duration: 1.461682936s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:38:11.343452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.480445033s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-06-04T12:38:11.343468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:38:09.881693Z","time spent":"1.461771938s","remote":"127.0.0.1:56256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-04T12:38:11.343477Z","caller":"traceutil/trace.go:171","msg":"trace[562964471] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18986; }","duration":"1.480505154s","start":"2026-06-04T12:38:09.862964Z","end":"2026-06-04T12:38:11.343469Z","steps":["trace[562964471] 'agreement among raft nodes before linearized reading' (duration: 1.480432712s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:38:11.343504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:38:09.862956Z","time spent":"1.480539245s","remote":"127.0.0.1:56256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-04T12:38:11.342726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.482319335s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-t9ch6\" ","response":"range_response_count:1 size:9791"} {"level":"warn","ts":"2026-06-04T12:38:11.343612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.481000738s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-04T12:38:11.343617Z","caller":"traceutil/trace.go:171","msg":"trace[1747199207] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-t9ch6; range_end:; response_count:1; response_revision:18986; }","duration":"1.483377454s","start":"2026-06-04T12:38:09.860209Z","end":"2026-06-04T12:38:11.343586Z","steps":["trace[1747199207] 'agreement among raft nodes before linearized reading' (duration: 1.482436708s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:38:11.343649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.472386261s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:701"} {"level":"info","ts":"2026-06-04T12:38:11.343677Z","caller":"traceutil/trace.go:171","msg":"trace[851817508] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18986; }","duration":"1.472439052s","start":"2026-06-04T12:38:09.87123Z","end":"2026-06-04T12:38:11.343669Z","steps":["trace[851817508] 'agreement among raft nodes before linearized reading' (duration: 1.47236156s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:38:11.343703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:38:09.871216Z","time spent":"1.472482424s","remote":"127.0.0.1:56168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":724,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"warn","ts":"2026-06-04T12:38:11.343705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:38:09.860201Z","time spent":"1.483473486s","remote":"127.0.0.1:56182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9814,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-t9ch6\" "} {"level":"warn","ts":"2026-06-04T12:38:11.343182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.197409064s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-06-04T12:38:11.343759Z","caller":"traceutil/trace.go:171","msg":"trace[1343787832] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18986; }","duration":"3.198033151s","start":"2026-06-04T12:38:08.145717Z","end":"2026-06-04T12:38:11.34375Z","steps":["trace[1343787832] 'agreement among raft nodes before linearized reading' (duration: 3.197392414s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:38:11.343793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:38:08.1457Z","time spent":"3.198084642s","remote":"127.0.0.1:56256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-04T12:38:11.343565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.461849631s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-04T12:38:11.343641Z","caller":"traceutil/trace.go:171","msg":"trace[209636703] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:18986; }","duration":"1.48105409s","start":"2026-06-04T12:38:09.86258Z","end":"2026-06-04T12:38:11.343634Z","steps":["trace[209636703] 'agreement among raft nodes before linearized reading' (duration: 1.481002379s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:38:11.343884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:38:09.862564Z","time spent":"1.481308046s","remote":"127.0.0.1:56434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":31,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-06-04T12:38:11.343877Z","caller":"traceutil/trace.go:171","msg":"trace[1082695635] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18986; }","duration":"1.46217811s","start":"2026-06-04T12:38:09.881679Z","end":"2026-06-04T12:38:11.343857Z","steps":["trace[1082695635] 'agreement among raft nodes before linearized reading' (duration: 1.461870732s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:38:11.343971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.472773812s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:701"} {"level":"warn","ts":"2026-06-04T12:38:11.34399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:38:09.881663Z","time spent":"1.462319183s","remote":"127.0.0.1:56002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-04T12:38:11.344001Z","caller":"traceutil/trace.go:171","msg":"trace[1528455401] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18986; }","duration":"1.472817163s","start":"2026-06-04T12:38:09.871174Z","end":"2026-06-04T12:38:11.343991Z","steps":["trace[1528455401] 'agreement among raft nodes before linearized reading' (duration: 1.47272704s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:38:11.344038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:38:09.871167Z","time spent":"1.472860844s","remote":"127.0.0.1:56168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":724,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"warn","ts":"2026-06-04T12:38:11.343839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.462131918s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-06-04T12:38:11.344083Z","caller":"traceutil/trace.go:171","msg":"trace[103137935] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18986; }","duration":"1.462408456s","start":"2026-06-04T12:38:09.881662Z","end":"2026-06-04T12:38:11.344071Z","steps":["trace[103137935] 'agreement among raft nodes before linearized reading' (duration: 1.462133328s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:38:11.344116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:38:09.881645Z","time spent":"1.462464078s","remote":"127.0.0.1:56256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-04T12:39:10.656136Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17620} {"level":"info","ts":"2026-06-04T12:39:10.749611Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17620,"took":"90.284527ms","hash":1381004177,"current-db-size-bytes":57225216,"current-db-size":"57 MB","current-db-size-in-use-bytes":27676672,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-06-04T12:39:10.749666Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1381004177,"revision":17620,"compact-revision":15338} {"level":"info","ts":"2026-06-04T12:39:19.60138Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"fed19d9291630e09","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-04T12:39:19.608927Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-06-04T12:39:19.609077Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002}