{"level":"warn","ts":"2026-10-09T02:25:06.135127Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-09T02:25:06.135293Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.156:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.156:2380","--initial-cluster=instance=https://162.253.55.156:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.156:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.156:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-10-09T02:25:06.135399Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-09T02:25:06.135414Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.156:2380"]} {"level":"info","ts":"2026-10-09T02:25:06.135436Z","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-10-09T02:25:06.13635Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.156:2379"]} {"level":"info","ts":"2026-10-09T02:25:06.136461Z","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.156:2380"],"listen-peer-urls":["https://162.253.55.156:2380"],"advertise-client-urls":["https://162.253.55.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.156:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-10-09T02:25:06.14334Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.563271ms"} {"level":"info","ts":"2026-10-09T02:25:06.151714Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"40fb1eca294b6a5a","cluster-id":"17c6999805fcc0eb"} {"level":"info","ts":"2026-10-09T02:25:06.151789Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a switched to configuration voters=()"} {"level":"info","ts":"2026-10-09T02:25:06.151813Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a became follower at term 0"} {"level":"info","ts":"2026-10-09T02:25:06.151827Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 40fb1eca294b6a5a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-09T02:25:06.151836Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a became follower at term 1"} {"level":"info","ts":"2026-10-09T02:25:06.151876Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a switched to configuration voters=(4682370091206797914)"} {"level":"warn","ts":"2026-10-09T02:25:06.157405Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-09T02:25:06.15913Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-09T02:25:06.160596Z","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-10-09T02:25:06.163821Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"40fb1eca294b6a5a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-09T02:25:06.164256Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"40fb1eca294b6a5a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-09T02:25:06.164417Z","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-10-09T02:25:06.164542Z","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-10-09T02:25:06.164559Z","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-10-09T02:25:06.166339Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a switched to configuration voters=(4682370091206797914)"} {"level":"info","ts":"2026-10-09T02:25:06.166552Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"17c6999805fcc0eb","local-member-id":"40fb1eca294b6a5a","added-peer-id":"40fb1eca294b6a5a","added-peer-peer-urls":["https://162.253.55.156:2380"]} {"level":"info","ts":"2026-10-09T02:25:06.169925Z","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-10-09T02:25:06.170231Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"40fb1eca294b6a5a","initial-advertise-peer-urls":["https://162.253.55.156:2380"],"listen-peer-urls":["https://162.253.55.156:2380"],"advertise-client-urls":["https://162.253.55.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-09T02:25:06.170289Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-09T02:25:06.170481Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.156:2380"} {"level":"info","ts":"2026-10-09T02:25:06.170515Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.156:2380"} {"level":"info","ts":"2026-10-09T02:25:07.052672Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a is starting a new election at term 1"} {"level":"info","ts":"2026-10-09T02:25:07.052796Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a became pre-candidate at term 1"} {"level":"info","ts":"2026-10-09T02:25:07.052829Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a received MsgPreVoteResp from 40fb1eca294b6a5a at term 1"} {"level":"info","ts":"2026-10-09T02:25:07.052848Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a became candidate at term 2"} {"level":"info","ts":"2026-10-09T02:25:07.052858Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a received MsgVoteResp from 40fb1eca294b6a5a at term 2"} {"level":"info","ts":"2026-10-09T02:25:07.052875Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a became leader at term 2"} {"level":"info","ts":"2026-10-09T02:25:07.052887Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 40fb1eca294b6a5a elected leader 40fb1eca294b6a5a at term 2"} {"level":"info","ts":"2026-10-09T02:25:07.054213Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"40fb1eca294b6a5a","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.156:2379]}","request-path":"/0/members/40fb1eca294b6a5a/attributes","cluster-id":"17c6999805fcc0eb","publish-timeout":"7s"} {"level":"info","ts":"2026-10-09T02:25:07.054407Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-09T02:25:07.054481Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-09T02:25:07.054589Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-09T02:25:07.054938Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-09T02:25:07.055002Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-09T02:25:07.056361Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-09T02:25:07.056369Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"17c6999805fcc0eb","local-member-id":"40fb1eca294b6a5a","cluster-version":"3.5"} {"level":"info","ts":"2026-10-09T02:25:07.056468Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-09T02:25:07.056515Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-09T02:25:07.05778Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.156:2379"} {"level":"info","ts":"2026-10-09T02:26:01.194067Z","caller":"traceutil/trace.go:171","msg":"trace[578856474] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"285.662611ms","start":"2026-10-09T02:26:00.908371Z","end":"2026-10-09T02:26:01.194034Z","steps":["trace[578856474] 'process raft request' (duration: 285.476678ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:26:01.681077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.052092ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-09T02:26:01.681208Z","caller":"traceutil/trace.go:171","msg":"trace[1233983011] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:643; }","duration":"714.607677ms","start":"2026-10-09T02:26:00.966582Z","end":"2026-10-09T02:26:01.681189Z","steps":["trace[1233983011] 'read index received' (duration: 227.314776ms)","trace[1233983011] 'applied index is now lower than readState.Index' (duration: 487.291801ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:26:01.681295Z","caller":"traceutil/trace.go:171","msg":"trace[217585417] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"738.105726ms","start":"2026-10-09T02:26:00.943179Z","end":"2026-10-09T02:26:01.681285Z","steps":["trace[217585417] 'process raft request' (duration: 258.287087ms)","trace[217585417] 'compare' (duration: 478.774006ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T02:26:01.681344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:26:00.943152Z","time spent":"738.166297ms","remote":"127.0.0.1:37332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-09T02:26:01.681718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"715.099947ms","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-10-09T02:26:01.68173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.873686ms","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-10-09T02:26:01.681852Z","caller":"traceutil/trace.go:171","msg":"trace[273666490] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:617; }","duration":"715.320942ms","start":"2026-10-09T02:26:00.966506Z","end":"2026-10-09T02:26:01.681827Z","steps":["trace[273666490] 'agreement among raft nodes before linearized reading' (duration: 714.930844ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:26:01.681869Z","caller":"traceutil/trace.go:171","msg":"trace[613561807] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:617; }","duration":"711.07956ms","start":"2026-10-09T02:26:00.970763Z","end":"2026-10-09T02:26:01.681843Z","steps":["trace[613561807] 'agreement among raft nodes before linearized reading' (duration: 710.682411ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:26:01.681909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:26:00.966493Z","time spent":"715.405424ms","remote":"127.0.0.1:37248","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-10-09T02:26:01.681916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:26:00.970723Z","time spent":"711.183212ms","remote":"127.0.0.1:37248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-09T02:26:01.682099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.904232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-kpf8p\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-10-09T02:26:01.682832Z","caller":"traceutil/trace.go:171","msg":"trace[1525883060] range","detail":"{range_begin:/registry/pods/kube-system/cilium-kpf8p; range_end:; response_count:1; response_revision:617; }","duration":"296.813552ms","start":"2026-10-09T02:26:01.385977Z","end":"2026-10-09T02:26:01.68279Z","steps":["trace[1525883060] 'agreement among raft nodes before linearized reading' (duration: 295.502354ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:26:46.997559Z","caller":"traceutil/trace.go:171","msg":"trace[1243986119] transaction","detail":"{read_only:false; response_revision:1185; number_of_response:1; }","duration":"198.315968ms","start":"2026-10-09T02:26:46.79921Z","end":"2026-10-09T02:26:46.997526Z","steps":["trace[1243986119] 'process raft request' (duration: 198.156135ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:27:40.152521Z","caller":"traceutil/trace.go:171","msg":"trace[2133592872] linearizableReadLoop","detail":"{readStateIndex:1957; appliedIndex:1956; }","duration":"275.56421ms","start":"2026-10-09T02:27:39.876905Z","end":"2026-10-09T02:27:40.15247Z","steps":["trace[2133592872] 'read index received' (duration: 48.536904ms)","trace[2133592872] 'applied index is now lower than readState.Index' (duration: 227.024956ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:27:40.152657Z","caller":"traceutil/trace.go:171","msg":"trace[1596731897] transaction","detail":"{read_only:false; response_revision:1880; number_of_response:1; }","duration":"319.251451ms","start":"2026-10-09T02:27:39.83331Z","end":"2026-10-09T02:27:40.152562Z","steps":["trace[1596731897] 'process raft request' (duration: 302.982771ms)","trace[1596731897] 'compare' (duration: 15.760029ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T02:27:40.152953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.028851ms","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-10-09T02:27:40.15302Z","caller":"traceutil/trace.go:171","msg":"trace[893355063] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1880; }","duration":"276.166803ms","start":"2026-10-09T02:27:39.87684Z","end":"2026-10-09T02:27:40.153006Z","steps":["trace[893355063] 'agreement among raft nodes before linearized reading' (duration: 275.832617ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:27:40.153369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:27:39.833269Z","time spent":"319.589439ms","remote":"127.0.0.1:37248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T02:27:40.178382Z","caller":"traceutil/trace.go:171","msg":"trace[483714658] transaction","detail":"{read_only:false; response_revision:1881; number_of_response:1; }","duration":"137.437328ms","start":"2026-10-09T02:27:40.040911Z","end":"2026-10-09T02:27:40.178349Z","steps":["trace[483714658] 'process raft request' (duration: 137.213062ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:27:40.178715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.748958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:10668"} {"level":"info","ts":"2026-10-09T02:27:40.178818Z","caller":"traceutil/trace.go:171","msg":"trace[1171270306] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:1882; }","duration":"224.951912ms","start":"2026-10-09T02:27:39.953847Z","end":"2026-10-09T02:27:40.178799Z","steps":["trace[1171270306] 'agreement among raft nodes before linearized reading' (duration: 224.645505ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:27:40.52189Z","caller":"traceutil/trace.go:171","msg":"trace[80893866] transaction","detail":"{read_only:false; response_revision:1890; number_of_response:1; }","duration":"207.479025ms","start":"2026-10-09T02:27:40.314351Z","end":"2026-10-09T02:27:40.52183Z","steps":["trace[80893866] 'process raft request' (duration: 112.601903ms)","trace[80893866] 'compare' (duration: 94.405162ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:27:40.522198Z","caller":"traceutil/trace.go:171","msg":"trace[369188511] transaction","detail":"{read_only:false; response_revision:1891; number_of_response:1; }","duration":"205.676466ms","start":"2026-10-09T02:27:40.316501Z","end":"2026-10-09T02:27:40.522177Z","steps":["trace[369188511] 'process raft request' (duration: 205.279718ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:27:53.49771Z","caller":"traceutil/trace.go:171","msg":"trace[1888811240] transaction","detail":"{read_only:false; response_revision:2005; number_of_response:1; }","duration":"119.450973ms","start":"2026-10-09T02:27:53.378202Z","end":"2026-10-09T02:27:53.497653Z","steps":["trace[1888811240] 'process raft request' (duration: 119.196017ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:28:30.387964Z","caller":"traceutil/trace.go:171","msg":"trace[465545887] transaction","detail":"{read_only:false; response_revision:2206; number_of_response:1; }","duration":"130.336238ms","start":"2026-10-09T02:28:30.257596Z","end":"2026-10-09T02:28:30.387933Z","steps":["trace[465545887] 'process raft request' (duration: 110.90939ms)","trace[465545887] 'compare' (duration: 19.272155ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T02:28:36.29916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.349594ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-10-09T02:28:36.299304Z","caller":"traceutil/trace.go:171","msg":"trace[628028097] linearizableReadLoop","detail":"{readStateIndex:2326; appliedIndex:2325; }","duration":"314.396553ms","start":"2026-10-09T02:28:35.984886Z","end":"2026-10-09T02:28:36.299283Z","steps":["trace[628028097] 'read index received' (duration: 134.917787ms)","trace[628028097] 'applied index is now lower than readState.Index' (duration: 179.476736ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T02:28:36.299706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.817823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-09T02:28:36.299784Z","caller":"traceutil/trace.go:171","msg":"trace[1543676251] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:2235; }","duration":"314.914095ms","start":"2026-10-09T02:28:35.984858Z","end":"2026-10-09T02:28:36.299772Z","steps":["trace[1543676251] 'agreement among raft nodes before linearized reading' (duration: 314.535737ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:28:36.299826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:28:35.98482Z","time spent":"314.995007ms","remote":"127.0.0.1:37186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":56,"response size":29,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"info","ts":"2026-10-09T02:28:36.441386Z","caller":"traceutil/trace.go:171","msg":"trace[1493934586] transaction","detail":"{read_only:false; response_revision:2236; number_of_response:1; }","duration":"135.367617ms","start":"2026-10-09T02:28:36.305204Z","end":"2026-10-09T02:28:36.440572Z","steps":["trace[1493934586] 'process raft request' (duration: 135.07755ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:29:19.613156Z","caller":"traceutil/trace.go:171","msg":"trace[367531726] transaction","detail":"{read_only:false; response_revision:2646; number_of_response:1; }","duration":"246.519898ms","start":"2026-10-09T02:29:19.366599Z","end":"2026-10-09T02:29:19.613119Z","steps":["trace[367531726] 'process raft request' (duration: 246.327994ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:29:53.592866Z","caller":"traceutil/trace.go:171","msg":"trace[881382205] transaction","detail":"{read_only:false; response_revision:2850; number_of_response:1; }","duration":"303.353439ms","start":"2026-10-09T02:29:53.289477Z","end":"2026-10-09T02:29:53.59283Z","steps":["trace[881382205] 'process raft request' (duration: 303.111553ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:29:53.593122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:29:53.289449Z","time spent":"303.538043ms","remote":"127.0.0.1:37248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T02:29:53.618113Z","caller":"traceutil/trace.go:171","msg":"trace[263127171] transaction","detail":"{read_only:false; response_revision:2851; number_of_response:1; }","duration":"327.944098ms","start":"2026-10-09T02:29:53.290132Z","end":"2026-10-09T02:29:53.618076Z","steps":["trace[263127171] 'process raft request' (duration: 327.710013ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:29:53.618362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:29:53.290119Z","time spent":"328.142443ms","remote":"127.0.0.1:37248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T02:33:16.762896Z","caller":"traceutil/trace.go:171","msg":"trace[740455562] transaction","detail":"{read_only:false; response_revision:4120; number_of_response:1; }","duration":"119.933383ms","start":"2026-10-09T02:33:16.642915Z","end":"2026-10-09T02:33:16.762848Z","steps":["trace[740455562] 'process raft request' (duration: 119.678788ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:33:16.876357Z","caller":"traceutil/trace.go:171","msg":"trace[669411672] transaction","detail":"{read_only:false; response_revision:4121; number_of_response:1; }","duration":"108.364286ms","start":"2026-10-09T02:33:16.767954Z","end":"2026-10-09T02:33:16.876318Z","steps":["trace[669411672] 'process raft request' (duration: 32.929581ms)","trace[669411672] 'compare' (duration: 75.20908ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:33:16.876628Z","caller":"traceutil/trace.go:171","msg":"trace[409620923] transaction","detail":"{read_only:false; response_revision:4122; number_of_response:1; }","duration":"108.54113ms","start":"2026-10-09T02:33:16.768016Z","end":"2026-10-09T02:33:16.876557Z","steps":["trace[409620923] 'process raft request' (duration: 108.259415ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:33:16.876615Z","caller":"traceutil/trace.go:171","msg":"trace[1621605769] linearizableReadLoop","detail":"{readStateIndex:4283; appliedIndex:4281; }","duration":"108.177422ms","start":"2026-10-09T02:33:16.768397Z","end":"2026-10-09T02:33:16.876574Z","steps":["trace[1621605769] 'read index received' (duration: 32.511392ms)","trace[1621605769] 'applied index is now lower than readState.Index' (duration: 75.66302ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T02:33:16.877013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.587951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:16618"} {"level":"info","ts":"2026-10-09T02:33:16.877062Z","caller":"traceutil/trace.go:171","msg":"trace[1177931679] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:4122; }","duration":"108.668282ms","start":"2026-10-09T02:33:16.768381Z","end":"2026-10-09T02:33:16.87705Z","steps":["trace[1177931679] 'agreement among raft nodes before linearized reading' (duration: 108.411377ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:34:49.609892Z","caller":"traceutil/trace.go:171","msg":"trace[420614618] linearizableReadLoop","detail":"{readStateIndex:5519; appliedIndex:5518; }","duration":"203.827969ms","start":"2026-10-09T02:34:49.406037Z","end":"2026-10-09T02:34:49.609865Z","steps":["trace[420614618] 'read index received' (duration: 203.580124ms)","trace[420614618] 'applied index is now lower than readState.Index' (duration: 246.815µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:34:49.609958Z","caller":"traceutil/trace.go:171","msg":"trace[1650318089] transaction","detail":"{read_only:false; response_revision:5314; number_of_response:1; }","duration":"207.781802ms","start":"2026-10-09T02:34:49.402153Z","end":"2026-10-09T02:34:49.609935Z","steps":["trace[1650318089] 'process raft request' (duration: 207.539577ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:34:49.610059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.994283ms","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:7"} {"level":"warn","ts":"2026-10-09T02:34:49.610613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.372946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-09T02:34:49.610766Z","caller":"traceutil/trace.go:171","msg":"trace[1416492193] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5314; }","duration":"162.585922ms","start":"2026-10-09T02:34:49.448166Z","end":"2026-10-09T02:34:49.610752Z","steps":["trace[1416492193] 'agreement among raft nodes before linearized reading' (duration: 162.220964ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:34:49.610481Z","caller":"traceutil/trace.go:171","msg":"trace[1669878280] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5314; }","duration":"204.438811ms","start":"2026-10-09T02:34:49.406015Z","end":"2026-10-09T02:34:49.610454Z","steps":["trace[1669878280] 'agreement among raft nodes before linearized reading' (duration: 203.988722ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:34:53.65013Z","caller":"traceutil/trace.go:171","msg":"trace[181237105] linearizableReadLoop","detail":"{readStateIndex:5608; appliedIndex:5607; }","duration":"104.980815ms","start":"2026-10-09T02:34:53.545131Z","end":"2026-10-09T02:34:53.650112Z","steps":["trace[181237105] 'read index received' (duration: 104.861902ms)","trace[181237105] 'applied index is now lower than readState.Index' (duration: 117.913µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:34:53.650225Z","caller":"traceutil/trace.go:171","msg":"trace[1357142121] transaction","detail":"{read_only:false; response_revision:5398; number_of_response:1; }","duration":"106.206351ms","start":"2026-10-09T02:34:53.544008Z","end":"2026-10-09T02:34:53.650214Z","steps":["trace[1357142121] 'process raft request' (duration: 106.000416ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:34:53.650534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.398433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-09T02:34:53.650573Z","caller":"traceutil/trace.go:171","msg":"trace[1653844014] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:5398; }","duration":"105.479486ms","start":"2026-10-09T02:34:53.545083Z","end":"2026-10-09T02:34:53.650562Z","steps":["trace[1653844014] 'agreement among raft nodes before linearized reading' (duration: 105.339392ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:35:05.19043Z","caller":"traceutil/trace.go:171","msg":"trace[1531312992] transaction","detail":"{read_only:false; response_revision:5591; number_of_response:1; }","duration":"152.098618ms","start":"2026-10-09T02:35:05.038304Z","end":"2026-10-09T02:35:05.190403Z","steps":["trace[1531312992] 'process raft request' (duration: 96.556765ms)","trace[1531312992] 'compare' (duration: 55.424481ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:35:05.742074Z","caller":"traceutil/trace.go:171","msg":"trace[100452552] transaction","detail":"{read_only:false; response_revision:5593; number_of_response:1; }","duration":"126.772079ms","start":"2026-10-09T02:35:05.615261Z","end":"2026-10-09T02:35:05.742034Z","steps":["trace[100452552] 'process raft request' (duration: 126.475832ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:35:07.092373Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2925} {"level":"info","ts":"2026-10-09T02:35:07.171808Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2925,"took":"78.112194ms","hash":1171270343,"current-db-size-bytes":55758848,"current-db-size":"56 MB","current-db-size-in-use-bytes":31170560,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-09T02:35:07.171885Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1171270343,"revision":2925,"compact-revision":-1} {"level":"info","ts":"2026-10-09T02:35:13.585922Z","caller":"traceutil/trace.go:171","msg":"trace[1424118655] transaction","detail":"{read_only:false; response_revision:5790; number_of_response:1; }","duration":"139.154971ms","start":"2026-10-09T02:35:13.446714Z","end":"2026-10-09T02:35:13.585869Z","steps":["trace[1424118655] 'process raft request' (duration: 138.855636ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:35:13.832591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.050013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-pushgateway-7b8659c68b-82zvq\" ","response":"range_response_count:1 size:4040"} {"level":"info","ts":"2026-10-09T02:35:13.833299Z","caller":"traceutil/trace.go:171","msg":"trace[1386834555] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-pushgateway-7b8659c68b-82zvq; range_end:; response_count:1; response_revision:5790; }","duration":"111.80498ms","start":"2026-10-09T02:35:13.721467Z","end":"2026-10-09T02:35:13.833272Z","steps":["trace[1386834555] 'range keys from in-memory index tree' (duration: 110.799218ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:35:14.108002Z","caller":"traceutil/trace.go:171","msg":"trace[1012062828] transaction","detail":"{read_only:false; response_revision:5792; number_of_response:1; }","duration":"224.346546ms","start":"2026-10-09T02:35:13.883617Z","end":"2026-10-09T02:35:14.107963Z","steps":["trace[1012062828] 'process raft request' (duration: 224.09659ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:35:14.118812Z","caller":"traceutil/trace.go:171","msg":"trace[1809395230] linearizableReadLoop","detail":"{readStateIndex:6016; appliedIndex:6013; }","duration":"198.899024ms","start":"2026-10-09T02:35:13.919869Z","end":"2026-10-09T02:35:14.118768Z","steps":["trace[1809395230] 'read index received' (duration: 187.760816ms)","trace[1809395230] 'applied index is now lower than readState.Index' (duration: 11.136788ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:35:14.118913Z","caller":"traceutil/trace.go:171","msg":"trace[1524479321] transaction","detail":"{read_only:false; response_revision:5793; number_of_response:1; }","duration":"233.121232ms","start":"2026-10-09T02:35:13.885756Z","end":"2026-10-09T02:35:14.118877Z","steps":["trace[1524479321] 'process raft request' (duration: 232.706243ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:35:14.118974Z","caller":"traceutil/trace.go:171","msg":"trace[1685989345] transaction","detail":"{read_only:false; response_revision:5794; number_of_response:1; }","duration":"228.171586ms","start":"2026-10-09T02:35:13.890788Z","end":"2026-10-09T02:35:14.11896Z","steps":["trace[1685989345] 'process raft request' (duration: 227.856219ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:35:14.119233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.983821ms","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-10-09T02:35:14.119379Z","caller":"traceutil/trace.go:171","msg":"trace[1497661261] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5794; }","duration":"196.120354ms","start":"2026-10-09T02:35:13.923181Z","end":"2026-10-09T02:35:14.119301Z","steps":["trace[1497661261] 'agreement among raft nodes before linearized reading' (duration: 195.875239ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:35:14.119633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.746762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-09T02:35:14.119774Z","caller":"traceutil/trace.go:171","msg":"trace[633101781] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5794; }","duration":"199.950046ms","start":"2026-10-09T02:35:13.919801Z","end":"2026-10-09T02:35:14.119751Z","steps":["trace[633101781] 'agreement among raft nodes before linearized reading' (duration: 199.265622ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:36:08.870869Z","caller":"traceutil/trace.go:171","msg":"trace[697666099] linearizableReadLoop","detail":"{readStateIndex:6527; appliedIndex:6526; }","duration":"123.021808ms","start":"2026-10-09T02:36:08.747823Z","end":"2026-10-09T02:36:08.870845Z","steps":["trace[697666099] 'read index received' (duration: 122.754102ms)","trace[697666099] 'applied index is now lower than readState.Index' (duration: 266.696µs)"],"step_count":2} {"level":"warn","ts":"2026-10-09T02:36:08.870994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.149271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T02:36:08.870972Z","caller":"traceutil/trace.go:171","msg":"trace[1242376685] transaction","detail":"{read_only:false; response_revision:6291; number_of_response:1; }","duration":"129.867675ms","start":"2026-10-09T02:36:08.741038Z","end":"2026-10-09T02:36:08.870906Z","steps":["trace[1242376685] 'process raft request' (duration: 129.640541ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:36:08.871033Z","caller":"traceutil/trace.go:171","msg":"trace[1485063047] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6291; }","duration":"123.248563ms","start":"2026-10-09T02:36:08.747775Z","end":"2026-10-09T02:36:08.871024Z","steps":["trace[1485063047] 'agreement among raft nodes before linearized reading' (duration: 123.169581ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:40:07.102555Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5623} {"level":"info","ts":"2026-10-09T02:40:07.195465Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5623,"took":"91.185027ms","hash":2958826041,"current-db-size-bytes":55758848,"current-db-size":"56 MB","current-db-size-in-use-bytes":26714112,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-09T02:40:07.195543Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2958826041,"revision":5623,"compact-revision":2925} {"level":"info","ts":"2026-10-09T02:41:28.406857Z","caller":"traceutil/trace.go:171","msg":"trace[1160356230] linearizableReadLoop","detail":"{readStateIndex:8955; appliedIndex:8954; }","duration":"192.267137ms","start":"2026-10-09T02:41:28.214566Z","end":"2026-10-09T02:41:28.406834Z","steps":["trace[1160356230] 'read index received' (duration: 192.048151ms)","trace[1160356230] 'applied index is now lower than readState.Index' (duration: 218.246µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:41:28.406955Z","caller":"traceutil/trace.go:171","msg":"trace[420848221] transaction","detail":"{read_only:false; response_revision:8641; number_of_response:1; }","duration":"247.93138ms","start":"2026-10-09T02:41:28.158991Z","end":"2026-10-09T02:41:28.406922Z","steps":["trace[420848221] 'process raft request' (duration: 247.690845ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:41:28.40716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.579392ms","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-10-09T02:41:28.407244Z","caller":"traceutil/trace.go:171","msg":"trace[42379931] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8641; }","duration":"192.690675ms","start":"2026-10-09T02:41:28.214515Z","end":"2026-10-09T02:41:28.407206Z","steps":["trace[42379931] 'agreement among raft nodes before linearized reading' (duration: 192.498921ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:41:28.407764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.534162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-09T02:41:28.407844Z","caller":"traceutil/trace.go:171","msg":"trace[1816461465] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8641; }","duration":"137.650244ms","start":"2026-10-09T02:41:28.270172Z","end":"2026-10-09T02:41:28.407823Z","steps":["trace[1816461465] 'agreement among raft nodes before linearized reading' (duration: 137.481541ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:41:40.865989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.125394ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-09T02:41:40.866092Z","caller":"traceutil/trace.go:171","msg":"trace[1095901401] linearizableReadLoop","detail":"{readStateIndex:9022; appliedIndex:9021; }","duration":"292.63846ms","start":"2026-10-09T02:41:40.573439Z","end":"2026-10-09T02:41:40.866078Z","steps":["trace[1095901401] 'read index received' (duration: 32.367924ms)","trace[1095901401] 'applied index is now lower than readState.Index' (duration: 260.269406ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:41:40.866167Z","caller":"traceutil/trace.go:171","msg":"trace[1999174041] transaction","detail":"{read_only:false; response_revision:8705; number_of_response:1; }","duration":"293.216784ms","start":"2026-10-09T02:41:40.572914Z","end":"2026-10-09T02:41:40.866131Z","steps":["trace[1999174041] 'process raft request' (duration: 32.874666ms)","trace[1999174041] 'compare' (duration: 259.9987ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T02:41:40.866265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.829245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-9jsvl\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-10-09T02:41:40.866293Z","caller":"traceutil/trace.go:171","msg":"trace[1527133977] range","detail":"{range_begin:/registry/pods/openstack/keepalived-9jsvl; range_end:; response_count:1; response_revision:8705; }","duration":"292.875996ms","start":"2026-10-09T02:41:40.573408Z","end":"2026-10-09T02:41:40.866284Z","steps":["trace[1527133977] 'agreement among raft nodes before linearized reading' (duration: 292.723283ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:41:40.901433Z","caller":"traceutil/trace.go:171","msg":"trace[509651959] transaction","detail":"{read_only:false; response_revision:8707; number_of_response:1; }","duration":"325.22018ms","start":"2026-10-09T02:41:40.576194Z","end":"2026-10-09T02:41:40.901414Z","steps":["trace[509651959] 'process raft request' (duration: 325.192569ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:41:40.901555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:41:40.576173Z","time spent":"325.319612ms","remote":"127.0.0.1:37248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T02:41:40.901551Z","caller":"traceutil/trace.go:171","msg":"trace[210945102] transaction","detail":"{read_only:false; response_revision:8706; number_of_response:1; }","duration":"325.657239ms","start":"2026-10-09T02:41:40.575862Z","end":"2026-10-09T02:41:40.901519Z","steps":["trace[210945102] 'process raft request' (duration: 325.435934ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:41:40.901743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:41:40.575838Z","time spent":"325.809853ms","remote":"127.0.0.1:37248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T02:41:40.917016Z","caller":"traceutil/trace.go:171","msg":"trace[1372611640] transaction","detail":"{read_only:false; response_revision:8708; number_of_response:1; }","duration":"129.919258ms","start":"2026-10-09T02:41:40.787064Z","end":"2026-10-09T02:41:40.916983Z","steps":["trace[1372611640] 'process raft request' (duration: 129.674243ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:41:40.917044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.839775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T02:41:40.917186Z","caller":"traceutil/trace.go:171","msg":"trace[691489783] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8708; }","duration":"169.003148ms","start":"2026-10-09T02:41:40.748125Z","end":"2026-10-09T02:41:40.917128Z","steps":["trace[691489783] 'agreement among raft nodes before linearized reading' (duration: 168.811294ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:41:41.539775Z","caller":"traceutil/trace.go:171","msg":"trace[1135541847] transaction","detail":"{read_only:false; response_revision:8710; number_of_response:1; }","duration":"154.309822ms","start":"2026-10-09T02:41:41.385436Z","end":"2026-10-09T02:41:41.539745Z","steps":["trace[1135541847] 'process raft request' (duration: 154.090647ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:42:11.275617Z","caller":"traceutil/trace.go:171","msg":"trace[2022420355] linearizableReadLoop","detail":"{readStateIndex:9245; appliedIndex:9244; }","duration":"228.004798ms","start":"2026-10-09T02:42:11.047536Z","end":"2026-10-09T02:42:11.27554Z","steps":["trace[2022420355] 'read index received' (duration: 227.825275ms)","trace[2022420355] 'applied index is now lower than readState.Index' (duration: 177.884µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:42:11.275642Z","caller":"traceutil/trace.go:171","msg":"trace[2051981489] transaction","detail":"{read_only:false; response_revision:8920; number_of_response:1; }","duration":"231.379351ms","start":"2026-10-09T02:42:11.044232Z","end":"2026-10-09T02:42:11.275612Z","steps":["trace[2051981489] 'process raft request' (duration: 231.146756ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:42:11.275978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.424108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-09T02:42:11.27605Z","caller":"traceutil/trace.go:171","msg":"trace[1067964345] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8920; }","duration":"228.54087ms","start":"2026-10-09T02:42:11.047497Z","end":"2026-10-09T02:42:11.276038Z","steps":["trace[1067964345] 'agreement among raft nodes before linearized reading' (duration: 228.306845ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:42:11.413386Z","caller":"traceutil/trace.go:171","msg":"trace[778389301] linearizableReadLoop","detail":"{readStateIndex:9246; appliedIndex:9245; }","duration":"137.557996ms","start":"2026-10-09T02:42:11.275803Z","end":"2026-10-09T02:42:11.413361Z","steps":["trace[778389301] 'read index received' (duration: 52.831386ms)","trace[778389301] 'applied index is now lower than readState.Index' (duration: 84.72544ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:42:11.413649Z","caller":"traceutil/trace.go:171","msg":"trace[2098438126] transaction","detail":"{read_only:false; response_revision:8921; number_of_response:1; }","duration":"336.413628ms","start":"2026-10-09T02:42:11.077222Z","end":"2026-10-09T02:42:11.413635Z","steps":["trace[2098438126] 'process raft request' (duration: 251.399381ms)","trace[2098438126] 'compare' (duration: 84.466535ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T02:42:11.413802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:42:11.077206Z","time spent":"336.516491ms","remote":"127.0.0.1:37248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T02:42:11.414088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.560926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-9jsvl\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-10-09T02:42:11.414127Z","caller":"traceutil/trace.go:171","msg":"trace[913487519] range","detail":"{range_begin:/registry/pods/openstack/keepalived-9jsvl; range_end:; response_count:1; response_revision:8921; }","duration":"231.636268ms","start":"2026-10-09T02:42:11.182481Z","end":"2026-10-09T02:42:11.414117Z","steps":["trace[913487519] 'agreement among raft nodes before linearized reading' (duration: 231.496124ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:42:11.426964Z","caller":"traceutil/trace.go:171","msg":"trace[1976236807] transaction","detail":"{read_only:false; response_revision:8922; number_of_response:1; }","duration":"146.740263ms","start":"2026-10-09T02:42:11.280189Z","end":"2026-10-09T02:42:11.426929Z","steps":["trace[1976236807] 'process raft request' (duration: 146.550409ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:42:11.427078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.259903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-09T02:42:11.427162Z","caller":"traceutil/trace.go:171","msg":"trace[641270235] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8922; }","duration":"119.392885ms","start":"2026-10-09T02:42:11.307749Z","end":"2026-10-09T02:42:11.427142Z","steps":["trace[641270235] 'agreement among raft nodes before linearized reading' (duration: 119.1353ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:42:13.696196Z","caller":"traceutil/trace.go:171","msg":"trace[1326514873] transaction","detail":"{read_only:false; response_revision:8934; number_of_response:1; }","duration":"200.963708ms","start":"2026-10-09T02:42:13.495211Z","end":"2026-10-09T02:42:13.696175Z","steps":["trace[1326514873] 'process raft request' (duration: 200.844635ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:42:17.936044Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002445.wal"} {"level":"info","ts":"2026-10-09T02:42:21.465494Z","caller":"traceutil/trace.go:171","msg":"trace[1799796417] transaction","detail":"{read_only:false; response_revision:8973; number_of_response:1; }","duration":"120.182973ms","start":"2026-10-09T02:42:21.345278Z","end":"2026-10-09T02:42:21.465461Z","steps":["trace[1799796417] 'process raft request' (duration: 119.984809ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:42:21.766988Z","caller":"traceutil/trace.go:171","msg":"trace[1341949185] linearizableReadLoop","detail":"{readStateIndex:9302; appliedIndex:9301; }","duration":"139.153948ms","start":"2026-10-09T02:42:21.627801Z","end":"2026-10-09T02:42:21.766955Z","steps":["trace[1341949185] 'read index received' (duration: 138.863713ms)","trace[1341949185] 'applied index is now lower than readState.Index' (duration: 288.625µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:42:21.767033Z","caller":"traceutil/trace.go:171","msg":"trace[2044051681] transaction","detail":"{read_only:false; response_revision:8975; number_of_response:1; }","duration":"262.744685ms","start":"2026-10-09T02:42:21.504244Z","end":"2026-10-09T02:42:21.766989Z","steps":["trace[2044051681] 'process raft request' (duration: 262.51865ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:42:21.767175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.348123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T02:42:21.767255Z","caller":"traceutil/trace.go:171","msg":"trace[88990554] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:8975; }","duration":"139.487277ms","start":"2026-10-09T02:42:21.627749Z","end":"2026-10-09T02:42:21.767236Z","steps":["trace[88990554] 'agreement among raft nodes before linearized reading' (duration: 139.324753ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:42:24.635225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.535744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-m2bj6\" ","response":"range_response_count:1 size:4648"} {"level":"info","ts":"2026-10-09T02:42:24.635294Z","caller":"traceutil/trace.go:171","msg":"trace[659214713] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-m2bj6; range_end:; response_count:1; response_revision:9003; }","duration":"170.659447ms","start":"2026-10-09T02:42:24.464621Z","end":"2026-10-09T02:42:24.63528Z","steps":["trace[659214713] 'range keys from in-memory index tree' (duration: 170.406481ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:42:24.635816Z","caller":"traceutil/trace.go:171","msg":"trace[2016143567] linearizableReadLoop","detail":"{readStateIndex:9331; appliedIndex:9330; }","duration":"116.768808ms","start":"2026-10-09T02:42:24.519038Z","end":"2026-10-09T02:42:24.635806Z","steps":["trace[2016143567] 'read index received' (duration: 53.97467ms)","trace[2016143567] 'applied index is now lower than readState.Index' (duration: 62.793188ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:42:24.636006Z","caller":"traceutil/trace.go:171","msg":"trace[421427363] transaction","detail":"{read_only:false; response_revision:9004; number_of_response:1; }","duration":"169.205375ms","start":"2026-10-09T02:42:24.466791Z","end":"2026-10-09T02:42:24.635996Z","steps":["trace[421427363] 'process raft request' (duration: 106.285723ms)","trace[421427363] 'compare' (duration: 62.637396ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T02:42:24.636163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.129986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T02:42:24.636189Z","caller":"traceutil/trace.go:171","msg":"trace[984599803] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:9004; }","duration":"117.192348ms","start":"2026-10-09T02:42:24.51899Z","end":"2026-10-09T02:42:24.636182Z","steps":["trace[984599803] 'agreement among raft nodes before linearized reading' (duration: 117.123766ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:42:24.63631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.025203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T02:42:24.636454Z","caller":"traceutil/trace.go:171","msg":"trace[159653351] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9004; }","duration":"116.241138ms","start":"2026-10-09T02:42:24.520176Z","end":"2026-10-09T02:42:24.636417Z","steps":["trace[159653351] 'agreement among raft nodes before linearized reading' (duration: 115.998553ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:43:25.195792Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"40fb1eca294b6a5a","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-09T02:43:25.205766Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-09T02:43:25.205911Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-09T02:43:46.393268Z","caller":"traceutil/trace.go:171","msg":"trace[275648737] transaction","detail":"{read_only:false; response_revision:9812; number_of_response:1; }","duration":"110.836831ms","start":"2026-10-09T02:43:46.282394Z","end":"2026-10-09T02:43:46.393231Z","steps":["trace[275648737] 'process raft request' (duration: 90.953354ms)","trace[275648737] 'compare' (duration: 19.737704ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:45:07.109742Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7980} {"level":"info","ts":"2026-10-09T02:45:07.218062Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7980,"took":"106.324828ms","hash":73338904,"current-db-size-bytes":55758848,"current-db-size":"56 MB","current-db-size-in-use-bytes":24944640,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-10-09T02:45:07.218136Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":73338904,"revision":7980,"compact-revision":5623} {"level":"info","ts":"2026-10-09T02:50:07.116993Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10372} {"level":"info","ts":"2026-10-09T02:50:07.207345Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10372,"took":"88.048103ms","hash":3336488259,"current-db-size-bytes":55758848,"current-db-size":"56 MB","current-db-size-in-use-bytes":25792512,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-09T02:50:07.20743Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3336488259,"revision":10372,"compact-revision":7980} {"level":"info","ts":"2026-10-09T02:55:07.123034Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12535} {"level":"info","ts":"2026-10-09T02:55:07.209956Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12535,"took":"84.494846ms","hash":500483125,"current-db-size-bytes":55758848,"current-db-size":"56 MB","current-db-size-in-use-bytes":27566080,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-09T02:55:07.210068Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":500483125,"revision":12535,"compact-revision":10372} {"level":"info","ts":"2026-10-09T02:55:27.800287Z","caller":"traceutil/trace.go:171","msg":"trace[1050293945] linearizableReadLoop","detail":"{readStateIndex:15219; appliedIndex:15218; }","duration":"101.864799ms","start":"2026-10-09T02:55:27.698402Z","end":"2026-10-09T02:55:27.800267Z","steps":["trace[1050293945] 'read index received' (duration: 101.647264ms)","trace[1050293945] 'applied index is now lower than readState.Index' (duration: 216.435µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:55:27.800368Z","caller":"traceutil/trace.go:171","msg":"trace[424650870] transaction","detail":"{read_only:false; response_revision:14703; number_of_response:1; }","duration":"124.687819ms","start":"2026-10-09T02:55:27.675641Z","end":"2026-10-09T02:55:27.800329Z","steps":["trace[424650870] 'process raft request' (duration: 124.474775ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:55:27.800522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.099674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-9jsvl\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-10-09T02:55:27.800623Z","caller":"traceutil/trace.go:171","msg":"trace[255638892] range","detail":"{range_begin:/registry/pods/openstack/keepalived-9jsvl; range_end:; response_count:1; response_revision:14703; }","duration":"102.228317ms","start":"2026-10-09T02:55:27.698378Z","end":"2026-10-09T02:55:27.800607Z","steps":["trace[255638892] 'agreement among raft nodes before linearized reading' (duration: 102.028991ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:55:48.551105Z","caller":"traceutil/trace.go:171","msg":"trace[446533604] linearizableReadLoop","detail":"{readStateIndex:15334; appliedIndex:15333; }","duration":"113.808825ms","start":"2026-10-09T02:55:48.437264Z","end":"2026-10-09T02:55:48.551073Z","steps":["trace[446533604] 'read index received' (duration: 113.314174ms)","trace[446533604] 'applied index is now lower than readState.Index' (duration: 493.111µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:55:48.551156Z","caller":"traceutil/trace.go:171","msg":"trace[1844188762] transaction","detail":"{read_only:false; response_revision:14814; number_of_response:1; }","duration":"225.442454ms","start":"2026-10-09T02:55:48.325692Z","end":"2026-10-09T02:55:48.551134Z","steps":["trace[1844188762] 'process raft request' (duration: 224.982025ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:55:48.551333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.061781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-09T02:55:48.551379Z","caller":"traceutil/trace.go:171","msg":"trace[827241205] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14814; }","duration":"114.160643ms","start":"2026-10-09T02:55:48.437204Z","end":"2026-10-09T02:55:48.551365Z","steps":["trace[827241205] 'agreement among raft nodes before linearized reading' (duration: 113.996979ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:56:06.561297Z","caller":"traceutil/trace.go:171","msg":"trace[549723209] transaction","detail":"{read_only:false; response_revision:14920; number_of_response:1; }","duration":"236.660535ms","start":"2026-10-09T02:56:06.324598Z","end":"2026-10-09T02:56:06.561259Z","steps":["trace[549723209] 'process raft request' (duration: 236.44103ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:56:06.725638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.595248ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-09T02:56:06.725757Z","caller":"traceutil/trace.go:171","msg":"trace[1424248239] linearizableReadLoop","detail":"{readStateIndex:15446; appliedIndex:15444; }","duration":"271.533945ms","start":"2026-10-09T02:56:06.454203Z","end":"2026-10-09T02:56:06.725737Z","steps":["trace[1424248239] 'read index received' (duration: 106.920127ms)","trace[1424248239] 'applied index is now lower than readState.Index' (duration: 164.611998ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T02:56:06.725921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.724289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-09T02:56:06.725889Z","caller":"traceutil/trace.go:171","msg":"trace[1289396894] transaction","detail":"{read_only:false; response_revision:14921; number_of_response:1; }","duration":"399.452894ms","start":"2026-10-09T02:56:06.326378Z","end":"2026-10-09T02:56:06.725831Z","steps":["trace[1289396894] 'process raft request' (duration: 281.502358ms)","trace[1289396894] 'compare' (duration: 117.298322ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:56:06.725965Z","caller":"traceutil/trace.go:171","msg":"trace[410865096] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:14921; }","duration":"271.816121ms","start":"2026-10-09T02:56:06.454131Z","end":"2026-10-09T02:56:06.725947Z","steps":["trace[410865096] 'agreement among raft nodes before linearized reading' (duration: 271.706559ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:56:06.726101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:56:06.326331Z","time spent":"399.651378ms","remote":"127.0.0.1:37248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T02:56:06.726221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.065383ms","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:7"} {"level":"info","ts":"2026-10-09T02:56:06.726254Z","caller":"traceutil/trace.go:171","msg":"trace[1129450795] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:14921; }","duration":"142.100784ms","start":"2026-10-09T02:56:06.584142Z","end":"2026-10-09T02:56:06.726242Z","steps":["trace[1129450795] 'agreement among raft nodes before linearized reading' (duration: 142.042952ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:56:30.989386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.019679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-10-09T02:56:30.989467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.573452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-09T02:56:30.989501Z","caller":"traceutil/trace.go:171","msg":"trace[896954183] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15244; }","duration":"127.204493ms","start":"2026-10-09T02:56:30.862276Z","end":"2026-10-09T02:56:30.98948Z","steps":["trace[896954183] 'range keys from in-memory index tree' (duration: 126.833694ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:56:30.989542Z","caller":"traceutil/trace.go:171","msg":"trace[1716167700] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15244; }","duration":"121.690645ms","start":"2026-10-09T02:56:30.867833Z","end":"2026-10-09T02:56:30.989524Z","steps":["trace[1716167700] 'range keys from in-memory index tree' (duration: 121.414799ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:56:31.283181Z","caller":"traceutil/trace.go:171","msg":"trace[2113718498] linearizableReadLoop","detail":"{readStateIndex:15785; appliedIndex:15784; }","duration":"148.520261ms","start":"2026-10-09T02:56:31.13463Z","end":"2026-10-09T02:56:31.283151Z","steps":["trace[2113718498] 'read index received' (duration: 138.889515ms)","trace[2113718498] 'applied index is now lower than readState.Index' (duration: 9.629436ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:56:31.283259Z","caller":"traceutil/trace.go:171","msg":"trace[1724527907] transaction","detail":"{read_only:false; response_revision:15246; number_of_response:1; }","duration":"290.041642ms","start":"2026-10-09T02:56:30.993182Z","end":"2026-10-09T02:56:31.283223Z","steps":["trace[1724527907] 'process raft request' (duration: 280.320443ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:56:31.283413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.762826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-5mmft\" ","response":"range_response_count:1 size:20660"} {"level":"info","ts":"2026-10-09T02:56:31.283453Z","caller":"traceutil/trace.go:171","msg":"trace[1713458346] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-5mmft; range_end:; response_count:1; response_revision:15246; }","duration":"148.88103ms","start":"2026-10-09T02:56:31.134559Z","end":"2026-10-09T02:56:31.28344Z","steps":["trace[1713458346] 'agreement among raft nodes before linearized reading' (duration: 148.689536ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:56:31.284091Z","caller":"traceutil/trace.go:171","msg":"trace[1753893567] transaction","detail":"{read_only:false; response_revision:15247; number_of_response:1; }","duration":"129.040133ms","start":"2026-10-09T02:56:31.155023Z","end":"2026-10-09T02:56:31.284063Z","steps":["trace[1753893567] 'process raft request' (duration: 128.89959ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:57:58.910029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.769214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T02:57:58.910138Z","caller":"traceutil/trace.go:171","msg":"trace[362411320] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:16065; }","duration":"161.912848ms","start":"2026-10-09T02:57:58.748205Z","end":"2026-10-09T02:57:58.910118Z","steps":["trace[362411320] 'range keys from in-memory index tree' (duration: 161.708983ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:00:07.130238Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14555} {"level":"info","ts":"2026-10-09T03:00:07.216246Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14555,"took":"83.125026ms","hash":1932506726,"current-db-size-bytes":55758848,"current-db-size":"56 MB","current-db-size-in-use-bytes":31633408,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-09T03:00:07.216326Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1932506726,"revision":14555,"compact-revision":12535} {"level":"info","ts":"2026-10-09T03:04:10.984766Z","caller":"traceutil/trace.go:171","msg":"trace[457506086] linearizableReadLoop","detail":"{readStateIndex:19471; appliedIndex:19470; }","duration":"245.014415ms","start":"2026-10-09T03:04:10.739725Z","end":"2026-10-09T03:04:10.984739Z","steps":["trace[457506086] 'read index received' (duration: 244.739749ms)","trace[457506086] 'applied index is now lower than readState.Index' (duration: 273.486µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:04:10.984838Z","caller":"traceutil/trace.go:171","msg":"trace[325760268] transaction","detail":"{read_only:false; response_revision:18820; number_of_response:1; }","duration":"318.024071ms","start":"2026-10-09T03:04:10.666776Z","end":"2026-10-09T03:04:10.9848Z","steps":["trace[325760268] 'process raft request' (duration: 317.788686ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:04:10.98497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.276028ms","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-10-09T03:04:10.985013Z","caller":"traceutil/trace.go:171","msg":"trace[732411515] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:18820; }","duration":"133.38273ms","start":"2026-10-09T03:04:10.851618Z","end":"2026-10-09T03:04:10.985Z","steps":["trace[732411515] 'agreement among raft nodes before linearized reading' (duration: 133.253748ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:04:10.985011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:04:10.666741Z","time spent":"318.177444ms","remote":"127.0.0.1:37174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20878,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T03:04:10.985108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.340102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-09T03:04:10.985215Z","caller":"traceutil/trace.go:171","msg":"trace[787493989] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18820; }","duration":"245.512494ms","start":"2026-10-09T03:04:10.739679Z","end":"2026-10-09T03:04:10.985191Z","steps":["trace[787493989] 'agreement among raft nodes before linearized reading' (duration: 245.191118ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:04:11.337556Z","caller":"traceutil/trace.go:171","msg":"trace[25692000] transaction","detail":"{read_only:false; response_revision:18822; number_of_response:1; }","duration":"204.65195ms","start":"2026-10-09T03:04:11.13288Z","end":"2026-10-09T03:04:11.337532Z","steps":["trace[25692000] 'process raft request' (duration: 204.493627ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:04:11.35224Z","caller":"traceutil/trace.go:171","msg":"trace[838680205] transaction","detail":"{read_only:false; response_revision:18823; number_of_response:1; }","duration":"217.749819ms","start":"2026-10-09T03:04:11.134465Z","end":"2026-10-09T03:04:11.352215Z","steps":["trace[838680205] 'process raft request' (duration: 217.568226ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:04:11.352576Z","caller":"traceutil/trace.go:171","msg":"trace[725738862] linearizableReadLoop","detail":"{readStateIndex:19474; appliedIndex:19472; }","duration":"155.159278ms","start":"2026-10-09T03:04:11.197391Z","end":"2026-10-09T03:04:11.35255Z","steps":["trace[725738862] 'read index received' (duration: 139.987193ms)","trace[725738862] 'applied index is now lower than readState.Index' (duration: 15.171055ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:04:11.352583Z","caller":"traceutil/trace.go:171","msg":"trace[2000206825] transaction","detail":"{read_only:false; response_revision:18824; number_of_response:1; }","duration":"119.762809ms","start":"2026-10-09T03:04:11.232793Z","end":"2026-10-09T03:04:11.352555Z","steps":["trace[2000206825] 'process raft request' (duration: 119.376801ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:04:11.352765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.356282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-09T03:04:11.353368Z","caller":"traceutil/trace.go:171","msg":"trace[828283228] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18825; }","duration":"155.997187ms","start":"2026-10-09T03:04:11.197348Z","end":"2026-10-09T03:04:11.353345Z","steps":["trace[828283228] 'agreement among raft nodes before linearized reading' (duration: 155.284981ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:04:11.545668Z","caller":"traceutil/trace.go:171","msg":"trace[1275469998] transaction","detail":"{read_only:false; response_revision:18826; number_of_response:1; }","duration":"188.193277ms","start":"2026-10-09T03:04:11.357452Z","end":"2026-10-09T03:04:11.545645Z","steps":["trace[1275469998] 'process raft request' (duration: 169.993226ms)","trace[1275469998] 'compare' (duration: 17.66281ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:04:11.586618Z","caller":"traceutil/trace.go:171","msg":"trace[1214281259] linearizableReadLoop","detail":"{readStateIndex:19478; appliedIndex:19476; }","duration":"200.976951ms","start":"2026-10-09T03:04:11.38562Z","end":"2026-10-09T03:04:11.586597Z","steps":["trace[1214281259] 'read index received' (duration: 141.735181ms)","trace[1214281259] 'applied index is now lower than readState.Index' (duration: 59.24097ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:04:11.586731Z","caller":"traceutil/trace.go:171","msg":"trace[1330993287] transaction","detail":"{read_only:false; response_revision:18827; number_of_response:1; }","duration":"228.471029ms","start":"2026-10-09T03:04:11.358251Z","end":"2026-10-09T03:04:11.586722Z","steps":["trace[1330993287] 'process raft request' (duration: 228.254855ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:04:11.58707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.427841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-5g2gh\" ","response":"range_response_count:1 size:9800"} {"level":"info","ts":"2026-10-09T03:04:11.587155Z","caller":"traceutil/trace.go:171","msg":"trace[1606752104] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-5g2gh; range_end:; response_count:1; response_revision:18827; }","duration":"201.571524ms","start":"2026-10-09T03:04:11.385565Z","end":"2026-10-09T03:04:11.587137Z","steps":["trace[1606752104] 'agreement among raft nodes before linearized reading' (duration: 201.326409ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:04:48.314784Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"40fb1eca294b6a5a","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-09T03:04:48.328532Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-09T03:04:48.328614Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-09T03:05:07.13799Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16966} {"level":"info","ts":"2026-10-09T03:05:07.255782Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16966,"took":"114.430204ms","hash":1931453910,"current-db-size-bytes":55758848,"current-db-size":"56 MB","current-db-size-in-use-bytes":33607680,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-09T03:05:07.255868Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1931453910,"revision":16966,"compact-revision":14555} {"level":"info","ts":"2026-10-09T03:09:40.707384Z","caller":"traceutil/trace.go:171","msg":"trace[1167808090] transaction","detail":"{read_only:false; response_revision:21468; number_of_response:1; }","duration":"213.152963ms","start":"2026-10-09T03:09:40.494205Z","end":"2026-10-09T03:09:40.707358Z","steps":["trace[1167808090] 'process raft request' (duration: 212.99644ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:09:40.721108Z","caller":"traceutil/trace.go:171","msg":"trace[1589589469] linearizableReadLoop","detail":"{readStateIndex:22207; appliedIndex:22205; }","duration":"146.406851ms","start":"2026-10-09T03:09:40.574679Z","end":"2026-10-09T03:09:40.721086Z","steps":["trace[1589589469] 'read index received' (duration: 132.532733ms)","trace[1589589469] 'applied index is now lower than readState.Index' (duration: 13.873078ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:09:40.721232Z","caller":"traceutil/trace.go:171","msg":"trace[1117221013] transaction","detail":"{read_only:false; response_revision:21469; number_of_response:1; }","duration":"148.337422ms","start":"2026-10-09T03:09:40.572866Z","end":"2026-10-09T03:09:40.721203Z","steps":["trace[1117221013] 'process raft request' (duration: 145.00464ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:09:40.721556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.8545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.156\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-10-09T03:09:40.721649Z","caller":"traceutil/trace.go:171","msg":"trace[1195996777] range","detail":"{range_begin:/registry/masterleases/162.253.55.156; range_end:; response_count:1; response_revision:21472; }","duration":"146.983673ms","start":"2026-10-09T03:09:40.574645Z","end":"2026-10-09T03:09:40.721628Z","steps":["trace[1195996777] 'agreement among raft nodes before linearized reading' (duration: 146.532313ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:09:50.985048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.158639ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T03:09:50.985155Z","caller":"traceutil/trace.go:171","msg":"trace[606301254] linearizableReadLoop","detail":"{readStateIndex:22265; appliedIndex:22264; }","duration":"177.605079ms","start":"2026-10-09T03:09:50.807533Z","end":"2026-10-09T03:09:50.985139Z","steps":["trace[606301254] 'read index received' (duration: 56.207376ms)","trace[606301254] 'applied index is now lower than readState.Index' (duration: 121.396463ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:09:50.985329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.802883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-09T03:09:50.985363Z","caller":"traceutil/trace.go:171","msg":"trace[284996227] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21524; }","duration":"177.882794ms","start":"2026-10-09T03:09:50.80747Z","end":"2026-10-09T03:09:50.985353Z","steps":["trace[284996227] 'agreement among raft nodes before linearized reading' (duration: 177.747411ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:09:50.98533Z","caller":"traceutil/trace.go:171","msg":"trace[793337870] transaction","detail":"{read_only:false; response_revision:21524; number_of_response:1; }","duration":"180.776407ms","start":"2026-10-09T03:09:50.804433Z","end":"2026-10-09T03:09:50.985209Z","steps":["trace[793337870] 'process raft request' (duration: 59.372483ms)","trace[793337870] 'compare' (duration: 121.044066ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:09:57.080349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.044352ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T03:09:57.080495Z","caller":"traceutil/trace.go:171","msg":"trace[270324621] linearizableReadLoop","detail":"{readStateIndex:22297; appliedIndex:22296; }","duration":"198.705848ms","start":"2026-10-09T03:09:56.881768Z","end":"2026-10-09T03:09:57.080474Z","steps":["trace[270324621] 'read index received' (duration: 55.164795ms)","trace[270324621] 'applied index is now lower than readState.Index' (duration: 143.539473ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:09:57.080582Z","caller":"traceutil/trace.go:171","msg":"trace[336897510] transaction","detail":"{read_only:false; response_revision:21555; number_of_response:1; }","duration":"252.36425ms","start":"2026-10-09T03:09:56.828205Z","end":"2026-10-09T03:09:57.080569Z","steps":["trace[336897510] 'process raft request' (duration: 108.735256ms)","trace[336897510] 'compare' (duration: 142.270065ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:09:57.080938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.137597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/heat-api-7c977d9cc6-6qrww.18dcbda4b3f9592b\" ","response":"range_response_count:1 size:907"} {"level":"info","ts":"2026-10-09T03:09:57.081042Z","caller":"traceutil/trace.go:171","msg":"trace[1090585101] range","detail":"{range_begin:/registry/events/openstack/heat-api-7c977d9cc6-6qrww.18dcbda4b3f9592b; range_end:; response_count:1; response_revision:21555; }","duration":"199.343482ms","start":"2026-10-09T03:09:56.881669Z","end":"2026-10-09T03:09:57.081013Z","steps":["trace[1090585101] 'agreement among raft nodes before linearized reading' (duration: 199.024365ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:09:57.389573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.171152ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T03:09:57.389702Z","caller":"traceutil/trace.go:171","msg":"trace[674974632] linearizableReadLoop","detail":"{readStateIndex:22300; appliedIndex:22299; }","duration":"280.092316ms","start":"2026-10-09T03:09:57.109588Z","end":"2026-10-09T03:09:57.389681Z","steps":["trace[674974632] 'read index received' (duration: 132.795542ms)","trace[674974632] 'applied index is now lower than readState.Index' (duration: 147.295304ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:09:57.389805Z","caller":"traceutil/trace.go:171","msg":"trace[1068310987] transaction","detail":"{read_only:false; response_revision:21558; number_of_response:1; }","duration":"289.925957ms","start":"2026-10-09T03:09:57.099825Z","end":"2026-10-09T03:09:57.389751Z","steps":["trace[1068310987] 'process raft request' (duration: 142.454209ms)","trace[1068310987] 'compare' (duration: 146.516148ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:09:57.390123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.508505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/heat-cfn-79fdcbc474-wh6j4.18dcbda4b4ec32f0\" ","response":"range_response_count:1 size:905"} {"level":"info","ts":"2026-10-09T03:09:57.390242Z","caller":"traceutil/trace.go:171","msg":"trace[1602243261] range","detail":"{range_begin:/registry/events/openstack/heat-cfn-79fdcbc474-wh6j4.18dcbda4b4ec32f0; range_end:; response_count:1; response_revision:21558; }","duration":"280.676249ms","start":"2026-10-09T03:09:57.109537Z","end":"2026-10-09T03:09:57.390214Z","steps":["trace[1602243261] 'agreement among raft nodes before linearized reading' (duration: 280.236979ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:09:57.393064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.507991ms","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-10-09T03:09:57.393128Z","caller":"traceutil/trace.go:171","msg":"trace[1067982761] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:21559; }","duration":"264.614383ms","start":"2026-10-09T03:09:57.128496Z","end":"2026-10-09T03:09:57.39311Z","steps":["trace[1067982761] 'agreement among raft nodes before linearized reading' (duration: 264.474221ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:09:57.393124Z","caller":"traceutil/trace.go:171","msg":"trace[173821870] transaction","detail":"{read_only:false; response_revision:21559; number_of_response:1; }","duration":"218.208307ms","start":"2026-10-09T03:09:57.174877Z","end":"2026-10-09T03:09:57.393085Z","steps":["trace[173821870] 'process raft request' (duration: 217.9209ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:09:57.617564Z","caller":"traceutil/trace.go:171","msg":"trace[1890248538] linearizableReadLoop","detail":"{readStateIndex:22302; appliedIndex:22301; }","duration":"220.397793ms","start":"2026-10-09T03:09:57.397119Z","end":"2026-10-09T03:09:57.617517Z","steps":["trace[1890248538] 'read index received' (duration: 129.611793ms)","trace[1890248538] 'applied index is now lower than readState.Index' (duration: 90.78453ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:09:57.61763Z","caller":"traceutil/trace.go:171","msg":"trace[1876254120] transaction","detail":"{read_only:false; response_revision:21560; number_of_response:1; }","duration":"225.130455ms","start":"2026-10-09T03:09:57.392464Z","end":"2026-10-09T03:09:57.617594Z","steps":["trace[1876254120] 'process raft request' (duration: 134.348415ms)","trace[1876254120] 'compare' (duration: 90.463423ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:09:57.617911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.039356ms","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-10-09T03:09:57.617916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.758202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-20f754f1-59f0-43e7-8dbd-f4ce579c5edd\" ","response":"range_response_count:1 size:1319"} {"level":"info","ts":"2026-10-09T03:09:57.618032Z","caller":"traceutil/trace.go:171","msg":"trace[1915203727] range","detail":"{range_begin:/registry/persistentvolumes/pvc-20f754f1-59f0-43e7-8dbd-f4ce579c5edd; range_end:; response_count:1; response_revision:21560; }","duration":"220.937825ms","start":"2026-10-09T03:09:57.397063Z","end":"2026-10-09T03:09:57.618001Z","steps":["trace[1915203727] 'agreement among raft nodes before linearized reading' (duration: 220.602849ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:09:57.618093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.750467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:19061"} {"level":"info","ts":"2026-10-09T03:09:57.618139Z","caller":"traceutil/trace.go:171","msg":"trace[1658448704] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:21560; }","duration":"217.818189ms","start":"2026-10-09T03:09:57.40031Z","end":"2026-10-09T03:09:57.618129Z","steps":["trace[1658448704] 'agreement among raft nodes before linearized reading' (duration: 217.672975ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:09:57.617962Z","caller":"traceutil/trace.go:171","msg":"trace[70729872] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:21560; }","duration":"220.165928ms","start":"2026-10-09T03:09:57.397782Z","end":"2026-10-09T03:09:57.617948Z","steps":["trace[70729872] 'agreement among raft nodes before linearized reading' (duration: 219.997585ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:09:57.61821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.482928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-09T03:09:57.618354Z","caller":"traceutil/trace.go:171","msg":"trace[1091875448] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21560; }","duration":"174.686672ms","start":"2026-10-09T03:09:57.443632Z","end":"2026-10-09T03:09:57.618318Z","steps":["trace[1091875448] 'agreement among raft nodes before linearized reading' (duration: 174.276303ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:10:07.144304Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19511} {"level":"info","ts":"2026-10-09T03:10:07.314309Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19511,"took":"165.053865ms","hash":2710534327,"current-db-size-bytes":55758848,"current-db-size":"56 MB","current-db-size-in-use-bytes":29646848,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-09T03:10:07.314383Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2710534327,"revision":19511,"compact-revision":16966} {"level":"info","ts":"2026-10-09T03:13:55.638489Z","caller":"traceutil/trace.go:171","msg":"trace[1314134761] transaction","detail":"{read_only:false; response_revision:24064; number_of_response:1; }","duration":"106.498917ms","start":"2026-10-09T03:13:55.531969Z","end":"2026-10-09T03:13:55.638468Z","steps":["trace[1314134761] 'process raft request' (duration: 106.360904ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:15:07.152858Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21630} {"level":"info","ts":"2026-10-09T03:15:07.262408Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21630,"took":"106.574569ms","hash":379122210,"current-db-size-bytes":56180736,"current-db-size":"56 MB","current-db-size-in-use-bytes":43442176,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-10-09T03:15:07.262479Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":379122210,"revision":21630,"compact-revision":19511} {"level":"info","ts":"2026-10-09T03:17:08.373247Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000068af.wal"} {"level":"warn","ts":"2026-10-09T03:17:22.438107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.740811ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T03:17:22.438334Z","caller":"traceutil/trace.go:171","msg":"trace[702243586] transaction","detail":"{read_only:false; response_revision:26150; number_of_response:1; }","duration":"289.102709ms","start":"2026-10-09T03:17:22.149195Z","end":"2026-10-09T03:17:22.438298Z","steps":["trace[702243586] 'process raft request' (duration: 122.083212ms)","trace[702243586] 'compare' (duration: 166.165898ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:17:22.438609Z","caller":"traceutil/trace.go:171","msg":"trace[1458479597] linearizableReadLoop","detail":"{readStateIndex:27023; appliedIndex:27021; }","duration":"198.729789ms","start":"2026-10-09T03:17:22.239843Z","end":"2026-10-09T03:17:22.438573Z","steps":["trace[1458479597] 'read index received' (duration: 31.526318ms)","trace[1458479597] 'applied index is now lower than readState.Index' (duration: 167.201501ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:17:22.438668Z","caller":"traceutil/trace.go:171","msg":"trace[1550144046] transaction","detail":"{read_only:false; response_revision:26151; number_of_response:1; }","duration":"289.057958ms","start":"2026-10-09T03:17:22.149539Z","end":"2026-10-09T03:17:22.438597Z","steps":["trace[1550144046] 'process raft request' (duration: 288.67859ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:17:22.438869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.003874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-10-09T03:17:22.438939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.423461ms","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-10-09T03:17:22.438973Z","caller":"traceutil/trace.go:171","msg":"trace[283826002] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26151; }","duration":"199.144748ms","start":"2026-10-09T03:17:22.239802Z","end":"2026-10-09T03:17:22.438946Z","steps":["trace[283826002] 'agreement among raft nodes before linearized reading' (duration: 198.858561ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:17:22.439002Z","caller":"traceutil/trace.go:171","msg":"trace[1699267018] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26151; }","duration":"191.522443ms","start":"2026-10-09T03:17:22.247461Z","end":"2026-10-09T03:17:22.438984Z","steps":["trace[1699267018] 'agreement among raft nodes before linearized reading' (duration: 191.317409ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:17:22.439548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.573773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T03:17:22.439623Z","caller":"traceutil/trace.go:171","msg":"trace[823632818] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:26151; }","duration":"177.319469ms","start":"2026-10-09T03:17:22.262291Z","end":"2026-10-09T03:17:22.43961Z","steps":["trace[823632818] 'agreement among raft nodes before linearized reading' (duration: 176.562112ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:17:22.653338Z","caller":"traceutil/trace.go:171","msg":"trace[449460318] transaction","detail":"{read_only:false; response_revision:26154; number_of_response:1; }","duration":"208.426246ms","start":"2026-10-09T03:17:22.444871Z","end":"2026-10-09T03:17:22.653297Z","steps":["trace[449460318] 'process raft request' (duration: 208.371666ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:17:22.653617Z","caller":"traceutil/trace.go:171","msg":"trace[1263047677] transaction","detail":"{read_only:false; response_revision:26153; number_of_response:1; }","duration":"209.938419ms","start":"2026-10-09T03:17:22.443645Z","end":"2026-10-09T03:17:22.653583Z","steps":["trace[1263047677] 'process raft request' (duration: 181.692792ms)","trace[1263047677] 'compare' (duration: 27.721385ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:17:23.007543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.171827ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T03:17:23.007882Z","caller":"traceutil/trace.go:171","msg":"trace[111845141] transaction","detail":"{read_only:false; response_revision:26157; number_of_response:1; }","duration":"416.068347ms","start":"2026-10-09T03:17:22.591792Z","end":"2026-10-09T03:17:23.00786Z","steps":["trace[111845141] 'process raft request' (duration: 415.998635ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:17:23.00795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:17:22.591785Z","time spent":"416.130518ms","remote":"127.0.0.1:37248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T03:17:23.007969Z","caller":"traceutil/trace.go:171","msg":"trace[1449479998] transaction","detail":"{read_only:false; response_revision:26155; number_of_response:1; }","duration":"473.93699ms","start":"2026-10-09T03:17:22.534002Z","end":"2026-10-09T03:17:23.007939Z","steps":["trace[1449479998] 'process raft request' (duration: 302.261271ms)","trace[1449479998] 'compare' (duration: 170.799539ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:17:23.008001Z","caller":"traceutil/trace.go:171","msg":"trace[1372462810] transaction","detail":"{read_only:false; response_revision:26156; number_of_response:1; }","duration":"417.079728ms","start":"2026-10-09T03:17:22.590904Z","end":"2026-10-09T03:17:23.007984Z","steps":["trace[1372462810] 'process raft request' (duration: 416.765792ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:17:23.00811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:17:22.590887Z","time spent":"417.15692ms","remote":"127.0.0.1:37248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T03:17:23.008343Z","caller":"traceutil/trace.go:171","msg":"trace[120602632] transaction","detail":"{read_only:false; response_revision:26158; number_of_response:1; }","duration":"414.409301ms","start":"2026-10-09T03:17:22.593925Z","end":"2026-10-09T03:17:23.008334Z","steps":["trace[120602632] 'process raft request' (duration: 413.90106ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:17:23.008384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:17:22.593917Z","time spent":"414.442532ms","remote":"127.0.0.1:37248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T03:17:23.008423Z","caller":"traceutil/trace.go:171","msg":"trace[2019104029] linearizableReadLoop","detail":"{readStateIndex:27030; appliedIndex:27026; }","duration":"256.405828ms","start":"2026-10-09T03:17:22.751982Z","end":"2026-10-09T03:17:23.008388Z","steps":["trace[2019104029] 'read index received' (duration: 84.28252ms)","trace[2019104029] 'applied index is now lower than readState.Index' (duration: 172.121568ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:17:23.008447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:17:22.533977Z","time spent":"474.052642ms","remote":"127.0.0.1:37248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T03:17:23.008526Z","caller":"traceutil/trace.go:171","msg":"trace[1617471824] transaction","detail":"{read_only:false; response_revision:26159; number_of_response:1; }","duration":"188.54649ms","start":"2026-10-09T03:17:22.819972Z","end":"2026-10-09T03:17:23.008518Z","steps":["trace[1617471824] 'process raft request' (duration: 188.394407ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:17:23.008644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.638983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T03:17:23.008741Z","caller":"traceutil/trace.go:171","msg":"trace[1515656807] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26159; }","duration":"256.756364ms","start":"2026-10-09T03:17:22.75194Z","end":"2026-10-09T03:17:23.008696Z","steps":["trace[1515656807] 'agreement among raft nodes before linearized reading' (duration: 256.55267ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:17:23.122855Z","caller":"traceutil/trace.go:171","msg":"trace[80765584] linearizableReadLoop","detail":"{readStateIndex:27032; appliedIndex:27031; }","duration":"114.331046ms","start":"2026-10-09T03:17:23.008499Z","end":"2026-10-09T03:17:23.12283Z","steps":["trace[80765584] 'read index received' (duration: 103.421851ms)","trace[80765584] 'applied index is now lower than readState.Index' (duration: 10.908365ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:17:23.12292Z","caller":"traceutil/trace.go:171","msg":"trace[947460058] transaction","detail":"{read_only:false; response_revision:26160; number_of_response:1; }","duration":"202.285305ms","start":"2026-10-09T03:17:22.920602Z","end":"2026-10-09T03:17:23.122887Z","steps":["trace[947460058] 'process raft request' (duration: 191.382611ms)","trace[947460058] 'compare' (duration: 10.69633ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:17:23.123019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.289289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T03:17:23.123065Z","caller":"traceutil/trace.go:171","msg":"trace[1636608066] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:26160; }","duration":"205.418641ms","start":"2026-10-09T03:17:22.917631Z","end":"2026-10-09T03:17:23.12305Z","steps":["trace[1636608066] 'agreement among raft nodes before linearized reading' (duration: 205.32449ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:17:23.123171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.679771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T03:17:23.123247Z","caller":"traceutil/trace.go:171","msg":"trace[1944030505] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26160; }","duration":"112.803222ms","start":"2026-10-09T03:17:23.010426Z","end":"2026-10-09T03:17:23.123229Z","steps":["trace[1944030505] 'agreement among raft nodes before linearized reading' (duration: 112.643509ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:17:28.421835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.057509ms","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-10-09T03:17:28.421908Z","caller":"traceutil/trace.go:171","msg":"trace[469514432] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26200; }","duration":"127.212241ms","start":"2026-10-09T03:17:28.29468Z","end":"2026-10-09T03:17:28.421892Z","steps":["trace[469514432] 'range keys from in-memory index tree' (duration: 126.943777ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:17:28.649045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.127549ms","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-10-09T03:17:28.649112Z","caller":"traceutil/trace.go:171","msg":"trace[1358260868] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26202; }","duration":"126.236631ms","start":"2026-10-09T03:17:28.522861Z","end":"2026-10-09T03:17:28.649097Z","steps":["trace[1358260868] 'range keys from in-memory index tree' (duration: 126.035017ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:17:28.75065Z","caller":"traceutil/trace.go:171","msg":"trace[1941153991] linearizableReadLoop","detail":"{readStateIndex:27076; appliedIndex:27075; }","duration":"100.101101ms","start":"2026-10-09T03:17:28.650529Z","end":"2026-10-09T03:17:28.750631Z","steps":["trace[1941153991] 'read index received' (duration: 100.001359ms)","trace[1941153991] 'applied index is now lower than readState.Index' (duration: 98.892µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:17:28.750759Z","caller":"traceutil/trace.go:171","msg":"trace[4915325] transaction","detail":"{read_only:false; response_revision:26203; number_of_response:1; }","duration":"206.891354ms","start":"2026-10-09T03:17:28.543797Z","end":"2026-10-09T03:17:28.750688Z","steps":["trace[4915325] 'process raft request' (duration: 206.70102ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:17:28.750858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.307714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19030"} {"level":"info","ts":"2026-10-09T03:17:28.750883Z","caller":"traceutil/trace.go:171","msg":"trace[1986989526] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:26203; }","duration":"100.367635ms","start":"2026-10-09T03:17:28.650508Z","end":"2026-10-09T03:17:28.750876Z","steps":["trace[1986989526] 'agreement among raft nodes before linearized reading' (duration: 100.250393ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:17:28.926648Z","caller":"traceutil/trace.go:171","msg":"trace[1505364692] transaction","detail":"{read_only:false; response_revision:26204; number_of_response:1; }","duration":"171.950353ms","start":"2026-10-09T03:17:28.754668Z","end":"2026-10-09T03:17:28.926618Z","steps":["trace[1505364692] 'process raft request' (duration: 171.78294ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:17:28.927538Z","caller":"traceutil/trace.go:171","msg":"trace[1206388000] linearizableReadLoop","detail":"{readStateIndex:27078; appliedIndex:27076; }","duration":"155.709233ms","start":"2026-10-09T03:17:28.771812Z","end":"2026-10-09T03:17:28.927521Z","steps":["trace[1206388000] 'read index received' (duration: 154.693212ms)","trace[1206388000] 'applied index is now lower than readState.Index' (duration: 1.015151ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:17:28.927662Z","caller":"traceutil/trace.go:171","msg":"trace[1478411218] transaction","detail":"{read_only:false; response_revision:26205; number_of_response:1; }","duration":"171.246168ms","start":"2026-10-09T03:17:28.756401Z","end":"2026-10-09T03:17:28.927647Z","steps":["trace[1478411218] 'process raft request' (duration: 171.013013ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:17:28.927918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.094043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-10-09T03:17:28.927963Z","caller":"traceutil/trace.go:171","msg":"trace[962906415] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:26205; }","duration":"156.297537ms","start":"2026-10-09T03:17:28.771653Z","end":"2026-10-09T03:17:28.927951Z","steps":["trace[962906415] 'agreement among raft nodes before linearized reading' (duration: 156.151384ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:17:29.138474Z","caller":"traceutil/trace.go:171","msg":"trace[864401586] transaction","detail":"{read_only:false; response_revision:26207; number_of_response:1; }","duration":"107.940538ms","start":"2026-10-09T03:17:29.03051Z","end":"2026-10-09T03:17:29.138451Z","steps":["trace[864401586] 'process raft request' (duration: 107.140621ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:17:29.447797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.745238ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T03:17:29.448347Z","caller":"traceutil/trace.go:171","msg":"trace[1049842016] transaction","detail":"{read_only:false; response_revision:26211; number_of_response:1; }","duration":"305.778747ms","start":"2026-10-09T03:17:29.142547Z","end":"2026-10-09T03:17:29.448326Z","steps":["trace[1049842016] 'process raft request' (duration: 140.413406ms)","trace[1049842016] 'compare' (duration: 164.625516ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:17:29.448857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:17:29.14252Z","time spent":"305.91296ms","remote":"127.0.0.1:37248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T03:17:29.723832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.684202ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T03:17:29.723938Z","caller":"traceutil/trace.go:171","msg":"trace[50303501] transaction","detail":"{read_only:false; response_revision:26212; number_of_response:1; }","duration":"188.618091ms","start":"2026-10-09T03:17:29.535298Z","end":"2026-10-09T03:17:29.723917Z","steps":["trace[50303501] 'process raft request' (duration: 40.758236ms)","trace[50303501] 'compare' (duration: 147.1645ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:17:30.009759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.513663ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T03:17:30.009879Z","caller":"traceutil/trace.go:171","msg":"trace[1169106319] transaction","detail":"{read_only:false; response_revision:26213; number_of_response:1; }","duration":"209.155903ms","start":"2026-10-09T03:17:29.800689Z","end":"2026-10-09T03:17:30.009844Z","steps":["trace[1169106319] 'process raft request' (duration: 78.443875ms)","trace[1169106319] 'compare' (duration: 130.34178ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:17:30.495882Z","caller":"traceutil/trace.go:171","msg":"trace[785573508] transaction","detail":"{read_only:false; response_revision:26214; number_of_response:1; }","duration":"260.368163ms","start":"2026-10-09T03:17:30.235494Z","end":"2026-10-09T03:17:30.495862Z","steps":["trace[785573508] 'process raft request' (duration: 260.24182ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:17:30.816856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.406148ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T03:17:30.817103Z","caller":"traceutil/trace.go:171","msg":"trace[1853823394] transaction","detail":"{read_only:false; response_revision:26215; number_of_response:1; }","duration":"307.416403ms","start":"2026-10-09T03:17:30.509651Z","end":"2026-10-09T03:17:30.817067Z","steps":["trace[1853823394] 'process raft request' (duration: 97.712778ms)","trace[1853823394] 'compare' (duration: 208.781684ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:17:30.81719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:17:30.509641Z","time spent":"307.508385ms","remote":"127.0.0.1:37248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T03:17:30.817177Z","caller":"traceutil/trace.go:171","msg":"trace[89209377] linearizableReadLoop","detail":"{readStateIndex:27088; appliedIndex:27087; }","duration":"292.084103ms","start":"2026-10-09T03:17:30.525069Z","end":"2026-10-09T03:17:30.817154Z","steps":["trace[89209377] 'read index received' (duration: 82.234995ms)","trace[89209377] 'applied index is now lower than readState.Index' (duration: 209.847878ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:17:30.817328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.25879ms","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-10-09T03:17:30.817369Z","caller":"traceutil/trace.go:171","msg":"trace[634368638] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26216; }","duration":"260.363531ms","start":"2026-10-09T03:17:30.556996Z","end":"2026-10-09T03:17:30.81736Z","steps":["trace[634368638] 'agreement among raft nodes before linearized reading' (duration: 260.27865ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:17:30.817383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.302748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-09T03:17:30.817445Z","caller":"traceutil/trace.go:171","msg":"trace[1163830463] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26216; }","duration":"292.4003ms","start":"2026-10-09T03:17:30.525029Z","end":"2026-10-09T03:17:30.817429Z","steps":["trace[1163830463] 'agreement among raft nodes before linearized reading' (duration: 292.207716ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:17:30.817596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.536971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.156\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-10-09T03:17:30.817627Z","caller":"traceutil/trace.go:171","msg":"trace[256714952] range","detail":"{range_begin:/registry/masterleases/162.253.55.156; range_end:; response_count:1; response_revision:26216; }","duration":"216.602252ms","start":"2026-10-09T03:17:30.601017Z","end":"2026-10-09T03:17:30.817619Z","steps":["trace[256714952] 'agreement among raft nodes before linearized reading' (duration: 216.515961ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:17:31.065536Z","caller":"traceutil/trace.go:171","msg":"trace[1217183041] linearizableReadLoop","detail":"{readStateIndex:27091; appliedIndex:27089; }","duration":"242.707883ms","start":"2026-10-09T03:17:30.822805Z","end":"2026-10-09T03:17:31.065513Z","steps":["trace[1217183041] 'read index received' (duration: 235.878326ms)","trace[1217183041] 'applied index is now lower than readState.Index' (duration: 6.828477ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:17:31.065652Z","caller":"traceutil/trace.go:171","msg":"trace[1902411209] transaction","detail":"{read_only:false; response_revision:26217; number_of_response:1; }","duration":"243.362577ms","start":"2026-10-09T03:17:30.822277Z","end":"2026-10-09T03:17:31.065639Z","steps":["trace[1902411209] 'process raft request' (duration: 243.039041ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:17:31.065947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.816989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-09T03:17:31.066022Z","caller":"traceutil/trace.go:171","msg":"trace[1613988436] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26217; }","duration":"130.948823ms","start":"2026-10-09T03:17:30.935056Z","end":"2026-10-09T03:17:31.066004Z","steps":["trace[1613988436] 'agreement among raft nodes before linearized reading' (duration: 130.756479ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:17:31.066038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.226135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T03:17:31.066088Z","caller":"traceutil/trace.go:171","msg":"trace[881798945] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:26217; }","duration":"243.278905ms","start":"2026-10-09T03:17:30.822794Z","end":"2026-10-09T03:17:31.066073Z","steps":["trace[881798945] 'agreement among raft nodes before linearized reading' (duration: 243.191864ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:17:31.066217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.751727ms","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-10-09T03:17:31.066312Z","caller":"traceutil/trace.go:171","msg":"trace[85374347] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26217; }","duration":"129.881089ms","start":"2026-10-09T03:17:30.936407Z","end":"2026-10-09T03:17:31.066288Z","steps":["trace[85374347] 'agreement among raft nodes before linearized reading' (duration: 129.619724ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:17:31.41598Z","caller":"traceutil/trace.go:171","msg":"trace[150873331] linearizableReadLoop","detail":"{readStateIndex:27094; appliedIndex:27091; }","duration":"340.143605ms","start":"2026-10-09T03:17:31.075819Z","end":"2026-10-09T03:17:31.415963Z","steps":["trace[150873331] 'read index received' (duration: 3.478774ms)","trace[150873331] 'applied index is now lower than readState.Index' (duration: 336.664021ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:17:31.416073Z","caller":"traceutil/trace.go:171","msg":"trace[1960095191] transaction","detail":"{read_only:false; response_revision:26219; number_of_response:1; }","duration":"345.123422ms","start":"2026-10-09T03:17:31.070938Z","end":"2026-10-09T03:17:31.416062Z","steps":["trace[1960095191] 'process raft request' (duration: 320.136796ms)","trace[1960095191] 'compare' (duration: 24.348252ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:17:31.416142Z","caller":"traceutil/trace.go:171","msg":"trace[425911081] transaction","detail":"{read_only:false; response_revision:26220; number_of_response:1; }","duration":"345.078932ms","start":"2026-10-09T03:17:31.071034Z","end":"2026-10-09T03:17:31.416113Z","steps":["trace[425911081] 'process raft request' (duration: 344.862407ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:17:31.416295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:17:31.07091Z","time spent":"345.303007ms","remote":"127.0.0.1:37248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T03:17:31.41636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:17:31.071026Z","time spent":"345.253726ms","remote":"127.0.0.1:37248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T03:17:31.416406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.586334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T03:17:31.416435Z","caller":"traceutil/trace.go:171","msg":"trace[184842491] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:26220; }","duration":"340.641056ms","start":"2026-10-09T03:17:31.075786Z","end":"2026-10-09T03:17:31.416427Z","steps":["trace[184842491] 'agreement among raft nodes before linearized reading' (duration: 340.595195ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:17:31.416458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:17:31.075774Z","time spent":"340.679557ms","remote":"127.0.0.1:37252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" "} {"level":"info","ts":"2026-10-09T03:17:31.513906Z","caller":"traceutil/trace.go:171","msg":"trace[1718948470] transaction","detail":"{read_only:false; response_revision:26221; number_of_response:1; }","duration":"394.993034ms","start":"2026-10-09T03:17:31.118888Z","end":"2026-10-09T03:17:31.513881Z","steps":["trace[1718948470] 'process raft request' (duration: 394.79995ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:17:31.514061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:17:31.118872Z","time spent":"395.107066ms","remote":"127.0.0.1:37248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T03:17:31.514263Z","caller":"traceutil/trace.go:171","msg":"trace[1995882502] transaction","detail":"{read_only:false; response_revision:26222; number_of_response:1; }","duration":"369.497087ms","start":"2026-10-09T03:17:31.144729Z","end":"2026-10-09T03:17:31.514226Z","steps":["trace[1995882502] 'process raft request' (duration: 369.117248ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:17:31.51432Z","caller":"traceutil/trace.go:171","msg":"trace[2034184750] transaction","detail":"{read_only:false; response_revision:26223; number_of_response:1; }","duration":"369.519477ms","start":"2026-10-09T03:17:31.144783Z","end":"2026-10-09T03:17:31.514302Z","steps":["trace[2034184750] 'process raft request' (duration: 369.368854ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:17:31.514449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:17:31.144701Z","time spent":"369.65239ms","remote":"127.0.0.1:37248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T03:17:31.514561Z","caller":"traceutil/trace.go:171","msg":"trace[1012864151] transaction","detail":"{read_only:false; response_revision:26225; number_of_response:1; }","duration":"367.128805ms","start":"2026-10-09T03:17:31.147408Z","end":"2026-10-09T03:17:31.514537Z","steps":["trace[1012864151] 'process raft request' (duration: 367.017833ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:17:31.514622Z","caller":"traceutil/trace.go:171","msg":"trace[520828443] transaction","detail":"{read_only:false; response_revision:26224; number_of_response:1; }","duration":"369.780772ms","start":"2026-10-09T03:17:31.144814Z","end":"2026-10-09T03:17:31.514594Z","steps":["trace[520828443] 'process raft request' (duration: 369.452206ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:17:31.514665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:17:31.147402Z","time spent":"367.213337ms","remote":"127.0.0.1:37248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T03:17:31.514665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.650312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"} {"level":"warn","ts":"2026-10-09T03:17:31.514534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:17:31.144778Z","time spent":"369.63992ms","remote":"127.0.0.1:37248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T03:17:31.514766Z","caller":"traceutil/trace.go:171","msg":"trace[132528304] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:26225; }","duration":"432.796586ms","start":"2026-10-09T03:17:31.081951Z","end":"2026-10-09T03:17:31.514748Z","steps":["trace[132528304] 'agreement among raft nodes before linearized reading' (duration: 432.572271ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:17:31.514834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:17:31.144809Z","time spent":"369.888105ms","remote":"127.0.0.1:37248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T03:17:31.514816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:17:31.081934Z","time spent":"432.871978ms","remote":"127.0.0.1:37166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-10-09T03:17:31.615739Z","caller":"traceutil/trace.go:171","msg":"trace[2015967189] linearizableReadLoop","detail":"{readStateIndex:27101; appliedIndex:27099; }","duration":"101.183953ms","start":"2026-10-09T03:17:31.514503Z","end":"2026-10-09T03:17:31.615686Z","steps":["trace[2015967189] 'read index received' (duration: 99.17928ms)","trace[2015967189] 'applied index is now lower than readState.Index' (duration: 2.003443ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:17:31.615745Z","caller":"traceutil/trace.go:171","msg":"trace[1648134010] transaction","detail":"{read_only:false; response_revision:26226; number_of_response:1; }","duration":"190.40349ms","start":"2026-10-09T03:17:31.42529Z","end":"2026-10-09T03:17:31.615693Z","steps":["trace[1648134010] 'process raft request' (duration: 188.54549ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:17:31.615894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.810611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T03:17:31.615785Z","caller":"traceutil/trace.go:171","msg":"trace[322977664] transaction","detail":"{read_only:false; response_revision:26227; number_of_response:1; }","duration":"160.793434ms","start":"2026-10-09T03:17:31.454976Z","end":"2026-10-09T03:17:31.61577Z","steps":["trace[322977664] 'process raft request' (duration: 160.577929ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:17:31.615948Z","caller":"traceutil/trace.go:171","msg":"trace[2081146686] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:26227; }","duration":"192.926353ms","start":"2026-10-09T03:17:31.423012Z","end":"2026-10-09T03:17:31.615939Z","steps":["trace[2081146686] 'agreement among raft nodes before linearized reading' (duration: 192.80397ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:17:31.615992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.463381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-10-09T03:17:31.616041Z","caller":"traceutil/trace.go:171","msg":"trace[851606409] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:26227; }","duration":"156.539042ms","start":"2026-10-09T03:17:31.459488Z","end":"2026-10-09T03:17:31.616027Z","steps":["trace[851606409] 'agreement among raft nodes before linearized reading' (duration: 156.320548ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:17:31.996822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.877145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-10-09T03:17:31.996878Z","caller":"traceutil/trace.go:171","msg":"trace[947804313] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:26227; }","duration":"159.997927ms","start":"2026-10-09T03:17:31.836868Z","end":"2026-10-09T03:17:31.996866Z","steps":["trace[947804313] 'count revisions from in-memory index tree' (duration: 159.6981ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:17:32.264382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.583698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-09T03:17:32.264489Z","caller":"traceutil/trace.go:171","msg":"trace[568978946] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:26228; }","duration":"106.773084ms","start":"2026-10-09T03:17:32.15769Z","end":"2026-10-09T03:17:32.264463Z","steps":["trace[568978946] 'count revisions from in-memory index tree' (duration: 106.461827ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:17:33.105884Z","caller":"traceutil/trace.go:171","msg":"trace[1985094130] transaction","detail":"{read_only:false; response_revision:26231; number_of_response:1; }","duration":"219.511376ms","start":"2026-10-09T03:17:32.886344Z","end":"2026-10-09T03:17:33.105855Z","steps":["trace[1985094130] 'process raft request' (duration: 219.25291ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:17:43.55965Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7663614868200457995,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-09T03:17:43.577973Z","caller":"traceutil/trace.go:171","msg":"trace[1496253438] transaction","detail":"{read_only:false; response_revision:26357; number_of_response:1; }","duration":"560.247963ms","start":"2026-10-09T03:17:43.017701Z","end":"2026-10-09T03:17:43.577949Z","steps":["trace[1496253438] 'process raft request' (duration: 559.931567ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:17:43.578208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:17:43.017676Z","time spent":"560.434518ms","remote":"127.0.0.1:42060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2230,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-09T03:17:43.655134Z","caller":"traceutil/trace.go:171","msg":"trace[312412660] transaction","detail":"{read_only:false; response_revision:26358; number_of_response:1; }","duration":"107.02194ms","start":"2026-10-09T03:17:43.548083Z","end":"2026-10-09T03:17:43.655105Z","steps":["trace[312412660] 'process raft request' (duration: 93.604951ms)","trace[312412660] 'compare' (duration: 13.176844ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:17:43.655771Z","caller":"traceutil/trace.go:171","msg":"trace[246068967] linearizableReadLoop","detail":"{readStateIndex:27235; appliedIndex:27232; }","duration":"596.510862ms","start":"2026-10-09T03:17:43.059235Z","end":"2026-10-09T03:17:43.655746Z","steps":["trace[246068967] 'read index received' (duration: 518.562958ms)","trace[246068967] 'applied index is now lower than readState.Index' (duration: 77.946354ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:17:43.655973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.895171ms","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":"warn","ts":"2026-10-09T03:17:43.656007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.539107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-09T03:17:43.656035Z","caller":"traceutil/trace.go:171","msg":"trace[966798026] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26362; }","duration":"564.024544ms","start":"2026-10-09T03:17:43.091991Z","end":"2026-10-09T03:17:43.656016Z","steps":["trace[966798026] 'agreement among raft nodes before linearized reading' (duration: 563.904121ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:17:43.656077Z","caller":"traceutil/trace.go:171","msg":"trace[925692441] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26362; }","duration":"179.614757ms","start":"2026-10-09T03:17:43.476444Z","end":"2026-10-09T03:17:43.656059Z","steps":["trace[925692441] 'agreement among raft nodes before linearized reading' (duration: 179.460374ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:17:43.656087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:17:43.091968Z","time spent":"564.103656ms","remote":"127.0.0.1:47376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-10-09T03:17:43.656235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.194962ms","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-10-09T03:17:43.656267Z","caller":"traceutil/trace.go:171","msg":"trace[1477416174] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26362; }","duration":"174.263994ms","start":"2026-10-09T03:17:43.481993Z","end":"2026-10-09T03:17:43.656257Z","steps":["trace[1477416174] 'agreement among raft nodes before linearized reading' (duration: 174.194382ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:17:43.65635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.30539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusterclasses/\" range_end:\"/registry/cluster.x-k8s.io/clusterclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T03:17:43.656417Z","caller":"traceutil/trace.go:171","msg":"trace[244886044] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusterclasses/; range_end:/registry/cluster.x-k8s.io/clusterclasses0; response_count:0; response_revision:26362; }","duration":"463.392563ms","start":"2026-10-09T03:17:43.193006Z","end":"2026-10-09T03:17:43.656398Z","steps":["trace[244886044] 'agreement among raft nodes before linearized reading' (duration: 463.295511ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:17:43.656459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.704595ms","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-10-09T03:17:43.656466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:17:43.192996Z","time spent":"463.457505ms","remote":"127.0.0.1:59546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cluster.x-k8s.io/clusterclasses/\" range_end:\"/registry/cluster.x-k8s.io/clusterclasses0\" count_only:true "} {"level":"info","ts":"2026-10-09T03:17:43.656486Z","caller":"traceutil/trace.go:171","msg":"trace[873600253] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26362; }","duration":"500.792196ms","start":"2026-10-09T03:17:43.155687Z","end":"2026-10-09T03:17:43.656479Z","steps":["trace[873600253] 'agreement among raft nodes before linearized reading' (duration: 500.720434ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:17:43.65651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:17:43.155669Z","time spent":"500.834558ms","remote":"127.0.0.1:37248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-09T03:17:43.656513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.268729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2225409"} {"level":"info","ts":"2026-10-09T03:17:43.656593Z","caller":"traceutil/trace.go:171","msg":"trace[1641869702] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:26362; }","duration":"597.35707ms","start":"2026-10-09T03:17:43.059222Z","end":"2026-10-09T03:17:43.656579Z","steps":["trace[1641869702] 'agreement among raft nodes before linearized reading' (duration: 596.605293ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:17:43.656619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.935567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-10-09T03:17:43.656626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:17:43.059206Z","time spent":"597.411502ms","remote":"127.0.0.1:37094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":57,"response size":2225431,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "} {"level":"info","ts":"2026-10-09T03:17:43.656647Z","caller":"traceutil/trace.go:171","msg":"trace[392690180] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26362; }","duration":"491.988487ms","start":"2026-10-09T03:17:43.164651Z","end":"2026-10-09T03:17:43.656639Z","steps":["trace[392690180] 'agreement among raft nodes before linearized reading' (duration: 491.925156ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:17:43.65667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:17:43.164638Z","time spent":"492.027558ms","remote":"127.0.0.1:37248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-09T03:17:43.991069Z","caller":"traceutil/trace.go:171","msg":"trace[641557911] transaction","detail":"{read_only:false; response_revision:26363; number_of_response:1; }","duration":"330.694324ms","start":"2026-10-09T03:17:43.660331Z","end":"2026-10-09T03:17:43.991026Z","steps":["trace[641557911] 'process raft request' (duration: 295.63746ms)","trace[641557911] 'compare' (duration: 34.620544ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:17:43.99125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:17:43.660309Z","time spent":"330.843526ms","remote":"127.0.0.1:37248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T03:17:44.311822Z","caller":"traceutil/trace.go:171","msg":"trace[856693171] linearizableReadLoop","detail":"{readStateIndex:27243; appliedIndex:27238; }","duration":"615.345547ms","start":"2026-10-09T03:17:43.696457Z","end":"2026-10-09T03:17:44.311803Z","steps":["trace[856693171] 'read index received' (duration: 259.593457ms)","trace[856693171] 'applied index is now lower than readState.Index' (duration: 355.75101ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:17:44.312051Z","caller":"traceutil/trace.go:171","msg":"trace[1022090090] transaction","detail":"{read_only:false; response_revision:26364; number_of_response:1; }","duration":"649.906239ms","start":"2026-10-09T03:17:43.662133Z","end":"2026-10-09T03:17:44.312039Z","steps":["trace[1022090090] 'process raft request' (duration: 625.656239ms)","trace[1022090090] 'compare' (duration: 23.594996ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:17:44.312142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:17:43.662105Z","time spent":"649.97711ms","remote":"127.0.0.1:37248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T03:17:44.312291Z","caller":"traceutil/trace.go:171","msg":"trace[1174958465] transaction","detail":"{read_only:false; response_revision:26365; number_of_response:1; }","duration":"650.106533ms","start":"2026-10-09T03:17:43.662175Z","end":"2026-10-09T03:17:44.312282Z","steps":["trace[1174958465] 'process raft request' (duration: 649.498991ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:17:44.312338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:17:43.662161Z","time spent":"650.154434ms","remote":"127.0.0.1:37248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T03:17:44.312626Z","caller":"traceutil/trace.go:171","msg":"trace[306960119] transaction","detail":"{read_only:false; response_revision:26366; number_of_response:1; }","duration":"650.364689ms","start":"2026-10-09T03:17:43.662252Z","end":"2026-10-09T03:17:44.312616Z","steps":["trace[306960119] 'process raft request' (duration: 649.495499ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:17:44.312671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:17:43.662239Z","time spent":"650.40846ms","remote":"127.0.0.1:37248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T03:17:44.312855Z","caller":"traceutil/trace.go:171","msg":"trace[1679309765] transaction","detail":"{read_only:false; response_revision:26367; number_of_response:1; }","duration":"648.06653ms","start":"2026-10-09T03:17:43.664778Z","end":"2026-10-09T03:17:44.312845Z","steps":["trace[1679309765] 'process raft request' (duration: 646.997016ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:17:44.312909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:17:43.664753Z","time spent":"648.129772ms","remote":"127.0.0.1:37248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T03:17:44.313127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.667676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1822"} {"level":"info","ts":"2026-10-09T03:17:44.313162Z","caller":"traceutil/trace.go:171","msg":"trace[1115213337] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:26367; }","duration":"616.738556ms","start":"2026-10-09T03:17:43.696414Z","end":"2026-10-09T03:17:44.313152Z","steps":["trace[1115213337] 'agreement among raft nodes before linearized reading' (duration: 616.619564ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:17:44.313189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:17:43.696395Z","time spent":"616.785128ms","remote":"127.0.0.1:37092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":1844,"request content":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" "} {"level":"warn","ts":"2026-10-09T03:17:44.313327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.854147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T03:17:44.313353Z","caller":"traceutil/trace.go:171","msg":"trace[1314293184] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26367; }","duration":"567.904049ms","start":"2026-10-09T03:17:43.745442Z","end":"2026-10-09T03:17:44.313346Z","steps":["trace[1314293184] 'agreement among raft nodes before linearized reading' (duration: 567.861368ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:17:44.313372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:17:43.74543Z","time spent":"567.938168ms","remote":"127.0.0.1:37044","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-10-09T03:17:44.31368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.824585ms","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-10-09T03:17:44.313731Z","caller":"traceutil/trace.go:171","msg":"trace[437220317] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26367; }","duration":"161.874187ms","start":"2026-10-09T03:17:44.151848Z","end":"2026-10-09T03:17:44.313722Z","steps":["trace[437220317] 'agreement among raft nodes before linearized reading' (duration: 161.796025ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:17:44.313898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.867561ms","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-10-09T03:17:44.313922Z","caller":"traceutil/trace.go:171","msg":"trace[891481432] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26367; }","duration":"205.934153ms","start":"2026-10-09T03:17:44.107981Z","end":"2026-10-09T03:17:44.313915Z","steps":["trace[891481432] 'agreement among raft nodes before linearized reading' (duration: 205.864121ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:17:44.31406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.277388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T03:17:44.314156Z","caller":"traceutil/trace.go:171","msg":"trace[1240120602] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:26367; }","duration":"387.446171ms","start":"2026-10-09T03:17:43.926688Z","end":"2026-10-09T03:17:44.314134Z","steps":["trace[1240120602] 'agreement among raft nodes before linearized reading' (duration: 387.282038ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:17:44.314216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:17:43.926665Z","time spent":"387.536624ms","remote":"127.0.0.1:37224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-10-09T03:17:44.314317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.749568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T03:17:44.314365Z","caller":"traceutil/trace.go:171","msg":"trace[1017956470] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:26367; }","duration":"556.83898ms","start":"2026-10-09T03:17:43.757512Z","end":"2026-10-09T03:17:44.314351Z","steps":["trace[1017956470] 'agreement among raft nodes before linearized reading' (duration: 556.763729ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:17:44.314407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:17:43.757492Z","time spent":"556.905711ms","remote":"127.0.0.1:59560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true "} {"level":"warn","ts":"2026-10-09T03:17:44.853271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.82288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:16 size:111966"} {"level":"info","ts":"2026-10-09T03:17:44.853344Z","caller":"traceutil/trace.go:171","msg":"trace[2083448935] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:16; response_revision:26368; }","duration":"441.928102ms","start":"2026-10-09T03:17:44.411401Z","end":"2026-10-09T03:17:44.853329Z","steps":["trace[2083448935] 'range keys from in-memory index tree' (duration: 441.549474ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:17:44.853399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:17:44.411386Z","time spent":"441.999373ms","remote":"127.0.0.1:37174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":16,"response size":111988,"request content":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" "} {"level":"warn","ts":"2026-10-09T03:17:44.8537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.218535ms","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-10-09T03:17:44.853749Z","caller":"traceutil/trace.go:171","msg":"trace[108218015] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26368; }","duration":"433.341608ms","start":"2026-10-09T03:17:44.420398Z","end":"2026-10-09T03:17:44.85374Z","steps":["trace[108218015] 'range keys from in-memory index tree' (duration: 433.141903ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:17:44.853776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:17:44.420382Z","time spent":"433.386948ms","remote":"127.0.0.1:37432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-10-09T03:17:44.853271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.225912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/neutron-l3-agent-default-swstv.18dcbd80d58ea6d6\" ","response":"range_response_count:1 size:949"} {"level":"info","ts":"2026-10-09T03:17:44.85413Z","caller":"traceutil/trace.go:171","msg":"trace[734751789] range","detail":"{range_begin:/registry/events/openstack/neutron-l3-agent-default-swstv.18dcbd80d58ea6d6; range_end:; response_count:1; response_revision:26368; }","duration":"269.119411ms","start":"2026-10-09T03:17:44.584985Z","end":"2026-10-09T03:17:44.854104Z","steps":["trace[734751789] 'range keys from in-memory index tree' (duration: 268.118049ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:17:44.854839Z","caller":"traceutil/trace.go:171","msg":"trace[29948159] linearizableReadLoop","detail":"{readStateIndex:27245; appliedIndex:27244; }","duration":"192.137538ms","start":"2026-10-09T03:17:44.662689Z","end":"2026-10-09T03:17:44.854826Z","steps":["trace[29948159] 'read index received' (duration: 168.145582ms)","trace[29948159] 'applied index is now lower than readState.Index' (duration: 23.989835ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:17:44.855075Z","caller":"traceutil/trace.go:171","msg":"trace[449994521] transaction","detail":"{read_only:false; response_revision:26369; number_of_response:1; }","duration":"264.004171ms","start":"2026-10-09T03:17:44.591057Z","end":"2026-10-09T03:17:44.855062Z","steps":["trace[449994521] 'process raft request' (duration: 239.839952ms)","trace[449994521] 'compare' (duration: 23.846901ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:17:44.855105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.760298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T03:17:44.85514Z","caller":"traceutil/trace.go:171","msg":"trace[250054347] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:26369; }","duration":"130.83134ms","start":"2026-10-09T03:17:44.724296Z","end":"2026-10-09T03:17:44.855128Z","steps":["trace[250054347] 'agreement among raft nodes before linearized reading' (duration: 130.769389ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:17:44.855332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.647747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-616d691e-df9b-44da-a4ad-af486d7af980\" ","response":"range_response_count:1 size:3644"} {"level":"warn","ts":"2026-10-09T03:17:44.855349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.371035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T03:17:44.855363Z","caller":"traceutil/trace.go:171","msg":"trace[972651517] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-616d691e-df9b-44da-a4ad-af486d7af980; range_end:; response_count:1; response_revision:26369; }","duration":"192.714909ms","start":"2026-10-09T03:17:44.662638Z","end":"2026-10-09T03:17:44.855353Z","steps":["trace[972651517] 'agreement among raft nodes before linearized reading' (duration: 192.627167ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:17:44.85538Z","caller":"traceutil/trace.go:171","msg":"trace[123725662] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26369; }","duration":"106.431906ms","start":"2026-10-09T03:17:44.748938Z","end":"2026-10-09T03:17:44.85537Z","steps":["trace[123725662] 'agreement among raft nodes before linearized reading' (duration: 106.381264ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:17:45.423011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.957579ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:18"} {"level":"info","ts":"2026-10-09T03:17:45.423113Z","caller":"traceutil/trace.go:171","msg":"trace[1842852575] linearizableReadLoop","detail":"{readStateIndex:27247; appliedIndex:27246; }","duration":"547.262175ms","start":"2026-10-09T03:17:44.875836Z","end":"2026-10-09T03:17:45.423098Z","steps":["trace[1842852575] 'read index received' (duration: 283.246463ms)","trace[1842852575] 'applied index is now lower than readState.Index' (duration: 264.012952ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:17:45.423351Z","caller":"traceutil/trace.go:171","msg":"trace[1544679858] transaction","detail":"{read_only:false; response_revision:26371; number_of_response:1; }","duration":"548.166984ms","start":"2026-10-09T03:17:44.875172Z","end":"2026-10-09T03:17:45.423339Z","steps":["trace[1544679858] 'process raft request' (duration: 283.813315ms)","trace[1544679858] 'compare' (duration: 263.48827ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:17:45.423402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:17:44.875148Z","time spent":"548.230035ms","remote":"127.0.0.1:37080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":923,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-09T03:17:45.423553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.685034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19030"} {"level":"info","ts":"2026-10-09T03:17:45.423688Z","caller":"traceutil/trace.go:171","msg":"trace[1327592910] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:26371; }","duration":"547.847817ms","start":"2026-10-09T03:17:44.875815Z","end":"2026-10-09T03:17:45.423663Z","steps":["trace[1327592910] 'agreement among raft nodes before linearized reading' (duration: 547.47986ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:17:45.423775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:17:44.875807Z","time spent":"547.95344ms","remote":"127.0.0.1:37168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":19052,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-10-09T03:17:45.745111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.779068ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T03:17:45.745465Z","caller":"traceutil/trace.go:171","msg":"trace[2043927461] transaction","detail":"{read_only:false; response_revision:26373; number_of_response:1; }","duration":"818.757195ms","start":"2026-10-09T03:17:44.926677Z","end":"2026-10-09T03:17:45.745434Z","steps":["trace[2043927461] 'process raft request' (duration: 818.546061ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:17:45.74561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:17:44.926654Z","time spent":"818.881159ms","remote":"127.0.0.1:37248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T03:17:45.745616Z","caller":"traceutil/trace.go:171","msg":"trace[2051164729] transaction","detail":"{read_only:false; response_revision:26374; number_of_response:1; }","duration":"633.123048ms","start":"2026-10-09T03:17:45.112474Z","end":"2026-10-09T03:17:45.745597Z","steps":["trace[2051164729] 'process raft request' (duration: 632.820172ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:17:45.745687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:17:45.112456Z","time spent":"633.19414ms","remote":"127.0.0.1:37248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T03:17:45.745677Z","caller":"traceutil/trace.go:171","msg":"trace[552165900] linearizableReadLoop","detail":"{readStateIndex:27251; appliedIndex:27247; }","duration":"322.515337ms","start":"2026-10-09T03:17:45.42313Z","end":"2026-10-09T03:17:45.745645Z","steps":["trace[552165900] 'read index received' (duration: 103.030593ms)","trace[552165900] 'applied index is now lower than readState.Index' (duration: 219.482304ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:17:45.745803Z","caller":"traceutil/trace.go:171","msg":"trace[502030317] transaction","detail":"{read_only:false; response_revision:26372; number_of_response:1; }","duration":"864.474747ms","start":"2026-10-09T03:17:44.881305Z","end":"2026-10-09T03:17:45.74578Z","steps":["trace[502030317] 'process raft request' (duration: 644.926992ms)","trace[502030317] 'compare' (duration: 218.635996ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:17:45.745887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.827217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/routers/\" range_end:\"/registry/openstack.k-orc.cloud/routers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-09T03:17:45.745892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:17:44.881293Z","time spent":"864.553679ms","remote":"127.0.0.1:48068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7439,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T03:17:45.745888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.845567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T03:17:45.745906Z","caller":"traceutil/trace.go:171","msg":"trace[641191594] transaction","detail":"{read_only:false; response_revision:26375; number_of_response:1; }","duration":"600.182211ms","start":"2026-10-09T03:17:45.145691Z","end":"2026-10-09T03:17:45.745873Z","steps":["trace[641191594] 'process raft request' (duration: 599.64101ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:17:45.745932Z","caller":"traceutil/trace.go:171","msg":"trace[32800044] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/routers/; range_end:/registry/openstack.k-orc.cloud/routers0; response_count:0; response_revision:26376; }","duration":"642.939759ms","start":"2026-10-09T03:17:45.102976Z","end":"2026-10-09T03:17:45.745916Z","steps":["trace[32800044] 'agreement among raft nodes before linearized reading' (duration: 642.871847ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:17:45.745945Z","caller":"traceutil/trace.go:171","msg":"trace[202278680] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:26376; }","duration":"365.97408ms","start":"2026-10-09T03:17:45.379956Z","end":"2026-10-09T03:17:45.74593Z","steps":["trace[202278680] 'agreement among raft nodes before linearized reading' (duration: 365.867498ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:17:45.745959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"779.343259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"warn","ts":"2026-10-09T03:17:45.745986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:17:45.102954Z","time spent":"643.019161ms","remote":"127.0.0.1:57612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/openstack.k-orc.cloud/routers/\" range_end:\"/registry/openstack.k-orc.cloud/routers0\" count_only:true "} {"level":"warn","ts":"2026-10-09T03:17:45.745994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:17:45.379938Z","time spent":"366.043031ms","remote":"127.0.0.1:37380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"info","ts":"2026-10-09T03:17:45.745999Z","caller":"traceutil/trace.go:171","msg":"trace[718811536] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:26376; }","duration":"779.42446ms","start":"2026-10-09T03:17:44.966563Z","end":"2026-10-09T03:17:45.745987Z","steps":["trace[718811536] 'agreement among raft nodes before linearized reading' (duration: 779.197846ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:17:45.746033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:17:44.966549Z","time spent":"779.474532ms","remote":"127.0.0.1:37068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":141,"response size":31,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-10-09T03:17:45.746025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"719.364961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-09T03:17:45.746047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:17:45.14567Z","time spent":"600.300373ms","remote":"127.0.0.1:37248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T03:17:45.746096Z","caller":"traceutil/trace.go:171","msg":"trace[734214800] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:26376; }","duration":"719.450142ms","start":"2026-10-09T03:17:45.026627Z","end":"2026-10-09T03:17:45.746077Z","steps":["trace[734214800] 'agreement among raft nodes before linearized reading' (duration: 719.32717ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:17:45.746139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:17:45.026614Z","time spent":"719.515133ms","remote":"127.0.0.1:59826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":128,"response count":0,"response size":28,"request content":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true "} {"level":"warn","ts":"2026-10-09T03:17:46.150368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.087906ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T03:17:46.15074Z","caller":"traceutil/trace.go:171","msg":"trace[1903287248] transaction","detail":"{read_only:false; response_revision:26378; number_of_response:1; }","duration":"487.53329ms","start":"2026-10-09T03:17:45.66315Z","end":"2026-10-09T03:17:46.150684Z","steps":["trace[1903287248] 'process raft request' (duration: 487.327686ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:17:46.150757Z","caller":"traceutil/trace.go:171","msg":"trace[124049975] transaction","detail":"{read_only:false; response_revision:26377; number_of_response:1; }","duration":"487.780916ms","start":"2026-10-09T03:17:45.662902Z","end":"2026-10-09T03:17:46.150683Z","steps":["trace[124049975] 'process raft request' (duration: 383.270582ms)","trace[124049975] 'compare' (duration: 103.896712ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:17:46.150773Z","caller":"traceutil/trace.go:171","msg":"trace[176655346] transaction","detail":"{read_only:false; response_revision:26380; number_of_response:1; }","duration":"486.694893ms","start":"2026-10-09T03:17:45.664053Z","end":"2026-10-09T03:17:46.150748Z","steps":["trace[176655346] 'process raft request' (duration: 486.55511ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:17:46.150769Z","caller":"traceutil/trace.go:171","msg":"trace[1613876624] transaction","detail":"{read_only:false; response_revision:26379; number_of_response:1; }","duration":"487.386128ms","start":"2026-10-09T03:17:45.663314Z","end":"2026-10-09T03:17:46.1507Z","steps":["trace[1613876624] 'process raft request' (duration: 487.247405ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:17:46.150815Z","caller":"traceutil/trace.go:171","msg":"trace[811958368] linearizableReadLoop","detail":"{readStateIndex:27256; appliedIndex:27252; }","duration":"404.931467ms","start":"2026-10-09T03:17:45.745818Z","end":"2026-10-09T03:17:46.150749Z","steps":["trace[811958368] 'read index received' (duration: 300.371591ms)","trace[811958368] 'applied index is now lower than readState.Index' (duration: 104.556826ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:17:46.150802Z","caller":"traceutil/trace.go:171","msg":"trace[170655285] transaction","detail":"{read_only:false; response_revision:26381; number_of_response:1; }","duration":"153.529098ms","start":"2026-10-09T03:17:45.997249Z","end":"2026-10-09T03:17:46.150779Z","steps":["trace[170655285] 'process raft request' (duration: 153.416016ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:17:46.150905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:17:45.662869Z","time spent":"487.956841ms","remote":"127.0.0.1:37248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T03:17:46.150906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:17:45.664034Z","time spent":"486.798126ms","remote":"127.0.0.1:37248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T03:17:46.150921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:17:45.663118Z","time spent":"487.691595ms","remote":"127.0.0.1:37248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T03:17:46.15094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:17:45.6633Z","time spent":"487.550113ms","remote":"127.0.0.1:37248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T03:17:46.151065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.895921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T03:17:46.151175Z","caller":"traceutil/trace.go:171","msg":"trace[573831139] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26381; }","duration":"401.045224ms","start":"2026-10-09T03:17:45.750067Z","end":"2026-10-09T03:17:46.151112Z","steps":["trace[573831139] 'agreement among raft nodes before linearized reading' (duration: 400.895361ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:17:46.151219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"715.358175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"warn","ts":"2026-10-09T03:17:46.151279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:17:45.750049Z","time spent":"401.171327ms","remote":"127.0.0.1:37044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-09T03:17:46.151783Z","caller":"traceutil/trace.go:171","msg":"trace[977276324] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:26381; }","duration":"715.491628ms","start":"2026-10-09T03:17:45.435812Z","end":"2026-10-09T03:17:46.151304Z","steps":["trace[977276324] 'agreement among raft nodes before linearized reading' (duration: 715.08381ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:17:46.151868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:17:45.435795Z","time spent":"716.053159ms","remote":"127.0.0.1:47440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5674,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-10-09T03:17:46.151281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.609971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-616d691e-df9b-44da-a4ad-af486d7af980\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-10-09T03:17:46.152154Z","caller":"traceutil/trace.go:171","msg":"trace[1640301952] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-616d691e-df9b-44da-a4ad-af486d7af980; range_end:; response_count:1; response_revision:26381; }","duration":"291.377088ms","start":"2026-10-09T03:17:45.860581Z","end":"2026-10-09T03:17:46.151958Z","steps":["trace[1640301952] 'agreement among raft nodes before linearized reading' (duration: 290.374706ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:17:46.432129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.271551ms","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-10-09T03:17:46.43224Z","caller":"traceutil/trace.go:171","msg":"trace[624335083] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26381; }","duration":"112.409535ms","start":"2026-10-09T03:17:46.3198Z","end":"2026-10-09T03:17:46.43221Z","steps":["trace[624335083] 'range keys from in-memory index tree' (duration: 112.123049ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:17:46.432261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.958702ms","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":"warn","ts":"2026-10-09T03:17:46.432344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.376324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-09T03:17:46.432445Z","caller":"traceutil/trace.go:171","msg":"trace[692951491] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26381; }","duration":"112.529277ms","start":"2026-10-09T03:17:46.319892Z","end":"2026-10-09T03:17:46.432422Z","steps":["trace[692951491] 'range keys from in-memory index tree' (duration: 112.17427ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:17:46.432643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.312234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-09T03:17:46.432678Z","caller":"traceutil/trace.go:171","msg":"trace[1141661651] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26381; }","duration":"113.381605ms","start":"2026-10-09T03:17:46.319285Z","end":"2026-10-09T03:17:46.432667Z","steps":["trace[1141661651] 'range keys from in-memory index tree' (duration: 113.191351ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:17:46.432162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.554789ms","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-10-09T03:17:46.432928Z","caller":"traceutil/trace.go:171","msg":"trace[1941232924] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26381; }","duration":"107.370617ms","start":"2026-10-09T03:17:46.325546Z","end":"2026-10-09T03:17:46.432916Z","steps":["trace[1941232924] 'range keys from in-memory index tree' (duration: 106.383935ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:17:46.432362Z","caller":"traceutil/trace.go:171","msg":"trace[1021412670] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26381; }","duration":"110.068454ms","start":"2026-10-09T03:17:46.322271Z","end":"2026-10-09T03:17:46.43234Z","steps":["trace[1021412670] 'range keys from in-memory index tree' (duration: 109.87823ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:17:53.889943Z","caller":"traceutil/trace.go:171","msg":"trace[1530784942] transaction","detail":"{read_only:false; response_revision:26459; number_of_response:1; }","duration":"109.749698ms","start":"2026-10-09T03:17:53.780159Z","end":"2026-10-09T03:17:53.889909Z","steps":["trace[1530784942] 'process raft request' (duration: 74.516581ms)","trace[1530784942] 'compare' (duration: 35.132734ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:17:59.582539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.978471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-09T03:17:59.582627Z","caller":"traceutil/trace.go:171","msg":"trace[1046301553] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:26510; }","duration":"481.115603ms","start":"2026-10-09T03:17:59.101493Z","end":"2026-10-09T03:17:59.582609Z","steps":["trace[1046301553] 'count revisions from in-memory index tree' (duration: 480.896499ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:17:59.582668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:17:59.101474Z","time spent":"481.182896ms","remote":"127.0.0.1:37348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":30,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-10-09T03:17:59.583202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.55123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"info","ts":"2026-10-09T03:17:59.583281Z","caller":"traceutil/trace.go:171","msg":"trace[1688394143] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0; range_end:; response_count:1; response_revision:26510; }","duration":"303.719054ms","start":"2026-10-09T03:17:59.27954Z","end":"2026-10-09T03:17:59.583259Z","steps":["trace[1688394143] 'range keys from in-memory index tree' (duration: 303.291675ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:17:59.583325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:17:59.279516Z","time spent":"303.799295ms","remote":"127.0.0.1:37162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":1,"response size":1886,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0\" "} {"level":"info","ts":"2026-10-09T03:17:59.95662Z","caller":"traceutil/trace.go:171","msg":"trace[756443180] transaction","detail":"{read_only:false; response_revision:26511; number_of_response:1; }","duration":"109.608274ms","start":"2026-10-09T03:17:59.846982Z","end":"2026-10-09T03:17:59.956591Z","steps":["trace[756443180] 'process raft request' (duration: 109.478681ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:18:15.064025Z","caller":"traceutil/trace.go:171","msg":"trace[2074544522] linearizableReadLoop","detail":"{readStateIndex:27521; appliedIndex:27520; }","duration":"269.827906ms","start":"2026-10-09T03:18:14.794172Z","end":"2026-10-09T03:18:15.064Z","steps":["trace[2074544522] 'read index received' (duration: 186.297172ms)","trace[2074544522] 'applied index is now lower than readState.Index' (duration: 83.529474ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:18:15.06424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.622123ms","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":"warn","ts":"2026-10-09T03:18:15.064236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.03757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-09T03:18:15.064271Z","caller":"traceutil/trace.go:171","msg":"trace[728957490] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26637; }","duration":"257.683895ms","start":"2026-10-09T03:18:14.806575Z","end":"2026-10-09T03:18:15.064259Z","steps":["trace[728957490] 'agreement among raft nodes before linearized reading' (duration: 257.551701ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:18:15.064302Z","caller":"traceutil/trace.go:171","msg":"trace[1766560748] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26637; }","duration":"270.141793ms","start":"2026-10-09T03:18:14.794143Z","end":"2026-10-09T03:18:15.064285Z","steps":["trace[1766560748] 'agreement among raft nodes before linearized reading' (duration: 269.963339ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:18:15.06408Z","caller":"traceutil/trace.go:171","msg":"trace[1400102664] transaction","detail":"{read_only:false; response_revision:26637; number_of_response:1; }","duration":"273.918833ms","start":"2026-10-09T03:18:14.790125Z","end":"2026-10-09T03:18:15.064044Z","steps":["trace[1400102664] 'process raft request' (duration: 190.311157ms)","trace[1400102664] 'compare' (duration: 83.025893ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:18:15.34728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.036932ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T03:18:15.347389Z","caller":"traceutil/trace.go:171","msg":"trace[2051503417] linearizableReadLoop","detail":"{readStateIndex:27524; appliedIndex:27522; }","duration":"177.136544ms","start":"2026-10-09T03:18:15.170242Z","end":"2026-10-09T03:18:15.347379Z","steps":["trace[2051503417] 'read index received' (duration: 33.892527ms)","trace[2051503417] 'applied index is now lower than readState.Index' (duration: 143.243297ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:18:15.347502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.273307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1989"} {"level":"info","ts":"2026-10-09T03:18:15.347473Z","caller":"traceutil/trace.go:171","msg":"trace[1228084183] transaction","detail":"{read_only:false; response_revision:26640; number_of_response:1; }","duration":"214.888745ms","start":"2026-10-09T03:18:15.132553Z","end":"2026-10-09T03:18:15.347442Z","steps":["trace[1228084183] 'process raft request' (duration: 214.789814ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:18:15.347526Z","caller":"traceutil/trace.go:171","msg":"trace[940185278] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:26640; }","duration":"177.331788ms","start":"2026-10-09T03:18:15.170186Z","end":"2026-10-09T03:18:15.347518Z","steps":["trace[940185278] 'agreement among raft nodes before linearized reading' (duration: 177.246856ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:18:15.347757Z","caller":"traceutil/trace.go:171","msg":"trace[1907517760] transaction","detail":"{read_only:false; response_revision:26639; number_of_response:1; }","duration":"279.259098ms","start":"2026-10-09T03:18:15.068485Z","end":"2026-10-09T03:18:15.347744Z","steps":["trace[1907517760] 'process raft request' (duration: 135.702595ms)","trace[1907517760] 'compare' (duration: 142.761076ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:18:15.641794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.807228ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T03:18:15.641947Z","caller":"traceutil/trace.go:171","msg":"trace[71658727] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:26640; }","duration":"136.972592ms","start":"2026-10-09T03:18:15.504955Z","end":"2026-10-09T03:18:15.641927Z","steps":["trace[71658727] 'range keys from in-memory index tree' (duration: 136.788908ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:18:16.298935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.166351ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-09T03:18:18.255341Z","caller":"traceutil/trace.go:171","msg":"trace[405604932] linearizableReadLoop","detail":"{readStateIndex:27543; appliedIndex:27541; }","duration":"121.54977ms","start":"2026-10-09T03:18:18.133761Z","end":"2026-10-09T03:18:18.255311Z","steps":["trace[405604932] 'read index received' (duration: 24.582808ms)","trace[405604932] 'applied index is now lower than readState.Index' (duration: 96.965462ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:18:18.255434Z","caller":"traceutil/trace.go:171","msg":"trace[2141296250] transaction","detail":"{read_only:false; response_revision:26658; number_of_response:1; }","duration":"136.295447ms","start":"2026-10-09T03:18:18.119094Z","end":"2026-10-09T03:18:18.255389Z","steps":["trace[2141296250] 'process raft request' (duration: 135.990651ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:18:18.25555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.752925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T03:18:18.255682Z","caller":"traceutil/trace.go:171","msg":"trace[1437293390] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:26658; }","duration":"121.96811ms","start":"2026-10-09T03:18:18.133692Z","end":"2026-10-09T03:18:18.25566Z","steps":["trace[1437293390] 'agreement among raft nodes before linearized reading' (duration: 121.741156ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:18:23.306471Z","caller":"traceutil/trace.go:171","msg":"trace[1093362710] transaction","detail":"{read_only:false; response_revision:26696; number_of_response:1; }","duration":"126.531468ms","start":"2026-10-09T03:18:23.179908Z","end":"2026-10-09T03:18:23.30644Z","steps":["trace[1093362710] 'process raft request' (duration: 77.620918ms)","trace[1093362710] 'compare' (duration: 48.744287ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:18:28.5764Z","caller":"traceutil/trace.go:171","msg":"trace[2007669672] transaction","detail":"{read_only:false; response_revision:26733; number_of_response:1; }","duration":"127.914337ms","start":"2026-10-09T03:18:28.448458Z","end":"2026-10-09T03:18:28.576373Z","steps":["trace[2007669672] 'process raft request' (duration: 127.745264ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:18:28.822096Z","caller":"traceutil/trace.go:171","msg":"trace[783680087] transaction","detail":"{read_only:false; response_revision:26734; number_of_response:1; }","duration":"357.163571ms","start":"2026-10-09T03:18:28.464908Z","end":"2026-10-09T03:18:28.822071Z","steps":["trace[783680087] 'process raft request' (duration: 328.442324ms)","trace[783680087] 'compare' (duration: 28.540133ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:18:28.822244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:18:28.464879Z","time spent":"357.295234ms","remote":"127.0.0.1:37248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T03:18:28.822305Z","caller":"traceutil/trace.go:171","msg":"trace[866995579] transaction","detail":"{read_only:false; response_revision:26735; number_of_response:1; }","duration":"351.781935ms","start":"2026-10-09T03:18:28.470502Z","end":"2026-10-09T03:18:28.822284Z","steps":["trace[866995579] 'process raft request' (duration: 351.52799ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:18:28.822414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:18:28.470487Z","time spent":"351.866167ms","remote":"127.0.0.1:37248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T03:18:28.822669Z","caller":"traceutil/trace.go:171","msg":"trace[1960079041] transaction","detail":"{read_only:false; response_revision:26737; number_of_response:1; }","duration":"349.102849ms","start":"2026-10-09T03:18:28.473547Z","end":"2026-10-09T03:18:28.82265Z","steps":["trace[1960079041] 'process raft request' (duration: 348.870484ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:18:28.822699Z","caller":"traceutil/trace.go:171","msg":"trace[515750792] transaction","detail":"{read_only:false; response_revision:26736; number_of_response:1; }","duration":"349.579669ms","start":"2026-10-09T03:18:28.473071Z","end":"2026-10-09T03:18:28.82265Z","steps":["trace[515750792] 'process raft request' (duration: 349.136318ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:18:28.822791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:18:28.473534Z","time spent":"349.20403ms","remote":"127.0.0.1:37248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T03:18:28.82293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:18:28.473056Z","time spent":"349.775262ms","remote":"127.0.0.1:37248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T03:18:29.118063Z","caller":"traceutil/trace.go:171","msg":"trace[798471489] linearizableReadLoop","detail":"{readStateIndex:27626; appliedIndex:27625; }","duration":"122.264336ms","start":"2026-10-09T03:18:28.995758Z","end":"2026-10-09T03:18:29.118022Z","steps":["trace[798471489] 'read index received' (duration: 84.251539ms)","trace[798471489] 'applied index is now lower than readState.Index' (duration: 38.011297ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:18:29.118607Z","caller":"traceutil/trace.go:171","msg":"trace[489964541] transaction","detail":"{read_only:false; response_revision:26739; number_of_response:1; }","duration":"215.918167ms","start":"2026-10-09T03:18:28.902643Z","end":"2026-10-09T03:18:29.118561Z","steps":["trace[489964541] 'process raft request' (duration: 177.37563ms)","trace[489964541] 'compare' (duration: 37.436953ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:18:29.11868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.91556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T03:18:29.11877Z","caller":"traceutil/trace.go:171","msg":"trace[120197729] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:26739; }","duration":"123.081634ms","start":"2026-10-09T03:18:28.99567Z","end":"2026-10-09T03:18:29.118752Z","steps":["trace[120197729] 'agreement among raft nodes before linearized reading' (duration: 122.91275ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:18:29.464455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.855693ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T03:18:29.464611Z","caller":"traceutil/trace.go:171","msg":"trace[904913704] linearizableReadLoop","detail":"{readStateIndex:27627; appliedIndex:27626; }","duration":"294.975296ms","start":"2026-10-09T03:18:29.169592Z","end":"2026-10-09T03:18:29.464567Z","steps":["trace[904913704] 'read index received' (duration: 128.792337ms)","trace[904913704] 'applied index is now lower than readState.Index' (duration: 166.181379ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:18:29.464792Z","caller":"traceutil/trace.go:171","msg":"trace[503712075] transaction","detail":"{read_only:false; response_revision:26740; number_of_response:1; }","duration":"343.14685ms","start":"2026-10-09T03:18:29.121595Z","end":"2026-10-09T03:18:29.464741Z","steps":["trace[503712075] 'process raft request' (duration: 176.899519ms)","trace[503712075] 'compare' (duration: 165.70763ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:18:29.464859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.261381ms","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-10-09T03:18:29.464895Z","caller":"traceutil/trace.go:171","msg":"trace[433375393] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26740; }","duration":"295.333773ms","start":"2026-10-09T03:18:29.16955Z","end":"2026-10-09T03:18:29.464884Z","steps":["trace[433375393] 'agreement among raft nodes before linearized reading' (duration: 295.1924ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:18:29.464973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:18:29.121574Z","time spent":"343.296614ms","remote":"127.0.0.1:37080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2091,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T03:18:29.464982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.452507ms","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-10-09T03:18:29.465074Z","caller":"traceutil/trace.go:171","msg":"trace[380644564] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26740; }","duration":"106.588848ms","start":"2026-10-09T03:18:29.358463Z","end":"2026-10-09T03:18:29.465052Z","steps":["trace[380644564] 'agreement among raft nodes before linearized reading' (duration: 106.351303ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:18:29.680159Z","caller":"traceutil/trace.go:171","msg":"trace[896167972] transaction","detail":"{read_only:false; response_revision:26741; number_of_response:1; }","duration":"210.032152ms","start":"2026-10-09T03:18:29.470098Z","end":"2026-10-09T03:18:29.68013Z","steps":["trace[896167972] 'process raft request' (duration: 209.863287ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:18:29.994809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.891058ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T03:18:29.99506Z","caller":"traceutil/trace.go:171","msg":"trace[1536011468] linearizableReadLoop","detail":"{readStateIndex:27630; appliedIndex:27627; }","duration":"435.80653ms","start":"2026-10-09T03:18:29.559234Z","end":"2026-10-09T03:18:29.995041Z","steps":["trace[1536011468] 'read index received' (duration: 120.802204ms)","trace[1536011468] 'applied index is now lower than readState.Index' (duration: 315.003286ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:18:29.995188Z","caller":"traceutil/trace.go:171","msg":"trace[2024858022] transaction","detail":"{read_only:false; response_revision:26743; number_of_response:1; }","duration":"484.370443ms","start":"2026-10-09T03:18:29.510807Z","end":"2026-10-09T03:18:29.995177Z","steps":["trace[2024858022] 'process raft request' (duration: 484.160958ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:18:29.995287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:18:29.51077Z","time spent":"484.478584ms","remote":"127.0.0.1:37248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T03:18:29.995554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.328992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-09T03:18:29.995653Z","caller":"traceutil/trace.go:171","msg":"trace[1803433886] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26743; }","duration":"436.479155ms","start":"2026-10-09T03:18:29.559161Z","end":"2026-10-09T03:18:29.99564Z","steps":["trace[1803433886] 'agreement among raft nodes before linearized reading' (duration: 436.299291ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:18:29.995663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.002073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/networks/\" range_end:\"/registry/openstack.k-orc.cloud/networks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T03:18:29.995749Z","caller":"traceutil/trace.go:171","msg":"trace[1234250220] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/networks/; range_end:/registry/openstack.k-orc.cloud/networks0; response_count:0; response_revision:26743; }","duration":"273.146256ms","start":"2026-10-09T03:18:29.722589Z","end":"2026-10-09T03:18:29.995735Z","steps":["trace[1234250220] 'agreement among raft nodes before linearized reading' (duration: 273.025374ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:18:29.995767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:18:29.559138Z","time spent":"436.565177ms","remote":"127.0.0.1:37248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-09T03:18:29.995825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.776072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T03:18:29.995898Z","caller":"traceutil/trace.go:171","msg":"trace[1419590007] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:26743; }","duration":"200.910845ms","start":"2026-10-09T03:18:29.794971Z","end":"2026-10-09T03:18:29.995882Z","steps":["trace[1419590007] 'agreement among raft nodes before linearized reading' (duration: 200.779221ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:18:29.995256Z","caller":"traceutil/trace.go:171","msg":"trace[729509977] transaction","detail":"{read_only:false; response_revision:26742; number_of_response:1; }","duration":"524.174868ms","start":"2026-10-09T03:18:29.471049Z","end":"2026-10-09T03:18:29.995224Z","steps":["trace[729509977] 'process raft request' (duration: 408.757779ms)","trace[729509977] 'compare' (duration: 114.704883ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:18:29.996029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:18:29.47102Z","time spent":"524.941265ms","remote":"127.0.0.1:37248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T03:18:31.012135Z","caller":"traceutil/trace.go:171","msg":"trace[694776613] transaction","detail":"{read_only:false; response_revision:26753; number_of_response:1; }","duration":"106.660461ms","start":"2026-10-09T03:18:30.905446Z","end":"2026-10-09T03:18:31.012106Z","steps":["trace[694776613] 'process raft request' (duration: 106.0924ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:18:34.485461Z","caller":"traceutil/trace.go:171","msg":"trace[1856545865] transaction","detail":"{read_only:false; response_revision:26775; number_of_response:1; }","duration":"153.386525ms","start":"2026-10-09T03:18:34.332047Z","end":"2026-10-09T03:18:34.485433Z","steps":["trace[1856545865] 'process raft request' (duration: 153.211002ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:18:35.182797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.293646ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T03:18:35.182982Z","caller":"traceutil/trace.go:171","msg":"trace[942130677] linearizableReadLoop","detail":"{readStateIndex:27668; appliedIndex:27664; }","duration":"247.844544ms","start":"2026-10-09T03:18:34.935125Z","end":"2026-10-09T03:18:35.18297Z","steps":["trace[942130677] 'read index received' (duration: 835.228µs)","trace[942130677] 'applied index is now lower than readState.Index' (duration: 247.008756ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:18:35.182991Z","caller":"traceutil/trace.go:171","msg":"trace[576727153] transaction","detail":"{read_only:false; response_revision:26778; number_of_response:1; }","duration":"319.920232ms","start":"2026-10-09T03:18:34.863047Z","end":"2026-10-09T03:18:35.182967Z","steps":["trace[576727153] 'process raft request' (duration: 170.358209ms)","trace[576727153] 'compare' (duration: 148.968179ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:18:35.183064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.946135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-09T03:18:35.183031Z","caller":"traceutil/trace.go:171","msg":"trace[703319342] transaction","detail":"{read_only:false; response_revision:26779; number_of_response:1; }","duration":"318.686234ms","start":"2026-10-09T03:18:34.864315Z","end":"2026-10-09T03:18:35.183001Z","steps":["trace[703319342] 'process raft request' (duration: 318.564112ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:18:35.18309Z","caller":"traceutil/trace.go:171","msg":"trace[1563177478] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:26780; }","duration":"248.010817ms","start":"2026-10-09T03:18:34.935072Z","end":"2026-10-09T03:18:35.183083Z","steps":["trace[1563177478] 'agreement among raft nodes before linearized reading' (duration: 247.965045ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:18:35.183069Z","caller":"traceutil/trace.go:171","msg":"trace[1414153100] transaction","detail":"{read_only:false; response_revision:26780; number_of_response:1; }","duration":"318.688974ms","start":"2026-10-09T03:18:34.864344Z","end":"2026-10-09T03:18:35.183033Z","steps":["trace[1414153100] 'process raft request' (duration: 318.593953ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:18:35.183095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:18:34.863019Z","time spent":"320.018462ms","remote":"127.0.0.1:37248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T03:18:35.183199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:18:34.8643Z","time spent":"318.802867ms","remote":"127.0.0.1:37248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T03:18:35.183219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:18:34.86433Z","time spent":"318.807098ms","remote":"127.0.0.1:37248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T03:18:35.183329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.189575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-09T03:18:35.183405Z","caller":"traceutil/trace.go:171","msg":"trace[478306548] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26780; }","duration":"150.272817ms","start":"2026-10-09T03:18:35.033115Z","end":"2026-10-09T03:18:35.183388Z","steps":["trace[478306548] 'agreement among raft nodes before linearized reading' (duration: 150.093124ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:18:35.810486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.67956ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-09T03:18:35.972246Z","caller":"traceutil/trace.go:171","msg":"trace[1961609441] transaction","detail":"{read_only:false; response_revision:26783; number_of_response:1; }","duration":"157.210435ms","start":"2026-10-09T03:18:35.815008Z","end":"2026-10-09T03:18:35.972218Z","steps":["trace[1961609441] 'process raft request' (duration: 157.017801ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:18:36.163097Z","caller":"traceutil/trace.go:171","msg":"trace[473973066] linearizableReadLoop","detail":"{readStateIndex:27673; appliedIndex:27672; }","duration":"132.409802ms","start":"2026-10-09T03:18:36.030663Z","end":"2026-10-09T03:18:36.163073Z","steps":["trace[473973066] 'read index received' (duration: 56.991553ms)","trace[473973066] 'applied index is now lower than readState.Index' (duration: 75.417239ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:18:36.16314Z","caller":"traceutil/trace.go:171","msg":"trace[1967605849] transaction","detail":"{read_only:false; response_revision:26784; number_of_response:1; }","duration":"151.347559ms","start":"2026-10-09T03:18:36.01175Z","end":"2026-10-09T03:18:36.163098Z","steps":["trace[1967605849] 'process raft request' (duration: 75.998072ms)","trace[1967605849] 'compare' (duration: 75.203864ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:18:36.163312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.627457ms","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-10-09T03:18:36.163354Z","caller":"traceutil/trace.go:171","msg":"trace[1939078054] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26784; }","duration":"132.710398ms","start":"2026-10-09T03:18:36.030631Z","end":"2026-10-09T03:18:36.163341Z","steps":["trace[1939078054] 'agreement among raft nodes before linearized reading' (duration: 132.568495ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:18:36.392733Z","caller":"traceutil/trace.go:171","msg":"trace[1292255980] transaction","detail":"{read_only:false; response_revision:26786; number_of_response:1; }","duration":"225.264145ms","start":"2026-10-09T03:18:36.167425Z","end":"2026-10-09T03:18:36.392689Z","steps":["trace[1292255980] 'process raft request' (duration: 204.255115ms)","trace[1292255980] 'compare' (duration: 20.746645ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:18:36.394275Z","caller":"traceutil/trace.go:171","msg":"trace[1609645968] transaction","detail":"{read_only:false; response_revision:26787; number_of_response:1; }","duration":"119.285611ms","start":"2026-10-09T03:18:36.274964Z","end":"2026-10-09T03:18:36.39425Z","steps":["trace[1609645968] 'process raft request' (duration: 119.137697ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:18:52.177678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.709658ms","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-10-09T03:18:52.177824Z","caller":"traceutil/trace.go:171","msg":"trace[1178421644] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26913; }","duration":"118.897702ms","start":"2026-10-09T03:18:52.058902Z","end":"2026-10-09T03:18:52.177799Z","steps":["trace[1178421644] 'range keys from in-memory index tree' (duration: 118.460544ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:18:52.315804Z","caller":"traceutil/trace.go:171","msg":"trace[1568316059] transaction","detail":"{read_only:false; response_revision:26914; number_of_response:1; }","duration":"131.450672ms","start":"2026-10-09T03:18:52.184322Z","end":"2026-10-09T03:18:52.315773Z","steps":["trace[1568316059] 'process raft request' (duration: 131.233618ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:18:52.628353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.8374ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T03:18:52.628526Z","caller":"traceutil/trace.go:171","msg":"trace[692946006] transaction","detail":"{read_only:false; response_revision:26915; number_of_response:1; }","duration":"396.129354ms","start":"2026-10-09T03:18:52.232364Z","end":"2026-10-09T03:18:52.628494Z","steps":["trace[692946006] 'process raft request' (duration: 237.04083ms)","trace[692946006] 'compare' (duration: 158.42557ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:18:52.628624Z","caller":"traceutil/trace.go:171","msg":"trace[1370207946] transaction","detail":"{read_only:false; response_revision:26916; number_of_response:1; }","duration":"179.548244ms","start":"2026-10-09T03:18:52.449052Z","end":"2026-10-09T03:18:52.6286Z","steps":["trace[1370207946] 'process raft request' (duration: 179.450383ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:18:52.628665Z","caller":"traceutil/trace.go:171","msg":"trace[147827893] linearizableReadLoop","detail":"{readStateIndex:27807; appliedIndex:27805; }","duration":"319.821525ms","start":"2026-10-09T03:18:52.308817Z","end":"2026-10-09T03:18:52.628638Z","steps":["trace[147827893] 'read index received' (duration: 6.723614ms)","trace[147827893] 'applied index is now lower than readState.Index' (duration: 313.094581ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:18:52.628674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:18:52.232331Z","time spent":"396.262946ms","remote":"127.0.0.1:37248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T03:18:52.629023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.205664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-09T03:18:52.62908Z","caller":"traceutil/trace.go:171","msg":"trace[1928479752] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26916; }","duration":"320.304015ms","start":"2026-10-09T03:18:52.308762Z","end":"2026-10-09T03:18:52.629066Z","steps":["trace[1928479752] 'agreement among raft nodes before linearized reading' (duration: 320.005399ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:18:52.62912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:18:52.308743Z","time spent":"320.365907ms","remote":"127.0.0.1:37248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-09T03:18:52.833413Z","caller":"traceutil/trace.go:171","msg":"trace[1139811705] transaction","detail":"{read_only:false; response_revision:26917; number_of_response:1; }","duration":"249.890324ms","start":"2026-10-09T03:18:52.583481Z","end":"2026-10-09T03:18:52.833371Z","steps":["trace[1139811705] 'process raft request' (duration: 249.72238ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:18:52.835413Z","caller":"traceutil/trace.go:171","msg":"trace[118927766] linearizableReadLoop","detail":"{readStateIndex:27809; appliedIndex:27809; }","duration":"206.652545ms","start":"2026-10-09T03:18:52.628732Z","end":"2026-10-09T03:18:52.835385Z","steps":["trace[118927766] 'read index received' (duration: 206.634415ms)","trace[118927766] 'applied index is now lower than readState.Index' (duration: 15.29µs)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:18:52.835553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.848102ms","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-10-09T03:18:52.835543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.393605ms","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-10-09T03:18:52.835597Z","caller":"traceutil/trace.go:171","msg":"trace[507540677] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26917; }","duration":"364.946614ms","start":"2026-10-09T03:18:52.470641Z","end":"2026-10-09T03:18:52.835588Z","steps":["trace[507540677] 'agreement among raft nodes before linearized reading' (duration: 364.825502ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:18:52.835627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:18:52.470621Z","time spent":"365.000585ms","remote":"127.0.0.1:37174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-10-09T03:18:52.835633Z","caller":"traceutil/trace.go:171","msg":"trace[1680100692] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26917; }","duration":"360.489008ms","start":"2026-10-09T03:18:52.475122Z","end":"2026-10-09T03:18:52.835611Z","steps":["trace[1680100692] 'agreement among raft nodes before linearized reading' (duration: 360.276813ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:18:52.835676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:18:52.475087Z","time spent":"360.5768ms","remote":"127.0.0.1:37248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-09T03:18:53.304256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.720523ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T03:18:53.30465Z","caller":"traceutil/trace.go:171","msg":"trace[1286858173] transaction","detail":"{read_only:false; response_revision:26919; number_of_response:1; }","duration":"540.127224ms","start":"2026-10-09T03:18:52.764465Z","end":"2026-10-09T03:18:53.304592Z","steps":["trace[1286858173] 'process raft request' (duration: 539.871128ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:18:53.304699Z","caller":"traceutil/trace.go:171","msg":"trace[385076997] transaction","detail":"{read_only:false; response_revision:26920; number_of_response:1; }","duration":"465.808949ms","start":"2026-10-09T03:18:52.838844Z","end":"2026-10-09T03:18:53.304653Z","steps":["trace[385076997] 'process raft request' (duration: 465.612765ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:18:53.304803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.725977ms","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-10-09T03:18:53.30487Z","caller":"traceutil/trace.go:171","msg":"trace[491635372] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26920; }","duration":"465.81994ms","start":"2026-10-09T03:18:52.839033Z","end":"2026-10-09T03:18:53.304853Z","steps":["trace[491635372] 'agreement among raft nodes before linearized reading' (duration: 465.669567ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:18:53.304872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:18:52.764441Z","time spent":"540.344929ms","remote":"127.0.0.1:37248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T03:18:53.304902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:18:52.838815Z","time spent":"465.994163ms","remote":"127.0.0.1:37248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T03:18:53.304773Z","caller":"traceutil/trace.go:171","msg":"trace[1514295894] transaction","detail":"{read_only:false; response_revision:26918; number_of_response:1; }","duration":"671.27838ms","start":"2026-10-09T03:18:52.633419Z","end":"2026-10-09T03:18:53.304697Z","steps":["trace[1514295894] 'process raft request' (duration: 317.033795ms)","trace[1514295894] 'compare' (duration: 353.57489ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:18:53.304927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:18:52.839018Z","time spent":"465.894081ms","remote":"127.0.0.1:37174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-10-09T03:18:53.30466Z","caller":"traceutil/trace.go:171","msg":"trace[500099760] linearizableReadLoop","detail":"{readStateIndex:27812; appliedIndex:27809; }","duration":"465.566384ms","start":"2026-10-09T03:18:52.839064Z","end":"2026-10-09T03:18:53.304631Z","steps":["trace[500099760] 'read index received' (duration: 110.631765ms)","trace[500099760] 'applied index is now lower than readState.Index' (duration: 354.931579ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:18:53.305059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:18:52.633396Z","time spent":"671.568575ms","remote":"127.0.0.1:37248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T03:18:53.33588Z","caller":"traceutil/trace.go:171","msg":"trace[503910319] transaction","detail":"{read_only:false; response_revision:26921; number_of_response:1; }","duration":"329.800449ms","start":"2026-10-09T03:18:53.006059Z","end":"2026-10-09T03:18:53.33586Z","steps":["trace[503910319] 'process raft request' (duration: 329.627805ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:18:53.335996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:18:53.006034Z","time spent":"329.901352ms","remote":"127.0.0.1:37248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T03:18:53.715018Z","caller":"traceutil/trace.go:171","msg":"trace[976019474] linearizableReadLoop","detail":"{readStateIndex:27816; appliedIndex:27812; }","duration":"409.991062ms","start":"2026-10-09T03:18:53.305007Z","end":"2026-10-09T03:18:53.714998Z","steps":["trace[976019474] 'read index received' (duration: 30.775332ms)","trace[976019474] 'applied index is now lower than readState.Index' (duration: 379.21494ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:18:53.715078Z","caller":"traceutil/trace.go:171","msg":"trace[331465638] transaction","detail":"{read_only:false; response_revision:26923; number_of_response:1; }","duration":"455.350094ms","start":"2026-10-09T03:18:53.259691Z","end":"2026-10-09T03:18:53.715041Z","steps":["trace[331465638] 'process raft request' (duration: 455.249672ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:18:53.715192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.112656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T03:18:53.715162Z","caller":"traceutil/trace.go:171","msg":"trace[751736673] transaction","detail":"{read_only:false; response_revision:26924; number_of_response:1; }","duration":"455.235612ms","start":"2026-10-09T03:18:53.259888Z","end":"2026-10-09T03:18:53.715123Z","steps":["trace[751736673] 'process raft request' (duration: 455.080968ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:18:53.715226Z","caller":"traceutil/trace.go:171","msg":"trace[782237214] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:26924; }","duration":"486.211287ms","start":"2026-10-09T03:18:53.229004Z","end":"2026-10-09T03:18:53.715215Z","steps":["trace[782237214] 'agreement among raft nodes before linearized reading' (duration: 486.121996ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:18:53.715238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:18:53.259681Z","time spent":"455.475258ms","remote":"127.0.0.1:37248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T03:18:53.715255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:18:53.228986Z","time spent":"486.259868ms","remote":"127.0.0.1:59766","response type":"/etcdserverpb.KV/Range","request count":0,"request size":126,"response count":0,"response size":28,"request content":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true "} {"level":"warn","ts":"2026-10-09T03:18:53.715331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:18:53.259881Z","time spent":"455.360314ms","remote":"127.0.0.1:37248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T03:18:53.715433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.816737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterctl.cluster.x-k8s.io/providers/\" range_end:\"/registry/clusterctl.cluster.x-k8s.io/providers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-09T03:18:53.715479Z","caller":"traceutil/trace.go:171","msg":"trace[1981935995] range","detail":"{range_begin:/registry/clusterctl.cluster.x-k8s.io/providers/; range_end:/registry/clusterctl.cluster.x-k8s.io/providers0; response_count:0; response_revision:26924; }","duration":"566.902209ms","start":"2026-10-09T03:18:53.148563Z","end":"2026-10-09T03:18:53.715465Z","steps":["trace[1981935995] 'agreement among raft nodes before linearized reading' (duration: 566.800717ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:18:53.715486Z","caller":"traceutil/trace.go:171","msg":"trace[2137526534] transaction","detail":"{read_only:false; response_revision:26922; number_of_response:1; }","duration":"456.602581ms","start":"2026-10-09T03:18:53.258871Z","end":"2026-10-09T03:18:53.715473Z","steps":["trace[2137526534] 'process raft request' (duration: 455.940567ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:18:53.715511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:18:53.148548Z","time spent":"566.954251ms","remote":"127.0.0.1:57666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":4,"response size":30,"request content":"key:\"/registry/clusterctl.cluster.x-k8s.io/providers/\" range_end:\"/registry/clusterctl.cluster.x-k8s.io/providers0\" count_only:true "} {"level":"warn","ts":"2026-10-09T03:18:53.715549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:18:53.25885Z","time spent":"456.661373ms","remote":"127.0.0.1:37248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T03:18:53.715871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.572828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-09T03:18:53.715901Z","caller":"traceutil/trace.go:171","msg":"trace[354159747] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26924; }","duration":"344.643399ms","start":"2026-10-09T03:18:53.371249Z","end":"2026-10-09T03:18:53.715892Z","steps":["trace[354159747] 'agreement among raft nodes before linearized reading' (duration: 344.511265ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:18:53.715925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:18:53.37123Z","time spent":"344.688908ms","remote":"127.0.0.1:37248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-09T03:18:53.715943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.602737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T03:18:53.71598Z","caller":"traceutil/trace.go:171","msg":"trace[199748813] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:26924; }","duration":"480.669388ms","start":"2026-10-09T03:18:53.235299Z","end":"2026-10-09T03:18:53.715968Z","steps":["trace[199748813] 'agreement among raft nodes before linearized reading' (duration: 480.601197ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:18:53.716005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.614232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19030"} {"level":"warn","ts":"2026-10-09T03:18:53.716008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:18:53.235286Z","time spent":"480.713259ms","remote":"127.0.0.1:51472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"info","ts":"2026-10-09T03:18:53.716035Z","caller":"traceutil/trace.go:171","msg":"trace[244184163] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:26924; }","duration":"409.693764ms","start":"2026-10-09T03:18:53.306332Z","end":"2026-10-09T03:18:53.716026Z","steps":["trace[244184163] 'agreement among raft nodes before linearized reading' (duration: 409.400358ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:18:53.716017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.560442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-09T03:18:53.716098Z","caller":"traceutil/trace.go:171","msg":"trace[1881128000] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26924; }","duration":"396.685765ms","start":"2026-10-09T03:18:53.31939Z","end":"2026-10-09T03:18:53.716076Z","steps":["trace[1881128000] 'agreement among raft nodes before linearized reading' (duration: 396.41962ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:18:53.71614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:18:53.319372Z","time spent":"396.758828ms","remote":"127.0.0.1:37248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-09T03:18:53.716055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:18:53.306314Z","time spent":"409.735896ms","remote":"127.0.0.1:37168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":19052,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-10-09T03:18:54.116512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.584771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-09T03:18:54.116596Z","caller":"traceutil/trace.go:171","msg":"trace[421072295] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:26926; }","duration":"135.726414ms","start":"2026-10-09T03:18:53.980846Z","end":"2026-10-09T03:18:54.116573Z","steps":["trace[421072295] 'count revisions from in-memory index tree' (duration: 135.499158ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:18:54.825865Z","caller":"traceutil/trace.go:171","msg":"trace[1993273054] transaction","detail":"{read_only:false; response_revision:26928; number_of_response:1; }","duration":"192.38651ms","start":"2026-10-09T03:18:54.633453Z","end":"2026-10-09T03:18:54.825839Z","steps":["trace[1993273054] 'process raft request' (duration: 192.177286ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:18:54.826931Z","caller":"traceutil/trace.go:171","msg":"trace[8683586] transaction","detail":"{read_only:false; response_revision:26929; number_of_response:1; }","duration":"192.862429ms","start":"2026-10-09T03:18:54.63405Z","end":"2026-10-09T03:18:54.826913Z","steps":["trace[8683586] 'process raft request' (duration: 192.569453ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:19:04.973511Z","caller":"traceutil/trace.go:171","msg":"trace[1335184753] transaction","detail":"{read_only:false; response_revision:27146; number_of_response:1; }","duration":"114.936767ms","start":"2026-10-09T03:19:04.858545Z","end":"2026-10-09T03:19:04.973482Z","steps":["trace[1335184753] 'process raft request' (duration: 114.751024ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:19:05.166643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.447348ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T03:19:05.166851Z","caller":"traceutil/trace.go:171","msg":"trace[296109109] transaction","detail":"{read_only:false; response_revision:27148; number_of_response:1; }","duration":"303.559077ms","start":"2026-10-09T03:19:04.863275Z","end":"2026-10-09T03:19:05.166834Z","steps":["trace[296109109] 'process raft request' (duration: 303.499615ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:19:05.166922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:19:04.863249Z","time spent":"303.639958ms","remote":"127.0.0.1:37248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T03:19:05.166923Z","caller":"traceutil/trace.go:171","msg":"trace[190559356] transaction","detail":"{read_only:false; response_revision:27147; number_of_response:1; }","duration":"304.763432ms","start":"2026-10-09T03:19:04.862087Z","end":"2026-10-09T03:19:05.16685Z","steps":["trace[190559356] 'process raft request' (duration: 189.023947ms)","trace[190559356] 'compare' (duration: 115.308326ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:19:05.167146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:19:04.862058Z","time spent":"304.994747ms","remote":"127.0.0.1:37248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T03:20:07.15882Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24880} {"level":"info","ts":"2026-10-09T03:20:07.306134Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24880,"took":"144.499862ms","hash":2199095481,"current-db-size-bytes":57446400,"current-db-size":"57 MB","current-db-size-in-use-bytes":36745216,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-10-09T03:20:07.306208Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2199095481,"revision":24880,"compact-revision":21630} {"level":"warn","ts":"2026-10-09T03:20:28.877491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.602574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T03:20:28.877563Z","caller":"traceutil/trace.go:171","msg":"trace[1941214512] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27937; }","duration":"110.708397ms","start":"2026-10-09T03:20:28.766838Z","end":"2026-10-09T03:20:28.877546Z","steps":["trace[1941214512] 'range keys from in-memory index tree' (duration: 110.540003ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:22:16.227404Z","caller":"traceutil/trace.go:171","msg":"trace[91927128] linearizableReadLoop","detail":"{readStateIndex:29902; appliedIndex:29901; }","duration":"151.088564ms","start":"2026-10-09T03:22:16.076283Z","end":"2026-10-09T03:22:16.227372Z","steps":["trace[91927128] 'read index received' (duration: 151.018972ms)","trace[91927128] 'applied index is now lower than readState.Index' (duration: 68.062µs)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:22:16.227627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.282797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T03:22:16.227673Z","caller":"traceutil/trace.go:171","msg":"trace[1497403937] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:28959; }","duration":"151.41463ms","start":"2026-10-09T03:22:16.076244Z","end":"2026-10-09T03:22:16.227659Z","steps":["trace[1497403937] 'agreement among raft nodes before linearized reading' (duration: 151.257476ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:22:16.228019Z","caller":"traceutil/trace.go:171","msg":"trace[873696704] transaction","detail":"{read_only:false; response_revision:28959; number_of_response:1; }","duration":"174.834043ms","start":"2026-10-09T03:22:16.053147Z","end":"2026-10-09T03:22:16.227981Z","steps":["trace[873696704] 'process raft request' (duration: 174.033556ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:22:16.243659Z","caller":"traceutil/trace.go:171","msg":"trace[407270089] transaction","detail":"{read_only:false; response_revision:28960; number_of_response:1; }","duration":"125.787201ms","start":"2026-10-09T03:22:16.11784Z","end":"2026-10-09T03:22:16.243627Z","steps":["trace[407270089] 'process raft request' (duration: 125.634878ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:22:23.273149Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"40fb1eca294b6a5a","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-09T03:22:23.28342Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-09T03:22:23.283608Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-09T03:22:51.256896Z","caller":"traceutil/trace.go:171","msg":"trace[440685692] transaction","detail":"{read_only:false; response_revision:29324; number_of_response:1; }","duration":"116.760167ms","start":"2026-10-09T03:22:51.140095Z","end":"2026-10-09T03:22:51.256855Z","steps":["trace[440685692] 'process raft request' (duration: 52.128849ms)","trace[440685692] 'compare' (duration: 64.344042ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:25:07.168679Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27719} {"level":"info","ts":"2026-10-09T03:25:07.327857Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27719,"took":"154.003896ms","hash":1425581345,"current-db-size-bytes":57446400,"current-db-size":"57 MB","current-db-size-in-use-bytes":34463744,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-09T03:25:07.328Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1425581345,"revision":27719,"compact-revision":24880} {"level":"info","ts":"2026-10-09T03:30:07.177251Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30361} {"level":"info","ts":"2026-10-09T03:30:07.326645Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30361,"took":"146.394107ms","hash":1799438993,"current-db-size-bytes":57446400,"current-db-size":"57 MB","current-db-size-in-use-bytes":30744576,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-09T03:30:07.326755Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1799438993,"revision":30361,"compact-revision":27719} {"level":"info","ts":"2026-10-09T03:35:07.18479Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32614} {"level":"info","ts":"2026-10-09T03:35:07.348812Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32614,"took":"159.510858ms","hash":2158993590,"current-db-size-bytes":58490880,"current-db-size":"58 MB","current-db-size-in-use-bytes":30400512,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-09T03:35:07.348883Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2158993590,"revision":32614,"compact-revision":30361} {"level":"info","ts":"2026-10-09T03:40:07.193757Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34849} {"level":"info","ts":"2026-10-09T03:40:07.330579Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34849,"took":"133.708973ms","hash":179415792,"current-db-size-bytes":58490880,"current-db-size":"58 MB","current-db-size-in-use-bytes":31707136,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-09T03:40:07.330646Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":179415792,"revision":34849,"compact-revision":32614} {"level":"info","ts":"2026-10-09T03:43:42.940331Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"40fb1eca294b6a5a","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-09T03:43:42.946363Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-09T03:43:42.946482Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-09T03:45:07.200435Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37145} {"level":"info","ts":"2026-10-09T03:45:07.334686Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37145,"took":"130.108087ms","hash":2237284175,"current-db-size-bytes":59342848,"current-db-size":"59 MB","current-db-size-in-use-bytes":32817152,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-09T03:45:07.334753Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2237284175,"revision":37145,"compact-revision":34849} {"level":"info","ts":"2026-10-09T03:50:07.208259Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39421} {"level":"info","ts":"2026-10-09T03:50:07.355389Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39421,"took":"142.509294ms","hash":4142859807,"current-db-size-bytes":59768832,"current-db-size":"60 MB","current-db-size-in-use-bytes":30896128,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-09T03:50:07.355512Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4142859807,"revision":39421,"compact-revision":37145}