{"level":"warn","ts":"2026-07-15T05:00:46.42132Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-15T05:00:46.421443Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.156: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.156:2380","--initial-cluster=instance=https://199.204.45.156:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.156:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.156: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-07-15T05:00:46.421539Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-15T05:00:46.421556Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-07-15T05:00:46.421584Z","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-07-15T05:00:46.422471Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"]} {"level":"info","ts":"2026-07-15T05:00:46.422566Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.156: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-07-15T05:00:46.425736Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.863866ms"} {"level":"info","ts":"2026-07-15T05:00:46.436751Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5ac240a6daec2148","cluster-id":"62163637a46c33c2"} {"level":"info","ts":"2026-07-15T05:00:46.436833Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=()"} {"level":"info","ts":"2026-07-15T05:00:46.436855Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 0"} {"level":"info","ts":"2026-07-15T05:00:46.436865Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5ac240a6daec2148 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-15T05:00:46.436875Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 1"} {"level":"info","ts":"2026-07-15T05:00:46.436921Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"warn","ts":"2026-07-15T05:00:46.441264Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-15T05:00:46.443299Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-15T05:00:46.446363Z","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-07-15T05:00:46.448464Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5ac240a6daec2148","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-15T05:00:46.448798Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5ac240a6daec2148","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-15T05:00:46.44898Z","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-07-15T05:00:46.449145Z","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-07-15T05:00:46.449163Z","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-07-15T05:00:46.450018Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"info","ts":"2026-07-15T05:00:46.450195Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","added-peer-id":"5ac240a6daec2148","added-peer-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-07-15T05:00:46.451665Z","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-07-15T05:00:46.451766Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-07-15T05:00:46.45181Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-07-15T05:00:46.451969Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5ac240a6daec2148","initial-advertise-peer-urls":["https://199.204.45.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-15T05:00:46.452028Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-15T05:00:47.238051Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 is starting a new election at term 1"} {"level":"info","ts":"2026-07-15T05:00:47.238105Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-15T05:00:47.238135Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgPreVoteResp from 5ac240a6daec2148 at term 1"} {"level":"info","ts":"2026-07-15T05:00:47.238149Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became candidate at term 2"} {"level":"info","ts":"2026-07-15T05:00:47.238157Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgVoteResp from 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-07-15T05:00:47.238169Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became leader at term 2"} {"level":"info","ts":"2026-07-15T05:00:47.238179Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5ac240a6daec2148 elected leader 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-07-15T05:00:47.239391Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5ac240a6daec2148","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.156:2379]}","request-path":"/0/members/5ac240a6daec2148/attributes","cluster-id":"62163637a46c33c2","publish-timeout":"7s"} {"level":"info","ts":"2026-07-15T05:00:47.239395Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-15T05:00:47.239418Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-15T05:00:47.239494Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-15T05:00:47.239701Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-15T05:00:47.239751Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-15T05:00:47.24047Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","cluster-version":"3.5"} {"level":"info","ts":"2026-07-15T05:00:47.241328Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-15T05:00:47.241382Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-15T05:00:47.242525Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-15T05:00:47.250404Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.156:2379"} {"level":"info","ts":"2026-07-15T05:01:40.10341Z","caller":"traceutil/trace.go:171","msg":"trace[51945376] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"103.269293ms","start":"2026-07-15T05:01:40.000103Z","end":"2026-07-15T05:01:40.103372Z","steps":["trace[51945376] 'process raft request' (duration: 102.453357ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:01:40.54393Z","caller":"traceutil/trace.go:171","msg":"trace[776945220] linearizableReadLoop","detail":"{readStateIndex:679; appliedIndex:678; }","duration":"146.122403ms","start":"2026-07-15T05:01:40.39778Z","end":"2026-07-15T05:01:40.543902Z","steps":["trace[776945220] 'read index received' (duration: 109.418932ms)","trace[776945220] 'applied index is now lower than readState.Index' (duration: 36.702271ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:01:40.544177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.748836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-15T05:01:40.544275Z","caller":"traceutil/trace.go:171","msg":"trace[645445089] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:653; }","duration":"161.91648ms","start":"2026-07-15T05:01:40.382341Z","end":"2026-07-15T05:01:40.544258Z","steps":["trace[645445089] 'agreement among raft nodes before linearized reading' (duration: 161.708376ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:01:40.544443Z","caller":"traceutil/trace.go:171","msg":"trace[2047714759] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"200.784712ms","start":"2026-07-15T05:01:40.343638Z","end":"2026-07-15T05:01:40.544423Z","steps":["trace[2047714759] 'process raft request' (duration: 163.54509ms)","trace[2047714759] 'compare' (duration: 36.321244ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:01:40.579297Z","caller":"traceutil/trace.go:171","msg":"trace[153808050] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"164.077312ms","start":"2026-07-15T05:01:40.415202Z","end":"2026-07-15T05:01:40.579279Z","steps":["trace[153808050] 'process raft request' (duration: 163.994631ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:01:40.579621Z","caller":"traceutil/trace.go:171","msg":"trace[335767378] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"180.618493ms","start":"2026-07-15T05:01:40.39899Z","end":"2026-07-15T05:01:40.579609Z","steps":["trace[335767378] 'process raft request' (duration: 180.059872ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:01:41.160882Z","caller":"traceutil/trace.go:171","msg":"trace[1372564674] linearizableReadLoop","detail":"{readStateIndex:683; appliedIndex:682; }","duration":"109.199308ms","start":"2026-07-15T05:01:41.051652Z","end":"2026-07-15T05:01:41.160851Z","steps":["trace[1372564674] 'read index received' (duration: 108.117027ms)","trace[1372564674] 'applied index is now lower than readState.Index' (duration: 1.081131ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:01:41.161027Z","caller":"traceutil/trace.go:171","msg":"trace[2036955688] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"480.086038ms","start":"2026-07-15T05:01:40.680887Z","end":"2026-07-15T05:01:41.160973Z","steps":["trace[2036955688] 'process raft request' (duration: 479.278993ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:01:41.161224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.550824ms","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-07-15T05:01:41.161357Z","caller":"traceutil/trace.go:171","msg":"trace[220789710] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:657; }","duration":"109.745519ms","start":"2026-07-15T05:01:41.05158Z","end":"2026-07-15T05:01:41.161325Z","steps":["trace[220789710] 'agreement among raft nodes before linearized reading' (duration: 109.431962ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:01:41.162054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:01:40.680859Z","time spent":"480.274991ms","remote":"127.0.0.1:59398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:01:42.584958Z","caller":"traceutil/trace.go:171","msg":"trace[535964141] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"102.816254ms","start":"2026-07-15T05:01:42.482109Z","end":"2026-07-15T05:01:42.584925Z","steps":["trace[535964141] 'process raft request' (duration: 101.241163ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:01:42.595748Z","caller":"traceutil/trace.go:171","msg":"trace[1599528740] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"113.42838ms","start":"2026-07-15T05:01:42.482285Z","end":"2026-07-15T05:01:42.595714Z","steps":["trace[1599528740] 'process raft request' (duration: 113.321778ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:01:55.839673Z","caller":"traceutil/trace.go:171","msg":"trace[1860899697] transaction","detail":"{read_only:false; response_revision:821; number_of_response:1; }","duration":"131.611558ms","start":"2026-07-15T05:01:55.708027Z","end":"2026-07-15T05:01:55.839639Z","steps":["trace[1860899697] 'process raft request' (duration: 131.292552ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:03:13.243702Z","caller":"traceutil/trace.go:171","msg":"trace[1540406743] transaction","detail":"{read_only:false; response_revision:1899; number_of_response:1; }","duration":"311.776939ms","start":"2026-07-15T05:03:12.931866Z","end":"2026-07-15T05:03:13.243643Z","steps":["trace[1540406743] 'process raft request' (duration: 223.822097ms)","trace[1540406743] 'compare' (duration: 87.712818ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:03:13.243906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:03:12.931845Z","time spent":"311.964984ms","remote":"127.0.0.1:59594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1530,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:03:13.244061Z","caller":"traceutil/trace.go:171","msg":"trace[1658758270] transaction","detail":"{read_only:false; response_revision:1900; number_of_response:1; }","duration":"311.76739ms","start":"2026-07-15T05:03:12.932265Z","end":"2026-07-15T05:03:13.244033Z","steps":["trace[1658758270] 'process raft request' (duration: 311.297861ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:03:13.244227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:03:12.932254Z","time spent":"311.885222ms","remote":"127.0.0.1:59468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":978,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:03:13.24453Z","caller":"traceutil/trace.go:171","msg":"trace[708645928] transaction","detail":"{read_only:false; response_revision:1902; number_of_response:1; }","duration":"311.645628ms","start":"2026-07-15T05:03:12.932854Z","end":"2026-07-15T05:03:13.244499Z","steps":["trace[708645928] 'process raft request' (duration: 311.489675ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:03:13.244699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:03:12.932837Z","time spent":"311.77861ms","remote":"127.0.0.1:59468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":894,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:03:13.244521Z","caller":"traceutil/trace.go:171","msg":"trace[1554898861] transaction","detail":"{read_only:false; response_revision:1901; number_of_response:1; }","duration":"311.961253ms","start":"2026-07-15T05:03:12.93251Z","end":"2026-07-15T05:03:13.244471Z","steps":["trace[1554898861] 'process raft request' (duration: 311.398572ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:03:13.24476Z","caller":"traceutil/trace.go:171","msg":"trace[301951664] transaction","detail":"{read_only:false; response_revision:1903; number_of_response:1; }","duration":"308.393965ms","start":"2026-07-15T05:03:12.936335Z","end":"2026-07-15T05:03:13.244729Z","steps":["trace[301951664] 'process raft request' (duration: 308.099549ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:03:13.244862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:03:12.932472Z","time spent":"312.349281ms","remote":"127.0.0.1:59594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1472,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T05:03:13.24498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:03:12.936319Z","time spent":"308.540149ms","remote":"127.0.0.1:59490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3729,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:03:13.24499Z","caller":"traceutil/trace.go:171","msg":"trace[2054840327] linearizableReadLoop","detail":"{readStateIndex:1976; appliedIndex:1970; }","duration":"247.328171ms","start":"2026-07-15T05:03:12.99762Z","end":"2026-07-15T05:03:13.244948Z","steps":["trace[2054840327] 'read index received' (duration: 158.021302ms)","trace[2054840327] 'applied index is now lower than readState.Index' (duration: 89.304709ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:03:13.244974Z","caller":"traceutil/trace.go:171","msg":"trace[1753568954] transaction","detail":"{read_only:false; response_revision:1904; number_of_response:1; }","duration":"255.263664ms","start":"2026-07-15T05:03:12.989621Z","end":"2026-07-15T05:03:13.244885Z","steps":["trace[1753568954] 'process raft request' (duration: 255.020908ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:03:13.245785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.481129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-15T05:03:13.245854Z","caller":"traceutil/trace.go:171","msg":"trace[1662239165] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1904; }","duration":"118.588762ms","start":"2026-07-15T05:03:13.127247Z","end":"2026-07-15T05:03:13.245836Z","steps":["trace[1662239165] 'agreement among raft nodes before linearized reading' (duration: 118.340707ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:03:13.246891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.270417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9630d98e-0c73-46e3-a0e5-55bd77813f53\" ","response":"range_response_count:1 size:3744"} {"level":"info","ts":"2026-07-15T05:03:13.246957Z","caller":"traceutil/trace.go:171","msg":"trace[780179917] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9630d98e-0c73-46e3-a0e5-55bd77813f53; range_end:; response_count:1; response_revision:1904; }","duration":"249.37519ms","start":"2026-07-15T05:03:12.997567Z","end":"2026-07-15T05:03:13.246942Z","steps":["trace[780179917] 'agreement among raft nodes before linearized reading' (duration: 247.486484ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:03:13.247277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.649656ms","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-07-15T05:03:13.24734Z","caller":"traceutil/trace.go:171","msg":"trace[658657924] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1904; }","duration":"209.087584ms","start":"2026-07-15T05:03:13.038236Z","end":"2026-07-15T05:03:13.247324Z","steps":["trace[658657924] 'agreement among raft nodes before linearized reading' (duration: 207.220579ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:03:13.245941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.535212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-15T05:03:13.249966Z","caller":"traceutil/trace.go:171","msg":"trace[1130682237] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1904; }","duration":"148.692991ms","start":"2026-07-15T05:03:13.101246Z","end":"2026-07-15T05:03:13.249939Z","steps":["trace[1130682237] 'agreement among raft nodes before linearized reading' (duration: 144.370469ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:03:13.649051Z","caller":"traceutil/trace.go:171","msg":"trace[1717085090] transaction","detail":"{read_only:false; response_revision:1908; number_of_response:1; }","duration":"146.04252ms","start":"2026-07-15T05:03:13.502978Z","end":"2026-07-15T05:03:13.649021Z","steps":["trace[1717085090] 'process raft request' (duration: 140.522654ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:03:25.416785Z","caller":"traceutil/trace.go:171","msg":"trace[272764167] linearizableReadLoop","detail":"{readStateIndex:2078; appliedIndex:2077; }","duration":"104.97281ms","start":"2026-07-15T05:03:25.31178Z","end":"2026-07-15T05:03:25.416753Z","steps":["trace[272764167] 'read index received' (duration: 104.790546ms)","trace[272764167] 'applied index is now lower than readState.Index' (duration: 180.504µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:03:25.416816Z","caller":"traceutil/trace.go:171","msg":"trace[750167349] transaction","detail":"{read_only:false; response_revision:2003; number_of_response:1; }","duration":"129.766008ms","start":"2026-07-15T05:03:25.287016Z","end":"2026-07-15T05:03:25.416782Z","steps":["trace[750167349] 'process raft request' (duration: 129.502082ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:03:25.417048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.257827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-15T05:03:25.417096Z","caller":"traceutil/trace.go:171","msg":"trace[1340495317] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2003; }","duration":"105.356978ms","start":"2026-07-15T05:03:25.311726Z","end":"2026-07-15T05:03:25.417083Z","steps":["trace[1340495317] 'agreement among raft nodes before linearized reading' (duration: 105.145094ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:03:51.920636Z","caller":"traceutil/trace.go:171","msg":"trace[920317976] transaction","detail":"{read_only:false; response_revision:2166; number_of_response:1; }","duration":"126.553996ms","start":"2026-07-15T05:03:51.794047Z","end":"2026-07-15T05:03:51.920601Z","steps":["trace[920317976] 'process raft request' (duration: 126.195878ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:04:03.921064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.422004ms","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-07-15T05:04:03.921187Z","caller":"traceutil/trace.go:171","msg":"trace[1350357887] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2227; }","duration":"169.601358ms","start":"2026-07-15T05:04:03.751551Z","end":"2026-07-15T05:04:03.921153Z","steps":["trace[1350357887] 'range keys from in-memory index tree' (duration: 169.187859ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:04:03.921202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.60259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-15T05:04:03.921371Z","caller":"traceutil/trace.go:171","msg":"trace[1800669631] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2227; }","duration":"191.854884ms","start":"2026-07-15T05:04:03.729486Z","end":"2026-07-15T05:04:03.921341Z","steps":["trace[1800669631] 'range keys from in-memory index tree' (duration: 191.331165ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:04:03.921555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.893007ms","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-07-15T05:04:03.921701Z","caller":"traceutil/trace.go:171","msg":"trace[1199356787] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2227; }","duration":"189.104643ms","start":"2026-07-15T05:04:03.732566Z","end":"2026-07-15T05:04:03.92167Z","steps":["trace[1199356787] 'range keys from in-memory index tree' (duration: 188.565602ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:04:03.922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.196405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-15T05:04:03.922075Z","caller":"traceutil/trace.go:171","msg":"trace[923415640] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2227; }","duration":"200.323678ms","start":"2026-07-15T05:04:03.721735Z","end":"2026-07-15T05:04:03.922059Z","steps":["trace[923415640] 'range keys from in-memory index tree' (duration: 199.014552ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:04:04.049028Z","caller":"traceutil/trace.go:171","msg":"trace[1597377996] transaction","detail":"{read_only:false; response_revision:2228; number_of_response:1; }","duration":"121.671606ms","start":"2026-07-15T05:04:03.927339Z","end":"2026-07-15T05:04:04.049011Z","steps":["trace[1597377996] 'process raft request' (duration: 121.522303ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:04:04.334061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.986168ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-15T05:04:04.334344Z","caller":"traceutil/trace.go:171","msg":"trace[1845640810] transaction","detail":"{read_only:false; response_revision:2232; number_of_response:1; }","duration":"176.022972ms","start":"2026-07-15T05:04:04.158303Z","end":"2026-07-15T05:04:04.334326Z","steps":["trace[1845640810] 'process raft request' (duration: 175.983051ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:04:04.334482Z","caller":"traceutil/trace.go:171","msg":"trace[1913911824] transaction","detail":"{read_only:false; response_revision:2229; number_of_response:1; }","duration":"407.1267ms","start":"2026-07-15T05:04:03.927344Z","end":"2026-07-15T05:04:04.334471Z","steps":["trace[1913911824] 'process raft request' (duration: 201.435048ms)","trace[1913911824] 'compare' (duration: 204.845756ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:04:04.334533Z","caller":"traceutil/trace.go:171","msg":"trace[8666153] transaction","detail":"{read_only:false; response_revision:2230; number_of_response:1; }","duration":"407.067519ms","start":"2026-07-15T05:04:03.927433Z","end":"2026-07-15T05:04:04.3345Z","steps":["trace[8666153] 'process raft request' (duration: 406.734703ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:04:04.33459Z","caller":"traceutil/trace.go:171","msg":"trace[1789024852] linearizableReadLoop","detail":"{readStateIndex:2315; appliedIndex:2311; }","duration":"303.138333ms","start":"2026-07-15T05:04:04.031443Z","end":"2026-07-15T05:04:04.334582Z","steps":["trace[1789024852] 'read index received' (duration: 17.398384ms)","trace[1789024852] 'applied index is now lower than readState.Index' (duration: 285.738989ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:04:04.334672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:04:03.927406Z","time spent":"407.202641ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:04:04.334665Z","caller":"traceutil/trace.go:171","msg":"trace[904936518] transaction","detail":"{read_only:false; response_revision:2231; number_of_response:1; }","duration":"317.404876ms","start":"2026-07-15T05:04:04.017201Z","end":"2026-07-15T05:04:04.334606Z","steps":["trace[904936518] 'process raft request' (duration: 317.024509ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:04:04.334786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.340966ms","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-07-15T05:04:04.334872Z","caller":"traceutil/trace.go:171","msg":"trace[1877226059] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2232; }","duration":"303.471389ms","start":"2026-07-15T05:04:04.03138Z","end":"2026-07-15T05:04:04.334851Z","steps":["trace[1877226059] 'agreement among raft nodes before linearized reading' (duration: 303.263815ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:04:04.334916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:04:04.03136Z","time spent":"303.546991ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-15T05:04:04.334946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:04:04.017173Z","time spent":"317.61091ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T05:04:04.334543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:04:03.927322Z","time spent":"407.189101ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:04:58.913849Z","caller":"traceutil/trace.go:171","msg":"trace[931849681] linearizableReadLoop","detail":"{readStateIndex:2796; appliedIndex:2795; }","duration":"215.055011ms","start":"2026-07-15T05:04:58.69876Z","end":"2026-07-15T05:04:58.913815Z","steps":["trace[931849681] 'read index received' (duration: 210.846591ms)","trace[931849681] 'applied index is now lower than readState.Index' (duration: 4.20691ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:04:58.913887Z","caller":"traceutil/trace.go:171","msg":"trace[1087780713] transaction","detail":"{read_only:false; response_revision:2695; number_of_response:1; }","duration":"256.370144ms","start":"2026-07-15T05:04:58.657472Z","end":"2026-07-15T05:04:58.913842Z","steps":["trace[1087780713] 'process raft request' (duration: 252.216174ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:04:58.91426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.455608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-15T05:04:58.914431Z","caller":"traceutil/trace.go:171","msg":"trace[1172315164] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2695; }","duration":"215.707823ms","start":"2026-07-15T05:04:58.69871Z","end":"2026-07-15T05:04:58.914417Z","steps":["trace[1172315164] 'agreement among raft nodes before linearized reading' (duration: 215.339346ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:08:10.120502Z","caller":"traceutil/trace.go:171","msg":"trace[1967564878] transaction","detail":"{read_only:false; response_revision:3929; number_of_response:1; }","duration":"108.051978ms","start":"2026-07-15T05:08:10.012422Z","end":"2026-07-15T05:08:10.120474Z","steps":["trace[1967564878] 'process raft request' (duration: 107.777733ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:08:10.124181Z","caller":"traceutil/trace.go:171","msg":"trace[855284086] transaction","detail":"{read_only:false; response_revision:3930; number_of_response:1; }","duration":"111.229408ms","start":"2026-07-15T05:08:10.01291Z","end":"2026-07-15T05:08:10.124139Z","steps":["trace[855284086] 'process raft request' (duration: 110.310901ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:09:39.229372Z","caller":"traceutil/trace.go:171","msg":"trace[1845365254] linearizableReadLoop","detail":"{readStateIndex:5295; appliedIndex:5294; }","duration":"161.771868ms","start":"2026-07-15T05:09:39.067571Z","end":"2026-07-15T05:09:39.229343Z","steps":["trace[1845365254] 'read index received' (duration: 113.273786ms)","trace[1845365254] 'applied index is now lower than readState.Index' (duration: 48.496762ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:09:39.229464Z","caller":"traceutil/trace.go:171","msg":"trace[48630768] transaction","detail":"{read_only:false; response_revision:5105; number_of_response:1; }","duration":"171.962973ms","start":"2026-07-15T05:09:39.057449Z","end":"2026-07-15T05:09:39.229412Z","steps":["trace[48630768] 'process raft request' (duration: 123.523523ms)","trace[48630768] 'compare' (duration: 48.212476ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:09:39.229738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.80692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T05:09:39.229826Z","caller":"traceutil/trace.go:171","msg":"trace[216405111] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5105; }","duration":"121.918902ms","start":"2026-07-15T05:09:39.107883Z","end":"2026-07-15T05:09:39.229802Z","steps":["trace[216405111] 'agreement among raft nodes before linearized reading' (duration: 121.81529ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:09:39.229809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.837109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-121a4dbe-62cc-4a35-b260-a0768b312341\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-07-15T05:09:39.229917Z","caller":"traceutil/trace.go:171","msg":"trace[151724774] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-121a4dbe-62cc-4a35-b260-a0768b312341; range_end:; response_count:1; response_revision:5105; }","duration":"160.982662ms","start":"2026-07-15T05:09:39.068907Z","end":"2026-07-15T05:09:39.22989Z","steps":["trace[151724774] 'agreement among raft nodes before linearized reading' (duration: 160.650506ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:09:39.229724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.119484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-121a4dbe-62cc-4a35-b260-a0768b312341\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-07-15T05:09:39.230007Z","caller":"traceutil/trace.go:171","msg":"trace[1351865029] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-121a4dbe-62cc-4a35-b260-a0768b312341; range_end:; response_count:1; response_revision:5105; }","duration":"162.456361ms","start":"2026-07-15T05:09:39.067521Z","end":"2026-07-15T05:09:39.229978Z","steps":["trace[1351865029] 'agreement among raft nodes before linearized reading' (duration: 161.946251ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:09:39.367932Z","caller":"traceutil/trace.go:171","msg":"trace[1967709538] transaction","detail":"{read_only:false; response_revision:5106; number_of_response:1; }","duration":"132.073287ms","start":"2026-07-15T05:09:39.235824Z","end":"2026-07-15T05:09:39.367898Z","steps":["trace[1967709538] 'process raft request' (duration: 131.848452ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:09:39.373064Z","caller":"traceutil/trace.go:171","msg":"trace[825472568] transaction","detail":"{read_only:false; response_revision:5107; number_of_response:1; }","duration":"136.650264ms","start":"2026-07-15T05:09:39.236379Z","end":"2026-07-15T05:09:39.37303Z","steps":["trace[825472568] 'process raft request' (duration: 136.460351ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:09:41.412991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.72618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-121a4dbe-62cc-4a35-b260-a0768b312341\" ","response":"range_response_count:1 size:3907"} {"level":"info","ts":"2026-07-15T05:09:41.413141Z","caller":"traceutil/trace.go:171","msg":"trace[1334208240] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-121a4dbe-62cc-4a35-b260-a0768b312341; range_end:; response_count:1; response_revision:5155; }","duration":"170.902094ms","start":"2026-07-15T05:09:41.242179Z","end":"2026-07-15T05:09:41.413081Z","steps":["trace[1334208240] 'range keys from in-memory index tree' (duration: 170.524215ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:09:53.95429Z","caller":"traceutil/trace.go:171","msg":"trace[1615044054] transaction","detail":"{read_only:false; response_revision:5398; number_of_response:1; }","duration":"274.693026ms","start":"2026-07-15T05:09:53.67956Z","end":"2026-07-15T05:09:53.954253Z","steps":["trace[1615044054] 'process raft request' (duration: 274.396781ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:09:54.119781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.395774ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-15T05:09:54.120304Z","caller":"traceutil/trace.go:171","msg":"trace[82383907] transaction","detail":"{read_only:false; response_revision:5400; number_of_response:1; }","duration":"359.850913ms","start":"2026-07-15T05:09:53.760379Z","end":"2026-07-15T05:09:54.12023Z","steps":["trace[82383907] 'process raft request' (duration: 359.561058ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:09:54.120535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:09:53.76035Z","time spent":"360.072847ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:09:54.120566Z","caller":"traceutil/trace.go:171","msg":"trace[1560806332] linearizableReadLoop","detail":"{readStateIndex:5600; appliedIndex:5597; }","duration":"205.831834ms","start":"2026-07-15T05:09:53.914707Z","end":"2026-07-15T05:09:54.120539Z","steps":["trace[1560806332] 'read index received' (duration: 39.186493ms)","trace[1560806332] 'applied index is now lower than readState.Index' (duration: 166.643991ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:09:54.120799Z","caller":"traceutil/trace.go:171","msg":"trace[696152941] transaction","detail":"{read_only:false; response_revision:5399; number_of_response:1; }","duration":"365.985181ms","start":"2026-07-15T05:09:53.754777Z","end":"2026-07-15T05:09:54.120762Z","steps":["trace[696152941] 'process raft request' (duration: 258.468215ms)","trace[696152941] 'compare' (duration: 106.091519ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:09:54.120864Z","caller":"traceutil/trace.go:171","msg":"trace[1468625166] transaction","detail":"{read_only:false; response_revision:5401; number_of_response:1; }","duration":"164.517401ms","start":"2026-07-15T05:09:53.956321Z","end":"2026-07-15T05:09:54.120838Z","steps":["trace[1468625166] 'process raft request' (duration: 163.783417ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:09:54.120998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:09:53.75475Z","time spent":"366.146424ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T05:09:54.121006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.282363ms","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-07-15T05:09:54.121099Z","caller":"traceutil/trace.go:171","msg":"trace[1824437459] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5402; }","duration":"206.439306ms","start":"2026-07-15T05:09:53.914636Z","end":"2026-07-15T05:09:54.121075Z","steps":["trace[1824437459] 'agreement among raft nodes before linearized reading' (duration: 206.062429ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:10:02.423923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.116003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-07-15T05:10:02.424038Z","caller":"traceutil/trace.go:171","msg":"trace[83302001] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5614; }","duration":"151.305146ms","start":"2026-07-15T05:10:02.272711Z","end":"2026-07-15T05:10:02.424016Z","steps":["trace[83302001] 'range keys from in-memory index tree' (duration: 150.867308ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:10:02.630466Z","caller":"traceutil/trace.go:171","msg":"trace[1793344082] transaction","detail":"{read_only:false; response_revision:5617; number_of_response:1; }","duration":"200.184985ms","start":"2026-07-15T05:10:02.43024Z","end":"2026-07-15T05:10:02.630424Z","steps":["trace[1793344082] 'process raft request' (duration: 199.93415ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:10:02.630747Z","caller":"traceutil/trace.go:171","msg":"trace[2067617918] transaction","detail":"{read_only:false; response_revision:5616; number_of_response:1; }","duration":"201.056272ms","start":"2026-07-15T05:10:02.429674Z","end":"2026-07-15T05:10:02.63073Z","steps":["trace[2067617918] 'process raft request' (duration: 175.284866ms)","trace[2067617918] 'compare' (duration: 24.9903ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:10:02.630812Z","caller":"traceutil/trace.go:171","msg":"trace[201095388] transaction","detail":"{read_only:false; response_revision:5618; number_of_response:1; }","duration":"192.906126ms","start":"2026-07-15T05:10:02.437889Z","end":"2026-07-15T05:10:02.630795Z","steps":["trace[201095388] 'process raft request' (duration: 192.337994ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:10:14.897952Z","caller":"traceutil/trace.go:171","msg":"trace[611021446] transaction","detail":"{read_only:false; response_revision:5726; number_of_response:1; }","duration":"155.187091ms","start":"2026-07-15T05:10:14.742726Z","end":"2026-07-15T05:10:14.897913Z","steps":["trace[611021446] 'process raft request' (duration: 129.686882ms)","trace[611021446] 'compare' (duration: 25.311966ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:10:14.903734Z","caller":"traceutil/trace.go:171","msg":"trace[992972863] linearizableReadLoop","detail":"{readStateIndex:5934; appliedIndex:5932; }","duration":"160.378982ms","start":"2026-07-15T05:10:14.743337Z","end":"2026-07-15T05:10:14.903716Z","steps":["trace[992972863] 'read index received' (duration: 129.023219ms)","trace[992972863] 'applied index is now lower than readState.Index' (duration: 31.354453ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:10:14.903839Z","caller":"traceutil/trace.go:171","msg":"trace[1471785516] transaction","detail":"{read_only:false; response_revision:5727; number_of_response:1; }","duration":"160.687197ms","start":"2026-07-15T05:10:14.743107Z","end":"2026-07-15T05:10:14.903794Z","steps":["trace[1471785516] 'process raft request' (duration: 160.505144ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:10:14.904458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.099775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:9 size:81899"} {"level":"info","ts":"2026-07-15T05:10:14.904531Z","caller":"traceutil/trace.go:171","msg":"trace[1777064299] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:9; response_revision:5727; }","duration":"161.265699ms","start":"2026-07-15T05:10:14.743248Z","end":"2026-07-15T05:10:14.904514Z","steps":["trace[1777064299] 'agreement among raft nodes before linearized reading' (duration: 160.566835ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:10:47.275458Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2982} {"level":"info","ts":"2026-07-15T05:10:47.350349Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2982,"took":"73.191616ms","hash":3208383979,"current-db-size-bytes":58408960,"current-db-size":"58 MB","current-db-size-in-use-bytes":33685504,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-15T05:10:47.350423Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3208383979,"revision":2982,"compact-revision":-1} {"level":"info","ts":"2026-07-15T05:15:47.283104Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6030} {"level":"info","ts":"2026-07-15T05:15:47.382854Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6030,"took":"97.967791ms","hash":748387205,"current-db-size-bytes":58408960,"current-db-size":"58 MB","current-db-size-in-use-bytes":21774336,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-07-15T05:15:47.382938Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":748387205,"revision":6030,"compact-revision":2982} {"level":"info","ts":"2026-07-15T05:16:23.950865Z","caller":"traceutil/trace.go:171","msg":"trace[1300567561] transaction","detail":"{read_only:false; response_revision:8473; number_of_response:1; }","duration":"221.483642ms","start":"2026-07-15T05:16:23.729347Z","end":"2026-07-15T05:16:23.950831Z","steps":["trace[1300567561] 'process raft request' (duration: 221.294018ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:16:24.023373Z","caller":"traceutil/trace.go:171","msg":"trace[243616937] transaction","detail":"{read_only:false; response_revision:8474; number_of_response:1; }","duration":"286.383287ms","start":"2026-07-15T05:16:23.736911Z","end":"2026-07-15T05:16:24.023294Z","steps":["trace[243616937] 'process raft request' (duration: 259.346468ms)","trace[243616937] 'compare' (duration: 26.815425ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:16:24.023413Z","caller":"traceutil/trace.go:171","msg":"trace[975596362] transaction","detail":"{read_only:false; response_revision:8475; number_of_response:1; }","duration":"215.211441ms","start":"2026-07-15T05:16:23.808157Z","end":"2026-07-15T05:16:24.023368Z","steps":["trace[975596362] 'process raft request' (duration: 215.039738ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:16:24.823801Z","caller":"traceutil/trace.go:171","msg":"trace[1351433342] transaction","detail":"{read_only:false; response_revision:8477; number_of_response:1; }","duration":"227.527967ms","start":"2026-07-15T05:16:24.596245Z","end":"2026-07-15T05:16:24.823773Z","steps":["trace[1351433342] 'process raft request' (duration: 227.349344ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:16:26.094706Z","caller":"traceutil/trace.go:171","msg":"trace[661287854] linearizableReadLoop","detail":"{readStateIndex:8779; appliedIndex:8778; }","duration":"136.5457ms","start":"2026-07-15T05:16:25.958126Z","end":"2026-07-15T05:16:26.094671Z","steps":["trace[661287854] 'read index received' (duration: 136.360297ms)","trace[661287854] 'applied index is now lower than readState.Index' (duration: 184.063µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:16:26.09478Z","caller":"traceutil/trace.go:171","msg":"trace[1296616944] transaction","detail":"{read_only:false; response_revision:8482; number_of_response:1; }","duration":"137.53708ms","start":"2026-07-15T05:16:25.957207Z","end":"2026-07-15T05:16:26.094744Z","steps":["trace[1296616944] 'process raft request' (duration: 137.241664ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:16:26.094901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.744674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T05:16:26.094953Z","caller":"traceutil/trace.go:171","msg":"trace[884405909] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:8482; }","duration":"136.876356ms","start":"2026-07-15T05:16:25.958063Z","end":"2026-07-15T05:16:26.09494Z","steps":["trace[884405909] 'agreement among raft nodes before linearized reading' (duration: 136.760534ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:16:26.096531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.045426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T05:16:26.096629Z","caller":"traceutil/trace.go:171","msg":"trace[958678671] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:8484; }","duration":"118.192339ms","start":"2026-07-15T05:16:25.978409Z","end":"2026-07-15T05:16:26.096602Z","steps":["trace[958678671] 'agreement among raft nodes before linearized reading' (duration: 118.019035ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:16:56.490031Z","caller":"traceutil/trace.go:171","msg":"trace[808074664] transaction","detail":"{read_only:false; response_revision:8700; number_of_response:1; }","duration":"252.172161ms","start":"2026-07-15T05:16:56.237828Z","end":"2026-07-15T05:16:56.49Z","steps":["trace[808074664] 'process raft request' (duration: 251.957136ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:16:56.573509Z","caller":"traceutil/trace.go:171","msg":"trace[1135324878] transaction","detail":"{read_only:false; response_revision:8701; number_of_response:1; }","duration":"327.877453ms","start":"2026-07-15T05:16:56.245604Z","end":"2026-07-15T05:16:56.573482Z","steps":["trace[1135324878] 'process raft request' (duration: 256.30022ms)","trace[1135324878] 'compare' (duration: 71.179796ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:16:56.573684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:16:56.245573Z","time spent":"328.012906ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:16:56.573849Z","caller":"traceutil/trace.go:171","msg":"trace[804494983] transaction","detail":"{read_only:false; response_revision:8702; number_of_response:1; }","duration":"327.567666ms","start":"2026-07-15T05:16:56.246197Z","end":"2026-07-15T05:16:56.573765Z","steps":["trace[804494983] 'process raft request' (duration: 327.056237ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:16:56.57401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:16:56.246178Z","time spent":"327.74316ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:17:06.40796Z","caller":"traceutil/trace.go:171","msg":"trace[2088722036] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8797; }","duration":"102.582649ms","start":"2026-07-15T05:17:06.305351Z","end":"2026-07-15T05:17:06.407933Z","steps":["trace[2088722036] 'process raft request' (duration: 102.415906ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:17:07.92754Z","caller":"traceutil/trace.go:171","msg":"trace[1885158318] transaction","detail":"{read_only:false; response_revision:8810; number_of_response:1; }","duration":"111.461688ms","start":"2026-07-15T05:17:07.81604Z","end":"2026-07-15T05:17:07.927502Z","steps":["trace[1885158318] 'process raft request' (duration: 111.187073ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:17:08.130304Z","caller":"traceutil/trace.go:171","msg":"trace[1489528391] transaction","detail":"{read_only:false; response_revision:8812; number_of_response:1; }","duration":"181.81436ms","start":"2026-07-15T05:17:07.94845Z","end":"2026-07-15T05:17:08.130264Z","steps":["trace[1489528391] 'process raft request' (duration: 176.569829ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:17:20.725629Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002413.wal"} {"level":"info","ts":"2026-07-15T05:18:37.665692Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5ac240a6daec2148","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-15T05:18:37.671892Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-15T05:18:37.672129Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-15T05:20:47.289416Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8104} {"level":"info","ts":"2026-07-15T05:20:47.356878Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8104,"took":"64.623769ms","hash":468536320,"current-db-size-bytes":58408960,"current-db-size":"58 MB","current-db-size-in-use-bytes":28647424,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-07-15T05:20:47.356959Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":468536320,"revision":8104,"compact-revision":6030} {"level":"info","ts":"2026-07-15T05:24:05.048209Z","caller":"traceutil/trace.go:171","msg":"trace[902639013] transaction","detail":"{read_only:false; response_revision:12003; number_of_response:1; }","duration":"137.330093ms","start":"2026-07-15T05:24:04.910832Z","end":"2026-07-15T05:24:05.048162Z","steps":["trace[902639013] 'process raft request' (duration: 137.061467ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:24:05.693622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.846549ms","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-07-15T05:24:05.693855Z","caller":"traceutil/trace.go:171","msg":"trace[491035856] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:12005; }","duration":"131.165695ms","start":"2026-07-15T05:24:05.562661Z","end":"2026-07-15T05:24:05.693827Z","steps":["trace[491035856] 'range keys from in-memory index tree' (duration: 130.520713ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:24:05.693653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.005717ms","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-07-15T05:24:05.694034Z","caller":"traceutil/trace.go:171","msg":"trace[1900339619] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:12005; }","duration":"125.413585ms","start":"2026-07-15T05:24:05.568553Z","end":"2026-07-15T05:24:05.693967Z","steps":["trace[1900339619] 'range keys from in-memory index tree' (duration: 124.762063ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:25:47.296836Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10510} {"level":"info","ts":"2026-07-15T05:25:47.379241Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10510,"took":"80.126744ms","hash":2819469464,"current-db-size-bytes":58408960,"current-db-size":"58 MB","current-db-size-in-use-bytes":26578944,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-07-15T05:25:47.379347Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2819469464,"revision":10510,"compact-revision":8104} {"level":"info","ts":"2026-07-15T05:30:07.833781Z","caller":"traceutil/trace.go:171","msg":"trace[1101039995] transaction","detail":"{read_only:false; response_revision:14457; number_of_response:1; }","duration":"154.707575ms","start":"2026-07-15T05:30:07.679007Z","end":"2026-07-15T05:30:07.833714Z","steps":["trace[1101039995] 'process raft request' (duration: 153.770097ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:30:07.83872Z","caller":"traceutil/trace.go:171","msg":"trace[1720506112] transaction","detail":"{read_only:false; response_revision:14458; number_of_response:1; }","duration":"118.292385ms","start":"2026-07-15T05:30:07.720389Z","end":"2026-07-15T05:30:07.838681Z","steps":["trace[1720506112] 'process raft request' (duration: 118.052031ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:30:07.83873Z","caller":"traceutil/trace.go:171","msg":"trace[806743287] transaction","detail":"{read_only:false; response_revision:14459; number_of_response:1; }","duration":"117.186003ms","start":"2026-07-15T05:30:07.721506Z","end":"2026-07-15T05:30:07.838693Z","steps":["trace[806743287] 'process raft request' (duration: 117.066311ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:30:32.065857Z","caller":"traceutil/trace.go:171","msg":"trace[887742448] transaction","detail":"{read_only:false; response_revision:14587; number_of_response:1; }","duration":"135.351927ms","start":"2026-07-15T05:30:31.930472Z","end":"2026-07-15T05:30:32.065824Z","steps":["trace[887742448] 'process raft request' (duration: 53.358083ms)","trace[887742448] 'compare' (duration: 81.858072ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:30:32.067819Z","caller":"traceutil/trace.go:171","msg":"trace[1734631065] transaction","detail":"{read_only:false; response_revision:14588; number_of_response:1; }","duration":"135.173855ms","start":"2026-07-15T05:30:31.932608Z","end":"2026-07-15T05:30:32.067782Z","steps":["trace[1734631065] 'process raft request' (duration: 134.948131ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:30:32.067868Z","caller":"traceutil/trace.go:171","msg":"trace[1857176511] transaction","detail":"{read_only:false; response_revision:14589; number_of_response:1; }","duration":"133.119876ms","start":"2026-07-15T05:30:31.93471Z","end":"2026-07-15T05:30:32.06783Z","steps":["trace[1857176511] 'process raft request' (duration: 132.990874ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:30:47.304048Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12696} {"level":"info","ts":"2026-07-15T05:30:47.382796Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12696,"took":"75.628495ms","hash":957576097,"current-db-size-bytes":58408960,"current-db-size":"58 MB","current-db-size-in-use-bytes":27348992,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-07-15T05:30:47.382876Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":957576097,"revision":12696,"compact-revision":10510} {"level":"info","ts":"2026-07-15T05:30:58.954988Z","caller":"traceutil/trace.go:171","msg":"trace[13214861] transaction","detail":"{read_only:false; response_revision:14860; number_of_response:1; }","duration":"112.381192ms","start":"2026-07-15T05:30:58.842554Z","end":"2026-07-15T05:30:58.954935Z","steps":["trace[13214861] 'process raft request' (duration: 111.920913ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:32:40.382636Z","caller":"traceutil/trace.go:171","msg":"trace[541736894] linearizableReadLoop","detail":"{readStateIndex:16494; appliedIndex:16493; }","duration":"145.446349ms","start":"2026-07-15T05:32:40.23717Z","end":"2026-07-15T05:32:40.382616Z","steps":["trace[541736894] 'read index received' (duration: 144.94194ms)","trace[541736894] 'applied index is now lower than readState.Index' (duration: 503.519µs)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:32:40.383173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.988289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-8glqx\" ","response":"range_response_count:1 size:20653"} {"level":"info","ts":"2026-07-15T05:32:40.383217Z","caller":"traceutil/trace.go:171","msg":"trace[353796562] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-8glqx; range_end:; response_count:1; response_revision:15952; }","duration":"146.107171ms","start":"2026-07-15T05:32:40.237099Z","end":"2026-07-15T05:32:40.383206Z","steps":["trace[353796562] 'agreement among raft nodes before linearized reading' (duration: 145.933938ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:32:40.383274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.865586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-15T05:32:40.38338Z","caller":"traceutil/trace.go:171","msg":"trace[1045257740] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15952; }","duration":"118.015989ms","start":"2026-07-15T05:32:40.265343Z","end":"2026-07-15T05:32:40.383359Z","steps":["trace[1045257740] 'agreement among raft nodes before linearized reading' (duration: 117.752914ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:32:40.383523Z","caller":"traceutil/trace.go:171","msg":"trace[1403125264] transaction","detail":"{read_only:false; response_revision:15952; number_of_response:1; }","duration":"169.555825ms","start":"2026-07-15T05:32:40.21393Z","end":"2026-07-15T05:32:40.383486Z","steps":["trace[1403125264] 'process raft request' (duration: 168.349153ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:32:40.384092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.926598ms","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-07-15T05:32:40.384138Z","caller":"traceutil/trace.go:171","msg":"trace[324846029] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15952; }","duration":"116.972359ms","start":"2026-07-15T05:32:40.267143Z","end":"2026-07-15T05:32:40.384115Z","steps":["trace[324846029] 'agreement among raft nodes before linearized reading' (duration: 116.896638ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:32:40.65335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.906901ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-15T05:32:40.653688Z","caller":"traceutil/trace.go:171","msg":"trace[1623330757] transaction","detail":"{read_only:false; response_revision:15954; number_of_response:1; }","duration":"262.590281ms","start":"2026-07-15T05:32:40.391073Z","end":"2026-07-15T05:32:40.653663Z","steps":["trace[1623330757] 'process raft request' (duration: 262.441078ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:32:40.653596Z","caller":"traceutil/trace.go:171","msg":"trace[1911040645] transaction","detail":"{read_only:false; response_revision:15953; number_of_response:1; }","duration":"264.215122ms","start":"2026-07-15T05:32:40.389313Z","end":"2026-07-15T05:32:40.653528Z","steps":["trace[1911040645] 'process raft request' (duration: 79.724723ms)","trace[1911040645] 'compare' (duration: 171.548814ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:32:40.653736Z","caller":"traceutil/trace.go:171","msg":"trace[873759324] linearizableReadLoop","detail":"{readStateIndex:16495; appliedIndex:16494; }","duration":"262.755584ms","start":"2026-07-15T05:32:40.390906Z","end":"2026-07-15T05:32:40.653661Z","steps":["trace[873759324] 'read index received' (duration: 78.218893ms)","trace[873759324] 'applied index is now lower than readState.Index' (duration: 184.530721ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:32:40.654903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.961067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:79 size:770009"} {"level":"info","ts":"2026-07-15T05:32:40.655155Z","caller":"traceutil/trace.go:171","msg":"trace[977468417] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:79; response_revision:15954; }","duration":"264.208401ms","start":"2026-07-15T05:32:40.390877Z","end":"2026-07-15T05:32:40.655085Z","steps":["trace[977468417] 'agreement among raft nodes before linearized reading' (duration: 262.999669ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:32:40.692113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.664519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T05:32:40.692233Z","caller":"traceutil/trace.go:171","msg":"trace[1028645277] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15957; }","duration":"274.848444ms","start":"2026-07-15T05:32:40.417355Z","end":"2026-07-15T05:32:40.692204Z","steps":["trace[1028645277] 'agreement among raft nodes before linearized reading' (duration: 274.534427ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:32:40.692154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.75844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.156\" ","response":"range_response_count:1 size:138"} {"level":"warn","ts":"2026-07-15T05:32:40.692278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.709241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T05:32:40.692404Z","caller":"traceutil/trace.go:171","msg":"trace[925214666] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15957; }","duration":"216.898475ms","start":"2026-07-15T05:32:40.475472Z","end":"2026-07-15T05:32:40.692371Z","steps":["trace[925214666] 'agreement among raft nodes before linearized reading' (duration: 216.64147ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:32:40.692502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.429787ms","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-07-15T05:32:40.692488Z","caller":"traceutil/trace.go:171","msg":"trace[139125952] range","detail":"{range_begin:/registry/masterleases/199.204.45.156; range_end:; response_count:1; response_revision:15957; }","duration":"247.986884ms","start":"2026-07-15T05:32:40.444336Z","end":"2026-07-15T05:32:40.692323Z","steps":["trace[139125952] 'agreement among raft nodes before linearized reading' (duration: 247.641838ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:32:40.692577Z","caller":"traceutil/trace.go:171","msg":"trace[1319846581] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15957; }","duration":"195.501499ms","start":"2026-07-15T05:32:40.497055Z","end":"2026-07-15T05:32:40.692557Z","steps":["trace[1319846581] 'agreement among raft nodes before linearized reading' (duration: 195.299685ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:32:40.692725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.211061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-07-15T05:32:40.692763Z","caller":"traceutil/trace.go:171","msg":"trace[558825450] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15957; }","duration":"217.291462ms","start":"2026-07-15T05:32:40.475461Z","end":"2026-07-15T05:32:40.692753Z","steps":["trace[558825450] 'agreement among raft nodes before linearized reading' (duration: 217.132369ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:32:40.692795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.466008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"warn","ts":"2026-07-15T05:32:40.692819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.202418ms","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-07-15T05:32:40.69289Z","caller":"traceutil/trace.go:171","msg":"trace[1045941667] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15957; }","duration":"273.61537ms","start":"2026-07-15T05:32:40.419243Z","end":"2026-07-15T05:32:40.692858Z","steps":["trace[1045941667] 'agreement among raft nodes before linearized reading' (duration: 273.443587ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:32:40.692925Z","caller":"traceutil/trace.go:171","msg":"trace[1131987897] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15957; }","duration":"200.354821ms","start":"2026-07-15T05:32:40.492541Z","end":"2026-07-15T05:32:40.692895Z","steps":["trace[1131987897] 'agreement among raft nodes before linearized reading' (duration: 200.065906ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:32:40.693066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.796745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-07-15T05:32:40.693116Z","caller":"traceutil/trace.go:171","msg":"trace[355264015] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15957; }","duration":"271.860677ms","start":"2026-07-15T05:32:40.421243Z","end":"2026-07-15T05:32:40.693103Z","steps":["trace[355264015] 'agreement among raft nodes before linearized reading' (duration: 271.738555ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:32:40.693648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.601668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-07-15T05:32:40.693725Z","caller":"traceutil/trace.go:171","msg":"trace[310207370] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15957; }","duration":"217.68748ms","start":"2026-07-15T05:32:40.476021Z","end":"2026-07-15T05:32:40.693708Z","steps":["trace[310207370] 'agreement among raft nodes before linearized reading' (duration: 215.886597ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:32:40.890397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.394945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:138"} {"level":"info","ts":"2026-07-15T05:32:40.890489Z","caller":"traceutil/trace.go:171","msg":"trace[1947453616] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:15960; }","duration":"148.524127ms","start":"2026-07-15T05:32:40.741943Z","end":"2026-07-15T05:32:40.890468Z","steps":["trace[1947453616] 'range keys from in-memory index tree' (duration: 148.316434ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:32:40.902101Z","caller":"traceutil/trace.go:171","msg":"trace[199848967] linearizableReadLoop","detail":"{readStateIndex:16504; appliedIndex:16503; }","duration":"102.213169ms","start":"2026-07-15T05:32:40.799862Z","end":"2026-07-15T05:32:40.902075Z","steps":["trace[199848967] 'read index received' (duration: 101.935333ms)","trace[199848967] 'applied index is now lower than readState.Index' (duration: 276.916µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:32:40.902211Z","caller":"traceutil/trace.go:171","msg":"trace[1475202749] transaction","detail":"{read_only:false; response_revision:15961; number_of_response:1; }","duration":"130.594448ms","start":"2026-07-15T05:32:40.771573Z","end":"2026-07-15T05:32:40.902168Z","steps":["trace[1475202749] 'process raft request' (duration: 130.258851ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:32:40.902305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.436093ms","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-07-15T05:32:40.902362Z","caller":"traceutil/trace.go:171","msg":"trace[768309114] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:15961; }","duration":"102.559475ms","start":"2026-07-15T05:32:40.799786Z","end":"2026-07-15T05:32:40.902346Z","steps":["trace[768309114] 'agreement among raft nodes before linearized reading' (duration: 102.392622ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:35:47.312841Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14677} {"level":"info","ts":"2026-07-15T05:35:47.391454Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14677,"took":"74.136481ms","hash":2952535860,"current-db-size-bytes":58408960,"current-db-size":"58 MB","current-db-size-in-use-bytes":33861632,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-15T05:35:47.391528Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2952535860,"revision":14677,"compact-revision":12696} {"level":"info","ts":"2026-07-15T05:38:15.565478Z","caller":"traceutil/trace.go:171","msg":"trace[1121394671] transaction","detail":"{read_only:false; response_revision:18413; number_of_response:1; }","duration":"151.87529ms","start":"2026-07-15T05:38:15.413566Z","end":"2026-07-15T05:38:15.565441Z","steps":["trace[1121394671] 'process raft request' (duration: 151.656586ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:38:18.296229Z","caller":"traceutil/trace.go:171","msg":"trace[405935895] transaction","detail":"{read_only:false; response_revision:18435; number_of_response:1; }","duration":"111.856709ms","start":"2026-07-15T05:38:18.18434Z","end":"2026-07-15T05:38:18.296197Z","steps":["trace[405935895] 'process raft request' (duration: 111.579574ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:38:18.701608Z","caller":"traceutil/trace.go:171","msg":"trace[104250371] transaction","detail":"{read_only:false; response_revision:18438; number_of_response:1; }","duration":"177.211724ms","start":"2026-07-15T05:38:18.524358Z","end":"2026-07-15T05:38:18.70157Z","steps":["trace[104250371] 'process raft request' (duration: 177.02986ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:38:18.718094Z","caller":"traceutil/trace.go:171","msg":"trace[517479044] transaction","detail":"{read_only:false; response_revision:18439; number_of_response:1; }","duration":"192.33562ms","start":"2026-07-15T05:38:18.525721Z","end":"2026-07-15T05:38:18.718057Z","steps":["trace[517479044] 'process raft request' (duration: 192.200838ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:38:20.639829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.219174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.156\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-07-15T05:38:20.639936Z","caller":"traceutil/trace.go:171","msg":"trace[965651591] range","detail":"{range_begin:/registry/masterleases/199.204.45.156; range_end:; response_count:1; response_revision:18461; }","duration":"176.368207ms","start":"2026-07-15T05:38:20.463541Z","end":"2026-07-15T05:38:20.639909Z","steps":["trace[965651591] 'range keys from in-memory index tree' (duration: 176.006729ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:38:20.640071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.454407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-15T05:38:20.640145Z","caller":"traceutil/trace.go:171","msg":"trace[1230947617] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18461; }","duration":"209.58113ms","start":"2026-07-15T05:38:20.430547Z","end":"2026-07-15T05:38:20.640128Z","steps":["trace[1230947617] 'range keys from in-memory index tree' (duration: 209.199442ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:38:20.640359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.831128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-15T05:38:20.640407Z","caller":"traceutil/trace.go:171","msg":"trace[1578255813] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18461; }","duration":"207.895018ms","start":"2026-07-15T05:38:20.432497Z","end":"2026-07-15T05:38:20.640392Z","steps":["trace[1578255813] 'range keys from in-memory index tree' (duration: 207.704195ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:38:20.813557Z","caller":"traceutil/trace.go:171","msg":"trace[2028974972] transaction","detail":"{read_only:false; response_revision:18462; number_of_response:1; }","duration":"169.136189ms","start":"2026-07-15T05:38:20.644387Z","end":"2026-07-15T05:38:20.813523Z","steps":["trace[2028974972] 'process raft request' (duration: 166.734684ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:38:20.814067Z","caller":"traceutil/trace.go:171","msg":"trace[1058890161] transaction","detail":"{read_only:false; response_revision:18463; number_of_response:1; }","duration":"169.507017ms","start":"2026-07-15T05:38:20.644501Z","end":"2026-07-15T05:38:20.814008Z","steps":["trace[1058890161] 'process raft request' (duration: 168.952416ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:38:20.814196Z","caller":"traceutil/trace.go:171","msg":"trace[578868562] linearizableReadLoop","detail":"{readStateIndex:19090; appliedIndex:19087; }","duration":"109.117187ms","start":"2026-07-15T05:38:20.705049Z","end":"2026-07-15T05:38:20.814166Z","steps":["trace[578868562] 'read index received' (duration: 106.087579ms)","trace[578868562] 'applied index is now lower than readState.Index' (duration: 3.028258ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:38:20.81438Z","caller":"traceutil/trace.go:171","msg":"trace[482038206] transaction","detail":"{read_only:false; response_revision:18464; number_of_response:1; }","duration":"112.604204ms","start":"2026-07-15T05:38:20.701702Z","end":"2026-07-15T05:38:20.814306Z","steps":["trace[482038206] 'process raft request' (duration: 112.143605ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:38:20.814513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.446113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-15T05:38:20.814585Z","caller":"traceutil/trace.go:171","msg":"trace[749028124] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18464; }","duration":"109.570925ms","start":"2026-07-15T05:38:20.704993Z","end":"2026-07-15T05:38:20.814564Z","steps":["trace[749028124] 'agreement among raft nodes before linearized reading' (duration: 109.323351ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:38:24.711412Z","caller":"traceutil/trace.go:171","msg":"trace[1683602205] linearizableReadLoop","detail":"{readStateIndex:19158; appliedIndex:19157; }","duration":"118.420904ms","start":"2026-07-15T05:38:24.592975Z","end":"2026-07-15T05:38:24.711396Z","steps":["trace[1683602205] 'read index received' (duration: 118.351942ms)","trace[1683602205] 'applied index is now lower than readState.Index' (duration: 68.542µs)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:38:24.711585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.623988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1286"} {"level":"info","ts":"2026-07-15T05:38:24.711608Z","caller":"traceutil/trace.go:171","msg":"trace[814003599] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:18532; }","duration":"118.686789ms","start":"2026-07-15T05:38:24.592913Z","end":"2026-07-15T05:38:24.7116Z","steps":["trace[814003599] 'agreement among raft nodes before linearized reading' (duration: 118.546056ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:38:24.711506Z","caller":"traceutil/trace.go:171","msg":"trace[434966294] transaction","detail":"{read_only:false; response_revision:18532; number_of_response:1; }","duration":"122.061544ms","start":"2026-07-15T05:38:24.589406Z","end":"2026-07-15T05:38:24.711467Z","steps":["trace[434966294] 'process raft request' (duration: 121.881801ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:39:41.311487Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5ac240a6daec2148","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-15T05:39:41.325777Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-15T05:39:41.325863Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-07-15T05:40:47.322408Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17409} {"level":"info","ts":"2026-07-15T05:40:47.418714Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17409,"took":"92.085893ms","hash":280903811,"current-db-size-bytes":58408960,"current-db-size":"58 MB","current-db-size-in-use-bytes":34934784,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-07-15T05:40:47.41877Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":280903811,"revision":17409,"compact-revision":14677} {"level":"warn","ts":"2026-07-15T05:44:14.020974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.420537ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T05:44:14.021096Z","caller":"traceutil/trace.go:171","msg":"trace[692459081] transaction","detail":"{read_only:false; response_revision:21192; number_of_response:1; }","duration":"324.584679ms","start":"2026-07-15T05:44:13.696493Z","end":"2026-07-15T05:44:14.021078Z","steps":["trace[692459081] 'process raft request' (duration: 42.987368ms)","trace[692459081] 'compare' (duration: 281.123731ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:44:14.021163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:44:13.696468Z","time spent":"324.66606ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T05:44:14.335468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.168856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:44:14.335579Z","caller":"traceutil/trace.go:171","msg":"trace[2023225631] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:21192; }","duration":"133.331088ms","start":"2026-07-15T05:44:14.202228Z","end":"2026-07-15T05:44:14.335559Z","steps":["trace[2023225631] 'count revisions from in-memory index tree' (duration: 133.095864ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:44:14.789656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.290872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:44:14.789773Z","caller":"traceutil/trace.go:171","msg":"trace[2057038399] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:21193; }","duration":"119.460275ms","start":"2026-07-15T05:44:14.670268Z","end":"2026-07-15T05:44:14.789729Z","steps":["trace[2057038399] 'count revisions from in-memory index tree' (duration: 119.114758ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:44:14.789831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.10041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-15T05:44:14.78992Z","caller":"traceutil/trace.go:171","msg":"trace[1545236923] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:21193; }","duration":"148.268462ms","start":"2026-07-15T05:44:14.641634Z","end":"2026-07-15T05:44:14.789902Z","steps":["trace[1545236923] 'count revisions from in-memory index tree' (duration: 147.990258ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:44:14.958524Z","caller":"traceutil/trace.go:171","msg":"trace[2079101826] transaction","detail":"{read_only:false; response_revision:21194; number_of_response:1; }","duration":"164.385669ms","start":"2026-07-15T05:44:14.794111Z","end":"2026-07-15T05:44:14.958497Z","steps":["trace[2079101826] 'process raft request' (duration: 164.208546ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:44:14.968309Z","caller":"traceutil/trace.go:171","msg":"trace[1488986025] transaction","detail":"{read_only:false; response_revision:21196; number_of_response:1; }","duration":"173.396761ms","start":"2026-07-15T05:44:14.794889Z","end":"2026-07-15T05:44:14.968286Z","steps":["trace[1488986025] 'process raft request' (duration: 173.35564ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:44:14.968435Z","caller":"traceutil/trace.go:171","msg":"trace[1713322973] transaction","detail":"{read_only:false; response_revision:21195; number_of_response:1; }","duration":"173.791748ms","start":"2026-07-15T05:44:14.794553Z","end":"2026-07-15T05:44:14.968345Z","steps":["trace[1713322973] 'process raft request' (duration: 173.596935ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:44:15.261766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.693887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:44:15.261858Z","caller":"traceutil/trace.go:171","msg":"trace[1400082280] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21196; }","duration":"152.84107ms","start":"2026-07-15T05:44:15.108999Z","end":"2026-07-15T05:44:15.26184Z","steps":["trace[1400082280] 'range keys from in-memory index tree' (duration: 152.596534ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:45:47.33155Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19789} {"level":"info","ts":"2026-07-15T05:45:47.444173Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19789,"took":"108.375483ms","hash":1896070867,"current-db-size-bytes":58408960,"current-db-size":"58 MB","current-db-size-in-use-bytes":30380032,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-15T05:45:47.444257Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1896070867,"revision":19789,"compact-revision":17409} {"level":"info","ts":"2026-07-15T05:47:02.265989Z","caller":"traceutil/trace.go:171","msg":"trace[1934392287] transaction","detail":"{read_only:false; response_revision:22529; number_of_response:1; }","duration":"133.048273ms","start":"2026-07-15T05:47:02.13291Z","end":"2026-07-15T05:47:02.265958Z","steps":["trace[1934392287] 'process raft request' (duration: 132.85679ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:18.040159Z","caller":"traceutil/trace.go:171","msg":"trace[1192631685] transaction","detail":"{read_only:false; response_revision:22647; number_of_response:1; }","duration":"114.438698ms","start":"2026-07-15T05:47:17.925683Z","end":"2026-07-15T05:47:18.040122Z","steps":["trace[1192631685] 'process raft request' (duration: 97.795921ms)","trace[1192631685] 'compare' (duration: 16.127868ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:47:18.285131Z","caller":"traceutil/trace.go:171","msg":"trace[1417909694] linearizableReadLoop","detail":"{readStateIndex:23416; appliedIndex:23415; }","duration":"176.466379ms","start":"2026-07-15T05:47:18.108642Z","end":"2026-07-15T05:47:18.285108Z","steps":["trace[1417909694] 'read index received' (duration: 176.254026ms)","trace[1417909694] 'applied index is now lower than readState.Index' (duration: 211.703µs)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:47:18.28527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.617282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:47:18.285214Z","caller":"traceutil/trace.go:171","msg":"trace[2124153953] transaction","detail":"{read_only:false; response_revision:22648; number_of_response:1; }","duration":"204.032555ms","start":"2026-07-15T05:47:18.081131Z","end":"2026-07-15T05:47:18.285163Z","steps":["trace[2124153953] 'process raft request' (duration: 203.819091ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:18.285308Z","caller":"traceutil/trace.go:171","msg":"trace[1116819880] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22648; }","duration":"176.737814ms","start":"2026-07-15T05:47:18.108558Z","end":"2026-07-15T05:47:18.285296Z","steps":["trace[1116819880] 'agreement among raft nodes before linearized reading' (duration: 176.644942ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:48:19.881207Z","caller":"traceutil/trace.go:171","msg":"trace[1326807397] transaction","detail":"{read_only:false; response_revision:23718; number_of_response:1; }","duration":"102.237336ms","start":"2026-07-15T05:48:19.778943Z","end":"2026-07-15T05:48:19.88118Z","steps":["trace[1326807397] 'process raft request' (duration: 102.060223ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:50:47.340069Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22026} {"level":"info","ts":"2026-07-15T05:50:47.441746Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22026,"took":"97.99965ms","hash":1564678229,"current-db-size-bytes":58408960,"current-db-size":"58 MB","current-db-size-in-use-bytes":44969984,"current-db-size-in-use":"45 MB"} {"level":"info","ts":"2026-07-15T05:50:47.441814Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1564678229,"revision":22026,"compact-revision":19789} {"level":"info","ts":"2026-07-15T05:51:53.295758Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000684b.wal"} {"level":"info","ts":"2026-07-15T05:51:57.311003Z","caller":"traceutil/trace.go:171","msg":"trace[1734180644] transaction","detail":"{read_only:false; response_revision:25887; number_of_response:1; }","duration":"149.503754ms","start":"2026-07-15T05:51:57.161475Z","end":"2026-07-15T05:51:57.310979Z","steps":["trace[1734180644] 'process raft request' (duration: 149.349181ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:02.222909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.818625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:52:02.223052Z","caller":"traceutil/trace.go:171","msg":"trace[1488775295] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25932; }","duration":"101.022889ms","start":"2026-07-15T05:52:02.121991Z","end":"2026-07-15T05:52:02.223014Z","steps":["trace[1488775295] 'range keys from in-memory index tree' (duration: 100.712553ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:52:02.823398Z","caller":"traceutil/trace.go:171","msg":"trace[699300660] transaction","detail":"{read_only:false; response_revision:25934; number_of_response:1; }","duration":"105.529016ms","start":"2026-07-15T05:52:02.717841Z","end":"2026-07-15T05:52:02.82337Z","steps":["trace[699300660] 'process raft request' (duration: 105.296311ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:52:03.090523Z","caller":"traceutil/trace.go:171","msg":"trace[1076291017] transaction","detail":"{read_only:false; response_revision:25935; number_of_response:1; }","duration":"171.010858ms","start":"2026-07-15T05:52:02.919461Z","end":"2026-07-15T05:52:03.090472Z","steps":["trace[1076291017] 'process raft request' (duration: 170.390897ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:03.396582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.670926ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T05:52:03.396895Z","caller":"traceutil/trace.go:171","msg":"trace[2007516261] transaction","detail":"{read_only:false; response_revision:25936; number_of_response:1; }","duration":"297.843994ms","start":"2026-07-15T05:52:03.099Z","end":"2026-07-15T05:52:03.396844Z","steps":["trace[2007516261] 'process raft request' (duration: 103.771631ms)","trace[2007516261] 'compare' (duration: 193.057244ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:52:07.533287Z","caller":"traceutil/trace.go:171","msg":"trace[1427393350] transaction","detail":"{read_only:false; response_revision:25965; number_of_response:1; }","duration":"120.488644ms","start":"2026-07-15T05:52:07.412765Z","end":"2026-07-15T05:52:07.533254Z","steps":["trace[1427393350] 'process raft request' (duration: 120.241329ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:07.824049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.946742ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T05:52:07.824296Z","caller":"traceutil/trace.go:171","msg":"trace[2134487767] transaction","detail":"{read_only:false; response_revision:25966; number_of_response:1; }","duration":"407.48644ms","start":"2026-07-15T05:52:07.416765Z","end":"2026-07-15T05:52:07.824252Z","steps":["trace[2134487767] 'process raft request' (duration: 284.151721ms)","trace[2134487767] 'compare' (duration: 122.448172ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:52:07.824412Z","caller":"traceutil/trace.go:171","msg":"trace[302410653] transaction","detail":"{read_only:false; response_revision:25968; number_of_response:1; }","duration":"339.089911ms","start":"2026-07-15T05:52:07.485298Z","end":"2026-07-15T05:52:07.824388Z","steps":["trace[302410653] 'process raft request' (duration: 338.943168ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:07.824494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:52:07.485274Z","time spent":"339.184522ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T05:52:07.824504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:52:07.416757Z","time spent":"407.625044ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:52:07.824542Z","caller":"traceutil/trace.go:171","msg":"trace[1959658902] transaction","detail":"{read_only:false; response_revision:25967; number_of_response:1; }","duration":"386.653448ms","start":"2026-07-15T05:52:07.437696Z","end":"2026-07-15T05:52:07.82435Z","steps":["trace[1959658902] 'process raft request' (duration: 386.475505ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:52:07.824589Z","caller":"traceutil/trace.go:171","msg":"trace[813090928] transaction","detail":"{read_only:false; response_revision:25969; number_of_response:1; }","duration":"336.942339ms","start":"2026-07-15T05:52:07.487596Z","end":"2026-07-15T05:52:07.824539Z","steps":["trace[813090928] 'process raft request' (duration: 336.688724ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:52:07.824753Z","caller":"traceutil/trace.go:171","msg":"trace[804578641] transaction","detail":"{read_only:false; response_revision:25970; number_of_response:1; }","duration":"337.117863ms","start":"2026-07-15T05:52:07.487622Z","end":"2026-07-15T05:52:07.82474Z","steps":["trace[804578641] 'process raft request' (duration: 336.859128ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:52:07.824754Z","caller":"traceutil/trace.go:171","msg":"trace[615739119] linearizableReadLoop","detail":"{readStateIndex:26820; appliedIndex:26817; }","duration":"383.644411ms","start":"2026-07-15T05:52:07.441093Z","end":"2026-07-15T05:52:07.824737Z","steps":["trace[615739119] 'read index received' (duration: 91.890533ms)","trace[615739119] 'applied index is now lower than readState.Index' (duration: 291.752508ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:52:07.824779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:52:07.48757Z","time spent":"337.112533ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T05:52:07.824779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:52:07.437669Z","time spent":"386.981755ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:52:07.824825Z","caller":"traceutil/trace.go:171","msg":"trace[282843816] transaction","detail":"{read_only:false; response_revision:25971; number_of_response:1; }","duration":"337.021521ms","start":"2026-07-15T05:52:07.487789Z","end":"2026-07-15T05:52:07.82481Z","steps":["trace[282843816] 'process raft request' (duration: 336.879338ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:07.824826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:52:07.487609Z","time spent":"337.174584ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T05:52:07.824917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:52:07.487757Z","time spent":"337.100022ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T05:52:07.82498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.886835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-15T05:52:07.825019Z","caller":"traceutil/trace.go:171","msg":"trace[334852575] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25971; }","duration":"383.952766ms","start":"2026-07-15T05:52:07.441056Z","end":"2026-07-15T05:52:07.825009Z","steps":["trace[334852575] 'agreement among raft nodes before linearized reading' (duration: 383.796543ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:07.82507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:52:07.441043Z","time spent":"384.020748ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-15T05:52:07.825374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.960298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-15T05:52:07.825416Z","caller":"traceutil/trace.go:171","msg":"trace[340724881] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25971; }","duration":"382.051209ms","start":"2026-07-15T05:52:07.443351Z","end":"2026-07-15T05:52:07.825402Z","steps":["trace[340724881] 'agreement among raft nodes before linearized reading' (duration: 381.866886ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:07.82545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:52:07.443323Z","time spent":"382.119861ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-15T05:52:07.825705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.741813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-15T05:52:07.825779Z","caller":"traceutil/trace.go:171","msg":"trace[1973399219] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25971; }","duration":"283.916567ms","start":"2026-07-15T05:52:07.541844Z","end":"2026-07-15T05:52:07.82576Z","steps":["trace[1973399219] 'agreement among raft nodes before linearized reading' (duration: 283.686563ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:52:07.994397Z","caller":"traceutil/trace.go:171","msg":"trace[608990962] transaction","detail":"{read_only:false; response_revision:25973; number_of_response:1; }","duration":"152.256247ms","start":"2026-07-15T05:52:07.842103Z","end":"2026-07-15T05:52:07.994359Z","steps":["trace[608990962] 'process raft request' (duration: 152.052863ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:52:08.062689Z","caller":"traceutil/trace.go:171","msg":"trace[18563035] linearizableReadLoop","detail":"{readStateIndex:26827; appliedIndex:26826; }","duration":"106.220929ms","start":"2026-07-15T05:52:07.956446Z","end":"2026-07-15T05:52:08.062666Z","steps":["trace[18563035] 'read index received' (duration: 38.683596ms)","trace[18563035] 'applied index is now lower than readState.Index' (duration: 67.536393ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:52:08.062856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.385522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-15T05:52:08.062904Z","caller":"traceutil/trace.go:171","msg":"trace[865919669] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:25974; }","duration":"106.503174ms","start":"2026-07-15T05:52:07.956385Z","end":"2026-07-15T05:52:08.062888Z","steps":["trace[865919669] 'agreement among raft nodes before linearized reading' (duration: 106.375372ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:52:08.063245Z","caller":"traceutil/trace.go:171","msg":"trace[1811614310] transaction","detail":"{read_only:false; response_revision:25974; number_of_response:1; }","duration":"220.34749ms","start":"2026-07-15T05:52:07.842885Z","end":"2026-07-15T05:52:08.063233Z","steps":["trace[1811614310] 'process raft request' (duration: 219.661058ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:08.215462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.802639ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T05:52:08.215583Z","caller":"traceutil/trace.go:171","msg":"trace[1097725673] transaction","detail":"{read_only:false; response_revision:25975; number_of_response:1; }","duration":"180.704526ms","start":"2026-07-15T05:52:08.034853Z","end":"2026-07-15T05:52:08.215558Z","steps":["trace[1097725673] 'process raft request' (duration: 58.719423ms)","trace[1097725673] 'compare' (duration: 121.449432ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:52:08.64575Z","caller":"traceutil/trace.go:171","msg":"trace[33788760] transaction","detail":"{read_only:false; response_revision:25976; number_of_response:1; }","duration":"112.549771ms","start":"2026-07-15T05:52:08.533163Z","end":"2026-07-15T05:52:08.645713Z","steps":["trace[33788760] 'process raft request' (duration: 112.325367ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:52:10.025583Z","caller":"traceutil/trace.go:171","msg":"trace[543678597] transaction","detail":"{read_only:false; response_revision:25979; number_of_response:1; }","duration":"195.515621ms","start":"2026-07-15T05:52:09.830044Z","end":"2026-07-15T05:52:10.025559Z","steps":["trace[543678597] 'process raft request' (duration: 195.353087ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:52:10.343116Z","caller":"traceutil/trace.go:171","msg":"trace[1426993529] transaction","detail":"{read_only:false; response_revision:25980; number_of_response:1; }","duration":"512.817652ms","start":"2026-07-15T05:52:09.830273Z","end":"2026-07-15T05:52:10.343091Z","steps":["trace[1426993529] 'process raft request' (duration: 512.593277ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:10.343288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:52:09.830251Z","time spent":"512.945964ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:52:10.343441Z","caller":"traceutil/trace.go:171","msg":"trace[1380936485] transaction","detail":"{read_only:false; response_revision:25981; number_of_response:1; }","duration":"511.572448ms","start":"2026-07-15T05:52:09.83184Z","end":"2026-07-15T05:52:10.343412Z","steps":["trace[1380936485] 'process raft request' (duration: 511.202951ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:10.343571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:52:09.831813Z","time spent":"511.69677ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:52:10.343648Z","caller":"traceutil/trace.go:171","msg":"trace[1295624588] transaction","detail":"{read_only:false; response_revision:25982; number_of_response:1; }","duration":"511.71046ms","start":"2026-07-15T05:52:09.831925Z","end":"2026-07-15T05:52:10.343636Z","steps":["trace[1295624588] 'process raft request' (duration: 511.408254ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:10.34372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:52:09.831882Z","time spent":"511.800272ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:52:10.34388Z","caller":"traceutil/trace.go:171","msg":"trace[1995078905] transaction","detail":"{read_only:false; response_revision:25983; number_of_response:1; }","duration":"511.407754ms","start":"2026-07-15T05:52:09.832437Z","end":"2026-07-15T05:52:10.343845Z","steps":["trace[1995078905] 'process raft request' (duration: 511.150109ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:52:10.343897Z","caller":"traceutil/trace.go:171","msg":"trace[599324371] linearizableReadLoop","detail":"{readStateIndex:26836; appliedIndex:26831; }","duration":"498.375833ms","start":"2026-07-15T05:52:09.845501Z","end":"2026-07-15T05:52:10.343876Z","steps":["trace[599324371] 'read index received' (duration: 179.89536ms)","trace[599324371] 'applied index is now lower than readState.Index' (duration: 318.479493ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:52:10.343929Z","caller":"traceutil/trace.go:171","msg":"trace[2047584620] transaction","detail":"{read_only:false; response_revision:25984; number_of_response:1; }","duration":"495.197842ms","start":"2026-07-15T05:52:09.848719Z","end":"2026-07-15T05:52:10.343917Z","steps":["trace[2047584620] 'process raft request' (duration: 495.022699ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:52:10.343975Z","caller":"traceutil/trace.go:171","msg":"trace[883697799] transaction","detail":"{read_only:false; response_revision:25985; number_of_response:1; }","duration":"395.664392ms","start":"2026-07-15T05:52:09.948279Z","end":"2026-07-15T05:52:10.343943Z","steps":["trace[883697799] 'process raft request' (duration: 395.54949ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:10.344072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:52:09.832422Z","time spent":"511.529996ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T05:52:10.344105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:52:09.848706Z","time spent":"495.326904ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T05:52:10.344148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:52:09.948236Z","time spent":"395.818855ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T05:52:10.344093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.605387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-15T05:52:10.344253Z","caller":"traceutil/trace.go:171","msg":"trace[1834140814] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25985; }","duration":"498.783361ms","start":"2026-07-15T05:52:09.845426Z","end":"2026-07-15T05:52:10.34421Z","steps":["trace[1834140814] 'agreement among raft nodes before linearized reading' (duration: 498.544116ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:10.344404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:52:09.845412Z","time spent":"498.937223ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-15T05:52:10.367157Z","caller":"traceutil/trace.go:171","msg":"trace[506006473] transaction","detail":"{read_only:false; response_revision:25986; number_of_response:1; }","duration":"146.557547ms","start":"2026-07-15T05:52:10.220563Z","end":"2026-07-15T05:52:10.367121Z","steps":["trace[506006473] 'process raft request' (duration: 146.255591ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:10.367598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.751036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-07-15T05:52:10.367641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.090531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-15T05:52:10.367688Z","caller":"traceutil/trace.go:171","msg":"trace[1383490481] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25987; }","duration":"293.883889ms","start":"2026-07-15T05:52:10.073777Z","end":"2026-07-15T05:52:10.367661Z","steps":["trace[1383490481] 'agreement among raft nodes before linearized reading' (duration: 293.617664ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:10.367686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.352846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-15T05:52:10.36773Z","caller":"traceutil/trace.go:171","msg":"trace[1840860832] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:25987; }","duration":"409.204203ms","start":"2026-07-15T05:52:09.958506Z","end":"2026-07-15T05:52:10.36771Z","steps":["trace[1840860832] 'agreement among raft nodes before linearized reading' (duration: 409.01595ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:10.367775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:52:09.958445Z","time spent":"409.321175ms","remote":"127.0.0.1:59708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":30,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"info","ts":"2026-07-15T05:52:10.367775Z","caller":"traceutil/trace.go:171","msg":"trace[686075115] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25987; }","duration":"368.499509ms","start":"2026-07-15T05:52:09.999249Z","end":"2026-07-15T05:52:10.367749Z","steps":["trace[686075115] 'agreement among raft nodes before linearized reading' (duration: 368.197643ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:10.367798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.301279ms","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:6"} {"level":"warn","ts":"2026-07-15T05:52:10.367854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.988357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-15T05:52:10.367907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.81218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-07-15T05:52:10.3681Z","caller":"traceutil/trace.go:171","msg":"trace[232816770] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25987; }","duration":"461.414511ms","start":"2026-07-15T05:52:09.90667Z","end":"2026-07-15T05:52:10.368084Z","steps":["trace[232816770] 'agreement among raft nodes before linearized reading' (duration: 460.676127ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:10.368145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:52:09.906649Z","time spent":"461.483142ms","remote":"127.0.0.1:59490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-07-15T05:52:10.36795Z","caller":"traceutil/trace.go:171","msg":"trace[362664288] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25987; }","duration":"259.109709ms","start":"2026-07-15T05:52:10.108817Z","end":"2026-07-15T05:52:10.367927Z","steps":["trace[362664288] 'agreement among raft nodes before linearized reading' (duration: 258.976957ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:10.367824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:52:09.999178Z","time spent":"368.634411ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-15T05:52:10.367934Z","caller":"traceutil/trace.go:171","msg":"trace[295982514] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:25987; }","duration":"180.459162ms","start":"2026-07-15T05:52:10.187453Z","end":"2026-07-15T05:52:10.367912Z","steps":["trace[295982514] 'agreement among raft nodes before linearized reading' (duration: 180.252268ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:10.646861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.45188ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T05:52:10.647003Z","caller":"traceutil/trace.go:171","msg":"trace[1557625317] linearizableReadLoop","detail":"{readStateIndex:26841; appliedIndex:26840; }","duration":"277.008373ms","start":"2026-07-15T05:52:10.369972Z","end":"2026-07-15T05:52:10.646981Z","steps":["trace[1557625317] 'read index received' (duration: 123.35372ms)","trace[1557625317] 'applied index is now lower than readState.Index' (duration: 153.652923ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:52:10.647127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.145815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:52:10.647175Z","caller":"traceutil/trace.go:171","msg":"trace[306472165] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25988; }","duration":"277.219577ms","start":"2026-07-15T05:52:10.369936Z","end":"2026-07-15T05:52:10.647156Z","steps":["trace[306472165] 'agreement among raft nodes before linearized reading' (duration: 277.138136ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:52:10.647407Z","caller":"traceutil/trace.go:171","msg":"trace[623812653] transaction","detail":"{read_only:false; response_revision:25988; number_of_response:1; }","duration":"297.680652ms","start":"2026-07-15T05:52:10.349364Z","end":"2026-07-15T05:52:10.647044Z","steps":["trace[623812653] 'process raft request' (duration: 143.944296ms)","trace[623812653] 'compare' (duration: 153.304698ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:52:10.647791Z","caller":"traceutil/trace.go:171","msg":"trace[319261358] transaction","detail":"{read_only:false; response_revision:25989; number_of_response:1; }","duration":"272.743791ms","start":"2026-07-15T05:52:10.375022Z","end":"2026-07-15T05:52:10.647766Z","steps":["trace[319261358] 'process raft request' (duration: 272.580408ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:10.648142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.26856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.156\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-07-15T05:52:10.648176Z","caller":"traceutil/trace.go:171","msg":"trace[439903748] range","detail":"{range_begin:/registry/masterleases/199.204.45.156; range_end:; response_count:1; response_revision:25990; }","duration":"134.366232ms","start":"2026-07-15T05:52:10.513799Z","end":"2026-07-15T05:52:10.648165Z","steps":["trace[439903748] 'agreement among raft nodes before linearized reading' (duration: 134.161178ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:10.648194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.728936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-messaging-topology-operator-6f465c979f-9nfkm\" ","response":"range_response_count:1 size:5931"} {"level":"info","ts":"2026-07-15T05:52:10.648249Z","caller":"traceutil/trace.go:171","msg":"trace[1376776303] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-messaging-topology-operator-6f465c979f-9nfkm; range_end:; response_count:1; response_revision:25990; }","duration":"277.846398ms","start":"2026-07-15T05:52:10.370384Z","end":"2026-07-15T05:52:10.64823Z","steps":["trace[1376776303] 'agreement among raft nodes before linearized reading' (duration: 277.529662ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:10.648239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.22524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-07-15T05:52:10.648319Z","caller":"traceutil/trace.go:171","msg":"trace[170398554] transaction","detail":"{read_only:false; response_revision:25990; number_of_response:1; }","duration":"272.354713ms","start":"2026-07-15T05:52:10.375954Z","end":"2026-07-15T05:52:10.648309Z","steps":["trace[170398554] 'process raft request' (duration: 271.768462ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:52:10.648319Z","caller":"traceutil/trace.go:171","msg":"trace[64450506] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25990; }","duration":"273.340062ms","start":"2026-07-15T05:52:10.37496Z","end":"2026-07-15T05:52:10.6483Z","steps":["trace[64450506] 'agreement among raft nodes before linearized reading' (duration: 273.001575ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:10.648321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.408244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:52:10.648372Z","caller":"traceutil/trace.go:171","msg":"trace[1862033462] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:25990; }","duration":"273.481975ms","start":"2026-07-15T05:52:10.374875Z","end":"2026-07-15T05:52:10.648357Z","steps":["trace[1862033462] 'agreement among raft nodes before linearized reading' (duration: 273.396183ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:10.947521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.97332ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T05:52:10.947795Z","caller":"traceutil/trace.go:171","msg":"trace[30118204] linearizableReadLoop","detail":"{readStateIndex:26846; appliedIndex:26844; }","duration":"282.336745ms","start":"2026-07-15T05:52:10.665403Z","end":"2026-07-15T05:52:10.947739Z","steps":["trace[30118204] 'read index received' (duration: 142.08298ms)","trace[30118204] 'applied index is now lower than readState.Index' (duration: 140.252035ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:52:10.947796Z","caller":"traceutil/trace.go:171","msg":"trace[1302677510] transaction","detail":"{read_only:false; response_revision:25991; number_of_response:1; }","duration":"286.857582ms","start":"2026-07-15T05:52:10.660903Z","end":"2026-07-15T05:52:10.94776Z","steps":["trace[1302677510] 'process raft request' (duration: 146.568337ms)","trace[1302677510] 'compare' (duration: 139.669724ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:52:10.947887Z","caller":"traceutil/trace.go:171","msg":"trace[1782946070] transaction","detail":"{read_only:false; response_revision:25992; number_of_response:1; }","duration":"283.238581ms","start":"2026-07-15T05:52:10.664637Z","end":"2026-07-15T05:52:10.947875Z","steps":["trace[1782946070] 'process raft request' (duration: 283.001867ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:10.948131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.702052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-15T05:52:10.948151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.97613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:52:10.948209Z","caller":"traceutil/trace.go:171","msg":"trace[1484274921] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25992; }","duration":"282.808883ms","start":"2026-07-15T05:52:10.66538Z","end":"2026-07-15T05:52:10.948188Z","steps":["trace[1484274921] 'agreement among raft nodes before linearized reading' (duration: 282.673971ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:10.948188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.499425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:52:10.948228Z","caller":"traceutil/trace.go:171","msg":"trace[727574488] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:25992; }","duration":"265.111963ms","start":"2026-07-15T05:52:10.683092Z","end":"2026-07-15T05:52:10.948204Z","steps":["trace[727574488] 'agreement among raft nodes before linearized reading' (duration: 264.97482ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:52:10.948295Z","caller":"traceutil/trace.go:171","msg":"trace[1559264804] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25992; }","duration":"188.645567ms","start":"2026-07-15T05:52:10.759621Z","end":"2026-07-15T05:52:10.948267Z","steps":["trace[1559264804] 'agreement among raft nodes before linearized reading' (duration: 188.460263ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:11.178784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.473732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-15T05:52:11.178881Z","caller":"traceutil/trace.go:171","msg":"trace[1256629820] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:25992; }","duration":"121.623095ms","start":"2026-07-15T05:52:11.057235Z","end":"2026-07-15T05:52:11.178858Z","steps":["trace[1256629820] 'count revisions from in-memory index tree' (duration: 121.379491ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:52:11.731092Z","caller":"traceutil/trace.go:171","msg":"trace[389729168] transaction","detail":"{read_only:false; response_revision:25994; number_of_response:1; }","duration":"124.020772ms","start":"2026-07-15T05:52:11.607048Z","end":"2026-07-15T05:52:11.731069Z","steps":["trace[389729168] 'process raft request' (duration: 123.895169ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:52:11.931682Z","caller":"traceutil/trace.go:171","msg":"trace[1259200274] transaction","detail":"{read_only:false; response_revision:25995; number_of_response:1; }","duration":"196.152022ms","start":"2026-07-15T05:52:11.735498Z","end":"2026-07-15T05:52:11.93165Z","steps":["trace[1259200274] 'process raft request' (duration: 195.201534ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:12.237478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.788366ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T05:52:12.237579Z","caller":"traceutil/trace.go:171","msg":"trace[143391039] linearizableReadLoop","detail":"{readStateIndex:26850; appliedIndex:26849; }","duration":"128.476748ms","start":"2026-07-15T05:52:12.109086Z","end":"2026-07-15T05:52:12.237563Z","steps":["trace[143391039] 'read index received' (duration: 47.051µs)","trace[143391039] 'applied index is now lower than readState.Index' (duration: 128.428377ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:52:12.237642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.5637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:52:12.237666Z","caller":"traceutil/trace.go:171","msg":"trace[1294364811] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25996; }","duration":"128.615151ms","start":"2026-07-15T05:52:12.109042Z","end":"2026-07-15T05:52:12.237657Z","steps":["trace[1294364811] 'agreement among raft nodes before linearized reading' (duration: 128.5668ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:52:12.237815Z","caller":"traceutil/trace.go:171","msg":"trace[840553850] transaction","detail":"{read_only:false; response_revision:25996; number_of_response:1; }","duration":"206.87944ms","start":"2026-07-15T05:52:12.030802Z","end":"2026-07-15T05:52:12.237682Z","steps":["trace[840553850] 'process raft request' (duration: 67.810629ms)","trace[840553850] 'compare' (duration: 138.426359ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:52:12.669547Z","caller":"traceutil/trace.go:171","msg":"trace[1086631340] transaction","detail":"{read_only:false; response_revision:25998; number_of_response:1; }","duration":"319.679386ms","start":"2026-07-15T05:52:12.349841Z","end":"2026-07-15T05:52:12.66952Z","steps":["trace[1086631340] 'process raft request' (duration: 223.318598ms)","trace[1086631340] 'compare' (duration: 96.134354ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:52:12.669732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:52:12.349817Z","time spent":"319.81717ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:52:12.669923Z","caller":"traceutil/trace.go:171","msg":"trace[1691211707] transaction","detail":"{read_only:false; response_revision:25999; number_of_response:1; }","duration":"319.948182ms","start":"2026-07-15T05:52:12.349954Z","end":"2026-07-15T05:52:12.669902Z","steps":["trace[1691211707] 'process raft request' (duration: 319.513493ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:12.669999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:52:12.349946Z","time spent":"320.009523ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:52:12.670125Z","caller":"traceutil/trace.go:171","msg":"trace[358845037] transaction","detail":"{read_only:false; response_revision:26002; number_of_response:1; }","duration":"274.801402ms","start":"2026-07-15T05:52:12.395306Z","end":"2026-07-15T05:52:12.670108Z","steps":["trace[358845037] 'process raft request' (duration: 274.631777ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:52:12.670152Z","caller":"traceutil/trace.go:171","msg":"trace[1924601848] transaction","detail":"{read_only:false; response_revision:26000; number_of_response:1; }","duration":"319.80336ms","start":"2026-07-15T05:52:12.350334Z","end":"2026-07-15T05:52:12.670137Z","steps":["trace[1924601848] 'process raft request' (duration: 319.378001ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:52:12.670223Z","caller":"traceutil/trace.go:171","msg":"trace[1023345474] transaction","detail":"{read_only:false; response_revision:26001; number_of_response:1; }","duration":"295.888358ms","start":"2026-07-15T05:52:12.374326Z","end":"2026-07-15T05:52:12.670214Z","steps":["trace[1023345474] 'process raft request' (duration: 295.54678ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:12.670216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:52:12.350304Z","time spent":"319.872661ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T05:52:25.494227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.609787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:52:25.494315Z","caller":"traceutil/trace.go:171","msg":"trace[723577003] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26092; }","duration":"386.734099ms","start":"2026-07-15T05:52:25.107561Z","end":"2026-07-15T05:52:25.494295Z","steps":["trace[723577003] 'range keys from in-memory index tree' (duration: 386.545536ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:25.494358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:52:25.107545Z","time spent":"386.802191ms","remote":"127.0.0.1:59294","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-07-15T05:52:25.494381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.98512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:52:25.494454Z","caller":"traceutil/trace.go:171","msg":"trace[988719701] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:26092; }","duration":"238.072982ms","start":"2026-07-15T05:52:25.256364Z","end":"2026-07-15T05:52:25.494437Z","steps":["trace[988719701] 'count revisions from in-memory index tree' (duration: 237.866118ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:25.49502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.487387ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T05:52:25.495089Z","caller":"traceutil/trace.go:171","msg":"trace[1755545326] linearizableReadLoop","detail":"{readStateIndex:26949; appliedIndex:26948; }","duration":"141.161284ms","start":"2026-07-15T05:52:25.353917Z","end":"2026-07-15T05:52:25.495078Z","steps":["trace[1755545326] 'read index received' (duration: 108.182µs)","trace[1755545326] 'applied index is now lower than readState.Index' (duration: 141.051742ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:52:25.495149Z","caller":"traceutil/trace.go:171","msg":"trace[569301096] transaction","detail":"{read_only:false; response_revision:26093; number_of_response:1; }","duration":"186.048419ms","start":"2026-07-15T05:52:25.30908Z","end":"2026-07-15T05:52:25.495128Z","steps":["trace[569301096] 'process raft request' (duration: 13.192535ms)","trace[569301096] 'compare' (duration: 172.073428ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:52:25.495176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.251595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:52:25.495206Z","caller":"traceutil/trace.go:171","msg":"trace[2048850999] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:26093; }","duration":"141.313916ms","start":"2026-07-15T05:52:25.353881Z","end":"2026-07-15T05:52:25.495195Z","steps":["trace[2048850999] 'agreement among raft nodes before linearized reading' (duration: 141.257435ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:26.102904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.093966ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T05:52:26.103208Z","caller":"traceutil/trace.go:171","msg":"trace[950402709] transaction","detail":"{read_only:false; response_revision:26096; number_of_response:1; }","duration":"320.531573ms","start":"2026-07-15T05:52:25.782656Z","end":"2026-07-15T05:52:26.103188Z","steps":["trace[950402709] 'process raft request' (duration: 320.458002ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:52:26.103205Z","caller":"traceutil/trace.go:171","msg":"trace[954201712] transaction","detail":"{read_only:false; response_revision:26094; number_of_response:1; }","duration":"739.432053ms","start":"2026-07-15T05:52:25.363677Z","end":"2026-07-15T05:52:26.103109Z","steps":["trace[954201712] 'process raft request' (duration: 267.040931ms)","trace[954201712] 'compare' (duration: 471.466113ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:52:26.103277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:52:25.782632Z","time spent":"320.610684ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:52:26.103253Z","caller":"traceutil/trace.go:171","msg":"trace[1641343356] transaction","detail":"{read_only:false; response_revision:26095; number_of_response:1; }","duration":"722.027337ms","start":"2026-07-15T05:52:25.381197Z","end":"2026-07-15T05:52:26.103224Z","steps":["trace[1641343356] 'process raft request' (duration: 721.817053ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:26.103399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:52:25.363646Z","time spent":"739.654106ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T05:52:26.103421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:52:25.381164Z","time spent":"722.18108ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:52:26.103427Z","caller":"traceutil/trace.go:171","msg":"trace[267986569] linearizableReadLoop","detail":"{readStateIndex:26951; appliedIndex:26949; }","duration":"608.285003ms","start":"2026-07-15T05:52:25.495114Z","end":"2026-07-15T05:52:26.103399Z","steps":["trace[267986569] 'read index received' (duration: 135.619176ms)","trace[267986569] 'applied index is now lower than readState.Index' (duration: 472.662707ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:52:26.202718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"801.327546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-15T05:52:26.202737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.687553ms","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:6"} {"level":"warn","ts":"2026-07-15T05:52:26.202778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"705.176992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:52:26.202914Z","caller":"traceutil/trace.go:171","msg":"trace[1385600963] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26096; }","duration":"705.345844ms","start":"2026-07-15T05:52:25.497554Z","end":"2026-07-15T05:52:26.2029Z","steps":["trace[1385600963] 'agreement among raft nodes before linearized reading' (duration: 705.201741ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:26.202958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:52:25.497534Z","time spent":"705.412196ms","remote":"127.0.0.1:59296","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-07-15T05:52:26.202794Z","caller":"traceutil/trace.go:171","msg":"trace[447916269] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:26096; }","duration":"801.393037ms","start":"2026-07-15T05:52:25.401372Z","end":"2026-07-15T05:52:26.202765Z","steps":["trace[447916269] 'agreement among raft nodes before linearized reading' (duration: 702.155164ms)","trace[447916269] 'count revisions from in-memory index tree' (duration: 99.133172ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:52:26.203026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:52:25.40132Z","time spent":"801.694363ms","remote":"127.0.0.1:51350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":0,"response size":28,"request content":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true "} {"level":"warn","ts":"2026-07-15T05:52:26.20304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.609279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"warn","ts":"2026-07-15T05:52:26.202713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.055375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:52:26.203088Z","caller":"traceutil/trace.go:171","msg":"trace[1908779673] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:26096; }","duration":"116.70988ms","start":"2026-07-15T05:52:26.086366Z","end":"2026-07-15T05:52:26.203076Z","steps":["trace[1908779673] 'agreement among raft nodes before linearized reading' (duration: 116.509526ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:52:26.203098Z","caller":"traceutil/trace.go:171","msg":"trace[1833831685] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:0; response_revision:26096; }","duration":"557.494484ms","start":"2026-07-15T05:52:25.645595Z","end":"2026-07-15T05:52:26.203089Z","steps":["trace[1833831685] 'agreement among raft nodes before linearized reading' (duration: 557.043215ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:26.203209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:52:25.645577Z","time spent":"557.602715ms","remote":"127.0.0.1:34632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-07-15T05:52:26.202861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.640526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/applicationcredentials/\" range_end:\"/registry/openstack.k-orc.cloud/applicationcredentials0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:52:26.203375Z","caller":"traceutil/trace.go:171","msg":"trace[345591544] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/applicationcredentials/; range_end:/registry/openstack.k-orc.cloud/applicationcredentials0; response_count:0; response_revision:26096; }","duration":"108.138965ms","start":"2026-07-15T05:52:26.095212Z","end":"2026-07-15T05:52:26.203351Z","steps":["trace[345591544] 'agreement among raft nodes before linearized reading' (duration: 107.620566ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:52:26.202807Z","caller":"traceutil/trace.go:171","msg":"trace[2074281098] 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:26096; }","duration":"379.814456ms","start":"2026-07-15T05:52:25.822976Z","end":"2026-07-15T05:52:26.202791Z","steps":["trace[2074281098] 'agreement among raft nodes before linearized reading' (duration: 379.658363ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:26.203483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:52:25.822962Z","time spent":"380.50439ms","remote":"127.0.0.1:38014","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-07-15T05:52:26.785242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.192095ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:18"} {"level":"info","ts":"2026-07-15T05:52:26.785382Z","caller":"traceutil/trace.go:171","msg":"trace[2067311416] linearizableReadLoop","detail":"{readStateIndex:26955; appliedIndex:26954; }","duration":"569.192448ms","start":"2026-07-15T05:52:26.216167Z","end":"2026-07-15T05:52:26.78536Z","steps":["trace[2067311416] 'read index received' (duration: 429.755909ms)","trace[2067311416] 'applied index is now lower than readState.Index' (duration: 139.435069ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:52:26.785406Z","caller":"traceutil/trace.go:171","msg":"trace[730869038] transaction","detail":"{read_only:false; response_revision:26098; number_of_response:1; }","duration":"572.001953ms","start":"2026-07-15T05:52:26.213372Z","end":"2026-07-15T05:52:26.785374Z","steps":["trace[730869038] 'process raft request' (duration: 432.583744ms)","trace[730869038] 'compare' (duration: 138.302618ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:52:26.785583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:52:26.213353Z","time spent":"572.126865ms","remote":"127.0.0.1:34632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1633,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-15T05:52:26.785856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.670907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-07-15T05:52:26.785937Z","caller":"traceutil/trace.go:171","msg":"trace[1506152792] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26098; }","duration":"569.78426ms","start":"2026-07-15T05:52:26.216139Z","end":"2026-07-15T05:52:26.785923Z","steps":["trace[1506152792] 'agreement among raft nodes before linearized reading' (duration: 569.318141ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:26.785981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:52:26.216126Z","time spent":"569.844461ms","remote":"127.0.0.1:59490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-07-15T05:52:26.786521Z","caller":"traceutil/trace.go:171","msg":"trace[563826355] transaction","detail":"{read_only:false; response_revision:26099; number_of_response:1; }","duration":"493.240503ms","start":"2026-07-15T05:52:26.293264Z","end":"2026-07-15T05:52:26.786505Z","steps":["trace[563826355] 'process raft request' (duration: 493.023779ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:52:26.786526Z","caller":"traceutil/trace.go:171","msg":"trace[787308385] transaction","detail":"{read_only:false; response_revision:26100; number_of_response:1; }","duration":"338.356926ms","start":"2026-07-15T05:52:26.448149Z","end":"2026-07-15T05:52:26.786506Z","steps":["trace[787308385] 'process raft request' (duration: 338.307675ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:26.786617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:52:26.293238Z","time spent":"493.331505ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T05:52:26.786632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:52:26.448119Z","time spent":"338.457349ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:52:27.370547Z","caller":"traceutil/trace.go:171","msg":"trace[1961857063] transaction","detail":"{read_only:false; response_revision:26101; number_of_response:1; }","duration":"639.433603ms","start":"2026-07-15T05:52:26.731081Z","end":"2026-07-15T05:52:27.370515Z","steps":["trace[1961857063] 'process raft request' (duration: 602.253736ms)","trace[1961857063] 'compare' (duration: 36.745658ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:52:27.370554Z","caller":"traceutil/trace.go:171","msg":"trace[1938991436] transaction","detail":"{read_only:false; response_revision:26102; number_of_response:1; }","duration":"637.068047ms","start":"2026-07-15T05:52:26.733459Z","end":"2026-07-15T05:52:27.370528Z","steps":["trace[1938991436] 'process raft request' (duration: 636.924754ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:27.370694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:52:26.731055Z","time spent":"639.564185ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T05:52:27.370722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:52:26.733445Z","time spent":"637.181039ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:52:27.370757Z","caller":"traceutil/trace.go:171","msg":"trace[1374645424] linearizableReadLoop","detail":"{readStateIndex:26961; appliedIndex:26955; }","duration":"585.328769ms","start":"2026-07-15T05:52:26.78542Z","end":"2026-07-15T05:52:27.370749Z","steps":["trace[1374645424] 'read index received' (duration: 912.778µs)","trace[1374645424] 'applied index is now lower than readState.Index' (duration: 584.414991ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:52:27.370807Z","caller":"traceutil/trace.go:171","msg":"trace[1108375307] transaction","detail":"{read_only:false; response_revision:26104; number_of_response:1; }","duration":"636.532397ms","start":"2026-07-15T05:52:26.734254Z","end":"2026-07-15T05:52:27.370786Z","steps":["trace[1108375307] 'process raft request' (duration: 636.361714ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:52:27.3707Z","caller":"traceutil/trace.go:171","msg":"trace[5137715] transaction","detail":"{read_only:false; response_revision:26103; number_of_response:1; }","duration":"636.495456ms","start":"2026-07-15T05:52:26.73419Z","end":"2026-07-15T05:52:27.370686Z","steps":["trace[5137715] 'process raft request' (duration: 636.292712ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:27.370887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.207573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-07-15T05:52:27.370877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.388091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-15T05:52:27.370909Z","caller":"traceutil/trace.go:171","msg":"trace[1505663916] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26104; }","duration":"633.259114ms","start":"2026-07-15T05:52:26.737643Z","end":"2026-07-15T05:52:27.370902Z","steps":["trace[1505663916] 'agreement among raft nodes before linearized reading' (duration: 633.161492ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:27.370897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:52:26.73424Z","time spent":"636.609688ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T05:52:27.37093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:52:26.73763Z","time spent":"633.296254ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-15T05:52:27.370928Z","caller":"traceutil/trace.go:171","msg":"trace[23994893] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26104; }","duration":"626.464332ms","start":"2026-07-15T05:52:26.74445Z","end":"2026-07-15T05:52:27.370915Z","steps":["trace[23994893] 'agreement among raft nodes before linearized reading' (duration: 626.32221ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:27.370962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:52:26.744439Z","time spent":"626.509023ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-15T05:52:27.370941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"791.910713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-15T05:52:27.370969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.512754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-15T05:52:27.371006Z","caller":"traceutil/trace.go:171","msg":"trace[1461789111] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26104; }","duration":"625.582076ms","start":"2026-07-15T05:52:26.745413Z","end":"2026-07-15T05:52:27.370995Z","steps":["trace[1461789111] 'agreement among raft nodes before linearized reading' (duration: 625.449813ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:27.371018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.11468618s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" ","response":"range_response_count:1 size:1852"} {"level":"info","ts":"2026-07-15T05:52:27.37101Z","caller":"traceutil/trace.go:171","msg":"trace[133103148] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:26104; }","duration":"792.016896ms","start":"2026-07-15T05:52:26.57897Z","end":"2026-07-15T05:52:27.370986Z","steps":["trace[133103148] 'agreement among raft nodes before linearized reading' (duration: 791.913074ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:52:27.371038Z","caller":"traceutil/trace.go:171","msg":"trace[1319408755] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0; range_end:; response_count:1; response_revision:26104; }","duration":"1.11472821s","start":"2026-07-15T05:52:26.256301Z","end":"2026-07-15T05:52:27.37103Z","steps":["trace[1319408755] 'agreement among raft nodes before linearized reading' (duration: 1.114673349s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:27.371034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:52:26.745396Z","time spent":"625.631706ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-15T05:52:27.371058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:52:26.256288Z","time spent":"1.114765842s","remote":"127.0.0.1:59466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":79,"response count":1,"response size":1874,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" "} {"level":"warn","ts":"2026-07-15T05:52:27.371057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:52:26.578949Z","time spent":"792.094337ms","remote":"127.0.0.1:37960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-07-15T05:52:27.37103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:52:26.734179Z","time spent":"636.74116ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T05:52:27.371186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"713.300367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-15T05:52:27.371227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.056413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:52:27.371238Z","caller":"traceutil/trace.go:171","msg":"trace[76671758] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26104; }","duration":"263.066893ms","start":"2026-07-15T05:52:27.108167Z","end":"2026-07-15T05:52:27.371234Z","steps":["trace[76671758] 'agreement among raft nodes before linearized reading' (duration: 263.045083ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:27.37126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.343758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/routerinterfaces/\" range_end:\"/registry/openstack.k-orc.cloud/routerinterfaces0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-15T05:52:27.372005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.63852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:1651"} {"level":"info","ts":"2026-07-15T05:52:27.372028Z","caller":"traceutil/trace.go:171","msg":"trace[1650560911] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/routerinterfaces/; range_end:/registry/openstack.k-orc.cloud/routerinterfaces0; response_count:0; response_revision:26104; }","duration":"475.072913ms","start":"2026-07-15T05:52:26.896886Z","end":"2026-07-15T05:52:27.371959Z","steps":["trace[1650560911] 'agreement among raft nodes before linearized reading' (duration: 474.319038ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:52:27.372112Z","caller":"traceutil/trace.go:171","msg":"trace[1254868962] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:26104; }","duration":"579.745652ms","start":"2026-07-15T05:52:26.792311Z","end":"2026-07-15T05:52:27.372057Z","steps":["trace[1254868962] 'agreement among raft nodes before linearized reading' (duration: 578.908425ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:27.372147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:52:26.896866Z","time spent":"475.221895ms","remote":"127.0.0.1:45310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/openstack.k-orc.cloud/routerinterfaces/\" range_end:\"/registry/openstack.k-orc.cloud/routerinterfaces0\" count_only:true "} {"level":"warn","ts":"2026-07-15T05:52:27.37217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:52:26.792306Z","time spent":"579.848924ms","remote":"127.0.0.1:34632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":1673,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-07-15T05:52:27.372484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.966163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18435"} {"level":"info","ts":"2026-07-15T05:52:27.372527Z","caller":"traceutil/trace.go:171","msg":"trace[1917518211] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:26104; }","duration":"585.056805ms","start":"2026-07-15T05:52:26.787459Z","end":"2026-07-15T05:52:27.372516Z","steps":["trace[1917518211] 'agreement among raft nodes before linearized reading' (duration: 583.697848ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:27.372555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:52:26.787442Z","time spent":"585.108395ms","remote":"127.0.0.1:59478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18457,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-07-15T05:52:27.373284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.061156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-07-15T05:52:27.373342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.132828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:1651"} {"level":"info","ts":"2026-07-15T05:52:27.373344Z","caller":"traceutil/trace.go:171","msg":"trace[1218087622] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26104; }","duration":"329.163628ms","start":"2026-07-15T05:52:27.044164Z","end":"2026-07-15T05:52:27.373328Z","steps":["trace[1218087622] 'agreement among raft nodes before linearized reading' (duration: 327.010716ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:52:27.373365Z","caller":"traceutil/trace.go:171","msg":"trace[1191032082] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:26104; }","duration":"713.323578ms","start":"2026-07-15T05:52:26.657877Z","end":"2026-07-15T05:52:27.371201Z","steps":["trace[1191032082] 'agreement among raft nodes before linearized reading' (duration: 713.284177ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:27.373412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:52:27.044144Z","time spent":"329.258099ms","remote":"127.0.0.1:59592","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-07-15T05:52:27.373439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:52:26.657856Z","time spent":"715.560571ms","remote":"127.0.0.1:48436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-07-15T05:52:27.37348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.537287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-15T05:52:27.37352Z","caller":"traceutil/trace.go:171","msg":"trace[753572458] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26104; }","duration":"636.609158ms","start":"2026-07-15T05:52:26.736902Z","end":"2026-07-15T05:52:27.373512Z","steps":["trace[753572458] 'agreement among raft nodes before linearized reading' (duration: 634.252833ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:27.373538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:52:26.736893Z","time spent":"636.640399ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-15T05:52:27.373473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.206037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:52:27.37697Z","caller":"traceutil/trace.go:171","msg":"trace[954364541] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:26104; }","duration":"338.358875ms","start":"2026-07-15T05:52:27.03856Z","end":"2026-07-15T05:52:27.376919Z","steps":["trace[954364541] 'agreement among raft nodes before linearized reading' (duration: 332.673415ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:27.377031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:52:27.038543Z","time spent":"338.464707ms","remote":"127.0.0.1:48488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":28,"request content":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true "} {"level":"info","ts":"2026-07-15T05:52:27.377941Z","caller":"traceutil/trace.go:171","msg":"trace[305502764] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:26104; }","duration":"585.747158ms","start":"2026-07-15T05:52:26.792154Z","end":"2026-07-15T05:52:27.377901Z","steps":["trace[305502764] 'agreement among raft nodes before linearized reading' (duration: 579.955876ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:27.378013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:52:26.792139Z","time spent":"585.850979ms","remote":"127.0.0.1:34632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":1673,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-07-15T05:52:27.922384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.655288ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T05:52:27.922521Z","caller":"traceutil/trace.go:171","msg":"trace[383275665] transaction","detail":"{read_only:false; response_revision:26105; number_of_response:1; }","duration":"542.227597ms","start":"2026-07-15T05:52:27.380269Z","end":"2026-07-15T05:52:27.922497Z","steps":["trace[383275665] 'process raft request' (duration: 267.370276ms)","trace[383275665] 'compare' (duration: 274.525545ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:52:27.922608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:52:27.380243Z","time spent":"542.32101ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:52:27.92539Z","caller":"traceutil/trace.go:171","msg":"trace[720188244] transaction","detail":"{read_only:false; response_revision:26108; number_of_response:1; }","duration":"544.444091ms","start":"2026-07-15T05:52:27.380927Z","end":"2026-07-15T05:52:27.925371Z","steps":["trace[720188244] 'process raft request' (duration: 544.3808ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:52:27.925416Z","caller":"traceutil/trace.go:171","msg":"trace[1149440875] transaction","detail":"{read_only:false; response_revision:26106; number_of_response:1; }","duration":"544.543653ms","start":"2026-07-15T05:52:27.380841Z","end":"2026-07-15T05:52:27.925385Z","steps":["trace[1149440875] 'process raft request' (duration: 544.316929ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:27.925491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:52:27.380917Z","time spent":"544.525992ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:52:27.925471Z","caller":"traceutil/trace.go:171","msg":"trace[125956588] transaction","detail":"{read_only:false; response_revision:26107; number_of_response:1; }","duration":"544.640495ms","start":"2026-07-15T05:52:27.380781Z","end":"2026-07-15T05:52:27.925422Z","steps":["trace[125956588] 'process raft request' (duration: 544.487782ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:27.92561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:52:27.380836Z","time spent":"544.648635ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T05:52:27.925667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:52:27.380772Z","time spent":"544.811868ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:52:27.925753Z","caller":"traceutil/trace.go:171","msg":"trace[1830379547] transaction","detail":"{read_only:false; response_revision:26109; number_of_response:1; }","duration":"544.652735ms","start":"2026-07-15T05:52:27.381082Z","end":"2026-07-15T05:52:27.925735Z","steps":["trace[1830379547] 'process raft request' (duration: 544.256647ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:27.925852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:52:27.381074Z","time spent":"544.730596ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:52:27.925849Z","caller":"traceutil/trace.go:171","msg":"trace[267663401] transaction","detail":"{read_only:false; response_revision:26110; number_of_response:1; }","duration":"541.537185ms","start":"2026-07-15T05:52:27.384293Z","end":"2026-07-15T05:52:27.92583Z","steps":["trace[267663401] 'process raft request' (duration: 541.233899ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:27.925943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:52:27.384277Z","time spent":"541.623686ms","remote":"127.0.0.1:34632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1946,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:52:27.925986Z","caller":"traceutil/trace.go:171","msg":"trace[761704827] linearizableReadLoop","detail":"{readStateIndex:26967; appliedIndex:26961; }","duration":"497.526095ms","start":"2026-07-15T05:52:27.428451Z","end":"2026-07-15T05:52:27.925977Z","steps":["trace[761704827] 'read index received' (duration: 219.204197ms)","trace[761704827] 'applied index is now lower than readState.Index' (duration: 278.321158ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:52:27.926078Z","caller":"traceutil/trace.go:171","msg":"trace[865647430] transaction","detail":"{read_only:false; response_revision:26111; number_of_response:1; }","duration":"425.364244ms","start":"2026-07-15T05:52:27.500702Z","end":"2026-07-15T05:52:27.926066Z","steps":["trace[865647430] 'process raft request' (duration: 425.083529ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:27.926121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.565812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:52:27.926181Z","caller":"traceutil/trace.go:171","msg":"trace[1807908907] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:26111; }","duration":"391.626553ms","start":"2026-07-15T05:52:27.53454Z","end":"2026-07-15T05:52:27.926166Z","steps":["trace[1807908907] 'agreement among raft nodes before linearized reading' (duration: 391.535351ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:27.926223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:52:27.534523Z","time spent":"391.691055ms","remote":"127.0.0.1:51244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":128,"response count":0,"response size":28,"request content":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true "} {"level":"warn","ts":"2026-07-15T05:52:27.92614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:52:27.50068Z","time spent":"425.430625ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T05:52:27.926319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.164748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-15T05:52:27.926251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.808982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:52:27.926388Z","caller":"traceutil/trace.go:171","msg":"trace[85342115] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26111; }","duration":"497.973445ms","start":"2026-07-15T05:52:27.428401Z","end":"2026-07-15T05:52:27.926374Z","steps":["trace[85342115] 'agreement among raft nodes before linearized reading' (duration: 497.816732ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:52:27.926388Z","caller":"traceutil/trace.go:171","msg":"trace[72981829] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:26111; }","duration":"204.233789ms","start":"2026-07-15T05:52:27.722135Z","end":"2026-07-15T05:52:27.926369Z","steps":["trace[72981829] 'agreement among raft nodes before linearized reading' (duration: 204.136837ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:27.926425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:52:27.428381Z","time spent":"498.035087ms","remote":"127.0.0.1:59764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-07-15T05:52:28.547754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.595443ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:52:28.547893Z","caller":"traceutil/trace.go:171","msg":"trace[734514047] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:26112; }","duration":"362.754016ms","start":"2026-07-15T05:52:28.185115Z","end":"2026-07-15T05:52:28.547869Z","steps":["trace[734514047] 'range keys from in-memory index tree' (duration: 362.574572ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:28.548034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.622518ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:18"} {"level":"info","ts":"2026-07-15T05:52:28.548143Z","caller":"traceutil/trace.go:171","msg":"trace[1282355368] linearizableReadLoop","detail":"{readStateIndex:26970; appliedIndex:26969; }","duration":"593.695261ms","start":"2026-07-15T05:52:27.954428Z","end":"2026-07-15T05:52:28.548123Z","steps":["trace[1282355368] 'read index received' (duration: 80.870759ms)","trace[1282355368] 'applied index is now lower than readState.Index' (duration: 512.822432ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:52:28.548168Z","caller":"traceutil/trace.go:171","msg":"trace[1798743756] transaction","detail":"{read_only:false; response_revision:26113; number_of_response:1; }","duration":"602.865758ms","start":"2026-07-15T05:52:27.945263Z","end":"2026-07-15T05:52:28.548129Z","steps":["trace[1798743756] 'process raft request' (duration: 90.047716ms)","trace[1798743756] 'compare' (duration: 512.19502ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:52:28.548311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:52:27.945246Z","time spent":"602.993461ms","remote":"127.0.0.1:59502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1240,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-15T05:52:28.548405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.962156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-07-15T05:52:28.548493Z","caller":"traceutil/trace.go:171","msg":"trace[1970137037] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:26113; }","duration":"594.057428ms","start":"2026-07-15T05:52:27.954399Z","end":"2026-07-15T05:52:28.548457Z","steps":["trace[1970137037] 'agreement among raft nodes before linearized reading' (duration: 593.811813ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:28.548551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:52:27.954386Z","time spent":"594.15109ms","remote":"127.0.0.1:59004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5674,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-07-15T05:52:28.551716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.964624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:52:28.551781Z","caller":"traceutil/trace.go:171","msg":"trace[13038870] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26114; }","duration":"443.060865ms","start":"2026-07-15T05:52:28.108703Z","end":"2026-07-15T05:52:28.551764Z","steps":["trace[13038870] 'agreement among raft nodes before linearized reading' (duration: 442.944513ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:28.55181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.431366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/ports/\" range_end:\"/registry/openstack.k-orc.cloud/ports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-15T05:52:28.551843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:52:28.108685Z","time spent":"443.147798ms","remote":"127.0.0.1:59294","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-07-15T05:52:28.551857Z","caller":"traceutil/trace.go:171","msg":"trace[1950907539] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/ports/; range_end:/registry/openstack.k-orc.cloud/ports0; response_count:0; response_revision:26114; }","duration":"425.513548ms","start":"2026-07-15T05:52:28.126328Z","end":"2026-07-15T05:52:28.551842Z","steps":["trace[1950907539] 'agreement among raft nodes before linearized reading' (duration: 425.426036ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:52:28.551719Z","caller":"traceutil/trace.go:171","msg":"trace[326097029] transaction","detail":"{read_only:false; response_revision:26114; number_of_response:1; }","duration":"442.985734ms","start":"2026-07-15T05:52:28.108706Z","end":"2026-07-15T05:52:28.551692Z","steps":["trace[326097029] 'process raft request' (duration: 442.838931ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:28.551898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:52:28.126315Z","time spent":"425.575909ms","remote":"127.0.0.1:45400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/openstack.k-orc.cloud/ports/\" range_end:\"/registry/openstack.k-orc.cloud/ports0\" count_only:true "} {"level":"warn","ts":"2026-07-15T05:52:28.551979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:52:28.108683Z","time spent":"443.234809ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T05:52:28.552279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.451923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:32 size:277959"} {"level":"info","ts":"2026-07-15T05:52:28.552352Z","caller":"traceutil/trace.go:171","msg":"trace[231025369] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:32; response_revision:26114; }","duration":"248.574135ms","start":"2026-07-15T05:52:28.303759Z","end":"2026-07-15T05:52:28.552333Z","steps":["trace[231025369] 'agreement among raft nodes before linearized reading' (duration: 247.978384ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:52:31.508264Z","caller":"traceutil/trace.go:171","msg":"trace[1418717051] transaction","detail":"{read_only:false; response_revision:26190; number_of_response:1; }","duration":"122.151976ms","start":"2026-07-15T05:52:31.386089Z","end":"2026-07-15T05:52:31.508241Z","steps":["trace[1418717051] 'process raft request' (duration: 121.971722ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:52:31.634818Z","caller":"traceutil/trace.go:171","msg":"trace[1906956566] linearizableReadLoop","detail":"{readStateIndex:27051; appliedIndex:27047; }","duration":"184.717304ms","start":"2026-07-15T05:52:31.450076Z","end":"2026-07-15T05:52:31.634794Z","steps":["trace[1906956566] 'read index received' (duration: 58.060101ms)","trace[1906956566] 'applied index is now lower than readState.Index' (duration: 126.655872ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:52:31.634865Z","caller":"traceutil/trace.go:171","msg":"trace[1210090482] transaction","detail":"{read_only:false; response_revision:26193; number_of_response:1; }","duration":"245.851231ms","start":"2026-07-15T05:52:31.388988Z","end":"2026-07-15T05:52:31.634839Z","steps":["trace[1210090482] 'process raft request' (duration: 245.76386ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:52:31.634929Z","caller":"traceutil/trace.go:171","msg":"trace[1356968071] transaction","detail":"{read_only:false; response_revision:26191; number_of_response:1; }","duration":"248.206757ms","start":"2026-07-15T05:52:31.386712Z","end":"2026-07-15T05:52:31.634919Z","steps":["trace[1356968071] 'process raft request' (duration: 247.82925ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:52:31.634857Z","caller":"traceutil/trace.go:171","msg":"trace[1031064492] transaction","detail":"{read_only:false; response_revision:26192; number_of_response:1; }","duration":"246.497614ms","start":"2026-07-15T05:52:31.388324Z","end":"2026-07-15T05:52:31.634821Z","steps":["trace[1031064492] 'process raft request' (duration: 246.378942ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:31.635088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.000438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-07-15T05:52:31.635144Z","caller":"traceutil/trace.go:171","msg":"trace[1089756164] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:26193; }","duration":"185.121411ms","start":"2026-07-15T05:52:31.450001Z","end":"2026-07-15T05:52:31.635122Z","steps":["trace[1089756164] 'agreement among raft nodes before linearized reading' (duration: 184.929397ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:52:36.711448Z","caller":"traceutil/trace.go:171","msg":"trace[1741421232] transaction","detail":"{read_only:false; response_revision:26254; number_of_response:1; }","duration":"135.29838ms","start":"2026-07-15T05:52:36.576114Z","end":"2026-07-15T05:52:36.711413Z","steps":["trace[1741421232] 'process raft request' (duration: 135.060045ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:52:37.044407Z","caller":"traceutil/trace.go:171","msg":"trace[1001864404] transaction","detail":"{read_only:false; response_revision:26255; number_of_response:1; }","duration":"226.749394ms","start":"2026-07-15T05:52:36.817628Z","end":"2026-07-15T05:52:37.044378Z","steps":["trace[1001864404] 'process raft request' (duration: 156.448798ms)","trace[1001864404] 'compare' (duration: 69.865957ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:52:37.207536Z","caller":"traceutil/trace.go:171","msg":"trace[940467507] linearizableReadLoop","detail":"{readStateIndex:27115; appliedIndex:27113; }","duration":"315.118599ms","start":"2026-07-15T05:52:36.892379Z","end":"2026-07-15T05:52:37.207497Z","steps":["trace[940467507] 'read index received' (duration: 81.605574ms)","trace[940467507] 'applied index is now lower than readState.Index' (duration: 233.511234ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:52:37.207583Z","caller":"traceutil/trace.go:171","msg":"trace[1680372772] transaction","detail":"{read_only:false; response_revision:26256; number_of_response:1; }","duration":"387.876513ms","start":"2026-07-15T05:52:36.81968Z","end":"2026-07-15T05:52:37.207557Z","steps":["trace[1680372772] 'process raft request' (duration: 376.424402ms)","trace[1680372772] 'compare' (duration: 11.219186ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:52:37.207702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:52:36.819647Z","time spent":"387.997284ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T05:52:37.207832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.447575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-15T05:52:37.207837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.011459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/roles/\" range_end:\"/registry/openstack.k-orc.cloud/roles0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:52:37.20789Z","caller":"traceutil/trace.go:171","msg":"trace[1636547624] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:26256; }","duration":"315.600748ms","start":"2026-07-15T05:52:36.892267Z","end":"2026-07-15T05:52:37.207868Z","steps":["trace[1636547624] 'agreement among raft nodes before linearized reading' (duration: 315.425524ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:52:37.207905Z","caller":"traceutil/trace.go:171","msg":"trace[668670840] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/roles/; range_end:/registry/openstack.k-orc.cloud/roles0; response_count:0; response_revision:26256; }","duration":"310.144562ms","start":"2026-07-15T05:52:36.897742Z","end":"2026-07-15T05:52:37.207887Z","steps":["trace[668670840] 'agreement among raft nodes before linearized reading' (duration: 310.02088ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:37.207941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:52:36.897715Z","time spent":"310.219014ms","remote":"127.0.0.1:51488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/openstack.k-orc.cloud/roles/\" range_end:\"/registry/openstack.k-orc.cloud/roles0\" count_only:true "} {"level":"warn","ts":"2026-07-15T05:52:37.207948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:52:36.892216Z","time spent":"315.71544ms","remote":"127.0.0.1:60672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-07-15T05:52:37.207925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.08516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:52:37.208053Z","caller":"traceutil/trace.go:171","msg":"trace[1805028107] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26256; }","duration":"101.268023ms","start":"2026-07-15T05:52:37.106763Z","end":"2026-07-15T05:52:37.208031Z","steps":["trace[1805028107] 'agreement among raft nodes before linearized reading' (duration: 101.050779ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:37.208055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.771163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs/\" range_end:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:52:37.208129Z","caller":"traceutil/trace.go:171","msg":"trace[1806865118] range","detail":"{range_begin:/registry/runtime.cluster.x-k8s.io/extensionconfigs/; range_end:/registry/runtime.cluster.x-k8s.io/extensionconfigs0; response_count:0; response_revision:26256; }","duration":"299.890445ms","start":"2026-07-15T05:52:36.908205Z","end":"2026-07-15T05:52:37.208096Z","steps":["trace[1806865118] 'agreement among raft nodes before linearized reading' (duration: 299.761352ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:46.343221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.751458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1936"} {"level":"info","ts":"2026-07-15T05:52:46.343335Z","caller":"traceutil/trace.go:171","msg":"trace[1723953073] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:26332; }","duration":"122.928741ms","start":"2026-07-15T05:52:46.220379Z","end":"2026-07-15T05:52:46.343307Z","steps":["trace[1723953073] 'range keys from in-memory index tree' (duration: 122.529673ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:52:46.891262Z","caller":"traceutil/trace.go:171","msg":"trace[66142127] transaction","detail":"{read_only:false; response_revision:26334; number_of_response:1; }","duration":"134.351352ms","start":"2026-07-15T05:52:46.756863Z","end":"2026-07-15T05:52:46.891215Z","steps":["trace[66142127] 'process raft request' (duration: 121.199079ms)","trace[66142127] 'compare' (duration: 12.842837ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:52:46.891313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.476544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:561"} {"level":"info","ts":"2026-07-15T05:52:46.8914Z","caller":"traceutil/trace.go:171","msg":"trace[1839931078] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:26333; }","duration":"246.599796ms","start":"2026-07-15T05:52:46.644777Z","end":"2026-07-15T05:52:46.891376Z","steps":["trace[1839931078] 'range keys from in-memory index tree' (duration: 246.28138ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:52:47.448219Z","caller":"traceutil/trace.go:171","msg":"trace[167966254] transaction","detail":"{read_only:false; response_revision:26337; number_of_response:1; }","duration":"195.590882ms","start":"2026-07-15T05:52:47.252605Z","end":"2026-07-15T05:52:47.448196Z","steps":["trace[167966254] 'process raft request' (duration: 195.43712ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:52:51.700591Z","caller":"traceutil/trace.go:171","msg":"trace[1073352331] transaction","detail":"{read_only:false; response_revision:26369; number_of_response:1; }","duration":"126.141313ms","start":"2026-07-15T05:52:51.574408Z","end":"2026-07-15T05:52:51.700549Z","steps":["trace[1073352331] 'process raft request' (duration: 102.822673ms)","trace[1073352331] 'compare' (duration: 23.038945ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:52:51.991897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.0518ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T05:52:51.992028Z","caller":"traceutil/trace.go:171","msg":"trace[1262724577] transaction","detail":"{read_only:false; response_revision:26370; number_of_response:1; }","duration":"283.262004ms","start":"2026-07-15T05:52:51.708705Z","end":"2026-07-15T05:52:51.991967Z","steps":["trace[1262724577] 'process raft request' (duration: 114.197043ms)","trace[1262724577] 'compare' (duration: 168.838366ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:52:52.021815Z","caller":"traceutil/trace.go:171","msg":"trace[1824729581] linearizableReadLoop","detail":"{readStateIndex:27234; appliedIndex:27231; }","duration":"218.647017ms","start":"2026-07-15T05:52:51.803154Z","end":"2026-07-15T05:52:52.021801Z","steps":["trace[1824729581] 'read index received' (duration: 19.639129ms)","trace[1824729581] 'applied index is now lower than readState.Index' (duration: 199.006868ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:52:52.02206Z","caller":"traceutil/trace.go:171","msg":"trace[263285736] transaction","detail":"{read_only:false; response_revision:26371; number_of_response:1; }","duration":"312.723992ms","start":"2026-07-15T05:52:51.709325Z","end":"2026-07-15T05:52:52.022049Z","steps":["trace[263285736] 'process raft request' (duration: 312.352625ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:52.022139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:52:51.7093Z","time spent":"312.785984ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:52:52.022262Z","caller":"traceutil/trace.go:171","msg":"trace[834288759] transaction","detail":"{read_only:false; response_revision:26372; number_of_response:1; }","duration":"312.247432ms","start":"2026-07-15T05:52:51.710007Z","end":"2026-07-15T05:52:52.022254Z","steps":["trace[834288759] 'process raft request' (duration: 311.760013ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:52.022306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:52:51.709991Z","time spent":"312.292503ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T05:52:52.02242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.272539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:52:52.02245Z","caller":"traceutil/trace.go:171","msg":"trace[1742450367] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:26372; }","duration":"219.306059ms","start":"2026-07-15T05:52:51.803134Z","end":"2026-07-15T05:52:52.02244Z","steps":["trace[1742450367] 'agreement among raft nodes before linearized reading' (duration: 219.250918ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:52.022585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.242698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1989"} {"level":"info","ts":"2026-07-15T05:52:52.022612Z","caller":"traceutil/trace.go:171","msg":"trace[1642701520] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:26372; }","duration":"149.313689ms","start":"2026-07-15T05:52:51.87329Z","end":"2026-07-15T05:52:52.022604Z","steps":["trace[1642701520] 'agreement among raft nodes before linearized reading' (duration: 149.265559ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:52.31878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.746173ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T05:52:52.318889Z","caller":"traceutil/trace.go:171","msg":"trace[1519206406] linearizableReadLoop","detail":"{readStateIndex:27236; appliedIndex:27235; }","duration":"260.152887ms","start":"2026-07-15T05:52:52.058719Z","end":"2026-07-15T05:52:52.318872Z","steps":["trace[1519206406] 'read index received' (duration: 116.221892ms)","trace[1519206406] 'applied index is now lower than readState.Index' (duration: 143.929495ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:52:52.31902Z","caller":"traceutil/trace.go:171","msg":"trace[284259959] transaction","detail":"{read_only:false; response_revision:26374; number_of_response:1; }","duration":"289.360541ms","start":"2026-07-15T05:52:52.029605Z","end":"2026-07-15T05:52:52.318965Z","steps":["trace[284259959] 'process raft request' (duration: 145.337164ms)","trace[284259959] 'compare' (duration: 143.526238ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:52:52.319102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.383012ms","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-07-15T05:52:52.31914Z","caller":"traceutil/trace.go:171","msg":"trace[1923876651] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26374; }","duration":"260.431553ms","start":"2026-07-15T05:52:52.058696Z","end":"2026-07-15T05:52:52.319127Z","steps":["trace[1923876651] 'agreement among raft nodes before linearized reading' (duration: 260.26593ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:52.31936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.236263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-07-15T05:52:52.319446Z","caller":"traceutil/trace.go:171","msg":"trace[591653658] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26374; }","duration":"255.364676ms","start":"2026-07-15T05:52:52.064061Z","end":"2026-07-15T05:52:52.319425Z","steps":["trace[591653658] 'agreement among raft nodes before linearized reading' (duration: 255.016289ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:52.319555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.671761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:52:52.319594Z","caller":"traceutil/trace.go:171","msg":"trace[2142761154] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26374; }","duration":"213.764673ms","start":"2026-07-15T05:52:52.105819Z","end":"2026-07-15T05:52:52.319584Z","steps":["trace[2142761154] 'agreement among raft nodes before linearized reading' (duration: 213.702702ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:52.319702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.467682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-15T05:52:52.319789Z","caller":"traceutil/trace.go:171","msg":"trace[1847191994] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26374; }","duration":"249.583113ms","start":"2026-07-15T05:52:52.070193Z","end":"2026-07-15T05:52:52.319776Z","steps":["trace[1847191994] 'agreement among raft nodes before linearized reading' (duration: 249.286558ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:52.320063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.793084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-07-15T05:52:52.32007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.443369ms","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-07-15T05:52:52.320103Z","caller":"traceutil/trace.go:171","msg":"trace[295047404] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26374; }","duration":"255.864085ms","start":"2026-07-15T05:52:52.064228Z","end":"2026-07-15T05:52:52.320092Z","steps":["trace[295047404] 'agreement among raft nodes before linearized reading' (duration: 255.55549ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:52:52.320132Z","caller":"traceutil/trace.go:171","msg":"trace[1348070125] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26374; }","duration":"251.727985ms","start":"2026-07-15T05:52:52.068386Z","end":"2026-07-15T05:52:52.320114Z","steps":["trace[1348070125] 'agreement among raft nodes before linearized reading' (duration: 251.343388ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:52:52.466251Z","caller":"traceutil/trace.go:171","msg":"trace[633413809] transaction","detail":"{read_only:false; response_revision:26376; number_of_response:1; }","duration":"141.215195ms","start":"2026-07-15T05:52:52.325003Z","end":"2026-07-15T05:52:52.466218Z","steps":["trace[633413809] 'process raft request' (duration: 140.841017ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:52:52.46631Z","caller":"traceutil/trace.go:171","msg":"trace[26234706] transaction","detail":"{read_only:false; response_revision:26377; number_of_response:1; }","duration":"141.154573ms","start":"2026-07-15T05:52:52.325121Z","end":"2026-07-15T05:52:52.466276Z","steps":["trace[26234706] 'process raft request' (duration: 140.958248ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:52:52.466315Z","caller":"traceutil/trace.go:171","msg":"trace[735391935] transaction","detail":"{read_only:false; response_revision:26379; number_of_response:1; }","duration":"140.538992ms","start":"2026-07-15T05:52:52.325739Z","end":"2026-07-15T05:52:52.466278Z","steps":["trace[735391935] 'process raft request' (duration: 140.413049ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:52:52.46634Z","caller":"traceutil/trace.go:171","msg":"trace[1963152490] transaction","detail":"{read_only:false; response_revision:26378; number_of_response:1; }","duration":"141.083481ms","start":"2026-07-15T05:52:52.325219Z","end":"2026-07-15T05:52:52.466302Z","steps":["trace[1963152490] 'process raft request' (duration: 140.902117ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:52:52.788304Z","caller":"traceutil/trace.go:171","msg":"trace[1323125090] transaction","detail":"{read_only:false; response_revision:26381; number_of_response:1; }","duration":"135.374551ms","start":"2026-07-15T05:52:52.652902Z","end":"2026-07-15T05:52:52.788277Z","steps":["trace[1323125090] 'process raft request' (duration: 135.193617ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:52:54.595164Z","caller":"traceutil/trace.go:171","msg":"trace[320621560] transaction","detail":"{read_only:false; response_revision:26400; number_of_response:1; }","duration":"119.27104ms","start":"2026-07-15T05:52:54.47587Z","end":"2026-07-15T05:52:54.595141Z","steps":["trace[320621560] 'process raft request' (duration: 64.503074ms)","trace[320621560] 'compare' (duration: 54.668714ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:52:54.658487Z","caller":"traceutil/trace.go:171","msg":"trace[451077392] transaction","detail":"{read_only:false; response_revision:26403; number_of_response:1; }","duration":"181.231316ms","start":"2026-07-15T05:52:54.477232Z","end":"2026-07-15T05:52:54.658463Z","steps":["trace[451077392] 'process raft request' (duration: 181.193845ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:52:54.658767Z","caller":"traceutil/trace.go:171","msg":"trace[653262712] transaction","detail":"{read_only:false; response_revision:26402; number_of_response:1; }","duration":"181.807196ms","start":"2026-07-15T05:52:54.476945Z","end":"2026-07-15T05:52:54.658752Z","steps":["trace[653262712] 'process raft request' (duration: 181.447039ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:52:54.658789Z","caller":"traceutil/trace.go:171","msg":"trace[189125121] transaction","detail":"{read_only:false; response_revision:26401; number_of_response:1; }","duration":"181.864457ms","start":"2026-07-15T05:52:54.476888Z","end":"2026-07-15T05:52:54.658752Z","steps":["trace[189125121] 'process raft request' (duration: 181.354168ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:56.836691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.409792ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T05:52:56.836877Z","caller":"traceutil/trace.go:171","msg":"trace[1457834484] transaction","detail":"{read_only:false; response_revision:26417; number_of_response:1; }","duration":"233.758569ms","start":"2026-07-15T05:52:56.603089Z","end":"2026-07-15T05:52:56.836847Z","steps":["trace[1457834484] 'process raft request' (duration: 98.083702ms)","trace[1457834484] 'compare' (duration: 135.186018ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:52:56.837028Z","caller":"traceutil/trace.go:171","msg":"trace[1484455187] linearizableReadLoop","detail":"{readStateIndex:27281; appliedIndex:27280; }","duration":"175.135879ms","start":"2026-07-15T05:52:56.661879Z","end":"2026-07-15T05:52:56.837015Z","steps":["trace[1484455187] 'read index received' (duration: 39.237097ms)","trace[1484455187] 'applied index is now lower than readState.Index' (duration: 135.895752ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:52:56.837413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.439904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-07-15T05:52:56.837471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.582947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-15T05:52:56.837509Z","caller":"traceutil/trace.go:171","msg":"trace[147460160] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26418; }","duration":"174.577717ms","start":"2026-07-15T05:52:56.662906Z","end":"2026-07-15T05:52:56.837483Z","steps":["trace[147460160] 'agreement among raft nodes before linearized reading' (duration: 174.312302ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:52:56.837542Z","caller":"traceutil/trace.go:171","msg":"trace[1002452916] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26418; }","duration":"175.655248ms","start":"2026-07-15T05:52:56.661872Z","end":"2026-07-15T05:52:56.837527Z","steps":["trace[1002452916] 'agreement among raft nodes before linearized reading' (duration: 175.482095ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:52:56.837585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.503835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-15T05:52:56.837632Z","caller":"traceutil/trace.go:171","msg":"trace[414798057] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26418; }","duration":"174.552946ms","start":"2026-07-15T05:52:56.663068Z","end":"2026-07-15T05:52:56.837621Z","steps":["trace[414798057] 'agreement among raft nodes before linearized reading' (duration: 174.427204ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:53:00.151076Z","caller":"traceutil/trace.go:171","msg":"trace[1914355348] transaction","detail":"{read_only:false; response_revision:26440; number_of_response:1; }","duration":"100.364446ms","start":"2026-07-15T05:53:00.050688Z","end":"2026-07-15T05:53:00.151053Z","steps":["trace[1914355348] 'process raft request' (duration: 100.208053ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:53:00.301848Z","caller":"traceutil/trace.go:171","msg":"trace[1929119549] transaction","detail":"{read_only:false; response_revision:26441; number_of_response:1; }","duration":"247.25037ms","start":"2026-07-15T05:53:00.054571Z","end":"2026-07-15T05:53:00.301821Z","steps":["trace[1929119549] 'process raft request' (duration: 160.026606ms)","trace[1929119549] 'compare' (duration: 86.965409ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:53:00.301967Z","caller":"traceutil/trace.go:171","msg":"trace[434921746] linearizableReadLoop","detail":"{readStateIndex:27306; appliedIndex:27303; }","duration":"193.665215ms","start":"2026-07-15T05:53:00.108278Z","end":"2026-07-15T05:53:00.301944Z","steps":["trace[434921746] 'read index received' (duration: 42.663452ms)","trace[434921746] 'applied index is now lower than readState.Index' (duration: 151.000843ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:53:00.30208Z","caller":"traceutil/trace.go:171","msg":"trace[345678648] transaction","detail":"{read_only:false; response_revision:26442; number_of_response:1; }","duration":"213.349815ms","start":"2026-07-15T05:53:00.088721Z","end":"2026-07-15T05:53:00.302071Z","steps":["trace[345678648] 'process raft request' (duration: 213.05237ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:53:00.30217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.340972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:53:00.302245Z","caller":"traceutil/trace.go:171","msg":"trace[1097380071] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:26442; }","duration":"119.454444ms","start":"2026-07-15T05:53:00.182769Z","end":"2026-07-15T05:53:00.302224Z","steps":["trace[1097380071] 'agreement among raft nodes before linearized reading' (duration: 119.323932ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:53:00.302229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.932031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:53:00.302306Z","caller":"traceutil/trace.go:171","msg":"trace[1123852976] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26442; }","duration":"194.034613ms","start":"2026-07-15T05:53:00.108252Z","end":"2026-07-15T05:53:00.302287Z","steps":["trace[1123852976] 'agreement among raft nodes before linearized reading' (duration: 193.889751ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:53:04.094495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.229266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-15T05:53:04.094533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.783028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:53:04.094563Z","caller":"traceutil/trace.go:171","msg":"trace[1685991614] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:26470; }","duration":"135.877441ms","start":"2026-07-15T05:53:03.958676Z","end":"2026-07-15T05:53:04.094554Z","steps":["trace[1685991614] 'count revisions from in-memory index tree' (duration: 135.632146ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:53:04.094559Z","caller":"traceutil/trace.go:171","msg":"trace[1387122038] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0; response_count:0; response_revision:26470; }","duration":"180.305968ms","start":"2026-07-15T05:53:03.914238Z","end":"2026-07-15T05:53:04.094544Z","steps":["trace[1387122038] 'count revisions from in-memory index tree' (duration: 180.132395ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:53:06.396367Z","caller":"traceutil/trace.go:171","msg":"trace[897146794] transaction","detail":"{read_only:false; response_revision:26486; number_of_response:1; }","duration":"169.60511ms","start":"2026-07-15T05:53:06.226728Z","end":"2026-07-15T05:53:06.396333Z","steps":["trace[897146794] 'process raft request' (duration: 93.995912ms)","trace[897146794] 'compare' (duration: 75.347313ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:53:06.601293Z","caller":"traceutil/trace.go:171","msg":"trace[1989804284] transaction","detail":"{read_only:false; response_revision:26488; number_of_response:1; }","duration":"249.596754ms","start":"2026-07-15T05:53:06.351667Z","end":"2026-07-15T05:53:06.601263Z","steps":["trace[1989804284] 'process raft request' (duration: 235.633385ms)","trace[1989804284] 'compare' (duration: 13.831257ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:53:07.826933Z","caller":"traceutil/trace.go:171","msg":"trace[1359164460] transaction","detail":"{read_only:false; response_revision:26497; number_of_response:1; }","duration":"224.795036ms","start":"2026-07-15T05:53:07.60211Z","end":"2026-07-15T05:53:07.826905Z","steps":["trace[1359164460] 'process raft request' (duration: 224.539821ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:53:07.88048Z","caller":"traceutil/trace.go:171","msg":"trace[585441828] transaction","detail":"{read_only:false; response_revision:26498; number_of_response:1; }","duration":"128.939798ms","start":"2026-07-15T05:53:07.751502Z","end":"2026-07-15T05:53:07.880441Z","steps":["trace[585441828] 'process raft request' (duration: 113.323206ms)","trace[585441828] 'compare' (duration: 15.466609ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:53:11.312679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.635705ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T05:53:11.312913Z","caller":"traceutil/trace.go:171","msg":"trace[359463814] transaction","detail":"{read_only:false; response_revision:26522; number_of_response:1; }","duration":"275.495153ms","start":"2026-07-15T05:53:11.037395Z","end":"2026-07-15T05:53:11.31289Z","steps":["trace[359463814] 'process raft request' (duration: 275.451462ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:53:11.312977Z","caller":"traceutil/trace.go:171","msg":"trace[1386177073] transaction","detail":"{read_only:false; response_revision:26520; number_of_response:1; }","duration":"286.777371ms","start":"2026-07-15T05:53:11.02617Z","end":"2026-07-15T05:53:11.312948Z","steps":["trace[1386177073] 'process raft request' (duration: 107.784199ms)","trace[1386177073] 'compare' (duration: 178.498733ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:53:11.312988Z","caller":"traceutil/trace.go:171","msg":"trace[1568759074] transaction","detail":"{read_only:false; response_revision:26521; number_of_response:1; }","duration":"277.039164ms","start":"2026-07-15T05:53:11.035907Z","end":"2026-07-15T05:53:11.312946Z","steps":["trace[1568759074] 'process raft request' (duration: 276.878071ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:53:11.312964Z","caller":"traceutil/trace.go:171","msg":"trace[1008967378] linearizableReadLoop","detail":"{readStateIndex:27387; appliedIndex:27386; }","duration":"279.558493ms","start":"2026-07-15T05:53:11.033374Z","end":"2026-07-15T05:53:11.312932Z","steps":["trace[1008967378] 'read index received' (duration: 100.598511ms)","trace[1008967378] 'applied index is now lower than readState.Index' (duration: 178.955832ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:53:11.313161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.180997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-07-15T05:53:11.313219Z","caller":"traceutil/trace.go:171","msg":"trace[208500306] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26522; }","duration":"291.267918ms","start":"2026-07-15T05:53:11.021937Z","end":"2026-07-15T05:53:11.313205Z","steps":["trace[208500306] 'agreement among raft nodes before linearized reading' (duration: 291.085255ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:53:11.605014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.5733ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T05:53:11.605339Z","caller":"traceutil/trace.go:171","msg":"trace[161778646] transaction","detail":"{read_only:false; response_revision:26526; number_of_response:1; }","duration":"288.606786ms","start":"2026-07-15T05:53:11.316713Z","end":"2026-07-15T05:53:11.60532Z","steps":["trace[161778646] 'process raft request' (duration: 288.554615ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:53:11.605335Z","caller":"traceutil/trace.go:171","msg":"trace[137953040] transaction","detail":"{read_only:false; response_revision:26524; number_of_response:1; }","duration":"433.093564ms","start":"2026-07-15T05:53:11.172211Z","end":"2026-07-15T05:53:11.605305Z","steps":["trace[137953040] 'process raft request' (duration: 432.91262ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:53:11.605364Z","caller":"traceutil/trace.go:171","msg":"trace[406208753] transaction","detail":"{read_only:false; response_revision:26525; number_of_response:1; }","duration":"356.843043ms","start":"2026-07-15T05:53:11.248483Z","end":"2026-07-15T05:53:11.605326Z","steps":["trace[406208753] 'process raft request' (duration: 356.731741ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:53:11.605362Z","caller":"traceutil/trace.go:171","msg":"trace[1473968502] transaction","detail":"{read_only:false; response_revision:26523; number_of_response:1; }","duration":"465.225333ms","start":"2026-07-15T05:53:11.140095Z","end":"2026-07-15T05:53:11.605321Z","steps":["trace[1473968502] 'process raft request' (duration: 294.264206ms)","trace[1473968502] 'compare' (duration: 170.416666ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:53:11.605476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.318373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:53:11.605535Z","caller":"traceutil/trace.go:171","msg":"trace[2035684435] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:26526; }","duration":"511.426635ms","start":"2026-07-15T05:53:11.094092Z","end":"2026-07-15T05:53:11.605519Z","steps":["trace[2035684435] 'agreement among raft nodes before linearized reading' (duration: 511.311083ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:53:11.605542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:53:11.248455Z","time spent":"356.992907ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T05:53:11.605588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:53:11.094062Z","time spent":"511.514487ms","remote":"127.0.0.1:51392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":28,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"warn","ts":"2026-07-15T05:53:11.605465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:53:11.172161Z","time spent":"433.235377ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:53:11.605338Z","caller":"traceutil/trace.go:171","msg":"trace[281219168] linearizableReadLoop","detail":"{readStateIndex:27392; appliedIndex:27389; }","duration":"292.214947ms","start":"2026-07-15T05:53:11.313093Z","end":"2026-07-15T05:53:11.605308Z","steps":["trace[281219168] 'read index received' (duration: 121.29171ms)","trace[281219168] 'applied index is now lower than readState.Index' (duration: 170.919707ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:53:11.606249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:53:11.140069Z","time spent":"465.451068ms","remote":"127.0.0.1:59592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:53:12.084316Z","caller":"traceutil/trace.go:171","msg":"trace[353984142] transaction","detail":"{read_only:false; response_revision:26528; number_of_response:1; }","duration":"192.703937ms","start":"2026-07-15T05:53:11.891579Z","end":"2026-07-15T05:53:12.084282Z","steps":["trace[353984142] 'process raft request' (duration: 114.082211ms)","trace[353984142] 'compare' (duration: 78.409031ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:53:12.610283Z","caller":"traceutil/trace.go:171","msg":"trace[43940815] transaction","detail":"{read_only:false; response_revision:26531; number_of_response:1; }","duration":"176.82164ms","start":"2026-07-15T05:53:12.433432Z","end":"2026-07-15T05:53:12.610254Z","steps":["trace[43940815] 'process raft request' (duration: 171.383465ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:53:29.166004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.365138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:53:29.166099Z","caller":"traceutil/trace.go:171","msg":"trace[1294072333] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:26660; }","duration":"238.518051ms","start":"2026-07-15T05:53:28.927556Z","end":"2026-07-15T05:53:29.166074Z","steps":["trace[1294072333] 'count revisions from in-memory index tree' (duration: 238.199114ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:53:29.309698Z","caller":"traceutil/trace.go:171","msg":"trace[680191937] transaction","detail":"{read_only:false; response_revision:26661; number_of_response:1; }","duration":"120.77409ms","start":"2026-07-15T05:53:29.18888Z","end":"2026-07-15T05:53:29.309654Z","steps":["trace[680191937] 'process raft request' (duration: 120.511455ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:53:29.533211Z","caller":"traceutil/trace.go:171","msg":"trace[834635301] linearizableReadLoop","detail":"{readStateIndex:27532; appliedIndex:27531; }","duration":"135.404931ms","start":"2026-07-15T05:53:29.397772Z","end":"2026-07-15T05:53:29.533177Z","steps":["trace[834635301] 'read index received' (duration: 61.232021ms)","trace[834635301] 'applied index is now lower than readState.Index' (duration: 74.17087ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:53:29.533327Z","caller":"traceutil/trace.go:171","msg":"trace[420119221] transaction","detail":"{read_only:false; response_revision:26662; number_of_response:1; }","duration":"149.596465ms","start":"2026-07-15T05:53:29.38369Z","end":"2026-07-15T05:53:29.533286Z","steps":["trace[420119221] 'process raft request' (duration: 75.384694ms)","trace[420119221] 'compare' (duration: 73.861205ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:53:29.53352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.739737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-15T05:53:29.533603Z","caller":"traceutil/trace.go:171","msg":"trace[1876104251] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26662; }","duration":"135.87802ms","start":"2026-07-15T05:53:29.397704Z","end":"2026-07-15T05:53:29.533582Z","steps":["trace[1876104251] 'agreement among raft nodes before linearized reading' (duration: 135.644445ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:53:29.533717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.36135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-15T05:53:29.533795Z","caller":"traceutil/trace.go:171","msg":"trace[928515124] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26662; }","duration":"122.494613ms","start":"2026-07-15T05:53:29.411286Z","end":"2026-07-15T05:53:29.533781Z","steps":["trace[928515124] 'agreement among raft nodes before linearized reading' (duration: 122.193836ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:53:29.534813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.614165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-07-15T05:53:29.534897Z","caller":"traceutil/trace.go:171","msg":"trace[765294502] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:26661; }","duration":"165.745616ms","start":"2026-07-15T05:53:29.369133Z","end":"2026-07-15T05:53:29.534879Z","steps":["trace[765294502] 'count revisions from in-memory index tree' (duration: 165.516502ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:53:29.761852Z","caller":"traceutil/trace.go:171","msg":"trace[904378975] transaction","detail":"{read_only:false; response_revision:26663; number_of_response:1; }","duration":"223.996981ms","start":"2026-07-15T05:53:29.537819Z","end":"2026-07-15T05:53:29.761816Z","steps":["trace[904378975] 'process raft request' (duration: 223.763926ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:53:29.781342Z","caller":"traceutil/trace.go:171","msg":"trace[1358493004] transaction","detail":"{read_only:false; response_revision:26664; number_of_response:1; }","duration":"241.778423ms","start":"2026-07-15T05:53:29.539534Z","end":"2026-07-15T05:53:29.781313Z","steps":["trace[1358493004] 'process raft request' (duration: 241.570749ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:53:29.781496Z","caller":"traceutil/trace.go:171","msg":"trace[1529987322] linearizableReadLoop","detail":"{readStateIndex:27534; appliedIndex:27532; }","duration":"217.462645ms","start":"2026-07-15T05:53:29.564013Z","end":"2026-07-15T05:53:29.781475Z","steps":["trace[1529987322] 'read index received' (duration: 197.5154ms)","trace[1529987322] 'applied index is now lower than readState.Index' (duration: 19.945105ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:53:29.781719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.650746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"warn","ts":"2026-07-15T05:53:29.781723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.7052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-15T05:53:29.781773Z","caller":"traceutil/trace.go:171","msg":"trace[1206055428] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26665; }","duration":"163.766208ms","start":"2026-07-15T05:53:29.617991Z","end":"2026-07-15T05:53:29.781757Z","steps":["trace[1206055428] 'agreement among raft nodes before linearized reading' (duration: 163.604845ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:53:29.781785Z","caller":"traceutil/trace.go:171","msg":"trace[1682779777] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:26665; }","duration":"217.833931ms","start":"2026-07-15T05:53:29.563934Z","end":"2026-07-15T05:53:29.781768Z","steps":["trace[1682779777] 'agreement among raft nodes before linearized reading' (duration: 217.659528ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:53:30.039903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.716417ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T05:53:30.040105Z","caller":"traceutil/trace.go:171","msg":"trace[1733348383] linearizableReadLoop","detail":"{readStateIndex:27536; appliedIndex:27535; }","duration":"252.362277ms","start":"2026-07-15T05:53:29.787716Z","end":"2026-07-15T05:53:30.040078Z","steps":["trace[1733348383] 'read index received' (duration: 116.247133ms)","trace[1733348383] 'applied index is now lower than readState.Index' (duration: 136.112684ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:53:30.040115Z","caller":"traceutil/trace.go:171","msg":"trace[397690402] transaction","detail":"{read_only:false; response_revision:26666; number_of_response:1; }","duration":"253.212883ms","start":"2026-07-15T05:53:29.786866Z","end":"2026-07-15T05:53:30.040079Z","steps":["trace[397690402] 'process raft request' (duration: 117.16153ms)","trace[397690402] 'compare' (duration: 135.34663ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:53:30.040363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.621472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18435"} {"level":"info","ts":"2026-07-15T05:53:30.040435Z","caller":"traceutil/trace.go:171","msg":"trace[1663348341] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:26666; }","duration":"252.749105ms","start":"2026-07-15T05:53:29.78767Z","end":"2026-07-15T05:53:30.040419Z","steps":["trace[1663348341] 'agreement among raft nodes before linearized reading' (duration: 252.50977ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:53:30.041515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.944765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-15T05:53:30.041596Z","caller":"traceutil/trace.go:171","msg":"trace[1302091636] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:26667; }","duration":"118.104408ms","start":"2026-07-15T05:53:29.923475Z","end":"2026-07-15T05:53:30.041579Z","steps":["trace[1302091636] 'agreement among raft nodes before linearized reading' (duration: 117.951035ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:53:30.363068Z","caller":"traceutil/trace.go:171","msg":"trace[110424665] transaction","detail":"{read_only:false; response_revision:26668; number_of_response:1; }","duration":"211.247256ms","start":"2026-07-15T05:53:30.151789Z","end":"2026-07-15T05:53:30.363036Z","steps":["trace[110424665] 'process raft request' (duration: 211.090882ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:53:30.370441Z","caller":"traceutil/trace.go:171","msg":"trace[1469228595] transaction","detail":"{read_only:false; response_revision:26669; number_of_response:1; }","duration":"122.112715ms","start":"2026-07-15T05:53:30.248275Z","end":"2026-07-15T05:53:30.370387Z","steps":["trace[1469228595] 'process raft request' (duration: 121.948532ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:55:47.348321Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25206} {"level":"info","ts":"2026-07-15T05:55:47.518661Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25206,"took":"164.674847ms","hash":1978859930,"current-db-size-bytes":59682816,"current-db-size":"60 MB","current-db-size-in-use-bytes":37683200,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-07-15T05:55:47.51881Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1978859930,"revision":25206,"compact-revision":22026} {"level":"info","ts":"2026-07-15T05:57:26.980827Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5ac240a6daec2148","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-15T05:57:26.985362Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-07-15T05:57:26.985477Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-07-15T06:00:47.354904Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28058} {"level":"info","ts":"2026-07-15T06:00:47.488919Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28058,"took":"129.195957ms","hash":1580721550,"current-db-size-bytes":59682816,"current-db-size":"60 MB","current-db-size-in-use-bytes":33722368,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-15T06:00:47.488997Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1580721550,"revision":28058,"compact-revision":25206} {"level":"info","ts":"2026-07-15T06:05:47.363356Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30641} {"level":"info","ts":"2026-07-15T06:05:47.509518Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30641,"took":"138.432705ms","hash":442391305,"current-db-size-bytes":59682816,"current-db-size":"60 MB","current-db-size-in-use-bytes":31068160,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-15T06:05:47.5096Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":442391305,"revision":30641,"compact-revision":28058} {"level":"info","ts":"2026-07-15T06:10:47.369854Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32924} {"level":"info","ts":"2026-07-15T06:10:47.520355Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32924,"took":"145.775671ms","hash":4293548014,"current-db-size-bytes":60727296,"current-db-size":"61 MB","current-db-size-in-use-bytes":30605312,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-15T06:10:47.520429Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4293548014,"revision":32924,"compact-revision":30641} {"level":"info","ts":"2026-07-15T06:15:47.375965Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35158} {"level":"info","ts":"2026-07-15T06:15:47.517424Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35158,"took":"135.661515ms","hash":411908869,"current-db-size-bytes":60727296,"current-db-size":"61 MB","current-db-size-in-use-bytes":32186368,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-15T06:15:47.517496Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":411908869,"revision":35158,"compact-revision":32924} {"level":"info","ts":"2026-07-15T06:18:44.457242Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5ac240a6daec2148","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-15T06:18:44.462192Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-07-15T06:18:44.462282Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-07-15T06:20:47.383195Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37453} {"level":"info","ts":"2026-07-15T06:20:47.499377Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37453,"took":"111.862991ms","hash":3489625313,"current-db-size-bytes":60727296,"current-db-size":"61 MB","current-db-size-in-use-bytes":31965184,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-15T06:20:47.499469Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3489625313,"revision":37453,"compact-revision":35158} {"level":"info","ts":"2026-07-15T06:25:47.388844Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39729} {"level":"info","ts":"2026-07-15T06:25:47.518711Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39729,"took":"123.088837ms","hash":1224991695,"current-db-size-bytes":60727296,"current-db-size":"61 MB","current-db-size-in-use-bytes":31916032,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-15T06:25:47.518796Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1224991695,"revision":39729,"compact-revision":37453}