{"level":"warn","ts":"2026-09-25T03:22:38.325492Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-25T03:22:38.325657Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.159: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.159:2380","--initial-cluster=instance=https://162.253.55.159:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.159:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.159:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-09-25T03:22:38.325747Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-25T03:22:38.325757Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.159:2380"]} {"level":"info","ts":"2026-09-25T03:22:38.325782Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-25T03:22:38.326758Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.159:2379"]} {"level":"info","ts":"2026-09-25T03:22:38.326863Z","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.159:2380"],"listen-peer-urls":["https://162.253.55.159:2380"],"advertise-client-urls":["https://162.253.55.159:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.159:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.159:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-09-25T03:22:38.333214Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.064908ms"} {"level":"info","ts":"2026-09-25T03:22:38.346046Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"94bfbb0e034a3ac2","cluster-id":"9676abcf1742949"} {"level":"info","ts":"2026-09-25T03:22:38.346153Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 switched to configuration voters=()"} {"level":"info","ts":"2026-09-25T03:22:38.346183Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 became follower at term 0"} {"level":"info","ts":"2026-09-25T03:22:38.346195Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 94bfbb0e034a3ac2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-25T03:22:38.346205Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 became follower at term 1"} {"level":"info","ts":"2026-09-25T03:22:38.346262Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 switched to configuration voters=(10718491307024202434)"} {"level":"warn","ts":"2026-09-25T03:22:38.360119Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-25T03:22:38.363221Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-25T03:22:38.366107Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-09-25T03:22:38.371125Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"94bfbb0e034a3ac2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-25T03:22:38.372012Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"94bfbb0e034a3ac2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-25T03:22:38.372116Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-25T03:22:38.372358Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-25T03:22:38.372441Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-25T03:22:38.375969Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 switched to configuration voters=(10718491307024202434)"} {"level":"info","ts":"2026-09-25T03:22:38.37619Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9676abcf1742949","local-member-id":"94bfbb0e034a3ac2","added-peer-id":"94bfbb0e034a3ac2","added-peer-peer-urls":["https://162.253.55.159:2380"]} {"level":"info","ts":"2026-09-25T03:22:38.376257Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-25T03:22:38.376343Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.159:2380"} {"level":"info","ts":"2026-09-25T03:22:38.376382Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.159:2380"} {"level":"info","ts":"2026-09-25T03:22:38.376651Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"94bfbb0e034a3ac2","initial-advertise-peer-urls":["https://162.253.55.159:2380"],"listen-peer-urls":["https://162.253.55.159:2380"],"advertise-client-urls":["https://162.253.55.159:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.159:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-25T03:22:38.376683Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-25T03:22:39.347185Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 is starting a new election at term 1"} {"level":"info","ts":"2026-09-25T03:22:39.347273Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-25T03:22:39.347307Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 received MsgPreVoteResp from 94bfbb0e034a3ac2 at term 1"} {"level":"info","ts":"2026-09-25T03:22:39.347328Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 became candidate at term 2"} {"level":"info","ts":"2026-09-25T03:22:39.347339Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 received MsgVoteResp from 94bfbb0e034a3ac2 at term 2"} {"level":"info","ts":"2026-09-25T03:22:39.347358Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 became leader at term 2"} {"level":"info","ts":"2026-09-25T03:22:39.34738Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 94bfbb0e034a3ac2 elected leader 94bfbb0e034a3ac2 at term 2"} {"level":"info","ts":"2026-09-25T03:22:39.3486Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-25T03:22:39.349673Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-25T03:22:39.349638Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"94bfbb0e034a3ac2","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.159:2379]}","request-path":"/0/members/94bfbb0e034a3ac2/attributes","cluster-id":"9676abcf1742949","publish-timeout":"7s"} {"level":"info","ts":"2026-09-25T03:22:39.349745Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-25T03:22:39.350114Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-25T03:22:39.350153Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-25T03:22:39.350386Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9676abcf1742949","local-member-id":"94bfbb0e034a3ac2","cluster-version":"3.5"} {"level":"info","ts":"2026-09-25T03:22:39.350508Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-25T03:22:39.350594Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-25T03:22:39.353246Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.159:2379"} {"level":"info","ts":"2026-09-25T03:22:39.353431Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-25T03:23:04.475626Z","caller":"traceutil/trace.go:171","msg":"trace[2047390785] transaction","detail":"{read_only:false; response_revision:438; number_of_response:1; }","duration":"109.498722ms","start":"2026-09-25T03:23:04.366084Z","end":"2026-09-25T03:23:04.475583Z","steps":["trace[2047390785] 'process raft request' (duration: 109.169534ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T03:23:29.593545Z","caller":"traceutil/trace.go:171","msg":"trace[54667966] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"133.434861ms","start":"2026-09-25T03:23:29.460069Z","end":"2026-09-25T03:23:29.593504Z","steps":["trace[54667966] 'process raft request' (duration: 80.173275ms)","trace[54667966] 'compare' (duration: 53.103012ms)"],"step_count":2} {"level":"warn","ts":"2026-09-25T03:23:29.861208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.488422ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-25T03:23:29.861386Z","caller":"traceutil/trace.go:171","msg":"trace[1222175150] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"164.994856ms","start":"2026-09-25T03:23:29.696363Z","end":"2026-09-25T03:23:29.861358Z","steps":["trace[1222175150] 'process raft request' (duration: 19.663085ms)","trace[1222175150] 'compare' (duration: 144.363749ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T03:23:30.111548Z","caller":"traceutil/trace.go:171","msg":"trace[1389140360] linearizableReadLoop","detail":"{readStateIndex:679; appliedIndex:678; }","duration":"169.448736ms","start":"2026-09-25T03:23:29.942061Z","end":"2026-09-25T03:23:30.111509Z","steps":["trace[1389140360] 'read index received' (duration: 168.29666ms)","trace[1389140360] 'applied index is now lower than readState.Index' (duration: 1.150346ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T03:23:30.111659Z","caller":"traceutil/trace.go:171","msg":"trace[839303347] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"320.65028ms","start":"2026-09-25T03:23:29.79098Z","end":"2026-09-25T03:23:30.111631Z","steps":["trace[839303347] 'process raft request' (duration: 319.840162ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T03:23:30.111805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.725863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-nq27d\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-09-25T03:23:30.111849Z","caller":"traceutil/trace.go:171","msg":"trace[466211615] range","detail":"{range_begin:/registry/pods/kube-system/cilium-nq27d; range_end:; response_count:1; response_revision:653; }","duration":"169.832075ms","start":"2026-09-25T03:23:29.942004Z","end":"2026-09-25T03:23:30.111836Z","steps":["trace[466211615] 'agreement among raft nodes before linearized reading' (duration: 169.657301ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T03:23:30.111823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T03:23:29.790948Z","time spent":"320.767542ms","remote":"127.0.0.1:35014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036293,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-25T03:23:30.680553Z","caller":"traceutil/trace.go:171","msg":"trace[552382745] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"541.312456ms","start":"2026-09-25T03:23:30.139218Z","end":"2026-09-25T03:23:30.68053Z","steps":["trace[552382745] 'process raft request' (duration: 541.149633ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T03:23:30.68084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T03:23:30.139171Z","time spent":"541.511493ms","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-25T03:23:30.72491Z","caller":"traceutil/trace.go:171","msg":"trace[438934459] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"533.381227ms","start":"2026-09-25T03:23:30.191441Z","end":"2026-09-25T03:23:30.724823Z","steps":["trace[438934459] 'process raft request' (duration: 533.04727ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T03:23:30.725233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T03:23:30.191402Z","time spent":"533.706435ms","remote":"127.0.0.1:35448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-25T03:23:46.312431Z","caller":"traceutil/trace.go:171","msg":"trace[625794837] transaction","detail":"{read_only:false; response_revision:819; number_of_response:1; }","duration":"249.059339ms","start":"2026-09-25T03:23:46.063349Z","end":"2026-09-25T03:23:46.312408Z","steps":["trace[625794837] 'process raft request' (duration: 248.929556ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T03:23:46.386385Z","caller":"traceutil/trace.go:171","msg":"trace[1322257382] linearizableReadLoop","detail":"{readStateIndex:851; appliedIndex:849; }","duration":"272.999921ms","start":"2026-09-25T03:23:46.113362Z","end":"2026-09-25T03:23:46.386362Z","steps":["trace[1322257382] 'read index received' (duration: 198.960125ms)","trace[1322257382] 'applied index is now lower than readState.Index' (duration: 74.038576ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T03:23:46.386454Z","caller":"traceutil/trace.go:171","msg":"trace[1683039773] transaction","detail":"{read_only:false; response_revision:820; number_of_response:1; }","duration":"314.768087ms","start":"2026-09-25T03:23:46.071664Z","end":"2026-09-25T03:23:46.386432Z","steps":["trace[1683039773] 'process raft request' (duration: 314.538322ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T03:23:46.386661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.259247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-25T03:23:46.386753Z","caller":"traceutil/trace.go:171","msg":"trace[1212510901] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:820; }","duration":"273.408591ms","start":"2026-09-25T03:23:46.113325Z","end":"2026-09-25T03:23:46.386734Z","steps":["trace[1212510901] 'agreement among raft nodes before linearized reading' (duration: 273.178656ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T03:23:46.386696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T03:23:46.071628Z","time spent":"314.936511ms","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-25T03:25:06.690917Z","caller":"traceutil/trace.go:171","msg":"trace[1556338294] linearizableReadLoop","detail":"{readStateIndex:1982; appliedIndex:1981; }","duration":"167.426367ms","start":"2026-09-25T03:25:06.523461Z","end":"2026-09-25T03:25:06.690887Z","steps":["trace[1556338294] 'read index received' (duration: 167.139331ms)","trace[1556338294] 'applied index is now lower than readState.Index' (duration: 286.236µs)"],"step_count":2} {"level":"info","ts":"2026-09-25T03:25:06.691023Z","caller":"traceutil/trace.go:171","msg":"trace[1585657711] transaction","detail":"{read_only:false; response_revision:1906; number_of_response:1; }","duration":"196.901139ms","start":"2026-09-25T03:25:06.494065Z","end":"2026-09-25T03:25:06.690966Z","steps":["trace[1585657711] 'process raft request' (duration: 196.646663ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T03:25:06.691141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.178776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-25T03:25:06.691191Z","caller":"traceutil/trace.go:171","msg":"trace[392934078] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:1906; }","duration":"121.35232ms","start":"2026-09-25T03:25:06.569828Z","end":"2026-09-25T03:25:06.69118Z","steps":["trace[392934078] 'agreement among raft nodes before linearized reading' (duration: 121.244267ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T03:25:06.691263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.232924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-09-25T03:25:06.691266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.810305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b2f11a0f-4520-4616-a368-c399fce0b461\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-09-25T03:25:06.691347Z","caller":"traceutil/trace.go:171","msg":"trace[1453586221] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1906; }","duration":"148.391879ms","start":"2026-09-25T03:25:06.542932Z","end":"2026-09-25T03:25:06.691324Z","steps":["trace[1453586221] 'agreement among raft nodes before linearized reading' (duration: 148.111553ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T03:25:06.691352Z","caller":"traceutil/trace.go:171","msg":"trace[1801691234] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b2f11a0f-4520-4616-a368-c399fce0b461; range_end:; response_count:1; response_revision:1906; }","duration":"167.984729ms","start":"2026-09-25T03:25:06.52335Z","end":"2026-09-25T03:25:06.691335Z","steps":["trace[1801691234] 'agreement among raft nodes before linearized reading' (duration: 167.678612ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T03:25:06.858254Z","caller":"traceutil/trace.go:171","msg":"trace[1518301590] transaction","detail":"{read_only:false; response_revision:1908; number_of_response:1; }","duration":"137.015592ms","start":"2026-09-25T03:25:06.721181Z","end":"2026-09-25T03:25:06.858197Z","steps":["trace[1518301590] 'process raft request' (duration: 94.633749ms)","trace[1518301590] 'compare' (duration: 42.095497ms)"],"step_count":2} {"level":"warn","ts":"2026-09-25T03:25:20.327592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.163046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-messaging-topology-operator-6f465c979f-5mr7k\" ","response":"range_response_count:1 size:6144"} {"level":"info","ts":"2026-09-25T03:25:20.327734Z","caller":"traceutil/trace.go:171","msg":"trace[695596911] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-messaging-topology-operator-6f465c979f-5mr7k; range_end:; response_count:1; response_revision:2020; }","duration":"113.390551ms","start":"2026-09-25T03:25:20.214314Z","end":"2026-09-25T03:25:20.327704Z","steps":["trace[695596911] 'range keys from in-memory index tree' (duration: 112.89031ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T03:25:23.341117Z","caller":"traceutil/trace.go:171","msg":"trace[1893816653] transaction","detail":"{read_only:false; response_revision:2043; number_of_response:1; }","duration":"133.30777ms","start":"2026-09-25T03:25:23.207775Z","end":"2026-09-25T03:25:23.341083Z","steps":["trace[1893816653] 'process raft request' (duration: 133.100025ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T03:26:02.97305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.976475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.159\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-09-25T03:26:02.973286Z","caller":"traceutil/trace.go:171","msg":"trace[1207449788] range","detail":"{range_begin:/registry/masterleases/162.253.55.159; range_end:; response_count:1; response_revision:2258; }","duration":"126.281792ms","start":"2026-09-25T03:26:02.846976Z","end":"2026-09-25T03:26:02.973258Z","steps":["trace[1207449788] 'range keys from in-memory index tree' (duration: 125.713299ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T03:26:03.285769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.349423ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-25T03:26:03.28617Z","caller":"traceutil/trace.go:171","msg":"trace[935690459] linearizableReadLoop","detail":"{readStateIndex:2350; appliedIndex:2348; }","duration":"172.829599ms","start":"2026-09-25T03:26:03.113318Z","end":"2026-09-25T03:26:03.286148Z","steps":["trace[935690459] 'read index received' (duration: 29.989915ms)","trace[935690459] 'applied index is now lower than readState.Index' (duration: 142.838124ms)"],"step_count":2} {"level":"warn","ts":"2026-09-25T03:26:03.286324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.013514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-25T03:26:03.286332Z","caller":"traceutil/trace.go:171","msg":"trace[250969197] transaction","detail":"{read_only:false; response_revision:2259; number_of_response:1; }","duration":"234.022307ms","start":"2026-09-25T03:26:03.052249Z","end":"2026-09-25T03:26:03.286271Z","steps":["trace[250969197] 'process raft request' (duration: 91.019449ms)","trace[250969197] 'compare' (duration: 141.987314ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T03:26:03.286397Z","caller":"traceutil/trace.go:171","msg":"trace[1901789088] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2260; }","duration":"173.129466ms","start":"2026-09-25T03:26:03.113256Z","end":"2026-09-25T03:26:03.286385Z","steps":["trace[1901789088] 'agreement among raft nodes before linearized reading' (duration: 172.999163ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T03:26:03.286305Z","caller":"traceutil/trace.go:171","msg":"trace[2006377202] transaction","detail":"{read_only:false; response_revision:2260; number_of_response:1; }","duration":"230.2029ms","start":"2026-09-25T03:26:03.056065Z","end":"2026-09-25T03:26:03.286268Z","steps":["trace[2006377202] 'process raft request' (duration: 229.961404ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T03:26:03.299421Z","caller":"traceutil/trace.go:171","msg":"trace[602927760] transaction","detail":"{read_only:false; response_revision:2261; number_of_response:1; }","duration":"129.946015ms","start":"2026-09-25T03:26:03.169454Z","end":"2026-09-25T03:26:03.2994Z","steps":["trace[602927760] 'process raft request' (duration: 129.607736ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T03:26:03.2995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.07879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-25T03:26:03.299545Z","caller":"traceutil/trace.go:171","msg":"trace[300408912] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2261; }","duration":"103.126841ms","start":"2026-09-25T03:26:03.196404Z","end":"2026-09-25T03:26:03.299531Z","steps":["trace[300408912] 'agreement among raft nodes before linearized reading' (duration: 102.909895ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T03:27:10.687266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.019093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-25T03:27:10.687414Z","caller":"traceutil/trace.go:171","msg":"trace[1578598064] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:2811; }","duration":"105.236048ms","start":"2026-09-25T03:27:10.582148Z","end":"2026-09-25T03:27:10.687384Z","steps":["trace[1578598064] 'count revisions from in-memory index tree' (duration: 104.933652ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T03:27:42.991955Z","caller":"traceutil/trace.go:171","msg":"trace[1811287768] transaction","detail":"{read_only:false; response_revision:2996; number_of_response:1; }","duration":"138.723744ms","start":"2026-09-25T03:27:42.853195Z","end":"2026-09-25T03:27:42.991919Z","steps":["trace[1811287768] 'process raft request' (duration: 138.472668ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T03:27:43.259102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.048081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-25T03:27:43.259249Z","caller":"traceutil/trace.go:171","msg":"trace[377842714] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2997; }","duration":"162.256636ms","start":"2026-09-25T03:27:43.096964Z","end":"2026-09-25T03:27:43.25922Z","steps":["trace[377842714] 'range keys from in-memory index tree' (duration: 161.843857ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T03:32:30.231625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.179359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-25T03:32:30.231737Z","caller":"traceutil/trace.go:171","msg":"trace[1571265538] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5239; }","duration":"120.364853ms","start":"2026-09-25T03:32:30.111354Z","end":"2026-09-25T03:32:30.231718Z","steps":["trace[1571265538] 'range keys from in-memory index tree' (duration: 120.070136ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T03:32:32.20951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.516237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-25T03:32:32.209645Z","caller":"traceutil/trace.go:171","msg":"trace[849126645] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:5255; }","duration":"129.728052ms","start":"2026-09-25T03:32:32.079886Z","end":"2026-09-25T03:32:32.209614Z","steps":["trace[849126645] 'count revisions from in-memory index tree' (duration: 129.381704ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T03:32:39.391547Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2977} {"level":"info","ts":"2026-09-25T03:32:39.479987Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2977,"took":"86.416836ms","hash":3011870753,"current-db-size-bytes":54939648,"current-db-size":"55 MB","current-db-size-in-use-bytes":30281728,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-25T03:32:39.480103Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3011870753,"revision":2977,"compact-revision":-1} {"level":"warn","ts":"2026-09-25T03:33:01.456729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.071189ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-25T03:33:01.456891Z","caller":"traceutil/trace.go:171","msg":"trace[1050712160] linearizableReadLoop","detail":"{readStateIndex:6163; appliedIndex:6162; }","duration":"181.065455ms","start":"2026-09-25T03:33:01.275777Z","end":"2026-09-25T03:33:01.456842Z","steps":["trace[1050712160] 'read index received' (duration: 63.770981ms)","trace[1050712160] 'applied index is now lower than readState.Index' (duration: 117.293154ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T03:33:01.456998Z","caller":"traceutil/trace.go:171","msg":"trace[1505971954] transaction","detail":"{read_only:false; response_revision:5939; number_of_response:1; }","duration":"199.036186ms","start":"2026-09-25T03:33:01.257947Z","end":"2026-09-25T03:33:01.456983Z","steps":["trace[1505971954] 'process raft request' (duration: 81.608329ms)","trace[1505971954] 'compare' (duration: 116.906376ms)"],"step_count":2} {"level":"warn","ts":"2026-09-25T03:33:01.457229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.445414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-25T03:33:01.457324Z","caller":"traceutil/trace.go:171","msg":"trace[272092248] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5939; }","duration":"181.597879ms","start":"2026-09-25T03:33:01.275708Z","end":"2026-09-25T03:33:01.457306Z","steps":["trace[272092248] 'agreement among raft nodes before linearized reading' (duration: 181.361612ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T03:33:01.601056Z","caller":"traceutil/trace.go:171","msg":"trace[754348168] transaction","detail":"{read_only:false; response_revision:5940; number_of_response:1; }","duration":"323.608654ms","start":"2026-09-25T03:33:01.277415Z","end":"2026-09-25T03:33:01.601024Z","steps":["trace[754348168] 'process raft request' (duration: 316.010174ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T03:33:01.601251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T03:33:01.277397Z","time spent":"323.749087ms","remote":"127.0.0.1:35188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-25T03:33:01.603946Z","caller":"traceutil/trace.go:171","msg":"trace[522939442] transaction","detail":"{read_only:false; response_revision:5941; number_of_response:1; }","duration":"239.315355ms","start":"2026-09-25T03:33:01.364587Z","end":"2026-09-25T03:33:01.603903Z","steps":["trace[522939442] 'process raft request' (duration: 239.022909ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T03:33:01.60404Z","caller":"traceutil/trace.go:171","msg":"trace[1983744306] transaction","detail":"{read_only:false; response_revision:5942; number_of_response:1; }","duration":"137.848363ms","start":"2026-09-25T03:33:01.466144Z","end":"2026-09-25T03:33:01.603992Z","steps":["trace[1983744306] 'process raft request' (duration: 137.617878ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T03:33:01.604076Z","caller":"traceutil/trace.go:171","msg":"trace[1618944618] linearizableReadLoop","detail":"{readStateIndex:6165; appliedIndex:6163; }","duration":"147.12848ms","start":"2026-09-25T03:33:01.456923Z","end":"2026-09-25T03:33:01.604051Z","steps":["trace[1618944618] 'read index received' (duration: 136.878041ms)","trace[1618944618] 'applied index is now lower than readState.Index' (duration: 10.249229ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T03:33:01.604088Z","caller":"traceutil/trace.go:171","msg":"trace[355162314] transaction","detail":"{read_only:false; response_revision:5943; number_of_response:1; }","duration":"110.103854ms","start":"2026-09-25T03:33:01.493965Z","end":"2026-09-25T03:33:01.604068Z","steps":["trace[355162314] 'process raft request' (duration: 109.885319ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T03:33:01.604292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.573316ms","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-09-25T03:33:01.604338Z","caller":"traceutil/trace.go:171","msg":"trace[1783999402] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5944; }","duration":"163.671238ms","start":"2026-09-25T03:33:01.440654Z","end":"2026-09-25T03:33:01.604325Z","steps":["trace[1783999402] 'agreement among raft nodes before linearized reading' (duration: 163.567646ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T03:33:01.604321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.47024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-09-25T03:33:01.604415Z","caller":"traceutil/trace.go:171","msg":"trace[1264808912] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5944; }","duration":"183.573923ms","start":"2026-09-25T03:33:01.42082Z","end":"2026-09-25T03:33:01.604394Z","steps":["trace[1264808912] 'agreement among raft nodes before linearized reading' (duration: 183.354238ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T03:33:01.604353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.533582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:13290"} {"level":"info","ts":"2026-09-25T03:33:01.604503Z","caller":"traceutil/trace.go:171","msg":"trace[325964686] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5944; }","duration":"240.737677ms","start":"2026-09-25T03:33:01.363752Z","end":"2026-09-25T03:33:01.604489Z","steps":["trace[325964686] 'agreement among raft nodes before linearized reading' (duration: 240.41831ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T03:33:01.604823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.041995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-25T03:33:01.604935Z","caller":"traceutil/trace.go:171","msg":"trace[601185701] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:5944; }","duration":"150.73552ms","start":"2026-09-25T03:33:01.454179Z","end":"2026-09-25T03:33:01.604914Z","steps":["trace[601185701] 'agreement among raft nodes before linearized reading' (duration: 149.948683ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T03:37:39.398391Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5496} {"level":"info","ts":"2026-09-25T03:37:39.493491Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5496,"took":"92.928441ms","hash":3765759338,"current-db-size-bytes":54939648,"current-db-size":"55 MB","current-db-size-in-use-bytes":28012544,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-25T03:37:39.493591Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3765759338,"revision":5496,"compact-revision":2977} {"level":"info","ts":"2026-09-25T03:39:28.990561Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023c5.wal"} {"level":"info","ts":"2026-09-25T03:39:32.522835Z","caller":"traceutil/trace.go:171","msg":"trace[206348707] linearizableReadLoop","detail":"{readStateIndex:9175; appliedIndex:9174; }","duration":"156.310864ms","start":"2026-09-25T03:39:32.3665Z","end":"2026-09-25T03:39:32.522811Z","steps":["trace[206348707] 'read index received' (duration: 156.16048ms)","trace[206348707] 'applied index is now lower than readState.Index' (duration: 149.474µs)"],"step_count":2} {"level":"info","ts":"2026-09-25T03:39:32.522934Z","caller":"traceutil/trace.go:171","msg":"trace[402854086] transaction","detail":"{read_only:false; response_revision:8857; number_of_response:1; }","duration":"166.482861ms","start":"2026-09-25T03:39:32.356433Z","end":"2026-09-25T03:39:32.522915Z","steps":["trace[402854086] 'process raft request' (duration: 166.250206ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T03:39:32.52304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.521348ms","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-09-25T03:39:32.5231Z","caller":"traceutil/trace.go:171","msg":"trace[1753877130] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8857; }","duration":"156.624971ms","start":"2026-09-25T03:39:32.366465Z","end":"2026-09-25T03:39:32.523089Z","steps":["trace[1753877130] 'agreement among raft nodes before linearized reading' (duration: 156.471128ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T03:39:33.379574Z","caller":"traceutil/trace.go:171","msg":"trace[2077382933] transaction","detail":"{read_only:false; response_revision:8861; number_of_response:1; }","duration":"170.913781ms","start":"2026-09-25T03:39:33.20862Z","end":"2026-09-25T03:39:33.379533Z","steps":["trace[2077382933] 'process raft request' (duration: 170.598495ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T03:39:33.46815Z","caller":"traceutil/trace.go:171","msg":"trace[1949649664] transaction","detail":"{read_only:false; response_revision:8863; number_of_response:1; }","duration":"252.449289ms","start":"2026-09-25T03:39:33.215673Z","end":"2026-09-25T03:39:33.468122Z","steps":["trace[1949649664] 'process raft request' (duration: 252.362487ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T03:39:33.468676Z","caller":"traceutil/trace.go:171","msg":"trace[587006981] transaction","detail":"{read_only:false; response_revision:8862; number_of_response:1; }","duration":"255.135139ms","start":"2026-09-25T03:39:33.213473Z","end":"2026-09-25T03:39:33.468608Z","steps":["trace[587006981] 'process raft request' (duration: 221.298821ms)","trace[587006981] 'compare' (duration: 32.99921ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T03:40:03.906929Z","caller":"traceutil/trace.go:171","msg":"trace[420757313] transaction","detail":"{read_only:false; response_revision:9077; number_of_response:1; }","duration":"407.442162ms","start":"2026-09-25T03:40:03.499458Z","end":"2026-09-25T03:40:03.906901Z","steps":["trace[420757313] 'process raft request' (duration: 407.254648ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T03:40:03.907164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T03:40:03.499433Z","time spent":"407.593945ms","remote":"127.0.0.1:35188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-25T03:40:03.910875Z","caller":"traceutil/trace.go:171","msg":"trace[476960305] transaction","detail":"{read_only:false; response_revision:9078; number_of_response:1; }","duration":"332.158195ms","start":"2026-09-25T03:40:03.578694Z","end":"2026-09-25T03:40:03.910852Z","steps":["trace[476960305] 'process raft request' (duration: 332.010622ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T03:40:03.911034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T03:40:03.578665Z","time spent":"332.296588ms","remote":"127.0.0.1:35188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-25T03:40:03.911103Z","caller":"traceutil/trace.go:171","msg":"trace[1635133432] linearizableReadLoop","detail":"{readStateIndex:9407; appliedIndex:9404; }","duration":"289.968008ms","start":"2026-09-25T03:40:03.621105Z","end":"2026-09-25T03:40:03.911073Z","steps":["trace[1635133432] 'read index received' (duration: 285.595679ms)","trace[1635133432] 'applied index is now lower than readState.Index' (duration: 4.370989ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T03:40:03.91123Z","caller":"traceutil/trace.go:171","msg":"trace[975486503] transaction","detail":"{read_only:false; response_revision:9079; number_of_response:1; }","duration":"326.803433ms","start":"2026-09-25T03:40:03.584415Z","end":"2026-09-25T03:40:03.911218Z","steps":["trace[975486503] 'process raft request' (duration: 326.392054ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T03:40:03.911293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T03:40:03.5844Z","time spent":"326.861124ms","remote":"127.0.0.1:35188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-25T03:40:03.911431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.002746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-25T03:40:03.91147Z","caller":"traceutil/trace.go:171","msg":"trace[1366602583] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9079; }","duration":"118.094609ms","start":"2026-09-25T03:40:03.793364Z","end":"2026-09-25T03:40:03.911458Z","steps":["trace[1366602583] 'agreement among raft nodes before linearized reading' (duration: 117.910725ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T03:40:03.911479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.346426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-25T03:40:03.911578Z","caller":"traceutil/trace.go:171","msg":"trace[1077171053] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9079; }","duration":"290.48623ms","start":"2026-09-25T03:40:03.62107Z","end":"2026-09-25T03:40:03.911556Z","steps":["trace[1077171053] 'agreement among raft nodes before linearized reading' (duration: 290.211674ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T03:40:16.883952Z","caller":"traceutil/trace.go:171","msg":"trace[978947394] transaction","detail":"{read_only:false; response_revision:9156; number_of_response:1; }","duration":"203.707112ms","start":"2026-09-25T03:40:16.68021Z","end":"2026-09-25T03:40:16.883917Z","steps":["trace[978947394] 'process raft request' (duration: 203.466076ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T03:40:16.884193Z","caller":"traceutil/trace.go:171","msg":"trace[1354411843] linearizableReadLoop","detail":"{readStateIndex:9486; appliedIndex:9485; }","duration":"200.760214ms","start":"2026-09-25T03:40:16.683087Z","end":"2026-09-25T03:40:16.883847Z","steps":["trace[1354411843] 'read index received' (duration: 200.478748ms)","trace[1354411843] 'applied index is now lower than readState.Index' (duration: 280.306µs)"],"step_count":2} {"level":"warn","ts":"2026-09-25T03:40:16.884374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.106482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-g8655\" ","response":"range_response_count:1 size:2803"} {"level":"info","ts":"2026-09-25T03:40:16.884462Z","caller":"traceutil/trace.go:171","msg":"trace[1005975994] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-g8655; range_end:; response_count:1; response_revision:9156; }","duration":"201.387818ms","start":"2026-09-25T03:40:16.683053Z","end":"2026-09-25T03:40:16.884441Z","steps":["trace[1005975994] 'agreement among raft nodes before linearized reading' (duration: 200.984119ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T03:40:30.35845Z","caller":"traceutil/trace.go:171","msg":"trace[966169558] transaction","detail":"{read_only:false; response_revision:9294; number_of_response:1; }","duration":"107.271814ms","start":"2026-09-25T03:40:30.251138Z","end":"2026-09-25T03:40:30.358409Z","steps":["trace[966169558] 'process raft request' (duration: 107.183732ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T03:40:30.358476Z","caller":"traceutil/trace.go:171","msg":"trace[62016039] transaction","detail":"{read_only:false; response_revision:9293; number_of_response:1; }","duration":"107.349786ms","start":"2026-09-25T03:40:30.251098Z","end":"2026-09-25T03:40:30.358447Z","steps":["trace[62016039] 'process raft request' (duration: 107.0764ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T03:41:12.905503Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"94bfbb0e034a3ac2","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-25T03:41:12.91277Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-25T03:41:12.912831Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-09-25T03:42:39.405917Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7983} {"level":"info","ts":"2026-09-25T03:42:39.504928Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7983,"took":"96.960201ms","hash":3319589953,"current-db-size-bytes":54939648,"current-db-size":"55 MB","current-db-size-in-use-bytes":25694208,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-25T03:42:39.505143Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3319589953,"revision":7983,"compact-revision":5496} {"level":"info","ts":"2026-09-25T03:47:09.887043Z","caller":"traceutil/trace.go:171","msg":"trace[575856497] transaction","detail":"{read_only:false; response_revision:12349; number_of_response:1; }","duration":"154.178916ms","start":"2026-09-25T03:47:09.732839Z","end":"2026-09-25T03:47:09.887018Z","steps":["trace[575856497] 'process raft request' (duration: 154.011252ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T03:47:31.405413Z","caller":"traceutil/trace.go:171","msg":"trace[1755548512] transaction","detail":"{read_only:false; response_revision:12470; number_of_response:1; }","duration":"108.077176ms","start":"2026-09-25T03:47:31.297297Z","end":"2026-09-25T03:47:31.405374Z","steps":["trace[1755548512] 'process raft request' (duration: 75.110198ms)","trace[1755548512] 'compare' (duration: 32.825725ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T03:47:39.414547Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10395} {"level":"info","ts":"2026-09-25T03:47:39.497619Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10395,"took":"81.448513ms","hash":1944842936,"current-db-size-bytes":54939648,"current-db-size":"55 MB","current-db-size-in-use-bytes":25939968,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-25T03:47:39.497681Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1944842936,"revision":10395,"compact-revision":7983} {"level":"info","ts":"2026-09-25T03:52:39.420877Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12534} {"level":"info","ts":"2026-09-25T03:52:39.499844Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12534,"took":"76.426271ms","hash":1065176772,"current-db-size-bytes":54939648,"current-db-size":"55 MB","current-db-size-in-use-bytes":28000256,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-25T03:52:39.499927Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1065176772,"revision":12534,"compact-revision":10395} {"level":"info","ts":"2026-09-25T03:54:10.889381Z","caller":"traceutil/trace.go:171","msg":"trace[273868724] transaction","detail":"{read_only:false; response_revision:15138; number_of_response:1; }","duration":"105.223304ms","start":"2026-09-25T03:54:10.784128Z","end":"2026-09-25T03:54:10.889351Z","steps":["trace[273868724] 'process raft request' (duration: 105.06307ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T03:54:11.693483Z","caller":"traceutil/trace.go:171","msg":"trace[813428918] transaction","detail":"{read_only:false; response_revision:15142; number_of_response:1; }","duration":"225.439662ms","start":"2026-09-25T03:54:11.468014Z","end":"2026-09-25T03:54:11.693454Z","steps":["trace[813428918] 'process raft request' (duration: 225.196617ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T03:54:11.694241Z","caller":"traceutil/trace.go:171","msg":"trace[205153879] transaction","detail":"{read_only:false; response_revision:15143; number_of_response:1; }","duration":"222.001715ms","start":"2026-09-25T03:54:11.471727Z","end":"2026-09-25T03:54:11.693728Z","steps":["trace[205153879] 'process raft request' (duration: 221.688078ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T03:54:34.490441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.811569ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-25T03:54:34.490699Z","caller":"traceutil/trace.go:171","msg":"trace[872424609] transaction","detail":"{read_only:false; response_revision:15455; number_of_response:1; }","duration":"419.856512ms","start":"2026-09-25T03:54:34.07082Z","end":"2026-09-25T03:54:34.490676Z","steps":["trace[872424609] 'process raft request' (duration: 253.695615ms)","trace[872424609] 'compare' (duration: 165.601685ms)"],"step_count":2} {"level":"warn","ts":"2026-09-25T03:54:34.490762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T03:54:34.070793Z","time spent":"419.939874ms","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3739,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-25T03:54:34.490759Z","caller":"traceutil/trace.go:171","msg":"trace[49301880] transaction","detail":"{read_only:false; response_revision:15456; number_of_response:1; }","duration":"268.739801ms","start":"2026-09-25T03:54:34.222002Z","end":"2026-09-25T03:54:34.490742Z","steps":["trace[49301880] 'process raft request' (duration: 268.633939ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T03:54:34.491069Z","caller":"traceutil/trace.go:171","msg":"trace[1333538155] linearizableReadLoop","detail":"{readStateIndex:16003; appliedIndex:16002; }","duration":"376.257676ms","start":"2026-09-25T03:54:34.11471Z","end":"2026-09-25T03:54:34.490968Z","steps":["trace[1333538155] 'read index received' (duration: 209.846404ms)","trace[1333538155] 'applied index is now lower than readState.Index' (duration: 166.408072ms)"],"step_count":2} {"level":"warn","ts":"2026-09-25T03:54:34.49118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.470141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-25T03:54:34.491234Z","caller":"traceutil/trace.go:171","msg":"trace[117433911] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15456; }","duration":"376.572504ms","start":"2026-09-25T03:54:34.114644Z","end":"2026-09-25T03:54:34.491216Z","steps":["trace[117433911] 'agreement among raft nodes before linearized reading' (duration: 376.482372ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T03:54:34.491306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T03:54:34.114617Z","time spent":"376.678696ms","remote":"127.0.0.1:34914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-25T03:54:34.4916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.493955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:356"} {"level":"info","ts":"2026-09-25T03:54:34.49165Z","caller":"traceutil/trace.go:171","msg":"trace[679029499] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:15456; }","duration":"356.622877ms","start":"2026-09-25T03:54:34.135014Z","end":"2026-09-25T03:54:34.491637Z","steps":["trace[679029499] 'agreement among raft nodes before linearized reading' (duration: 356.232249ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T03:54:34.491685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T03:54:34.134993Z","time spent":"356.683139ms","remote":"127.0.0.1:35018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":379,"request content":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" "} {"level":"info","ts":"2026-09-25T03:55:46.979474Z","caller":"traceutil/trace.go:171","msg":"trace[991176969] linearizableReadLoop","detail":"{readStateIndex:16766; appliedIndex:16765; }","duration":"143.882413ms","start":"2026-09-25T03:55:46.835561Z","end":"2026-09-25T03:55:46.979443Z","steps":["trace[991176969] 'read index received' (duration: 143.720119ms)","trace[991176969] 'applied index is now lower than readState.Index' (duration: 160.984µs)"],"step_count":2} {"level":"info","ts":"2026-09-25T03:55:46.979535Z","caller":"traceutil/trace.go:171","msg":"trace[821482756] transaction","detail":"{read_only:false; response_revision:16196; number_of_response:1; }","duration":"151.149396ms","start":"2026-09-25T03:55:46.828339Z","end":"2026-09-25T03:55:46.979489Z","steps":["trace[821482756] 'process raft request' (duration: 150.916801ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T03:55:46.979697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.065215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-25T03:55:46.979735Z","caller":"traceutil/trace.go:171","msg":"trace[128847862] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:16196; }","duration":"143.174207ms","start":"2026-09-25T03:55:46.836551Z","end":"2026-09-25T03:55:46.979725Z","steps":["trace[128847862] 'agreement among raft nodes before linearized reading' (duration: 143.077075ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T03:55:46.979881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.964406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"warn","ts":"2026-09-25T03:55:46.97989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.926902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-25T03:55:46.979995Z","caller":"traceutil/trace.go:171","msg":"trace[579056737] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16196; }","duration":"128.105159ms","start":"2026-09-25T03:55:46.851849Z","end":"2026-09-25T03:55:46.979954Z","steps":["trace[579056737] 'agreement among raft nodes before linearized reading' (duration: 127.870054ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T03:55:46.980032Z","caller":"traceutil/trace.go:171","msg":"trace[1023915541] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:16196; }","duration":"143.109606ms","start":"2026-09-25T03:55:46.836891Z","end":"2026-09-25T03:55:46.980001Z","steps":["trace[1023915541] 'agreement among raft nodes before linearized reading' (duration: 142.871641ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T03:55:46.98032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.730212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-25T03:55:46.980393Z","caller":"traceutil/trace.go:171","msg":"trace[598681760] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16196; }","duration":"144.879215ms","start":"2026-09-25T03:55:46.835496Z","end":"2026-09-25T03:55:46.980375Z","steps":["trace[598681760] 'agreement among raft nodes before linearized reading' (duration: 144.106208ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T03:57:39.428239Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14572} {"level":"info","ts":"2026-09-25T03:57:39.508544Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14572,"took":"77.429989ms","hash":1013011532,"current-db-size-bytes":54939648,"current-db-size":"55 MB","current-db-size-in-use-bytes":30855168,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-25T03:57:39.508621Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1013011532,"revision":14572,"compact-revision":12534} {"level":"warn","ts":"2026-09-25T04:01:57.109953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.062918ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-25T04:01:57.110107Z","caller":"traceutil/trace.go:171","msg":"trace[2135632940] transaction","detail":"{read_only:false; response_revision:18938; number_of_response:1; }","duration":"162.03395ms","start":"2026-09-25T04:01:56.948041Z","end":"2026-09-25T04:01:57.110075Z","steps":["trace[2135632940] 'process raft request' (duration: 58.757497ms)","trace[2135632940] 'compare' (duration: 102.866454ms)"],"step_count":2} {"level":"warn","ts":"2026-09-25T04:01:57.23975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.454638ms","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-09-25T04:01:57.239875Z","caller":"traceutil/trace.go:171","msg":"trace[147055190] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18940; }","duration":"123.609542ms","start":"2026-09-25T04:01:57.116228Z","end":"2026-09-25T04:01:57.239837Z","steps":["trace[147055190] 'agreement among raft nodes before linearized reading' (duration: 123.220903ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:01:57.239894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.053696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-svtfk\" ","response":"range_response_count:1 size:20763"} {"level":"info","ts":"2026-09-25T04:01:57.240033Z","caller":"traceutil/trace.go:171","msg":"trace[697025992] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-svtfk; range_end:; response_count:1; response_revision:18940; }","duration":"118.20986ms","start":"2026-09-25T04:01:57.121785Z","end":"2026-09-25T04:01:57.239994Z","steps":["trace[697025992] 'agreement among raft nodes before linearized reading' (duration: 117.808931ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:01:57.240205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.914795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-25T04:01:57.240238Z","caller":"traceutil/trace.go:171","msg":"trace[1841116200] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18940; }","duration":"118.986777ms","start":"2026-09-25T04:01:57.121241Z","end":"2026-09-25T04:01:57.240227Z","steps":["trace[1841116200] 'agreement among raft nodes before linearized reading' (duration: 118.880454ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:01:57.240312Z","caller":"traceutil/trace.go:171","msg":"trace[1814836224] transaction","detail":"{read_only:false; response_revision:18940; number_of_response:1; }","duration":"124.302357ms","start":"2026-09-25T04:01:57.115993Z","end":"2026-09-25T04:01:57.240295Z","steps":["trace[1814836224] 'process raft request' (duration: 97.975813ms)","trace[1814836224] 'compare' (duration: 24.944974ms)"],"step_count":2} {"level":"warn","ts":"2026-09-25T04:01:57.567873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.523767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:96 size:962464"} {"level":"info","ts":"2026-09-25T04:01:57.567989Z","caller":"traceutil/trace.go:171","msg":"trace[560286916] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:96; response_revision:18943; }","duration":"226.68613ms","start":"2026-09-25T04:01:57.34128Z","end":"2026-09-25T04:01:57.567966Z","steps":["trace[560286916] 'range keys from in-memory index tree' (duration: 224.768768ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:02:37.154266Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"94bfbb0e034a3ac2","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-25T04:02:37.161678Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-09-25T04:02:37.16177Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-09-25T04:02:39.436042Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16902} {"level":"info","ts":"2026-09-25T04:02:39.536319Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16902,"took":"96.788556ms","hash":2122765704,"current-db-size-bytes":54939648,"current-db-size":"55 MB","current-db-size-in-use-bytes":33624064,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-09-25T04:02:39.536393Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2122765704,"revision":16902,"compact-revision":14572} {"level":"info","ts":"2026-09-25T04:07:39.443628Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19487} {"level":"info","ts":"2026-09-25T04:07:39.56123Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19487,"took":"113.817509ms","hash":176488188,"current-db-size-bytes":54939648,"current-db-size":"55 MB","current-db-size-in-use-bytes":29810688,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-25T04:07:39.561302Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":176488188,"revision":19487,"compact-revision":16902} {"level":"info","ts":"2026-09-25T04:08:34.380245Z","caller":"traceutil/trace.go:171","msg":"trace[1406326532] transaction","detail":"{read_only:false; response_revision:21940; number_of_response:1; }","duration":"108.643523ms","start":"2026-09-25T04:08:34.271569Z","end":"2026-09-25T04:08:34.380213Z","steps":["trace[1406326532] 'process raft request' (duration: 108.453568ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:08:34.645048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.950772ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-25T04:08:34.64521Z","caller":"traceutil/trace.go:171","msg":"trace[394762182] transaction","detail":"{read_only:false; response_revision:21941; number_of_response:1; }","duration":"330.240206ms","start":"2026-09-25T04:08:34.314951Z","end":"2026-09-25T04:08:34.645191Z","steps":["trace[394762182] 'process raft request' (duration: 204.044528ms)","trace[394762182] 'compare' (duration: 125.737938ms)"],"step_count":2} {"level":"warn","ts":"2026-09-25T04:08:34.645303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:08:34.314926Z","time spent":"330.343788ms","remote":"127.0.0.1:35188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-25T04:08:35.21937Z","caller":"traceutil/trace.go:171","msg":"trace[1740742044] linearizableReadLoop","detail":"{readStateIndex:22706; appliedIndex:22705; }","duration":"106.561635ms","start":"2026-09-25T04:08:35.112786Z","end":"2026-09-25T04:08:35.219348Z","steps":["trace[1740742044] 'read index received' (duration: 106.439613ms)","trace[1740742044] 'applied index is now lower than readState.Index' (duration: 120.992µs)"],"step_count":2} {"level":"warn","ts":"2026-09-25T04:08:35.219523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.71882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-25T04:08:35.219557Z","caller":"traceutil/trace.go:171","msg":"trace[968509020] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21943; }","duration":"106.804641ms","start":"2026-09-25T04:08:35.112741Z","end":"2026-09-25T04:08:35.219546Z","steps":["trace[968509020] 'agreement among raft nodes before linearized reading' (duration: 106.734749ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:08:35.21951Z","caller":"traceutil/trace.go:171","msg":"trace[1741711833] transaction","detail":"{read_only:false; response_revision:21943; number_of_response:1; }","duration":"109.620384ms","start":"2026-09-25T04:08:35.109855Z","end":"2026-09-25T04:08:35.219475Z","steps":["trace[1741711833] 'process raft request' (duration: 109.349778ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:12:37.293204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.16237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-25T04:12:37.293334Z","caller":"traceutil/trace.go:171","msg":"trace[484649633] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24456; }","duration":"180.342085ms","start":"2026-09-25T04:12:37.112962Z","end":"2026-09-25T04:12:37.293304Z","steps":["trace[484649633] 'range keys from in-memory index tree' (duration: 180.090109ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:12:39.451734Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21654} {"level":"info","ts":"2026-09-25T04:12:39.564935Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21654,"took":"109.205215ms","hash":1448313743,"current-db-size-bytes":56475648,"current-db-size":"56 MB","current-db-size-in-use-bytes":41992192,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-09-25T04:12:39.565012Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1448313743,"revision":21654,"compact-revision":19487} {"level":"info","ts":"2026-09-25T04:14:06.663627Z","caller":"traceutil/trace.go:171","msg":"trace[192609050] linearizableReadLoop","detail":"{readStateIndex:26254; appliedIndex:26253; }","duration":"140.410705ms","start":"2026-09-25T04:14:06.523186Z","end":"2026-09-25T04:14:06.663597Z","steps":["trace[192609050] 'read index received' (duration: 116.227481ms)","trace[192609050] 'applied index is now lower than readState.Index' (duration: 24.182094ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T04:14:06.663972Z","caller":"traceutil/trace.go:171","msg":"trace[18775312] transaction","detail":"{read_only:false; response_revision:25395; number_of_response:1; }","duration":"142.955093ms","start":"2026-09-25T04:14:06.520996Z","end":"2026-09-25T04:14:06.663951Z","steps":["trace[18775312] 'process raft request' (duration: 118.479553ms)","trace[18775312] 'compare' (duration: 24.02218ms)"],"step_count":2} {"level":"warn","ts":"2026-09-25T04:14:06.6642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.684364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-25T04:14:06.664218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.674241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-25T04:14:06.664236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.02296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-25T04:14:06.664273Z","caller":"traceutil/trace.go:171","msg":"trace[2107079795] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-user; range_end:; response_count:0; response_revision:25395; }","duration":"137.788027ms","start":"2026-09-25T04:14:06.526466Z","end":"2026-09-25T04:14:06.664254Z","steps":["trace[2107079795] 'agreement among raft nodes before linearized reading' (duration: 137.652354ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:14:06.664261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.61105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-25T04:14:06.664305Z","caller":"traceutil/trace.go:171","msg":"trace[43598297] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-endpoints; range_end:; response_count:0; response_revision:25395; }","duration":"140.800204ms","start":"2026-09-25T04:14:06.523484Z","end":"2026-09-25T04:14:06.664284Z","steps":["trace[43598297] 'agreement among raft nodes before linearized reading' (duration: 140.61204ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:14:06.664334Z","caller":"traceutil/trace.go:171","msg":"trace[1806936848] range","detail":"{range_begin:/registry/jobs/openstack/magnum-domain-ks-user; range_end:; response_count:0; response_revision:25395; }","duration":"140.689412ms","start":"2026-09-25T04:14:06.523625Z","end":"2026-09-25T04:14:06.664314Z","steps":["trace[1806936848] 'agreement among raft nodes before linearized reading' (duration: 140.559259ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:14:06.66435Z","caller":"traceutil/trace.go:171","msg":"trace[1116258886] range","detail":"{range_begin:/registry/jobs/openstack/magnum-rabbit-init; range_end:; response_count:0; response_revision:25395; }","duration":"141.137882ms","start":"2026-09-25T04:14:06.52316Z","end":"2026-09-25T04:14:06.664298Z","steps":["trace[1116258886] 'agreement among raft nodes before linearized reading' (duration: 140.948998ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:14:06.664424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.477852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-db-sync\" ","response":"range_response_count:1 size:5082"} {"level":"info","ts":"2026-09-25T04:14:06.664487Z","caller":"traceutil/trace.go:171","msg":"trace[1945462421] range","detail":"{range_begin:/registry/jobs/openstack/magnum-db-sync; range_end:; response_count:1; response_revision:25395; }","duration":"130.598465ms","start":"2026-09-25T04:14:06.533872Z","end":"2026-09-25T04:14:06.664471Z","steps":["trace[1945462421] 'agreement among raft nodes before linearized reading' (duration: 130.234136ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:15:29.096217Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000069a5.wal"} {"level":"info","ts":"2026-09-25T04:15:34.958768Z","caller":"traceutil/trace.go:171","msg":"trace[994979181] transaction","detail":"{read_only:false; response_revision:26218; number_of_response:1; }","duration":"120.801516ms","start":"2026-09-25T04:15:34.837938Z","end":"2026-09-25T04:15:34.95874Z","steps":["trace[994979181] 'process raft request' (duration: 33.390321ms)","trace[994979181] 'compare' (duration: 87.237671ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T04:15:58.149247Z","caller":"traceutil/trace.go:171","msg":"trace[1725669862] transaction","detail":"{read_only:false; response_revision:26508; number_of_response:1; }","duration":"111.939916ms","start":"2026-09-25T04:15:58.037284Z","end":"2026-09-25T04:15:58.149224Z","steps":["trace[1725669862] 'process raft request' (duration: 111.773892ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:15:59.785984Z","caller":"traceutil/trace.go:171","msg":"trace[1658140567] linearizableReadLoop","detail":"{readStateIndex:27408; appliedIndex:27407; }","duration":"107.989167ms","start":"2026-09-25T04:15:59.677965Z","end":"2026-09-25T04:15:59.785954Z","steps":["trace[1658140567] 'read index received' (duration: 107.593668ms)","trace[1658140567] 'applied index is now lower than readState.Index' (duration: 393.719µs)"],"step_count":2} {"level":"info","ts":"2026-09-25T04:15:59.786193Z","caller":"traceutil/trace.go:171","msg":"trace[1623994543] transaction","detail":"{read_only:false; response_revision:26518; number_of_response:1; }","duration":"120.509808ms","start":"2026-09-25T04:15:59.665632Z","end":"2026-09-25T04:15:59.786141Z","steps":["trace[1623994543] 'process raft request' (duration: 119.908615ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:15:59.786241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.260683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-09-25T04:15:59.786281Z","caller":"traceutil/trace.go:171","msg":"trace[112063420] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:26518; }","duration":"108.394846ms","start":"2026-09-25T04:15:59.677875Z","end":"2026-09-25T04:15:59.78627Z","steps":["trace[112063420] 'agreement among raft nodes before linearized reading' (duration: 108.184241ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:05.004873Z","caller":"traceutil/trace.go:171","msg":"trace[935119666] linearizableReadLoop","detail":"{readStateIndex:27453; appliedIndex:27452; }","duration":"161.641283ms","start":"2026-09-25T04:16:04.843212Z","end":"2026-09-25T04:16:05.004853Z","steps":["trace[935119666] 'read index received' (duration: 161.540451ms)","trace[935119666] 'applied index is now lower than readState.Index' (duration: 99.922µs)"],"step_count":2} {"level":"info","ts":"2026-09-25T04:16:05.004949Z","caller":"traceutil/trace.go:171","msg":"trace[212957317] transaction","detail":"{read_only:false; response_revision:26562; number_of_response:1; }","duration":"212.481906ms","start":"2026-09-25T04:16:04.792435Z","end":"2026-09-25T04:16:05.004917Z","steps":["trace[212957317] 'process raft request' (duration: 212.300772ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:05.005031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.804127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-09-25T04:16:05.005055Z","caller":"traceutil/trace.go:171","msg":"trace[880071382] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26562; }","duration":"161.882258ms","start":"2026-09-25T04:16:04.843165Z","end":"2026-09-25T04:16:05.005047Z","steps":["trace[880071382] 'agreement among raft nodes before linearized reading' (duration: 161.774966ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:05.464739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.835917ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-25T04:16:05.46486Z","caller":"traceutil/trace.go:171","msg":"trace[1485150021] linearizableReadLoop","detail":"{readStateIndex:27457; appliedIndex:27456; }","duration":"142.746958ms","start":"2026-09-25T04:16:05.322093Z","end":"2026-09-25T04:16:05.46484Z","steps":["trace[1485150021] 'read index received' (duration: 20.640424ms)","trace[1485150021] 'applied index is now lower than readState.Index' (duration: 122.104954ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T04:16:05.46491Z","caller":"traceutil/trace.go:171","msg":"trace[1172135724] transaction","detail":"{read_only:false; response_revision:26566; number_of_response:1; }","duration":"142.974303ms","start":"2026-09-25T04:16:05.321897Z","end":"2026-09-25T04:16:05.464871Z","steps":["trace[1172135724] 'process raft request' (duration: 20.90786ms)","trace[1172135724] 'compare' (duration: 121.692444ms)"],"step_count":2} {"level":"warn","ts":"2026-09-25T04:16:05.464995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.905961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-25T04:16:05.465041Z","caller":"traceutil/trace.go:171","msg":"trace[1020368017] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:26566; }","duration":"142.984973ms","start":"2026-09-25T04:16:05.322043Z","end":"2026-09-25T04:16:05.465028Z","steps":["trace[1020368017] 'agreement among raft nodes before linearized reading' (duration: 142.900271ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:05.465407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.157597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-09-25T04:16:05.465461Z","caller":"traceutil/trace.go:171","msg":"trace[219227393] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26566; }","duration":"135.231768ms","start":"2026-09-25T04:16:05.330216Z","end":"2026-09-25T04:16:05.465448Z","steps":["trace[219227393] 'agreement among raft nodes before linearized reading' (duration: 135.071905ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:05.624827Z","caller":"traceutil/trace.go:171","msg":"trace[612990239] transaction","detail":"{read_only:false; response_revision:26567; number_of_response:1; }","duration":"155.052995ms","start":"2026-09-25T04:16:05.46973Z","end":"2026-09-25T04:16:05.624783Z","steps":["trace[612990239] 'process raft request' (duration: 154.82362ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:10.048491Z","caller":"traceutil/trace.go:171","msg":"trace[1051675217] transaction","detail":"{read_only:false; response_revision:26596; number_of_response:1; }","duration":"157.878358ms","start":"2026-09-25T04:16:09.890595Z","end":"2026-09-25T04:16:10.048474Z","steps":["trace[1051675217] 'process raft request' (duration: 157.763406ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:10.048495Z","caller":"traceutil/trace.go:171","msg":"trace[1222398691] linearizableReadLoop","detail":"{readStateIndex:27488; appliedIndex:27487; }","duration":"155.091855ms","start":"2026-09-25T04:16:09.893378Z","end":"2026-09-25T04:16:10.04847Z","steps":["trace[1222398691] 'read index received' (duration: 155.080665ms)","trace[1222398691] 'applied index is now lower than readState.Index' (duration: 9.71µs)"],"step_count":2} {"level":"warn","ts":"2026-09-25T04:16:10.048692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.296231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-1e3c6ebc-3dbe-4423-ac0a-a289ff7755b4\" ","response":"range_response_count:1 size:1319"} {"level":"info","ts":"2026-09-25T04:16:10.048745Z","caller":"traceutil/trace.go:171","msg":"trace[218427837] range","detail":"{range_begin:/registry/persistentvolumes/pvc-1e3c6ebc-3dbe-4423-ac0a-a289ff7755b4; range_end:; response_count:1; response_revision:26596; }","duration":"155.401423ms","start":"2026-09-25T04:16:09.893329Z","end":"2026-09-25T04:16:10.048731Z","steps":["trace[218427837] 'agreement among raft nodes before linearized reading' (duration: 155.196968ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:10.252356Z","caller":"traceutil/trace.go:171","msg":"trace[61619481] linearizableReadLoop","detail":"{readStateIndex:27489; appliedIndex:27488; }","duration":"203.669037ms","start":"2026-09-25T04:16:10.048656Z","end":"2026-09-25T04:16:10.252325Z","steps":["trace[61619481] 'read index received' (duration: 154.151155ms)","trace[61619481] 'applied index is now lower than readState.Index' (duration: 49.515922ms)"],"step_count":2} {"level":"warn","ts":"2026-09-25T04:16:10.252515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.322631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-09-25T04:16:10.252566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.316143ms","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-09-25T04:16:10.252585Z","caller":"traceutil/trace.go:171","msg":"trace[1269773969] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26597; }","duration":"321.419403ms","start":"2026-09-25T04:16:09.931153Z","end":"2026-09-25T04:16:10.252572Z","steps":["trace[1269773969] 'agreement among raft nodes before linearized reading' (duration: 321.207088ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:10.252584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.265429ms","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-09-25T04:16:10.252608Z","caller":"traceutil/trace.go:171","msg":"trace[760867134] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26597; }","duration":"354.376034ms","start":"2026-09-25T04:16:09.898218Z","end":"2026-09-25T04:16:10.252594Z","steps":["trace[760867134] 'agreement among raft nodes before linearized reading' (duration: 354.215071ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:10.252616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:09.931147Z","time spent":"321.463945ms","remote":"127.0.0.1:35188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-09-25T04:16:10.252629Z","caller":"traceutil/trace.go:171","msg":"trace[117882354] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26597; }","duration":"341.327461ms","start":"2026-09-25T04:16:09.911288Z","end":"2026-09-25T04:16:10.252616Z","steps":["trace[117882354] 'agreement among raft nodes before linearized reading' (duration: 341.107246ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:10.252649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:09.898198Z","time spent":"354.441536ms","remote":"127.0.0.1:35188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-25T04:16:10.25266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:09.911282Z","time spent":"341.371052ms","remote":"127.0.0.1:35188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-09-25T04:16:10.252704Z","caller":"traceutil/trace.go:171","msg":"trace[515101143] transaction","detail":"{read_only:false; response_revision:26597; number_of_response:1; }","duration":"240.351331ms","start":"2026-09-25T04:16:10.012276Z","end":"2026-09-25T04:16:10.252627Z","steps":["trace[515101143] 'process raft request' (duration: 190.631344ms)","trace[515101143] 'compare' (duration: 49.205206ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T04:16:10.450367Z","caller":"traceutil/trace.go:171","msg":"trace[17976904] transaction","detail":"{read_only:false; response_revision:26602; number_of_response:1; }","duration":"186.979283ms","start":"2026-09-25T04:16:10.263365Z","end":"2026-09-25T04:16:10.450344Z","steps":["trace[17976904] 'process raft request' (duration: 186.937552ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:10.450792Z","caller":"traceutil/trace.go:171","msg":"trace[569764478] transaction","detail":"{read_only:false; response_revision:26599; number_of_response:1; }","duration":"194.263056ms","start":"2026-09-25T04:16:10.256518Z","end":"2026-09-25T04:16:10.450781Z","steps":["trace[569764478] 'process raft request' (duration: 193.516069ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:10.450926Z","caller":"traceutil/trace.go:171","msg":"trace[1967061965] transaction","detail":"{read_only:false; response_revision:26600; number_of_response:1; }","duration":"194.308237ms","start":"2026-09-25T04:16:10.256607Z","end":"2026-09-25T04:16:10.450916Z","steps":["trace[1967061965] 'process raft request' (duration: 193.617281ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:10.451073Z","caller":"traceutil/trace.go:171","msg":"trace[172786496] transaction","detail":"{read_only:false; response_revision:26601; number_of_response:1; }","duration":"193.56065ms","start":"2026-09-25T04:16:10.257498Z","end":"2026-09-25T04:16:10.451059Z","steps":["trace[172786496] 'process raft request' (duration: 192.772052ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:10.451233Z","caller":"traceutil/trace.go:171","msg":"trace[1880968778] linearizableReadLoop","detail":"{readStateIndex:27494; appliedIndex:27494; }","duration":"116.839865ms","start":"2026-09-25T04:16:10.334384Z","end":"2026-09-25T04:16:10.451223Z","steps":["trace[1880968778] 'read index received' (duration: 116.836165ms)","trace[1880968778] 'applied index is now lower than readState.Index' (duration: 3.13µs)"],"step_count":2} {"level":"warn","ts":"2026-09-25T04:16:10.451318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.927457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-25T04:16:10.45138Z","caller":"traceutil/trace.go:171","msg":"trace[1230170479] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26602; }","duration":"117.04093ms","start":"2026-09-25T04:16:10.334328Z","end":"2026-09-25T04:16:10.451369Z","steps":["trace[1230170479] 'agreement among raft nodes before linearized reading' (duration: 116.936667ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:10.625757Z","caller":"traceutil/trace.go:171","msg":"trace[44509391] transaction","detail":"{read_only:false; response_revision:26603; number_of_response:1; }","duration":"285.089717ms","start":"2026-09-25T04:16:10.340641Z","end":"2026-09-25T04:16:10.62573Z","steps":["trace[44509391] 'process raft request' (duration: 185.067369ms)","trace[44509391] 'compare' (duration: 99.69462ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T04:16:10.625832Z","caller":"traceutil/trace.go:171","msg":"trace[1740823591] transaction","detail":"{read_only:false; response_revision:26604; number_of_response:1; }","duration":"111.272531ms","start":"2026-09-25T04:16:10.514537Z","end":"2026-09-25T04:16:10.625809Z","steps":["trace[1740823591] 'process raft request' (duration: 111.090466ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:10.882932Z","caller":"traceutil/trace.go:171","msg":"trace[329536611] transaction","detail":"{read_only:false; response_revision:26605; number_of_response:1; }","duration":"252.536276ms","start":"2026-09-25T04:16:10.630373Z","end":"2026-09-25T04:16:10.882909Z","steps":["trace[329536611] 'process raft request' (duration: 252.401353ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:11.338803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.94568ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-25T04:16:11.338995Z","caller":"traceutil/trace.go:171","msg":"trace[1636725693] transaction","detail":"{read_only:false; response_revision:26608; number_of_response:1; }","duration":"109.564462ms","start":"2026-09-25T04:16:11.229414Z","end":"2026-09-25T04:16:11.338979Z","steps":["trace[1636725693] 'process raft request' (duration: 109.48301ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:11.339114Z","caller":"traceutil/trace.go:171","msg":"trace[792994375] transaction","detail":"{read_only:false; response_revision:26607; number_of_response:1; }","duration":"235.250427ms","start":"2026-09-25T04:16:11.103769Z","end":"2026-09-25T04:16:11.339019Z","steps":["trace[792994375] 'process raft request' (duration: 97.00675ms)","trace[792994375] 'compare' (duration: 137.737736ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T04:16:11.339208Z","caller":"traceutil/trace.go:171","msg":"trace[1207555409] linearizableReadLoop","detail":"{readStateIndex:27499; appliedIndex:27498; }","duration":"233.587909ms","start":"2026-09-25T04:16:11.105585Z","end":"2026-09-25T04:16:11.339173Z","steps":["trace[1207555409] 'read index received' (duration: 95.23194ms)","trace[1207555409] 'applied index is now lower than readState.Index' (duration: 138.354809ms)"],"step_count":2} {"level":"warn","ts":"2026-09-25T04:16:11.339312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.606833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-25T04:16:11.339343Z","caller":"traceutil/trace.go:171","msg":"trace[1759858218] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26608; }","duration":"226.660394ms","start":"2026-09-25T04:16:11.112674Z","end":"2026-09-25T04:16:11.339335Z","steps":["trace[1759858218] 'agreement among raft nodes before linearized reading' (duration: 226.600902ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:11.339378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.772003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-09-25T04:16:11.339455Z","caller":"traceutil/trace.go:171","msg":"trace[1497317040] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26608; }","duration":"233.841745ms","start":"2026-09-25T04:16:11.105583Z","end":"2026-09-25T04:16:11.339425Z","steps":["trace[1497317040] 'agreement among raft nodes before linearized reading' (duration: 233.670751ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:11.63644Z","caller":"traceutil/trace.go:171","msg":"trace[368020617] transaction","detail":"{read_only:false; response_revision:26609; number_of_response:1; }","duration":"148.005957ms","start":"2026-09-25T04:16:11.488417Z","end":"2026-09-25T04:16:11.636423Z","steps":["trace[368020617] 'process raft request' (duration: 147.889144ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:12.158205Z","caller":"traceutil/trace.go:171","msg":"trace[980976259] transaction","detail":"{read_only:false; response_revision:26610; number_of_response:1; }","duration":"501.647916ms","start":"2026-09-25T04:16:11.656509Z","end":"2026-09-25T04:16:12.158157Z","steps":["trace[980976259] 'process raft request' (duration: 501.512683ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:12.158333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:11.65649Z","time spent":"501.779188ms","remote":"127.0.0.1:35188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-25T04:16:12.397003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.137812ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-25T04:16:12.397281Z","caller":"traceutil/trace.go:171","msg":"trace[1314192795] transaction","detail":"{read_only:false; response_revision:26613; number_of_response:1; }","duration":"140.578449ms","start":"2026-09-25T04:16:12.256686Z","end":"2026-09-25T04:16:12.397264Z","steps":["trace[1314192795] 'process raft request' (duration: 140.530528ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:12.397705Z","caller":"traceutil/trace.go:171","msg":"trace[1020876350] transaction","detail":"{read_only:false; response_revision:26612; number_of_response:1; }","duration":"344.467881ms","start":"2026-09-25T04:16:12.053222Z","end":"2026-09-25T04:16:12.39769Z","steps":["trace[1020876350] 'process raft request' (duration: 343.872548ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:12.397763Z","caller":"traceutil/trace.go:171","msg":"trace[939316732] linearizableReadLoop","detail":"{readStateIndex:27504; appliedIndex:27501; }","duration":"286.873467ms","start":"2026-09-25T04:16:12.110833Z","end":"2026-09-25T04:16:12.397707Z","steps":["trace[939316732] 'read index received' (duration: 47.200291ms)","trace[939316732] 'applied index is now lower than readState.Index' (duration: 239.670886ms)"],"step_count":2} {"level":"warn","ts":"2026-09-25T04:16:12.397795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:12.053198Z","time spent":"344.555074ms","remote":"127.0.0.1:35188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-25T04:16:12.397915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.077111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/flavors/\" range_end:\"/registry/openstack.k-orc.cloud/flavors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-25T04:16:12.397949Z","caller":"traceutil/trace.go:171","msg":"trace[521405213] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/flavors/; range_end:/registry/openstack.k-orc.cloud/flavors0; response_count:0; response_revision:26613; }","duration":"287.110352ms","start":"2026-09-25T04:16:12.110827Z","end":"2026-09-25T04:16:12.397938Z","steps":["trace[521405213] 'agreement among raft nodes before linearized reading' (duration: 287.02925ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:12.398281Z","caller":"traceutil/trace.go:171","msg":"trace[185426877] transaction","detail":"{read_only:false; response_revision:26611; number_of_response:1; }","duration":"728.507323ms","start":"2026-09-25T04:16:11.66976Z","end":"2026-09-25T04:16:12.398268Z","steps":["trace[185426877] 'process raft request' (duration: 574.020931ms)","trace[185426877] 'compare' (duration: 152.732393ms)"],"step_count":2} {"level":"warn","ts":"2026-09-25T04:16:12.398361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:11.669736Z","time spent":"728.589405ms","remote":"127.0.0.1:35188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-25T04:16:12.658314Z","caller":"traceutil/trace.go:171","msg":"trace[421789335] transaction","detail":"{read_only:false; response_revision:26614; number_of_response:1; }","duration":"201.392827ms","start":"2026-09-25T04:16:12.456898Z","end":"2026-09-25T04:16:12.658291Z","steps":["trace[421789335] 'process raft request' (duration: 106.591606ms)","trace[421789335] 'compare' (duration: 94.536965ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T04:16:13.031665Z","caller":"traceutil/trace.go:171","msg":"trace[349555946] linearizableReadLoop","detail":"{readStateIndex:27509; appliedIndex:27505; }","duration":"524.544046ms","start":"2026-09-25T04:16:12.507102Z","end":"2026-09-25T04:16:13.031646Z","steps":["trace[349555946] 'read index received' (duration: 56.410957ms)","trace[349555946] 'applied index is now lower than readState.Index' (duration: 468.132019ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T04:16:13.031771Z","caller":"traceutil/trace.go:171","msg":"trace[945438295] transaction","detail":"{read_only:false; response_revision:26616; number_of_response:1; }","duration":"572.435363ms","start":"2026-09-25T04:16:12.459322Z","end":"2026-09-25T04:16:13.031757Z","steps":["trace[945438295] 'process raft request' (duration: 572.191977ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:13.031758Z","caller":"traceutil/trace.go:171","msg":"trace[619709613] transaction","detail":"{read_only:false; response_revision:26615; number_of_response:1; }","duration":"572.992235ms","start":"2026-09-25T04:16:12.458736Z","end":"2026-09-25T04:16:13.031728Z","steps":["trace[619709613] 'process raft request' (duration: 558.9326ms)","trace[619709613] 'compare' (duration: 13.658316ms)"],"step_count":2} {"level":"warn","ts":"2026-09-25T04:16:13.031875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:12.459312Z","time spent":"572.479384ms","remote":"127.0.0.1:35188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-25T04:16:13.031898Z","caller":"traceutil/trace.go:171","msg":"trace[1866074071] transaction","detail":"{read_only:false; response_revision:26617; number_of_response:1; }","duration":"572.374581ms","start":"2026-09-25T04:16:12.459494Z","end":"2026-09-25T04:16:13.031869Z","steps":["trace[1866074071] 'process raft request' (duration: 572.104375ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:13.032057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:12.459487Z","time spent":"572.495674ms","remote":"127.0.0.1:35188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-25T04:16:13.032293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.158671ms","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-09-25T04:16:13.032384Z","caller":"traceutil/trace.go:171","msg":"trace[739836996] 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:26617; }","duration":"525.277374ms","start":"2026-09-25T04:16:12.507084Z","end":"2026-09-25T04:16:13.032362Z","steps":["trace[739836996] 'agreement among raft nodes before linearized reading' (duration: 524.834993ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:13.032435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:12.507061Z","time spent":"525.356645ms","remote":"127.0.0.1:37642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":126,"response count":0,"response size":29,"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-09-25T04:16:13.031895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:12.458717Z","time spent":"573.100347ms","remote":"127.0.0.1:35188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-25T04:16:13.033729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.629077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-25T04:16:13.033779Z","caller":"traceutil/trace.go:171","msg":"trace[2002161412] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:26619; }","duration":"394.716289ms","start":"2026-09-25T04:16:12.63905Z","end":"2026-09-25T04:16:13.033766Z","steps":["trace[2002161412] 'agreement among raft nodes before linearized reading' (duration: 394.561536ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:13.033811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:12.639036Z","time spent":"394.76564ms","remote":"127.0.0.1:37698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"warn","ts":"2026-09-25T04:16:13.034054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.966446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-09-25T04:16:13.034082Z","caller":"traceutil/trace.go:171","msg":"trace[1782871091] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:26619; }","duration":"234.030548ms","start":"2026-09-25T04:16:12.800042Z","end":"2026-09-25T04:16:13.034073Z","steps":["trace[1782871091] 'agreement among raft nodes before linearized reading' (duration: 233.944246ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:13.034264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.680804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-25T04:16:13.034256Z","caller":"traceutil/trace.go:171","msg":"trace[497877667] transaction","detail":"{read_only:false; response_revision:26618; number_of_response:1; }","duration":"402.895033ms","start":"2026-09-25T04:16:12.63117Z","end":"2026-09-25T04:16:13.034065Z","steps":["trace[497877667] 'process raft request' (duration: 402.29928ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:13.034387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:12.631144Z","time spent":"403.182149ms","remote":"127.0.0.1:35188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-25T04:16:13.034424Z","caller":"traceutil/trace.go:171","msg":"trace[21254697] transaction","detail":"{read_only:false; response_revision:26619; number_of_response:1; }","duration":"403.065067ms","start":"2026-09-25T04:16:12.631323Z","end":"2026-09-25T04:16:13.034388Z","steps":["trace[21254697] 'process raft request' (duration: 402.241209ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:13.034523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.012978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-09-25T04:16:13.034554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:12.6313Z","time spent":"403.185839ms","remote":"127.0.0.1:35188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-25T04:16:13.034561Z","caller":"traceutil/trace.go:171","msg":"trace[953481048] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:26619; }","duration":"311.068969ms","start":"2026-09-25T04:16:12.723477Z","end":"2026-09-25T04:16:13.034546Z","steps":["trace[953481048] 'agreement among raft nodes before linearized reading' (duration: 311.008228ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:13.034601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:12.723467Z","time spent":"311.12473ms","remote":"127.0.0.1:35330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":31,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"info","ts":"2026-09-25T04:16:13.03454Z","caller":"traceutil/trace.go:171","msg":"trace[1148391899] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:26619; }","duration":"332.748646ms","start":"2026-09-25T04:16:12.701535Z","end":"2026-09-25T04:16:13.034284Z","steps":["trace[1148391899] 'agreement among raft nodes before linearized reading' (duration: 332.631743ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:13.034872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:12.701519Z","time spent":"333.302379ms","remote":"127.0.0.1:37544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true "} {"level":"info","ts":"2026-09-25T04:16:13.338319Z","caller":"traceutil/trace.go:171","msg":"trace[1090718694] transaction","detail":"{read_only:false; response_revision:26621; number_of_response:1; }","duration":"234.373758ms","start":"2026-09-25T04:16:13.103918Z","end":"2026-09-25T04:16:13.338292Z","steps":["trace[1090718694] 'process raft request' (duration: 234.322437ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:13.338398Z","caller":"traceutil/trace.go:171","msg":"trace[194740712] transaction","detail":"{read_only:false; response_revision:26620; number_of_response:1; }","duration":"297.327793ms","start":"2026-09-25T04:16:13.041026Z","end":"2026-09-25T04:16:13.338354Z","steps":["trace[194740712] 'process raft request' (duration: 297.005676ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:13.350361Z","caller":"traceutil/trace.go:171","msg":"trace[1209385237] linearizableReadLoop","detail":"{readStateIndex:27515; appliedIndex:27512; }","duration":"237.713663ms","start":"2026-09-25T04:16:13.112616Z","end":"2026-09-25T04:16:13.350329Z","steps":["trace[1209385237] 'read index received' (duration: 225.461948ms)","trace[1209385237] 'applied index is now lower than readState.Index' (duration: 12.219834ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T04:16:13.350421Z","caller":"traceutil/trace.go:171","msg":"trace[1128584170] transaction","detail":"{read_only:false; response_revision:26622; number_of_response:1; }","duration":"239.320729ms","start":"2026-09-25T04:16:13.111065Z","end":"2026-09-25T04:16:13.350386Z","steps":["trace[1128584170] 'process raft request' (duration: 238.93256ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:13.350664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.047071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-25T04:16:13.350743Z","caller":"traceutil/trace.go:171","msg":"trace[1237338161] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26622; }","duration":"238.178013ms","start":"2026-09-25T04:16:13.112547Z","end":"2026-09-25T04:16:13.350725Z","steps":["trace[1237338161] 'agreement among raft nodes before linearized reading' (duration: 237.938448ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:13.402003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.56709ms","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-09-25T04:16:13.402107Z","caller":"traceutil/trace.go:171","msg":"trace[875187310] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:26623; }","duration":"282.709264ms","start":"2026-09-25T04:16:13.119376Z","end":"2026-09-25T04:16:13.402086Z","steps":["trace[875187310] 'agreement among raft nodes before linearized reading' (duration: 282.560561ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:13.623645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.93284ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-25T04:16:13.623765Z","caller":"traceutil/trace.go:171","msg":"trace[48265240] linearizableReadLoop","detail":"{readStateIndex:27517; appliedIndex:27516; }","duration":"213.15225ms","start":"2026-09-25T04:16:13.410579Z","end":"2026-09-25T04:16:13.623731Z","steps":["trace[48265240] 'read index received' (duration: 95.063386ms)","trace[48265240] 'applied index is now lower than readState.Index' (duration: 118.087694ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T04:16:13.623806Z","caller":"traceutil/trace.go:171","msg":"trace[281169652] transaction","detail":"{read_only:false; response_revision:26624; number_of_response:1; }","duration":"214.9059ms","start":"2026-09-25T04:16:13.408867Z","end":"2026-09-25T04:16:13.623773Z","steps":["trace[281169652] 'process raft request' (duration: 96.764805ms)","trace[281169652] 'compare' (duration: 117.787457ms)"],"step_count":2} {"level":"warn","ts":"2026-09-25T04:16:13.623888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.314563ms","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-09-25T04:16:13.623918Z","caller":"traceutil/trace.go:171","msg":"trace[1111645443] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:26624; }","duration":"213.375044ms","start":"2026-09-25T04:16:13.410532Z","end":"2026-09-25T04:16:13.623907Z","steps":["trace[1111645443] 'agreement among raft nodes before linearized reading' (duration: 213.308803ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:13.623961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.652268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/volumetypes/\" range_end:\"/registry/openstack.k-orc.cloud/volumetypes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-25T04:16:13.624043Z","caller":"traceutil/trace.go:171","msg":"trace[89123634] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/volumetypes/; range_end:/registry/openstack.k-orc.cloud/volumetypes0; response_count:0; response_revision:26624; }","duration":"163.78419ms","start":"2026-09-25T04:16:13.460237Z","end":"2026-09-25T04:16:13.624022Z","steps":["trace[89123634] 'agreement among raft nodes before linearized reading' (duration: 163.650197ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:14.822574Z","caller":"traceutil/trace.go:171","msg":"trace[112484991] transaction","detail":"{read_only:false; response_revision:26627; number_of_response:1; }","duration":"419.158162ms","start":"2026-09-25T04:16:14.403389Z","end":"2026-09-25T04:16:14.822547Z","steps":["trace[112484991] 'process raft request' (duration: 418.984418ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:14.822754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:14.403358Z","time spent":"419.312075ms","remote":"127.0.0.1:35188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-25T04:16:15.029671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.709462ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-25T04:16:15.029827Z","caller":"traceutil/trace.go:171","msg":"trace[261881697] transaction","detail":"{read_only:false; response_revision:26628; number_of_response:1; }","duration":"626.237095ms","start":"2026-09-25T04:16:14.403572Z","end":"2026-09-25T04:16:15.029809Z","steps":["trace[261881697] 'process raft request' (duration: 461.286938ms)","trace[261881697] 'compare' (duration: 164.572579ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T04:16:15.030014Z","caller":"traceutil/trace.go:171","msg":"trace[2041414431] linearizableReadLoop","detail":"{readStateIndex:27521; appliedIndex:27519; }","duration":"573.48137ms","start":"2026-09-25T04:16:14.456511Z","end":"2026-09-25T04:16:15.029993Z","steps":["trace[2041414431] 'read index received' (duration: 365.935016ms)","trace[2041414431] 'applied index is now lower than readState.Index' (duration: 207.545544ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T04:16:15.030027Z","caller":"traceutil/trace.go:171","msg":"trace[897313908] transaction","detail":"{read_only:false; response_revision:26629; number_of_response:1; }","duration":"365.917735ms","start":"2026-09-25T04:16:14.664089Z","end":"2026-09-25T04:16:15.030007Z","steps":["trace[897313908] 'process raft request' (duration: 365.68013ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:15.030163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.067189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-25T04:16:15.030206Z","caller":"traceutil/trace.go:171","msg":"trace[1883720599] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:26629; }","duration":"387.182652ms","start":"2026-09-25T04:16:14.643014Z","end":"2026-09-25T04:16:15.030197Z","steps":["trace[1883720599] 'agreement among raft nodes before linearized reading' (duration: 387.09688ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:15.030174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:14.403563Z","time spent":"626.294217ms","remote":"127.0.0.1:35188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-25T04:16:15.030238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:14.642988Z","time spent":"387.241713ms","remote":"127.0.0.1:45114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"warn","ts":"2026-09-25T04:16:15.030238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.684054ms","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":"warn","ts":"2026-09-25T04:16:15.030252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:14.664063Z","time spent":"366.060807ms","remote":"127.0.0.1:35188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-25T04:16:15.03122Z","caller":"traceutil/trace.go:171","msg":"trace[1149880110] 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:26629; }","duration":"573.861698ms","start":"2026-09-25T04:16:14.456449Z","end":"2026-09-25T04:16:15.03031Z","steps":["trace[1149880110] 'agreement among raft nodes before linearized reading' (duration: 573.632972ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:15.031482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:14.456425Z","time spent":"575.029164ms","remote":"127.0.0.1:37626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":126,"response count":0,"response size":29,"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-09-25T04:16:15.288667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.085726ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-25T04:16:15.288789Z","caller":"traceutil/trace.go:171","msg":"trace[153238281] transaction","detail":"{read_only:false; response_revision:26630; number_of_response:1; }","duration":"248.072095ms","start":"2026-09-25T04:16:15.040696Z","end":"2026-09-25T04:16:15.288768Z","steps":["trace[153238281] 'process raft request' (duration: 100.788645ms)","trace[153238281] 'compare' (duration: 146.917602ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T04:16:15.365431Z","caller":"traceutil/trace.go:171","msg":"trace[1196062947] transaction","detail":"{read_only:false; response_revision:26632; number_of_response:1; }","duration":"321.070455ms","start":"2026-09-25T04:16:15.044329Z","end":"2026-09-25T04:16:15.3654Z","steps":["trace[1196062947] 'process raft request' (duration: 320.984023ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:15.365441Z","caller":"traceutil/trace.go:171","msg":"trace[586166829] transaction","detail":"{read_only:false; response_revision:26631; number_of_response:1; }","duration":"324.42031ms","start":"2026-09-25T04:16:15.040974Z","end":"2026-09-25T04:16:15.365394Z","steps":["trace[586166829] 'process raft request' (duration: 324.171995ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:15.365591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:15.044315Z","time spent":"321.195549ms","remote":"127.0.0.1:35188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-25T04:16:15.365641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:15.040959Z","time spent":"324.579624ms","remote":"127.0.0.1:35188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-25T04:16:15.365768Z","caller":"traceutil/trace.go:171","msg":"trace[120324628] linearizableReadLoop","detail":"{readStateIndex:27527; appliedIndex:27522; }","duration":"253.008208ms","start":"2026-09-25T04:16:15.112731Z","end":"2026-09-25T04:16:15.365739Z","steps":["trace[120324628] 'read index received' (duration: 28.766008ms)","trace[120324628] 'applied index is now lower than readState.Index' (duration: 224.24081ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T04:16:15.365803Z","caller":"traceutil/trace.go:171","msg":"trace[736772358] transaction","detail":"{read_only:false; response_revision:26634; number_of_response:1; }","duration":"320.940134ms","start":"2026-09-25T04:16:15.044835Z","end":"2026-09-25T04:16:15.365775Z","steps":["trace[736772358] 'process raft request' (duration: 320.77818ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:15.365815Z","caller":"traceutil/trace.go:171","msg":"trace[1220065995] transaction","detail":"{read_only:false; response_revision:26633; number_of_response:1; }","duration":"321.099687ms","start":"2026-09-25T04:16:15.044652Z","end":"2026-09-25T04:16:15.365752Z","steps":["trace[1220065995] 'process raft request' (duration: 320.702387ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:15.36592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.145411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-25T04:16:15.365987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:15.044826Z","time spent":"321.032775ms","remote":"127.0.0.1:35188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-25T04:16:15.365995Z","caller":"traceutil/trace.go:171","msg":"trace[555021579] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26634; }","duration":"253.292524ms","start":"2026-09-25T04:16:15.112685Z","end":"2026-09-25T04:16:15.365977Z","steps":["trace[555021579] 'agreement among raft nodes before linearized reading' (duration: 253.145351ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:15.366037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:15.044635Z","time spent":"321.300252ms","remote":"127.0.0.1:35188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-25T04:16:15.595581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.35493ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-25T04:16:15.595864Z","caller":"traceutil/trace.go:171","msg":"trace[33359495] transaction","detail":"{read_only:false; response_revision:26637; number_of_response:1; }","duration":"187.841402ms","start":"2026-09-25T04:16:15.408001Z","end":"2026-09-25T04:16:15.595842Z","steps":["trace[33359495] 'process raft request' (duration: 187.787681ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:15.59615Z","caller":"traceutil/trace.go:171","msg":"trace[1836122254] transaction","detail":"{read_only:false; response_revision:26636; number_of_response:1; }","duration":"251.795ms","start":"2026-09-25T04:16:15.344324Z","end":"2026-09-25T04:16:15.596119Z","steps":["trace[1836122254] 'process raft request' (duration: 251.3765ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:15.596168Z","caller":"traceutil/trace.go:171","msg":"trace[2028598807] transaction","detail":"{read_only:false; response_revision:26635; number_of_response:1; }","duration":"338.633071ms","start":"2026-09-25T04:16:15.257504Z","end":"2026-09-25T04:16:15.596137Z","steps":["trace[2028598807] 'process raft request' (duration: 195.600616ms)","trace[2028598807] 'compare' (duration: 142.067883ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T04:16:15.59621Z","caller":"traceutil/trace.go:171","msg":"trace[1721993319] linearizableReadLoop","detail":"{readStateIndex:27529; appliedIndex:27527; }","duration":"230.319266ms","start":"2026-09-25T04:16:15.365831Z","end":"2026-09-25T04:16:15.59615Z","steps":["trace[1721993319] 'read index received' (duration: 87.288321ms)","trace[1721993319] 'applied index is now lower than readState.Index' (duration: 143.023195ms)"],"step_count":2} {"level":"warn","ts":"2026-09-25T04:16:15.596341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:15.257484Z","time spent":"338.780615ms","remote":"127.0.0.1:35188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-25T04:16:15.596344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.070797ms","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-09-25T04:16:15.596401Z","caller":"traceutil/trace.go:171","msg":"trace[311763266] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:26637; }","duration":"418.174928ms","start":"2026-09-25T04:16:15.178209Z","end":"2026-09-25T04:16:15.596384Z","steps":["trace[311763266] 'agreement among raft nodes before linearized reading' (duration: 418.054106ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:15.596444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:15.178191Z","time spent":"418.244081ms","remote":"127.0.0.1:36516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true "} {"level":"warn","ts":"2026-09-25T04:16:15.596641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.823543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-25T04:16:15.596696Z","caller":"traceutil/trace.go:171","msg":"trace[1807605603] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26637; }","duration":"228.950056ms","start":"2026-09-25T04:16:15.36773Z","end":"2026-09-25T04:16:15.596681Z","steps":["trace[1807605603] 'agreement among raft nodes before linearized reading' (duration: 228.765932ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:15.803442Z","caller":"traceutil/trace.go:171","msg":"trace[761612277] transaction","detail":"{read_only:false; response_revision:26638; number_of_response:1; }","duration":"170.754067ms","start":"2026-09-25T04:16:15.632657Z","end":"2026-09-25T04:16:15.803411Z","steps":["trace[761612277] 'process raft request' (duration: 170.512232ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:26.073991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.279709ms","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-09-25T04:16:26.074124Z","caller":"traceutil/trace.go:171","msg":"trace[517308369] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:26704; }","duration":"446.424143ms","start":"2026-09-25T04:16:25.62768Z","end":"2026-09-25T04:16:26.074104Z","steps":["trace[517308369] 'range keys from in-memory index tree' (duration: 446.262099ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:26.074447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.30263ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-25T04:16:26.074514Z","caller":"traceutil/trace.go:171","msg":"trace[1799185133] transaction","detail":"{read_only:false; response_revision:26705; number_of_response:1; }","duration":"600.541396ms","start":"2026-09-25T04:16:25.473961Z","end":"2026-09-25T04:16:26.074502Z","steps":["trace[1799185133] 'process raft request' (duration: 271.130644ms)","trace[1799185133] 'compare' (duration: 328.704427ms)"],"step_count":2} {"level":"warn","ts":"2026-09-25T04:16:26.074547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:25.473944Z","time spent":"600.588057ms","remote":"127.0.0.1:35188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-25T04:16:26.45039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.538734ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-25T04:16:26.450613Z","caller":"traceutil/trace.go:171","msg":"trace[713754225] transaction","detail":"{read_only:false; response_revision:26707; number_of_response:1; }","duration":"840.984732ms","start":"2026-09-25T04:16:25.609615Z","end":"2026-09-25T04:16:26.4506Z","steps":["trace[713754225] 'process raft request' (duration: 840.865919ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:26.450615Z","caller":"traceutil/trace.go:171","msg":"trace[576728705] transaction","detail":"{read_only:false; response_revision:26706; number_of_response:1; }","duration":"974.882741ms","start":"2026-09-25T04:16:25.475706Z","end":"2026-09-25T04:16:26.450589Z","steps":["trace[576728705] 'process raft request' (duration: 755.07067ms)","trace[576728705] 'compare' (duration: 218.89893ms)"],"step_count":2} {"level":"warn","ts":"2026-09-25T04:16:26.45068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:25.609585Z","time spent":"841.058794ms","remote":"127.0.0.1:35188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-25T04:16:26.4507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:25.475684Z","time spent":"974.972094ms","remote":"127.0.0.1:35188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-25T04:16:26.450934Z","caller":"traceutil/trace.go:171","msg":"trace[1592841462] transaction","detail":"{read_only:false; response_revision:26708; number_of_response:1; }","duration":"821.276789ms","start":"2026-09-25T04:16:25.629626Z","end":"2026-09-25T04:16:26.450903Z","steps":["trace[1592841462] 'process raft request' (duration: 820.91685ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:26.451066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:25.629595Z","time spent":"821.396592ms","remote":"127.0.0.1:35188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-25T04:16:26.450936Z","caller":"traceutil/trace.go:171","msg":"trace[42471055] linearizableReadLoop","detail":"{readStateIndex:27605; appliedIndex:27600; }","duration":"572.658151ms","start":"2026-09-25T04:16:25.878255Z","end":"2026-09-25T04:16:26.450913Z","steps":["trace[42471055] 'read index received' (duration: 352.537304ms)","trace[42471055] 'applied index is now lower than readState.Index' (duration: 220.119817ms)"],"step_count":2} {"level":"warn","ts":"2026-09-25T04:16:26.451145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.872236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-09-25T04:16:26.451215Z","caller":"traceutil/trace.go:171","msg":"trace[1479149982] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26710; }","duration":"572.930987ms","start":"2026-09-25T04:16:25.878245Z","end":"2026-09-25T04:16:26.451176Z","steps":["trace[1479149982] 'agreement among raft nodes before linearized reading' (duration: 572.738253ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:26.451256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:25.878149Z","time spent":"573.096841ms","remote":"127.0.0.1:35188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-25T04:16:26.451298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.052408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:561"} {"level":"info","ts":"2026-09-25T04:16:26.451333Z","caller":"traceutil/trace.go:171","msg":"trace[470124152] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:26710; }","duration":"564.111039ms","start":"2026-09-25T04:16:25.88721Z","end":"2026-09-25T04:16:26.451321Z","steps":["trace[470124152] 'agreement among raft nodes before linearized reading' (duration: 563.960736ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:26.451361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:25.887199Z","time spent":"564.15492ms","remote":"127.0.0.1:35188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":584,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-09-25T04:16:26.451387Z","caller":"traceutil/trace.go:171","msg":"trace[1797616849] transaction","detail":"{read_only:false; response_revision:26709; number_of_response:1; }","duration":"819.984649ms","start":"2026-09-25T04:16:25.631388Z","end":"2026-09-25T04:16:26.451372Z","steps":["trace[1797616849] 'process raft request' (duration: 819.360646ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:26.451449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:25.631358Z","time spent":"820.060011ms","remote":"127.0.0.1:35188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-25T04:16:26.451475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.8225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-09-25T04:16:26.451501Z","caller":"traceutil/trace.go:171","msg":"trace[819966115] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26710; }","duration":"162.884601ms","start":"2026-09-25T04:16:26.288608Z","end":"2026-09-25T04:16:26.451493Z","steps":["trace[819966115] 'agreement among raft nodes before linearized reading' (duration: 162.813309ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:26.451523Z","caller":"traceutil/trace.go:171","msg":"trace[1276683892] transaction","detail":"{read_only:false; response_revision:26710; number_of_response:1; }","duration":"591.628306ms","start":"2026-09-25T04:16:25.859879Z","end":"2026-09-25T04:16:26.451508Z","steps":["trace[1276683892] 'process raft request' (duration: 590.989592ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:26.451626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:25.859851Z","time spent":"591.704768ms","remote":"127.0.0.1:35188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-25T04:16:26.861637Z","caller":"traceutil/trace.go:171","msg":"trace[601212369] transaction","detail":"{read_only:false; response_revision:26711; number_of_response:1; }","duration":"405.704878ms","start":"2026-09-25T04:16:26.455911Z","end":"2026-09-25T04:16:26.861616Z","steps":["trace[601212369] 'process raft request' (duration: 325.301012ms)","trace[601212369] 'compare' (duration: 80.318424ms)"],"step_count":2} {"level":"warn","ts":"2026-09-25T04:16:26.861755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:26.455894Z","time spent":"405.793911ms","remote":"127.0.0.1:35188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-25T04:16:27.215215Z","caller":"traceutil/trace.go:171","msg":"trace[1058068392] linearizableReadLoop","detail":"{readStateIndex:27608; appliedIndex:27605; }","duration":"580.170639ms","start":"2026-09-25T04:16:26.634991Z","end":"2026-09-25T04:16:27.215162Z","steps":["trace[1058068392] 'read index received' (duration: 146.319989ms)","trace[1058068392] 'applied index is now lower than readState.Index' (duration: 433.84975ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T04:16:27.2153Z","caller":"traceutil/trace.go:171","msg":"trace[310134501] transaction","detail":"{read_only:false; response_revision:26712; number_of_response:1; }","duration":"759.041589ms","start":"2026-09-25T04:16:26.456199Z","end":"2026-09-25T04:16:27.21524Z","steps":["trace[310134501] 'process raft request' (duration: 758.768453ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:27.215326Z","caller":"traceutil/trace.go:171","msg":"trace[978114145] transaction","detail":"{read_only:false; response_revision:26713; number_of_response:1; }","duration":"759.009198ms","start":"2026-09-25T04:16:26.456281Z","end":"2026-09-25T04:16:27.21529Z","steps":["trace[978114145] 'process raft request' (duration: 758.833684ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:27.215519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:26.456242Z","time spent":"759.175913ms","remote":"127.0.0.1:35188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-25T04:16:27.215517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:26.456177Z","time spent":"759.227454ms","remote":"127.0.0.1:35188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-25T04:16:27.215567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.558159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-09-25T04:16:27.215641Z","caller":"traceutil/trace.go:171","msg":"trace[529575170] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26713; }","duration":"580.682371ms","start":"2026-09-25T04:16:26.634939Z","end":"2026-09-25T04:16:27.215622Z","steps":["trace[529575170] 'agreement among raft nodes before linearized reading' (duration: 580.407784ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:27.215687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:26.634919Z","time spent":"580.759843ms","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-09-25T04:16:27.557431Z","caller":"traceutil/trace.go:171","msg":"trace[678362870] linearizableReadLoop","detail":"{readStateIndex:27611; appliedIndex:27608; }","duration":"342.098109ms","start":"2026-09-25T04:16:27.215304Z","end":"2026-09-25T04:16:27.557403Z","steps":["trace[678362870] 'read index received' (duration: 290.237013ms)","trace[678362870] 'applied index is now lower than readState.Index' (duration: 51.859956ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T04:16:27.557524Z","caller":"traceutil/trace.go:171","msg":"trace[2096240074] transaction","detail":"{read_only:false; response_revision:26714; number_of_response:1; }","duration":"680.142316ms","start":"2026-09-25T04:16:26.877366Z","end":"2026-09-25T04:16:27.557509Z","steps":["trace[2096240074] 'process raft request' (duration: 628.093016ms)","trace[2096240074] 'compare' (duration: 51.390124ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T04:16:27.557457Z","caller":"traceutil/trace.go:171","msg":"trace[2134660406] transaction","detail":"{read_only:false; response_revision:26716; number_of_response:1; }","duration":"469.712057ms","start":"2026-09-25T04:16:27.087724Z","end":"2026-09-25T04:16:27.557436Z","steps":["trace[2134660406] 'process raft request' (duration: 469.615625ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:27.557586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.525033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-25T04:16:27.557613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.610279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-25T04:16:27.557449Z","caller":"traceutil/trace.go:171","msg":"trace[13322942] transaction","detail":"{read_only:false; response_revision:26715; number_of_response:1; }","duration":"481.269247ms","start":"2026-09-25T04:16:27.076147Z","end":"2026-09-25T04:16:27.557416Z","steps":["trace[13322942] 'process raft request' (duration: 480.995551ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:27.55769Z","caller":"traceutil/trace.go:171","msg":"trace[1749317990] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:26716; }","duration":"608.704901ms","start":"2026-09-25T04:16:26.948968Z","end":"2026-09-25T04:16:27.557673Z","steps":["trace[1749317990] 'agreement among raft nodes before linearized reading' (duration: 608.573139ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:27.55782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:27.076124Z","time spent":"481.604934ms","remote":"127.0.0.1:35188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-25T04:16:27.557676Z","caller":"traceutil/trace.go:171","msg":"trace[1320292787] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26716; }","duration":"445.685007ms","start":"2026-09-25T04:16:27.111974Z","end":"2026-09-25T04:16:27.557659Z","steps":["trace[1320292787] 'agreement among raft nodes before linearized reading' (duration: 445.514303ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:27.558011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:27.111951Z","time spent":"446.041015ms","remote":"127.0.0.1:34920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-25T04:16:27.558026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:26.948907Z","time spent":"608.818284ms","remote":"127.0.0.1:54602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":29,"request content":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true "} {"level":"warn","ts":"2026-09-25T04:16:27.558143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:26.877352Z","time spent":"680.216288ms","remote":"127.0.0.1:35188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-25T04:16:27.558256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:27.087696Z","time spent":"469.947592ms","remote":"127.0.0.1:35188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-25T04:16:28.058493Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4234123443183987527,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-25T04:16:28.220933Z","caller":"traceutil/trace.go:171","msg":"trace[1185561520] linearizableReadLoop","detail":"{readStateIndex:27613; appliedIndex:27611; }","duration":"663.398899ms","start":"2026-09-25T04:16:27.557506Z","end":"2026-09-25T04:16:28.220905Z","steps":["trace[1185561520] 'read index received' (duration: 570.280636ms)","trace[1185561520] 'applied index is now lower than readState.Index' (duration: 93.116793ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T04:16:28.22101Z","caller":"traceutil/trace.go:171","msg":"trace[1613252126] transaction","detail":"{read_only:false; response_revision:26717; number_of_response:1; }","duration":"882.507245ms","start":"2026-09-25T04:16:27.33847Z","end":"2026-09-25T04:16:28.220977Z","steps":["trace[1613252126] 'process raft request' (duration: 789.388182ms)","trace[1613252126] 'compare' (duration: 92.582211ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T04:16:28.221028Z","caller":"traceutil/trace.go:171","msg":"trace[2060283949] transaction","detail":"{read_only:false; response_revision:26718; number_of_response:1; }","duration":"814.588907ms","start":"2026-09-25T04:16:27.406406Z","end":"2026-09-25T04:16:28.220995Z","steps":["trace[2060283949] 'process raft request' (duration: 814.412593ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:28.221058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"727.004459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-25T04:16:28.221087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"773.526066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/users/\" range_end:\"/registry/openstack.k-orc.cloud/users0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-25T04:16:28.221139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.087931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-25T04:16:28.221164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:27.406384Z","time spent":"814.69832ms","remote":"127.0.0.1:35188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-25T04:16:28.221157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"745.814983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-09-25T04:16:28.22123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.01967ms","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":"warn","ts":"2026-09-25T04:16:28.221216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.001322855s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"warn","ts":"2026-09-25T04:16:28.22124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"818.788563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/glance-api-6595857d7f-nvfc7.18d873956b57af94\" ","response":"range_response_count:1 size:2108"} {"level":"warn","ts":"2026-09-25T04:16:28.22125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.276136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-25T04:16:28.221264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:27.338445Z","time spent":"882.770521ms","remote":"127.0.0.1:35188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-25T04:16:28.2213Z","caller":"traceutil/trace.go:171","msg":"trace[980983264] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:0; response_revision:26718; }","duration":"727.123183ms","start":"2026-09-25T04:16:27.493993Z","end":"2026-09-25T04:16:28.221116Z","steps":["trace[980983264] 'agreement among raft nodes before linearized reading' (duration: 726.957558ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:28.22136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.568735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-09-25T04:16:28.221338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.356799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-25T04:16:28.221403Z","caller":"traceutil/trace.go:171","msg":"trace[1276281791] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26718; }","duration":"142.633776ms","start":"2026-09-25T04:16:28.078757Z","end":"2026-09-25T04:16:28.221391Z","steps":["trace[1276281791] 'agreement among raft nodes before linearized reading' (duration: 142.472272ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:28.221389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:27.493974Z","time spent":"727.401659ms","remote":"127.0.0.1:45574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"info","ts":"2026-09-25T04:16:28.221434Z","caller":"traceutil/trace.go:171","msg":"trace[2029826941] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:26718; }","duration":"614.523582ms","start":"2026-09-25T04:16:27.606891Z","end":"2026-09-25T04:16:28.221414Z","steps":["trace[2029826941] 'agreement among raft nodes before linearized reading' (duration: 614.321378ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:28.22137Z","caller":"traceutil/trace.go:171","msg":"trace[1489150592] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26718; }","duration":"1.001449008s","start":"2026-09-25T04:16:27.219824Z","end":"2026-09-25T04:16:28.221273Z","steps":["trace[1489150592] 'agreement among raft nodes before linearized reading' (duration: 1.001213622s)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:28.221464Z","caller":"traceutil/trace.go:171","msg":"trace[1298298510] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/users/; range_end:/registry/openstack.k-orc.cloud/users0; response_count:0; response_revision:26718; }","duration":"773.630058ms","start":"2026-09-25T04:16:27.447502Z","end":"2026-09-25T04:16:28.221132Z","steps":["trace[1298298510] 'agreement among raft nodes before linearized reading' (duration: 773.450873ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:28.22148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:27.606869Z","time spent":"614.599904ms","remote":"127.0.0.1:38850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true "} {"level":"warn","ts":"2026-09-25T04:16:28.221519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:27.44749Z","time spent":"774.014356ms","remote":"127.0.0.1:58230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/users/\" range_end:\"/registry/openstack.k-orc.cloud/users0\" count_only:true "} {"level":"info","ts":"2026-09-25T04:16:28.221554Z","caller":"traceutil/trace.go:171","msg":"trace[1154021376] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26718; }","duration":"662.186373ms","start":"2026-09-25T04:16:27.558995Z","end":"2026-09-25T04:16:28.221181Z","steps":["trace[1154021376] 'agreement among raft nodes before linearized reading' (duration: 662.091571ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:28.221582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:27.558968Z","time spent":"662.605323ms","remote":"127.0.0.1:34914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-25T04:16:28.22167Z","caller":"traceutil/trace.go:171","msg":"trace[1457321015] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26718; }","duration":"745.946916ms","start":"2026-09-25T04:16:27.475296Z","end":"2026-09-25T04:16:28.221243Z","steps":["trace[1457321015] 'agreement among raft nodes before linearized reading' (duration: 745.698799ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:28.221691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:27.475287Z","time spent":"746.399306ms","remote":"127.0.0.1:35188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-09-25T04:16:28.221729Z","caller":"traceutil/trace.go:171","msg":"trace[1326318028] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:26718; }","duration":"440.102092ms","start":"2026-09-25T04:16:27.781148Z","end":"2026-09-25T04:16:28.22125Z","steps":["trace[1326318028] 'agreement among raft nodes before linearized reading' (duration: 439.99872ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:28.221749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:27.781127Z","time spent":"440.617384ms","remote":"127.0.0.1:35254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":22,"response size":31,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"info","ts":"2026-09-25T04:16:28.221817Z","caller":"traceutil/trace.go:171","msg":"trace[461890148] range","detail":"{range_begin:/registry/events/openstack/glance-api-6595857d7f-nvfc7.18d873956b57af94; range_end:; response_count:1; response_revision:26718; }","duration":"818.946987ms","start":"2026-09-25T04:16:27.402342Z","end":"2026-09-25T04:16:28.221289Z","steps":["trace[461890148] 'agreement among raft nodes before linearized reading' (duration: 818.640859ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:28.221918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:27.40232Z","time spent":"819.53484ms","remote":"127.0.0.1:34998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":2131,"request content":"key:\"/registry/events/openstack/glance-api-6595857d7f-nvfc7.18d873956b57af94\" "} {"level":"info","ts":"2026-09-25T04:16:28.221869Z","caller":"traceutil/trace.go:171","msg":"trace[15447675] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:26718; }","duration":"157.48627ms","start":"2026-09-25T04:16:28.063809Z","end":"2026-09-25T04:16:28.221295Z","steps":["trace[15447675] 'agreement among raft nodes before linearized reading' (duration: 157.282925ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:28.222391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:27.219809Z","time spent":"1.001689423s","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-09-25T04:16:28.912071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:28.563768Z","time spent":"348.294647ms","remote":"127.0.0.1:42128","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-09-25T04:16:28.912878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.414381ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-25T04:16:28.913176Z","caller":"traceutil/trace.go:171","msg":"trace[248482972] transaction","detail":"{read_only:false; response_revision:26720; number_of_response:1; }","duration":"686.466229ms","start":"2026-09-25T04:16:28.226697Z","end":"2026-09-25T04:16:28.913163Z","steps":["trace[248482972] 'process raft request' (duration: 321.699139ms)","trace[248482972] 'compare' (duration: 363.650034ms)"],"step_count":2} {"level":"warn","ts":"2026-09-25T04:16:28.913229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:28.226675Z","time spent":"686.53589ms","remote":"127.0.0.1:35188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-25T04:16:28.91348Z","caller":"traceutil/trace.go:171","msg":"trace[1679111447] linearizableReadLoop","detail":"{readStateIndex:27616; appliedIndex:27613; }","duration":"682.241043ms","start":"2026-09-25T04:16:28.231234Z","end":"2026-09-25T04:16:28.913475Z","steps":["trace[1679111447] 'read index received' (duration: 12.636154ms)","trace[1679111447] 'applied index is now lower than readState.Index' (duration: 669.604399ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T04:16:28.913519Z","caller":"traceutil/trace.go:171","msg":"trace[1875573905] transaction","detail":"{read_only:false; response_revision:26721; number_of_response:1; }","duration":"686.234683ms","start":"2026-09-25T04:16:28.227278Z","end":"2026-09-25T04:16:28.913513Z","steps":["trace[1875573905] 'process raft request' (duration: 685.66167ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:28.913547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:28.227256Z","time spent":"686.275605ms","remote":"127.0.0.1:35188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-25T04:16:28.913574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.325996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-25T04:16:28.913634Z","caller":"traceutil/trace.go:171","msg":"trace[937417409] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26721; }","duration":"682.472039ms","start":"2026-09-25T04:16:28.23115Z","end":"2026-09-25T04:16:28.913622Z","steps":["trace[937417409] 'agreement among raft nodes before linearized reading' (duration: 682.375167ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:28.913665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:28.231133Z","time spent":"682.526611ms","remote":"127.0.0.1:34920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-25T04:16:29.021852Z","caller":"traceutil/trace.go:171","msg":"trace[60586360] transaction","detail":"{read_only:false; response_revision:26722; number_of_response:1; }","duration":"563.674318ms","start":"2026-09-25T04:16:28.458155Z","end":"2026-09-25T04:16:29.02183Z","steps":["trace[60586360] 'process raft request' (duration: 562.446661ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:29.022021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:28.458139Z","time spent":"563.810912ms","remote":"127.0.0.1:35188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-25T04:16:29.022065Z","caller":"traceutil/trace.go:171","msg":"trace[1884596714] linearizableReadLoop","detail":"{readStateIndex:27620; appliedIndex:27616; }","duration":"108.547199ms","start":"2026-09-25T04:16:28.913496Z","end":"2026-09-25T04:16:29.022043Z","steps":["trace[1884596714] 'read index received' (duration: 102.326689ms)","trace[1884596714] 'applied index is now lower than readState.Index' (duration: 6.21952ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T04:16:29.022096Z","caller":"traceutil/trace.go:171","msg":"trace[587070009] transaction","detail":"{read_only:false; response_revision:26723; number_of_response:1; }","duration":"563.785701ms","start":"2026-09-25T04:16:28.458274Z","end":"2026-09-25T04:16:29.02206Z","steps":["trace[587070009] 'process raft request' (duration: 563.480595ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:29.022303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"678.698294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"warn","ts":"2026-09-25T04:16:29.022297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:28.458264Z","time spent":"563.904434ms","remote":"127.0.0.1:35188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-25T04:16:29.022339Z","caller":"traceutil/trace.go:171","msg":"trace[960376739] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:26724; }","duration":"678.760476ms","start":"2026-09-25T04:16:28.34357Z","end":"2026-09-25T04:16:29.022331Z","steps":["trace[960376739] 'agreement among raft nodes before linearized reading' (duration: 678.665503ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:29.022331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.61017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-25T04:16:29.022372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.965741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:419144"} {"level":"info","ts":"2026-09-25T04:16:29.022404Z","caller":"traceutil/trace.go:171","msg":"trace[1677047148] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26724; }","duration":"107.715492ms","start":"2026-09-25T04:16:28.914677Z","end":"2026-09-25T04:16:29.022393Z","steps":["trace[1677047148] 'agreement among raft nodes before linearized reading' (duration: 107.599639ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:29.022447Z","caller":"traceutil/trace.go:171","msg":"trace[1862256860] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:26724; }","duration":"453.072643ms","start":"2026-09-25T04:16:28.569357Z","end":"2026-09-25T04:16:29.02243Z","steps":["trace[1862256860] 'agreement among raft nodes before linearized reading' (duration: 452.740155ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:29.022491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:28.569345Z","time spent":"453.138435ms","remote":"127.0.0.1:53678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":419167,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" "} {"level":"warn","ts":"2026-09-25T04:16:29.022361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:28.343559Z","time spent":"678.797557ms","remote":"127.0.0.1:35188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":556,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-09-25T04:16:29.022303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.860616ms","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-09-25T04:16:29.022572Z","caller":"traceutil/trace.go:171","msg":"trace[1254486509] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26724; }","duration":"565.169422ms","start":"2026-09-25T04:16:28.457392Z","end":"2026-09-25T04:16:29.022561Z","steps":["trace[1254486509] 'agreement among raft nodes before linearized reading' (duration: 564.772803ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:29.022608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:28.457378Z","time spent":"565.221694ms","remote":"127.0.0.1:35188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-25T04:16:29.02236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.637262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-25T04:16:29.022694Z","caller":"traceutil/trace.go:171","msg":"trace[1561803400] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26724; }","duration":"237.994ms","start":"2026-09-25T04:16:28.784677Z","end":"2026-09-25T04:16:29.022671Z","steps":["trace[1561803400] 'agreement among raft nodes before linearized reading' (duration: 237.651032ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:29.022847Z","caller":"traceutil/trace.go:171","msg":"trace[1210373786] transaction","detail":"{read_only:false; response_revision:26724; number_of_response:1; }","duration":"563.841592ms","start":"2026-09-25T04:16:28.458333Z","end":"2026-09-25T04:16:29.022175Z","steps":["trace[1210373786] 'process raft request' (duration: 563.619347ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:29.022984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:28.458326Z","time spent":"564.57748ms","remote":"127.0.0.1:35188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-25T04:16:29.022331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.905734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-25T04:16:29.02321Z","caller":"traceutil/trace.go:171","msg":"trace[243496991] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26724; }","duration":"709.816424ms","start":"2026-09-25T04:16:28.313359Z","end":"2026-09-25T04:16:29.023175Z","steps":["trace[243496991] 'agreement among raft nodes before linearized reading' (duration: 708.854802ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:29.023257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:28.313338Z","time spent":"709.904346ms","remote":"127.0.0.1:35402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-09-25T04:16:31.134501Z","caller":"traceutil/trace.go:171","msg":"trace[1388636845] linearizableReadLoop","detail":"{readStateIndex:27689; appliedIndex:27688; }","duration":"218.173074ms","start":"2026-09-25T04:16:30.916307Z","end":"2026-09-25T04:16:31.13448Z","steps":["trace[1388636845] 'read index received' (duration: 218.0629ms)","trace[1388636845] 'applied index is now lower than readState.Index' (duration: 109.404µs)"],"step_count":2} {"level":"info","ts":"2026-09-25T04:16:31.134526Z","caller":"traceutil/trace.go:171","msg":"trace[1438320671] transaction","detail":"{read_only:false; response_revision:26793; number_of_response:1; }","duration":"240.362462ms","start":"2026-09-25T04:16:30.894148Z","end":"2026-09-25T04:16:31.13451Z","steps":["trace[1438320671] 'process raft request' (duration: 240.156837ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:31.134654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.329757ms","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-09-25T04:16:31.134679Z","caller":"traceutil/trace.go:171","msg":"trace[1534384957] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26793; }","duration":"218.420099ms","start":"2026-09-25T04:16:30.916253Z","end":"2026-09-25T04:16:31.134673Z","steps":["trace[1534384957] 'agreement among raft nodes before linearized reading' (duration: 218.298686ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:31.30927Z","caller":"traceutil/trace.go:171","msg":"trace[916966711] linearizableReadLoop","detail":"{readStateIndex:27692; appliedIndex:27689; }","duration":"174.694866ms","start":"2026-09-25T04:16:31.134555Z","end":"2026-09-25T04:16:31.309249Z","steps":["trace[916966711] 'read index received' (duration: 174.463391ms)","trace[916966711] 'applied index is now lower than readState.Index' (duration: 230.745µs)"],"step_count":2} {"level":"info","ts":"2026-09-25T04:16:31.309501Z","caller":"traceutil/trace.go:171","msg":"trace[808886338] transaction","detail":"{read_only:false; response_revision:26796; number_of_response:1; }","duration":"279.012961ms","start":"2026-09-25T04:16:31.030457Z","end":"2026-09-25T04:16:31.30947Z","steps":["trace[808886338] 'process raft request' (duration: 278.749695ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:31.309514Z","caller":"traceutil/trace.go:171","msg":"trace[97810074] transaction","detail":"{read_only:false; response_revision:26794; number_of_response:1; }","duration":"280.74524ms","start":"2026-09-25T04:16:31.028741Z","end":"2026-09-25T04:16:31.309486Z","steps":["trace[97810074] 'process raft request' (duration: 280.264039ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:31.309594Z","caller":"traceutil/trace.go:171","msg":"trace[1330058130] transaction","detail":"{read_only:false; response_revision:26795; number_of_response:1; }","duration":"279.468692ms","start":"2026-09-25T04:16:31.030105Z","end":"2026-09-25T04:16:31.309573Z","steps":["trace[1330058130] 'process raft request' (duration: 279.050362ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:31.309621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.269424ms","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-09-25T04:16:31.309832Z","caller":"traceutil/trace.go:171","msg":"trace[855951697] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26796; }","duration":"391.496879ms","start":"2026-09-25T04:16:30.918321Z","end":"2026-09-25T04:16:31.309818Z","steps":["trace[855951697] 'agreement among raft nodes before linearized reading' (duration: 391.207453ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:31.309622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.352927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-25T04:16:31.309876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:30.918316Z","time spent":"391.54599ms","remote":"127.0.0.1:35188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-25T04:16:31.309681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.556939ms","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-09-25T04:16:31.30997Z","caller":"traceutil/trace.go:171","msg":"trace[2127887804] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:26796; }","duration":"363.789997ms","start":"2026-09-25T04:16:30.946146Z","end":"2026-09-25T04:16:31.309936Z","steps":["trace[2127887804] 'agreement among raft nodes before linearized reading' (duration: 363.377537ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:31.310015Z","caller":"traceutil/trace.go:171","msg":"trace[1246822886] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26796; }","duration":"269.914786ms","start":"2026-09-25T04:16:31.040086Z","end":"2026-09-25T04:16:31.31Z","steps":["trace[1246822886] 'agreement among raft nodes before linearized reading' (duration: 269.492577ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:31.310078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:30.946101Z","time spent":"363.93649ms","remote":"127.0.0.1:37360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":29,"request content":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true "} {"level":"info","ts":"2026-09-25T04:16:31.310414Z","caller":"traceutil/trace.go:171","msg":"trace[892010562] transaction","detail":"{read_only:false; response_revision:26797; number_of_response:1; }","duration":"171.113926ms","start":"2026-09-25T04:16:31.139262Z","end":"2026-09-25T04:16:31.310375Z","steps":["trace[892010562] 'process raft request' (duration: 170.988654ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:31.310735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.135813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-09-25T04:16:31.310839Z","caller":"traceutil/trace.go:171","msg":"trace[1539281568] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:26797; }","duration":"113.255025ms","start":"2026-09-25T04:16:31.197572Z","end":"2026-09-25T04:16:31.310827Z","steps":["trace[1539281568] 'agreement among raft nodes before linearized reading' (duration: 113.00491ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:33.422832Z","caller":"traceutil/trace.go:171","msg":"trace[81201614] transaction","detail":"{read_only:false; response_revision:26814; number_of_response:1; }","duration":"106.36327ms","start":"2026-09-25T04:16:33.316444Z","end":"2026-09-25T04:16:33.422808Z","steps":["trace[81201614] 'process raft request' (duration: 106.152656ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:33.423128Z","caller":"traceutil/trace.go:171","msg":"trace[1883819739] transaction","detail":"{read_only:false; response_revision:26815; number_of_response:1; }","duration":"105.416359ms","start":"2026-09-25T04:16:33.317692Z","end":"2026-09-25T04:16:33.423109Z","steps":["trace[1883819739] 'process raft request' (duration: 105.065301ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:33.423211Z","caller":"traceutil/trace.go:171","msg":"trace[530247844] transaction","detail":"{read_only:false; response_revision:26816; number_of_response:1; }","duration":"105.146122ms","start":"2026-09-25T04:16:33.318028Z","end":"2026-09-25T04:16:33.423174Z","steps":["trace[530247844] 'process raft request' (duration: 105.02188ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:38.543112Z","caller":"traceutil/trace.go:171","msg":"trace[2077930656] linearizableReadLoop","detail":"{readStateIndex:27766; appliedIndex:27765; }","duration":"430.370092ms","start":"2026-09-25T04:16:38.112715Z","end":"2026-09-25T04:16:38.543085Z","steps":["trace[2077930656] 'read index received' (duration: 430.306601ms)","trace[2077930656] 'applied index is now lower than readState.Index' (duration: 62.031µs)"],"step_count":2} {"level":"info","ts":"2026-09-25T04:16:38.543232Z","caller":"traceutil/trace.go:171","msg":"trace[975697999] transaction","detail":"{read_only:false; response_revision:26868; number_of_response:1; }","duration":"452.03725ms","start":"2026-09-25T04:16:38.091124Z","end":"2026-09-25T04:16:38.543161Z","steps":["trace[975697999] 'process raft request' (duration: 451.751333ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:38.543298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.570386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-25T04:16:38.543337Z","caller":"traceutil/trace.go:171","msg":"trace[577589287] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26868; }","duration":"430.678269ms","start":"2026-09-25T04:16:38.112648Z","end":"2026-09-25T04:16:38.543326Z","steps":["trace[577589287] 'agreement among raft nodes before linearized reading' (duration: 430.588517ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:38.543374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:38.112627Z","time spent":"430.740241ms","remote":"127.0.0.1:34920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-25T04:16:38.543385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:38.091105Z","time spent":"452.200603ms","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10631,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-25T04:16:38.561363Z","caller":"traceutil/trace.go:171","msg":"trace[2130850300] transaction","detail":"{read_only:false; response_revision:26869; number_of_response:1; }","duration":"309.787292ms","start":"2026-09-25T04:16:38.251543Z","end":"2026-09-25T04:16:38.56133Z","steps":["trace[2130850300] 'process raft request' (duration: 309.451935ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:38.561413Z","caller":"traceutil/trace.go:171","msg":"trace[1783139868] transaction","detail":"{read_only:false; response_revision:26870; number_of_response:1; }","duration":"301.983147ms","start":"2026-09-25T04:16:38.259406Z","end":"2026-09-25T04:16:38.56139Z","steps":["trace[1783139868] 'process raft request' (duration: 301.744321ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:38.561522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:38.251516Z","time spent":"309.923526ms","remote":"127.0.0.1:35188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-25T04:16:38.561536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:38.259383Z","time spent":"302.08808ms","remote":"127.0.0.1:35188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-25T04:16:38.714148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.889056ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-09-25T04:16:38.714268Z","caller":"traceutil/trace.go:171","msg":"trace[292529930] linearizableReadLoop","detail":"{readStateIndex:27769; appliedIndex:27766; }","duration":"171.065505ms","start":"2026-09-25T04:16:38.543184Z","end":"2026-09-25T04:16:38.714249Z","steps":["trace[292529930] 'read index received' (duration: 17.907373ms)","trace[292529930] 'applied index is now lower than readState.Index' (duration: 153.156572ms)"],"step_count":2} {"level":"warn","ts":"2026-09-25T04:16:38.714634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.672914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedrainrules/\" range_end:\"/registry/cluster.x-k8s.io/machinedrainrules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-25T04:16:38.714805Z","caller":"traceutil/trace.go:171","msg":"trace[113261037] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedrainrules/; range_end:/registry/cluster.x-k8s.io/machinedrainrules0; response_count:0; response_revision:26870; }","duration":"331.903439ms","start":"2026-09-25T04:16:38.382849Z","end":"2026-09-25T04:16:38.714753Z","steps":["trace[113261037] 'agreement among raft nodes before linearized reading' (duration: 331.599532ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:38.714855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:38.382831Z","time spent":"332.010471ms","remote":"127.0.0.1:37562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/cluster.x-k8s.io/machinedrainrules/\" range_end:\"/registry/cluster.x-k8s.io/machinedrainrules0\" count_only:true "} {"level":"warn","ts":"2026-09-25T04:16:38.761063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.0481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-25T04:16:38.761147Z","caller":"traceutil/trace.go:171","msg":"trace[882802867] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26874; }","duration":"214.187974ms","start":"2026-09-25T04:16:38.546938Z","end":"2026-09-25T04:16:38.761126Z","steps":["trace[882802867] 'agreement among raft nodes before linearized reading' (duration: 213.99355ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:38.761524Z","caller":"traceutil/trace.go:171","msg":"trace[1365538459] transaction","detail":"{read_only:false; response_revision:26871; number_of_response:1; }","duration":"210.759687ms","start":"2026-09-25T04:16:38.550743Z","end":"2026-09-25T04:16:38.761503Z","steps":["trace[1365538459] 'process raft request' (duration: 209.864067ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:38.761536Z","caller":"traceutil/trace.go:171","msg":"trace[1723984743] transaction","detail":"{read_only:false; response_revision:26872; number_of_response:1; }","duration":"209.181632ms","start":"2026-09-25T04:16:38.55231Z","end":"2026-09-25T04:16:38.761492Z","steps":["trace[1723984743] 'process raft request' (duration: 208.470166ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:38.761627Z","caller":"traceutil/trace.go:171","msg":"trace[827386874] transaction","detail":"{read_only:false; response_revision:26874; number_of_response:1; }","duration":"207.994035ms","start":"2026-09-25T04:16:38.553623Z","end":"2026-09-25T04:16:38.761617Z","steps":["trace[827386874] 'process raft request' (duration: 207.263769ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:38.761587Z","caller":"traceutil/trace.go:171","msg":"trace[1507220716] transaction","detail":"{read_only:false; response_revision:26873; number_of_response:1; }","duration":"209.159222ms","start":"2026-09-25T04:16:38.55241Z","end":"2026-09-25T04:16:38.76157Z","steps":["trace[1507220716] 'process raft request' (duration: 208.433516ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:38.972827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.247114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1854"} {"level":"info","ts":"2026-09-25T04:16:38.972909Z","caller":"traceutil/trace.go:171","msg":"trace[1157171053] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26874; }","duration":"153.388458ms","start":"2026-09-25T04:16:38.819502Z","end":"2026-09-25T04:16:38.97289Z","steps":["trace[1157171053] 'range keys from in-memory index tree' (duration: 153.001978ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:17:01.694772Z","caller":"traceutil/trace.go:171","msg":"trace[768191468] linearizableReadLoop","detail":"{readStateIndex:27972; appliedIndex:27967; }","duration":"112.289905ms","start":"2026-09-25T04:17:01.58245Z","end":"2026-09-25T04:17:01.69474Z","steps":["trace[768191468] 'read index received' (duration: 11.53326ms)","trace[768191468] 'applied index is now lower than readState.Index' (duration: 100.755385ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T04:17:01.69536Z","caller":"traceutil/trace.go:171","msg":"trace[1775299059] transaction","detail":"{read_only:false; response_revision:27068; number_of_response:1; }","duration":"114.854091ms","start":"2026-09-25T04:17:01.580484Z","end":"2026-09-25T04:17:01.695338Z","steps":["trace[1775299059] 'process raft request' (duration: 114.206857ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:17:01.69559Z","caller":"traceutil/trace.go:171","msg":"trace[1236122990] transaction","detail":"{read_only:false; response_revision:27066; number_of_response:1; }","duration":"126.079393ms","start":"2026-09-25T04:17:01.569491Z","end":"2026-09-25T04:17:01.69557Z","steps":["trace[1236122990] 'process raft request' (duration: 125.05557ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:17:01.69562Z","caller":"traceutil/trace.go:171","msg":"trace[1662274889] transaction","detail":"{read_only:false; response_revision:27067; number_of_response:1; }","duration":"122.052404ms","start":"2026-09-25T04:17:01.573554Z","end":"2026-09-25T04:17:01.695606Z","steps":["trace[1662274889] 'process raft request' (duration: 121.065721ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:17:01.695722Z","caller":"traceutil/trace.go:171","msg":"trace[2001721776] transaction","detail":"{read_only:false; response_revision:27065; number_of_response:1; }","duration":"144.802775ms","start":"2026-09-25T04:17:01.550912Z","end":"2026-09-25T04:17:01.695715Z","steps":["trace[2001721776] 'process raft request' (duration: 142.699518ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:17:01.723978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.329945ms","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-09-25T04:17:01.724068Z","caller":"traceutil/trace.go:171","msg":"trace[254141222] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27068; }","duration":"100.465428ms","start":"2026-09-25T04:17:01.62358Z","end":"2026-09-25T04:17:01.724045Z","steps":["trace[254141222] 'agreement among raft nodes before linearized reading' (duration: 71.969778ms)","trace[254141222] 'range keys from in-memory index tree' (duration: 28.326476ms)"],"step_count":2} {"level":"warn","ts":"2026-09-25T04:17:01.72436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.90395ms","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-09-25T04:17:01.724418Z","caller":"traceutil/trace.go:171","msg":"trace[1203010264] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27068; }","duration":"141.985502ms","start":"2026-09-25T04:17:01.582411Z","end":"2026-09-25T04:17:01.724396Z","steps":["trace[1203010264] 'agreement among raft nodes before linearized reading' (duration: 113.126153ms)","trace[1203010264] 'range keys from in-memory index tree' (duration: 28.719986ms)"],"step_count":2} {"level":"warn","ts":"2026-09-25T04:17:01.724009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.791134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-09-25T04:17:01.724469Z","caller":"traceutil/trace.go:171","msg":"trace[988528624] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27068; }","duration":"105.286806ms","start":"2026-09-25T04:17:01.619167Z","end":"2026-09-25T04:17:01.724454Z","steps":["trace[988528624] 'agreement among raft nodes before linearized reading' (duration: 76.387287ms)","trace[988528624] 'range keys from in-memory index tree' (duration: 28.398998ms)"],"step_count":2} {"level":"warn","ts":"2026-09-25T04:17:01.724625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.992291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-09-25T04:17:01.724651Z","caller":"traceutil/trace.go:171","msg":"trace[1237340200] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27068; }","duration":"110.041753ms","start":"2026-09-25T04:17:01.614599Z","end":"2026-09-25T04:17:01.724641Z","steps":["trace[1237340200] 'agreement among raft nodes before linearized reading' (duration: 80.916639ms)","trace[1237340200] 'range keys from in-memory index tree' (duration: 29.017022ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T04:17:01.953418Z","caller":"traceutil/trace.go:171","msg":"trace[880227016] transaction","detail":"{read_only:false; response_revision:27070; number_of_response:1; }","duration":"221.322065ms","start":"2026-09-25T04:17:01.732069Z","end":"2026-09-25T04:17:01.953391Z","steps":["trace[880227016] 'process raft request' (duration: 189.449838ms)","trace[880227016] 'compare' (duration: 31.457177ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T04:17:01.953772Z","caller":"traceutil/trace.go:171","msg":"trace[288392596] transaction","detail":"{read_only:false; response_revision:27073; number_of_response:1; }","duration":"220.68337ms","start":"2026-09-25T04:17:01.733065Z","end":"2026-09-25T04:17:01.953748Z","steps":["trace[288392596] 'process raft request' (duration: 220.604029ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:17:01.953811Z","caller":"traceutil/trace.go:171","msg":"trace[1714593868] transaction","detail":"{read_only:false; response_revision:27074; number_of_response:1; }","duration":"204.501957ms","start":"2026-09-25T04:17:01.749297Z","end":"2026-09-25T04:17:01.953799Z","steps":["trace[1714593868] 'process raft request' (duration: 204.412575ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:17:01.953936Z","caller":"traceutil/trace.go:171","msg":"trace[624207933] transaction","detail":"{read_only:false; response_revision:27072; number_of_response:1; }","duration":"221.566309ms","start":"2026-09-25T04:17:01.732353Z","end":"2026-09-25T04:17:01.95392Z","steps":["trace[624207933] 'process raft request' (duration: 221.260803ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:17:01.953934Z","caller":"traceutil/trace.go:171","msg":"trace[1295158551] transaction","detail":"{read_only:false; response_revision:27071; number_of_response:1; }","duration":"221.754464ms","start":"2026-09-25T04:17:01.732152Z","end":"2026-09-25T04:17:01.953906Z","steps":["trace[1295158551] 'process raft request' (duration: 221.153241ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:17:02.002808Z","caller":"traceutil/trace.go:171","msg":"trace[226964308] transaction","detail":"{read_only:false; response_revision:27075; number_of_response:1; }","duration":"208.786382ms","start":"2026-09-25T04:17:01.793996Z","end":"2026-09-25T04:17:02.002782Z","steps":["trace[226964308] 'process raft request' (duration: 208.587727ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:17:02.00288Z","caller":"traceutil/trace.go:171","msg":"trace[49647784] transaction","detail":"{read_only:false; response_revision:27076; number_of_response:1; }","duration":"207.178066ms","start":"2026-09-25T04:17:01.795669Z","end":"2026-09-25T04:17:02.002847Z","steps":["trace[49647784] 'process raft request' (duration: 207.018572ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:17:02.253227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.329313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-25T04:17:02.253311Z","caller":"traceutil/trace.go:171","msg":"trace[1644588844] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27076; }","duration":"140.488867ms","start":"2026-09-25T04:17:02.112803Z","end":"2026-09-25T04:17:02.253292Z","steps":["trace[1644588844] 'range keys from in-memory index tree' (duration: 140.215131ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:17:02.843749Z","caller":"traceutil/trace.go:171","msg":"trace[2092870410] transaction","detail":"{read_only:false; response_revision:27078; number_of_response:1; }","duration":"173.180873ms","start":"2026-09-25T04:17:02.670531Z","end":"2026-09-25T04:17:02.843712Z","steps":["trace[2092870410] 'process raft request' (duration: 155.534476ms)","trace[2092870410] 'compare' (duration: 17.445722ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T04:17:03.814861Z","caller":"traceutil/trace.go:171","msg":"trace[805960270] transaction","detail":"{read_only:false; response_revision:27081; number_of_response:1; }","duration":"113.4666ms","start":"2026-09-25T04:17:03.701365Z","end":"2026-09-25T04:17:03.814832Z","steps":["trace[805960270] 'process raft request' (duration: 110.957563ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:17:03.814984Z","caller":"traceutil/trace.go:171","msg":"trace[301212301] transaction","detail":"{read_only:false; response_revision:27083; number_of_response:1; }","duration":"111.546666ms","start":"2026-09-25T04:17:03.703411Z","end":"2026-09-25T04:17:03.814958Z","steps":["trace[301212301] 'process raft request' (duration: 110.964913ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:17:03.815046Z","caller":"traceutil/trace.go:171","msg":"trace[1625635180] transaction","detail":"{read_only:false; response_revision:27082; number_of_response:1; }","duration":"111.949236ms","start":"2026-09-25T04:17:03.703071Z","end":"2026-09-25T04:17:03.81502Z","steps":["trace[1625635180] 'process raft request' (duration: 111.20061ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:17:04.153517Z","caller":"traceutil/trace.go:171","msg":"trace[1412260778] linearizableReadLoop","detail":"{readStateIndex:27990; appliedIndex:27989; }","duration":"191.81366ms","start":"2026-09-25T04:17:03.96168Z","end":"2026-09-25T04:17:04.153494Z","steps":["trace[1412260778] 'read index received' (duration: 191.649247ms)","trace[1412260778] 'applied index is now lower than readState.Index' (duration: 163.433µs)"],"step_count":2} {"level":"info","ts":"2026-09-25T04:17:04.153566Z","caller":"traceutil/trace.go:171","msg":"trace[1590039393] transaction","detail":"{read_only:false; response_revision:27085; number_of_response:1; }","duration":"193.568791ms","start":"2026-09-25T04:17:03.959974Z","end":"2026-09-25T04:17:04.153543Z","steps":["trace[1590039393] 'process raft request' (duration: 193.299155ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:17:04.153718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.017185ms","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-09-25T04:17:04.153756Z","caller":"traceutil/trace.go:171","msg":"trace[1450503061] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27085; }","duration":"192.091607ms","start":"2026-09-25T04:17:03.961654Z","end":"2026-09-25T04:17:04.153745Z","steps":["trace[1450503061] 'agreement among raft nodes before linearized reading' (duration: 191.950584ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:17:04.326674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.897108ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-25T04:17:04.326817Z","caller":"traceutil/trace.go:171","msg":"trace[1042256332] transaction","detail":"{read_only:false; response_revision:27086; number_of_response:1; }","duration":"342.510358ms","start":"2026-09-25T04:17:03.984287Z","end":"2026-09-25T04:17:04.326798Z","steps":["trace[1042256332] 'process raft request' (duration: 241.410326ms)","trace[1042256332] 'compare' (duration: 100.785785ms)"],"step_count":2} {"level":"warn","ts":"2026-09-25T04:17:04.326885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:17:03.984262Z","time spent":"342.5943ms","remote":"127.0.0.1:35188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-25T04:17:04.327174Z","caller":"traceutil/trace.go:171","msg":"trace[451215274] transaction","detail":"{read_only:false; response_revision:27089; number_of_response:1; }","duration":"317.628969ms","start":"2026-09-25T04:17:04.009524Z","end":"2026-09-25T04:17:04.327153Z","steps":["trace[451215274] 'process raft request' (duration: 317.520546ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:17:04.327171Z","caller":"traceutil/trace.go:171","msg":"trace[277800148] transaction","detail":"{read_only:false; response_revision:27090; number_of_response:1; }","duration":"168.017886ms","start":"2026-09-25T04:17:04.159123Z","end":"2026-09-25T04:17:04.327141Z","steps":["trace[277800148] 'process raft request' (duration: 167.972315ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:17:04.32729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:17:04.009502Z","time spent":"317.747331ms","remote":"127.0.0.1:35188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-25T04:17:04.327302Z","caller":"traceutil/trace.go:171","msg":"trace[522064660] transaction","detail":"{read_only:false; response_revision:27087; number_of_response:1; }","duration":"342.459357ms","start":"2026-09-25T04:17:03.984828Z","end":"2026-09-25T04:17:04.327287Z","steps":["trace[522064660] 'process raft request' (duration: 341.927905ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:17:04.327364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:17:03.984814Z","time spent":"342.516588ms","remote":"127.0.0.1:35188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-25T04:17:04.327391Z","caller":"traceutil/trace.go:171","msg":"trace[1411734688] transaction","detail":"{read_only:false; response_revision:27088; number_of_response:1; }","duration":"319.536822ms","start":"2026-09-25T04:17:04.007844Z","end":"2026-09-25T04:17:04.327381Z","steps":["trace[1411734688] 'process raft request' (duration: 319.071111ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:17:04.327437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:17:04.007822Z","time spent":"319.588082ms","remote":"127.0.0.1:35188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-25T04:17:04.327479Z","caller":"traceutil/trace.go:171","msg":"trace[1452047545] linearizableReadLoop","detail":"{readStateIndex:27994; appliedIndex:27990; }","duration":"173.890989ms","start":"2026-09-25T04:17:04.15358Z","end":"2026-09-25T04:17:04.327471Z","steps":["trace[1452047545] 'read index received' (duration: 72.286295ms)","trace[1452047545] 'applied index is now lower than readState.Index' (duration: 101.603604ms)"],"step_count":2} {"level":"warn","ts":"2026-09-25T04:17:04.327548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.541134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-25T04:17:04.327578Z","caller":"traceutil/trace.go:171","msg":"trace[1614364740] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27090; }","duration":"215.607716ms","start":"2026-09-25T04:17:04.111962Z","end":"2026-09-25T04:17:04.32757Z","steps":["trace[1614364740] 'agreement among raft nodes before linearized reading' (duration: 215.554465ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:17:04.327584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.281839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-09-25T04:17:04.327607Z","caller":"traceutil/trace.go:171","msg":"trace[806738468] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27090; }","duration":"365.333261ms","start":"2026-09-25T04:17:03.962266Z","end":"2026-09-25T04:17:04.327599Z","steps":["trace[806738468] 'agreement among raft nodes before linearized reading' (duration: 365.239318ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:17:04.32763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:17:03.962253Z","time spent":"365.371481ms","remote":"127.0.0.1:35188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-25T04:17:09.96287Z","caller":"traceutil/trace.go:171","msg":"trace[1867663460] transaction","detail":"{read_only:false; response_revision:27123; number_of_response:1; }","duration":"130.681847ms","start":"2026-09-25T04:17:09.832167Z","end":"2026-09-25T04:17:09.962848Z","steps":["trace[1867663460] 'process raft request' (duration: 130.552684ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:17:10.296754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.19937ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-25T04:17:10.296951Z","caller":"traceutil/trace.go:171","msg":"trace[1620788530] linearizableReadLoop","detail":"{readStateIndex:28031; appliedIndex:28029; }","duration":"375.706074ms","start":"2026-09-25T04:17:09.921229Z","end":"2026-09-25T04:17:10.296935Z","steps":["trace[1620788530] 'read index received' (duration: 41.621725ms)","trace[1620788530] 'applied index is now lower than readState.Index' (duration: 334.083479ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T04:17:10.297036Z","caller":"traceutil/trace.go:171","msg":"trace[1060483785] transaction","detail":"{read_only:false; response_revision:27124; number_of_response:1; }","duration":"463.093658ms","start":"2026-09-25T04:17:09.833931Z","end":"2026-09-25T04:17:10.297025Z","steps":["trace[1060483785] 'process raft request' (duration: 189.5412ms)","trace[1060483785] 'compare' (duration: 272.857912ms)"],"step_count":2} {"level":"warn","ts":"2026-09-25T04:17:10.297088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:17:09.833911Z","time spent":"463.145788ms","remote":"127.0.0.1:35188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-25T04:17:10.297089Z","caller":"traceutil/trace.go:171","msg":"trace[1003902123] transaction","detail":"{read_only:false; response_revision:27125; number_of_response:1; }","duration":"455.460946ms","start":"2026-09-25T04:17:09.841594Z","end":"2026-09-25T04:17:10.297055Z","steps":["trace[1003902123] 'process raft request' (duration: 455.248732ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:17:10.297175Z","caller":"traceutil/trace.go:171","msg":"trace[717856200] transaction","detail":"{read_only:false; response_revision:27126; number_of_response:1; }","duration":"124.071498ms","start":"2026-09-25T04:17:10.173075Z","end":"2026-09-25T04:17:10.297147Z","steps":["trace[717856200] 'process raft request' (duration: 123.830553ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:17:10.297242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.612569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-25T04:17:10.297281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.033511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-09-25T04:17:10.297299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.98407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-09-25T04:17:10.29732Z","caller":"traceutil/trace.go:171","msg":"trace[16147800] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27126; }","duration":"184.763583ms","start":"2026-09-25T04:17:10.112536Z","end":"2026-09-25T04:17:10.2973Z","steps":["trace[16147800] 'agreement among raft nodes before linearized reading' (duration: 184.606539ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:17:10.297351Z","caller":"traceutil/trace.go:171","msg":"trace[1501647619] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:27126; }","duration":"376.083723ms","start":"2026-09-25T04:17:09.921256Z","end":"2026-09-25T04:17:10.297339Z","steps":["trace[1501647619] 'agreement among raft nodes before linearized reading' (duration: 375.840387ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:17:10.297349Z","caller":"traceutil/trace.go:171","msg":"trace[761201284] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27126; }","duration":"376.176705ms","start":"2026-09-25T04:17:09.921154Z","end":"2026-09-25T04:17:10.297331Z","steps":["trace[761201284] 'agreement among raft nodes before linearized reading' (duration: 375.886038ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:17:10.29738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:17:09.921239Z","time spent":"376.133833ms","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-09-25T04:17:10.29739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:17:09.921113Z","time spent":"376.266807ms","remote":"127.0.0.1:35188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-25T04:17:10.297303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:17:09.841585Z","time spent":"455.565249ms","remote":"127.0.0.1:35188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-25T04:17:10.511365Z","caller":"traceutil/trace.go:171","msg":"trace[472017762] transaction","detail":"{read_only:false; response_revision:27129; number_of_response:1; }","duration":"155.602067ms","start":"2026-09-25T04:17:10.355743Z","end":"2026-09-25T04:17:10.511345Z","steps":["trace[472017762] 'process raft request' (duration: 155.575416ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:17:10.511574Z","caller":"traceutil/trace.go:171","msg":"trace[1122116826] transaction","detail":"{read_only:false; response_revision:27128; number_of_response:1; }","duration":"157.07023ms","start":"2026-09-25T04:17:10.354462Z","end":"2026-09-25T04:17:10.511532Z","steps":["trace[1122116826] 'process raft request' (duration: 156.824455ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:17:10.511568Z","caller":"traceutil/trace.go:171","msg":"trace[1842869154] transaction","detail":"{read_only:false; response_revision:27127; number_of_response:1; }","duration":"210.113072ms","start":"2026-09-25T04:17:10.30138Z","end":"2026-09-25T04:17:10.511493Z","steps":["trace[1842869154] 'process raft request' (duration: 159.473484ms)","trace[1842869154] 'compare' (duration: 50.310791ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T04:17:10.511595Z","caller":"traceutil/trace.go:171","msg":"trace[1876541739] linearizableReadLoop","detail":"{readStateIndex:28033; appliedIndex:28032; }","duration":"209.605131ms","start":"2026-09-25T04:17:10.301961Z","end":"2026-09-25T04:17:10.511566Z","steps":["trace[1876541739] 'read index received' (duration: 158.905982ms)","trace[1876541739] 'applied index is now lower than readState.Index' (duration: 50.696049ms)"],"step_count":2} {"level":"warn","ts":"2026-09-25T04:17:10.51177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.787435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19030"} {"level":"info","ts":"2026-09-25T04:17:10.511804Z","caller":"traceutil/trace.go:171","msg":"trace[1932655408] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:27129; }","duration":"209.852026ms","start":"2026-09-25T04:17:10.301945Z","end":"2026-09-25T04:17:10.511797Z","steps":["trace[1932655408] 'agreement among raft nodes before linearized reading' (duration: 209.693063ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:17:10.51183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.418837ms","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-09-25T04:17:10.511864Z","caller":"traceutil/trace.go:171","msg":"trace[1670776892] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27129; }","duration":"157.494919ms","start":"2026-09-25T04:17:10.354361Z","end":"2026-09-25T04:17:10.511855Z","steps":["trace[1670776892] 'agreement among raft nodes before linearized reading' (duration: 157.425528ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:17:10.511933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.52905ms","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-09-25T04:17:10.511952Z","caller":"traceutil/trace.go:171","msg":"trace[1222559956] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27129; }","duration":"157.566751ms","start":"2026-09-25T04:17:10.35438Z","end":"2026-09-25T04:17:10.511947Z","steps":["trace[1222559956] 'agreement among raft nodes before linearized reading' (duration: 157.532741ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:17:10.511998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.317713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-09-25T04:17:10.511997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.805904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-09-25T04:17:10.512049Z","caller":"traceutil/trace.go:171","msg":"trace[360353985] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27129; }","duration":"156.391115ms","start":"2026-09-25T04:17:10.355642Z","end":"2026-09-25T04:17:10.512033Z","steps":["trace[360353985] 'agreement among raft nodes before linearized reading' (duration: 156.214701ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:17:10.512056Z","caller":"traceutil/trace.go:171","msg":"trace[1193729638] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27129; }","duration":"132.907136ms","start":"2026-09-25T04:17:10.379133Z","end":"2026-09-25T04:17:10.51204Z","steps":["trace[1193729638] 'agreement among raft nodes before linearized reading' (duration: 132.674471ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:17:10.771118Z","caller":"traceutil/trace.go:171","msg":"trace[317204471] linearizableReadLoop","detail":"{readStateIndex:28039; appliedIndex:28036; }","duration":"181.004578ms","start":"2026-09-25T04:17:10.590095Z","end":"2026-09-25T04:17:10.7711Z","steps":["trace[317204471] 'read index received' (duration: 164.382225ms)","trace[317204471] 'applied index is now lower than readState.Index' (duration: 16.621393ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T04:17:10.771142Z","caller":"traceutil/trace.go:171","msg":"trace[1358503329] transaction","detail":"{read_only:false; response_revision:27131; number_of_response:1; }","duration":"246.788118ms","start":"2026-09-25T04:17:10.524324Z","end":"2026-09-25T04:17:10.771112Z","steps":["trace[1358503329] 'process raft request' (duration: 230.117413ms)","trace[1358503329] 'compare' (duration: 16.49972ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T04:17:10.771223Z","caller":"traceutil/trace.go:171","msg":"trace[1008770154] transaction","detail":"{read_only:false; response_revision:27132; number_of_response:1; }","duration":"246.831738ms","start":"2026-09-25T04:17:10.524382Z","end":"2026-09-25T04:17:10.771214Z","steps":["trace[1008770154] 'process raft request' (duration: 246.658843ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:17:10.77133Z","caller":"traceutil/trace.go:171","msg":"trace[494162007] transaction","detail":"{read_only:false; response_revision:27133; number_of_response:1; }","duration":"246.891869ms","start":"2026-09-25T04:17:10.52443Z","end":"2026-09-25T04:17:10.771322Z","steps":["trace[494162007] 'process raft request' (duration: 246.641623ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:17:10.771422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.325645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-25T04:17:10.771444Z","caller":"traceutil/trace.go:171","msg":"trace[1942074085] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:27133; }","duration":"181.395787ms","start":"2026-09-25T04:17:10.590041Z","end":"2026-09-25T04:17:10.771437Z","steps":["trace[1942074085] 'agreement among raft nodes before linearized reading' (duration: 181.329365ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:17:10.842423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.757499ms","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:8"} {"level":"info","ts":"2026-09-25T04:17:10.84249Z","caller":"traceutil/trace.go:171","msg":"trace[218718616] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:27134; }","duration":"179.870482ms","start":"2026-09-25T04:17:10.662601Z","end":"2026-09-25T04:17:10.842472Z","steps":["trace[218718616] 'agreement among raft nodes before linearized reading' (duration: 179.76891ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:17:11.010653Z","caller":"traceutil/trace.go:171","msg":"trace[880616344] transaction","detail":"{read_only:false; response_revision:27135; number_of_response:1; }","duration":"145.526941ms","start":"2026-09-25T04:17:10.865102Z","end":"2026-09-25T04:17:11.010629Z","steps":["trace[880616344] 'process raft request' (duration: 54.979087ms)","trace[880616344] 'compare' (duration: 90.145085ms)"],"step_count":2} {"level":"warn","ts":"2026-09-25T04:17:16.266914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.410431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-25T04:17:16.26703Z","caller":"traceutil/trace.go:171","msg":"trace[95926085] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27168; }","duration":"154.537073ms","start":"2026-09-25T04:17:16.112468Z","end":"2026-09-25T04:17:16.267005Z","steps":["trace[95926085] 'range keys from in-memory index tree' (duration: 154.292698ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:17:16.436226Z","caller":"traceutil/trace.go:171","msg":"trace[2123997185] transaction","detail":"{read_only:false; response_revision:27169; number_of_response:1; }","duration":"120.763684ms","start":"2026-09-25T04:17:16.315412Z","end":"2026-09-25T04:17:16.436175Z","steps":["trace[2123997185] 'process raft request' (duration: 120.60193ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:17:16.488393Z","caller":"traceutil/trace.go:171","msg":"trace[1908035624] transaction","detail":"{read_only:false; response_revision:27170; number_of_response:1; }","duration":"168.542817ms","start":"2026-09-25T04:17:16.319824Z","end":"2026-09-25T04:17:16.488367Z","steps":["trace[1908035624] 'process raft request' (duration: 168.178369ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:17:16.488454Z","caller":"traceutil/trace.go:171","msg":"trace[1299797849] linearizableReadLoop","detail":"{readStateIndex:28078; appliedIndex:28076; }","duration":"102.729828ms","start":"2026-09-25T04:17:16.385704Z","end":"2026-09-25T04:17:16.488434Z","steps":["trace[1299797849] 'read index received' (duration: 50.532315ms)","trace[1299797849] 'applied index is now lower than readState.Index' (duration: 52.196433ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T04:17:16.488632Z","caller":"traceutil/trace.go:171","msg":"trace[1306786147] transaction","detail":"{read_only:false; response_revision:27171; number_of_response:1; }","duration":"167.492183ms","start":"2026-09-25T04:17:16.321125Z","end":"2026-09-25T04:17:16.488617Z","steps":["trace[1306786147] 'process raft request' (duration: 167.015002ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:17:16.488781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.888012ms","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-09-25T04:17:16.488819Z","caller":"traceutil/trace.go:171","msg":"trace[368381691] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:27171; }","duration":"103.169249ms","start":"2026-09-25T04:17:16.385635Z","end":"2026-09-25T04:17:16.488805Z","steps":["trace[368381691] 'agreement among raft nodes before linearized reading' (duration: 102.892722ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:17:27.071899Z","caller":"traceutil/trace.go:171","msg":"trace[635881108] linearizableReadLoop","detail":"{readStateIndex:28159; appliedIndex:28158; }","duration":"223.499802ms","start":"2026-09-25T04:17:26.84837Z","end":"2026-09-25T04:17:27.07187Z","steps":["trace[635881108] 'read index received' (duration: 223.268707ms)","trace[635881108] 'applied index is now lower than readState.Index' (duration: 229.105µs)"],"step_count":2} {"level":"info","ts":"2026-09-25T04:17:27.071985Z","caller":"traceutil/trace.go:171","msg":"trace[2000885343] transaction","detail":"{read_only:false; response_revision:27250; number_of_response:1; }","duration":"228.498175ms","start":"2026-09-25T04:17:26.843439Z","end":"2026-09-25T04:17:27.071938Z","steps":["trace[2000885343] 'process raft request' (duration: 228.238139ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:17:27.072137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.744117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-09-25T04:17:27.072183Z","caller":"traceutil/trace.go:171","msg":"trace[1717845306] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27250; }","duration":"223.83693ms","start":"2026-09-25T04:17:26.848332Z","end":"2026-09-25T04:17:27.072168Z","steps":["trace[1717845306] 'agreement among raft nodes before linearized reading' (duration: 223.667746ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:17:27.294106Z","caller":"traceutil/trace.go:171","msg":"trace[1664012642] linearizableReadLoop","detail":"{readStateIndex:28160; appliedIndex:28159; }","duration":"222.111633ms","start":"2026-09-25T04:17:27.071965Z","end":"2026-09-25T04:17:27.294076Z","steps":["trace[1664012642] 'read index received' (duration: 174.687837ms)","trace[1664012642] 'applied index is now lower than readState.Index' (duration: 47.422416ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T04:17:27.294165Z","caller":"traceutil/trace.go:171","msg":"trace[544992976] transaction","detail":"{read_only:false; response_revision:27251; number_of_response:1; }","duration":"386.69659ms","start":"2026-09-25T04:17:26.907432Z","end":"2026-09-25T04:17:27.294129Z","steps":["trace[544992976] 'process raft request' (duration: 339.06887ms)","trace[544992976] 'compare' (duration: 47.200301ms)"],"step_count":2} {"level":"warn","ts":"2026-09-25T04:17:27.294352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:17:26.90741Z","time spent":"386.844854ms","remote":"127.0.0.1:35188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-25T04:17:27.294403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.088094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:561"} {"level":"warn","ts":"2026-09-25T04:17:27.294405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.784858ms","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-09-25T04:17:27.294447Z","caller":"traceutil/trace.go:171","msg":"trace[1295711522] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:27251; }","duration":"401.202917ms","start":"2026-09-25T04:17:26.893229Z","end":"2026-09-25T04:17:27.294432Z","steps":["trace[1295711522] 'agreement among raft nodes before linearized reading' (duration: 401.007373ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:17:27.294463Z","caller":"traceutil/trace.go:171","msg":"trace[1820363916] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27251; }","duration":"440.874629ms","start":"2026-09-25T04:17:26.853576Z","end":"2026-09-25T04:17:27.294451Z","steps":["trace[1820363916] 'agreement among raft nodes before linearized reading' (duration: 440.666935ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:17:27.294481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:17:26.893182Z","time spent":"401.289699ms","remote":"127.0.0.1:35188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":584,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-09-25T04:17:27.2945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:17:26.853567Z","time spent":"440.92677ms","remote":"127.0.0.1:35188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-09-25T04:17:27.343267Z","caller":"traceutil/trace.go:171","msg":"trace[704523830] transaction","detail":"{read_only:false; response_revision:27252; number_of_response:1; }","duration":"265.320333ms","start":"2026-09-25T04:17:27.077914Z","end":"2026-09-25T04:17:27.343234Z","steps":["trace[704523830] 'process raft request' (duration: 265.042186ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:17:27.343327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.220004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-25T04:17:27.34335Z","caller":"traceutil/trace.go:171","msg":"trace[1794412309] transaction","detail":"{read_only:false; response_revision:27253; number_of_response:1; }","duration":"247.560243ms","start":"2026-09-25T04:17:27.095752Z","end":"2026-09-25T04:17:27.343312Z","steps":["trace[1794412309] 'process raft request' (duration: 247.384379ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:17:27.343415Z","caller":"traceutil/trace.go:171","msg":"trace[1515074015] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27253; }","duration":"230.377307ms","start":"2026-09-25T04:17:27.113016Z","end":"2026-09-25T04:17:27.343394Z","steps":["trace[1515074015] 'agreement among raft nodes before linearized reading' (duration: 230.202763ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:17:27.415978Z","caller":"traceutil/trace.go:171","msg":"trace[74242483] transaction","detail":"{read_only:false; response_revision:27254; number_of_response:1; }","duration":"116.951579ms","start":"2026-09-25T04:17:27.299002Z","end":"2026-09-25T04:17:27.415954Z","steps":["trace[74242483] 'process raft request' (duration: 116.142281ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:17:27.416292Z","caller":"traceutil/trace.go:171","msg":"trace[1786310426] transaction","detail":"{read_only:false; response_revision:27255; number_of_response:1; }","duration":"117.215904ms","start":"2026-09-25T04:17:27.299024Z","end":"2026-09-25T04:17:27.41624Z","steps":["trace[1786310426] 'process raft request' (duration: 116.878527ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:17:28.228978Z","caller":"traceutil/trace.go:171","msg":"trace[269061742] linearizableReadLoop","detail":"{readStateIndex:28165; appliedIndex:28164; }","duration":"115.355822ms","start":"2026-09-25T04:17:28.113599Z","end":"2026-09-25T04:17:28.228955Z","steps":["trace[269061742] 'read index received' (duration: 115.178058ms)","trace[269061742] 'applied index is now lower than readState.Index' (duration: 176.814µs)"],"step_count":2} {"level":"info","ts":"2026-09-25T04:17:28.2291Z","caller":"traceutil/trace.go:171","msg":"trace[1873026963] transaction","detail":"{read_only:false; response_revision:27256; number_of_response:1; }","duration":"175.804841ms","start":"2026-09-25T04:17:28.053266Z","end":"2026-09-25T04:17:28.229071Z","steps":["trace[1873026963] 'process raft request' (duration: 175.508554ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:17:28.229151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.526707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-25T04:17:28.229231Z","caller":"traceutil/trace.go:171","msg":"trace[1950200367] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27256; }","duration":"115.643489ms","start":"2026-09-25T04:17:28.113548Z","end":"2026-09-25T04:17:28.229191Z","steps":["trace[1950200367] 'agreement among raft nodes before linearized reading' (duration: 115.505556ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:17:39.457749Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24477} {"level":"info","ts":"2026-09-25T04:17:39.60684Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24477,"took":"142.237387ms","hash":876106763,"current-db-size-bytes":59428864,"current-db-size":"59 MB","current-db-size-in-use-bytes":37273600,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-09-25T04:17:39.606907Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":876106763,"revision":24477,"compact-revision":21654} {"level":"warn","ts":"2026-09-25T04:17:49.528389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.670081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-25T04:17:49.528509Z","caller":"traceutil/trace.go:171","msg":"trace[1670455319] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:27569; }","duration":"281.803124ms","start":"2026-09-25T04:17:49.246675Z","end":"2026-09-25T04:17:49.528478Z","steps":["trace[1670455319] 'range keys from in-memory index tree' (duration: 281.576729ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:17:49.528733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.819144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-25T04:17:49.528838Z","caller":"traceutil/trace.go:171","msg":"trace[2063099024] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:0; response_revision:27569; }","duration":"281.996639ms","start":"2026-09-25T04:17:49.246816Z","end":"2026-09-25T04:17:49.528812Z","steps":["trace[2063099024] 'range keys from in-memory index tree' (duration: 281.759803ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:17:49.529093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.349446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-25T04:17:49.529174Z","caller":"traceutil/trace.go:171","msg":"trace[174232729] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:27569; }","duration":"282.463408ms","start":"2026-09-25T04:17:49.246692Z","end":"2026-09-25T04:17:49.529155Z","steps":["trace[174232729] 'range keys from in-memory index tree' (duration: 282.251714ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:17:49.52937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.991019ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-25T04:17:49.529509Z","caller":"traceutil/trace.go:171","msg":"trace[543933423] transaction","detail":"{read_only:false; response_revision:27570; number_of_response:1; }","duration":"152.254452ms","start":"2026-09-25T04:17:49.37723Z","end":"2026-09-25T04:17:49.529485Z","steps":["trace[543933423] 'process raft request' (duration: 39.027647ms)","trace[543933423] 'compare' (duration: 112.313254ms)"],"step_count":2} {"level":"warn","ts":"2026-09-25T04:17:49.528307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.579839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-25T04:17:49.529628Z","caller":"traceutil/trace.go:171","msg":"trace[1499084976] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:27569; }","duration":"282.96559ms","start":"2026-09-25T04:17:49.24664Z","end":"2026-09-25T04:17:49.529606Z","steps":["trace[1499084976] 'range keys from in-memory index tree' (duration: 281.450096ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:17:49.758403Z","caller":"traceutil/trace.go:171","msg":"trace[1609024277] linearizableReadLoop","detail":"{readStateIndex:28488; appliedIndex:28487; }","duration":"298.751205ms","start":"2026-09-25T04:17:49.459627Z","end":"2026-09-25T04:17:49.758378Z","steps":["trace[1609024277] 'read index received' (duration: 298.346836ms)","trace[1609024277] 'applied index is now lower than readState.Index' (duration: 403.419µs)"],"step_count":2} {"level":"info","ts":"2026-09-25T04:17:49.758484Z","caller":"traceutil/trace.go:171","msg":"trace[1745254947] transaction","detail":"{read_only:false; response_revision:27571; number_of_response:1; }","duration":"328.816111ms","start":"2026-09-25T04:17:49.429632Z","end":"2026-09-25T04:17:49.758448Z","steps":["trace[1745254947] 'process raft request' (duration: 328.514674ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:17:49.758646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:17:49.429608Z","time spent":"328.952164ms","remote":"127.0.0.1:35188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-25T04:17:49.758737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.05183ms","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-09-25T04:17:49.758795Z","caller":"traceutil/trace.go:171","msg":"trace[1379181385] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27571; }","duration":"209.170062ms","start":"2026-09-25T04:17:49.54961Z","end":"2026-09-25T04:17:49.75878Z","steps":["trace[1379181385] 'agreement among raft nodes before linearized reading' (duration: 208.980418ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:17:49.758819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.157974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-09-25T04:17:49.75892Z","caller":"traceutil/trace.go:171","msg":"trace[1074535319] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27571; }","duration":"299.308258ms","start":"2026-09-25T04:17:49.459586Z","end":"2026-09-25T04:17:49.758894Z","steps":["trace[1074535319] 'agreement among raft nodes before linearized reading' (duration: 298.988071ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:17:49.961788Z","caller":"traceutil/trace.go:171","msg":"trace[979409013] transaction","detail":"{read_only:false; response_revision:27573; number_of_response:1; }","duration":"196.736291ms","start":"2026-09-25T04:17:49.765027Z","end":"2026-09-25T04:17:49.961763Z","steps":["trace[979409013] 'process raft request' (duration: 196.6774ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:17:49.961825Z","caller":"traceutil/trace.go:171","msg":"trace[278400252] transaction","detail":"{read_only:false; response_revision:27572; number_of_response:1; }","duration":"197.076749ms","start":"2026-09-25T04:17:49.764699Z","end":"2026-09-25T04:17:49.961776Z","steps":["trace[278400252] 'process raft request' (duration: 105.655575ms)","trace[278400252] 'compare' (duration: 91.194619ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T04:20:27.725396Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"94bfbb0e034a3ac2","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-25T04:20:27.733121Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-09-25T04:20:27.7336Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-09-25T04:22:39.467056Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27354} {"level":"info","ts":"2026-09-25T04:22:39.602507Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27354,"took":"130.478414ms","hash":665008581,"current-db-size-bytes":60694528,"current-db-size":"61 MB","current-db-size-in-use-bytes":37867520,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-09-25T04:22:39.602601Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":665008581,"revision":27354,"compact-revision":24477} {"level":"info","ts":"2026-09-25T04:27:39.474672Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30228} {"level":"info","ts":"2026-09-25T04:27:39.622581Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30228,"took":"143.757422ms","hash":2592115809,"current-db-size-bytes":60694528,"current-db-size":"61 MB","current-db-size-in-use-bytes":30740480,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-25T04:27:39.622665Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2592115809,"revision":30228,"compact-revision":27354} {"level":"info","ts":"2026-09-25T04:32:39.482389Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32486} {"level":"info","ts":"2026-09-25T04:32:39.652578Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32486,"took":"165.633834ms","hash":488633022,"current-db-size-bytes":61734912,"current-db-size":"62 MB","current-db-size-in-use-bytes":30330880,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-25T04:32:39.652651Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":488633022,"revision":32486,"compact-revision":30228} {"level":"info","ts":"2026-09-25T04:37:39.490188Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34711} {"level":"info","ts":"2026-09-25T04:37:39.622393Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34711,"took":"128.263594ms","hash":10616633,"current-db-size-bytes":61734912,"current-db-size":"62 MB","current-db-size-in-use-bytes":30408704,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-25T04:37:39.622462Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":10616633,"revision":34711,"compact-revision":32486} {"level":"info","ts":"2026-09-25T04:38:24.59473Z","caller":"traceutil/trace.go:171","msg":"trace[1979064705] transaction","detail":"{read_only:false; response_revision:37335; number_of_response:1; }","duration":"167.848714ms","start":"2026-09-25T04:38:24.426848Z","end":"2026-09-25T04:38:24.594697Z","steps":["trace[1979064705] 'process raft request' (duration: 85.236726ms)","trace[1979064705] 'compare' (duration: 82.334731ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T04:41:28.862038Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"94bfbb0e034a3ac2","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-25T04:41:28.868994Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-09-25T04:41:28.86911Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-09-25T04:42:39.498495Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":36946} {"level":"info","ts":"2026-09-25T04:42:39.633939Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":36946,"took":"131.250381ms","hash":1228165829,"current-db-size-bytes":61734912,"current-db-size":"62 MB","current-db-size-in-use-bytes":32616448,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-09-25T04:42:39.634014Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1228165829,"revision":36946,"compact-revision":34711} {"level":"info","ts":"2026-09-25T04:47:39.508059Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39288} {"level":"info","ts":"2026-09-25T04:47:39.640366Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39288,"took":"127.322662ms","hash":2283602955,"current-db-size-bytes":63430656,"current-db-size":"63 MB","current-db-size-in-use-bytes":32133120,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-25T04:47:39.640458Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2283602955,"revision":39288,"compact-revision":36946}