{"level":"warn","ts":"2026-05-06T01:09:32.031929Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-06T01:09:32.032039Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.25: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.25:2380","--initial-cluster=instance=https://199.204.45.25:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.25:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.25: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-05-06T01:09:32.032106Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-06T01:09:32.032115Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.25:2380"]} {"level":"info","ts":"2026-05-06T01:09:32.032129Z","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-05-06T01:09:32.032726Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.25:2379"]} {"level":"info","ts":"2026-05-06T01:09:32.032787Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.25:2380"],"listen-peer-urls":["https://199.204.45.25:2380"],"advertise-client-urls":["https://199.204.45.25:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.25:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.25: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-05-06T01:09:32.037646Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.678929ms"} {"level":"info","ts":"2026-05-06T01:09:32.050884Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ba52c55cf4193752","cluster-id":"4468ed52294bc271"} {"level":"info","ts":"2026-05-06T01:09:32.05097Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 switched to configuration voters=()"} {"level":"info","ts":"2026-05-06T01:09:32.050999Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became follower at term 0"} {"level":"info","ts":"2026-05-06T01:09:32.05102Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ba52c55cf4193752 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-06T01:09:32.05104Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became follower at term 1"} {"level":"info","ts":"2026-05-06T01:09:32.05108Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 switched to configuration voters=(13426010442167826258)"} {"level":"warn","ts":"2026-05-06T01:09:32.060753Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-06T01:09:32.061903Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-06T01:09:32.063221Z","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-05-06T01:09:32.066062Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ba52c55cf4193752","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-06T01:09:32.067526Z","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-05-06T01:09:32.067589Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ba52c55cf4193752","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-06T01:09:32.067661Z","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-05-06T01:09:32.067697Z","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-05-06T01:09:32.071812Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 switched to configuration voters=(13426010442167826258)"} {"level":"info","ts":"2026-05-06T01:09:32.072682Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4468ed52294bc271","local-member-id":"ba52c55cf4193752","added-peer-id":"ba52c55cf4193752","added-peer-peer-urls":["https://199.204.45.25:2380"]} {"level":"info","ts":"2026-05-06T01:09:32.073917Z","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-05-06T01:09:32.074062Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.25:2380"} {"level":"info","ts":"2026-05-06T01:09:32.074136Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ba52c55cf4193752","initial-advertise-peer-urls":["https://199.204.45.25:2380"],"listen-peer-urls":["https://199.204.45.25:2380"],"advertise-client-urls":["https://199.204.45.25:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.25:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-06T01:09:32.074191Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-06T01:09:32.074136Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.25:2380"} {"level":"info","ts":"2026-05-06T01:09:32.75371Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 is starting a new election at term 1"} {"level":"info","ts":"2026-05-06T01:09:32.753772Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-06T01:09:32.753813Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 received MsgPreVoteResp from ba52c55cf4193752 at term 1"} {"level":"info","ts":"2026-05-06T01:09:32.753832Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became candidate at term 2"} {"level":"info","ts":"2026-05-06T01:09:32.75385Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 received MsgVoteResp from ba52c55cf4193752 at term 2"} {"level":"info","ts":"2026-05-06T01:09:32.753871Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became leader at term 2"} {"level":"info","ts":"2026-05-06T01:09:32.75389Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ba52c55cf4193752 elected leader ba52c55cf4193752 at term 2"} {"level":"info","ts":"2026-05-06T01:09:32.810062Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T01:09:32.832128Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-06T01:09:32.832158Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-06T01:09:32.832014Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ba52c55cf4193752","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.25:2379]}","request-path":"/0/members/ba52c55cf4193752/attributes","cluster-id":"4468ed52294bc271","publish-timeout":"7s"} {"level":"info","ts":"2026-05-06T01:09:32.832663Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-06T01:09:32.832804Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-06T01:09:32.835384Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-06T01:09:32.836317Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4468ed52294bc271","local-member-id":"ba52c55cf4193752","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T01:09:32.838064Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T01:09:32.838164Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T01:09:32.838526Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.25:2379"} {"level":"warn","ts":"2026-05-06T01:09:36.057683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.000239ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-06T01:09:36.057848Z","caller":"traceutil/trace.go:171","msg":"trace[1554094015] transaction","detail":"{read_only:false; response_revision:233; number_of_response:1; }","duration":"358.312563ms","start":"2026-05-06T01:09:35.699508Z","end":"2026-05-06T01:09:36.05782Z","steps":["trace[1554094015] 'process raft request' (duration: 29.630597ms)","trace[1554094015] 'compare' (duration: 327.840064ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:09:36.058017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:09:35.699494Z","time spent":"358.489677ms","remote":"127.0.0.1:54396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":714,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T01:09:36.77059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.356577ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-06T01:09:36.770848Z","caller":"traceutil/trace.go:171","msg":"trace[1050159304] transaction","detail":"{read_only:false; response_revision:234; number_of_response:1; }","duration":"706.514934ms","start":"2026-05-06T01:09:36.064283Z","end":"2026-05-06T01:09:36.770798Z","steps":["trace[1050159304] 'process raft request' (duration: 437.883743ms)","trace[1050159304] 'compare' (duration: 268.239147ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:09:36.770952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:09:36.064264Z","time spent":"706.632564ms","remote":"127.0.0.1:54396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":726,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T01:09:36.771003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.866937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:09:36.771109Z","caller":"traceutil/trace.go:171","msg":"trace[1466480770] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:234; }","duration":"623.01461ms","start":"2026-05-06T01:09:36.148076Z","end":"2026-05-06T01:09:36.771091Z","steps":["trace[1466480770] 'agreement among raft nodes before linearized reading' (duration: 622.818073ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:09:36.771251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:09:36.14806Z","time spent":"623.086135ms","remote":"127.0.0.1:54038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-06T01:09:36.770751Z","caller":"traceutil/trace.go:171","msg":"trace[880381756] linearizableReadLoop","detail":"{readStateIndex:239; appliedIndex:238; }","duration":"622.568982ms","start":"2026-05-06T01:09:36.148113Z","end":"2026-05-06T01:09:36.770682Z","steps":["trace[880381756] 'read index received' (duration: 353.969773ms)","trace[880381756] 'applied index is now lower than readState.Index' (duration: 268.597619ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:09:36.771533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.275478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:09:36.771758Z","caller":"traceutil/trace.go:171","msg":"trace[1334569833] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:234; }","duration":"228.386558ms","start":"2026-05-06T01:09:36.543166Z","end":"2026-05-06T01:09:36.771552Z","steps":["trace[1334569833] 'agreement among raft nodes before linearized reading' (duration: 228.324452ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:09:37.077589Z","caller":"traceutil/trace.go:171","msg":"trace[1035332611] linearizableReadLoop","detail":"{readStateIndex:240; appliedIndex:239; }","duration":"302.4319ms","start":"2026-05-06T01:09:36.775132Z","end":"2026-05-06T01:09:37.077564Z","steps":["trace[1035332611] 'read index received' (duration: 299.040864ms)","trace[1035332611] 'applied index is now lower than readState.Index' (duration: 3.389746ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:09:37.07769Z","caller":"traceutil/trace.go:171","msg":"trace[1776042722] transaction","detail":"{read_only:false; response_revision:235; number_of_response:1; }","duration":"302.828413ms","start":"2026-05-06T01:09:36.774785Z","end":"2026-05-06T01:09:37.077613Z","steps":["trace[1776042722] 'process raft request' (duration: 299.406434ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:09:37.077867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:09:36.774771Z","time spent":"303.019289ms","remote":"127.0.0.1:54326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T01:09:37.077738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.589454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:113"} {"level":"info","ts":"2026-05-06T01:09:37.07821Z","caller":"traceutil/trace.go:171","msg":"trace[271499367] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:235; }","duration":"303.102586ms","start":"2026-05-06T01:09:36.77509Z","end":"2026-05-06T01:09:37.078193Z","steps":["trace[271499367] 'agreement among raft nodes before linearized reading' (duration: 302.55854ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:09:37.078443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:09:36.77507Z","time spent":"303.249368ms","remote":"127.0.0.1:54098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":136,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"info","ts":"2026-05-06T01:10:03.548748Z","caller":"traceutil/trace.go:171","msg":"trace[607211436] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"329.174923ms","start":"2026-05-06T01:10:03.219549Z","end":"2026-05-06T01:10:03.548724Z","steps":["trace[607211436] 'process raft request' (duration: 329.0317ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:10:03.549094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:10:03.219536Z","time spent":"329.298734ms","remote":"127.0.0.1:54326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T01:10:03.559911Z","caller":"traceutil/trace.go:171","msg":"trace[743492968] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"248.583601ms","start":"2026-05-06T01:10:03.311292Z","end":"2026-05-06T01:10:03.559875Z","steps":["trace[743492968] 'process raft request' (duration: 248.411189ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:10:03.560122Z","caller":"traceutil/trace.go:171","msg":"trace[1888574838] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"244.849073ms","start":"2026-05-06T01:10:03.315247Z","end":"2026-05-06T01:10:03.560096Z","steps":["trace[1888574838] 'process raft request' (duration: 244.62917ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:10:03.560342Z","caller":"traceutil/trace.go:171","msg":"trace[248341091] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"228.064068ms","start":"2026-05-06T01:10:03.332264Z","end":"2026-05-06T01:10:03.560328Z","steps":["trace[248341091] 'process raft request' (duration: 227.729143ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:10:03.560398Z","caller":"traceutil/trace.go:171","msg":"trace[1597978293] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"245.200518ms","start":"2026-05-06T01:10:03.315186Z","end":"2026-05-06T01:10:03.560387Z","steps":["trace[1597978293] 'process raft request' (duration: 244.60176ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:10:03.560507Z","caller":"traceutil/trace.go:171","msg":"trace[121707] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"211.458126ms","start":"2026-05-06T01:10:03.349036Z","end":"2026-05-06T01:10:03.560494Z","steps":["trace[121707] 'process raft request' (duration: 211.085651ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:10:03.560561Z","caller":"traceutil/trace.go:171","msg":"trace[2059289989] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"225.620197ms","start":"2026-05-06T01:10:03.334924Z","end":"2026-05-06T01:10:03.560544Z","steps":["trace[2059289989] 'process raft request' (duration: 225.105962ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:10:03.560635Z","caller":"traceutil/trace.go:171","msg":"trace[1411134070] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"228.474213ms","start":"2026-05-06T01:10:03.332153Z","end":"2026-05-06T01:10:03.560627Z","steps":["trace[1411134070] 'process raft request' (duration: 227.760423ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:10:03.560673Z","caller":"traceutil/trace.go:171","msg":"trace[143482066] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"228.458683ms","start":"2026-05-06T01:10:03.332208Z","end":"2026-05-06T01:10:03.560666Z","steps":["trace[143482066] 'process raft request' (duration: 227.747244ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:10:03.560747Z","caller":"traceutil/trace.go:171","msg":"trace[891290241] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"199.083177ms","start":"2026-05-06T01:10:03.361656Z","end":"2026-05-06T01:10:03.560739Z","steps":["trace[891290241] 'process raft request' (duration: 198.730123ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:10:03.56078Z","caller":"traceutil/trace.go:171","msg":"trace[1292844512] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"209.737603ms","start":"2026-05-06T01:10:03.351032Z","end":"2026-05-06T01:10:03.56077Z","steps":["trace[1292844512] 'process raft request' (duration: 209.246947ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:10:03.560777Z","caller":"traceutil/trace.go:171","msg":"trace[2068787030] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"174.510942ms","start":"2026-05-06T01:10:03.38625Z","end":"2026-05-06T01:10:03.560761Z","steps":["trace[2068787030] 'process raft request' (duration: 174.188428ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:10:03.775919Z","caller":"traceutil/trace.go:171","msg":"trace[293534366] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"123.224396ms","start":"2026-05-06T01:10:03.652674Z","end":"2026-05-06T01:10:03.775899Z","steps":["trace[293534366] 'process raft request' (duration: 84.235567ms)","trace[293534366] 'compare' (duration: 38.886347ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:10:03.777144Z","caller":"traceutil/trace.go:171","msg":"trace[448558691] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"119.195264ms","start":"2026-05-06T01:10:03.657923Z","end":"2026-05-06T01:10:03.777118Z","steps":["trace[448558691] 'process raft request' (duration: 118.766609ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:10:03.777592Z","caller":"traceutil/trace.go:171","msg":"trace[459254941] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"119.582891ms","start":"2026-05-06T01:10:03.657991Z","end":"2026-05-06T01:10:03.777574Z","steps":["trace[459254941] 'process raft request' (duration: 118.906971ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:10:03.777706Z","caller":"traceutil/trace.go:171","msg":"trace[1244409196] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"113.796486ms","start":"2026-05-06T01:10:03.663892Z","end":"2026-05-06T01:10:03.777689Z","steps":["trace[1244409196] 'process raft request' (duration: 113.583563ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:10:03.778375Z","caller":"traceutil/trace.go:171","msg":"trace[366560402] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"110.558646ms","start":"2026-05-06T01:10:03.667803Z","end":"2026-05-06T01:10:03.778362Z","steps":["trace[366560402] 'process raft request' (duration: 109.709355ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:10:10.402171Z","caller":"traceutil/trace.go:171","msg":"trace[1179975242] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"555.793496ms","start":"2026-05-06T01:10:09.846347Z","end":"2026-05-06T01:10:10.40214Z","steps":["trace[1179975242] 'process raft request' (duration: 458.321361ms)","trace[1179975242] 'compare' (duration: 97.092216ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:10:10.402318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:10:09.846325Z","time spent":"555.91858ms","remote":"127.0.0.1:54256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T01:10:10.659476Z","caller":"traceutil/trace.go:171","msg":"trace[2044976993] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:643; }","duration":"738.898993ms","start":"2026-05-06T01:10:09.920554Z","end":"2026-05-06T01:10:10.659453Z","steps":["trace[2044976993] 'read index received' (duration: 384.222529ms)","trace[2044976993] 'applied index is now lower than readState.Index' (duration: 354.675904ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:10:10.659591Z","caller":"traceutil/trace.go:171","msg":"trace[1709290760] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"756.351271ms","start":"2026-05-06T01:10:09.903232Z","end":"2026-05-06T01:10:10.659583Z","steps":["trace[1709290760] 'process raft request' (duration: 755.821449ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:10:10.659713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:10:09.9032Z","time spent":"756.409382ms","remote":"127.0.0.1:54142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036301,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T01:10:10.659946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.12556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-06T01:10:10.660054Z","caller":"traceutil/trace.go:171","msg":"trace[552213191] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:621; }","duration":"492.283034ms","start":"2026-05-06T01:10:10.16774Z","end":"2026-05-06T01:10:10.660024Z","steps":["trace[552213191] 'agreement among raft nodes before linearized reading' (duration: 492.019028ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:10:10.660117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:10:10.167717Z","time spent":"492.385896ms","remote":"127.0.0.1:54326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-06T01:10:10.660698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"740.095961ms","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-05-06T01:10:10.660763Z","caller":"traceutil/trace.go:171","msg":"trace[260976754] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:621; }","duration":"740.242264ms","start":"2026-05-06T01:10:09.920508Z","end":"2026-05-06T01:10:10.66075Z","steps":["trace[260976754] 'agreement among raft nodes before linearized reading' (duration: 739.549238ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:10:10.660785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:10:09.920489Z","time spent":"740.290754ms","remote":"127.0.0.1:54326","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-05-06T01:10:10.660934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.907255ms","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-05-06T01:10:10.660978Z","caller":"traceutil/trace.go:171","msg":"trace[907828904] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:621; }","duration":"197.996508ms","start":"2026-05-06T01:10:10.46297Z","end":"2026-05-06T01:10:10.660966Z","steps":["trace[907828904] 'agreement among raft nodes before linearized reading' (duration: 197.024236ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:10:11.014296Z","caller":"traceutil/trace.go:171","msg":"trace[1730572140] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"348.718344ms","start":"2026-05-06T01:10:10.665551Z","end":"2026-05-06T01:10:11.014269Z","steps":["trace[1730572140] 'process raft request' (duration: 348.456057ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:10:11.014366Z","caller":"traceutil/trace.go:171","msg":"trace[995940301] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"346.766809ms","start":"2026-05-06T01:10:10.667568Z","end":"2026-05-06T01:10:11.014335Z","steps":["trace[995940301] 'process raft request' (duration: 346.580514ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:10:11.014442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:10:10.665541Z","time spent":"348.813315ms","remote":"127.0.0.1:54326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T01:10:11.014524Z","caller":"traceutil/trace.go:171","msg":"trace[1465598019] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"297.602152ms","start":"2026-05-06T01:10:10.716878Z","end":"2026-05-06T01:10:11.01448Z","steps":["trace[1465598019] 'process raft request' (duration: 297.345036ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:10:11.01463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:10:10.667559Z","time spent":"346.902662ms","remote":"127.0.0.1:54326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T01:10:11.910727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.803475ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:10:11.910905Z","caller":"traceutil/trace.go:171","msg":"trace[1266445378] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:625; }","duration":"136.99979ms","start":"2026-05-06T01:10:11.773888Z","end":"2026-05-06T01:10:11.910888Z","steps":["trace[1266445378] 'range keys from in-memory index tree' (duration: 136.785205ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:10:11.911929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"844.025569ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-06T01:10:11.912216Z","caller":"traceutil/trace.go:171","msg":"trace[114472070] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:649; }","duration":"433.778797ms","start":"2026-05-06T01:10:11.478414Z","end":"2026-05-06T01:10:11.912193Z","steps":["trace[114472070] 'read index received' (duration: 255.387µs)","trace[114472070] 'applied index is now lower than readState.Index' (duration: 433.52121ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:10:11.912482Z","caller":"traceutil/trace.go:171","msg":"trace[1636886697] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"883.492778ms","start":"2026-05-06T01:10:11.028973Z","end":"2026-05-06T01:10:11.912466Z","steps":["trace[1636886697] 'process raft request' (duration: 38.869684ms)","trace[1636886697] 'compare' (duration: 843.044186ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:10:11.91259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:10:11.02896Z","time spent":"883.59767ms","remote":"127.0.0.1:54256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T01:10:11.912945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.481432ms","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:5"} {"level":"info","ts":"2026-05-06T01:10:11.913151Z","caller":"traceutil/trace.go:171","msg":"trace[1925012880] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:626; }","duration":"434.728759ms","start":"2026-05-06T01:10:11.478404Z","end":"2026-05-06T01:10:11.913132Z","steps":["trace[1925012880] 'agreement among raft nodes before linearized reading' (duration: 434.414452ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:10:11.913241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.383109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:10:11.913344Z","caller":"traceutil/trace.go:171","msg":"trace[39775003] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:626; }","duration":"231.514531ms","start":"2026-05-06T01:10:11.681776Z","end":"2026-05-06T01:10:11.913291Z","steps":["trace[39775003] 'agreement among raft nodes before linearized reading' (duration: 231.370578ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:10:11.913444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.324955ms","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\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:10:11.913498Z","caller":"traceutil/trace.go:171","msg":"trace[1415801772] 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:626; }","duration":"387.392756ms","start":"2026-05-06T01:10:11.526086Z","end":"2026-05-06T01:10:11.913478Z","steps":["trace[1415801772] 'agreement among raft nodes before linearized reading' (duration: 387.306364ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:10:11.913529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:10:11.526083Z","time spent":"387.438528ms","remote":"127.0.0.1:54564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":131,"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\" limit:10000 "} {"level":"warn","ts":"2026-05-06T01:10:11.913541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.515804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:10:11.913638Z","caller":"traceutil/trace.go:171","msg":"trace[274900847] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:626; }","duration":"346.607416ms","start":"2026-05-06T01:10:11.566973Z","end":"2026-05-06T01:10:11.91358Z","steps":["trace[274900847] 'agreement among raft nodes before linearized reading' (duration: 346.486024ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:10:11.913682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.776891ms","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\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:10:11.913703Z","caller":"traceutil/trace.go:171","msg":"trace[1148419893] 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:626; }","duration":"386.808812ms","start":"2026-05-06T01:10:11.526889Z","end":"2026-05-06T01:10:11.913697Z","steps":["trace[1148419893] 'agreement among raft nodes before linearized reading' (duration: 386.774152ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:10:11.913727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:10:11.526884Z","time spent":"386.837812ms","remote":"127.0.0.1:54564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":131,"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\" limit:500 "} {"level":"warn","ts":"2026-05-06T01:10:11.913726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:10:11.566933Z","time spent":"346.744481ms","remote":"127.0.0.1:54038","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-05-06T01:10:11.913831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.411515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:10:11.913871Z","caller":"traceutil/trace.go:171","msg":"trace[1697789410] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:626; }","duration":"435.493156ms","start":"2026-05-06T01:10:11.478368Z","end":"2026-05-06T01:10:11.913861Z","steps":["trace[1697789410] 'agreement among raft nodes before linearized reading' (duration: 435.431815ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:10:11.913902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:10:11.478347Z","time spent":"435.546348ms","remote":"127.0.0.1:54540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 "} {"level":"warn","ts":"2026-05-06T01:10:11.913927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.43132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:10:11.913954Z","caller":"traceutil/trace.go:171","msg":"trace[386375377] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:626; }","duration":"117.636866ms","start":"2026-05-06T01:10:11.796311Z","end":"2026-05-06T01:10:11.913948Z","steps":["trace[386375377] 'agreement among raft nodes before linearized reading' (duration: 117.596314ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:10:11.914062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.286998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:10:11.914085Z","caller":"traceutil/trace.go:171","msg":"trace[536227034] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:626; }","duration":"117.352069ms","start":"2026-05-06T01:10:11.796727Z","end":"2026-05-06T01:10:11.914079Z","steps":["trace[536227034] 'agreement among raft nodes before linearized reading' (duration: 117.316027ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:10:11.914231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.99127ms","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-05-06T01:10:11.91427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.965343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:10:11.914298Z","caller":"traceutil/trace.go:171","msg":"trace[1129070692] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:626; }","duration":"411.998254ms","start":"2026-05-06T01:10:11.502287Z","end":"2026-05-06T01:10:11.914285Z","steps":["trace[1129070692] 'agreement among raft nodes before linearized reading' (duration: 411.966363ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:10:11.914323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:10:11.502282Z","time spent":"412.035635ms","remote":"127.0.0.1:54540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:500 "} {"level":"info","ts":"2026-05-06T01:10:11.91432Z","caller":"traceutil/trace.go:171","msg":"trace[1533971533] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:626; }","duration":"334.093103ms","start":"2026-05-06T01:10:11.580211Z","end":"2026-05-06T01:10:11.914304Z","steps":["trace[1533971533] 'agreement among raft nodes before linearized reading' (duration: 333.830586ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:10:11.914376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.297147ms","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-05-06T01:10:11.914396Z","caller":"traceutil/trace.go:171","msg":"trace[1114162750] 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:626; }","duration":"388.336678ms","start":"2026-05-06T01:10:11.526054Z","end":"2026-05-06T01:10:11.914391Z","steps":["trace[1114162750] 'agreement among raft nodes before linearized reading' (duration: 388.308357ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:10:11.914414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:10:11.526043Z","time spent":"388.365548ms","remote":"127.0.0.1:54564","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-05-06T01:10:11.914412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:10:11.580204Z","time spent":"334.193315ms","remote":"127.0.0.1:54326","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-05-06T01:10:11.914537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.25604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:10:11.91456Z","caller":"traceutil/trace.go:171","msg":"trace[918028333] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:626; }","duration":"118.372652ms","start":"2026-05-06T01:10:11.796182Z","end":"2026-05-06T01:10:11.914555Z","steps":["trace[918028333] 'agreement among raft nodes before linearized reading' (duration: 118.338482ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:10:11.914617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.782892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:10:11.914653Z","caller":"traceutil/trace.go:171","msg":"trace[17767081] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:626; }","duration":"232.829143ms","start":"2026-05-06T01:10:11.681815Z","end":"2026-05-06T01:10:11.914645Z","steps":["trace[17767081] 'agreement among raft nodes before linearized reading' (duration: 232.775401ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:10:11.914702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.214542ms","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:5"} {"level":"info","ts":"2026-05-06T01:10:11.914736Z","caller":"traceutil/trace.go:171","msg":"trace[1147613827] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:626; }","duration":"413.248484ms","start":"2026-05-06T01:10:11.501479Z","end":"2026-05-06T01:10:11.914727Z","steps":["trace[1147613827] 'agreement among raft nodes before linearized reading' (duration: 413.202022ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:10:11.914758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:10:11.501435Z","time spent":"413.316875ms","remote":"127.0.0.1:54548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-06T01:10:11.914962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.530561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:10:11.914999Z","caller":"traceutil/trace.go:171","msg":"trace[1122660858] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:626; }","duration":"413.593091ms","start":"2026-05-06T01:10:11.501397Z","end":"2026-05-06T01:10:11.91499Z","steps":["trace[1122660858] 'agreement among raft nodes before linearized reading' (duration: 413.537331ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:10:11.915026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:10:11.501382Z","time spent":"413.635362ms","remote":"127.0.0.1:54548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 "} {"level":"warn","ts":"2026-05-06T01:10:11.913257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:10:11.47838Z","time spent":"434.856582ms","remote":"127.0.0.1:54540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"info","ts":"2026-05-06T01:10:29.149341Z","caller":"traceutil/trace.go:171","msg":"trace[604397219] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"269.349421ms","start":"2026-05-06T01:10:28.879967Z","end":"2026-05-06T01:10:29.149316Z","steps":["trace[604397219] 'process raft request' (duration: 269.194494ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:10:29.159433Z","caller":"traceutil/trace.go:171","msg":"trace[819094888] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"251.054624ms","start":"2026-05-06T01:10:28.908361Z","end":"2026-05-06T01:10:29.159416Z","steps":["trace[819094888] 'process raft request' (duration: 250.953901ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:11:45.760711Z","caller":"traceutil/trace.go:171","msg":"trace[1038809548] linearizableReadLoop","detail":"{readStateIndex:1938; appliedIndex:1937; }","duration":"127.031103ms","start":"2026-05-06T01:11:45.633653Z","end":"2026-05-06T01:11:45.760684Z","steps":["trace[1038809548] 'read index received' (duration: 126.818691ms)","trace[1038809548] 'applied index is now lower than readState.Index' (duration: 211.342µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:11:45.76077Z","caller":"traceutil/trace.go:171","msg":"trace[1401891293] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"127.113228ms","start":"2026-05-06T01:11:45.633627Z","end":"2026-05-06T01:11:45.76074Z","steps":["trace[1401891293] 'process raft request' (duration: 126.861123ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:11:45.760998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.32463ms","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-05-06T01:11:45.761095Z","caller":"traceutil/trace.go:171","msg":"trace[1833826808] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1866; }","duration":"127.46841ms","start":"2026-05-06T01:11:45.633596Z","end":"2026-05-06T01:11:45.761064Z","steps":["trace[1833826808] 'agreement among raft nodes before linearized reading' (duration: 127.200364ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:11:46.112152Z","caller":"traceutil/trace.go:171","msg":"trace[1989760688] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"457.574583ms","start":"2026-05-06T01:11:45.654551Z","end":"2026-05-06T01:11:46.112126Z","steps":["trace[1989760688] 'process raft request' (duration: 457.454385ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:11:46.112189Z","caller":"traceutil/trace.go:171","msg":"trace[645407461] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"476.865015ms","start":"2026-05-06T01:11:45.635289Z","end":"2026-05-06T01:11:46.112154Z","steps":["trace[645407461] 'process raft request' (duration: 458.682507ms)","trace[645407461] 'compare' (duration: 17.717841ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:11:46.112607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:11:45.635273Z","time spent":"477.239456ms","remote":"127.0.0.1:54326","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-05-06T01:11:46.112633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:11:45.654536Z","time spent":"457.932573ms","remote":"127.0.0.1:54326","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-05-06T01:11:46.201923Z","caller":"traceutil/trace.go:171","msg":"trace[347984724] transaction","detail":"{read_only:false; response_revision:1869; number_of_response:1; }","duration":"436.65608ms","start":"2026-05-06T01:11:45.765239Z","end":"2026-05-06T01:11:46.201895Z","steps":["trace[347984724] 'process raft request' (duration: 436.388995ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:11:46.202159Z","caller":"traceutil/trace.go:171","msg":"trace[308641545] linearizableReadLoop","detail":"{readStateIndex:1942; appliedIndex:1938; }","duration":"334.332596ms","start":"2026-05-06T01:11:45.867794Z","end":"2026-05-06T01:11:46.202127Z","steps":["trace[308641545] 'read index received' (duration: 226.192336ms)","trace[308641545] 'applied index is now lower than readState.Index' (duration: 108.13853ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:11:46.202214Z","caller":"traceutil/trace.go:171","msg":"trace[249376765] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"385.653668ms","start":"2026-05-06T01:11:45.816515Z","end":"2026-05-06T01:11:46.202169Z","steps":["trace[249376765] 'process raft request' (duration: 385.309567ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:11:46.202432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:11:45.816489Z","time spent":"385.827488ms","remote":"127.0.0.1:54326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T01:11:46.202542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.696919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-52cd3a8b-40d1-4249-a519-31fe3e5e36e6\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-05-06T01:11:46.202655Z","caller":"traceutil/trace.go:171","msg":"trace[573434498] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-52cd3a8b-40d1-4249-a519-31fe3e5e36e6; range_end:; response_count:1; response_revision:1870; }","duration":"334.848807ms","start":"2026-05-06T01:11:45.867784Z","end":"2026-05-06T01:11:46.202633Z","steps":["trace[573434498] 'agreement among raft nodes before linearized reading' (duration: 334.506557ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:11:46.202676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.895441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-06T01:11:46.202757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:11:45.867621Z","time spent":"335.117674ms","remote":"127.0.0.1:54256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-52cd3a8b-40d1-4249-a519-31fe3e5e36e6\" "} {"level":"warn","ts":"2026-05-06T01:11:46.20281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:11:45.765207Z","time spent":"436.8028ms","remote":"127.0.0.1:54326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T01:11:46.202853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.074044ms","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-05-06T01:11:46.202842Z","caller":"traceutil/trace.go:171","msg":"trace[832713195] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1870; }","duration":"274.061971ms","start":"2026-05-06T01:11:45.928763Z","end":"2026-05-06T01:11:46.202825Z","steps":["trace[832713195] 'agreement among raft nodes before linearized reading' (duration: 273.665847ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:11:46.203116Z","caller":"traceutil/trace.go:171","msg":"trace[868762740] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1870; }","duration":"318.322848ms","start":"2026-05-06T01:11:45.884732Z","end":"2026-05-06T01:11:46.203055Z","steps":["trace[868762740] 'agreement among raft nodes before linearized reading' (duration: 317.66517ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:11:46.203228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:11:45.884723Z","time spent":"318.445315ms","remote":"127.0.0.1:52004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-05-06T01:11:46.685094Z","caller":"traceutil/trace.go:171","msg":"trace[115716278] transaction","detail":"{read_only:false; response_revision:1872; number_of_response:1; }","duration":"390.967802ms","start":"2026-05-06T01:11:46.29409Z","end":"2026-05-06T01:11:46.685058Z","steps":["trace[115716278] 'process raft request' (duration: 374.707297ms)","trace[115716278] 'compare' (duration: 15.951377ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:11:46.685268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:11:46.294074Z","time spent":"391.10651ms","remote":"127.0.0.1:54256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3731,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T01:11:46.75013Z","caller":"traceutil/trace.go:171","msg":"trace[1247905373] linearizableReadLoop","detail":"{readStateIndex:1945; appliedIndex:1943; }","duration":"351.025866ms","start":"2026-05-06T01:11:46.399022Z","end":"2026-05-06T01:11:46.750048Z","steps":["trace[1247905373] 'read index received' (duration: 269.769013ms)","trace[1247905373] 'applied index is now lower than readState.Index' (duration: 81.255733ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:11:46.750239Z","caller":"traceutil/trace.go:171","msg":"trace[834181593] transaction","detail":"{read_only:false; response_revision:1873; number_of_response:1; }","duration":"380.610153ms","start":"2026-05-06T01:11:46.369598Z","end":"2026-05-06T01:11:46.750208Z","steps":["trace[834181593] 'process raft request' (duration: 380.318117ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:11:46.750357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.334683ms","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-05-06T01:11:46.750376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.129746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:11:46.751231Z","caller":"traceutil/trace.go:171","msg":"trace[621196397] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1873; }","duration":"184.035679ms","start":"2026-05-06T01:11:46.56718Z","end":"2026-05-06T01:11:46.751216Z","steps":["trace[621196397] 'agreement among raft nodes before linearized reading' (duration: 183.123055ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:11:46.751166Z","caller":"traceutil/trace.go:171","msg":"trace[1252250738] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1873; }","duration":"352.183283ms","start":"2026-05-06T01:11:46.398955Z","end":"2026-05-06T01:11:46.751138Z","steps":["trace[1252250738] 'agreement among raft nodes before linearized reading' (duration: 351.282861ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:11:46.751138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:11:46.369574Z","time spent":"381.364387ms","remote":"127.0.0.1:54326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T01:11:46.751397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:11:46.398934Z","time spent":"352.405017ms","remote":"127.0.0.1:54326","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":"info","ts":"2026-05-06T01:11:54.339752Z","caller":"traceutil/trace.go:171","msg":"trace[646019061] transaction","detail":"{read_only:false; response_revision:1945; number_of_response:1; }","duration":"108.137211ms","start":"2026-05-06T01:11:54.231589Z","end":"2026-05-06T01:11:54.339727Z","steps":["trace[646019061] 'process raft request' (duration: 107.665049ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:11:54.340746Z","caller":"traceutil/trace.go:171","msg":"trace[779274792] linearizableReadLoop","detail":"{readStateIndex:2020; appliedIndex:2018; }","duration":"103.4619ms","start":"2026-05-06T01:11:54.237259Z","end":"2026-05-06T01:11:54.340721Z","steps":["trace[779274792] 'read index received' (duration: 102.126425ms)","trace[779274792] 'applied index is now lower than readState.Index' (duration: 1.333725ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:11:54.34091Z","caller":"traceutil/trace.go:171","msg":"trace[212374526] transaction","detail":"{read_only:false; response_revision:1946; number_of_response:1; }","duration":"109.19291ms","start":"2026-05-06T01:11:54.231703Z","end":"2026-05-06T01:11:54.340895Z","steps":["trace[212374526] 'process raft request' (duration: 108.814258ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:11:54.341226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.908082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-06T01:11:54.341326Z","caller":"traceutil/trace.go:171","msg":"trace[156544109] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1946; }","duration":"104.111889ms","start":"2026-05-06T01:11:54.237196Z","end":"2026-05-06T01:11:54.341308Z","steps":["trace[156544109] 'agreement among raft nodes before linearized reading' (duration: 103.749826ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:11:59.176149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.967774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:11:59.176239Z","caller":"traceutil/trace.go:171","msg":"trace[1972710387] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1973; }","duration":"610.104403ms","start":"2026-05-06T01:11:58.566115Z","end":"2026-05-06T01:11:59.176219Z","steps":["trace[1972710387] 'range keys from in-memory index tree' (duration: 609.889076ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:11:59.176287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:11:58.566093Z","time spent":"610.177172ms","remote":"127.0.0.1:54038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-06T01:11:59.178548Z","caller":"traceutil/trace.go:171","msg":"trace[1769507871] linearizableReadLoop","detail":"{readStateIndex:2049; appliedIndex:2048; }","duration":"330.506053ms","start":"2026-05-06T01:11:58.848018Z","end":"2026-05-06T01:11:59.178525Z","steps":["trace[1769507871] 'read index received' (duration: 330.304466ms)","trace[1769507871] 'applied index is now lower than readState.Index' (duration: 200.527µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:11:59.178673Z","caller":"traceutil/trace.go:171","msg":"trace[2090777591] transaction","detail":"{read_only:false; response_revision:1974; number_of_response:1; }","duration":"335.886943ms","start":"2026-05-06T01:11:58.84277Z","end":"2026-05-06T01:11:59.178657Z","steps":["trace[2090777591] 'process raft request' (duration: 335.611048ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:11:59.17878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:11:58.842745Z","time spent":"335.957303ms","remote":"127.0.0.1:54326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T01:11:59.179526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.814709ms","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-05-06T01:11:59.179585Z","caller":"traceutil/trace.go:171","msg":"trace[1742445518] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1974; }","duration":"331.586428ms","start":"2026-05-06T01:11:58.847982Z","end":"2026-05-06T01:11:59.179568Z","steps":["trace[1742445518] 'agreement among raft nodes before linearized reading' (duration: 330.72319ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:11:59.179642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:11:58.847969Z","time spent":"331.657297ms","remote":"127.0.0.1:54326","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":"info","ts":"2026-05-06T01:12:14.208624Z","caller":"traceutil/trace.go:171","msg":"trace[950314396] linearizableReadLoop","detail":"{readStateIndex:2150; appliedIndex:2149; }","duration":"160.786904ms","start":"2026-05-06T01:12:14.047799Z","end":"2026-05-06T01:12:14.208586Z","steps":["trace[950314396] 'read index received' (duration: 160.568894ms)","trace[950314396] 'applied index is now lower than readState.Index' (duration: 216.33µs)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:12:14.208824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.974373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:12:14.208877Z","caller":"traceutil/trace.go:171","msg":"trace[1898052154] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:2072; }","duration":"161.093093ms","start":"2026-05-06T01:12:14.047766Z","end":"2026-05-06T01:12:14.208859Z","steps":["trace[1898052154] 'agreement among raft nodes before linearized reading' (duration: 160.964583ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:12:14.208762Z","caller":"traceutil/trace.go:171","msg":"trace[848951999] transaction","detail":"{read_only:false; response_revision:2072; number_of_response:1; }","duration":"260.058568ms","start":"2026-05-06T01:12:13.948627Z","end":"2026-05-06T01:12:14.208686Z","steps":["trace[848951999] 'process raft request' (duration: 259.756158ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:12:14.209615Z","caller":"traceutil/trace.go:171","msg":"trace[1501192103] transaction","detail":"{read_only:false; response_revision:2073; number_of_response:1; }","duration":"160.746713ms","start":"2026-05-06T01:12:14.048659Z","end":"2026-05-06T01:12:14.209406Z","steps":["trace[1501192103] 'process raft request' (duration: 160.573404ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:12:20.636808Z","caller":"traceutil/trace.go:171","msg":"trace[242686907] linearizableReadLoop","detail":"{readStateIndex:2186; appliedIndex:2185; }","duration":"162.110638ms","start":"2026-05-06T01:12:20.474677Z","end":"2026-05-06T01:12:20.636788Z","steps":["trace[242686907] 'read index received' (duration: 161.945798ms)","trace[242686907] 'applied index is now lower than readState.Index' (duration: 164.15µs)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:12:20.637001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.321349ms","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-05-06T01:12:20.636986Z","caller":"traceutil/trace.go:171","msg":"trace[1829245874] transaction","detail":"{read_only:false; response_revision:2107; number_of_response:1; }","duration":"198.829576ms","start":"2026-05-06T01:12:20.43807Z","end":"2026-05-06T01:12:20.636899Z","steps":["trace[1829245874] 'process raft request' (duration: 198.614275ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:12:20.637046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.303399ms","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-05-06T01:12:20.637097Z","caller":"traceutil/trace.go:171","msg":"trace[1619656966] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2107; }","duration":"162.39648ms","start":"2026-05-06T01:12:20.474686Z","end":"2026-05-06T01:12:20.637082Z","steps":["trace[1619656966] 'agreement among raft nodes before linearized reading' (duration: 162.261199ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:12:20.63713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.668993ms","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-05-06T01:12:20.637167Z","caller":"traceutil/trace.go:171","msg":"trace[101150546] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2107; }","duration":"123.726993ms","start":"2026-05-06T01:12:20.513431Z","end":"2026-05-06T01:12:20.637158Z","steps":["trace[101150546] 'agreement among raft nodes before linearized reading' (duration: 123.638063ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:12:20.637038Z","caller":"traceutil/trace.go:171","msg":"trace[1613674466] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2107; }","duration":"162.40859ms","start":"2026-05-06T01:12:20.474617Z","end":"2026-05-06T01:12:20.637025Z","steps":["trace[1613674466] 'agreement among raft nodes before linearized reading' (duration: 162.26695ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:12:20.637186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.083294ms","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-05-06T01:12:20.637473Z","caller":"traceutil/trace.go:171","msg":"trace[844346206] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2107; }","duration":"126.413195ms","start":"2026-05-06T01:12:20.511048Z","end":"2026-05-06T01:12:20.637461Z","steps":["trace[844346206] 'agreement among raft nodes before linearized reading' (duration: 126.067774ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:12:20.891124Z","caller":"traceutil/trace.go:171","msg":"trace[2141028860] transaction","detail":"{read_only:false; response_revision:2110; number_of_response:1; }","duration":"248.392342ms","start":"2026-05-06T01:12:20.642708Z","end":"2026-05-06T01:12:20.8911Z","steps":["trace[2141028860] 'process raft request' (duration: 248.335801ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:12:20.891428Z","caller":"traceutil/trace.go:171","msg":"trace[450772874] transaction","detail":"{read_only:false; response_revision:2109; number_of_response:1; }","duration":"249.562957ms","start":"2026-05-06T01:12:20.641849Z","end":"2026-05-06T01:12:20.891412Z","steps":["trace[450772874] 'process raft request' (duration: 249.022414ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:12:21.628246Z","caller":"traceutil/trace.go:171","msg":"trace[1334632982] linearizableReadLoop","detail":"{readStateIndex:2190; appliedIndex:2189; }","duration":"308.008779ms","start":"2026-05-06T01:12:21.320213Z","end":"2026-05-06T01:12:21.628222Z","steps":["trace[1334632982] 'read index received' (duration: 307.875719ms)","trace[1334632982] 'applied index is now lower than readState.Index' (duration: 131.87µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:12:21.628354Z","caller":"traceutil/trace.go:171","msg":"trace[352896638] transaction","detail":"{read_only:false; response_revision:2111; number_of_response:1; }","duration":"372.432149ms","start":"2026-05-06T01:12:21.255911Z","end":"2026-05-06T01:12:21.628344Z","steps":["trace[352896638] 'process raft request' (duration: 372.154488ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:12:21.628458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:12:21.255886Z","time spent":"372.494149ms","remote":"127.0.0.1:54326","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-05-06T01:12:21.628847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.645243ms","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-05-06T01:12:21.628894Z","caller":"traceutil/trace.go:171","msg":"trace[1280307325] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2111; }","duration":"308.724181ms","start":"2026-05-06T01:12:21.32016Z","end":"2026-05-06T01:12:21.628885Z","steps":["trace[1280307325] 'agreement among raft nodes before linearized reading' (duration: 308.421161ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:12:21.628922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:12:21.320144Z","time spent":"308.771352ms","remote":"127.0.0.1:54326","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":"info","ts":"2026-05-06T01:12:21.747315Z","caller":"traceutil/trace.go:171","msg":"trace[1057014408] transaction","detail":"{read_only:false; response_revision:2113; number_of_response:1; }","duration":"113.740241ms","start":"2026-05-06T01:12:21.633556Z","end":"2026-05-06T01:12:21.747297Z","steps":["trace[1057014408] 'process raft request' (duration: 113.704771ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:12:21.747461Z","caller":"traceutil/trace.go:171","msg":"trace[1858604048] transaction","detail":"{read_only:false; response_revision:2112; number_of_response:1; }","duration":"371.073513ms","start":"2026-05-06T01:12:21.376298Z","end":"2026-05-06T01:12:21.747371Z","steps":["trace[1858604048] 'process raft request' (duration: 359.767713ms)","trace[1858604048] 'compare' (duration: 11.086059ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:12:21.747642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:12:21.37627Z","time spent":"371.275613ms","remote":"127.0.0.1:54326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T01:12:21.748066Z","caller":"traceutil/trace.go:171","msg":"trace[1882670967] linearizableReadLoop","detail":"{readStateIndex:2191; appliedIndex:2190; }","duration":"119.732193ms","start":"2026-05-06T01:12:21.628308Z","end":"2026-05-06T01:12:21.74804Z","steps":["trace[1882670967] 'read index received' (duration: 107.709959ms)","trace[1882670967] 'applied index is now lower than readState.Index' (duration: 12.019734ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:12:21.748282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.55113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:12:21.748339Z","caller":"traceutil/trace.go:171","msg":"trace[685966090] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2113; }","duration":"183.700212ms","start":"2026-05-06T01:12:21.564626Z","end":"2026-05-06T01:12:21.748326Z","steps":["trace[685966090] 'agreement among raft nodes before linearized reading' (duration: 183.55008ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:12:21.748765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.701413ms","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-05-06T01:12:21.748809Z","caller":"traceutil/trace.go:171","msg":"trace[1629986729] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2113; }","duration":"104.789104ms","start":"2026-05-06T01:12:21.644007Z","end":"2026-05-06T01:12:21.748796Z","steps":["trace[1629986729] 'agreement among raft nodes before linearized reading' (duration: 104.637624ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:12:22.378717Z","caller":"traceutil/trace.go:171","msg":"trace[908521433] transaction","detail":"{read_only:false; response_revision:2115; number_of_response:1; }","duration":"124.298877ms","start":"2026-05-06T01:12:22.254397Z","end":"2026-05-06T01:12:22.378696Z","steps":["trace[908521433] 'process raft request' (duration: 124.194166ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:13:32.870201Z","caller":"traceutil/trace.go:171","msg":"trace[160616718] transaction","detail":"{read_only:false; response_revision:2597; number_of_response:1; }","duration":"146.340292ms","start":"2026-05-06T01:13:32.723825Z","end":"2026-05-06T01:13:32.870165Z","steps":["trace[160616718] 'process raft request' (duration: 145.337505ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:13:59.570982Z","caller":"traceutil/trace.go:171","msg":"trace[1399090683] transaction","detail":"{read_only:false; response_revision:2748; number_of_response:1; }","duration":"119.970149ms","start":"2026-05-06T01:13:59.450989Z","end":"2026-05-06T01:13:59.570959Z","steps":["trace[1399090683] 'process raft request' (duration: 94.018137ms)","trace[1399090683] 'compare' (duration: 25.872648ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:14:05.323879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.568921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-06T01:14:05.323983Z","caller":"traceutil/trace.go:171","msg":"trace[2092848080] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2776; }","duration":"163.744089ms","start":"2026-05-06T01:14:05.160218Z","end":"2026-05-06T01:14:05.323962Z","steps":["trace[2092848080] 'range keys from in-memory index tree' (duration: 163.358873ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:14:05.440258Z","caller":"traceutil/trace.go:171","msg":"trace[508272997] transaction","detail":"{read_only:false; response_revision:2777; number_of_response:1; }","duration":"110.217041ms","start":"2026-05-06T01:14:05.330012Z","end":"2026-05-06T01:14:05.440229Z","steps":["trace[508272997] 'process raft request' (duration: 109.832874ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:14:05.66609Z","caller":"traceutil/trace.go:171","msg":"trace[1496214652] transaction","detail":"{read_only:false; response_revision:2778; number_of_response:1; }","duration":"220.323218ms","start":"2026-05-06T01:14:05.445743Z","end":"2026-05-06T01:14:05.666066Z","steps":["trace[1496214652] 'process raft request' (duration: 220.15503ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:14:05.674823Z","caller":"traceutil/trace.go:171","msg":"trace[160317794] linearizableReadLoop","detail":"{readStateIndex:2882; appliedIndex:2880; }","duration":"167.786882ms","start":"2026-05-06T01:14:05.507014Z","end":"2026-05-06T01:14:05.674801Z","steps":["trace[160317794] 'read index received' (duration: 158.836401ms)","trace[160317794] 'applied index is now lower than readState.Index' (duration: 8.948571ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:14:05.674919Z","caller":"traceutil/trace.go:171","msg":"trace[1516774696] transaction","detail":"{read_only:false; response_revision:2779; number_of_response:1; }","duration":"228.862921ms","start":"2026-05-06T01:14:05.44603Z","end":"2026-05-06T01:14:05.674893Z","steps":["trace[1516774696] 'process raft request' (duration: 228.464684ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:14:05.675108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.087964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18acd4e346212b47\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-05-06T01:14:05.675145Z","caller":"traceutil/trace.go:171","msg":"trace[1018912754] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18acd4e346212b47; range_end:; response_count:1; response_revision:2779; }","duration":"168.140936ms","start":"2026-05-06T01:14:05.506993Z","end":"2026-05-06T01:14:05.675134Z","steps":["trace[1018912754] 'agreement among raft nodes before linearized reading' (duration: 167.962089ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:14:05.675235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.91313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:14:05.675354Z","caller":"traceutil/trace.go:171","msg":"trace[1295769562] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2779; }","duration":"107.156471ms","start":"2026-05-06T01:14:05.568174Z","end":"2026-05-06T01:14:05.675331Z","steps":["trace[1295769562] 'agreement among raft nodes before linearized reading' (duration: 106.907ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:15:03.272972Z","caller":"traceutil/trace.go:171","msg":"trace[19075769] transaction","detail":"{read_only:false; response_revision:3080; number_of_response:1; }","duration":"116.528357ms","start":"2026-05-06T01:15:03.15642Z","end":"2026-05-06T01:15:03.272948Z","steps":["trace[19075769] 'process raft request' (duration: 116.142018ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:16:30.118635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.315076ms","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-05-06T01:16:30.118746Z","caller":"traceutil/trace.go:171","msg":"trace[1411909994] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3627; }","duration":"106.470207ms","start":"2026-05-06T01:16:30.012253Z","end":"2026-05-06T01:16:30.118723Z","steps":["trace[1411909994] 'range keys from in-memory index tree' (duration: 106.074696ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:17:44.333329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.032516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/prometheus-tls\" ","response":"range_response_count:1 size:3405"} {"level":"info","ts":"2026-05-06T01:17:44.333403Z","caller":"traceutil/trace.go:171","msg":"trace[1578752193] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/prometheus-tls; range_end:; response_count:1; response_revision:4414; }","duration":"105.145577ms","start":"2026-05-06T01:17:44.228242Z","end":"2026-05-06T01:17:44.333387Z","steps":["trace[1578752193] 'range keys from in-memory index tree' (duration: 104.76729ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:17:54.605222Z","caller":"traceutil/trace.go:171","msg":"trace[224662504] transaction","detail":"{read_only:false; response_revision:4698; number_of_response:1; }","duration":"319.839515ms","start":"2026-05-06T01:17:54.285368Z","end":"2026-05-06T01:17:54.605207Z","steps":["trace[224662504] 'process raft request' (duration: 319.14757ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:17:54.605318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:17:54.285349Z","time spent":"319.921227ms","remote":"127.0.0.1:54326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T01:17:59.147449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.505435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:17:59.147522Z","caller":"traceutil/trace.go:171","msg":"trace[1861531993] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4781; }","duration":"250.609038ms","start":"2026-05-06T01:17:58.896895Z","end":"2026-05-06T01:17:59.147504Z","steps":["trace[1861531993] 'range keys from in-memory index tree' (duration: 250.444615ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:17:59.147988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.657466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-cfd685666-q8tvk\" ","response":"range_response_count:1 size:11251"} {"level":"info","ts":"2026-05-06T01:17:59.148078Z","caller":"traceutil/trace.go:171","msg":"trace[1506609175] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-grafana-cfd685666-q8tvk; range_end:; response_count:1; response_revision:4781; }","duration":"114.796939ms","start":"2026-05-06T01:17:59.03326Z","end":"2026-05-06T01:17:59.148057Z","steps":["trace[1506609175] 'range keys from in-memory index tree' (duration: 114.475152ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:17:59.148258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.861092ms","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-05-06T01:17:59.148374Z","caller":"traceutil/trace.go:171","msg":"trace[1251637597] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4781; }","duration":"179.032917ms","start":"2026-05-06T01:17:58.969323Z","end":"2026-05-06T01:17:59.148356Z","steps":["trace[1251637597] 'range keys from in-memory index tree' (duration: 178.658667ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:17:59.156306Z","caller":"traceutil/trace.go:171","msg":"trace[718522358] transaction","detail":"{read_only:false; response_revision:4782; number_of_response:1; }","duration":"121.412263ms","start":"2026-05-06T01:17:59.034873Z","end":"2026-05-06T01:17:59.156285Z","steps":["trace[718522358] 'process raft request' (duration: 121.28743ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:17:59.569005Z","caller":"traceutil/trace.go:171","msg":"trace[1397876814] linearizableReadLoop","detail":"{readStateIndex:4962; appliedIndex:4961; }","duration":"412.721622ms","start":"2026-05-06T01:17:59.156262Z","end":"2026-05-06T01:17:59.568984Z","steps":["trace[1397876814] 'read index received' (duration: 388.625672ms)","trace[1397876814] 'applied index is now lower than readState.Index' (duration: 24.09493ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:17:59.569054Z","caller":"traceutil/trace.go:171","msg":"trace[764868147] transaction","detail":"{read_only:false; response_revision:4783; number_of_response:1; }","duration":"416.211623ms","start":"2026-05-06T01:17:59.152811Z","end":"2026-05-06T01:17:59.569022Z","steps":["trace[764868147] 'process raft request' (duration: 392.065391ms)","trace[764868147] 'compare' (duration: 24.022699ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:17:59.569115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.418487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:17:59.569142Z","caller":"traceutil/trace.go:171","msg":"trace[2141613705] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4783; }","duration":"416.49882ms","start":"2026-05-06T01:17:59.152637Z","end":"2026-05-06T01:17:59.569136Z","steps":["trace[2141613705] 'agreement among raft nodes before linearized reading' (duration: 416.438798ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:17:59.569167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:17:59.152617Z","time spent":"416.54456ms","remote":"127.0.0.1:54270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-05-06T01:17:59.569219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:17:59.152788Z","time spent":"416.327735ms","remote":"127.0.0.1:54326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T01:17:59.603012Z","caller":"traceutil/trace.go:171","msg":"trace[1305477235] transaction","detail":"{read_only:false; response_revision:4784; number_of_response:1; }","duration":"444.539851ms","start":"2026-05-06T01:17:59.158455Z","end":"2026-05-06T01:17:59.602994Z","steps":["trace[1305477235] 'process raft request' (duration: 444.332596ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:17:59.603163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:17:59.158441Z","time spent":"444.628343ms","remote":"127.0.0.1:54326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T01:17:59.603323Z","caller":"traceutil/trace.go:171","msg":"trace[495703191] transaction","detail":"{read_only:false; response_revision:4785; number_of_response:1; }","duration":"444.363037ms","start":"2026-05-06T01:17:59.158934Z","end":"2026-05-06T01:17:59.603297Z","steps":["trace[495703191] 'process raft request' (duration: 443.991707ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:17:59.603476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:17:59.158921Z","time spent":"444.46991ms","remote":"127.0.0.1:54256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11827,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T01:17:59.603478Z","caller":"traceutil/trace.go:171","msg":"trace[1807269276] transaction","detail":"{read_only:false; response_revision:4786; number_of_response:1; }","duration":"278.262956ms","start":"2026-05-06T01:17:59.325201Z","end":"2026-05-06T01:17:59.603464Z","steps":["trace[1807269276] 'process raft request' (duration: 277.99662ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:17:59.603982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.5607ms","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-05-06T01:17:59.604033Z","caller":"traceutil/trace.go:171","msg":"trace[1363431330] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:4786; }","duration":"138.634803ms","start":"2026-05-06T01:17:59.465383Z","end":"2026-05-06T01:17:59.604018Z","steps":["trace[1363431330] 'agreement among raft nodes before linearized reading' (duration: 138.52625ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:17:59.604103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.179674ms","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-05-06T01:17:59.604192Z","caller":"traceutil/trace.go:171","msg":"trace[504578670] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4786; }","duration":"253.309337ms","start":"2026-05-06T01:17:59.350862Z","end":"2026-05-06T01:17:59.604171Z","steps":["trace[504578670] 'agreement among raft nodes before linearized reading' (duration: 253.071941ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:18:00.270034Z","caller":"traceutil/trace.go:171","msg":"trace[1952464088] transaction","detail":"{read_only:false; response_revision:4793; number_of_response:1; }","duration":"105.755487ms","start":"2026-05-06T01:18:00.164251Z","end":"2026-05-06T01:18:00.270007Z","steps":["trace[1952464088] 'process raft request' (duration: 105.585183ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:18:04.767087Z","caller":"traceutil/trace.go:171","msg":"trace[670187246] linearizableReadLoop","detail":"{readStateIndex:5043; appliedIndex:5042; }","duration":"257.713937ms","start":"2026-05-06T01:18:04.509348Z","end":"2026-05-06T01:18:04.767062Z","steps":["trace[670187246] 'read index received' (duration: 257.522352ms)","trace[670187246] 'applied index is now lower than readState.Index' (duration: 190.355µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:18:04.767179Z","caller":"traceutil/trace.go:171","msg":"trace[721020131] transaction","detail":"{read_only:false; response_revision:4862; number_of_response:1; }","duration":"300.225991ms","start":"2026-05-06T01:18:04.466928Z","end":"2026-05-06T01:18:04.767154Z","steps":["trace[721020131] 'process raft request' (duration: 299.972106ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:04.767333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:04.466912Z","time spent":"300.351024ms","remote":"127.0.0.1:54804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2131,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T01:18:04.767426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.054285ms","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-05-06T01:18:04.767475Z","caller":"traceutil/trace.go:171","msg":"trace[657030259] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:4862; }","duration":"258.157988ms","start":"2026-05-06T01:18:04.509305Z","end":"2026-05-06T01:18:04.767463Z","steps":["trace[657030259] 'agreement among raft nodes before linearized reading' (duration: 257.876701ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:04.767554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.11856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:18:04.76759Z","caller":"traceutil/trace.go:171","msg":"trace[533127436] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4863; }","duration":"200.180862ms","start":"2026-05-06T01:18:04.567403Z","end":"2026-05-06T01:18:04.767583Z","steps":["trace[533127436] 'agreement among raft nodes before linearized reading' (duration: 200.11331ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:04.767741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.191065ms","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-05-06T01:18:04.767759Z","caller":"traceutil/trace.go:171","msg":"trace[1674492748] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4863; }","duration":"110.230485ms","start":"2026-05-06T01:18:04.657524Z","end":"2026-05-06T01:18:04.767755Z","steps":["trace[1674492748] 'agreement among raft nodes before linearized reading' (duration: 110.164243ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:04.767887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.214719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-06T01:18:04.767903Z","caller":"traceutil/trace.go:171","msg":"trace[1251056958] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:4863; }","duration":"165.254819ms","start":"2026-05-06T01:18:04.602645Z","end":"2026-05-06T01:18:04.7679Z","steps":["trace[1251056958] 'agreement among raft nodes before linearized reading' (duration: 165.181987ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:10.030211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.734797ms","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-05-06T01:18:10.03032Z","caller":"traceutil/trace.go:171","msg":"trace[1006787517] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4933; }","duration":"187.894291ms","start":"2026-05-06T01:18:09.842404Z","end":"2026-05-06T01:18:10.030298Z","steps":["trace[1006787517] 'range keys from in-memory index tree' (duration: 187.562083ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:10.030308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.750948ms","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-05-06T01:18:10.030387Z","caller":"traceutil/trace.go:171","msg":"trace[157239207] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4933; }","duration":"322.884031ms","start":"2026-05-06T01:18:09.707485Z","end":"2026-05-06T01:18:10.030369Z","steps":["trace[157239207] 'range keys from in-memory index tree' (duration: 322.589554ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:10.030514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:09.707459Z","time spent":"323.027465ms","remote":"127.0.0.1:54326","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":"warn","ts":"2026-05-06T01:18:10.030869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.183848ms","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-05-06T01:18:10.030921Z","caller":"traceutil/trace.go:171","msg":"trace[298274260] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4933; }","duration":"135.625071ms","start":"2026-05-06T01:18:09.895282Z","end":"2026-05-06T01:18:10.030907Z","steps":["trace[298274260] 'range keys from in-memory index tree' (duration: 134.544643ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:18:16.097482Z","caller":"traceutil/trace.go:171","msg":"trace[903635889] transaction","detail":"{read_only:false; response_revision:4989; number_of_response:1; }","duration":"436.507423ms","start":"2026-05-06T01:18:15.660952Z","end":"2026-05-06T01:18:16.097459Z","steps":["trace[903635889] 'process raft request' (duration: 436.198494ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:16.097647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:15.660927Z","time spent":"436.632236ms","remote":"127.0.0.1:54326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T01:18:16.472952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.015528ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T01:18:16.473312Z","caller":"traceutil/trace.go:171","msg":"trace[132947631] transaction","detail":"{read_only:false; response_revision:4991; number_of_response:1; }","duration":"138.004444ms","start":"2026-05-06T01:18:16.33529Z","end":"2026-05-06T01:18:16.473295Z","steps":["trace[132947631] 'process raft request' (duration: 137.776969ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:18:16.473339Z","caller":"traceutil/trace.go:171","msg":"trace[838700442] transaction","detail":"{read_only:false; response_revision:4990; number_of_response:1; }","duration":"812.130667ms","start":"2026-05-06T01:18:15.661164Z","end":"2026-05-06T01:18:16.473294Z","steps":["trace[838700442] 'process raft request' (duration: 706.70589ms)","trace[838700442] 'compare' (duration: 104.856002ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:18:16.473508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:15.661154Z","time spent":"812.271761ms","remote":"127.0.0.1:54326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T01:18:16.473587Z","caller":"traceutil/trace.go:171","msg":"trace[617771596] linearizableReadLoop","detail":"{readStateIndex:5175; appliedIndex:5173; }","duration":"805.117297ms","start":"2026-05-06T01:18:15.668459Z","end":"2026-05-06T01:18:16.473576Z","steps":["trace[617771596] 'read index received' (duration: 428.818205ms)","trace[617771596] 'applied index is now lower than readState.Index' (duration: 376.297762ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:18:16.473958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"666.026816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T01:18:16.473979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"805.516538ms","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-05-06T01:18:16.474003Z","caller":"traceutil/trace.go:171","msg":"trace[666407585] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:4991; }","duration":"666.107797ms","start":"2026-05-06T01:18:15.807882Z","end":"2026-05-06T01:18:16.47399Z","steps":["trace[666407585] 'agreement among raft nodes before linearized reading' (duration: 666.037015ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:18:16.474032Z","caller":"traceutil/trace.go:171","msg":"trace[828581603] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4991; }","duration":"805.599479ms","start":"2026-05-06T01:18:15.668418Z","end":"2026-05-06T01:18:16.474018Z","steps":["trace[828581603] 'agreement among raft nodes before linearized reading' (duration: 805.384693ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:16.474087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:15.668402Z","time spent":"805.674031ms","remote":"127.0.0.1:54326","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-05-06T01:18:16.474248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.264439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-05-06T01:18:16.47429Z","caller":"traceutil/trace.go:171","msg":"trace[1062388327] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4991; }","duration":"275.335361ms","start":"2026-05-06T01:18:16.198944Z","end":"2026-05-06T01:18:16.474279Z","steps":["trace[1062388327] 'agreement among raft nodes before linearized reading' (duration: 275.169466ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:16.474038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:15.807865Z","time spent":"666.16409ms","remote":"127.0.0.1:51866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true "} {"level":"warn","ts":"2026-05-06T01:18:16.474532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.774512ms","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-05-06T01:18:16.474564Z","caller":"traceutil/trace.go:171","msg":"trace[58129504] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4991; }","duration":"332.841983ms","start":"2026-05-06T01:18:16.141711Z","end":"2026-05-06T01:18:16.474553Z","steps":["trace[58129504] 'agreement among raft nodes before linearized reading' (duration: 332.754332ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:16.474608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:16.14169Z","time spent":"332.912005ms","remote":"127.0.0.1:54326","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-05-06T01:18:16.477194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.474816ms","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-05-06T01:18:16.477256Z","caller":"traceutil/trace.go:171","msg":"trace[1486248618] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4991; }","duration":"411.600559ms","start":"2026-05-06T01:18:16.06564Z","end":"2026-05-06T01:18:16.47724Z","steps":["trace[1486248618] 'agreement among raft nodes before linearized reading' (duration: 409.027872ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:16.477467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:16.065623Z","time spent":"411.830585ms","remote":"127.0.0.1:54326","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-05-06T01:18:17.255964Z","caller":"traceutil/trace.go:171","msg":"trace[489296925] linearizableReadLoop","detail":"{readStateIndex:5182; appliedIndex:5181; }","duration":"358.17091ms","start":"2026-05-06T01:18:16.897769Z","end":"2026-05-06T01:18:17.25594Z","steps":["trace[489296925] 'read index received' (duration: 357.962514ms)","trace[489296925] 'applied index is now lower than readState.Index' (duration: 207.486µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:18:17.256024Z","caller":"traceutil/trace.go:171","msg":"trace[743895417] transaction","detail":"{read_only:false; response_revision:4996; number_of_response:1; }","duration":"407.12285ms","start":"2026-05-06T01:18:16.848872Z","end":"2026-05-06T01:18:17.255995Z","steps":["trace[743895417] 'process raft request' (duration: 406.911234ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:17.256237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.450466ms","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-05-06T01:18:17.256278Z","caller":"traceutil/trace.go:171","msg":"trace[948431324] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4996; }","duration":"358.545319ms","start":"2026-05-06T01:18:16.897723Z","end":"2026-05-06T01:18:17.256268Z","steps":["trace[948431324] 'agreement among raft nodes before linearized reading' (duration: 358.337654ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:17.256314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:16.897707Z","time spent":"358.59888ms","remote":"127.0.0.1:54326","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-05-06T01:18:17.256461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.603932ms","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":"warn","ts":"2026-05-06T01:18:17.256489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.47733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:18:17.256576Z","caller":"traceutil/trace.go:171","msg":"trace[364721096] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4996; }","duration":"340.779258ms","start":"2026-05-06T01:18:16.915778Z","end":"2026-05-06T01:18:17.256557Z","steps":["trace[364721096] 'agreement among raft nodes before linearized reading' (duration: 340.388318ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:18:17.25661Z","caller":"traceutil/trace.go:171","msg":"trace[2103750039] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:4996; }","duration":"204.678296ms","start":"2026-05-06T01:18:17.051912Z","end":"2026-05-06T01:18:17.256591Z","steps":["trace[2103750039] 'agreement among raft nodes before linearized reading' (duration: 204.404188ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:17.256716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:16.915768Z","time spent":"340.935412ms","remote":"127.0.0.1:54326","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-05-06T01:18:17.256243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:16.848854Z","time spent":"407.232093ms","remote":"127.0.0.1:54074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T01:18:17.71291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.075167ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T01:18:17.713088Z","caller":"traceutil/trace.go:171","msg":"trace[1854683231] linearizableReadLoop","detail":"{readStateIndex:5184; appliedIndex:5182; }","duration":"451.01987ms","start":"2026-05-06T01:18:17.262048Z","end":"2026-05-06T01:18:17.713068Z","steps":["trace[1854683231] 'read index received' (duration: 41.539334ms)","trace[1854683231] 'applied index is now lower than readState.Index' (duration: 409.479296ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:18:17.713216Z","caller":"traceutil/trace.go:171","msg":"trace[4009855] transaction","detail":"{read_only:false; response_revision:4998; number_of_response:1; }","duration":"451.881273ms","start":"2026-05-06T01:18:17.26131Z","end":"2026-05-06T01:18:17.713192Z","steps":["trace[4009855] 'process raft request' (duration: 249.415435ms)","trace[4009855] 'compare' (duration: 201.849442ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:18:17.713342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:17.261297Z","time spent":"451.991596ms","remote":"127.0.0.1:54326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T01:18:17.713351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.322269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-06T01:18:17.713469Z","caller":"traceutil/trace.go:171","msg":"trace[522452626] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:4998; }","duration":"452.328344ms","start":"2026-05-06T01:18:17.26109Z","end":"2026-05-06T01:18:17.713418Z","steps":["trace[522452626] 'agreement among raft nodes before linearized reading' (duration: 452.045766ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:17.71352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:17.261071Z","time spent":"452.437778ms","remote":"127.0.0.1:54074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"info","ts":"2026-05-06T01:18:17.762726Z","caller":"traceutil/trace.go:171","msg":"trace[1815688524] transaction","detail":"{read_only:false; response_revision:4999; number_of_response:1; }","duration":"226.437364ms","start":"2026-05-06T01:18:17.536257Z","end":"2026-05-06T01:18:17.762695Z","steps":["trace[1815688524] 'process raft request' (duration: 226.088336ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:18:18.631021Z","caller":"traceutil/trace.go:171","msg":"trace[330785415] linearizableReadLoop","detail":"{readStateIndex:5188; appliedIndex:5186; }","duration":"129.273766ms","start":"2026-05-06T01:18:18.501717Z","end":"2026-05-06T01:18:18.630991Z","steps":["trace[330785415] 'read index received' (duration: 14.209884ms)","trace[330785415] 'applied index is now lower than readState.Index' (duration: 115.062542ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:18:18.6312Z","caller":"traceutil/trace.go:171","msg":"trace[2076168456] transaction","detail":"{read_only:false; response_revision:5002; number_of_response:1; }","duration":"149.477517ms","start":"2026-05-06T01:18:18.481682Z","end":"2026-05-06T01:18:18.631159Z","steps":["trace[2076168456] 'process raft request' (duration: 149.131417ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:18.631472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.445935ms","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-05-06T01:18:18.631515Z","caller":"traceutil/trace.go:171","msg":"trace[229799940] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5002; }","duration":"125.538577ms","start":"2026-05-06T01:18:18.505965Z","end":"2026-05-06T01:18:18.631504Z","steps":["trace[229799940] 'agreement among raft nodes before linearized reading' (duration: 125.403784ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:18.631631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.930283ms","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-05-06T01:18:18.631723Z","caller":"traceutil/trace.go:171","msg":"trace[506169883] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5002; }","duration":"130.068557ms","start":"2026-05-06T01:18:18.501643Z","end":"2026-05-06T01:18:18.631712Z","steps":["trace[506169883] 'agreement among raft nodes before linearized reading' (duration: 129.912822ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:18.631729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.283512ms","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-05-06T01:18:18.631808Z","caller":"traceutil/trace.go:171","msg":"trace[544269491] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5002; }","duration":"125.394183ms","start":"2026-05-06T01:18:18.506398Z","end":"2026-05-06T01:18:18.631792Z","steps":["trace[544269491] 'agreement among raft nodes before linearized reading' (duration: 125.191349ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:19:11.061538Z","caller":"traceutil/trace.go:171","msg":"trace[2134800220] linearizableReadLoop","detail":"{readStateIndex:5627; appliedIndex:5626; }","duration":"100.316353ms","start":"2026-05-06T01:19:10.9612Z","end":"2026-05-06T01:19:11.061517Z","steps":["trace[2134800220] 'read index received' (duration: 100.146038ms)","trace[2134800220] 'applied index is now lower than readState.Index' (duration: 169.415µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:19:11.061597Z","caller":"traceutil/trace.go:171","msg":"trace[1640039187] transaction","detail":"{read_only:false; response_revision:5429; number_of_response:1; }","duration":"111.243719ms","start":"2026-05-06T01:19:10.950326Z","end":"2026-05-06T01:19:11.06157Z","steps":["trace[1640039187] 'process raft request' (duration: 111.062603ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:19:11.061767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.55083ms","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-05-06T01:19:11.061806Z","caller":"traceutil/trace.go:171","msg":"trace[443538872] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5429; }","duration":"100.647764ms","start":"2026-05-06T01:19:10.961148Z","end":"2026-05-06T01:19:11.061796Z","steps":["trace[443538872] 'agreement among raft nodes before linearized reading' (duration: 100.489069ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:19:12.970281Z","caller":"traceutil/trace.go:171","msg":"trace[1559137398] transaction","detail":"{read_only:false; response_revision:5441; number_of_response:1; }","duration":"187.0855ms","start":"2026-05-06T01:19:12.783163Z","end":"2026-05-06T01:19:12.970249Z","steps":["trace[1559137398] 'process raft request' (duration: 164.122758ms)","trace[1559137398] 'compare' (duration: 22.717174ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:19:13.30836Z","caller":"traceutil/trace.go:171","msg":"trace[771843863] transaction","detail":"{read_only:false; response_revision:5445; number_of_response:1; }","duration":"180.868663ms","start":"2026-05-06T01:19:13.127464Z","end":"2026-05-06T01:19:13.308332Z","steps":["trace[771843863] 'process raft request' (duration: 115.312319ms)","trace[771843863] 'compare' (duration: 65.441521ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:19:13.327317Z","caller":"traceutil/trace.go:171","msg":"trace[1490561943] transaction","detail":"{read_only:false; response_revision:5446; number_of_response:1; }","duration":"187.738759ms","start":"2026-05-06T01:19:13.139547Z","end":"2026-05-06T01:19:13.327286Z","steps":["trace[1490561943] 'process raft request' (duration: 187.545963ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:19:13.709385Z","caller":"traceutil/trace.go:171","msg":"trace[1253714313] transaction","detail":"{read_only:false; response_revision:5447; number_of_response:1; }","duration":"111.719212ms","start":"2026-05-06T01:19:13.597636Z","end":"2026-05-06T01:19:13.709355Z","steps":["trace[1253714313] 'process raft request' (duration: 111.556186ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:19:32.861319Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2921} {"level":"info","ts":"2026-05-06T01:19:32.946296Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2921,"took":"83.34532ms","hash":719834088,"current-db-size-bytes":53948416,"current-db-size":"54 MB","current-db-size-in-use-bytes":29097984,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-06T01:19:32.946365Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":719834088,"revision":2921,"compact-revision":-1} {"level":"info","ts":"2026-05-06T01:22:04.298791Z","caller":"traceutil/trace.go:171","msg":"trace[428855585] linearizableReadLoop","detail":"{readStateIndex:6859; appliedIndex:6858; }","duration":"101.498923ms","start":"2026-05-06T01:22:04.197268Z","end":"2026-05-06T01:22:04.298766Z","steps":["trace[428855585] 'read index received' (duration: 101.283414ms)","trace[428855585] 'applied index is now lower than readState.Index' (duration: 214.479µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:22:04.298932Z","caller":"traceutil/trace.go:171","msg":"trace[1834622801] transaction","detail":"{read_only:false; response_revision:6619; number_of_response:1; }","duration":"107.493233ms","start":"2026-05-06T01:22:04.191427Z","end":"2026-05-06T01:22:04.29892Z","steps":["trace[1834622801] 'process raft request' (duration: 107.192841ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:22:04.299174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.889128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4857"} {"level":"info","ts":"2026-05-06T01:22:04.29931Z","caller":"traceutil/trace.go:171","msg":"trace[810608391] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6619; }","duration":"102.025014ms","start":"2026-05-06T01:22:04.197247Z","end":"2026-05-06T01:22:04.299272Z","steps":["trace[810608391] 'agreement among raft nodes before linearized reading' (duration: 101.813135ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:22:24.432301Z","caller":"traceutil/trace.go:171","msg":"trace[959487058] linearizableReadLoop","detail":"{readStateIndex:7021; appliedIndex:7020; }","duration":"161.753661ms","start":"2026-05-06T01:22:24.270527Z","end":"2026-05-06T01:22:24.432281Z","steps":["trace[959487058] 'read index received' (duration: 161.623346ms)","trace[959487058] 'applied index is now lower than readState.Index' (duration: 129.385µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:22:24.43282Z","caller":"traceutil/trace.go:171","msg":"trace[1231852718] transaction","detail":"{read_only:false; response_revision:6775; number_of_response:1; }","duration":"166.133447ms","start":"2026-05-06T01:22:24.266649Z","end":"2026-05-06T01:22:24.432783Z","steps":["trace[1231852718] 'process raft request' (duration: 165.51324ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:22:24.433528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.973232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/bindings.rabbitmq.com\" ","response":"range_response_count:1 size:5746"} {"level":"info","ts":"2026-05-06T01:22:24.43361Z","caller":"traceutil/trace.go:171","msg":"trace[946685002] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/bindings.rabbitmq.com; range_end:; response_count:1; response_revision:6775; }","duration":"163.095357ms","start":"2026-05-06T01:22:24.270493Z","end":"2026-05-06T01:22:24.433589Z","steps":["trace[946685002] 'agreement among raft nodes before linearized reading' (duration: 162.242921ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:22:24.601238Z","caller":"traceutil/trace.go:171","msg":"trace[1154732886] transaction","detail":"{read_only:false; response_revision:6777; number_of_response:1; }","duration":"101.565582ms","start":"2026-05-06T01:22:24.499621Z","end":"2026-05-06T01:22:24.601187Z","steps":["trace[1154732886] 'process raft request' (duration: 94.825416ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:22:24.625649Z","caller":"traceutil/trace.go:171","msg":"trace[1113284845] linearizableReadLoop","detail":"{readStateIndex:7024; appliedIndex:7022; }","duration":"115.955931ms","start":"2026-05-06T01:22:24.509664Z","end":"2026-05-06T01:22:24.62562Z","steps":["trace[1113284845] 'read index received' (duration: 84.706138ms)","trace[1113284845] 'applied index is now lower than readState.Index' (duration: 31.248513ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:22:24.625994Z","caller":"traceutil/trace.go:171","msg":"trace[1196636801] transaction","detail":"{read_only:false; response_revision:6778; number_of_response:1; }","duration":"125.683953ms","start":"2026-05-06T01:22:24.500295Z","end":"2026-05-06T01:22:24.625979Z","steps":["trace[1196636801] 'process raft request' (duration: 125.203243ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:22:24.626612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.924642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/federations.rabbitmq.com\" ","response":"range_response_count:1 size:7781"} {"level":"info","ts":"2026-05-06T01:22:24.626676Z","caller":"traceutil/trace.go:171","msg":"trace[639747569] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/federations.rabbitmq.com; range_end:; response_count:1; response_revision:6778; }","duration":"117.023997ms","start":"2026-05-06T01:22:24.509636Z","end":"2026-05-06T01:22:24.62666Z","steps":["trace[639747569] 'agreement among raft nodes before linearized reading' (duration: 116.813758ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:22:24.732364Z","caller":"traceutil/trace.go:171","msg":"trace[2065334065] transaction","detail":"{read_only:false; response_revision:6779; number_of_response:1; }","duration":"103.058554ms","start":"2026-05-06T01:22:24.62928Z","end":"2026-05-06T01:22:24.732339Z","steps":["trace[2065334065] 'process raft request' (duration: 76.913607ms)","trace[2065334065] 'compare' (duration: 25.654466ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:22:38.950066Z","caller":"traceutil/trace.go:171","msg":"trace[84237765] transaction","detail":"{read_only:false; response_revision:6856; number_of_response:1; }","duration":"128.853353ms","start":"2026-05-06T01:22:38.821184Z","end":"2026-05-06T01:22:38.950038Z","steps":["trace[84237765] 'process raft request' (duration: 128.604723ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:23:38.783165Z","caller":"traceutil/trace.go:171","msg":"trace[1329026896] transaction","detail":"{read_only:false; response_revision:7178; number_of_response:1; }","duration":"337.045372ms","start":"2026-05-06T01:23:38.446088Z","end":"2026-05-06T01:23:38.783133Z","steps":["trace[1329026896] 'process raft request' (duration: 336.618203ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:23:38.783299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:23:38.446066Z","time spent":"337.163586ms","remote":"127.0.0.1:54326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T01:23:40.467355Z","caller":"traceutil/trace.go:171","msg":"trace[127361077] linearizableReadLoop","detail":"{readStateIndex:7447; appliedIndex:7446; }","duration":"273.130183ms","start":"2026-05-06T01:23:40.194212Z","end":"2026-05-06T01:23:40.467343Z","steps":["trace[127361077] 'read index received' (duration: 272.84035ms)","trace[127361077] 'applied index is now lower than readState.Index' (duration: 289.283µs)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:23:40.467513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.294741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:23:40.467775Z","caller":"traceutil/trace.go:171","msg":"trace[473708721] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7186; }","duration":"273.590553ms","start":"2026-05-06T01:23:40.194174Z","end":"2026-05-06T01:23:40.467765Z","steps":["trace[473708721] 'agreement among raft nodes before linearized reading' (duration: 273.305551ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:23:40.467876Z","caller":"traceutil/trace.go:171","msg":"trace[1242549113] transaction","detail":"{read_only:false; response_revision:7186; number_of_response:1; }","duration":"273.689529ms","start":"2026-05-06T01:23:40.194145Z","end":"2026-05-06T01:23:40.467835Z","steps":["trace[1242549113] 'process raft request' (duration: 273.118813ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:23:40.467932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.095266ms","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:5"} {"level":"info","ts":"2026-05-06T01:23:40.468085Z","caller":"traceutil/trace.go:171","msg":"trace[59929127] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:7186; }","duration":"126.300665ms","start":"2026-05-06T01:23:40.341762Z","end":"2026-05-06T01:23:40.468063Z","steps":["trace[59929127] 'agreement among raft nodes before linearized reading' (duration: 126.013812ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:23:42.249099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.938696ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:23:42.24925Z","caller":"traceutil/trace.go:171","msg":"trace[836459588] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:7194; }","duration":"475.042981ms","start":"2026-05-06T01:23:41.774137Z","end":"2026-05-06T01:23:42.24918Z","steps":["trace[836459588] 'range keys from in-memory index tree' (duration: 474.925586ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:23:42.249733Z","caller":"traceutil/trace.go:171","msg":"trace[8247537] transaction","detail":"{read_only:false; response_revision:7195; number_of_response:1; }","duration":"312.054525ms","start":"2026-05-06T01:23:41.93766Z","end":"2026-05-06T01:23:42.249715Z","steps":["trace[8247537] 'process raft request' (duration: 311.93242ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:23:42.24986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:23:41.937641Z","time spent":"312.14681ms","remote":"127.0.0.1:54326","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-05-06T01:23:43.819789Z","caller":"traceutil/trace.go:171","msg":"trace[999439788] transaction","detail":"{read_only:false; response_revision:7201; number_of_response:1; }","duration":"194.079705ms","start":"2026-05-06T01:23:43.625678Z","end":"2026-05-06T01:23:43.819758Z","steps":["trace[999439788] 'process raft request' (duration: 193.948639ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:23:43.82033Z","caller":"traceutil/trace.go:171","msg":"trace[973337295] linearizableReadLoop","detail":"{readStateIndex:7463; appliedIndex:7463; }","duration":"194.157148ms","start":"2026-05-06T01:23:43.626156Z","end":"2026-05-06T01:23:43.820314Z","steps":["trace[973337295] 'read index received' (duration: 194.151808ms)","trace[973337295] 'applied index is now lower than readState.Index' (duration: 4.5µs)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:23:43.820526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.352966ms","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-05-06T01:23:43.820566Z","caller":"traceutil/trace.go:171","msg":"trace[82930882] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7201; }","duration":"194.444031ms","start":"2026-05-06T01:23:43.626111Z","end":"2026-05-06T01:23:43.820555Z","steps":["trace[82930882] 'agreement among raft nodes before linearized reading' (duration: 194.291714ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:23:43.820829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.411684ms","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":"info","ts":"2026-05-06T01:23:43.82086Z","caller":"traceutil/trace.go:171","msg":"trace[1765225551] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:7202; }","duration":"162.456136ms","start":"2026-05-06T01:23:43.658395Z","end":"2026-05-06T01:23:43.820851Z","steps":["trace[1765225551] 'agreement among raft nodes before linearized reading' (duration: 162.388722ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:23:46.649732Z","caller":"traceutil/trace.go:171","msg":"trace[153213130] transaction","detail":"{read_only:false; response_revision:7210; number_of_response:1; }","duration":"669.41275ms","start":"2026-05-06T01:23:45.980289Z","end":"2026-05-06T01:23:46.649702Z","steps":["trace[153213130] 'process raft request' (duration: 669.246362ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:23:46.649883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:23:45.980265Z","time spent":"669.543296ms","remote":"127.0.0.1:54326","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-05-06T01:23:46.657855Z","caller":"traceutil/trace.go:171","msg":"trace[34015148] linearizableReadLoop","detail":"{readStateIndex:7473; appliedIndex:7471; }","duration":"657.49789ms","start":"2026-05-06T01:23:45.999496Z","end":"2026-05-06T01:23:46.656994Z","steps":["trace[34015148] 'read index received' (duration: 650.008993ms)","trace[34015148] 'applied index is now lower than readState.Index' (duration: 7.487906ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:23:46.658583Z","caller":"traceutil/trace.go:171","msg":"trace[261555754] transaction","detail":"{read_only:false; response_revision:7213; number_of_response:1; }","duration":"394.114563ms","start":"2026-05-06T01:23:46.264448Z","end":"2026-05-06T01:23:46.658563Z","steps":["trace[261555754] 'process raft request' (duration: 392.493083ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:23:46.659169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:23:46.264424Z","time spent":"394.692578ms","remote":"127.0.0.1:54326","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-05-06T01:23:46.658634Z","caller":"traceutil/trace.go:171","msg":"trace[886643213] transaction","detail":"{read_only:false; response_revision:7211; number_of_response:1; }","duration":"677.111525ms","start":"2026-05-06T01:23:45.981496Z","end":"2026-05-06T01:23:46.658607Z","steps":["trace[886643213] 'process raft request' (duration: 675.198761ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:23:46.659373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:23:45.981482Z","time spent":"677.825977ms","remote":"127.0.0.1:54326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T01:23:46.658879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.135155ms","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-05-06T01:23:46.658994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.485007ms","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-05-06T01:23:46.659531Z","caller":"traceutil/trace.go:171","msg":"trace[1239658377] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7213; }","duration":"660.051131ms","start":"2026-05-06T01:23:45.999466Z","end":"2026-05-06T01:23:46.659517Z","steps":["trace[1239658377] 'agreement among raft nodes before linearized reading' (duration: 659.437304ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:23:46.65959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:23:45.999456Z","time spent":"660.093272ms","remote":"127.0.0.1:54326","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":"info","ts":"2026-05-06T01:23:46.659486Z","caller":"traceutil/trace.go:171","msg":"trace[1968957974] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:7213; }","duration":"570.795455ms","start":"2026-05-06T01:23:46.088673Z","end":"2026-05-06T01:23:46.659469Z","steps":["trace[1968957974] 'agreement among raft nodes before linearized reading' (duration: 570.044973ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:23:46.659057Z","caller":"traceutil/trace.go:171","msg":"trace[577604355] transaction","detail":"{read_only:false; response_revision:7212; number_of_response:1; }","duration":"400.339314ms","start":"2026-05-06T01:23:46.25871Z","end":"2026-05-06T01:23:46.659049Z","steps":["trace[577604355] 'process raft request' (duration: 398.104637ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:23:46.659935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:23:46.25869Z","time spent":"401.214032ms","remote":"127.0.0.1:54326","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-05-06T01:23:46.6598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:23:46.088652Z","time spent":"571.116349ms","remote":"127.0.0.1:54256","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":"info","ts":"2026-05-06T01:24:32.866905Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5599} {"level":"info","ts":"2026-05-06T01:24:32.959843Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5599,"took":"91.819689ms","hash":2247550052,"current-db-size-bytes":53948416,"current-db-size":"54 MB","current-db-size-in-use-bytes":18481152,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-06T01:24:32.959918Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2247550052,"revision":5599,"compact-revision":2921}