{"level":"warn","ts":"2026-09-04T00:23:49.403408Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-04T00:23:49.403518Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.118: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://162.253.55.118:2380","--initial-cluster=instance=https://162.253.55.118:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.118:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.118: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-09-04T00:23:49.403635Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-04T00:23:49.403652Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.118:2380"]} {"level":"info","ts":"2026-09-04T00:23:49.403673Z","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-09-04T00:23:49.404475Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.118:2379"]} {"level":"info","ts":"2026-09-04T00:23:49.404565Z","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://162.253.55.118:2380"],"listen-peer-urls":["https://162.253.55.118:2380"],"advertise-client-urls":["https://162.253.55.118:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.118:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.118: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-09-04T00:23:49.409706Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.849792ms"} {"level":"info","ts":"2026-09-04T00:23:49.41626Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"39c975536cfc1086","cluster-id":"ac0a95643fc422f0"} {"level":"info","ts":"2026-09-04T00:23:49.416479Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39c975536cfc1086 switched to configuration voters=()"} {"level":"info","ts":"2026-09-04T00:23:49.41656Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39c975536cfc1086 became follower at term 0"} {"level":"info","ts":"2026-09-04T00:23:49.416646Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 39c975536cfc1086 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-04T00:23:49.41673Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39c975536cfc1086 became follower at term 1"} {"level":"info","ts":"2026-09-04T00:23:49.416812Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39c975536cfc1086 switched to configuration voters=(4163988331651928198)"} {"level":"warn","ts":"2026-09-04T00:23:49.425456Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-04T00:23:49.427477Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-04T00:23:49.42905Z","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-09-04T00:23:49.43313Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"39c975536cfc1086","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-04T00:23:49.433693Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"39c975536cfc1086","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-04T00:23:49.433858Z","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-09-04T00:23:49.434023Z","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-09-04T00:23:49.43406Z","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-09-04T00:23:49.435789Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39c975536cfc1086 switched to configuration voters=(4163988331651928198)"} {"level":"info","ts":"2026-09-04T00:23:49.436002Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ac0a95643fc422f0","local-member-id":"39c975536cfc1086","added-peer-id":"39c975536cfc1086","added-peer-peer-urls":["https://162.253.55.118:2380"]} {"level":"info","ts":"2026-09-04T00:23:49.442797Z","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-09-04T00:23:49.442888Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.118:2380"} {"level":"info","ts":"2026-09-04T00:23:49.44295Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.118:2380"} {"level":"info","ts":"2026-09-04T00:23:49.443082Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"39c975536cfc1086","initial-advertise-peer-urls":["https://162.253.55.118:2380"],"listen-peer-urls":["https://162.253.55.118:2380"],"advertise-client-urls":["https://162.253.55.118:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.118:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-04T00:23:49.443201Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-04T00:23:50.317234Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39c975536cfc1086 is starting a new election at term 1"} {"level":"info","ts":"2026-09-04T00:23:50.317334Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39c975536cfc1086 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-04T00:23:50.317373Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39c975536cfc1086 received MsgPreVoteResp from 39c975536cfc1086 at term 1"} {"level":"info","ts":"2026-09-04T00:23:50.317392Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39c975536cfc1086 became candidate at term 2"} {"level":"info","ts":"2026-09-04T00:23:50.317403Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39c975536cfc1086 received MsgVoteResp from 39c975536cfc1086 at term 2"} {"level":"info","ts":"2026-09-04T00:23:50.317418Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39c975536cfc1086 became leader at term 2"} {"level":"info","ts":"2026-09-04T00:23:50.317447Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 39c975536cfc1086 elected leader 39c975536cfc1086 at term 2"} {"level":"info","ts":"2026-09-04T00:23:50.318863Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"39c975536cfc1086","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.118:2379]}","request-path":"/0/members/39c975536cfc1086/attributes","cluster-id":"ac0a95643fc422f0","publish-timeout":"7s"} {"level":"info","ts":"2026-09-04T00:23:50.319122Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-04T00:23:50.319211Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-04T00:23:50.319215Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-04T00:23:50.319474Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-04T00:23:50.319511Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-04T00:23:50.320768Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ac0a95643fc422f0","local-member-id":"39c975536cfc1086","cluster-version":"3.5"} {"level":"info","ts":"2026-09-04T00:23:50.320866Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-04T00:23:50.320897Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-04T00:23:50.32295Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-04T00:23:50.326345Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.118:2379"} {"level":"info","ts":"2026-09-04T00:24:09.367158Z","caller":"traceutil/trace.go:171","msg":"trace[1368141553] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"118.00475ms","start":"2026-09-04T00:24:09.249128Z","end":"2026-09-04T00:24:09.367132Z","steps":["trace[1368141553] 'process raft request' (duration: 117.838015ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:24:14.628566Z","caller":"traceutil/trace.go:171","msg":"trace[991106756] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"128.401715ms","start":"2026-09-04T00:24:14.500146Z","end":"2026-09-04T00:24:14.628547Z","steps":["trace[991106756] 'process raft request' (duration: 128.313183ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:24:36.022977Z","caller":"traceutil/trace.go:171","msg":"trace[232927415] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"451.904781ms","start":"2026-09-04T00:24:35.57103Z","end":"2026-09-04T00:24:36.022935Z","steps":["trace[232927415] 'process raft request' (duration: 355.363563ms)","trace[232927415] 'compare' (duration: 95.728114ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T00:24:36.023004Z","caller":"traceutil/trace.go:171","msg":"trace[2101461806] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"248.961408ms","start":"2026-09-04T00:24:35.774023Z","end":"2026-09-04T00:24:36.022985Z","steps":["trace[2101461806] 'process raft request' (duration: 248.919326ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:24:36.023049Z","caller":"traceutil/trace.go:171","msg":"trace[1223859895] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"410.518103ms","start":"2026-09-04T00:24:35.612507Z","end":"2026-09-04T00:24:36.023025Z","steps":["trace[1223859895] 'process raft request' (duration: 410.005358ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:24:36.024041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T00:24:35.571012Z","time spent":"452.17646ms","remote":"127.0.0.1:54330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-04T00:24:36.024203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T00:24:35.612473Z","time spent":"411.021278ms","remote":"127.0.0.1:54246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036261,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-04T00:24:36.690923Z","caller":"traceutil/trace.go:171","msg":"trace[1709965308] linearizableReadLoop","detail":"{readStateIndex:660; appliedIndex:659; }","duration":"472.135992ms","start":"2026-09-04T00:24:36.218758Z","end":"2026-09-04T00:24:36.690894Z","steps":["trace[1709965308] 'read index received' (duration: 470.559706ms)","trace[1709965308] 'applied index is now lower than readState.Index' (duration: 1.574396ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T00:24:36.690981Z","caller":"traceutil/trace.go:171","msg":"trace[853633175] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"589.192009ms","start":"2026-09-04T00:24:36.101767Z","end":"2026-09-04T00:24:36.690959Z","steps":["trace[853633175] 'process raft request' (duration: 587.605573ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:24:36.69112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T00:24:36.101739Z","time spent":"589.297532ms","remote":"127.0.0.1:54458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-04T00:24:36.691279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.808739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-09-04T00:24:36.691274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.0825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T00:24:36.691383Z","caller":"traceutil/trace.go:171","msg":"trace[1464195908] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:638; }","duration":"401.962844ms","start":"2026-09-04T00:24:36.2894Z","end":"2026-09-04T00:24:36.691363Z","steps":["trace[1464195908] 'agreement among raft nodes before linearized reading' (duration: 401.646765ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:24:36.691412Z","caller":"traceutil/trace.go:171","msg":"trace[671101197] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:638; }","duration":"225.262355ms","start":"2026-09-04T00:24:36.466122Z","end":"2026-09-04T00:24:36.691384Z","steps":["trace[671101197] 'agreement among raft nodes before linearized reading' (duration: 225.07026ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:24:36.691417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T00:24:36.289376Z","time spent":"402.033676ms","remote":"127.0.0.1:54390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-04T00:24:36.691459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.652817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-09-04T00:24:36.691586Z","caller":"traceutil/trace.go:171","msg":"trace[830558378] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:638; }","duration":"472.891214ms","start":"2026-09-04T00:24:36.218669Z","end":"2026-09-04T00:24:36.69156Z","steps":["trace[830558378] 'agreement among raft nodes before linearized reading' (duration: 472.345498ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:24:36.691665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T00:24:36.218641Z","time spent":"473.011097ms","remote":"127.0.0.1:54390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-04T00:24:53.014257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.521357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T00:24:53.014336Z","caller":"traceutil/trace.go:171","msg":"trace[551682814] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:802; }","duration":"282.673842ms","start":"2026-09-04T00:24:52.731643Z","end":"2026-09-04T00:24:53.014317Z","steps":["trace[551682814] 'count revisions from in-memory index tree' (duration: 282.377003ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:24:53.014336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.343365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-09-04T00:24:53.014377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.828357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-09-04T00:24:53.014399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.24019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-fj8kg\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-09-04T00:24:53.014432Z","caller":"traceutil/trace.go:171","msg":"trace[40629050] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:802; }","duration":"155.522499ms","start":"2026-09-04T00:24:52.858889Z","end":"2026-09-04T00:24:53.014411Z","steps":["trace[40629050] 'range keys from in-memory index tree' (duration: 155.132229ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:24:53.014441Z","caller":"traceutil/trace.go:171","msg":"trace[730245121] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:802; }","duration":"219.91153ms","start":"2026-09-04T00:24:52.794509Z","end":"2026-09-04T00:24:53.014421Z","steps":["trace[730245121] 'range keys from in-memory index tree' (duration: 219.644872ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:24:53.014465Z","caller":"traceutil/trace.go:171","msg":"trace[1137570383] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-fj8kg; range_end:; response_count:1; response_revision:802; }","duration":"354.351274ms","start":"2026-09-04T00:24:52.660098Z","end":"2026-09-04T00:24:53.014449Z","steps":["trace[1137570383] 'range keys from in-memory index tree' (duration: 354.050394ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:24:53.014503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T00:24:52.660071Z","time spent":"354.420216ms","remote":"127.0.0.1:54330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-fj8kg\" "} {"level":"warn","ts":"2026-09-04T00:24:53.014674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.406548ms","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-09-04T00:24:53.014888Z","caller":"traceutil/trace.go:171","msg":"trace[688942014] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:802; }","duration":"227.575013ms","start":"2026-09-04T00:24:52.787231Z","end":"2026-09-04T00:24:53.014806Z","steps":["trace[688942014] 'range keys from in-memory index tree' (duration: 226.918494ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:26:12.695505Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1190815527755298823,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-04T00:26:12.845223Z","caller":"traceutil/trace.go:171","msg":"trace[1582664010] transaction","detail":"{read_only:false; response_revision:1882; number_of_response:1; }","duration":"706.940356ms","start":"2026-09-04T00:26:12.138257Z","end":"2026-09-04T00:26:12.845197Z","steps":["trace[1582664010] 'process raft request' (duration: 706.72377ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:26:12.845373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T00:26:12.138209Z","time spent":"707.084011ms","remote":"127.0.0.1:54394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1530,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-04T00:26:12.845568Z","caller":"traceutil/trace.go:171","msg":"trace[1169808601] transaction","detail":"{read_only:false; response_revision:1883; number_of_response:1; }","duration":"707.264366ms","start":"2026-09-04T00:26:12.138265Z","end":"2026-09-04T00:26:12.845529Z","steps":["trace[1169808601] 'process raft request' (duration: 706.886644ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:26:12.845724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T00:26:12.138248Z","time spent":"707.393869ms","remote":"127.0.0.1:54394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1472,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-04T00:26:12.845943Z","caller":"traceutil/trace.go:171","msg":"trace[620377806] transaction","detail":"{read_only:false; response_revision:1884; number_of_response:1; }","duration":"707.42614ms","start":"2026-09-04T00:26:12.138471Z","end":"2026-09-04T00:26:12.845897Z","steps":["trace[620377806] 'process raft request' (duration: 706.978187ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:26:12.846206Z","caller":"traceutil/trace.go:171","msg":"trace[1650918207] transaction","detail":"{read_only:false; response_revision:1885; number_of_response:1; }","duration":"701.93437ms","start":"2026-09-04T00:26:12.144229Z","end":"2026-09-04T00:26:12.846164Z","steps":["trace[1650918207] 'process raft request' (duration: 701.535888ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:26:12.846424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T00:26:12.13841Z","time spent":"707.792111ms","remote":"127.0.0.1:54318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":978,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-04T00:26:12.846507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T00:26:12.144219Z","time spent":"702.142636ms","remote":"127.0.0.1:54330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-04T00:26:12.866316Z","caller":"traceutil/trace.go:171","msg":"trace[1758556568] linearizableReadLoop","detail":"{readStateIndex:1957; appliedIndex:1952; }","duration":"671.276426ms","start":"2026-09-04T00:26:12.195013Z","end":"2026-09-04T00:26:12.866289Z","steps":["trace[1758556568] 'read index received' (duration: 650.044968ms)","trace[1758556568] 'applied index is now lower than readState.Index' (duration: 21.229868ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T00:26:12.866546Z","caller":"traceutil/trace.go:171","msg":"trace[528548589] transaction","detail":"{read_only:false; response_revision:1886; number_of_response:1; }","duration":"204.46458ms","start":"2026-09-04T00:26:12.662046Z","end":"2026-09-04T00:26:12.86651Z","steps":["trace[528548589] 'process raft request' (duration: 204.11037ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:26:12.866614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.502643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-04T00:26:12.866644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.622826ms","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-09-04T00:26:12.866684Z","caller":"traceutil/trace.go:171","msg":"trace[97074864] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1886; }","duration":"401.611446ms","start":"2026-09-04T00:26:12.465054Z","end":"2026-09-04T00:26:12.866665Z","steps":["trace[97074864] 'agreement among raft nodes before linearized reading' (duration: 401.493093ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:26:12.866687Z","caller":"traceutil/trace.go:171","msg":"trace[1285539308] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1886; }","duration":"249.74454ms","start":"2026-09-04T00:26:12.616927Z","end":"2026-09-04T00:26:12.866672Z","steps":["trace[1285539308] 'agreement among raft nodes before linearized reading' (duration: 249.559814ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:26:12.86672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T00:26:12.465036Z","time spent":"401.677238ms","remote":"127.0.0.1:54160","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-09-04T00:26:12.866812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.267397ms","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-09-04T00:26:12.866937Z","caller":"traceutil/trace.go:171","msg":"trace[1867684813] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1886; }","duration":"552.456133ms","start":"2026-09-04T00:26:12.314453Z","end":"2026-09-04T00:26:12.866909Z","steps":["trace[1867684813] 'agreement among raft nodes before linearized reading' (duration: 552.0269ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:26:12.866998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T00:26:12.314428Z","time spent":"552.556546ms","remote":"127.0.0.1:55324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-09-04T00:26:12.867286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.232074ms","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-09-04T00:26:12.867546Z","caller":"traceutil/trace.go:171","msg":"trace[680591970] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1886; }","duration":"672.362948ms","start":"2026-09-04T00:26:12.194955Z","end":"2026-09-04T00:26:12.867318Z","steps":["trace[680591970] 'agreement among raft nodes before linearized reading' (duration: 671.473562ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:26:12.867596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T00:26:12.194938Z","time spent":"672.646846ms","remote":"127.0.0.1:54390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-09-04T00:26:12.989801Z","caller":"traceutil/trace.go:171","msg":"trace[437826060] transaction","detail":"{read_only:false; response_revision:1887; number_of_response:1; }","duration":"117.67458ms","start":"2026-09-04T00:26:12.872101Z","end":"2026-09-04T00:26:12.989776Z","steps":["trace[437826060] 'process raft request' (duration: 64.297064ms)","trace[437826060] 'compare' (duration: 53.117478ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T00:26:12.990155Z","caller":"traceutil/trace.go:171","msg":"trace[1474421766] transaction","detail":"{read_only:false; response_revision:1888; number_of_response:1; }","duration":"117.633858ms","start":"2026-09-04T00:26:12.872509Z","end":"2026-09-04T00:26:12.990143Z","steps":["trace[1474421766] 'process raft request' (duration: 117.219476ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:26:13.35381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.168892ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-04T00:26:13.354099Z","caller":"traceutil/trace.go:171","msg":"trace[1417194786] transaction","detail":"{read_only:false; response_revision:1890; number_of_response:1; }","duration":"223.818854ms","start":"2026-09-04T00:26:13.130263Z","end":"2026-09-04T00:26:13.354082Z","steps":["trace[1417194786] 'process raft request' (duration: 223.721401ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:26:13.354089Z","caller":"traceutil/trace.go:171","msg":"trace[256882113] transaction","detail":"{read_only:false; response_revision:1889; number_of_response:1; }","duration":"307.73889ms","start":"2026-09-04T00:26:13.046316Z","end":"2026-09-04T00:26:13.354055Z","steps":["trace[256882113] 'process raft request' (duration: 115.9517ms)","trace[256882113] 'compare' (duration: 190.906514ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T00:26:13.354249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T00:26:13.046282Z","time spent":"307.877234ms","remote":"127.0.0.1:54390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-04T00:26:26.116354Z","caller":"traceutil/trace.go:171","msg":"trace[1849821578] transaction","detail":"{read_only:false; response_revision:1996; number_of_response:1; }","duration":"325.827977ms","start":"2026-09-04T00:26:25.790499Z","end":"2026-09-04T00:26:26.116326Z","steps":["trace[1849821578] 'process raft request' (duration: 325.649792ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:26:26.116523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T00:26:25.790485Z","time spent":"325.94441ms","remote":"127.0.0.1:54330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5626,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-04T00:26:26.128503Z","caller":"traceutil/trace.go:171","msg":"trace[1391538699] linearizableReadLoop","detail":"{readStateIndex:2071; appliedIndex:2069; }","duration":"163.556837ms","start":"2026-09-04T00:26:25.96492Z","end":"2026-09-04T00:26:26.128477Z","steps":["trace[1391538699] 'read index received' (duration: 151.279909ms)","trace[1391538699] 'applied index is now lower than readState.Index' (duration: 12.275738ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T00:26:26.128589Z","caller":"traceutil/trace.go:171","msg":"trace[903464640] transaction","detail":"{read_only:false; response_revision:1997; number_of_response:1; }","duration":"200.847714ms","start":"2026-09-04T00:26:25.927726Z","end":"2026-09-04T00:26:26.128574Z","steps":["trace[903464640] 'process raft request' (duration: 200.615527ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:26:26.128739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.803595ms","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-09-04T00:26:26.128795Z","caller":"traceutil/trace.go:171","msg":"trace[980305981] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1997; }","duration":"163.912118ms","start":"2026-09-04T00:26:25.964864Z","end":"2026-09-04T00:26:26.128776Z","steps":["trace[980305981] 'agreement among raft nodes before linearized reading' (duration: 163.723442ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:27:04.245863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.68958ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-04T00:27:04.246019Z","caller":"traceutil/trace.go:171","msg":"trace[1425221107] linearizableReadLoop","detail":"{readStateIndex:2302; appliedIndex:2301; }","duration":"393.425162ms","start":"2026-09-04T00:27:03.852572Z","end":"2026-09-04T00:27:04.245997Z","steps":["trace[1425221107] 'read index received' (duration: 139.445774ms)","trace[1425221107] 'applied index is now lower than readState.Index' (duration: 253.977598ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T00:27:04.246126Z","caller":"traceutil/trace.go:171","msg":"trace[2056414531] transaction","detail":"{read_only:false; response_revision:2219; number_of_response:1; }","duration":"412.525032ms","start":"2026-09-04T00:27:03.833588Z","end":"2026-09-04T00:27:04.246113Z","steps":["trace[2056414531] 'process raft request' (duration: 158.466351ms)","trace[2056414531] 'compare' (duration: 253.151794ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T00:27:04.24619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T00:27:03.833568Z","time spent":"412.589224ms","remote":"127.0.0.1:54390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-04T00:27:04.246657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.516638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-04T00:27:04.246753Z","caller":"traceutil/trace.go:171","msg":"trace[1701402404] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:2219; }","duration":"334.649921ms","start":"2026-09-04T00:27:03.912081Z","end":"2026-09-04T00:27:04.246731Z","steps":["trace[1701402404] 'agreement among raft nodes before linearized reading' (duration: 334.486566ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:27:04.246797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T00:27:03.912069Z","time spent":"334.717303ms","remote":"127.0.0.1:33808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-09-04T00:27:04.246788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.204516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-09-04T00:27:04.24684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.148285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-04T00:27:04.246932Z","caller":"traceutil/trace.go:171","msg":"trace[333555253] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2219; }","duration":"394.393341ms","start":"2026-09-04T00:27:03.852491Z","end":"2026-09-04T00:27:04.246884Z","steps":["trace[333555253] 'agreement among raft nodes before linearized reading' (duration: 394.072132ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:27:04.246977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T00:27:03.852461Z","time spent":"394.507055ms","remote":"127.0.0.1:54390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-04T00:27:04.246989Z","caller":"traceutil/trace.go:171","msg":"trace[1020850927] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2219; }","duration":"392.32134ms","start":"2026-09-04T00:27:03.85463Z","end":"2026-09-04T00:27:04.246951Z","steps":["trace[1020850927] 'agreement among raft nodes before linearized reading' (duration: 391.574088ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:27:04.24704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T00:27:03.854618Z","time spent":"392.411472ms","remote":"127.0.0.1:54390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-09-04T00:27:04.430007Z","caller":"traceutil/trace.go:171","msg":"trace[1024367288] transaction","detail":"{read_only:false; response_revision:2221; number_of_response:1; }","duration":"177.246152ms","start":"2026-09-04T00:27:04.252594Z","end":"2026-09-04T00:27:04.42984Z","steps":["trace[1024367288] 'process raft request' (duration: 109.846808ms)","trace[1024367288] 'compare' (duration: 67.179757ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T00:27:09.593865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.131084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-04T00:27:09.594032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.842987ms","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-09-04T00:27:09.593983Z","caller":"traceutil/trace.go:171","msg":"trace[394627267] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2246; }","duration":"127.279018ms","start":"2026-09-04T00:27:09.466682Z","end":"2026-09-04T00:27:09.593961Z","steps":["trace[394627267] 'range keys from in-memory index tree' (duration: 126.819614ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:27:09.594198Z","caller":"traceutil/trace.go:171","msg":"trace[1127794655] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2246; }","duration":"122.932231ms","start":"2026-09-04T00:27:09.471152Z","end":"2026-09-04T00:27:09.594084Z","steps":["trace[1127794655] 'range keys from in-memory index tree' (duration: 122.645552ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:28:28.601508Z","caller":"traceutil/trace.go:171","msg":"trace[1250148553] transaction","detail":"{read_only:false; response_revision:2864; number_of_response:1; }","duration":"117.28063ms","start":"2026-09-04T00:28:28.484197Z","end":"2026-09-04T00:28:28.601478Z","steps":["trace[1250148553] 'process raft request' (duration: 117.088454ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:28:28.854441Z","caller":"traceutil/trace.go:171","msg":"trace[295359266] linearizableReadLoop","detail":"{readStateIndex:2973; appliedIndex:2972; }","duration":"122.241425ms","start":"2026-09-04T00:28:28.732162Z","end":"2026-09-04T00:28:28.854403Z","steps":["trace[295359266] 'read index received' (duration: 25.076483ms)","trace[295359266] 'applied index is now lower than readState.Index' (duration: 97.163912ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T00:28:28.854536Z","caller":"traceutil/trace.go:171","msg":"trace[751928561] transaction","detail":"{read_only:false; response_revision:2865; number_of_response:1; }","duration":"222.422675ms","start":"2026-09-04T00:28:28.632065Z","end":"2026-09-04T00:28:28.854488Z","steps":["trace[751928561] 'process raft request' (duration: 125.267314ms)","trace[751928561] 'compare' (duration: 96.953785ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T00:28:28.854699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.502512ms","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-09-04T00:28:28.854744Z","caller":"traceutil/trace.go:171","msg":"trace[2085453999] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2865; }","duration":"122.632786ms","start":"2026-09-04T00:28:28.732099Z","end":"2026-09-04T00:28:28.854732Z","steps":["trace[2085453999] 'agreement among raft nodes before linearized reading' (duration: 122.457761ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:31:42.408599Z","caller":"traceutil/trace.go:171","msg":"trace[910934657] transaction","detail":"{read_only:false; response_revision:4085; number_of_response:1; }","duration":"162.767217ms","start":"2026-09-04T00:31:42.245784Z","end":"2026-09-04T00:31:42.408551Z","steps":["trace[910934657] 'process raft request' (duration: 162.50814ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:32:58.419231Z","caller":"traceutil/trace.go:171","msg":"trace[1294982208] transaction","detail":"{read_only:false; response_revision:4587; number_of_response:1; }","duration":"106.205258ms","start":"2026-09-04T00:32:58.313006Z","end":"2026-09-04T00:32:58.419211Z","steps":["trace[1294982208] 'process raft request' (duration: 106.119006ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:32:58.534876Z","caller":"traceutil/trace.go:171","msg":"trace[1373965469] transaction","detail":"{read_only:false; response_revision:4588; number_of_response:1; }","duration":"124.901593ms","start":"2026-09-04T00:32:58.409945Z","end":"2026-09-04T00:32:58.534847Z","steps":["trace[1373965469] 'process raft request' (duration: 103.438189ms)","trace[1373965469] 'compare' (duration: 21.272638ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T00:33:13.149584Z","caller":"traceutil/trace.go:171","msg":"trace[1134369225] transaction","detail":"{read_only:false; response_revision:5165; number_of_response:1; }","duration":"230.080802ms","start":"2026-09-04T00:33:12.919483Z","end":"2026-09-04T00:33:13.149564Z","steps":["trace[1134369225] 'process raft request' (duration: 229.946628ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:33:16.84938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.262884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:1 size:894"} {"level":"info","ts":"2026-09-04T00:33:16.84968Z","caller":"traceutil/trace.go:171","msg":"trace[1621331665] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:1; response_revision:5315; }","duration":"266.607354ms","start":"2026-09-04T00:33:16.583055Z","end":"2026-09-04T00:33:16.849663Z","steps":["trace[1621331665] 'range keys from in-memory index tree' (duration: 266.073449ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:33:16.849594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.415854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-db23ff23-4497-4e56-838f-2a3904d27d02\" ","response":"range_response_count:1 size:3721"} {"level":"warn","ts":"2026-09-04T00:33:16.84939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.544935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-grndb-tls\" ","response":"range_response_count:1 size:2184"} {"level":"info","ts":"2026-09-04T00:33:16.849773Z","caller":"traceutil/trace.go:171","msg":"trace[440416735] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-db23ff23-4497-4e56-838f-2a3904d27d02; range_end:; response_count:1; response_revision:5315; }","duration":"358.643401ms","start":"2026-09-04T00:33:16.491101Z","end":"2026-09-04T00:33:16.849744Z","steps":["trace[440416735] 'range keys from in-memory index tree' (duration: 358.316951ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:33:16.849797Z","caller":"traceutil/trace.go:171","msg":"trace[47939372] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-grndb-tls; range_end:; response_count:1; response_revision:5315; }","duration":"165.956126ms","start":"2026-09-04T00:33:16.683805Z","end":"2026-09-04T00:33:16.849762Z","steps":["trace[47939372] 'range keys from in-memory index tree' (duration: 165.440142ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:33:16.849466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.548196ms","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-09-04T00:33:16.849859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T00:33:16.491077Z","time spent":"358.760254ms","remote":"127.0.0.1:54330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3744,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-db23ff23-4497-4e56-838f-2a3904d27d02\" "} {"level":"warn","ts":"2026-09-04T00:33:16.849508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.697207ms","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-09-04T00:33:16.850031Z","caller":"traceutil/trace.go:171","msg":"trace[1133714661] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5315; }","duration":"197.126903ms","start":"2026-09-04T00:33:16.652885Z","end":"2026-09-04T00:33:16.850011Z","steps":["trace[1133714661] 'range keys from in-memory index tree' (duration: 196.461164ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:33:16.849585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.897193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-04T00:33:16.85015Z","caller":"traceutil/trace.go:171","msg":"trace[670392820] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5315; }","duration":"344.300114ms","start":"2026-09-04T00:33:16.505769Z","end":"2026-09-04T00:33:16.850069Z","steps":["trace[670392820] 'range keys from in-memory index tree' (duration: 343.587593ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:33:16.850254Z","caller":"traceutil/trace.go:171","msg":"trace[2018317004] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5315; }","duration":"310.543842ms","start":"2026-09-04T00:33:16.53966Z","end":"2026-09-04T00:33:16.850203Z","steps":["trace[2018317004] 'range keys from in-memory index tree' (duration: 309.761659ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:33:16.850311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T00:33:16.539647Z","time spent":"310.651575ms","remote":"127.0.0.1:54390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-04T00:33:16.850413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T00:33:16.505758Z","time spent":"344.557371ms","remote":"127.0.0.1:54390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-04T00:33:16.850692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.013437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:787132"} {"level":"info","ts":"2026-09-04T00:33:16.850826Z","caller":"traceutil/trace.go:171","msg":"trace[1946517457] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:5315; }","duration":"135.208222ms","start":"2026-09-04T00:33:16.71559Z","end":"2026-09-04T00:33:16.850798Z","steps":["trace[1946517457] 'range keys from in-memory index tree' (duration: 133.75944ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:33:18.678487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.941697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:17004"} {"level":"info","ts":"2026-09-04T00:33:18.678592Z","caller":"traceutil/trace.go:171","msg":"trace[639138859] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:5335; }","duration":"176.087971ms","start":"2026-09-04T00:33:18.50248Z","end":"2026-09-04T00:33:18.678568Z","steps":["trace[639138859] 'range keys from in-memory index tree' (duration: 175.753401ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:33:39.560805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.066533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-09-04T00:33:39.560877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.757863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b64c2d3a-b1d3-4e28-9e47-3c9f4f4fec0b\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-09-04T00:33:39.560912Z","caller":"traceutil/trace.go:171","msg":"trace[1252113570] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5726; }","duration":"107.231078ms","start":"2026-09-04T00:33:39.45366Z","end":"2026-09-04T00:33:39.560891Z","steps":["trace[1252113570] 'range keys from in-memory index tree' (duration: 106.860487ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:33:39.56097Z","caller":"traceutil/trace.go:171","msg":"trace[610351472] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b64c2d3a-b1d3-4e28-9e47-3c9f4f4fec0b; range_end:; response_count:1; response_revision:5726; }","duration":"118.925488ms","start":"2026-09-04T00:33:39.442022Z","end":"2026-09-04T00:33:39.560948Z","steps":["trace[610351472] 'range keys from in-memory index tree' (duration: 118.484385ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:33:39.807598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.420856ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-04T00:33:39.807822Z","caller":"traceutil/trace.go:171","msg":"trace[925167610] linearizableReadLoop","detail":"{readStateIndex:5942; appliedIndex:5941; }","duration":"159.023895ms","start":"2026-09-04T00:33:39.648777Z","end":"2026-09-04T00:33:39.807801Z","steps":["trace[925167610] 'read index received' (duration: 42.27552ms)","trace[925167610] 'applied index is now lower than readState.Index' (duration: 116.746915ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T00:33:39.807921Z","caller":"traceutil/trace.go:171","msg":"trace[2084366815] transaction","detail":"{read_only:false; response_revision:5728; number_of_response:1; }","duration":"241.540195ms","start":"2026-09-04T00:33:39.566275Z","end":"2026-09-04T00:33:39.807816Z","steps":["trace[2084366815] 'process raft request' (duration: 124.791209ms)","trace[2084366815] 'compare' (duration: 116.276942ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T00:33:39.808142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.320763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b64c2d3a-b1d3-4e28-9e47-3c9f4f4fec0b\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-09-04T00:33:39.808256Z","caller":"traceutil/trace.go:171","msg":"trace[1465437157] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b64c2d3a-b1d3-4e28-9e47-3c9f4f4fec0b; range_end:; response_count:1; response_revision:5728; }","duration":"159.518849ms","start":"2026-09-04T00:33:39.648718Z","end":"2026-09-04T00:33:39.808237Z","steps":["trace[1465437157] 'agreement among raft nodes before linearized reading' (duration: 159.149328ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:33:40.008205Z","caller":"traceutil/trace.go:171","msg":"trace[1643445571] transaction","detail":"{read_only:false; response_revision:5729; number_of_response:1; }","duration":"191.820199ms","start":"2026-09-04T00:33:39.816351Z","end":"2026-09-04T00:33:40.008172Z","steps":["trace[1643445571] 'process raft request' (duration: 179.283585ms)","trace[1643445571] 'compare' (duration: 12.413321ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T00:33:50.360435Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2988} {"level":"info","ts":"2026-09-04T00:33:50.447465Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2988,"took":"85.537238ms","hash":2238707878,"current-db-size-bytes":57167872,"current-db-size":"57 MB","current-db-size-in-use-bytes":32219136,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-04T00:33:50.447536Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2238707878,"revision":2988,"compact-revision":-1} {"level":"info","ts":"2026-09-04T00:34:33.598783Z","caller":"traceutil/trace.go:171","msg":"trace[2019887041] transaction","detail":"{read_only:false; response_revision:6251; number_of_response:1; }","duration":"108.340498ms","start":"2026-09-04T00:34:33.490422Z","end":"2026-09-04T00:34:33.598762Z","steps":["trace[2019887041] 'process raft request' (duration: 108.180864ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:34:33.59906Z","caller":"traceutil/trace.go:171","msg":"trace[538301911] transaction","detail":"{read_only:false; response_revision:6252; number_of_response:1; }","duration":"100.542291ms","start":"2026-09-04T00:34:33.498489Z","end":"2026-09-04T00:34:33.599032Z","steps":["trace[538301911] 'process raft request' (duration: 100.227872ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:38:50.367678Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5868} {"level":"info","ts":"2026-09-04T00:38:50.473333Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5868,"took":"102.704116ms","hash":2408435815,"current-db-size-bytes":57167872,"current-db-size":"57 MB","current-db-size-in-use-bytes":26636288,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-09-04T00:38:50.473422Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2408435815,"revision":5868,"compact-revision":2988} {"level":"info","ts":"2026-09-04T00:40:09.205906Z","caller":"traceutil/trace.go:171","msg":"trace[1202926120] transaction","detail":"{read_only:false; response_revision:8680; number_of_response:1; }","duration":"526.213237ms","start":"2026-09-04T00:40:08.679647Z","end":"2026-09-04T00:40:09.20586Z","steps":["trace[1202926120] 'process raft request' (duration: 525.957629ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:40:09.206169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T00:40:08.679622Z","time spent":"526.420723ms","remote":"127.0.0.1:54390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-04T00:40:09.267345Z","caller":"traceutil/trace.go:171","msg":"trace[757382379] transaction","detail":"{read_only:false; response_revision:8681; number_of_response:1; }","duration":"322.446663ms","start":"2026-09-04T00:40:08.944869Z","end":"2026-09-04T00:40:09.267316Z","steps":["trace[757382379] 'process raft request' (duration: 318.930781ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:40:09.267381Z","caller":"traceutil/trace.go:171","msg":"trace[1689831490] transaction","detail":"{read_only:false; response_revision:8682; number_of_response:1; }","duration":"256.088014ms","start":"2026-09-04T00:40:09.011267Z","end":"2026-09-04T00:40:09.267355Z","steps":["trace[1689831490] 'process raft request' (duration: 255.830137ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:40:09.267459Z","caller":"traceutil/trace.go:171","msg":"trace[439476650] transaction","detail":"{read_only:false; response_revision:8683; number_of_response:1; }","duration":"249.415511ms","start":"2026-09-04T00:40:09.017985Z","end":"2026-09-04T00:40:09.2674Z","steps":["trace[439476650] 'process raft request' (duration: 249.164243ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:40:09.267549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T00:40:08.944839Z","time spent":"322.579727ms","remote":"127.0.0.1:54390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-04T00:40:09.269995Z","caller":"traceutil/trace.go:171","msg":"trace[14135888] linearizableReadLoop","detail":"{readStateIndex:8991; appliedIndex:8987; }","duration":"223.561129ms","start":"2026-09-04T00:40:09.046416Z","end":"2026-09-04T00:40:09.269977Z","steps":["trace[14135888] 'read index received' (duration: 159.184037ms)","trace[14135888] 'applied index is now lower than readState.Index' (duration: 64.375632ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T00:40:09.27101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.595719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-04T00:40:09.271065Z","caller":"traceutil/trace.go:171","msg":"trace[1754993781] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:8683; }","duration":"224.657771ms","start":"2026-09-04T00:40:09.046396Z","end":"2026-09-04T00:40:09.271053Z","steps":["trace[1754993781] 'agreement among raft nodes before linearized reading' (duration: 223.860818ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:40:13.247179Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002332.wal"} {"level":"info","ts":"2026-09-04T00:40:21.358078Z","caller":"traceutil/trace.go:171","msg":"trace[1873291849] transaction","detail":"{read_only:false; response_revision:8742; number_of_response:1; }","duration":"108.078262ms","start":"2026-09-04T00:40:21.249979Z","end":"2026-09-04T00:40:21.358057Z","steps":["trace[1873291849] 'process raft request' (duration: 107.942358ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:40:22.232093Z","caller":"traceutil/trace.go:171","msg":"trace[84599335] transaction","detail":"{read_only:false; response_revision:8750; number_of_response:1; }","duration":"408.968458ms","start":"2026-09-04T00:40:21.823099Z","end":"2026-09-04T00:40:22.232068Z","steps":["trace[84599335] 'process raft request' (duration: 408.796863ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:40:22.232378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T00:40:21.823084Z","time spent":"409.085082ms","remote":"127.0.0.1:54390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-04T00:40:22.243583Z","caller":"traceutil/trace.go:171","msg":"trace[431605624] linearizableReadLoop","detail":"{readStateIndex:9062; appliedIndex:9060; }","duration":"261.872843ms","start":"2026-09-04T00:40:21.981684Z","end":"2026-09-04T00:40:22.243557Z","steps":["trace[431605624] 'read index received' (duration: 250.262765ms)","trace[431605624] 'applied index is now lower than readState.Index' (duration: 11.609068ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T00:40:22.243636Z","caller":"traceutil/trace.go:171","msg":"trace[1070695730] transaction","detail":"{read_only:false; response_revision:8751; number_of_response:1; }","duration":"406.354462ms","start":"2026-09-04T00:40:21.837252Z","end":"2026-09-04T00:40:22.243606Z","steps":["trace[1070695730] 'process raft request' (duration: 406.155937ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:40:22.243784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T00:40:21.837235Z","time spent":"406.472366ms","remote":"127.0.0.1:54390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-04T00:40:22.243824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.1138ms","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-09-04T00:40:22.24387Z","caller":"traceutil/trace.go:171","msg":"trace[1968311323] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8751; }","duration":"262.212903ms","start":"2026-09-04T00:40:21.981643Z","end":"2026-09-04T00:40:22.243856Z","steps":["trace[1968311323] 'agreement among raft nodes before linearized reading' (duration: 262.045578ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:40:22.243989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.579167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-tbzbk\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-09-04T00:40:22.244037Z","caller":"traceutil/trace.go:171","msg":"trace[563299023] range","detail":"{range_begin:/registry/pods/openstack/keepalived-tbzbk; range_end:; response_count:1; response_revision:8751; }","duration":"150.66694ms","start":"2026-09-04T00:40:22.093358Z","end":"2026-09-04T00:40:22.244024Z","steps":["trace[563299023] 'agreement among raft nodes before linearized reading' (duration: 150.425163ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:40:52.258315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.541853ms","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-09-04T00:40:52.258405Z","caller":"traceutil/trace.go:171","msg":"trace[1679813261] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:8964; }","duration":"476.691267ms","start":"2026-09-04T00:40:51.781686Z","end":"2026-09-04T00:40:52.258378Z","steps":["trace[1679813261] 'count revisions from in-memory index tree' (duration: 476.43871ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:40:52.258447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T00:40:51.781661Z","time spent":"476.77394ms","remote":"127.0.0.1:33844","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":"info","ts":"2026-09-04T00:40:55.211182Z","caller":"traceutil/trace.go:171","msg":"trace[611474334] transaction","detail":"{read_only:false; response_revision:8979; number_of_response:1; }","duration":"178.285023ms","start":"2026-09-04T00:40:55.032869Z","end":"2026-09-04T00:40:55.211154Z","steps":["trace[611474334] 'process raft request' (duration: 178.128968ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:41:03.778963Z","caller":"traceutil/trace.go:171","msg":"trace[795067484] transaction","detail":"{read_only:false; response_revision:9023; number_of_response:1; }","duration":"210.298504ms","start":"2026-09-04T00:41:03.568637Z","end":"2026-09-04T00:41:03.778936Z","steps":["trace[795067484] 'process raft request' (duration: 210.111568ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:41:03.779079Z","caller":"traceutil/trace.go:171","msg":"trace[92946610] transaction","detail":"{read_only:false; response_revision:9024; number_of_response:1; }","duration":"208.643765ms","start":"2026-09-04T00:41:03.570426Z","end":"2026-09-04T00:41:03.77907Z","steps":["trace[92946610] 'process raft request' (duration: 208.46485ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:41:03.779174Z","caller":"traceutil/trace.go:171","msg":"trace[1102036858] linearizableReadLoop","detail":"{readStateIndex:9347; appliedIndex:9344; }","duration":"143.479201ms","start":"2026-09-04T00:41:03.635674Z","end":"2026-09-04T00:41:03.779153Z","steps":["trace[1102036858] 'read index received' (duration: 143.022078ms)","trace[1102036858] 'applied index is now lower than readState.Index' (duration: 456.033µs)"],"step_count":2} {"level":"info","ts":"2026-09-04T00:41:03.779274Z","caller":"traceutil/trace.go:171","msg":"trace[1005026136] transaction","detail":"{read_only:false; response_revision:9025; number_of_response:1; }","duration":"208.1259ms","start":"2026-09-04T00:41:03.571131Z","end":"2026-09-04T00:41:03.779257Z","steps":["trace[1005026136] 'process raft request' (duration: 207.909884ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:41:03.779362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.910329ms","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-09-04T00:41:03.779364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.681097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-09-04T00:41:03.779363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.364061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-04T00:41:03.77939Z","caller":"traceutil/trace.go:171","msg":"trace[1905621037] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9025; }","duration":"136.986462ms","start":"2026-09-04T00:41:03.642396Z","end":"2026-09-04T00:41:03.779383Z","steps":["trace[1905621037] 'agreement among raft nodes before linearized reading' (duration: 136.881299ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:41:03.779408Z","caller":"traceutil/trace.go:171","msg":"trace[1876596175] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9025; }","duration":"128.468065ms","start":"2026-09-04T00:41:03.650933Z","end":"2026-09-04T00:41:03.779401Z","steps":["trace[1876596175] 'agreement among raft nodes before linearized reading' (duration: 128.367812ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:41:03.779411Z","caller":"traceutil/trace.go:171","msg":"trace[1769392084] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9025; }","duration":"143.763819ms","start":"2026-09-04T00:41:03.635631Z","end":"2026-09-04T00:41:03.779395Z","steps":["trace[1769392084] 'agreement among raft nodes before linearized reading' (duration: 143.630665ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:41:03.907065Z","caller":"traceutil/trace.go:171","msg":"trace[602303515] linearizableReadLoop","detail":"{readStateIndex:9350; appliedIndex:9347; }","duration":"110.869353ms","start":"2026-09-04T00:41:03.796175Z","end":"2026-09-04T00:41:03.907045Z","steps":["trace[602303515] 'read index received' (duration: 8.640371ms)","trace[602303515] 'applied index is now lower than readState.Index' (duration: 102.228132ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T00:41:03.907161Z","caller":"traceutil/trace.go:171","msg":"trace[839112078] transaction","detail":"{read_only:false; response_revision:9027; number_of_response:1; }","duration":"123.251403ms","start":"2026-09-04T00:41:03.783855Z","end":"2026-09-04T00:41:03.907106Z","steps":["trace[839112078] 'process raft request' (duration: 97.967097ms)","trace[839112078] 'compare' (duration: 25.10359ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T00:41:03.907215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.025848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.118\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-09-04T00:41:03.907194Z","caller":"traceutil/trace.go:171","msg":"trace[729425330] transaction","detail":"{read_only:false; response_revision:9028; number_of_response:1; }","duration":"123.247713ms","start":"2026-09-04T00:41:03.783917Z","end":"2026-09-04T00:41:03.907165Z","steps":["trace[729425330] 'process raft request' (duration: 123.095389ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:41:03.907242Z","caller":"traceutil/trace.go:171","msg":"trace[1369881852] range","detail":"{range_begin:/registry/masterleases/162.253.55.118; range_end:; response_count:1; response_revision:9028; }","duration":"111.09855ms","start":"2026-09-04T00:41:03.796136Z","end":"2026-09-04T00:41:03.907235Z","steps":["trace[1369881852] 'agreement among raft nodes before linearized reading' (duration: 110.984107ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:41:04.371354Z","caller":"traceutil/trace.go:171","msg":"trace[1573330463] transaction","detail":"{read_only:false; response_revision:9030; number_of_response:1; }","duration":"137.783805ms","start":"2026-09-04T00:41:04.23354Z","end":"2026-09-04T00:41:04.371324Z","steps":["trace[1573330463] 'process raft request' (duration: 137.60024ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:41:05.571079Z","caller":"traceutil/trace.go:171","msg":"trace[619115371] transaction","detail":"{read_only:false; response_revision:9044; number_of_response:1; }","duration":"101.675216ms","start":"2026-09-04T00:41:05.46938Z","end":"2026-09-04T00:41:05.571055Z","steps":["trace[619115371] 'process raft request' (duration: 101.611354ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:41:05.571622Z","caller":"traceutil/trace.go:171","msg":"trace[1989282293] transaction","detail":"{read_only:false; response_revision:9043; number_of_response:1; }","duration":"103.038535ms","start":"2026-09-04T00:41:05.468568Z","end":"2026-09-04T00:41:05.571607Z","steps":["trace[1989282293] 'process raft request' (duration: 102.258702ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:42:06.828788Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"39c975536cfc1086","local-member-applied-index":10008,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-04T00:42:06.835403Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10008} {"level":"info","ts":"2026-09-04T00:42:06.835504Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5008} {"level":"warn","ts":"2026-09-04T00:42:20.48069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.389565ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-04T00:42:20.480836Z","caller":"traceutil/trace.go:171","msg":"trace[296928491] transaction","detail":"{read_only:false; response_revision:9813; number_of_response:1; }","duration":"149.046032ms","start":"2026-09-04T00:42:20.331765Z","end":"2026-09-04T00:42:20.480811Z","steps":["trace[296928491] 'process raft request' (duration: 24.42121ms)","trace[296928491] 'compare' (duration: 124.251152ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T00:42:34.341279Z","caller":"traceutil/trace.go:171","msg":"trace[1250479638] transaction","detail":"{read_only:false; response_revision:9888; number_of_response:1; }","duration":"216.819983ms","start":"2026-09-04T00:42:34.124411Z","end":"2026-09-04T00:42:34.341231Z","steps":["trace[1250479638] 'process raft request' (duration: 176.312865ms)","trace[1250479638] 'compare' (duration: 40.387694ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T00:42:34.499463Z","caller":"traceutil/trace.go:171","msg":"trace[1952721133] linearizableReadLoop","detail":"{readStateIndex:10240; appliedIndex:10237; }","duration":"250.842482ms","start":"2026-09-04T00:42:34.248599Z","end":"2026-09-04T00:42:34.499441Z","steps":["trace[1952721133] 'read index received' (duration: 52.235559ms)","trace[1952721133] 'applied index is now lower than readState.Index' (duration: 198.605933ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T00:42:34.499615Z","caller":"traceutil/trace.go:171","msg":"trace[1657847486] transaction","detail":"{read_only:false; response_revision:9889; number_of_response:1; }","duration":"317.782258ms","start":"2026-09-04T00:42:34.18182Z","end":"2026-09-04T00:42:34.499602Z","steps":["trace[1657847486] 'process raft request' (duration: 283.397018ms)","trace[1657847486] 'compare' (duration: 33.897445ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T00:42:34.49971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T00:42:34.181803Z","time spent":"317.84148ms","remote":"127.0.0.1:54390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-04T00:42:34.499847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.215813ms","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-09-04T00:42:34.499866Z","caller":"traceutil/trace.go:171","msg":"trace[1455181341] transaction","detail":"{read_only:false; response_revision:9890; number_of_response:1; }","duration":"315.77698ms","start":"2026-09-04T00:42:34.184043Z","end":"2026-09-04T00:42:34.49982Z","steps":["trace[1455181341] 'process raft request' (duration: 315.300886ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:42:34.499922Z","caller":"traceutil/trace.go:171","msg":"trace[1425323390] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9890; }","duration":"251.337926ms","start":"2026-09-04T00:42:34.248566Z","end":"2026-09-04T00:42:34.499904Z","steps":["trace[1425323390] 'agreement among raft nodes before linearized reading' (duration: 251.10826ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:42:34.499984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T00:42:34.184027Z","time spent":"315.897423ms","remote":"127.0.0.1:54390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-04T00:42:34.500083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.486996ms","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-09-04T00:42:34.500261Z","caller":"traceutil/trace.go:171","msg":"trace[1158519620] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9890; }","duration":"116.814546ms","start":"2026-09-04T00:42:34.383421Z","end":"2026-09-04T00:42:34.500236Z","steps":["trace[1158519620] 'agreement among raft nodes before linearized reading' (duration: 116.300561ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:43:50.374691Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8035} {"level":"info","ts":"2026-09-04T00:43:50.449288Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8035,"took":"72.505699ms","hash":2337012584,"current-db-size-bytes":57167872,"current-db-size":"57 MB","current-db-size-in-use-bytes":27938816,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-04T00:43:50.449377Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2337012584,"revision":8035,"compact-revision":5868} {"level":"info","ts":"2026-09-04T00:47:55.285889Z","caller":"traceutil/trace.go:171","msg":"trace[1908899044] transaction","detail":"{read_only:false; response_revision:12220; number_of_response:1; }","duration":"111.737439ms","start":"2026-09-04T00:47:55.174127Z","end":"2026-09-04T00:47:55.285864Z","steps":["trace[1908899044] 'process raft request' (duration: 111.585815ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:48:13.983924Z","caller":"traceutil/trace.go:171","msg":"trace[1921689093] linearizableReadLoop","detail":"{readStateIndex:12751; appliedIndex:12750; }","duration":"160.875493ms","start":"2026-09-04T00:48:13.823022Z","end":"2026-09-04T00:48:13.983898Z","steps":["trace[1921689093] 'read index received' (duration: 149.400638ms)","trace[1921689093] 'applied index is now lower than readState.Index' (duration: 11.473475ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T00:48:13.983967Z","caller":"traceutil/trace.go:171","msg":"trace[573415306] transaction","detail":"{read_only:false; response_revision:12325; number_of_response:1; }","duration":"201.808907ms","start":"2026-09-04T00:48:13.782121Z","end":"2026-09-04T00:48:13.98393Z","steps":["trace[573415306] 'process raft request' (duration: 190.358713ms)","trace[573415306] 'compare' (duration: 11.036232ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T00:48:13.984825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.776589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.118\" ","response":"range_response_count:1 size:138"} {"level":"info","ts":"2026-09-04T00:48:13.984898Z","caller":"traceutil/trace.go:171","msg":"trace[85018895] range","detail":"{range_begin:/registry/masterleases/162.253.55.118; range_end:; response_count:1; response_revision:12325; }","duration":"161.903732ms","start":"2026-09-04T00:48:13.822975Z","end":"2026-09-04T00:48:13.984878Z","steps":["trace[85018895] 'agreement among raft nodes before linearized reading' (duration: 161.078268ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:48:14.226429Z","caller":"traceutil/trace.go:171","msg":"trace[796695551] transaction","detail":"{read_only:false; response_revision:12327; number_of_response:1; }","duration":"130.769064ms","start":"2026-09-04T00:48:14.095632Z","end":"2026-09-04T00:48:14.226401Z","steps":["trace[796695551] 'process raft request' (duration: 130.569138ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:48:14.388875Z","caller":"traceutil/trace.go:171","msg":"trace[308892228] linearizableReadLoop","detail":"{readStateIndex:12755; appliedIndex:12754; }","duration":"118.97417ms","start":"2026-09-04T00:48:14.269869Z","end":"2026-09-04T00:48:14.388843Z","steps":["trace[308892228] 'read index received' (duration: 99.566154ms)","trace[308892228] 'applied index is now lower than readState.Index' (duration: 19.406916ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T00:48:14.38895Z","caller":"traceutil/trace.go:171","msg":"trace[408664799] transaction","detail":"{read_only:false; response_revision:12328; number_of_response:1; }","duration":"158.318078ms","start":"2026-09-04T00:48:14.230597Z","end":"2026-09-04T00:48:14.388915Z","steps":["trace[408664799] 'process raft request' (duration: 138.968054ms)","trace[408664799] 'compare' (duration: 19.147858ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T00:48:14.389063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.128605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T00:48:14.38919Z","caller":"traceutil/trace.go:171","msg":"trace[1846683656] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:12328; }","duration":"119.349582ms","start":"2026-09-04T00:48:14.269823Z","end":"2026-09-04T00:48:14.389173Z","steps":["trace[1846683656] 'agreement among raft nodes before linearized reading' (duration: 119.124345ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:48:14.389241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.409127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-09-04T00:48:14.389239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.514157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-04T00:48:14.389222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.631994ms","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-09-04T00:48:14.389279Z","caller":"traceutil/trace.go:171","msg":"trace[1801991622] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:12328; }","duration":"102.48909ms","start":"2026-09-04T00:48:14.286776Z","end":"2026-09-04T00:48:14.389265Z","steps":["trace[1801991622] 'agreement among raft nodes before linearized reading' (duration: 102.322615ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:48:14.389326Z","caller":"traceutil/trace.go:171","msg":"trace[1703617478] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:12328; }","duration":"113.763549ms","start":"2026-09-04T00:48:14.275535Z","end":"2026-09-04T00:48:14.389298Z","steps":["trace[1703617478] 'agreement among raft nodes before linearized reading' (duration: 113.491061ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:48:14.389378Z","caller":"traceutil/trace.go:171","msg":"trace[428028952] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:12328; }","duration":"118.781875ms","start":"2026-09-04T00:48:14.270494Z","end":"2026-09-04T00:48:14.389276Z","steps":["trace[428028952] 'agreement among raft nodes before linearized reading' (duration: 118.513497ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:48:14.389437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.861839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-04T00:48:14.389493Z","caller":"traceutil/trace.go:171","msg":"trace[1240590567] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:12328; }","duration":"103.938502ms","start":"2026-09-04T00:48:14.285538Z","end":"2026-09-04T00:48:14.389476Z","steps":["trace[1240590567] 'agreement among raft nodes before linearized reading' (duration: 103.795808ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:48:14.389624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.399154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-04T00:48:14.389691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.485257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5077"} {"level":"info","ts":"2026-09-04T00:48:14.389711Z","caller":"traceutil/trace.go:171","msg":"trace[489217933] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:12328; }","duration":"118.516797ms","start":"2026-09-04T00:48:14.27118Z","end":"2026-09-04T00:48:14.389697Z","steps":["trace[489217933] 'agreement among raft nodes before linearized reading' (duration: 118.407244ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:48:14.389725Z","caller":"traceutil/trace.go:171","msg":"trace[1362146884] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:12328; }","duration":"118.560679ms","start":"2026-09-04T00:48:14.271155Z","end":"2026-09-04T00:48:14.389715Z","steps":["trace[1362146884] 'agreement among raft nodes before linearized reading' (duration: 118.465496ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:48:14.38983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.593319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T00:48:14.38986Z","caller":"traceutil/trace.go:171","msg":"trace[1074074840] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:12328; }","duration":"118.646701ms","start":"2026-09-04T00:48:14.271207Z","end":"2026-09-04T00:48:14.389853Z","steps":["trace[1074074840] 'agreement among raft nodes before linearized reading' (duration: 118.603759ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:48:14.389949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.834466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/cinder-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-09-04T00:48:14.390003Z","caller":"traceutil/trace.go:171","msg":"trace[1079646697] range","detail":"{range_begin:/registry/services/endpoints/openstack/cinder-api; range_end:; response_count:1; response_revision:12328; }","duration":"118.906049ms","start":"2026-09-04T00:48:14.271083Z","end":"2026-09-04T00:48:14.38999Z","steps":["trace[1079646697] 'agreement among raft nodes before linearized reading' (duration: 118.60185ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:48:15.229729Z","caller":"traceutil/trace.go:171","msg":"trace[264720119] transaction","detail":"{read_only:false; response_revision:12333; number_of_response:1; }","duration":"272.719235ms","start":"2026-09-04T00:48:14.956985Z","end":"2026-09-04T00:48:15.229704Z","steps":["trace[264720119] 'process raft request' (duration: 272.539299ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:48:50.380636Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10401} {"level":"info","ts":"2026-09-04T00:48:50.471374Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10401,"took":"87.08472ms","hash":1579557660,"current-db-size-bytes":57167872,"current-db-size":"57 MB","current-db-size-in-use-bytes":25780224,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-04T00:48:50.471444Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1579557660,"revision":10401,"compact-revision":8035} {"level":"info","ts":"2026-09-04T00:53:50.388775Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12569} {"level":"info","ts":"2026-09-04T00:53:50.472826Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12569,"took":"81.649395ms","hash":3208583397,"current-db-size-bytes":57167872,"current-db-size":"57 MB","current-db-size-in-use-bytes":27516928,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-04T00:53:50.472926Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3208583397,"revision":12569,"compact-revision":10401} {"level":"info","ts":"2026-09-04T00:54:21.405969Z","caller":"traceutil/trace.go:171","msg":"trace[745382057] transaction","detail":"{read_only:false; response_revision:14806; number_of_response:1; }","duration":"184.15578ms","start":"2026-09-04T00:54:21.221779Z","end":"2026-09-04T00:54:21.405935Z","steps":["trace[745382057] 'process raft request' (duration: 84.699115ms)","trace[745382057] 'compare' (duration: 98.95039ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T00:54:25.025111Z","caller":"traceutil/trace.go:171","msg":"trace[1023033311] transaction","detail":"{read_only:false; response_revision:14831; number_of_response:1; }","duration":"173.360715ms","start":"2026-09-04T00:54:24.851708Z","end":"2026-09-04T00:54:25.025069Z","steps":["trace[1023033311] 'process raft request' (duration: 171.165901ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:54:45.465202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.977459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-tbzbk\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-09-04T00:54:45.465295Z","caller":"traceutil/trace.go:171","msg":"trace[1534397707] range","detail":"{range_begin:/registry/pods/openstack/keepalived-tbzbk; range_end:; response_count:1; response_revision:14933; }","duration":"229.158934ms","start":"2026-09-04T00:54:45.23612Z","end":"2026-09-04T00:54:45.465279Z","steps":["trace[1534397707] 'range keys from in-memory index tree' (duration: 228.786514ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:54:45.465354Z","caller":"traceutil/trace.go:171","msg":"trace[128724632] transaction","detail":"{read_only:false; response_revision:14934; number_of_response:1; }","duration":"166.800913ms","start":"2026-09-04T00:54:45.2985Z","end":"2026-09-04T00:54:45.465301Z","steps":["trace[128724632] 'process raft request' (duration: 127.912687ms)","trace[128724632] 'compare' (duration: 38.748382ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T00:55:03.099021Z","caller":"traceutil/trace.go:171","msg":"trace[7503592] transaction","detail":"{read_only:false; response_revision:15041; number_of_response:1; }","duration":"113.750733ms","start":"2026-09-04T00:55:02.985247Z","end":"2026-09-04T00:55:03.098997Z","steps":["trace[7503592] 'process raft request' (duration: 113.603469ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:55:03.295985Z","caller":"traceutil/trace.go:171","msg":"trace[1738386395] linearizableReadLoop","detail":"{readStateIndex:15567; appliedIndex:15566; }","duration":"114.617528ms","start":"2026-09-04T00:55:03.181337Z","end":"2026-09-04T00:55:03.295954Z","steps":["trace[1738386395] 'read index received' (duration: 111.789186ms)","trace[1738386395] 'applied index is now lower than readState.Index' (duration: 2.827112ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T00:55:03.296111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.753812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T00:55:03.296097Z","caller":"traceutil/trace.go:171","msg":"trace[720198975] transaction","detail":"{read_only:false; response_revision:15042; number_of_response:1; }","duration":"119.697317ms","start":"2026-09-04T00:55:03.176355Z","end":"2026-09-04T00:55:03.296053Z","steps":["trace[720198975] 'process raft request' (duration: 116.756271ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:55:03.296146Z","caller":"traceutil/trace.go:171","msg":"trace[732911455] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:15042; }","duration":"114.845245ms","start":"2026-09-04T00:55:03.18129Z","end":"2026-09-04T00:55:03.296135Z","steps":["trace[732911455] 'agreement among raft nodes before linearized reading' (duration: 114.760462ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:55:03.301707Z","caller":"traceutil/trace.go:171","msg":"trace[1960250041] transaction","detail":"{read_only:false; response_revision:15043; number_of_response:1; }","duration":"111.404635ms","start":"2026-09-04T00:55:03.190277Z","end":"2026-09-04T00:55:03.301682Z","steps":["trace[1960250041] 'process raft request' (duration: 111.25994ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:56:49.83051Z","caller":"traceutil/trace.go:171","msg":"trace[2087164714] transaction","detail":"{read_only:false; response_revision:16153; number_of_response:1; }","duration":"163.616596ms","start":"2026-09-04T00:56:49.666865Z","end":"2026-09-04T00:56:49.830482Z","steps":["trace[2087164714] 'process raft request' (duration: 163.446551ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:56:49.851215Z","caller":"traceutil/trace.go:171","msg":"trace[1182342800] transaction","detail":"{read_only:false; response_revision:16154; number_of_response:1; }","duration":"144.588481ms","start":"2026-09-04T00:56:49.706604Z","end":"2026-09-04T00:56:49.851193Z","steps":["trace[1182342800] 'process raft request' (duration: 144.437187ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:56:49.85137Z","caller":"traceutil/trace.go:171","msg":"trace[929767625] transaction","detail":"{read_only:false; response_revision:16155; number_of_response:1; }","duration":"142.626585ms","start":"2026-09-04T00:56:49.70873Z","end":"2026-09-04T00:56:49.851357Z","steps":["trace[929767625] 'process raft request' (duration: 142.421069ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:56:49.851355Z","caller":"traceutil/trace.go:171","msg":"trace[1533597932] linearizableReadLoop","detail":"{readStateIndex:16720; appliedIndex:16717; }","duration":"139.014279ms","start":"2026-09-04T00:56:49.712321Z","end":"2026-09-04T00:56:49.851335Z","steps":["trace[1533597932] 'read index received' (duration: 117.965666ms)","trace[1533597932] 'applied index is now lower than readState.Index' (duration: 21.047573ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T00:56:49.851449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.106042ms","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-09-04T00:56:49.851503Z","caller":"traceutil/trace.go:171","msg":"trace[1267612890] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16155; }","duration":"139.185185ms","start":"2026-09-04T00:56:49.712292Z","end":"2026-09-04T00:56:49.851477Z","steps":["trace[1267612890] 'agreement among raft nodes before linearized reading' (duration: 139.099982ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:56:49.851754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.073981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-09-04T00:56:49.851793Z","caller":"traceutil/trace.go:171","msg":"trace[899972837] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16155; }","duration":"105.153093ms","start":"2026-09-04T00:56:49.746628Z","end":"2026-09-04T00:56:49.851781Z","steps":["trace[899972837] 'agreement among raft nodes before linearized reading' (duration: 105.03398ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:56:49.851779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.931947ms","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-09-04T00:56:49.851907Z","caller":"traceutil/trace.go:171","msg":"trace[1617098356] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16155; }","duration":"139.092412ms","start":"2026-09-04T00:56:49.712777Z","end":"2026-09-04T00:56:49.851869Z","steps":["trace[1617098356] 'agreement among raft nodes before linearized reading' (duration: 138.763092ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:56:55.316674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.570733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-4mwqw\" ","response":"range_response_count:1 size:22100"} {"level":"info","ts":"2026-09-04T00:56:55.31689Z","caller":"traceutil/trace.go:171","msg":"trace[1818063386] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-4mwqw; range_end:; response_count:1; response_revision:16180; }","duration":"107.82779ms","start":"2026-09-04T00:56:55.209024Z","end":"2026-09-04T00:56:55.316852Z","steps":["trace[1818063386] 'range keys from in-memory index tree' (duration: 107.337667ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:56:55.316704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.931663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:356"} {"level":"info","ts":"2026-09-04T00:56:55.317778Z","caller":"traceutil/trace.go:171","msg":"trace[1368335568] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:16180; }","duration":"156.059505ms","start":"2026-09-04T00:56:55.161688Z","end":"2026-09-04T00:56:55.317747Z","steps":["trace[1368335568] 'range keys from in-memory index tree' (duration: 154.740517ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:56:55.517411Z","caller":"traceutil/trace.go:171","msg":"trace[2064118573] transaction","detail":"{read_only:false; response_revision:16182; number_of_response:1; }","duration":"148.231437ms","start":"2026-09-04T00:56:55.369089Z","end":"2026-09-04T00:56:55.51732Z","steps":["trace[2064118573] 'process raft request' (duration: 120.927032ms)","trace[2064118573] 'compare' (duration: 27.12539ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T00:56:55.525227Z","caller":"traceutil/trace.go:171","msg":"trace[736100599] transaction","detail":"{read_only:false; response_revision:16183; number_of_response:1; }","duration":"154.331585ms","start":"2026-09-04T00:56:55.370678Z","end":"2026-09-04T00:56:55.52501Z","steps":["trace[736100599] 'process raft request' (duration: 154.181411ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:56:56.481588Z","caller":"traceutil/trace.go:171","msg":"trace[559531070] linearizableReadLoop","detail":"{readStateIndex:16755; appliedIndex:16754; }","duration":"184.984578ms","start":"2026-09-04T00:56:56.296579Z","end":"2026-09-04T00:56:56.481563Z","steps":["trace[559531070] 'read index received' (duration: 184.881605ms)","trace[559531070] 'applied index is now lower than readState.Index' (duration: 101.423µs)"],"step_count":2} {"level":"info","ts":"2026-09-04T00:56:56.481728Z","caller":"traceutil/trace.go:171","msg":"trace[859517389] transaction","detail":"{read_only:false; response_revision:16189; number_of_response:1; }","duration":"202.047435ms","start":"2026-09-04T00:56:56.279669Z","end":"2026-09-04T00:56:56.481716Z","steps":["trace[859517389] 'process raft request' (duration: 201.730506ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:56:56.481928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.320878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T00:56:56.481963Z","caller":"traceutil/trace.go:171","msg":"trace[689577556] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:16189; }","duration":"185.38943ms","start":"2026-09-04T00:56:56.29656Z","end":"2026-09-04T00:56:56.48195Z","steps":["trace[689577556] 'agreement among raft nodes before linearized reading' (duration: 185.310668ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:56:56.482495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.981836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-09-04T00:56:56.4826Z","caller":"traceutil/trace.go:171","msg":"trace[1390661462] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:16189; }","duration":"164.144821ms","start":"2026-09-04T00:56:56.318429Z","end":"2026-09-04T00:56:56.482574Z","steps":["trace[1390661462] 'agreement among raft nodes before linearized reading' (duration: 163.860143ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:56:56.890267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.234418ms","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-09-04T00:56:56.890357Z","caller":"traceutil/trace.go:171","msg":"trace[1837449694] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16189; }","duration":"114.424873ms","start":"2026-09-04T00:56:56.77591Z","end":"2026-09-04T00:56:56.890335Z","steps":["trace[1837449694] 'range keys from in-memory index tree' (duration: 114.146115ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:56:56.890416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.745693ms","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-09-04T00:56:56.890451Z","caller":"traceutil/trace.go:171","msg":"trace[1344766249] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16189; }","duration":"113.793984ms","start":"2026-09-04T00:56:56.776645Z","end":"2026-09-04T00:56:56.890439Z","steps":["trace[1344766249] 'range keys from in-memory index tree' (duration: 113.62897ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:56:56.890554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.504902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:681"} {"level":"info","ts":"2026-09-04T00:56:56.890654Z","caller":"traceutil/trace.go:171","msg":"trace[1711540799] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:16189; }","duration":"106.638147ms","start":"2026-09-04T00:56:56.783989Z","end":"2026-09-04T00:56:56.890627Z","steps":["trace[1711540799] 'range keys from in-memory index tree' (duration: 106.332708ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:56:56.890677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.613169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-tbzbk\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-09-04T00:56:56.89072Z","caller":"traceutil/trace.go:171","msg":"trace[230969506] range","detail":"{range_begin:/registry/pods/openstack/keepalived-tbzbk; range_end:; response_count:1; response_revision:16189; }","duration":"113.686381ms","start":"2026-09-04T00:56:56.777023Z","end":"2026-09-04T00:56:56.890709Z","steps":["trace[230969506] 'range keys from in-memory index tree' (duration: 113.489216ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:56:56.890771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.549928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"warn","ts":"2026-09-04T00:56:56.890295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.570611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T00:56:56.890818Z","caller":"traceutil/trace.go:171","msg":"trace[820438784] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16189; }","duration":"113.62238ms","start":"2026-09-04T00:56:56.777173Z","end":"2026-09-04T00:56:56.890795Z","steps":["trace[820438784] 'range keys from in-memory index tree' (duration: 113.391193ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:56:56.890836Z","caller":"traceutil/trace.go:171","msg":"trace[1676684317] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:16189; }","duration":"111.145268ms","start":"2026-09-04T00:56:56.77968Z","end":"2026-09-04T00:56:56.890826Z","steps":["trace[1676684317] 'range keys from in-memory index tree' (duration: 110.483448ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:56:56.890602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.398979ms","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-09-04T00:56:56.890924Z","caller":"traceutil/trace.go:171","msg":"trace[514628392] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:16189; }","duration":"255.804421ms","start":"2026-09-04T00:56:56.6351Z","end":"2026-09-04T00:56:56.890905Z","steps":["trace[514628392] 'range keys from in-memory index tree' (duration: 255.265716ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:58:50.39535Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14571} {"level":"info","ts":"2026-09-04T00:58:50.481929Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14571,"took":"82.982417ms","hash":3529970230,"current-db-size-bytes":57167872,"current-db-size":"57 MB","current-db-size-in-use-bytes":31207424,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-04T00:58:50.482015Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3529970230,"revision":14571,"compact-revision":12569} {"level":"info","ts":"2026-09-04T01:03:08.915723Z","caller":"traceutil/trace.go:171","msg":"trace[1294007071] linearizableReadLoop","detail":"{readStateIndex:19591; appliedIndex:19590; }","duration":"107.467939ms","start":"2026-09-04T01:03:08.808235Z","end":"2026-09-04T01:03:08.915703Z","steps":["trace[1294007071] 'read index received' (duration: 107.278073ms)","trace[1294007071] 'applied index is now lower than readState.Index' (duration: 189.016µs)"],"step_count":2} {"level":"warn","ts":"2026-09-04T01:03:08.915925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.668884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-4mwqw\" ","response":"range_response_count:1 size:22101"} {"level":"info","ts":"2026-09-04T01:03:08.915959Z","caller":"traceutil/trace.go:171","msg":"trace[290757336] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-4mwqw; range_end:; response_count:1; response_revision:18935; }","duration":"107.744287ms","start":"2026-09-04T01:03:08.808206Z","end":"2026-09-04T01:03:08.91595Z","steps":["trace[290757336] 'agreement among raft nodes before linearized reading' (duration: 107.578432ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T01:03:08.916227Z","caller":"traceutil/trace.go:171","msg":"trace[1620289406] transaction","detail":"{read_only:false; response_revision:18935; number_of_response:1; }","duration":"158.138549ms","start":"2026-09-04T01:03:08.75808Z","end":"2026-09-04T01:03:08.916219Z","steps":["trace[1620289406] 'process raft request' (duration: 157.49766ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T01:03:16.084124Z","caller":"traceutil/trace.go:171","msg":"trace[198221531] transaction","detail":"{read_only:false; response_revision:18983; number_of_response:1; }","duration":"110.146177ms","start":"2026-09-04T01:03:15.973947Z","end":"2026-09-04T01:03:16.084093Z","steps":["trace[198221531] 'process raft request' (duration: 109.383555ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T01:03:16.460032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.196779ms","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-09-04T01:03:16.460179Z","caller":"traceutil/trace.go:171","msg":"trace[209051714] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:18983; }","duration":"235.413014ms","start":"2026-09-04T01:03:16.224725Z","end":"2026-09-04T01:03:16.460138Z","steps":["trace[209051714] 'count revisions from in-memory index tree' (duration: 235.069135ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T01:03:16.460518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.463268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-04T01:03:16.460601Z","caller":"traceutil/trace.go:171","msg":"trace[619924159] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:18983; }","duration":"233.608202ms","start":"2026-09-04T01:03:16.226974Z","end":"2026-09-04T01:03:16.460582Z","steps":["trace[619924159] 'count revisions from in-memory index tree' (duration: 233.345315ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T01:03:16.89728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.616963ms","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-09-04T01:03:16.897416Z","caller":"traceutil/trace.go:171","msg":"trace[345325186] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18985; }","duration":"308.84717ms","start":"2026-09-04T01:03:16.588534Z","end":"2026-09-04T01:03:16.897381Z","steps":["trace[345325186] 'range keys from in-memory index tree' (duration: 308.356466ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T01:03:16.897477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T01:03:16.58851Z","time spent":"308.949982ms","remote":"127.0.0.1:54390","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-09-04T01:03:17.388133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.128807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-9cls2\" ","response":"range_response_count:1 size:9792"} {"level":"info","ts":"2026-09-04T01:03:17.388225Z","caller":"traceutil/trace.go:171","msg":"trace[353161819] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-9cls2; range_end:; response_count:1; response_revision:18986; }","duration":"235.290062ms","start":"2026-09-04T01:03:17.152916Z","end":"2026-09-04T01:03:17.388206Z","steps":["trace[353161819] 'range keys from in-memory index tree' (duration: 234.91483ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T01:03:17.38827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.944511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-04T01:03:17.388367Z","caller":"traceutil/trace.go:171","msg":"trace[681884152] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:18986; }","duration":"210.135877ms","start":"2026-09-04T01:03:17.178211Z","end":"2026-09-04T01:03:17.388347Z","steps":["trace[681884152] 'count revisions from in-memory index tree' (duration: 209.836739ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T01:03:17.389335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.25499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-4mwqw\" ","response":"range_response_count:1 size:22101"} {"level":"info","ts":"2026-09-04T01:03:17.389422Z","caller":"traceutil/trace.go:171","msg":"trace[202016223] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-4mwqw; range_end:; response_count:1; response_revision:18986; }","duration":"311.873089ms","start":"2026-09-04T01:03:17.077531Z","end":"2026-09-04T01:03:17.389404Z","steps":["trace[202016223] 'range keys from in-memory index tree' (duration: 311.051745ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T01:03:17.389462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T01:03:17.077507Z","time spent":"311.942101ms","remote":"127.0.0.1:54330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22124,"request content":"key:\"/registry/pods/openstack/nova-compute-default-4mwqw\" "} {"level":"info","ts":"2026-09-04T01:03:17.598122Z","caller":"traceutil/trace.go:171","msg":"trace[396761123] linearizableReadLoop","detail":"{readStateIndex:19645; appliedIndex:19644; }","duration":"204.641706ms","start":"2026-09-04T01:03:17.393448Z","end":"2026-09-04T01:03:17.59809Z","steps":["trace[396761123] 'read index received' (duration: 204.45517ms)","trace[396761123] 'applied index is now lower than readState.Index' (duration: 185.016µs)"],"step_count":2} {"level":"info","ts":"2026-09-04T01:03:17.598535Z","caller":"traceutil/trace.go:171","msg":"trace[1967196831] transaction","detail":"{read_only:false; response_revision:18987; number_of_response:1; }","duration":"206.380467ms","start":"2026-09-04T01:03:17.392141Z","end":"2026-09-04T01:03:17.598521Z","steps":["trace[1967196831] 'process raft request' (duration: 205.78054ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T01:03:17.599626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.168261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:96 size:962196"} {"level":"info","ts":"2026-09-04T01:03:17.599671Z","caller":"traceutil/trace.go:171","msg":"trace[1204445082] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:96; response_revision:18987; }","duration":"206.230593ms","start":"2026-09-04T01:03:17.393429Z","end":"2026-09-04T01:03:17.599659Z","steps":["trace[1204445082] 'agreement among raft nodes before linearized reading' (duration: 205.317566ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T01:03:17.704906Z","caller":"traceutil/trace.go:171","msg":"trace[25709164] linearizableReadLoop","detail":"{readStateIndex:19648; appliedIndex:19645; }","duration":"106.675125ms","start":"2026-09-04T01:03:17.598198Z","end":"2026-09-04T01:03:17.704873Z","steps":["trace[25709164] 'read index received' (duration: 98.893008ms)","trace[25709164] 'applied index is now lower than readState.Index' (duration: 7.780637ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T01:03:17.705353Z","caller":"traceutil/trace.go:171","msg":"trace[1708761968] transaction","detail":"{read_only:false; response_revision:18988; number_of_response:1; }","duration":"286.124266ms","start":"2026-09-04T01:03:17.419213Z","end":"2026-09-04T01:03:17.705338Z","steps":["trace[1708761968] 'process raft request' (duration: 277.950297ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T01:03:17.705654Z","caller":"traceutil/trace.go:171","msg":"trace[655550418] transaction","detail":"{read_only:false; response_revision:18989; number_of_response:1; }","duration":"285.481387ms","start":"2026-09-04T01:03:17.420159Z","end":"2026-09-04T01:03:17.705641Z","steps":["trace[655550418] 'process raft request' (duration: 284.608791ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T01:03:17.705808Z","caller":"traceutil/trace.go:171","msg":"trace[539628655] transaction","detail":"{read_only:false; response_revision:18990; number_of_response:1; }","duration":"219.535411ms","start":"2026-09-04T01:03:17.486261Z","end":"2026-09-04T01:03:17.705796Z","steps":["trace[539628655] 'process raft request' (duration: 218.559303ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T01:03:17.706032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.212773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-04T01:03:17.706356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.491991ms","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-09-04T01:03:17.706422Z","caller":"traceutil/trace.go:171","msg":"trace[1081414530] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:18990; }","duration":"269.631775ms","start":"2026-09-04T01:03:17.43677Z","end":"2026-09-04T01:03:17.706402Z","steps":["trace[1081414530] 'agreement among raft nodes before linearized reading' (duration: 269.43895ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T01:03:17.707093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.412577ms","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-09-04T01:03:17.70718Z","caller":"traceutil/trace.go:171","msg":"trace[1605376003] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18990; }","duration":"270.544701ms","start":"2026-09-04T01:03:17.436615Z","end":"2026-09-04T01:03:17.707159Z","steps":["trace[1605376003] 'agreement among raft nodes before linearized reading' (duration: 270.355356ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T01:03:17.707477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.823536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:96 size:962196"} {"level":"info","ts":"2026-09-04T01:03:17.707603Z","caller":"traceutil/trace.go:171","msg":"trace[461442132] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:96; response_revision:18990; }","duration":"312.001752ms","start":"2026-09-04T01:03:17.395579Z","end":"2026-09-04T01:03:17.70758Z","steps":["trace[461442132] 'agreement among raft nodes before linearized reading' (duration: 310.742355ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T01:03:17.707657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T01:03:17.395543Z","time spent":"312.100564ms","remote":"127.0.0.1:54330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":96,"response size":962219,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-09-04T01:03:17.70867Z","caller":"traceutil/trace.go:171","msg":"trace[515692916] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:18990; }","duration":"155.350357ms","start":"2026-09-04T01:03:17.55072Z","end":"2026-09-04T01:03:17.70607Z","steps":["trace[515692916] 'agreement among raft nodes before linearized reading' (duration: 155.230314ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T01:03:50.404285Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16901} {"level":"info","ts":"2026-09-04T01:03:50.513872Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16901,"took":"105.566933ms","hash":1644105052,"current-db-size-bytes":57167872,"current-db-size":"57 MB","current-db-size-in-use-bytes":32632832,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-09-04T01:03:50.513949Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1644105052,"revision":16901,"compact-revision":14571} {"level":"info","ts":"2026-09-04T01:03:51.847196Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"39c975536cfc1086","local-member-applied-index":20009,"local-member-snapshot-index":10008,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-04T01:03:51.853398Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20009} {"level":"info","ts":"2026-09-04T01:03:51.853509Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15009} {"level":"info","ts":"2026-09-04T01:08:50.411969Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19334} {"level":"info","ts":"2026-09-04T01:08:50.554687Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19334,"took":"138.583479ms","hash":1288512084,"current-db-size-bytes":57167872,"current-db-size":"57 MB","current-db-size-in-use-bytes":30502912,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-04T01:08:50.55477Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1288512084,"revision":19334,"compact-revision":16901} {"level":"info","ts":"2026-09-04T01:08:51.951995Z","caller":"traceutil/trace.go:171","msg":"trace[1649530935] transaction","detail":"{read_only:false; response_revision:21663; number_of_response:1; }","duration":"126.987481ms","start":"2026-09-04T01:08:51.824983Z","end":"2026-09-04T01:08:51.95197Z","steps":["trace[1649530935] 'process raft request' (duration: 126.854517ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T01:09:14.269659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.277303ms","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-09-04T01:09:14.269742Z","caller":"traceutil/trace.go:171","msg":"trace[1127706260] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21775; }","duration":"204.418568ms","start":"2026-09-04T01:09:14.065304Z","end":"2026-09-04T01:09:14.269722Z","steps":["trace[1127706260] 'range keys from in-memory index tree' (duration: 204.108128ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T01:09:29.583248Z","caller":"traceutil/trace.go:171","msg":"trace[2039090583] transaction","detail":"{read_only:false; response_revision:21853; number_of_response:1; }","duration":"105.579327ms","start":"2026-09-04T01:09:29.477648Z","end":"2026-09-04T01:09:29.583227Z","steps":["trace[2039090583] 'process raft request' (duration: 105.448213ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T01:09:35.399811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.917276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-04T01:09:35.39989Z","caller":"traceutil/trace.go:171","msg":"trace[1991049420] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21881; }","duration":"353.027469ms","start":"2026-09-04T01:09:35.046846Z","end":"2026-09-04T01:09:35.399873Z","steps":["trace[1991049420] 'range keys from in-memory index tree' (duration: 352.797632ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T01:09:35.400122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.812078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/neutron-dhcp-agent-default-9fbxz.18d1f899a8690c39\" ","response":"range_response_count:1 size:1395"} {"level":"info","ts":"2026-09-04T01:09:35.400175Z","caller":"traceutil/trace.go:171","msg":"trace[1906756757] range","detail":"{range_begin:/registry/events/openstack/neutron-dhcp-agent-default-9fbxz.18d1f899a8690c39; range_end:; response_count:1; response_revision:21881; }","duration":"310.885021ms","start":"2026-09-04T01:09:35.089276Z","end":"2026-09-04T01:09:35.400161Z","steps":["trace[1906756757] 'range keys from in-memory index tree' (duration: 310.721615ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T01:09:35.40021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T01:09:35.089265Z","time spent":"310.936242ms","remote":"127.0.0.1:54238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":1418,"request content":"key:\"/registry/events/openstack/neutron-dhcp-agent-default-9fbxz.18d1f899a8690c39\" "} {"level":"warn","ts":"2026-09-04T01:09:35.400459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T01:09:35.046834Z","time spent":"353.08149ms","remote":"127.0.0.1:54390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-09-04T01:09:35.58282Z","caller":"traceutil/trace.go:171","msg":"trace[282624719] transaction","detail":"{read_only:false; response_revision:21882; number_of_response:1; }","duration":"484.15077ms","start":"2026-09-04T01:09:35.098637Z","end":"2026-09-04T01:09:35.582788Z","steps":["trace[282624719] 'process raft request' (duration: 483.968695ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T01:09:35.58299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T01:09:35.098616Z","time spent":"484.289264ms","remote":"127.0.0.1:54390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-04T01:09:36.005152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.698656ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-04T01:09:36.00573Z","caller":"traceutil/trace.go:171","msg":"trace[82512710] transaction","detail":"{read_only:false; response_revision:21883; number_of_response:1; }","duration":"905.292165ms","start":"2026-09-04T01:09:35.100375Z","end":"2026-09-04T01:09:36.005667Z","steps":["trace[82512710] 'process raft request' (duration: 747.97138ms)","trace[82512710] 'compare' (duration: 156.368707ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T01:09:36.005804Z","caller":"traceutil/trace.go:171","msg":"trace[2081459314] linearizableReadLoop","detail":"{readStateIndex:22639; appliedIndex:22637; }","duration":"791.352024ms","start":"2026-09-04T01:09:35.2144Z","end":"2026-09-04T01:09:36.005752Z","steps":["trace[2081459314] 'read index received' (duration: 368.214772ms)","trace[2081459314] 'applied index is now lower than readState.Index' (duration: 423.135872ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T01:09:36.006072Z","caller":"traceutil/trace.go:171","msg":"trace[1564978065] transaction","detail":"{read_only:false; response_revision:21884; number_of_response:1; }","duration":"749.963297ms","start":"2026-09-04T01:09:35.256086Z","end":"2026-09-04T01:09:36.006049Z","steps":["trace[1564978065] 'process raft request' (duration: 749.182614ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T01:09:36.006168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T01:09:35.256062Z","time spent":"750.05999ms","remote":"127.0.0.1:54390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-04T01:09:36.006591Z","caller":"traceutil/trace.go:171","msg":"trace[232754304] transaction","detail":"{read_only:false; response_revision:21885; number_of_response:1; }","duration":"741.390307ms","start":"2026-09-04T01:09:35.265186Z","end":"2026-09-04T01:09:36.006576Z","steps":["trace[232754304] 'process raft request' (duration: 740.380368ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T01:09:36.006663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T01:09:35.265166Z","time spent":"741.45912ms","remote":"127.0.0.1:54390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-04T01:09:36.00701Z","caller":"traceutil/trace.go:171","msg":"trace[1155725558] transaction","detail":"{read_only:false; response_revision:21886; number_of_response:1; }","duration":"603.966862ms","start":"2026-09-04T01:09:35.403029Z","end":"2026-09-04T01:09:36.006996Z","steps":["trace[1155725558] 'process raft request' (duration: 602.604473ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T01:09:36.007091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T01:09:35.403002Z","time spent":"604.041235ms","remote":"127.0.0.1:54238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1377,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-04T01:09:36.007389Z","caller":"traceutil/trace.go:171","msg":"trace[1490831911] transaction","detail":"{read_only:false; response_revision:21887; number_of_response:1; }","duration":"602.281333ms","start":"2026-09-04T01:09:35.405092Z","end":"2026-09-04T01:09:36.007374Z","steps":["trace[1490831911] 'process raft request' (duration: 600.609425ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T01:09:36.007503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T01:09:35.405077Z","time spent":"602.377656ms","remote":"127.0.0.1:54390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-04T01:09:36.007761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"793.346612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-04T01:09:36.007816Z","caller":"traceutil/trace.go:171","msg":"trace[895765998] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:21887; }","duration":"793.425544ms","start":"2026-09-04T01:09:35.214371Z","end":"2026-09-04T01:09:36.007797Z","steps":["trace[895765998] 'agreement among raft nodes before linearized reading' (duration: 793.333871ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T01:09:36.007851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T01:09:35.21436Z","time spent":"793.481946ms","remote":"127.0.0.1:54592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":3,"response size":31,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-09-04T01:09:36.008313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"788.709367ms","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-09-04T01:09:36.008356Z","caller":"traceutil/trace.go:171","msg":"trace[48412201] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:21887; }","duration":"788.774249ms","start":"2026-09-04T01:09:35.219571Z","end":"2026-09-04T01:09:36.008345Z","steps":["trace[48412201] 'agreement among raft nodes before linearized reading' (duration: 788.575023ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T01:09:36.008384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T01:09:35.219562Z","time spent":"788.81525ms","remote":"127.0.0.1:54330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-09-04T01:09:36.006141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T01:09:35.10035Z","time spent":"905.5ms","remote":"127.0.0.1:54390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-04T01:09:36.009821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.103083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-09-04T01:09:36.009878Z","caller":"traceutil/trace.go:171","msg":"trace[1302666000] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21887; }","duration":"129.220326ms","start":"2026-09-04T01:09:35.880643Z","end":"2026-09-04T01:09:36.009863Z","steps":["trace[1302666000] 'agreement among raft nodes before linearized reading' (duration: 128.943618ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T01:09:36.010313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.686124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-04T01:09:36.010368Z","caller":"traceutil/trace.go:171","msg":"trace[270997433] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21887; }","duration":"147.866489ms","start":"2026-09-04T01:09:35.862485Z","end":"2026-09-04T01:09:36.010351Z","steps":["trace[270997433] 'agreement among raft nodes before linearized reading' (duration: 147.680804ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T01:09:36.010615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.905694ms","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-09-04T01:09:36.010658Z","caller":"traceutil/trace.go:171","msg":"trace[47696466] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21887; }","duration":"295.949255ms","start":"2026-09-04T01:09:35.714697Z","end":"2026-09-04T01:09:36.010646Z","steps":["trace[47696466] 'agreement among raft nodes before linearized reading' (duration: 295.838942ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T01:09:36.01086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.589589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-04T01:09:36.010906Z","caller":"traceutil/trace.go:171","msg":"trace[1613819686] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:21887; }","duration":"314.697972ms","start":"2026-09-04T01:09:35.696194Z","end":"2026-09-04T01:09:36.010892Z","steps":["trace[1613819686] 'agreement among raft nodes before linearized reading' (duration: 314.600229ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T01:09:36.010941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T01:09:35.696175Z","time spent":"314.753903ms","remote":"127.0.0.1:54964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-09-04T01:09:36.01114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.1617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-04T01:09:36.011186Z","caller":"traceutil/trace.go:171","msg":"trace[1894525730] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21887; }","duration":"543.232152ms","start":"2026-09-04T01:09:35.467939Z","end":"2026-09-04T01:09:36.011171Z","steps":["trace[1894525730] 'agreement among raft nodes before linearized reading' (duration: 543.17128ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T01:09:36.01122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T01:09:35.467922Z","time spent":"543.290164ms","remote":"127.0.0.1:54160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-04T01:09:36.011597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.97156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-04T01:09:36.01165Z","caller":"traceutil/trace.go:171","msg":"trace[2030815515] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:21887; }","duration":"619.057493ms","start":"2026-09-04T01:09:35.392577Z","end":"2026-09-04T01:09:36.011634Z","steps":["trace[2030815515] 'agreement among raft nodes before linearized reading' (duration: 618.97371ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T01:09:36.011685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T01:09:35.392559Z","time spent":"619.115344ms","remote":"127.0.0.1:55302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-09-04T01:09:36.381555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.496524ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-04T01:09:36.38182Z","caller":"traceutil/trace.go:171","msg":"trace[406642752] transaction","detail":"{read_only:false; response_revision:21889; number_of_response:1; }","duration":"359.758615ms","start":"2026-09-04T01:09:36.022038Z","end":"2026-09-04T01:09:36.381797Z","steps":["trace[406642752] 'process raft request' (duration: 359.636511ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T01:09:36.381831Z","caller":"traceutil/trace.go:171","msg":"trace[260996948] transaction","detail":"{read_only:false; response_revision:21888; number_of_response:1; }","duration":"360.038003ms","start":"2026-09-04T01:09:36.021737Z","end":"2026-09-04T01:09:36.381775Z","steps":["trace[260996948] 'process raft request' (duration: 195.21785ms)","trace[260996948] 'compare' (duration: 164.326349ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T01:09:36.381899Z","caller":"traceutil/trace.go:171","msg":"trace[470198954] linearizableReadLoop","detail":"{readStateIndex:22646; appliedIndex:22643; }","duration":"286.077098ms","start":"2026-09-04T01:09:36.095779Z","end":"2026-09-04T01:09:36.381856Z","steps":["trace[470198954] 'read index received' (duration: 121.146171ms)","trace[470198954] 'applied index is now lower than readState.Index' (duration: 164.929826ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T01:09:36.381949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T01:09:36.022002Z","time spent":"359.869518ms","remote":"127.0.0.1:54390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-04T01:09:36.381984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T01:09:36.02172Z","time spent":"360.186507ms","remote":"127.0.0.1:54390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-04T01:09:36.382023Z","caller":"traceutil/trace.go:171","msg":"trace[1919770045] transaction","detail":"{read_only:false; response_revision:21890; number_of_response:1; }","duration":"359.94181ms","start":"2026-09-04T01:09:36.02205Z","end":"2026-09-04T01:09:36.381992Z","steps":["trace[1919770045] 'process raft request' (duration: 359.698193ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T01:09:36.382126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.340485ms","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-09-04T01:09:36.382182Z","caller":"traceutil/trace.go:171","msg":"trace[1523217262] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:21890; }","duration":"286.458549ms","start":"2026-09-04T01:09:36.095711Z","end":"2026-09-04T01:09:36.38217Z","steps":["trace[1523217262] 'agreement among raft nodes before linearized reading' (duration: 286.325695ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T01:09:36.382175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T01:09:36.022015Z","time spent":"360.080735ms","remote":"127.0.0.1:54390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-04T01:09:36.788735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.509089ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-04T01:09:36.788857Z","caller":"traceutil/trace.go:171","msg":"trace[459635147] linearizableReadLoop","detail":"{readStateIndex:22647; appliedIndex:22646; }","duration":"397.550207ms","start":"2026-09-04T01:09:36.391283Z","end":"2026-09-04T01:09:36.788834Z","steps":["trace[459635147] 'read index received' (duration: 191.810541ms)","trace[459635147] 'applied index is now lower than readState.Index' (duration: 205.735306ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T01:09:36.788957Z","caller":"traceutil/trace.go:171","msg":"trace[162123572] transaction","detail":"{read_only:false; response_revision:21891; number_of_response:1; }","duration":"398.433822ms","start":"2026-09-04T01:09:36.390507Z","end":"2026-09-04T01:09:36.788941Z","steps":["trace[162123572] 'process raft request' (duration: 192.641784ms)","trace[162123572] 'compare' (duration: 205.405797ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T01:09:36.789031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T01:09:36.390484Z","time spent":"398.512455ms","remote":"127.0.0.1:54390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-04T01:09:36.789236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.958898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19060"} {"level":"info","ts":"2026-09-04T01:09:36.78927Z","caller":"traceutil/trace.go:171","msg":"trace[1558761108] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:21891; }","duration":"398.01701ms","start":"2026-09-04T01:09:36.391241Z","end":"2026-09-04T01:09:36.789258Z","steps":["trace[1558761108] 'agreement among raft nodes before linearized reading' (duration: 397.900037ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T01:09:36.789296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T01:09:36.391222Z","time spent":"398.068101ms","remote":"127.0.0.1:54328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":19083,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-09-04T01:09:36.789709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.793016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-04T01:09:36.789826Z","caller":"traceutil/trace.go:171","msg":"trace[354794499] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:21891; }","duration":"266.023863ms","start":"2026-09-04T01:09:36.523756Z","end":"2026-09-04T01:09:36.78978Z","steps":["trace[354794499] 'agreement among raft nodes before linearized reading' (duration: 265.877399ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T01:09:37.227564Z","caller":"traceutil/trace.go:171","msg":"trace[901149230] transaction","detail":"{read_only:false; response_revision:21892; number_of_response:1; }","duration":"339.325959ms","start":"2026-09-04T01:09:36.888189Z","end":"2026-09-04T01:09:37.227515Z","steps":["trace[901149230] 'process raft request' (duration: 339.118283ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T01:09:37.22773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T01:09:36.888159Z","time spent":"339.483614ms","remote":"127.0.0.1:54238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1369,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-04T01:13:46.225828Z","caller":"traceutil/trace.go:171","msg":"trace[481906355] transaction","detail":"{read_only:false; response_revision:24441; number_of_response:1; }","duration":"129.920126ms","start":"2026-09-04T01:13:46.095882Z","end":"2026-09-04T01:13:46.225802Z","steps":["trace[481906355] 'process raft request' (duration: 129.746161ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T01:13:46.439357Z","caller":"traceutil/trace.go:171","msg":"trace[934850928] linearizableReadLoop","detail":"{readStateIndex:25274; appliedIndex:25273; }","duration":"115.407824ms","start":"2026-09-04T01:13:46.323922Z","end":"2026-09-04T01:13:46.439329Z","steps":["trace[934850928] 'read index received' (duration: 29.768318ms)","trace[934850928] 'applied index is now lower than readState.Index' (duration: 85.638326ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T01:13:46.439554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.615379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-04T01:13:46.439608Z","caller":"traceutil/trace.go:171","msg":"trace[1111129362] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:24442; }","duration":"115.733843ms","start":"2026-09-04T01:13:46.323859Z","end":"2026-09-04T01:13:46.439593Z","steps":["trace[1111129362] 'agreement among raft nodes before linearized reading' (duration: 115.597799ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T01:13:46.439642Z","caller":"traceutil/trace.go:171","msg":"trace[1265178106] transaction","detail":"{read_only:false; response_revision:24442; number_of_response:1; }","duration":"169.955813ms","start":"2026-09-04T01:13:46.269643Z","end":"2026-09-04T01:13:46.439599Z","steps":["trace[1265178106] 'process raft request' (duration: 84.129652ms)","trace[1265178106] 'compare' (duration: 85.42023ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T01:13:50.424676Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21654} {"level":"info","ts":"2026-09-04T01:13:50.552308Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21654,"took":"124.132928ms","hash":1601284846,"current-db-size-bytes":57167872,"current-db-size":"57 MB","current-db-size-in-use-bytes":42344448,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-09-04T01:13:50.552398Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1601284846,"revision":21654,"compact-revision":19334} {"level":"info","ts":"2026-09-04T01:15:52.526828Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000067c5.wal"} {"level":"info","ts":"2026-09-04T01:18:50.441139Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24490} {"level":"info","ts":"2026-09-04T01:18:50.596778Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24490,"took":"148.021086ms","hash":1436041952,"current-db-size-bytes":57167872,"current-db-size":"57 MB","current-db-size-in-use-bytes":33415168,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-09-04T01:18:50.596847Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1436041952,"revision":24490,"compact-revision":21654} {"level":"info","ts":"2026-09-04T01:23:22.502749Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"39c975536cfc1086","local-member-applied-index":30010,"local-member-snapshot-index":20009,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-04T01:23:22.510385Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30010} {"level":"info","ts":"2026-09-04T01:23:22.510561Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25010} {"level":"info","ts":"2026-09-04T01:23:50.450143Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27026} {"level":"info","ts":"2026-09-04T01:23:50.589895Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27026,"took":"136.767818ms","hash":158491533,"current-db-size-bytes":57167872,"current-db-size":"57 MB","current-db-size-in-use-bytes":29597696,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-04T01:23:50.590114Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":158491533,"revision":27026,"compact-revision":24490}