{"level":"warn","ts":"2026-10-04T01:21:39.389175Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-04T01:21:39.389335Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.69: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.69:2380","--initial-cluster=instance=https://162.253.55.69:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.69:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.69:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-10-04T01:21:39.389473Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-04T01:21:39.389504Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.69:2380"]} {"level":"info","ts":"2026-10-04T01:21:39.389538Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-04T01:21:39.390914Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.69:2379"]} {"level":"info","ts":"2026-10-04T01:21:39.391058Z","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.69:2380"],"listen-peer-urls":["https://162.253.55.69:2380"],"advertise-client-urls":["https://162.253.55.69:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.69:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.69:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-10-04T01:21:39.396389Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.896333ms"} {"level":"info","ts":"2026-10-04T01:21:39.404164Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"905ccde8a677f143","cluster-id":"6fd07fe3af558460"} {"level":"info","ts":"2026-10-04T01:21:39.404535Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"905ccde8a677f143 switched to configuration voters=()"} {"level":"info","ts":"2026-10-04T01:21:39.404644Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"905ccde8a677f143 became follower at term 0"} {"level":"info","ts":"2026-10-04T01:21:39.404808Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 905ccde8a677f143 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-04T01:21:39.405106Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"905ccde8a677f143 became follower at term 1"} {"level":"info","ts":"2026-10-04T01:21:39.405271Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"905ccde8a677f143 switched to configuration voters=(10402415638427988291)"} {"level":"warn","ts":"2026-10-04T01:21:39.416957Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-04T01:21:39.419867Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-04T01:21:39.424961Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-10-04T01:21:39.428534Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"905ccde8a677f143","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-04T01:21:39.428771Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"905ccde8a677f143","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-04T01:21:39.429021Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-04T01:21:39.42917Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-04T01:21:39.429211Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-04T01:21:39.43053Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"905ccde8a677f143 switched to configuration voters=(10402415638427988291)"} {"level":"info","ts":"2026-10-04T01:21:39.430692Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6fd07fe3af558460","local-member-id":"905ccde8a677f143","added-peer-id":"905ccde8a677f143","added-peer-peer-urls":["https://162.253.55.69:2380"]} {"level":"info","ts":"2026-10-04T01:21:39.43687Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-04T01:21:39.43693Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.69:2380"} {"level":"info","ts":"2026-10-04T01:21:39.437137Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.69:2380"} {"level":"info","ts":"2026-10-04T01:21:39.438601Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"905ccde8a677f143","initial-advertise-peer-urls":["https://162.253.55.69:2380"],"listen-peer-urls":["https://162.253.55.69:2380"],"advertise-client-urls":["https://162.253.55.69:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.69:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-04T01:21:39.438665Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-04T01:21:39.606361Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"905ccde8a677f143 is starting a new election at term 1"} {"level":"info","ts":"2026-10-04T01:21:39.606481Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"905ccde8a677f143 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-04T01:21:39.60651Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"905ccde8a677f143 received MsgPreVoteResp from 905ccde8a677f143 at term 1"} {"level":"info","ts":"2026-10-04T01:21:39.606534Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"905ccde8a677f143 became candidate at term 2"} {"level":"info","ts":"2026-10-04T01:21:39.606574Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"905ccde8a677f143 received MsgVoteResp from 905ccde8a677f143 at term 2"} {"level":"info","ts":"2026-10-04T01:21:39.606595Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"905ccde8a677f143 became leader at term 2"} {"level":"info","ts":"2026-10-04T01:21:39.606615Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 905ccde8a677f143 elected leader 905ccde8a677f143 at term 2"} {"level":"info","ts":"2026-10-04T01:21:39.607985Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"905ccde8a677f143","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.69:2379]}","request-path":"/0/members/905ccde8a677f143/attributes","cluster-id":"6fd07fe3af558460","publish-timeout":"7s"} {"level":"info","ts":"2026-10-04T01:21:39.608035Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-04T01:21:39.60858Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-04T01:21:39.608766Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-04T01:21:39.609199Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-04T01:21:39.609262Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-04T01:21:39.610085Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6fd07fe3af558460","local-member-id":"905ccde8a677f143","cluster-version":"3.5"} {"level":"info","ts":"2026-10-04T01:21:39.6102Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-04T01:21:39.610238Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-04T01:21:39.611099Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.69:2379"} {"level":"info","ts":"2026-10-04T01:21:39.611711Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-04T01:21:59.826362Z","caller":"traceutil/trace.go:171","msg":"trace[876244670] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"116.528611ms","start":"2026-10-04T01:21:59.709808Z","end":"2026-10-04T01:21:59.826336Z","steps":["trace[876244670] 'process raft request' (duration: 114.811927ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T01:22:24.807097Z","caller":"traceutil/trace.go:171","msg":"trace[124608254] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"152.911583ms","start":"2026-10-04T01:22:24.654151Z","end":"2026-10-04T01:22:24.807062Z","steps":["trace[124608254] 'process raft request' (duration: 151.814203ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T01:22:25.559891Z","caller":"traceutil/trace.go:171","msg":"trace[239651672] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"145.108663ms","start":"2026-10-04T01:22:25.414752Z","end":"2026-10-04T01:22:25.55986Z","steps":["trace[239651672] 'process raft request' (duration: 144.468911ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T01:22:25.565701Z","caller":"traceutil/trace.go:171","msg":"trace[116170697] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"136.590341ms","start":"2026-10-04T01:22:25.429091Z","end":"2026-10-04T01:22:25.565681Z","steps":["trace[116170697] 'process raft request' (duration: 136.490619ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T01:22:41.057537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.649227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-04T01:22:41.05765Z","caller":"traceutil/trace.go:171","msg":"trace[2025598085] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:767; }","duration":"156.875822ms","start":"2026-10-04T01:22:40.900756Z","end":"2026-10-04T01:22:41.057632Z","steps":["trace[2025598085] 'range keys from in-memory index tree' (duration: 156.432444ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T01:23:24.745968Z","caller":"traceutil/trace.go:171","msg":"trace[845659181] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"109.826157ms","start":"2026-10-04T01:23:24.636117Z","end":"2026-10-04T01:23:24.745944Z","steps":["trace[845659181] 'process raft request' (duration: 109.661114ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T01:23:57.059495Z","caller":"traceutil/trace.go:171","msg":"trace[815048726] linearizableReadLoop","detail":"{readStateIndex:1907; appliedIndex:1906; }","duration":"181.198961ms","start":"2026-10-04T01:23:56.878236Z","end":"2026-10-04T01:23:57.059435Z","steps":["trace[815048726] 'read index received' (duration: 180.877455ms)","trace[815048726] 'applied index is now lower than readState.Index' (duration: 320.276µs)"],"step_count":2} {"level":"info","ts":"2026-10-04T01:23:57.059786Z","caller":"traceutil/trace.go:171","msg":"trace[1550867909] transaction","detail":"{read_only:false; response_revision:1828; number_of_response:1; }","duration":"235.944615ms","start":"2026-10-04T01:23:56.82382Z","end":"2026-10-04T01:23:57.059765Z","steps":["trace[1550867909] 'process raft request' (duration: 235.430135ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T01:23:57.060043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.778002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-10-04T01:23:57.060118Z","caller":"traceutil/trace.go:171","msg":"trace[870236828] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1828; }","duration":"181.927435ms","start":"2026-10-04T01:23:56.87817Z","end":"2026-10-04T01:23:57.060098Z","steps":["trace[870236828] 'agreement among raft nodes before linearized reading' (duration: 181.64966ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T01:23:57.060193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.50403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-04T01:23:57.060238Z","caller":"traceutil/trace.go:171","msg":"trace[375383460] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1828; }","duration":"159.585912ms","start":"2026-10-04T01:23:56.900636Z","end":"2026-10-04T01:23:57.060222Z","steps":["trace[375383460] 'agreement among raft nodes before linearized reading' (duration: 159.526291ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T01:23:57.060111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.983622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-04T01:23:57.060604Z","caller":"traceutil/trace.go:171","msg":"trace[695698323] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1828; }","duration":"168.592574ms","start":"2026-10-04T01:23:56.89199Z","end":"2026-10-04T01:23:57.060582Z","steps":["trace[695698323] 'agreement among raft nodes before linearized reading' (duration: 168.047353ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T01:24:46.711147Z","caller":"traceutil/trace.go:171","msg":"trace[1423648848] transaction","detail":"{read_only:false; response_revision:2148; number_of_response:1; }","duration":"118.567299ms","start":"2026-10-04T01:24:46.592558Z","end":"2026-10-04T01:24:46.711125Z","steps":["trace[1423648848] 'process raft request' (duration: 118.213882ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T01:24:46.990242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.051527ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-04T01:24:46.990498Z","caller":"traceutil/trace.go:171","msg":"trace[1591016462] linearizableReadLoop","detail":"{readStateIndex:2239; appliedIndex:2237; }","duration":"250.509706ms","start":"2026-10-04T01:24:46.739922Z","end":"2026-10-04T01:24:46.990432Z","steps":["trace[1591016462] 'read index received' (duration: 100.151155ms)","trace[1591016462] 'applied index is now lower than readState.Index' (duration: 150.356851ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T01:24:46.990635Z","caller":"traceutil/trace.go:171","msg":"trace[1520018981] transaction","detail":"{read_only:false; response_revision:2150; number_of_response:1; }","duration":"274.524373ms","start":"2026-10-04T01:24:46.7161Z","end":"2026-10-04T01:24:46.990624Z","steps":["trace[1520018981] 'process raft request' (duration: 274.267669ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T01:24:46.990782Z","caller":"traceutil/trace.go:171","msg":"trace[1458635746] transaction","detail":"{read_only:false; response_revision:2149; number_of_response:1; }","duration":"275.115404ms","start":"2026-10-04T01:24:46.715624Z","end":"2026-10-04T01:24:46.99074Z","steps":["trace[1458635746] 'process raft request' (duration: 124.435977ms)","trace[1458635746] 'compare' (duration: 148.868323ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T01:24:46.990938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.000015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"warn","ts":"2026-10-04T01:24:46.990944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.977717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-04T01:24:46.991007Z","caller":"traceutil/trace.go:171","msg":"trace[395309751] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2150; }","duration":"251.095967ms","start":"2026-10-04T01:24:46.73989Z","end":"2026-10-04T01:24:46.990986Z","steps":["trace[395309751] 'agreement among raft nodes before linearized reading' (duration: 250.877793ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T01:24:46.991033Z","caller":"traceutil/trace.go:171","msg":"trace[353268171] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2150; }","duration":"184.1407ms","start":"2026-10-04T01:24:46.806875Z","end":"2026-10-04T01:24:46.991016Z","steps":["trace[353268171] 'agreement among raft nodes before linearized reading' (duration: 183.906576ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T01:30:39.066385Z","caller":"traceutil/trace.go:171","msg":"trace[2092273773] linearizableReadLoop","detail":"{readStateIndex:5333; appliedIndex:5332; }","duration":"111.537235ms","start":"2026-10-04T01:30:38.954802Z","end":"2026-10-04T01:30:39.06634Z","steps":["trace[2092273773] 'read index received' (duration: 111.27447ms)","trace[2092273773] 'applied index is now lower than readState.Index' (duration: 261.735µs)"],"step_count":2} {"level":"warn","ts":"2026-10-04T01:30:39.066663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.83647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-10-04T01:30:39.066707Z","caller":"traceutil/trace.go:171","msg":"trace[140902432] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5131; }","duration":"111.931122ms","start":"2026-10-04T01:30:38.954763Z","end":"2026-10-04T01:30:39.066694Z","steps":["trace[140902432] 'agreement among raft nodes before linearized reading' (duration: 111.727409ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T01:30:39.067118Z","caller":"traceutil/trace.go:171","msg":"trace[1155600941] transaction","detail":"{read_only:false; response_revision:5131; number_of_response:1; }","duration":"121.546003ms","start":"2026-10-04T01:30:38.945561Z","end":"2026-10-04T01:30:39.067107Z","steps":["trace[1155600941] 'process raft request' (duration: 120.622035ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T01:30:39.276161Z","caller":"traceutil/trace.go:171","msg":"trace[367555035] linearizableReadLoop","detail":"{readStateIndex:5334; appliedIndex:5333; }","duration":"187.069793ms","start":"2026-10-04T01:30:39.08906Z","end":"2026-10-04T01:30:39.276129Z","steps":["trace[367555035] 'read index received' (duration: 186.830639ms)","trace[367555035] 'applied index is now lower than readState.Index' (duration: 237.974µs)"],"step_count":2} {"level":"info","ts":"2026-10-04T01:30:39.276292Z","caller":"traceutil/trace.go:171","msg":"trace[995898877] transaction","detail":"{read_only:false; response_revision:5132; number_of_response:1; }","duration":"192.428053ms","start":"2026-10-04T01:30:39.083849Z","end":"2026-10-04T01:30:39.276277Z","steps":["trace[995898877] 'process raft request' (duration: 192.121337ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T01:30:39.276515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.030697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-10-04T01:30:39.276606Z","caller":"traceutil/trace.go:171","msg":"trace[1351868837] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5132; }","duration":"117.147488ms","start":"2026-10-04T01:30:39.159435Z","end":"2026-10-04T01:30:39.276583Z","steps":["trace[1351868837] 'agreement among raft nodes before linearized reading' (duration: 116.895234ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T01:30:39.276822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.740735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:413950"} {"level":"info","ts":"2026-10-04T01:30:39.27688Z","caller":"traceutil/trace.go:171","msg":"trace[1172187173] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:5132; }","duration":"187.847347ms","start":"2026-10-04T01:30:39.089018Z","end":"2026-10-04T01:30:39.276865Z","steps":["trace[1172187173] 'agreement among raft nodes before linearized reading' (duration: 187.584153ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T01:30:54.619075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.671579ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-04T01:30:54.619252Z","caller":"traceutil/trace.go:171","msg":"trace[1060684833] transaction","detail":"{read_only:false; response_revision:5401; number_of_response:1; }","duration":"315.667027ms","start":"2026-10-04T01:30:54.30356Z","end":"2026-10-04T01:30:54.619227Z","steps":["trace[1060684833] 'process raft request' (duration: 107.763053ms)","trace[1060684833] 'compare' (duration: 207.514516ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T01:30:54.619308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T01:30:54.303494Z","time spent":"315.792871ms","remote":"127.0.0.1:56160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":750,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-04T01:31:03.680207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.845914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-10-04T01:31:03.680274Z","caller":"traceutil/trace.go:171","msg":"trace[1240260023] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:5578; }","duration":"100.982936ms","start":"2026-10-04T01:31:03.579277Z","end":"2026-10-04T01:31:03.68026Z","steps":["trace[1240260023] 'range keys from in-memory index tree' (duration: 100.750512ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T01:31:03.744041Z","caller":"traceutil/trace.go:171","msg":"trace[815441034] transaction","detail":"{read_only:false; response_revision:5579; number_of_response:1; }","duration":"124.039379ms","start":"2026-10-04T01:31:03.619976Z","end":"2026-10-04T01:31:03.744016Z","steps":["trace[815441034] 'process raft request' (duration: 123.742693ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T01:31:05.44771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.336383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-023acc1f-4a21-4a62-bfdc-da7df6442994\" ","response":"range_response_count:1 size:3935"} {"level":"info","ts":"2026-10-04T01:31:05.447793Z","caller":"traceutil/trace.go:171","msg":"trace[1077088893] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-023acc1f-4a21-4a62-bfdc-da7df6442994; range_end:; response_count:1; response_revision:5590; }","duration":"158.461434ms","start":"2026-10-04T01:31:05.289309Z","end":"2026-10-04T01:31:05.44777Z","steps":["trace[1077088893] 'range keys from in-memory index tree' (duration: 157.875333ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T01:31:39.881479Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2964} {"level":"info","ts":"2026-10-04T01:31:39.959202Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2964,"took":"76.119659ms","hash":2305755020,"current-db-size-bytes":57425920,"current-db-size":"57 MB","current-db-size-in-use-bytes":32698368,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-04T01:31:39.959273Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2305755020,"revision":2964,"compact-revision":-1} {"level":"info","ts":"2026-10-04T01:31:56.199018Z","caller":"traceutil/trace.go:171","msg":"trace[1585964974] transaction","detail":"{read_only:false; response_revision:6092; number_of_response:1; }","duration":"103.190067ms","start":"2026-10-04T01:31:56.095785Z","end":"2026-10-04T01:31:56.198976Z","steps":["trace[1585964974] 'compare' (duration: 93.035106ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T01:31:56.520329Z","caller":"traceutil/trace.go:171","msg":"trace[948326855] transaction","detail":"{read_only:false; response_revision:6094; number_of_response:1; }","duration":"124.334033ms","start":"2026-10-04T01:31:56.395969Z","end":"2026-10-04T01:31:56.520303Z","steps":["trace[948326855] 'process raft request' (duration: 124.16089ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T01:31:56.561883Z","caller":"traceutil/trace.go:171","msg":"trace[1950999758] transaction","detail":"{read_only:false; response_revision:6095; number_of_response:1; }","duration":"124.025668ms","start":"2026-10-04T01:31:56.437795Z","end":"2026-10-04T01:31:56.561821Z","steps":["trace[1950999758] 'process raft request' (duration: 112.42782ms)","trace[1950999758] 'compare' (duration: 11.014747ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T01:36:39.891062Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5895} {"level":"info","ts":"2026-10-04T01:36:39.991183Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5895,"took":"97.985976ms","hash":2350010130,"current-db-size-bytes":57425920,"current-db-size":"57 MB","current-db-size-in-use-bytes":25980928,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-04T01:36:39.991246Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2350010130,"revision":5895,"compact-revision":2964} {"level":"info","ts":"2026-10-04T01:37:25.323999Z","caller":"traceutil/trace.go:171","msg":"trace[658677341] transaction","detail":"{read_only:false; response_revision:8488; number_of_response:1; }","duration":"210.442535ms","start":"2026-10-04T01:37:25.113516Z","end":"2026-10-04T01:37:25.323959Z","steps":["trace[658677341] 'process raft request' (duration: 210.089138ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T01:37:26.083888Z","caller":"traceutil/trace.go:171","msg":"trace[1434942985] linearizableReadLoop","detail":"{readStateIndex:8805; appliedIndex:8804; }","duration":"100.82951ms","start":"2026-10-04T01:37:25.983031Z","end":"2026-10-04T01:37:26.08386Z","steps":["trace[1434942985] 'read index received' (duration: 100.494594ms)","trace[1434942985] 'applied index is now lower than readState.Index' (duration: 333.526µs)"],"step_count":2} {"level":"info","ts":"2026-10-04T01:37:26.08395Z","caller":"traceutil/trace.go:171","msg":"trace[142815599] transaction","detail":"{read_only:false; response_revision:8495; number_of_response:1; }","duration":"156.538379ms","start":"2026-10-04T01:37:25.927385Z","end":"2026-10-04T01:37:26.083924Z","steps":["trace[142815599] 'process raft request' (duration: 156.245804ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T01:37:26.084186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.137126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-10-04T01:37:26.084271Z","caller":"traceutil/trace.go:171","msg":"trace[1093297030] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8495; }","duration":"101.292448ms","start":"2026-10-04T01:37:25.982955Z","end":"2026-10-04T01:37:26.084248Z","steps":["trace[1093297030] 'agreement among raft nodes before linearized reading' (duration: 101.041264ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T01:38:10.460151Z","caller":"traceutil/trace.go:171","msg":"trace[1371107192] transaction","detail":"{read_only:false; response_revision:8826; number_of_response:1; }","duration":"171.637493ms","start":"2026-10-04T01:38:10.288465Z","end":"2026-10-04T01:38:10.460102Z","steps":["trace[1371107192] 'process raft request' (duration: 165.872505ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T01:38:10.468702Z","caller":"traceutil/trace.go:171","msg":"trace[1942104189] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8827; }","duration":"162.498262ms","start":"2026-10-04T01:38:10.306156Z","end":"2026-10-04T01:38:10.468654Z","steps":["trace[1942104189] 'process raft request' (duration: 162.310078ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T01:38:21.6716Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002418.wal"} {"level":"info","ts":"2026-10-04T01:39:36.264344Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"905ccde8a677f143","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-04T01:39:36.272268Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-04T01:39:36.272402Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-04T01:41:39.908394Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8077} {"level":"info","ts":"2026-10-04T01:41:39.981523Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8077,"took":"70.280097ms","hash":2813327843,"current-db-size-bytes":57425920,"current-db-size":"57 MB","current-db-size-in-use-bytes":28692480,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-04T01:41:39.981615Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2813327843,"revision":8077,"compact-revision":5895} {"level":"info","ts":"2026-10-04T01:43:07.489099Z","caller":"traceutil/trace.go:171","msg":"trace[1221704485] linearizableReadLoop","detail":"{readStateIndex:11803; appliedIndex:11802; }","duration":"119.864687ms","start":"2026-10-04T01:43:07.369208Z","end":"2026-10-04T01:43:07.489073Z","steps":["trace[1221704485] 'read index received' (duration: 119.591742ms)","trace[1221704485] 'applied index is now lower than readState.Index' (duration: 271.925µs)"],"step_count":2} {"level":"info","ts":"2026-10-04T01:43:07.489179Z","caller":"traceutil/trace.go:171","msg":"trace[1492710637] transaction","detail":"{read_only:false; response_revision:11405; number_of_response:1; }","duration":"147.881314ms","start":"2026-10-04T01:43:07.341256Z","end":"2026-10-04T01:43:07.489137Z","steps":["trace[1492710637] 'process raft request' (duration: 147.623569ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T01:43:07.489313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.07635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-04T01:43:07.489365Z","caller":"traceutil/trace.go:171","msg":"trace[1280326716] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:11405; }","duration":"120.192693ms","start":"2026-10-04T01:43:07.369158Z","end":"2026-10-04T01:43:07.489351Z","steps":["trace[1280326716] 'agreement among raft nodes before linearized reading' (duration: 120.073511ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T01:43:07.63813Z","caller":"traceutil/trace.go:171","msg":"trace[2010925196] transaction","detail":"{read_only:false; response_revision:11408; number_of_response:1; }","duration":"140.665078ms","start":"2026-10-04T01:43:07.497443Z","end":"2026-10-04T01:43:07.638108Z","steps":["trace[2010925196] 'process raft request' (duration: 140.613597ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T01:43:07.638353Z","caller":"traceutil/trace.go:171","msg":"trace[1712171339] transaction","detail":"{read_only:false; response_revision:11407; number_of_response:1; }","duration":"142.094205ms","start":"2026-10-04T01:43:07.496205Z","end":"2026-10-04T01:43:07.638299Z","steps":["trace[1712171339] 'process raft request' (duration: 98.427663ms)","trace[1712171339] 'compare' (duration: 43.291055ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T01:46:39.916223Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10515} {"level":"info","ts":"2026-10-04T01:46:40.014095Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10515,"took":"93.826769ms","hash":3421900499,"current-db-size-bytes":57425920,"current-db-size":"57 MB","current-db-size-in-use-bytes":26820608,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-04T01:46:40.014175Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3421900499,"revision":10515,"compact-revision":8077} {"level":"info","ts":"2026-10-04T01:49:02.598113Z","caller":"traceutil/trace.go:171","msg":"trace[646343370] transaction","detail":"{read_only:false; response_revision:13822; number_of_response:1; }","duration":"147.934559ms","start":"2026-10-04T01:49:02.450116Z","end":"2026-10-04T01:49:02.598051Z","steps":["trace[646343370] 'process raft request' (duration: 147.844957ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T01:49:02.598241Z","caller":"traceutil/trace.go:171","msg":"trace[629095652] transaction","detail":"{read_only:false; response_revision:13821; number_of_response:1; }","duration":"158.968127ms","start":"2026-10-04T01:49:02.439252Z","end":"2026-10-04T01:49:02.59822Z","steps":["trace[629095652] 'process raft request' (duration: 155.918759ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T01:49:23.997134Z","caller":"traceutil/trace.go:171","msg":"trace[1290825350] transaction","detail":"{read_only:false; response_revision:13938; number_of_response:1; }","duration":"132.421207ms","start":"2026-10-04T01:49:23.864669Z","end":"2026-10-04T01:49:23.99709Z","steps":["trace[1290825350] 'process raft request' (duration: 132.218183ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T01:49:42.867789Z","caller":"traceutil/trace.go:171","msg":"trace[1832282387] transaction","detail":"{read_only:false; response_revision:14055; number_of_response:1; }","duration":"106.403896ms","start":"2026-10-04T01:49:42.761348Z","end":"2026-10-04T01:49:42.867752Z","steps":["trace[1832282387] 'process raft request' (duration: 94.981711ms)","trace[1832282387] 'compare' (duration: 11.15821ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T01:49:43.712078Z","caller":"traceutil/trace.go:171","msg":"trace[1114059142] linearizableReadLoop","detail":"{readStateIndex:14548; appliedIndex:14547; }","duration":"136.667306ms","start":"2026-10-04T01:49:43.575372Z","end":"2026-10-04T01:49:43.712039Z","steps":["trace[1114059142] 'read index received' (duration: 136.345029ms)","trace[1114059142] 'applied index is now lower than readState.Index' (duration: 317.527µs)"],"step_count":2} {"level":"info","ts":"2026-10-04T01:49:43.712136Z","caller":"traceutil/trace.go:171","msg":"trace[1463337932] transaction","detail":"{read_only:false; response_revision:14057; number_of_response:1; }","duration":"269.949139ms","start":"2026-10-04T01:49:43.442151Z","end":"2026-10-04T01:49:43.7121Z","steps":["trace[1463337932] 'process raft request' (duration: 269.659543ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T01:49:43.712449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.021883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.69\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-10-04T01:49:43.712547Z","caller":"traceutil/trace.go:171","msg":"trace[1388024589] range","detail":"{range_begin:/registry/masterleases/162.253.55.69; range_end:; response_count:1; response_revision:14057; }","duration":"137.210906ms","start":"2026-10-04T01:49:43.575312Z","end":"2026-10-04T01:49:43.712523Z","steps":["trace[1388024589] 'agreement among raft nodes before linearized reading' (duration: 136.89037ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T01:51:32.267156Z","caller":"traceutil/trace.go:171","msg":"trace[1749543348] transaction","detail":"{read_only:false; response_revision:15303; number_of_response:1; }","duration":"101.080526ms","start":"2026-10-04T01:51:32.166048Z","end":"2026-10-04T01:51:32.267129Z","steps":["trace[1749543348] 'process raft request' (duration: 100.847411ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T01:51:39.924206Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12770} {"level":"info","ts":"2026-10-04T01:51:40.009745Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12770,"took":"82.269259ms","hash":1449862782,"current-db-size-bytes":57425920,"current-db-size":"57 MB","current-db-size-in-use-bytes":31109120,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-04T01:51:40.009838Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1449862782,"revision":12770,"compact-revision":10515} {"level":"info","ts":"2026-10-04T01:56:39.934011Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15344} {"level":"info","ts":"2026-10-04T01:56:40.041953Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15344,"took":"104.290069ms","hash":2876858516,"current-db-size-bytes":57425920,"current-db-size":"57 MB","current-db-size-in-use-bytes":31776768,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-04T01:56:40.042045Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2876858516,"revision":15344,"compact-revision":12770} {"level":"info","ts":"2026-10-04T01:57:10.499567Z","caller":"traceutil/trace.go:171","msg":"trace[1499288457] transaction","detail":"{read_only:false; response_revision:17843; number_of_response:1; }","duration":"102.293053ms","start":"2026-10-04T01:57:10.397247Z","end":"2026-10-04T01:57:10.499541Z","steps":["trace[1499288457] 'process raft request' (duration: 102.107819ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T01:57:11.071018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.296763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-gb4cc\" ","response":"range_response_count:1 size:22122"} {"level":"info","ts":"2026-10-04T01:57:11.071186Z","caller":"traceutil/trace.go:171","msg":"trace[1931425482] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-gb4cc; range_end:; response_count:1; response_revision:17846; }","duration":"267.540687ms","start":"2026-10-04T01:57:10.803624Z","end":"2026-10-04T01:57:11.071165Z","steps":["trace[1931425482] 'range keys from in-memory index tree' (duration: 267.029887ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T01:57:11.071629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.211139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T01:57:11.071694Z","caller":"traceutil/trace.go:171","msg":"trace[241794340] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17846; }","duration":"170.2765ms","start":"2026-10-04T01:57:10.901408Z","end":"2026-10-04T01:57:11.071685Z","steps":["trace[241794340] 'range keys from in-memory index tree' (duration: 170.126157ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T01:57:11.191874Z","caller":"traceutil/trace.go:171","msg":"trace[1071937503] linearizableReadLoop","detail":"{readStateIndex:18456; appliedIndex:18455; }","duration":"215.270205ms","start":"2026-10-04T01:57:10.976571Z","end":"2026-10-04T01:57:11.191841Z","steps":["trace[1071937503] 'read index received' (duration: 214.959539ms)","trace[1071937503] 'applied index is now lower than readState.Index' (duration: 309.256µs)"],"step_count":2} {"level":"info","ts":"2026-10-04T01:57:11.19196Z","caller":"traceutil/trace.go:171","msg":"trace[757143841] transaction","detail":"{read_only:false; response_revision:17847; number_of_response:1; }","duration":"230.212155ms","start":"2026-10-04T01:57:10.961692Z","end":"2026-10-04T01:57:11.191904Z","steps":["trace[757143841] 'process raft request' (duration: 229.93561ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T01:57:11.192233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.775124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-655ba0b2-1a52-460e-ba23-95250bbf1eea\" ","response":"range_response_count:1 size:3639"} {"level":"info","ts":"2026-10-04T01:57:11.192338Z","caller":"traceutil/trace.go:171","msg":"trace[1377774076] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-655ba0b2-1a52-460e-ba23-95250bbf1eea; range_end:; response_count:1; response_revision:17847; }","duration":"132.913867ms","start":"2026-10-04T01:57:11.059399Z","end":"2026-10-04T01:57:11.192313Z","steps":["trace[1377774076] 'agreement among raft nodes before linearized reading' (duration: 132.646712ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T01:57:11.192853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.136341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-04T01:57:11.192917Z","caller":"traceutil/trace.go:171","msg":"trace[1982185263] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:17847; }","duration":"216.365065ms","start":"2026-10-04T01:57:10.976532Z","end":"2026-10-04T01:57:11.192898Z","steps":["trace[1982185263] 'agreement among raft nodes before linearized reading' (duration: 215.457949ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T01:57:11.193238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.772689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:95 size:940755"} {"level":"info","ts":"2026-10-04T01:57:11.193291Z","caller":"traceutil/trace.go:171","msg":"trace[2089493117] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:95; response_revision:17847; }","duration":"112.887531ms","start":"2026-10-04T01:57:11.080389Z","end":"2026-10-04T01:57:11.193277Z","steps":["trace[2089493117] 'agreement among raft nodes before linearized reading' (duration: 111.76068ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T01:57:11.319086Z","caller":"traceutil/trace.go:171","msg":"trace[241313718] transaction","detail":"{read_only:false; response_revision:17848; number_of_response:1; }","duration":"121.179147ms","start":"2026-10-04T01:57:11.197872Z","end":"2026-10-04T01:57:11.319051Z","steps":["trace[241313718] 'process raft request' (duration: 95.7854ms)","trace[241313718] 'compare' (duration: 25.262805ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T01:57:11.348039Z","caller":"traceutil/trace.go:171","msg":"trace[2096703991] transaction","detail":"{read_only:false; response_revision:17849; number_of_response:1; }","duration":"149.672333ms","start":"2026-10-04T01:57:11.19834Z","end":"2026-10-04T01:57:11.348012Z","steps":["trace[2096703991] 'process raft request' (duration: 149.444499ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T01:57:11.348364Z","caller":"traceutil/trace.go:171","msg":"trace[1502605327] transaction","detail":"{read_only:false; response_revision:17850; number_of_response:1; }","duration":"147.280088ms","start":"2026-10-04T01:57:11.201059Z","end":"2026-10-04T01:57:11.348339Z","steps":["trace[1502605327] 'process raft request' (duration: 146.87876ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:00:05.051647Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"905ccde8a677f143","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-04T02:00:05.058715Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-04T02:00:05.058939Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-04T02:01:39.9456Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17587} {"level":"info","ts":"2026-10-04T02:01:40.05649Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17587,"took":"105.712664ms","hash":354041035,"current-db-size-bytes":57425920,"current-db-size":"57 MB","current-db-size-in-use-bytes":34230272,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-04T02:01:40.056605Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":354041035,"revision":17587,"compact-revision":15344} {"level":"info","ts":"2026-10-04T02:02:17.905065Z","caller":"traceutil/trace.go:171","msg":"trace[482571495] transaction","detail":"{read_only:false; response_revision:20332; number_of_response:1; }","duration":"100.753802ms","start":"2026-10-04T02:02:17.804278Z","end":"2026-10-04T02:02:17.905032Z","steps":["trace[482571495] 'process raft request' (duration: 100.535818ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:02:23.329026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.73887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-10-04T02:02:23.329164Z","caller":"traceutil/trace.go:171","msg":"trace[1455666560] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20358; }","duration":"108.967025ms","start":"2026-10-04T02:02:23.220168Z","end":"2026-10-04T02:02:23.329135Z","steps":["trace[1455666560] 'range keys from in-memory index tree' (duration: 108.464915ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:02:23.487222Z","caller":"traceutil/trace.go:171","msg":"trace[2062388826] transaction","detail":"{read_only:false; response_revision:20360; number_of_response:1; }","duration":"151.866091ms","start":"2026-10-04T02:02:23.335315Z","end":"2026-10-04T02:02:23.487181Z","steps":["trace[2062388826] 'process raft request' (duration: 126.839362ms)","trace[2062388826] 'compare' (duration: 24.842206ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T02:02:39.570039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.716706ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-04T02:02:39.57019Z","caller":"traceutil/trace.go:171","msg":"trace[120258702] linearizableReadLoop","detail":"{readStateIndex:21135; appliedIndex:21134; }","duration":"157.467026ms","start":"2026-10-04T02:02:39.412698Z","end":"2026-10-04T02:02:39.570165Z","steps":["trace[120258702] 'read index received' (duration: 19.420594ms)","trace[120258702] 'applied index is now lower than readState.Index' (duration: 138.044472ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T02:02:39.570321Z","caller":"traceutil/trace.go:171","msg":"trace[861945895] transaction","detail":"{read_only:false; response_revision:20440; number_of_response:1; }","duration":"162.049652ms","start":"2026-10-04T02:02:39.408215Z","end":"2026-10-04T02:02:39.570265Z","steps":["trace[861945895] 'process raft request' (duration: 23.96718ms)","trace[861945895] 'compare' (duration: 137.285028ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T02:02:39.570507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.380997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-04T02:02:39.570548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.740881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-04T02:02:39.570634Z","caller":"traceutil/trace.go:171","msg":"trace[1004401434] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:20440; }","duration":"139.591941ms","start":"2026-10-04T02:02:39.431011Z","end":"2026-10-04T02:02:39.570603Z","steps":["trace[1004401434] 'agreement among raft nodes before linearized reading' (duration: 139.322586ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:02:39.570675Z","caller":"traceutil/trace.go:171","msg":"trace[543639617] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:20440; }","duration":"157.961365ms","start":"2026-10-04T02:02:39.412676Z","end":"2026-10-04T02:02:39.570637Z","steps":["trace[543639617] 'agreement among raft nodes before linearized reading' (duration: 157.580468ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:02:39.843619Z","caller":"traceutil/trace.go:171","msg":"trace[1658170229] linearizableReadLoop","detail":"{readStateIndex:21136; appliedIndex:21135; }","duration":"197.312825ms","start":"2026-10-04T02:02:39.646243Z","end":"2026-10-04T02:02:39.843556Z","steps":["trace[1658170229] 'read index received' (duration: 197.02553ms)","trace[1658170229] 'applied index is now lower than readState.Index' (duration: 284.215µs)"],"step_count":2} {"level":"info","ts":"2026-10-04T02:02:39.843716Z","caller":"traceutil/trace.go:171","msg":"trace[539868693] transaction","detail":"{read_only:false; response_revision:20441; number_of_response:1; }","duration":"260.141334ms","start":"2026-10-04T02:02:39.583518Z","end":"2026-10-04T02:02:39.843659Z","steps":["trace[539868693] 'process raft request' (duration: 259.783817ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:02:39.843983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.682132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-04T02:02:39.8441Z","caller":"traceutil/trace.go:171","msg":"trace[672859494] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20441; }","duration":"197.868825ms","start":"2026-10-04T02:02:39.646208Z","end":"2026-10-04T02:02:39.844077Z","steps":["trace[672859494] 'agreement among raft nodes before linearized reading' (duration: 197.528799ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:02:40.323846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.880014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T02:02:40.324076Z","caller":"traceutil/trace.go:171","msg":"trace[942015289] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:20444; }","duration":"133.20498ms","start":"2026-10-04T02:02:40.190836Z","end":"2026-10-04T02:02:40.324041Z","steps":["trace[942015289] 'count revisions from in-memory index tree' (duration: 132.691151ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:06:38.514632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.915355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1936"} {"level":"info","ts":"2026-10-04T02:06:38.514754Z","caller":"traceutil/trace.go:171","msg":"trace[1784376891] 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:22934; }","duration":"248.060368ms","start":"2026-10-04T02:06:38.266667Z","end":"2026-10-04T02:06:38.514727Z","steps":["trace[1784376891] 'range keys from in-memory index tree' (duration: 247.685861ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:06:38.514931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.138001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-04T02:06:38.515112Z","caller":"traceutil/trace.go:171","msg":"trace[672839140] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:22934; }","duration":"165.439306ms","start":"2026-10-04T02:06:38.349646Z","end":"2026-10-04T02:06:38.515085Z","steps":["trace[672839140] 'count revisions from in-memory index tree' (duration: 164.942197ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:06:39.960582Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20134} {"level":"info","ts":"2026-10-04T02:06:40.092696Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20134,"took":"127.059275ms","hash":1261206965,"current-db-size-bytes":57425920,"current-db-size":"57 MB","current-db-size-in-use-bytes":42041344,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-10-04T02:06:40.092792Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1261206965,"revision":20134,"compact-revision":17587} {"level":"warn","ts":"2026-10-04T02:10:19.453588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.172124ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-04T02:10:19.453724Z","caller":"traceutil/trace.go:171","msg":"trace[1092205342] linearizableReadLoop","detail":"{readStateIndex:25975; appliedIndex:25974; }","duration":"107.908476ms","start":"2026-10-04T02:10:19.345796Z","end":"2026-10-04T02:10:19.453704Z","steps":["trace[1092205342] 'read index received' (duration: 54.311µs)","trace[1092205342] 'applied index is now lower than readState.Index' (duration: 107.852255ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T02:10:19.453814Z","caller":"traceutil/trace.go:171","msg":"trace[883334590] transaction","detail":"{read_only:false; response_revision:25148; number_of_response:1; }","duration":"250.588644ms","start":"2026-10-04T02:10:19.203187Z","end":"2026-10-04T02:10:19.453775Z","steps":["trace[883334590] 'process raft request' (duration: 136.102595ms)","trace[883334590] 'compare' (duration: 113.796376ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T02:10:19.453919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.13115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-10-04T02:10:19.453959Z","caller":"traceutil/trace.go:171","msg":"trace[648496582] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:25148; }","duration":"108.216841ms","start":"2026-10-04T02:10:19.345726Z","end":"2026-10-04T02:10:19.453943Z","steps":["trace[648496582] 'agreement among raft nodes before linearized reading' (duration: 108.027848ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:10:19.74812Z","caller":"traceutil/trace.go:171","msg":"trace[408266847] linearizableReadLoop","detail":"{readStateIndex:25977; appliedIndex:25975; }","duration":"159.267869ms","start":"2026-10-04T02:10:19.588823Z","end":"2026-10-04T02:10:19.748091Z","steps":["trace[408266847] 'read index received' (duration: 148.534079ms)","trace[408266847] 'applied index is now lower than readState.Index' (duration: 10.73276ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T02:10:19.748268Z","caller":"traceutil/trace.go:171","msg":"trace[717841648] transaction","detail":"{read_only:false; response_revision:25149; number_of_response:1; }","duration":"353.319093ms","start":"2026-10-04T02:10:19.394935Z","end":"2026-10-04T02:10:19.748255Z","steps":["trace[717841648] 'process raft request' (duration: 342.327148ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:19.748403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:19.3949Z","time spent":"353.395484ms","remote":"127.0.0.1:56236","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-10-04T02:10:19.748482Z","caller":"traceutil/trace.go:171","msg":"trace[1732496916] transaction","detail":"{read_only:false; response_revision:25150; number_of_response:1; }","duration":"288.544967ms","start":"2026-10-04T02:10:19.459904Z","end":"2026-10-04T02:10:19.748449Z","steps":["trace[1732496916] 'process raft request' (duration: 287.984286ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:19.748653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.839091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-04T02:10:19.748689Z","caller":"traceutil/trace.go:171","msg":"trace[284685363] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25152; }","duration":"159.927702ms","start":"2026-10-04T02:10:19.588752Z","end":"2026-10-04T02:10:19.748679Z","steps":["trace[284685363] 'agreement among raft nodes before linearized reading' (duration: 159.740319ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:19.748824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.636521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-04T02:10:19.748856Z","caller":"traceutil/trace.go:171","msg":"trace[1015442885] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25152; }","duration":"156.722272ms","start":"2026-10-04T02:10:19.592126Z","end":"2026-10-04T02:10:19.748848Z","steps":["trace[1015442885] 'agreement among raft nodes before linearized reading' (duration: 156.641881ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:24.397868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.820266ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-04T02:10:24.398265Z","caller":"traceutil/trace.go:171","msg":"trace[1212657779] transaction","detail":"{read_only:false; response_revision:25184; number_of_response:1; }","duration":"236.329087ms","start":"2026-10-04T02:10:24.161914Z","end":"2026-10-04T02:10:24.398243Z","steps":["trace[1212657779] 'process raft request' (duration: 114.017491ms)","trace[1212657779] 'compare' (duration: 121.303957ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T02:10:25.02006Z","caller":"traceutil/trace.go:171","msg":"trace[578942744] transaction","detail":"{read_only:false; response_revision:25187; number_of_response:1; }","duration":"263.843143ms","start":"2026-10-04T02:10:24.756183Z","end":"2026-10-04T02:10:25.020027Z","steps":["trace[578942744] 'process raft request' (duration: 261.864057ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:10:25.02028Z","caller":"traceutil/trace.go:171","msg":"trace[1065665303] linearizableReadLoop","detail":"{readStateIndex:26015; appliedIndex:26013; }","duration":"257.26397ms","start":"2026-10-04T02:10:24.763004Z","end":"2026-10-04T02:10:25.020268Z","steps":["trace[1065665303] 'read index received' (duration: 56.99105ms)","trace[1065665303] 'applied index is now lower than readState.Index' (duration: 200.27125ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T02:10:25.020432Z","caller":"traceutil/trace.go:171","msg":"trace[1990002614] transaction","detail":"{read_only:false; response_revision:25188; number_of_response:1; }","duration":"200.8871ms","start":"2026-10-04T02:10:24.819533Z","end":"2026-10-04T02:10:25.02042Z","steps":["trace[1990002614] 'process raft request' (duration: 200.371001ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:25.020648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.626447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-04T02:10:25.020686Z","caller":"traceutil/trace.go:171","msg":"trace[225741076] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25188; }","duration":"257.726339ms","start":"2026-10-04T02:10:24.762949Z","end":"2026-10-04T02:10:25.020675Z","steps":["trace[225741076] 'agreement among raft nodes before linearized reading' (duration: 257.552645ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:25.020836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.786438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-04T02:10:25.020869Z","caller":"traceutil/trace.go:171","msg":"trace[1380849172] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25188; }","duration":"234.87046ms","start":"2026-10-04T02:10:24.785991Z","end":"2026-10-04T02:10:25.020861Z","steps":["trace[1380849172] 'agreement among raft nodes before linearized reading' (duration: 234.779068ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:25.020982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.773099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T02:10:25.021099Z","caller":"traceutil/trace.go:171","msg":"trace[2086367354] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25188; }","duration":"119.964483ms","start":"2026-10-04T02:10:24.901112Z","end":"2026-10-04T02:10:25.021076Z","steps":["trace[2086367354] 'agreement among raft nodes before linearized reading' (duration: 119.755339ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:10:25.543388Z","caller":"traceutil/trace.go:171","msg":"trace[76060780] transaction","detail":"{read_only:false; response_revision:25192; number_of_response:1; }","duration":"136.829009ms","start":"2026-10-04T02:10:25.406526Z","end":"2026-10-04T02:10:25.543355Z","steps":["trace[76060780] 'process raft request' (duration: 136.512503ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:10:25.752952Z","caller":"traceutil/trace.go:171","msg":"trace[1459365394] transaction","detail":"{read_only:false; response_revision:25193; number_of_response:1; }","duration":"204.394837ms","start":"2026-10-04T02:10:25.548505Z","end":"2026-10-04T02:10:25.7529Z","steps":["trace[1459365394] 'process raft request' (duration: 204.030141ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:10:25.882642Z","caller":"traceutil/trace.go:171","msg":"trace[1283340174] transaction","detail":"{read_only:false; response_revision:25194; number_of_response:1; }","duration":"109.071287ms","start":"2026-10-04T02:10:25.773535Z","end":"2026-10-04T02:10:25.882606Z","steps":["trace[1283340174] 'process raft request' (duration: 106.979608ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:10:25.883036Z","caller":"traceutil/trace.go:171","msg":"trace[277896536] transaction","detail":"{read_only:false; response_revision:25195; number_of_response:1; }","duration":"108.065568ms","start":"2026-10-04T02:10:25.774932Z","end":"2026-10-04T02:10:25.882998Z","steps":["trace[277896536] 'process raft request' (duration: 107.558749ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:26.25486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.475362ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-04T02:10:26.255036Z","caller":"traceutil/trace.go:171","msg":"trace[159920911] linearizableReadLoop","detail":"{readStateIndex:26025; appliedIndex:26023; }","duration":"353.813312ms","start":"2026-10-04T02:10:25.901199Z","end":"2026-10-04T02:10:26.255012Z","steps":["trace[159920911] 'read index received' (duration: 6.893859ms)","trace[159920911] 'applied index is now lower than readState.Index' (duration: 346.917433ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T02:10:26.255125Z","caller":"traceutil/trace.go:171","msg":"trace[1595185520] transaction","detail":"{read_only:false; response_revision:25197; number_of_response:1; }","duration":"364.29005ms","start":"2026-10-04T02:10:25.890822Z","end":"2026-10-04T02:10:26.255112Z","steps":["trace[1595185520] 'process raft request' (duration: 154.42162ms)","trace[1595185520] 'compare' (duration: 209.107136ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T02:10:26.255206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:25.890799Z","time spent":"364.370311ms","remote":"127.0.0.1:56236","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-10-04T02:10:26.255694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.333692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T02:10:26.255803Z","caller":"traceutil/trace.go:171","msg":"trace[1893738154] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25197; }","duration":"354.624817ms","start":"2026-10-04T02:10:25.901151Z","end":"2026-10-04T02:10:26.255776Z","steps":["trace[1893738154] 'agreement among raft nodes before linearized reading' (duration: 354.042876ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:26.255861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:25.901133Z","time spent":"354.719808ms","remote":"127.0.0.1:55972","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-10-04T02:10:26.593757Z","caller":"traceutil/trace.go:171","msg":"trace[819008783] linearizableReadLoop","detail":"{readStateIndex:26026; appliedIndex:26025; }","duration":"338.658938ms","start":"2026-10-04T02:10:26.255062Z","end":"2026-10-04T02:10:26.593721Z","steps":["trace[819008783] 'read index received' (duration: 305.177629ms)","trace[819008783] 'applied index is now lower than readState.Index' (duration: 33.479899ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T02:10:26.594105Z","caller":"traceutil/trace.go:171","msg":"trace[543496824] transaction","detail":"{read_only:false; response_revision:25198; number_of_response:1; }","duration":"478.396662ms","start":"2026-10-04T02:10:26.115692Z","end":"2026-10-04T02:10:26.594089Z","steps":["trace[543496824] 'process raft request' (duration: 444.511976ms)","trace[543496824] 'compare' (duration: 33.325496ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T02:10:26.594244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:26.11566Z","time spent":"478.483424ms","remote":"127.0.0.1:56236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T02:10:26.595003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.998683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-04T02:10:26.595108Z","caller":"traceutil/trace.go:171","msg":"trace[2009770640] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:25198; }","duration":"462.188517ms","start":"2026-10-04T02:10:26.132899Z","end":"2026-10-04T02:10:26.595088Z","steps":["trace[2009770640] 'agreement among raft nodes before linearized reading' (duration: 461.953162ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:26.595159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:26.132884Z","time spent":"462.264109ms","remote":"127.0.0.1:38320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":31,"request content":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true "} {"level":"info","ts":"2026-10-04T02:10:26.645687Z","caller":"traceutil/trace.go:171","msg":"trace[844302358] transaction","detail":"{read_only:false; response_revision:25199; number_of_response:1; }","duration":"239.318474ms","start":"2026-10-04T02:10:26.40631Z","end":"2026-10-04T02:10:26.645628Z","steps":["trace[844302358] 'process raft request' (duration: 239.042438ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:26.645762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.518354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T02:10:26.645858Z","caller":"traceutil/trace.go:171","msg":"trace[473592634] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25199; }","duration":"388.681658ms","start":"2026-10-04T02:10:26.257155Z","end":"2026-10-04T02:10:26.645836Z","steps":["trace[473592634] 'agreement among raft nodes before linearized reading' (duration: 388.409643ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:26.645909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:26.257136Z","time spent":"388.765629ms","remote":"127.0.0.1:55988","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-10-04T02:10:26.64594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.066149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-04T02:10:26.646061Z","caller":"traceutil/trace.go:171","msg":"trace[1387831176] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25199; }","duration":"239.249602ms","start":"2026-10-04T02:10:26.406779Z","end":"2026-10-04T02:10:26.646029Z","steps":["trace[1387831176] 'agreement among raft nodes before linearized reading' (duration: 238.866925ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:10:26.775432Z","caller":"traceutil/trace.go:171","msg":"trace[424815703] transaction","detail":"{read_only:false; response_revision:25200; number_of_response:1; }","duration":"124.677632ms","start":"2026-10-04T02:10:26.650712Z","end":"2026-10-04T02:10:26.77539Z","steps":["trace[424815703] 'process raft request' (duration: 122.631043ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:10:27.056027Z","caller":"traceutil/trace.go:171","msg":"trace[657097239] linearizableReadLoop","detail":"{readStateIndex:26029; appliedIndex:26028; }","duration":"146.340557ms","start":"2026-10-04T02:10:26.90966Z","end":"2026-10-04T02:10:27.056001Z","steps":["trace[657097239] 'read index received' (duration: 127.063845ms)","trace[657097239] 'applied index is now lower than readState.Index' (duration: 19.275432ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T02:10:27.056118Z","caller":"traceutil/trace.go:171","msg":"trace[283982713] transaction","detail":"{read_only:false; response_revision:25201; number_of_response:1; }","duration":"228.020451ms","start":"2026-10-04T02:10:26.828047Z","end":"2026-10-04T02:10:27.056068Z","steps":["trace[283982713] 'process raft request' (duration: 208.683938ms)","trace[283982713] 'compare' (duration: 19.025877ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T02:10:27.056597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.628923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T02:10:27.056664Z","caller":"traceutil/trace.go:171","msg":"trace[577633394] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25202; }","duration":"147.063401ms","start":"2026-10-04T02:10:26.909585Z","end":"2026-10-04T02:10:27.056649Z","steps":["trace[577633394] 'agreement among raft nodes before linearized reading' (duration: 146.567042ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:10:27.433723Z","caller":"traceutil/trace.go:171","msg":"trace[258084119] linearizableReadLoop","detail":"{readStateIndex:26032; appliedIndex:26030; }","duration":"366.342998ms","start":"2026-10-04T02:10:27.06734Z","end":"2026-10-04T02:10:27.433683Z","steps":["trace[258084119] 'read index received' (duration: 20.439493ms)","trace[258084119] 'applied index is now lower than readState.Index' (duration: 345.902405ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T02:10:27.433905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.537852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T02:10:27.433946Z","caller":"traceutil/trace.go:171","msg":"trace[803866659] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:25204; }","duration":"366.657924ms","start":"2026-10-04T02:10:27.067273Z","end":"2026-10-04T02:10:27.433931Z","steps":["trace[803866659] 'agreement among raft nodes before linearized reading' (duration: 366.543772ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:10:27.433894Z","caller":"traceutil/trace.go:171","msg":"trace[1320455604] transaction","detail":"{read_only:false; response_revision:25204; number_of_response:1; }","duration":"371.144638ms","start":"2026-10-04T02:10:27.062696Z","end":"2026-10-04T02:10:27.433841Z","steps":["trace[1320455604] 'process raft request' (duration: 370.770642ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:27.433991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:27.067253Z","time spent":"366.726765ms","remote":"127.0.0.1:37182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"warn","ts":"2026-10-04T02:10:27.434128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:27.062672Z","time spent":"371.336682ms","remote":"127.0.0.1:56236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T02:10:27.698005Z","caller":"traceutil/trace.go:171","msg":"trace[2110443545] linearizableReadLoop","detail":"{readStateIndex:26033; appliedIndex:26032; }","duration":"174.639958ms","start":"2026-10-04T02:10:27.523331Z","end":"2026-10-04T02:10:27.697971Z","steps":["trace[2110443545] 'read index received' (duration: 155.394896ms)","trace[2110443545] 'applied index is now lower than readState.Index' (duration: 19.243032ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T02:10:27.698033Z","caller":"traceutil/trace.go:171","msg":"trace[17469908] transaction","detail":"{read_only:false; response_revision:25205; number_of_response:1; }","duration":"464.865617ms","start":"2026-10-04T02:10:27.233112Z","end":"2026-10-04T02:10:27.697978Z","steps":["trace[17469908] 'process raft request' (duration: 445.695307ms)","trace[17469908] 'compare' (duration: 18.874304ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T02:10:27.698234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:27.233076Z","time spent":"465.04729ms","remote":"127.0.0.1:56236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":605,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T02:10:27.69826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.745125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T02:10:27.698303Z","caller":"traceutil/trace.go:171","msg":"trace[1378473287] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:25205; }","duration":"121.848226ms","start":"2026-10-04T02:10:27.576438Z","end":"2026-10-04T02:10:27.698286Z","steps":["trace[1378473287] 'agreement among raft nodes before linearized reading' (duration: 121.737824ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:27.698811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.448803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:31 size:268829"} {"level":"info","ts":"2026-10-04T02:10:27.698912Z","caller":"traceutil/trace.go:171","msg":"trace[214719974] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:31; response_revision:25205; }","duration":"175.597037ms","start":"2026-10-04T02:10:27.52329Z","end":"2026-10-04T02:10:27.698887Z","steps":["trace[214719974] 'agreement among raft nodes before linearized reading' (duration: 174.868682ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:10:27.936497Z","caller":"traceutil/trace.go:171","msg":"trace[1807631473] transaction","detail":"{read_only:false; response_revision:25206; number_of_response:1; }","duration":"172.695052ms","start":"2026-10-04T02:10:27.763753Z","end":"2026-10-04T02:10:27.936448Z","steps":["trace[1807631473] 'process raft request' (duration: 122.928048ms)","trace[1807631473] 'compare' (duration: 49.374537ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T02:10:28.173753Z","caller":"traceutil/trace.go:171","msg":"trace[446604323] linearizableReadLoop","detail":"{readStateIndex:26037; appliedIndex:26036; }","duration":"213.793434ms","start":"2026-10-04T02:10:27.959921Z","end":"2026-10-04T02:10:28.173714Z","steps":["trace[446604323] 'read index received' (duration: 167.047726ms)","trace[446604323] 'applied index is now lower than readState.Index' (duration: 46.744018ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T02:10:28.173965Z","caller":"traceutil/trace.go:171","msg":"trace[61984418] transaction","detail":"{read_only:false; response_revision:25209; number_of_response:1; }","duration":"214.753862ms","start":"2026-10-04T02:10:27.959175Z","end":"2026-10-04T02:10:28.173929Z","steps":["trace[61984418] 'process raft request' (duration: 167.891952ms)","trace[61984418] 'compare' (duration: 46.32687ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T02:10:28.174223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.290633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19231"} {"level":"info","ts":"2026-10-04T02:10:28.174278Z","caller":"traceutil/trace.go:171","msg":"trace[1909398752] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25209; }","duration":"214.417175ms","start":"2026-10-04T02:10:27.959848Z","end":"2026-10-04T02:10:28.174265Z","steps":["trace[1909398752] 'agreement among raft nodes before linearized reading' (duration: 214.16773ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:10:28.781909Z","caller":"traceutil/trace.go:171","msg":"trace[1542096139] transaction","detail":"{read_only:false; response_revision:25212; number_of_response:1; }","duration":"129.285177ms","start":"2026-10-04T02:10:28.652597Z","end":"2026-10-04T02:10:28.781882Z","steps":["trace[1542096139] 'process raft request' (duration: 123.605061ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:29.042351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.392515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T02:10:29.042471Z","caller":"traceutil/trace.go:171","msg":"trace[619340858] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25214; }","duration":"140.563239ms","start":"2026-10-04T02:10:28.901864Z","end":"2026-10-04T02:10:29.042427Z","steps":["trace[619340858] 'range keys from in-memory index tree' (duration: 140.279933ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:29.04266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.149788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-04T02:10:29.042764Z","caller":"traceutil/trace.go:171","msg":"trace[960759674] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:25214; }","duration":"159.334521ms","start":"2026-10-04T02:10:28.883406Z","end":"2026-10-04T02:10:29.042741Z","steps":["trace[960759674] 'count revisions from in-memory index tree' (duration: 159.046457ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:10:39.747525Z","caller":"traceutil/trace.go:171","msg":"trace[1389694897] transaction","detail":"{read_only:false; response_revision:25297; number_of_response:1; }","duration":"236.722915ms","start":"2026-10-04T02:10:39.51077Z","end":"2026-10-04T02:10:39.747493Z","steps":["trace[1389694897] 'process raft request' (duration: 236.50981ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:10:40.967023Z","caller":"traceutil/trace.go:171","msg":"trace[25572312] linearizableReadLoop","detail":"{readStateIndex:26137; appliedIndex:26136; }","duration":"480.96899ms","start":"2026-10-04T02:10:40.486024Z","end":"2026-10-04T02:10:40.966993Z","steps":["trace[25572312] 'read index received' (duration: 480.799117ms)","trace[25572312] 'applied index is now lower than readState.Index' (duration: 168.383µs)"],"step_count":2} {"level":"info","ts":"2026-10-04T02:10:40.967194Z","caller":"traceutil/trace.go:171","msg":"trace[1310032844] transaction","detail":"{read_only:false; response_revision:25305; number_of_response:1; }","duration":"538.641072ms","start":"2026-10-04T02:10:40.428507Z","end":"2026-10-04T02:10:40.967148Z","steps":["trace[1310032844] 'process raft request' (duration: 538.260115ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:40.967284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.250055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19231"} {"level":"info","ts":"2026-10-04T02:10:40.967326Z","caller":"traceutil/trace.go:171","msg":"trace[175399007] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25305; }","duration":"481.361987ms","start":"2026-10-04T02:10:40.485952Z","end":"2026-10-04T02:10:40.967314Z","steps":["trace[175399007] 'agreement among raft nodes before linearized reading' (duration: 481.188494ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:40.967369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:40.485931Z","time spent":"481.429909ms","remote":"127.0.0.1:56130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":19254,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-10-04T02:10:40.967458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:40.428468Z","time spent":"538.840006ms","remote":"127.0.0.1:56236","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-10-04T02:10:41.28798Z","caller":"traceutil/trace.go:171","msg":"trace[1569385206] linearizableReadLoop","detail":"{readStateIndex:26139; appliedIndex:26137; }","duration":"320.843353ms","start":"2026-10-04T02:10:40.967101Z","end":"2026-10-04T02:10:41.287944Z","steps":["trace[1569385206] 'read index received' (duration: 300.659364ms)","trace[1569385206] 'applied index is now lower than readState.Index' (duration: 20.175559ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T02:10:41.288404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.146998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/ports/\" range_end:\"/registry/openstack.k-orc.cloud/ports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-04T02:10:41.288512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.489833ms","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-10-04T02:10:41.288598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"681.679559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-04T02:10:41.288617Z","caller":"traceutil/trace.go:171","msg":"trace[480385716] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25307; }","duration":"373.667066ms","start":"2026-10-04T02:10:40.914931Z","end":"2026-10-04T02:10:41.288598Z","steps":["trace[480385716] 'agreement among raft nodes before linearized reading' (duration: 373.394401ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:10:41.288503Z","caller":"traceutil/trace.go:171","msg":"trace[1497437337] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/ports/; range_end:/registry/openstack.k-orc.cloud/ports0; response_count:0; response_revision:25307; }","duration":"331.326581ms","start":"2026-10-04T02:10:40.957151Z","end":"2026-10-04T02:10:41.288478Z","steps":["trace[1497437337] 'agreement among raft nodes before linearized reading' (duration: 331.130167ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:10:41.288659Z","caller":"traceutil/trace.go:171","msg":"trace[1005893232] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:25307; }","duration":"681.75316ms","start":"2026-10-04T02:10:40.606889Z","end":"2026-10-04T02:10:41.288642Z","steps":["trace[1005893232] 'agreement among raft nodes before linearized reading' (duration: 681.600977ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:41.288679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:40.914907Z","time spent":"373.752487ms","remote":"127.0.0.1:56236","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-10-04T02:10:41.288664Z","caller":"traceutil/trace.go:171","msg":"trace[1712183441] transaction","detail":"{read_only:false; response_revision:25307; number_of_response:1; }","duration":"458.436207ms","start":"2026-10-04T02:10:40.830174Z","end":"2026-10-04T02:10:41.288611Z","steps":["trace[1712183441] 'process raft request' (duration: 457.662082ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:41.2887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:40.606806Z","time spent":"681.880982ms","remote":"127.0.0.1:38306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":2,"response size":31,"request content":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true "} {"level":"info","ts":"2026-10-04T02:10:41.288647Z","caller":"traceutil/trace.go:171","msg":"trace[1617331290] transaction","detail":"{read_only:false; response_revision:25306; number_of_response:1; }","duration":"603.279376ms","start":"2026-10-04T02:10:40.685318Z","end":"2026-10-04T02:10:41.288597Z","steps":["trace[1617331290] 'process raft request' (duration: 582.358233ms)","trace[1617331290] 'compare' (duration: 19.557847ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T02:10:41.288855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:40.83014Z","time spent":"458.60011ms","remote":"127.0.0.1:56236","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-10-04T02:10:41.288899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:40.95712Z","time spent":"331.562636ms","remote":"127.0.0.1:59148","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/ports/\" range_end:\"/registry/openstack.k-orc.cloud/ports0\" count_only:true "} {"level":"warn","ts":"2026-10-04T02:10:41.288915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:40.685278Z","time spent":"603.517471ms","remote":"127.0.0.1:56236","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-10-04T02:10:41.29974Z","caller":"traceutil/trace.go:171","msg":"trace[1131359747] transaction","detail":"{read_only:false; response_revision:25308; number_of_response:1; }","duration":"167.539016ms","start":"2026-10-04T02:10:41.132183Z","end":"2026-10-04T02:10:41.299722Z","steps":["trace[1131359747] 'process raft request' (duration: 167.328102ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:10:41.299929Z","caller":"traceutil/trace.go:171","msg":"trace[129403314] transaction","detail":"{read_only:false; response_revision:25309; number_of_response:1; }","duration":"167.659327ms","start":"2026-10-04T02:10:41.132245Z","end":"2026-10-04T02:10:41.299904Z","steps":["trace[129403314] 'process raft request' (duration: 167.433233ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:41.300012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.478419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19231"} {"level":"info","ts":"2026-10-04T02:10:41.300057Z","caller":"traceutil/trace.go:171","msg":"trace[1642942173] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25309; }","duration":"326.578981ms","start":"2026-10-04T02:10:40.973467Z","end":"2026-10-04T02:10:41.300046Z","steps":["trace[1642942173] 'agreement among raft nodes before linearized reading' (duration: 326.411088ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:41.300095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:40.973432Z","time spent":"326.656513ms","remote":"127.0.0.1:56130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":19254,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-10-04T02:10:41.300149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.769668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T02:10:41.300221Z","caller":"traceutil/trace.go:171","msg":"trace[1644885628] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:25309; }","duration":"267.859659ms","start":"2026-10-04T02:10:41.032334Z","end":"2026-10-04T02:10:41.300194Z","steps":["trace[1644885628] 'agreement among raft nodes before linearized reading' (duration: 267.741887ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:41.300227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.811632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-04T02:10:41.300333Z","caller":"traceutil/trace.go:171","msg":"trace[1292747622] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25309; }","duration":"142.962674ms","start":"2026-10-04T02:10:41.157339Z","end":"2026-10-04T02:10:41.300302Z","steps":["trace[1292747622] 'agreement among raft nodes before linearized reading' (duration: 142.605097ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:10:41.585795Z","caller":"traceutil/trace.go:171","msg":"trace[1766665469] linearizableReadLoop","detail":"{readStateIndex:26143; appliedIndex:26141; }","duration":"266.037615ms","start":"2026-10-04T02:10:41.319728Z","end":"2026-10-04T02:10:41.585765Z","steps":["trace[1766665469] 'read index received' (duration: 260.771726ms)","trace[1766665469] 'applied index is now lower than readState.Index' (duration: 5.264399ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T02:10:41.585943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.190858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:186"} {"level":"info","ts":"2026-10-04T02:10:41.585976Z","caller":"traceutil/trace.go:171","msg":"trace[1298328482] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:25311; }","duration":"266.29084ms","start":"2026-10-04T02:10:41.319673Z","end":"2026-10-04T02:10:41.585964Z","steps":["trace[1298328482] 'agreement among raft nodes before linearized reading' (duration: 266.195438ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:10:41.585934Z","caller":"traceutil/trace.go:171","msg":"trace[1802752383] transaction","detail":"{read_only:false; response_revision:25310; number_of_response:1; }","duration":"290.616676ms","start":"2026-10-04T02:10:41.295281Z","end":"2026-10-04T02:10:41.585898Z","steps":["trace[1802752383] 'process raft request' (duration: 285.192725ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:10:41.586328Z","caller":"traceutil/trace.go:171","msg":"trace[1738260059] transaction","detail":"{read_only:false; response_revision:25311; number_of_response:1; }","duration":"281.594357ms","start":"2026-10-04T02:10:41.3047Z","end":"2026-10-04T02:10:41.586294Z","steps":["trace[1738260059] 'process raft request' (duration: 280.947045ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:42.086609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.115222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-04T02:10:42.086726Z","caller":"traceutil/trace.go:171","msg":"trace[284964495] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:25311; }","duration":"303.316555ms","start":"2026-10-04T02:10:41.783384Z","end":"2026-10-04T02:10:42.086701Z","steps":["trace[284964495] 'count revisions from in-memory index tree' (duration: 302.927659ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:42.086781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:41.783351Z","time spent":"303.418947ms","remote":"127.0.0.1:38530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":40,"response size":31,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-10-04T02:10:42.087179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.889558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-04T02:10:42.087269Z","caller":"traceutil/trace.go:171","msg":"trace[1350535313] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25311; }","duration":"334.034891ms","start":"2026-10-04T02:10:41.753209Z","end":"2026-10-04T02:10:42.087244Z","steps":["trace[1350535313] 'range keys from in-memory index tree' (duration: 333.620133ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:42.087434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:41.753187Z","time spent":"334.232515ms","remote":"127.0.0.1:56236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-10-04T02:10:42.373377Z","caller":"traceutil/trace.go:171","msg":"trace[858544545] linearizableReadLoop","detail":"{readStateIndex:26144; appliedIndex:26143; }","duration":"472.45591ms","start":"2026-10-04T02:10:41.900882Z","end":"2026-10-04T02:10:42.373338Z","steps":["trace[858544545] 'read index received' (duration: 472.269236ms)","trace[858544545] 'applied index is now lower than readState.Index' (duration: 184.754µs)"],"step_count":2} {"level":"info","ts":"2026-10-04T02:10:42.373508Z","caller":"traceutil/trace.go:171","msg":"trace[1028234501] transaction","detail":"{read_only:false; response_revision:25312; number_of_response:1; }","duration":"522.427598ms","start":"2026-10-04T02:10:41.85104Z","end":"2026-10-04T02:10:42.373467Z","steps":["trace[1028234501] 'process raft request' (duration: 522.049291ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:42.373711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.804147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-04T02:10:42.373736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:41.851005Z","time spent":"522.604592ms","remote":"127.0.0.1:56236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T02:10:42.373762Z","caller":"traceutil/trace.go:171","msg":"trace[267279276] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25312; }","duration":"472.921588ms","start":"2026-10-04T02:10:41.900824Z","end":"2026-10-04T02:10:42.373745Z","steps":["trace[267279276] 'agreement among raft nodes before linearized reading' (duration: 472.689904ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:42.373797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:41.900802Z","time spent":"472.98721ms","remote":"127.0.0.1:55972","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-10-04T02:10:42.703043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.201562ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-04T02:10:42.703341Z","caller":"traceutil/trace.go:171","msg":"trace[1293147126] linearizableReadLoop","detail":"{readStateIndex:26147; appliedIndex:26144; }","duration":"329.850423ms","start":"2026-10-04T02:10:42.37347Z","end":"2026-10-04T02:10:42.70332Z","steps":["trace[1293147126] 'read index received' (duration: 17.204923ms)","trace[1293147126] 'applied index is now lower than readState.Index' (duration: 312.64437ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T02:10:42.703302Z","caller":"traceutil/trace.go:171","msg":"trace[79117866] transaction","detail":"{read_only:false; response_revision:25313; number_of_response:1; }","duration":"692.232146ms","start":"2026-10-04T02:10:42.011029Z","end":"2026-10-04T02:10:42.703261Z","steps":["trace[79117866] 'process raft request' (duration: 379.713598ms)","trace[79117866] 'compare' (duration: 311.986677ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T02:10:42.703474Z","caller":"traceutil/trace.go:171","msg":"trace[1853975046] transaction","detail":"{read_only:false; response_revision:25315; number_of_response:1; }","duration":"611.289126ms","start":"2026-10-04T02:10:42.092125Z","end":"2026-10-04T02:10:42.703414Z","steps":["trace[1853975046] 'process raft request' (duration: 611.146353ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:42.703567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:42.010995Z","time spent":"692.412289ms","remote":"127.0.0.1:56236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T02:10:42.7036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.414974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-04T02:10:42.703631Z","caller":"traceutil/trace.go:171","msg":"trace[773989717] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25315; }","duration":"444.495586ms","start":"2026-10-04T02:10:42.259125Z","end":"2026-10-04T02:10:42.70362Z","steps":["trace[773989717] 'agreement among raft nodes before linearized reading' (duration: 444.309972ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:42.703662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:42.259102Z","time spent":"444.552016ms","remote":"127.0.0.1:56236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-04T02:10:42.703748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:42.092098Z","time spent":"611.513851ms","remote":"127.0.0.1:56236","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-10-04T02:10:42.703829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.10198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-04T02:10:42.703853Z","caller":"traceutil/trace.go:171","msg":"trace[1291101266] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25315; }","duration":"670.170612ms","start":"2026-10-04T02:10:42.033675Z","end":"2026-10-04T02:10:42.703846Z","steps":["trace[1291101266] 'agreement among raft nodes before linearized reading' (duration: 670.08837ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:42.703877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:42.033648Z","time spent":"670.223622ms","remote":"127.0.0.1:56236","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-10-04T02:10:42.703989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.832839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T02:10:42.704017Z","caller":"traceutil/trace.go:171","msg":"trace[161129605] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:25315; }","duration":"706.920661ms","start":"2026-10-04T02:10:41.997086Z","end":"2026-10-04T02:10:42.704007Z","steps":["trace[161129605] 'agreement among raft nodes before linearized reading' (duration: 706.85821ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:42.704013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.001048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-04T02:10:42.704041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:41.997023Z","time spent":"707.010243ms","remote":"127.0.0.1:56580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true "} {"level":"info","ts":"2026-10-04T02:10:42.704052Z","caller":"traceutil/trace.go:171","msg":"trace[1512313370] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25315; }","duration":"329.086428ms","start":"2026-10-04T02:10:42.374954Z","end":"2026-10-04T02:10:42.70404Z","steps":["trace[1512313370] 'agreement among raft nodes before linearized reading' (duration: 329.012067ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:42.704081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:42.374928Z","time spent":"329.14752ms","remote":"127.0.0.1:55988","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-10-04T02:10:42.70431Z","caller":"traceutil/trace.go:171","msg":"trace[1954043360] transaction","detail":"{read_only:false; response_revision:25314; number_of_response:1; }","duration":"684.787545ms","start":"2026-10-04T02:10:42.019512Z","end":"2026-10-04T02:10:42.7043Z","steps":["trace[1954043360] 'process raft request' (duration: 683.644304ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:42.704371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:42.019491Z","time spent":"684.843667ms","remote":"127.0.0.1:56236","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-10-04T02:10:43.213204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.719981ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-04T02:10:43.213462Z","caller":"traceutil/trace.go:171","msg":"trace[1403386396] linearizableReadLoop","detail":"{readStateIndex:26149; appliedIndex:26147; }","duration":"378.74895ms","start":"2026-10-04T02:10:42.834693Z","end":"2026-10-04T02:10:43.213442Z","steps":["trace[1403386396] 'read index received' (duration: 57.541359ms)","trace[1403386396] 'applied index is now lower than readState.Index' (duration: 321.206281ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T02:10:43.213619Z","caller":"traceutil/trace.go:171","msg":"trace[167867983] transaction","detail":"{read_only:false; response_revision:25317; number_of_response:1; }","duration":"501.444684ms","start":"2026-10-04T02:10:42.712157Z","end":"2026-10-04T02:10:43.213602Z","steps":["trace[167867983] 'process raft request' (duration: 501.173479ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:10:43.213641Z","caller":"traceutil/trace.go:171","msg":"trace[1369192877] transaction","detail":"{read_only:false; response_revision:25316; number_of_response:1; }","duration":"503.281509ms","start":"2026-10-04T02:10:42.710195Z","end":"2026-10-04T02:10:43.213476Z","steps":["trace[1369192877] 'process raft request' (duration: 182.18081ms)","trace[1369192877] 'compare' (duration: 320.13779ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T02:10:43.213703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:42.712124Z","time spent":"501.541826ms","remote":"127.0.0.1:56236","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-10-04T02:10:43.213973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.286591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T02:10:43.214033Z","caller":"traceutil/trace.go:171","msg":"trace[1983861607] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:25317; }","duration":"379.376262ms","start":"2026-10-04T02:10:42.83462Z","end":"2026-10-04T02:10:43.213996Z","steps":["trace[1983861607] 'agreement among raft nodes before linearized reading' (duration: 379.290561ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:43.214065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:42.834585Z","time spent":"379.471753ms","remote":"127.0.0.1:38278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":29,"request content":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true "} {"level":"warn","ts":"2026-10-04T02:10:43.213854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:42.71017Z","time spent":"503.568344ms","remote":"127.0.0.1:56236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T02:10:43.214594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.642609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-04T02:10:43.214737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.304999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/addressscopes/\" range_end:\"/registry/openstack.k-orc.cloud/addressscopes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T02:10:43.214808Z","caller":"traceutil/trace.go:171","msg":"trace[380251667] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/addressscopes/; range_end:/registry/openstack.k-orc.cloud/addressscopes0; response_count:0; response_revision:25317; }","duration":"298.433742ms","start":"2026-10-04T02:10:42.916356Z","end":"2026-10-04T02:10:43.21479Z","steps":["trace[380251667] 'agreement among raft nodes before linearized reading' (duration: 298.304449ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:10:43.21476Z","caller":"traceutil/trace.go:171","msg":"trace[823116718] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25317; }","duration":"312.862343ms","start":"2026-10-04T02:10:42.901874Z","end":"2026-10-04T02:10:43.214737Z","steps":["trace[823116718] 'agreement among raft nodes before linearized reading' (duration: 312.617378ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:43.214879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:42.901812Z","time spent":"313.053416ms","remote":"127.0.0.1:55972","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-10-04T02:10:43.21489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.336539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-04T02:10:43.214981Z","caller":"traceutil/trace.go:171","msg":"trace[1622765318] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25317; }","duration":"242.464941ms","start":"2026-10-04T02:10:42.972494Z","end":"2026-10-04T02:10:43.214959Z","steps":["trace[1622765318] 'agreement among raft nodes before linearized reading' (duration: 242.157015ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:10:43.732856Z","caller":"traceutil/trace.go:171","msg":"trace[2094293288] linearizableReadLoop","detail":"{readStateIndex:26150; appliedIndex:26149; }","duration":"466.238823ms","start":"2026-10-04T02:10:43.266546Z","end":"2026-10-04T02:10:43.732785Z","steps":["trace[2094293288] 'read index received' (duration: 426.09014ms)","trace[2094293288] 'applied index is now lower than readState.Index' (duration: 40.146723ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T02:10:43.733024Z","caller":"traceutil/trace.go:171","msg":"trace[160891335] transaction","detail":"{read_only:false; response_revision:25318; number_of_response:1; }","duration":"513.092183ms","start":"2026-10-04T02:10:43.219905Z","end":"2026-10-04T02:10:43.732997Z","steps":["trace[160891335] 'process raft request' (duration: 472.726936ms)","trace[160891335] 'compare' (duration: 39.865258ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T02:10:43.733182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:43.219881Z","time spent":"513.184164ms","remote":"127.0.0.1:56236","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-10-04T02:10:43.733245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.693791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:1 size:847"} {"level":"info","ts":"2026-10-04T02:10:43.733326Z","caller":"traceutil/trace.go:171","msg":"trace[2026630981] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway; range_end:; response_count:1; response_revision:25318; }","duration":"466.825773ms","start":"2026-10-04T02:10:43.266479Z","end":"2026-10-04T02:10:43.733304Z","steps":["trace[2026630981] 'agreement among raft nodes before linearized reading' (duration: 466.596299ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:43.733369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:43.266434Z","time spent":"466.925535ms","remote":"127.0.0.1:56174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":870,"request content":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway\" "} {"level":"info","ts":"2026-10-04T02:10:43.762143Z","caller":"traceutil/trace.go:171","msg":"trace[1061638171] transaction","detail":"{read_only:false; response_revision:25319; number_of_response:1; }","duration":"468.435685ms","start":"2026-10-04T02:10:43.293685Z","end":"2026-10-04T02:10:43.76212Z","steps":["trace[1061638171] 'process raft request' (duration: 467.942755ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:43.762302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:43.29366Z","time spent":"468.555268ms","remote":"127.0.0.1:56236","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-10-04T02:10:43.76257Z","caller":"traceutil/trace.go:171","msg":"trace[405899079] transaction","detail":"{read_only:false; response_revision:25320; number_of_response:1; }","duration":"467.638729ms","start":"2026-10-04T02:10:43.294918Z","end":"2026-10-04T02:10:43.762556Z","steps":["trace[405899079] 'process raft request' (duration: 466.846034ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:43.762639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:43.294909Z","time spent":"467.696021ms","remote":"127.0.0.1:56236","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-10-04T02:10:43.762784Z","caller":"traceutil/trace.go:171","msg":"trace[1107619389] transaction","detail":"{read_only:false; response_revision:25321; number_of_response:1; }","duration":"457.887127ms","start":"2026-10-04T02:10:43.304887Z","end":"2026-10-04T02:10:43.762775Z","steps":["trace[1107619389] 'process raft request' (duration: 456.921289ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:43.762843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:43.304857Z","time spent":"457.957198ms","remote":"127.0.0.1:56236","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-10-04T02:10:43.762972Z","caller":"traceutil/trace.go:171","msg":"trace[1328904082] transaction","detail":"{read_only:false; response_revision:25322; number_of_response:1; }","duration":"457.55469ms","start":"2026-10-04T02:10:43.305408Z","end":"2026-10-04T02:10:43.762963Z","steps":["trace[1328904082] 'process raft request' (duration: 456.432929ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:43.763027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:43.305395Z","time spent":"457.60729ms","remote":"127.0.0.1:56236","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-10-04T02:10:43.763189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.919486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.69\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-10-04T02:10:43.763226Z","caller":"traceutil/trace.go:171","msg":"trace[513837515] range","detail":"{range_begin:/registry/masterleases/162.253.55.69; range_end:; response_count:1; response_revision:25322; }","duration":"116.002747ms","start":"2026-10-04T02:10:43.647215Z","end":"2026-10-04T02:10:43.763218Z","steps":["trace[513837515] 'agreement among raft nodes before linearized reading' (duration: 115.878115ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:43.763439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.363469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-10-04T02:10:43.763473Z","caller":"traceutil/trace.go:171","msg":"trace[334669132] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25322; }","duration":"145.41592ms","start":"2026-10-04T02:10:43.618049Z","end":"2026-10-04T02:10:43.763464Z","steps":["trace[334669132] 'agreement among raft nodes before linearized reading' (duration: 145.317708ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:43.763744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.53788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-04T02:10:43.763864Z","caller":"traceutil/trace.go:171","msg":"trace[2131153782] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25322; }","duration":"171.693423ms","start":"2026-10-04T02:10:43.59214Z","end":"2026-10-04T02:10:43.763833Z","steps":["trace[2131153782] 'agreement among raft nodes before linearized reading' (duration: 171.303025ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:43.764254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.838194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-04T02:10:43.764294Z","caller":"traceutil/trace.go:171","msg":"trace[1428938026] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25322; }","duration":"172.930406ms","start":"2026-10-04T02:10:43.591354Z","end":"2026-10-04T02:10:43.764284Z","steps":["trace[1428938026] 'agreement among raft nodes before linearized reading' (duration: 172.816474ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:43.764307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.886378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-04T02:10:43.764353Z","caller":"traceutil/trace.go:171","msg":"trace[1123276149] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:25322; }","duration":"333.97546ms","start":"2026-10-04T02:10:43.430367Z","end":"2026-10-04T02:10:43.764343Z","steps":["trace[1123276149] 'agreement among raft nodes before linearized reading' (duration: 333.887988ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:43.764386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:43.430346Z","time spent":"334.032901ms","remote":"127.0.0.1:56236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":19,"response size":31,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"info","ts":"2026-10-04T02:10:43.92317Z","caller":"traceutil/trace.go:171","msg":"trace[309098409] linearizableReadLoop","detail":"{readStateIndex:26155; appliedIndex:26154; }","duration":"155.640951ms","start":"2026-10-04T02:10:43.767489Z","end":"2026-10-04T02:10:43.92313Z","steps":["trace[309098409] 'read index received' (duration: 146.265356ms)","trace[309098409] 'applied index is now lower than readState.Index' (duration: 9.373205ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T02:10:43.923495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.976468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-10-04T02:10:43.92358Z","caller":"traceutil/trace.go:171","msg":"trace[578741674] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25322; }","duration":"156.109151ms","start":"2026-10-04T02:10:43.767458Z","end":"2026-10-04T02:10:43.923567Z","steps":["trace[578741674] 'agreement among raft nodes before linearized reading' (duration: 155.831065ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:10:43.948068Z","caller":"traceutil/trace.go:171","msg":"trace[2062994709] transaction","detail":"{read_only:false; response_revision:25323; number_of_response:1; }","duration":"178.310578ms","start":"2026-10-04T02:10:43.769719Z","end":"2026-10-04T02:10:43.948029Z","steps":["trace[2062994709] 'process raft request' (duration: 177.35724ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:10:43.948301Z","caller":"traceutil/trace.go:171","msg":"trace[1353215716] transaction","detail":"{read_only:false; response_revision:25324; number_of_response:1; }","duration":"177.182437ms","start":"2026-10-04T02:10:43.77109Z","end":"2026-10-04T02:10:43.948272Z","steps":["trace[1353215716] 'process raft request' (duration: 176.873531ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:49.461197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.077447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/persistence-rabbitmq-manila-server-0.18db31a1e0f02437\" ","response":"range_response_count:1 size:1010"} {"level":"info","ts":"2026-10-04T02:10:49.461301Z","caller":"traceutil/trace.go:171","msg":"trace[1906966321] range","detail":"{range_begin:/registry/events/openstack/persistence-rabbitmq-manila-server-0.18db31a1e0f02437; range_end:; response_count:1; response_revision:25420; }","duration":"118.216899ms","start":"2026-10-04T02:10:49.343062Z","end":"2026-10-04T02:10:49.461279Z","steps":["trace[1906966321] 'range keys from in-memory index tree' (duration: 117.914004ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:49.461279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.960688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-04T02:10:49.461386Z","caller":"traceutil/trace.go:171","msg":"trace[453359530] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25420; }","duration":"221.118862ms","start":"2026-10-04T02:10:49.240239Z","end":"2026-10-04T02:10:49.461358Z","steps":["trace[453359530] 'range keys from in-memory index tree' (duration: 220.724474ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:49.461648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.408577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusteridentities/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusteridentities0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-04T02:10:49.461691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.945133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T02:10:49.461736Z","caller":"traceutil/trace.go:171","msg":"trace[1499303104] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:25420; }","duration":"192.016574ms","start":"2026-10-04T02:10:49.269704Z","end":"2026-10-04T02:10:49.46172Z","steps":["trace[1499303104] 'count revisions from in-memory index tree' (duration: 191.898972ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:10:49.461742Z","caller":"traceutil/trace.go:171","msg":"trace[1029957969] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusteridentities/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusteridentities0; response_count:0; response_revision:25420; }","duration":"196.530549ms","start":"2026-10-04T02:10:49.265187Z","end":"2026-10-04T02:10:49.461718Z","steps":["trace[1029957969] 'count revisions from in-memory index tree' (duration: 196.343677ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:49.461956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.222503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5684"} {"level":"info","ts":"2026-10-04T02:10:49.462006Z","caller":"traceutil/trace.go:171","msg":"trace[1402370751] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:25420; }","duration":"213.318345ms","start":"2026-10-04T02:10:49.248673Z","end":"2026-10-04T02:10:49.461992Z","steps":["trace[1402370751] 'range keys from in-memory index tree' (duration: 213.09636ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:49.462169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.999169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-04T02:10:49.462359Z","caller":"traceutil/trace.go:171","msg":"trace[904283879] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25420; }","duration":"221.210913ms","start":"2026-10-04T02:10:49.241136Z","end":"2026-10-04T02:10:49.462347Z","steps":["trace[904283879] 'range keys from in-memory index tree' (duration: 220.896656ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:10:49.546479Z","caller":"traceutil/trace.go:171","msg":"trace[1929606841] linearizableReadLoop","detail":"{readStateIndex:26255; appliedIndex:26254; }","duration":"173.961526ms","start":"2026-10-04T02:10:49.372471Z","end":"2026-10-04T02:10:49.546432Z","steps":["trace[1929606841] 'read index received' (duration: 173.841064ms)","trace[1929606841] 'applied index is now lower than readState.Index' (duration: 119.522µs)"],"step_count":2} {"level":"warn","ts":"2026-10-04T02:10:49.54668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.199561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-04T02:10:49.546722Z","caller":"traceutil/trace.go:171","msg":"trace[767802667] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25421; }","duration":"174.317793ms","start":"2026-10-04T02:10:49.372392Z","end":"2026-10-04T02:10:49.54671Z","steps":["trace[767802667] 'agreement among raft nodes before linearized reading' (duration: 174.181611ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:10:49.547136Z","caller":"traceutil/trace.go:171","msg":"trace[2091872567] transaction","detail":"{read_only:false; response_revision:25421; number_of_response:1; }","duration":"184.414482ms","start":"2026-10-04T02:10:49.362656Z","end":"2026-10-04T02:10:49.54707Z","steps":["trace[2091872567] 'process raft request' (duration: 183.577236ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:10:49.9574Z","caller":"traceutil/trace.go:171","msg":"trace[1145809229] transaction","detail":"{read_only:false; response_revision:25425; number_of_response:1; }","duration":"189.544628ms","start":"2026-10-04T02:10:49.767826Z","end":"2026-10-04T02:10:49.957371Z","steps":["trace[1145809229] 'process raft request' (duration: 189.351354ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:10:50.077806Z","caller":"traceutil/trace.go:171","msg":"trace[1144584708] transaction","detail":"{read_only:false; response_revision:25426; number_of_response:1; }","duration":"308.299278ms","start":"2026-10-04T02:10:49.76947Z","end":"2026-10-04T02:10:50.077769Z","steps":["trace[1144584708] 'process raft request' (duration: 306.218239ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:50.07803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:49.769451Z","time spent":"308.44382ms","remote":"127.0.0.1:56236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T02:10:50.078122Z","caller":"traceutil/trace.go:171","msg":"trace[1275906814] transaction","detail":"{read_only:false; response_revision:25427; number_of_response:1; }","duration":"292.177575ms","start":"2026-10-04T02:10:49.785905Z","end":"2026-10-04T02:10:50.078083Z","steps":["trace[1275906814] 'process raft request' (duration: 291.556803ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:10:50.078415Z","caller":"traceutil/trace.go:171","msg":"trace[78141982] transaction","detail":"{read_only:false; response_revision:25428; number_of_response:1; }","duration":"287.039598ms","start":"2026-10-04T02:10:49.791343Z","end":"2026-10-04T02:10:50.078382Z","steps":["trace[78141982] 'process raft request' (duration: 286.480828ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:10:50.078666Z","caller":"traceutil/trace.go:171","msg":"trace[1305868737] linearizableReadLoop","detail":"{readStateIndex:26264; appliedIndex:26259; }","duration":"178.4661ms","start":"2026-10-04T02:10:49.900178Z","end":"2026-10-04T02:10:50.078644Z","steps":["trace[1305868737] 'read index received' (duration: 57.205484ms)","trace[1305868737] 'applied index is now lower than readState.Index' (duration: 121.258156ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T02:10:50.078701Z","caller":"traceutil/trace.go:171","msg":"trace[946289415] transaction","detail":"{read_only:false; response_revision:25430; number_of_response:1; }","duration":"285.700684ms","start":"2026-10-04T02:10:49.792986Z","end":"2026-10-04T02:10:50.078687Z","steps":["trace[946289415] 'process raft request' (duration: 285.381298ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:10:50.078621Z","caller":"traceutil/trace.go:171","msg":"trace[134341247] transaction","detail":"{read_only:false; response_revision:25429; number_of_response:1; }","duration":"286.862385ms","start":"2026-10-04T02:10:49.791631Z","end":"2026-10-04T02:10:50.078493Z","steps":["trace[134341247] 'process raft request' (duration: 286.629071ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:50.078806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.620844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T02:10:50.078941Z","caller":"traceutil/trace.go:171","msg":"trace[1931687215] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25430; }","duration":"178.822417ms","start":"2026-10-04T02:10:49.900101Z","end":"2026-10-04T02:10:50.078924Z","steps":["trace[1931687215] 'agreement among raft nodes before linearized reading' (duration: 178.634543ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:50.157722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T02:10:50.157791Z","caller":"traceutil/trace.go:171","msg":"trace[641694612] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:25431; }","duration":"137.593433ms","start":"2026-10-04T02:10:50.020182Z","end":"2026-10-04T02:10:50.157776Z","steps":["trace[641694612] 'agreement among raft nodes before linearized reading' (duration: 137.375099ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:50.157814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.805113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-04T02:10:50.157917Z","caller":"traceutil/trace.go:171","msg":"trace[923579580] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25431; }","duration":"181.946906ms","start":"2026-10-04T02:10:49.975944Z","end":"2026-10-04T02:10:50.15789Z","steps":["trace[923579580] 'agreement among raft nodes before linearized reading' (duration: 181.63404ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:50.157929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.946592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-04T02:10:50.158037Z","caller":"traceutil/trace.go:171","msg":"trace[1375530595] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25431; }","duration":"177.111615ms","start":"2026-10-04T02:10:49.980897Z","end":"2026-10-04T02:10:50.158009Z","steps":["trace[1375530595] 'agreement among raft nodes before linearized reading' (duration: 176.724228ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:53.302015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.639771ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-04T02:10:53.302134Z","caller":"traceutil/trace.go:171","msg":"trace[610676516] linearizableReadLoop","detail":"{readStateIndex:26282; appliedIndex:26280; }","duration":"1.065507884s","start":"2026-10-04T02:10:52.236606Z","end":"2026-10-04T02:10:53.302114Z","steps":["trace[610676516] 'read index received' (duration: 18.156141ms)","trace[610676516] 'applied index is now lower than readState.Index' (duration: 1.047350273s)"],"step_count":2} {"level":"info","ts":"2026-10-04T02:10:53.302347Z","caller":"traceutil/trace.go:171","msg":"trace[426194716] transaction","detail":"{read_only:false; response_revision:25448; number_of_response:1; }","duration":"1.076393088s","start":"2026-10-04T02:10:52.22594Z","end":"2026-10-04T02:10:53.302333Z","steps":["trace[426194716] 'process raft request' (duration: 911.323709ms)","trace[426194716] 'compare' (duration: 164.508059ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T02:10:53.30241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:52.225904Z","time spent":"1.07647366s","remote":"127.0.0.1:56236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T02:10:53.302744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.066116075s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7ba1df7c-07d1-4d05-b5a0-5da9c4ae76fe\" ","response":"range_response_count:1 size:3949"} {"level":"info","ts":"2026-10-04T02:10:53.302838Z","caller":"traceutil/trace.go:171","msg":"trace[215791868] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7ba1df7c-07d1-4d05-b5a0-5da9c4ae76fe; range_end:; response_count:1; response_revision:25448; }","duration":"1.066251467s","start":"2026-10-04T02:10:52.236564Z","end":"2026-10-04T02:10:53.302815Z","steps":["trace[215791868] 'agreement among raft nodes before linearized reading' (duration: 1.065933221s)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:53.302885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:52.236546Z","time spent":"1.066329149s","remote":"127.0.0.1:56146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3972,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7ba1df7c-07d1-4d05-b5a0-5da9c4ae76fe\" "} {"level":"warn","ts":"2026-10-04T02:10:53.607949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.812535ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-04T02:10:53.608139Z","caller":"traceutil/trace.go:171","msg":"trace[1468691042] transaction","detail":"{read_only:false; response_revision:25449; number_of_response:1; }","duration":"1.198873148s","start":"2026-10-04T02:10:52.409243Z","end":"2026-10-04T02:10:53.608116Z","steps":["trace[1468691042] 'process raft request' (duration: 1.067788207s)","trace[1468691042] 'compare' (duration: 130.683633ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T02:10:53.608212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:52.409211Z","time spent":"1.19896898s","remote":"127.0.0.1:56236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T02:10:53.608487Z","caller":"traceutil/trace.go:171","msg":"trace[1916671052] transaction","detail":"{read_only:false; response_revision:25450; number_of_response:1; }","duration":"865.526789ms","start":"2026-10-04T02:10:52.742922Z","end":"2026-10-04T02:10:53.608448Z","steps":["trace[1916671052] 'process raft request' (duration: 865.141852ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:10:53.608588Z","caller":"traceutil/trace.go:171","msg":"trace[611333254] linearizableReadLoop","detail":"{readStateIndex:26285; appliedIndex:26282; }","duration":"306.390273ms","start":"2026-10-04T02:10:53.302159Z","end":"2026-10-04T02:10:53.608549Z","steps":["trace[611333254] 'read index received' (duration: 174.833713ms)","trace[611333254] 'applied index is now lower than readState.Index' (duration: 131.55525ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T02:10:53.608717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.098724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T02:10:53.608773Z","caller":"traceutil/trace.go:171","msg":"trace[1816964516] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25451; }","duration":"708.192056ms","start":"2026-10-04T02:10:52.900564Z","end":"2026-10-04T02:10:53.608756Z","steps":["trace[1816964516] 'agreement among raft nodes before linearized reading' (duration: 708.093855ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:53.60881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:52.900493Z","time spent":"708.311098ms","remote":"127.0.0.1:55972","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-10-04T02:10:53.608796Z","caller":"traceutil/trace.go:171","msg":"trace[1169481028] transaction","detail":"{read_only:false; response_revision:25451; number_of_response:1; }","duration":"863.92102ms","start":"2026-10-04T02:10:52.744843Z","end":"2026-10-04T02:10:53.608764Z","steps":["trace[1169481028] 'process raft request' (duration: 863.523191ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:53.608842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.216143953s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-04T02:10:53.608858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"845.860531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-10-04T02:10:53.608945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:52.744825Z","time spent":"864.039092ms","remote":"127.0.0.1:56236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T02:10:53.60893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.556577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3236"} {"level":"warn","ts":"2026-10-04T02:10:53.608918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.415886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T02:10:53.609007Z","caller":"traceutil/trace.go:171","msg":"trace[1776906039] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25451; }","duration":"655.68403ms","start":"2026-10-04T02:10:52.9533Z","end":"2026-10-04T02:10:53.608984Z","steps":["trace[1776906039] 'agreement among raft nodes before linearized reading' (duration: 655.463315ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:53.609058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:52.953271Z","time spent":"655.776262ms","remote":"127.0.0.1:37032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":3259,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-10-04T02:10:53.609097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.355729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T02:10:53.6092Z","caller":"traceutil/trace.go:171","msg":"trace[637751572] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25451; }","duration":"846.255857ms","start":"2026-10-04T02:10:52.76292Z","end":"2026-10-04T02:10:53.609176Z","steps":["trace[637751572] 'agreement among raft nodes before linearized reading' (duration: 845.766028ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:53.609276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:52.762892Z","time spent":"846.37069ms","remote":"127.0.0.1:56236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-04T02:10:53.608877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:52.742893Z","time spent":"865.684842ms","remote":"127.0.0.1:56236","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-10-04T02:10:53.609161Z","caller":"traceutil/trace.go:171","msg":"trace[1830975362] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:25451; }","duration":"345.724611ms","start":"2026-10-04T02:10:53.263419Z","end":"2026-10-04T02:10:53.609144Z","steps":["trace[1830975362] 'agreement among raft nodes before linearized reading' (duration: 345.406116ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:53.609347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:53.263381Z","time spent":"345.949085ms","remote":"127.0.0.1:45500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true "} {"level":"info","ts":"2026-10-04T02:10:53.608892Z","caller":"traceutil/trace.go:171","msg":"trace[1356305786] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:25451; }","duration":"1.216273186s","start":"2026-10-04T02:10:52.392604Z","end":"2026-10-04T02:10:53.608877Z","steps":["trace[1356305786] 'agreement among raft nodes before linearized reading' (duration: 1.216130133s)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:10:53.609293Z","caller":"traceutil/trace.go:171","msg":"trace[2129950288] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0; response_count:0; response_revision:25451; }","duration":"578.815567ms","start":"2026-10-04T02:10:53.030404Z","end":"2026-10-04T02:10:53.60922Z","steps":["trace[2129950288] 'agreement among raft nodes before linearized reading' (duration: 578.221226ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:53.609422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:52.392575Z","time spent":"1.216814115s","remote":"127.0.0.1:56180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-10-04T02:10:53.609534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:53.030378Z","time spent":"579.116933ms","remote":"127.0.0.1:45614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":142,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0\" count_only:true "} {"level":"info","ts":"2026-10-04T02:10:57.796084Z","caller":"traceutil/trace.go:171","msg":"trace[2132350707] linearizableReadLoop","detail":"{readStateIndex:26331; appliedIndex:26330; }","duration":"479.460622ms","start":"2026-10-04T02:10:57.316571Z","end":"2026-10-04T02:10:57.796032Z","steps":["trace[2132350707] 'read index received' (duration: 479.247268ms)","trace[2132350707] 'applied index is now lower than readState.Index' (duration: 211.944µs)"],"step_count":2} {"level":"info","ts":"2026-10-04T02:10:57.796115Z","caller":"traceutil/trace.go:171","msg":"trace[707138325] transaction","detail":"{read_only:false; response_revision:25495; number_of_response:1; }","duration":"518.259959ms","start":"2026-10-04T02:10:57.277814Z","end":"2026-10-04T02:10:57.796074Z","steps":["trace[707138325] 'process raft request' (duration: 517.998215ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:57.796319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:57.277768Z","time spent":"518.433973ms","remote":"127.0.0.1:56146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10633,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T02:10:57.796428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.829638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-04T02:10:57.796548Z","caller":"traceutil/trace.go:171","msg":"trace[1027353070] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25495; }","duration":"479.995382ms","start":"2026-10-04T02:10:57.316529Z","end":"2026-10-04T02:10:57.796524Z","steps":["trace[1027353070] 'agreement among raft nodes before linearized reading' (duration: 479.665205ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:57.79661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:57.316499Z","time spent":"480.101044ms","remote":"127.0.0.1:56236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-04T02:10:58.296487Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17384916027289854435,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-10-04T02:10:58.467028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.08039ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-04T02:10:58.467206Z","caller":"traceutil/trace.go:171","msg":"trace[291991989] transaction","detail":"{read_only:false; response_revision:25498; number_of_response:1; }","duration":"828.382613ms","start":"2026-10-04T02:10:57.638804Z","end":"2026-10-04T02:10:58.467187Z","steps":["trace[291991989] 'process raft request' (duration: 828.349912ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:58.467253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:57.638792Z","time spent":"828.438573ms","remote":"127.0.0.1:56236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T02:10:58.467334Z","caller":"traceutil/trace.go:171","msg":"trace[40992375] transaction","detail":"{read_only:false; response_revision:25497; number_of_response:1; }","duration":"829.514474ms","start":"2026-10-04T02:10:57.637785Z","end":"2026-10-04T02:10:58.467299Z","steps":["trace[40992375] 'process raft request' (duration: 829.33189ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:10:58.467347Z","caller":"traceutil/trace.go:171","msg":"trace[1058622052] transaction","detail":"{read_only:false; response_revision:25496; number_of_response:1; }","duration":"838.807538ms","start":"2026-10-04T02:10:57.628493Z","end":"2026-10-04T02:10:58.467301Z","steps":["trace[1058622052] 'process raft request' (duration: 731.374931ms)","trace[1058622052] 'compare' (duration: 106.851746ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T02:10:58.467498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:57.637752Z","time spent":"829.653556ms","remote":"127.0.0.1:56236","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-10-04T02:10:58.467673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:57.628462Z","time spent":"838.963821ms","remote":"127.0.0.1:56236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T02:10:58.640391Z","caller":"traceutil/trace.go:171","msg":"trace[2036373192] transaction","detail":"{read_only:false; response_revision:25499; number_of_response:1; }","duration":"838.061814ms","start":"2026-10-04T02:10:57.802304Z","end":"2026-10-04T02:10:58.640365Z","steps":["trace[2036373192] 'process raft request' (duration: 837.742638ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:58.640764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:57.802278Z","time spent":"838.366019ms","remote":"127.0.0.1:56236","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-10-04T02:10:58.640813Z","caller":"traceutil/trace.go:171","msg":"trace[1041461573] transaction","detail":"{read_only:false; response_revision:25501; number_of_response:1; }","duration":"837.34126ms","start":"2026-10-04T02:10:57.803427Z","end":"2026-10-04T02:10:58.640769Z","steps":["trace[1041461573] 'process raft request' (duration: 837.110036ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:10:58.640831Z","caller":"traceutil/trace.go:171","msg":"trace[1462568719] transaction","detail":"{read_only:false; response_revision:25502; number_of_response:1; }","duration":"837.036355ms","start":"2026-10-04T02:10:57.803759Z","end":"2026-10-04T02:10:58.640795Z","steps":["trace[1462568719] 'process raft request' (duration: 836.934773ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:58.640922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:57.80342Z","time spent":"837.444342ms","remote":"127.0.0.1:56250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1415,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T02:10:58.641063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:57.803743Z","time spent":"837.193567ms","remote":"127.0.0.1:56250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T02:10:58.641145Z","caller":"traceutil/trace.go:171","msg":"trace[1525232858] transaction","detail":"{read_only:false; response_revision:25503; number_of_response:1; }","duration":"835.222311ms","start":"2026-10-04T02:10:57.805895Z","end":"2026-10-04T02:10:58.641117Z","steps":["trace[1525232858] 'process raft request' (duration: 834.842644ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:58.641299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:57.80587Z","time spent":"835.343273ms","remote":"127.0.0.1:56116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":885,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T02:10:58.641428Z","caller":"traceutil/trace.go:171","msg":"trace[273667125] transaction","detail":"{read_only:false; response_revision:25500; number_of_response:1; }","duration":"837.657547ms","start":"2026-10-04T02:10:57.803077Z","end":"2026-10-04T02:10:58.640734Z","steps":["trace[273667125] 'process raft request' (duration: 837.150307ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:10:58.641527Z","caller":"traceutil/trace.go:171","msg":"trace[1206547938] transaction","detail":"{read_only:false; response_revision:25504; number_of_response:1; }","duration":"518.362682ms","start":"2026-10-04T02:10:58.123121Z","end":"2026-10-04T02:10:58.641483Z","steps":["trace[1206547938] 'process raft request' (duration: 517.916133ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:58.641602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:58.123085Z","time spent":"518.474184ms","remote":"127.0.0.1:56236","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-10-04T02:10:58.641766Z","caller":"traceutil/trace.go:171","msg":"trace[1189113593] transaction","detail":"{read_only:false; response_revision:25506; number_of_response:1; }","duration":"473.00032ms","start":"2026-10-04T02:10:58.168747Z","end":"2026-10-04T02:10:58.641748Z","steps":["trace[1189113593] 'process raft request' (duration: 472.603433ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:58.641775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.299199522s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"warn","ts":"2026-10-04T02:10:58.641776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:57.803069Z","time spent":"838.516182ms","remote":"127.0.0.1:56116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T02:10:58.641823Z","caller":"traceutil/trace.go:171","msg":"trace[2047392726] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25507; }","duration":"1.299267463s","start":"2026-10-04T02:10:57.34254Z","end":"2026-10-04T02:10:58.641808Z","steps":["trace[2047392726] 'agreement among raft nodes before linearized reading' (duration: 1.299087169s)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:10:58.641605Z","caller":"traceutil/trace.go:171","msg":"trace[1753915050] linearizableReadLoop","detail":"{readStateIndex:26343; appliedIndex:26331; }","duration":"845.424463ms","start":"2026-10-04T02:10:57.796164Z","end":"2026-10-04T02:10:58.641588Z","steps":["trace[1753915050] 'read index received' (duration: 563.829306ms)","trace[1753915050] 'applied index is now lower than readState.Index' (duration: 281.593607ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T02:10:58.64183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:58.168724Z","time spent":"473.069981ms","remote":"127.0.0.1:56236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T02:10:58.641898Z","caller":"traceutil/trace.go:171","msg":"trace[827557284] transaction","detail":"{read_only:false; response_revision:25507; number_of_response:1; }","duration":"468.779661ms","start":"2026-10-04T02:10:58.173101Z","end":"2026-10-04T02:10:58.64188Z","steps":["trace[827557284] 'process raft request' (duration: 468.428214ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:58.641922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"948.174701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-04T02:10:58.641948Z","caller":"traceutil/trace.go:171","msg":"trace[1616656238] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25507; }","duration":"948.242642ms","start":"2026-10-04T02:10:57.693698Z","end":"2026-10-04T02:10:58.641941Z","steps":["trace[1616656238] 'agreement among raft nodes before linearized reading' (duration: 948.170411ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:58.641967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:57.693677Z","time spent":"948.284602ms","remote":"127.0.0.1:56236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-04T02:10:58.641991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:58.173085Z","time spent":"468.854702ms","remote":"127.0.0.1:56236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T02:10:58.642083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"968.807468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-04T02:10:58.642104Z","caller":"traceutil/trace.go:171","msg":"trace[1207039235] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25507; }","duration":"968.88202ms","start":"2026-10-04T02:10:57.673215Z","end":"2026-10-04T02:10:58.642097Z","steps":["trace[1207039235] 'agreement among raft nodes before linearized reading' (duration: 968.813009ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:58.642101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.146715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-10-04T02:10:58.641852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:57.34253Z","time spent":"1.299314034s","remote":"127.0.0.1:56114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":1883,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" "} {"level":"info","ts":"2026-10-04T02:10:58.642148Z","caller":"traceutil/trace.go:171","msg":"trace[389919500] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25507; }","duration":"593.251587ms","start":"2026-10-04T02:10:58.048884Z","end":"2026-10-04T02:10:58.642136Z","steps":["trace[389919500] 'agreement among raft nodes before linearized reading' (duration: 593.077823ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:58.642173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.023569286s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-04T02:10:58.642185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:58.048854Z","time spent":"593.320388ms","remote":"127.0.0.1:56236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-04T02:10:58.642199Z","caller":"traceutil/trace.go:171","msg":"trace[930474784] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:25507; }","duration":"1.023637668s","start":"2026-10-04T02:10:57.618553Z","end":"2026-10-04T02:10:58.642191Z","steps":["trace[930474784] 'agreement among raft nodes before linearized reading' (duration: 1.023584297s)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:58.642222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:57.618528Z","time spent":"1.023688549s","remote":"127.0.0.1:45418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true "} {"level":"warn","ts":"2026-10-04T02:10:58.641837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"940.082879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-10-04T02:10:58.642315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.606343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/keypairs/\" range_end:\"/registry/openstack.k-orc.cloud/keypairs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-04T02:10:58.642125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:57.673188Z","time spent":"968.931621ms","remote":"127.0.0.1:56236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-04T02:10:58.642369Z","caller":"traceutil/trace.go:171","msg":"trace[1194117642] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/keypairs/; range_end:/registry/openstack.k-orc.cloud/keypairs0; response_count:0; response_revision:25507; }","duration":"349.718985ms","start":"2026-10-04T02:10:58.292632Z","end":"2026-10-04T02:10:58.642351Z","steps":["trace[1194117642] 'agreement among raft nodes before linearized reading' (duration: 349.631303ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:58.642416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:58.292584Z","time spent":"349.819537ms","remote":"127.0.0.1:59248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/keypairs/\" range_end:\"/registry/openstack.k-orc.cloud/keypairs0\" count_only:true "} {"level":"warn","ts":"2026-10-04T02:10:58.642574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.031249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-04T02:10:58.642646Z","caller":"traceutil/trace.go:171","msg":"trace[1506371389] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25507; }","duration":"320.201143ms","start":"2026-10-04T02:10:58.322423Z","end":"2026-10-04T02:10:58.642624Z","steps":["trace[1506371389] 'agreement among raft nodes before linearized reading' (duration: 319.822475ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:10:58.642106Z","caller":"traceutil/trace.go:171","msg":"trace[2064990135] transaction","detail":"{read_only:false; response_revision:25505; number_of_response:1; }","duration":"473.841566ms","start":"2026-10-04T02:10:58.168249Z","end":"2026-10-04T02:10:58.642091Z","steps":["trace[2064990135] 'process raft request' (duration: 473.047121ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:10:58.642331Z","caller":"traceutil/trace.go:171","msg":"trace[1340595048] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25507; }","duration":"940.634649ms","start":"2026-10-04T02:10:57.701683Z","end":"2026-10-04T02:10:58.642318Z","steps":["trace[1340595048] 'agreement among raft nodes before linearized reading' (duration: 940.033528ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:10:58.642752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:57.701659Z","time spent":"941.079448ms","remote":"127.0.0.1:56236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-04T02:10:58.642764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:58.168207Z","time spent":"474.512369ms","remote":"127.0.0.1:56236","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-10-04T02:10:58.642688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:10:58.322405Z","time spent":"320.271604ms","remote":"127.0.0.1:56236","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-10-04T02:10:58.755119Z","caller":"traceutil/trace.go:171","msg":"trace[1405332819] transaction","detail":"{read_only:false; response_revision:25510; number_of_response:1; }","duration":"105.42955ms","start":"2026-10-04T02:10:58.64966Z","end":"2026-10-04T02:10:58.75509Z","steps":["trace[1405332819] 'process raft request' (duration: 105.322568ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:10:58.75546Z","caller":"traceutil/trace.go:171","msg":"trace[582417787] transaction","detail":"{read_only:false; response_revision:25511; number_of_response:1; }","duration":"105.646614ms","start":"2026-10-04T02:10:58.649776Z","end":"2026-10-04T02:10:58.755423Z","steps":["trace[582417787] 'process raft request' (duration: 105.262687ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:10:58.755716Z","caller":"traceutil/trace.go:171","msg":"trace[1247141130] transaction","detail":"{read_only:false; response_revision:25512; number_of_response:1; }","duration":"105.703735ms","start":"2026-10-04T02:10:58.649987Z","end":"2026-10-04T02:10:58.755691Z","steps":["trace[1247141130] 'process raft request' (duration: 105.44386ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:10:58.755911Z","caller":"traceutil/trace.go:171","msg":"trace[820496561] transaction","detail":"{read_only:false; response_revision:25509; number_of_response:1; }","duration":"105.736665ms","start":"2026-10-04T02:10:58.649445Z","end":"2026-10-04T02:10:58.755182Z","steps":["trace[820496561] 'process raft request' (duration: 100.798522ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:11:16.906096Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006786.wal"} {"level":"info","ts":"2026-10-04T02:11:28.902828Z","caller":"traceutil/trace.go:171","msg":"trace[82572862] transaction","detail":"{read_only:false; response_revision:25738; number_of_response:1; }","duration":"263.454987ms","start":"2026-10-04T02:11:28.639343Z","end":"2026-10-04T02:11:28.902798Z","steps":["trace[82572862] 'process raft request' (duration: 263.252063ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:11:29.324935Z","caller":"traceutil/trace.go:171","msg":"trace[1063055300] transaction","detail":"{read_only:false; response_revision:25740; number_of_response:1; }","duration":"685.124892ms","start":"2026-10-04T02:11:28.639767Z","end":"2026-10-04T02:11:29.324891Z","steps":["trace[1063055300] 'process raft request' (duration: 684.98798ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:11:29.324975Z","caller":"traceutil/trace.go:171","msg":"trace[2074386772] transaction","detail":"{read_only:false; response_revision:25739; number_of_response:1; }","duration":"685.190063ms","start":"2026-10-04T02:11:28.639728Z","end":"2026-10-04T02:11:29.324918Z","steps":["trace[2074386772] 'process raft request' (duration: 684.719574ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:11:29.325193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:11:28.639722Z","time spent":"685.357477ms","remote":"127.0.0.1:56236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T02:11:29.325221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:11:28.63976Z","time spent":"685.302116ms","remote":"127.0.0.1:56236","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-10-04T02:11:29.325969Z","caller":"traceutil/trace.go:171","msg":"trace[1264637826] transaction","detail":"{read_only:false; response_revision:25742; number_of_response:1; }","duration":"467.722661ms","start":"2026-10-04T02:11:28.858231Z","end":"2026-10-04T02:11:29.325954Z","steps":["trace[1264637826] 'process raft request' (duration: 467.587918ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:11:29.326013Z","caller":"traceutil/trace.go:171","msg":"trace[1172464334] transaction","detail":"{read_only:false; response_revision:25741; number_of_response:1; }","duration":"480.284688ms","start":"2026-10-04T02:11:28.845709Z","end":"2026-10-04T02:11:29.325993Z","steps":["trace[1172464334] 'process raft request' (duration: 479.124365ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:11:29.326056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:11:28.858206Z","time spent":"467.808602ms","remote":"127.0.0.1:56236","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-10-04T02:11:29.326092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:11:28.845683Z","time spent":"480.366999ms","remote":"127.0.0.1:56236","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-10-04T02:11:29.326118Z","caller":"traceutil/trace.go:171","msg":"trace[699562007] linearizableReadLoop","detail":"{readStateIndex:26586; appliedIndex:26579; }","duration":"426.5751ms","start":"2026-10-04T02:11:28.899527Z","end":"2026-10-04T02:11:29.326102Z","steps":["trace[699562007] 'read index received' (duration: 3.039877ms)","trace[699562007] 'applied index is now lower than readState.Index' (duration: 423.534203ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T02:11:29.326212Z","caller":"traceutil/trace.go:171","msg":"trace[1033254570] transaction","detail":"{read_only:false; response_revision:25743; number_of_response:1; }","duration":"462.648996ms","start":"2026-10-04T02:11:28.863547Z","end":"2026-10-04T02:11:29.326196Z","steps":["trace[1033254570] 'process raft request' (duration: 462.369851ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:11:29.326237Z","caller":"traceutil/trace.go:171","msg":"trace[1575551756] transaction","detail":"{read_only:false; response_revision:25744; number_of_response:1; }","duration":"458.02527ms","start":"2026-10-04T02:11:28.868196Z","end":"2026-10-04T02:11:29.326221Z","steps":["trace[1575551756] 'process raft request' (duration: 457.847357ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:11:29.326263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:11:28.863511Z","time spent":"462.725328ms","remote":"127.0.0.1:56236","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-10-04T02:11:29.326305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:11:28.868186Z","time spent":"458.075511ms","remote":"127.0.0.1:56236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T02:11:29.326421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.892966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T02:11:29.326501Z","caller":"traceutil/trace.go:171","msg":"trace[1381983334] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25744; }","duration":"427.004577ms","start":"2026-10-04T02:11:28.899486Z","end":"2026-10-04T02:11:29.32649Z","steps":["trace[1381983334] 'agreement among raft nodes before linearized reading' (duration: 426.868734ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:11:29.326531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:11:28.899454Z","time spent":"427.070409ms","remote":"127.0.0.1:55972","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-10-04T02:11:29.368428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.332342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-04T02:11:29.368551Z","caller":"traceutil/trace.go:171","msg":"trace[704765144] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:25744; }","duration":"378.518486ms","start":"2026-10-04T02:11:28.990006Z","end":"2026-10-04T02:11:29.368525Z","steps":["trace[704765144] 'agreement among raft nodes before linearized reading' (duration: 378.274931ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:11:29.368603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:11:28.989982Z","time spent":"378.610778ms","remote":"127.0.0.1:38554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":60,"response size":31,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-10-04T02:11:29.368958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.33623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-04T02:11:29.36905Z","caller":"traceutil/trace.go:171","msg":"trace[887639444] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25744; }","duration":"397.443381ms","start":"2026-10-04T02:11:28.971593Z","end":"2026-10-04T02:11:29.369037Z","steps":["trace[887639444] 'agreement among raft nodes before linearized reading' (duration: 397.217907ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:11:29.369085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:11:28.971584Z","time spent":"397.493592ms","remote":"127.0.0.1:56236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-04T02:11:29.369162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.050121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-04T02:11:29.369274Z","caller":"traceutil/trace.go:171","msg":"trace[1552451182] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25744; }","duration":"433.217603ms","start":"2026-10-04T02:11:28.936033Z","end":"2026-10-04T02:11:29.36925Z","steps":["trace[1552451182] 'agreement among raft nodes before linearized reading' (duration: 432.878887ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:11:29.369327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:11:28.936021Z","time spent":"433.297335ms","remote":"127.0.0.1:56236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-04T02:11:29.369333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.688388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-04T02:11:29.369371Z","caller":"traceutil/trace.go:171","msg":"trace[1130163117] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25744; }","duration":"461.725338ms","start":"2026-10-04T02:11:28.907636Z","end":"2026-10-04T02:11:29.369361Z","steps":["trace[1130163117] 'agreement among raft nodes before linearized reading' (duration: 461.601836ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:11:29.369403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:11:28.907575Z","time spent":"461.82209ms","remote":"127.0.0.1:56236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-04T02:11:29.369742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.285791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-04T02:11:29.369784Z","caller":"traceutil/trace.go:171","msg":"trace[194596501] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25744; }","duration":"428.347762ms","start":"2026-10-04T02:11:28.941427Z","end":"2026-10-04T02:11:29.369775Z","steps":["trace[194596501] 'agreement among raft nodes before linearized reading' (duration: 428.24234ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:11:29.36981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:11:28.941416Z","time spent":"428.388072ms","remote":"127.0.0.1:56236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-04T02:11:29.369955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.261527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-04T02:11:29.369986Z","caller":"traceutil/trace.go:171","msg":"trace[1076586315] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25744; }","duration":"406.290728ms","start":"2026-10-04T02:11:28.963687Z","end":"2026-10-04T02:11:29.369977Z","steps":["trace[1076586315] 'agreement among raft nodes before linearized reading' (duration: 406.204856ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:11:29.370009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:11:28.963644Z","time spent":"406.359659ms","remote":"127.0.0.1:56236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-04T02:11:29.370235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.485184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-04T02:11:29.370936Z","caller":"traceutil/trace.go:171","msg":"trace[174688685] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25744; }","duration":"412.617986ms","start":"2026-10-04T02:11:28.957695Z","end":"2026-10-04T02:11:29.370313Z","steps":["trace[174688685] 'agreement among raft nodes before linearized reading' (duration: 412.341141ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:11:29.371079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:11:28.957686Z","time spent":"413.347271ms","remote":"127.0.0.1:56236","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-10-04T02:11:29.701772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.721702ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-04T02:11:29.702207Z","caller":"traceutil/trace.go:171","msg":"trace[1163291702] transaction","detail":"{read_only:false; response_revision:25747; number_of_response:1; }","duration":"323.591955ms","start":"2026-10-04T02:11:29.37855Z","end":"2026-10-04T02:11:29.702142Z","steps":["trace[1163291702] 'process raft request' (duration: 323.33149ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:11:29.702272Z","caller":"traceutil/trace.go:171","msg":"trace[701800449] transaction","detail":"{read_only:false; response_revision:25748; number_of_response:1; }","duration":"323.587516ms","start":"2026-10-04T02:11:29.37865Z","end":"2026-10-04T02:11:29.702237Z","steps":["trace[701800449] 'process raft request' (duration: 323.362242ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:11:29.702374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:11:29.378639Z","time spent":"323.689987ms","remote":"127.0.0.1:56236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T02:11:29.702427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:11:29.378537Z","time spent":"323.771889ms","remote":"127.0.0.1:56236","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-10-04T02:11:29.702384Z","caller":"traceutil/trace.go:171","msg":"trace[1627769894] transaction","detail":"{read_only:false; response_revision:25746; number_of_response:1; }","duration":"327.304755ms","start":"2026-10-04T02:11:29.374706Z","end":"2026-10-04T02:11:29.70201Z","steps":["trace[1627769894] 'process raft request' (duration: 170.231956ms)","trace[1627769894] 'compare' (duration: 156.036599ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T02:11:29.702426Z","caller":"traceutil/trace.go:171","msg":"trace[837663576] transaction","detail":"{read_only:false; response_revision:25749; number_of_response:1; }","duration":"323.676607ms","start":"2026-10-04T02:11:29.378708Z","end":"2026-10-04T02:11:29.702384Z","steps":["trace[837663576] 'process raft request' (duration: 323.360402ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:11:29.702693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:11:29.378701Z","time spent":"323.853741ms","remote":"127.0.0.1:56236","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-10-04T02:11:29.702949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:11:29.374685Z","time spent":"327.858224ms","remote":"127.0.0.1:56236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T02:11:29.703252Z","caller":"traceutil/trace.go:171","msg":"trace[944015167] transaction","detail":"{read_only:false; response_revision:25750; number_of_response:1; }","duration":"324.470762ms","start":"2026-10-04T02:11:29.378765Z","end":"2026-10-04T02:11:29.703236Z","steps":["trace[944015167] 'process raft request' (duration: 323.419753ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:11:29.703336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:11:29.378759Z","time spent":"324.536583ms","remote":"127.0.0.1:56236","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-10-04T02:11:29.974688Z","caller":"traceutil/trace.go:171","msg":"trace[1519952314] linearizableReadLoop","detail":"{readStateIndex:26594; appliedIndex:26593; }","duration":"103.448052ms","start":"2026-10-04T02:11:29.871192Z","end":"2026-10-04T02:11:29.974641Z","steps":["trace[1519952314] 'read index received' (duration: 28.025696ms)","trace[1519952314] 'applied index is now lower than readState.Index' (duration: 75.420046ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T02:11:29.975052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.805189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T02:11:29.975124Z","caller":"traceutil/trace.go:171","msg":"trace[13844186] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:25751; }","duration":"103.961311ms","start":"2026-10-04T02:11:29.871143Z","end":"2026-10-04T02:11:29.975104Z","steps":["trace[13844186] 'agreement among raft nodes before linearized reading' (duration: 103.741177ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:11:29.974893Z","caller":"traceutil/trace.go:171","msg":"trace[267882363] transaction","detail":"{read_only:false; response_revision:25751; number_of_response:1; }","duration":"181.262893ms","start":"2026-10-04T02:11:29.793596Z","end":"2026-10-04T02:11:29.974858Z","steps":["trace[267882363] 'process raft request' (duration: 105.749555ms)","trace[267882363] 'compare' (duration: 75.021009ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T02:11:30.282128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.868807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/images/\" range_end:\"/registry/openstack.k-orc.cloud/images0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T02:11:30.282566Z","caller":"traceutil/trace.go:171","msg":"trace[176055399] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/images/; range_end:/registry/openstack.k-orc.cloud/images0; response_count:0; response_revision:25751; }","duration":"148.389415ms","start":"2026-10-04T02:11:30.134147Z","end":"2026-10-04T02:11:30.282537Z","steps":["trace[176055399] 'count revisions from in-memory index tree' (duration: 147.759854ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:11:30.417061Z","caller":"traceutil/trace.go:171","msg":"trace[2105438253] transaction","detail":"{read_only:false; response_revision:25752; number_of_response:1; }","duration":"182.625419ms","start":"2026-10-04T02:11:30.23439Z","end":"2026-10-04T02:11:30.417015Z","steps":["trace[2105438253] 'process raft request' (duration: 182.304523ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:11:30.652524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.459949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T02:11:30.652639Z","caller":"traceutil/trace.go:171","msg":"trace[1920480741] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25752; }","duration":"147.647652ms","start":"2026-10-04T02:11:30.504964Z","end":"2026-10-04T02:11:30.652612Z","steps":["trace[1920480741] 'range keys from in-memory index tree' (duration: 147.347007ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:11:31.134956Z","caller":"traceutil/trace.go:171","msg":"trace[646602007] transaction","detail":"{read_only:false; response_revision:25753; number_of_response:1; }","duration":"226.114754ms","start":"2026-10-04T02:11:30.90878Z","end":"2026-10-04T02:11:31.134894Z","steps":["trace[646602007] 'process raft request' (duration: 225.87111ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:11:31.542795Z","caller":"traceutil/trace.go:171","msg":"trace[363058439] transaction","detail":"{read_only:false; response_revision:25755; number_of_response:1; }","duration":"209.982943ms","start":"2026-10-04T02:11:31.332766Z","end":"2026-10-04T02:11:31.542749Z","steps":["trace[363058439] 'process raft request' (duration: 209.634987ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:11:31.543385Z","caller":"traceutil/trace.go:171","msg":"trace[89341230] linearizableReadLoop","detail":"{readStateIndex:26603; appliedIndex:26597; }","duration":"145.797777ms","start":"2026-10-04T02:11:31.397554Z","end":"2026-10-04T02:11:31.543352Z","steps":["trace[89341230] 'read index received' (duration: 144.769458ms)","trace[89341230] 'applied index is now lower than readState.Index' (duration: 1.026869ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T02:11:31.543743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.171824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-04T02:11:31.543807Z","caller":"traceutil/trace.go:171","msg":"trace[582336531] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25760; }","duration":"146.300367ms","start":"2026-10-04T02:11:31.397479Z","end":"2026-10-04T02:11:31.54378Z","steps":["trace[582336531] 'agreement among raft nodes before linearized reading' (duration: 146.020882ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:11:31.544039Z","caller":"traceutil/trace.go:171","msg":"trace[521234021] transaction","detail":"{read_only:false; response_revision:25756; number_of_response:1; }","duration":"211.192734ms","start":"2026-10-04T02:11:31.332834Z","end":"2026-10-04T02:11:31.544027Z","steps":["trace[521234021] 'process raft request' (duration: 209.85456ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:11:31.5442Z","caller":"traceutil/trace.go:171","msg":"trace[889054044] transaction","detail":"{read_only:false; response_revision:25759; number_of_response:1; }","duration":"211.203465ms","start":"2026-10-04T02:11:31.332982Z","end":"2026-10-04T02:11:31.544185Z","steps":["trace[889054044] 'process raft request' (duration: 210.249138ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:11:31.544348Z","caller":"traceutil/trace.go:171","msg":"trace[278505829] transaction","detail":"{read_only:false; response_revision:25757; number_of_response:1; }","duration":"211.51576ms","start":"2026-10-04T02:11:31.332824Z","end":"2026-10-04T02:11:31.54434Z","steps":["trace[278505829] 'process raft request' (duration: 210.178256ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:11:31.5444Z","caller":"traceutil/trace.go:171","msg":"trace[610423038] transaction","detail":"{read_only:false; response_revision:25758; number_of_response:1; }","duration":"211.528441ms","start":"2026-10-04T02:11:31.332865Z","end":"2026-10-04T02:11:31.544393Z","steps":["trace[610423038] 'process raft request' (duration: 210.309139ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:11:31.544482Z","caller":"traceutil/trace.go:171","msg":"trace[1478056535] transaction","detail":"{read_only:false; response_revision:25760; number_of_response:1; }","duration":"175.709409ms","start":"2026-10-04T02:11:31.368765Z","end":"2026-10-04T02:11:31.544475Z","steps":["trace[1478056535] 'process raft request' (duration: 174.522817ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:11:31.864856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.833176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-10-04T02:11:31.864866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.496248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-04T02:11:31.864944Z","caller":"traceutil/trace.go:171","msg":"trace[1950801004] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25761; }","duration":"155.998929ms","start":"2026-10-04T02:11:31.708922Z","end":"2026-10-04T02:11:31.864921Z","steps":["trace[1950801004] 'range keys from in-memory index tree' (duration: 155.583721ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:11:31.864976Z","caller":"traceutil/trace.go:171","msg":"trace[477684596] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25761; }","duration":"156.685452ms","start":"2026-10-04T02:11:31.70826Z","end":"2026-10-04T02:11:31.864946Z","steps":["trace[477684596] 'range keys from in-memory index tree' (duration: 156.159061ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:11:31.864959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.728116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-04T02:11:31.865081Z","caller":"traceutil/trace.go:171","msg":"trace[1563844817] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25761; }","duration":"153.857529ms","start":"2026-10-04T02:11:31.711194Z","end":"2026-10-04T02:11:31.865052Z","steps":["trace[1563844817] 'range keys from in-memory index tree' (duration: 153.523132ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:11:31.86508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.094199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-04T02:11:31.865189Z","caller":"traceutil/trace.go:171","msg":"trace[1084420187] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25761; }","duration":"157.276693ms","start":"2026-10-04T02:11:31.707891Z","end":"2026-10-04T02:11:31.865167Z","steps":["trace[1084420187] 'range keys from in-memory index tree' (duration: 156.862375ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:11:31.865512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.074329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-04T02:11:31.865614Z","caller":"traceutil/trace.go:171","msg":"trace[1663442374] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25761; }","duration":"157.235302ms","start":"2026-10-04T02:11:31.708354Z","end":"2026-10-04T02:11:31.865589Z","steps":["trace[1663442374] 'range keys from in-memory index tree' (duration: 156.687372ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:11:32.161959Z","caller":"traceutil/trace.go:171","msg":"trace[1199972414] linearizableReadLoop","detail":"{readStateIndex:26609; appliedIndex:26606; }","duration":"179.912428ms","start":"2026-10-04T02:11:31.982019Z","end":"2026-10-04T02:11:32.161932Z","steps":["trace[1199972414] 'read index received' (duration: 179.551031ms)","trace[1199972414] 'applied index is now lower than readState.Index' (duration: 360.186µs)"],"step_count":2} {"level":"info","ts":"2026-10-04T02:11:32.162038Z","caller":"traceutil/trace.go:171","msg":"trace[356337067] transaction","detail":"{read_only:false; response_revision:25765; number_of_response:1; }","duration":"291.104565ms","start":"2026-10-04T02:11:31.870871Z","end":"2026-10-04T02:11:32.161975Z","steps":["trace[356337067] 'process raft request' (duration: 290.984593ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:11:32.162113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.06162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T02:11:32.162088Z","caller":"traceutil/trace.go:171","msg":"trace[401683354] transaction","detail":"{read_only:false; response_revision:25764; number_of_response:1; }","duration":"291.445061ms","start":"2026-10-04T02:11:31.870594Z","end":"2026-10-04T02:11:32.162039Z","steps":["trace[401683354] 'process raft request' (duration: 291.057814ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:11:32.162163Z","caller":"traceutil/trace.go:171","msg":"trace[1734111602] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:25766; }","duration":"180.137161ms","start":"2026-10-04T02:11:31.98201Z","end":"2026-10-04T02:11:32.162147Z","steps":["trace[1734111602] 'agreement among raft nodes before linearized reading' (duration: 180.027909ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:11:32.162193Z","caller":"traceutil/trace.go:171","msg":"trace[1130979093] transaction","detail":"{read_only:false; response_revision:25766; number_of_response:1; }","duration":"290.978153ms","start":"2026-10-04T02:11:31.871187Z","end":"2026-10-04T02:11:32.162165Z","steps":["trace[1130979093] 'process raft request' (duration: 290.706448ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:11:32.437906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.457073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T02:11:32.438026Z","caller":"traceutil/trace.go:171","msg":"trace[141076806] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25766; }","duration":"200.649937ms","start":"2026-10-04T02:11:32.237348Z","end":"2026-10-04T02:11:32.437998Z","steps":["trace[141076806] 'range keys from in-memory index tree' (duration: 200.32618ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:11:33.048128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.959646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T02:11:33.048232Z","caller":"traceutil/trace.go:171","msg":"trace[1478978941] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25767; }","duration":"141.128259ms","start":"2026-10-04T02:11:32.907081Z","end":"2026-10-04T02:11:33.048209Z","steps":["trace[1478978941] 'range keys from in-memory index tree' (duration: 140.855134ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:11:33.665189Z","caller":"traceutil/trace.go:171","msg":"trace[1437299660] linearizableReadLoop","detail":"{readStateIndex:26612; appliedIndex:26611; }","duration":"206.364454ms","start":"2026-10-04T02:11:33.458786Z","end":"2026-10-04T02:11:33.66515Z","steps":["trace[1437299660] 'read index received' (duration: 206.180601ms)","trace[1437299660] 'applied index is now lower than readState.Index' (duration: 181.873µs)"],"step_count":2} {"level":"info","ts":"2026-10-04T02:11:33.66527Z","caller":"traceutil/trace.go:171","msg":"trace[1959819021] transaction","detail":"{read_only:false; response_revision:25769; number_of_response:1; }","duration":"283.741787ms","start":"2026-10-04T02:11:33.381477Z","end":"2026-10-04T02:11:33.665219Z","steps":["trace[1959819021] 'process raft request' (duration: 283.436601ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:11:33.665735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.62903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T02:11:33.665876Z","caller":"traceutil/trace.go:171","msg":"trace[800137199] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:25769; }","duration":"207.132749ms","start":"2026-10-04T02:11:33.458717Z","end":"2026-10-04T02:11:33.665849Z","steps":["trace[800137199] 'agreement among raft nodes before linearized reading' (duration: 206.597559ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:11:34.016113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.584011ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-04T02:11:34.016334Z","caller":"traceutil/trace.go:171","msg":"trace[736349685] transaction","detail":"{read_only:false; response_revision:25770; number_of_response:1; }","duration":"464.857307ms","start":"2026-10-04T02:11:33.551447Z","end":"2026-10-04T02:11:34.016304Z","steps":["trace[736349685] 'process raft request' (duration: 243.94789ms)","trace[736349685] 'compare' (duration: 219.95001ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T02:11:34.016423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:11:33.55142Z","time spent":"464.966659ms","remote":"127.0.0.1:56236","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-10-04T02:11:34.016773Z","caller":"traceutil/trace.go:171","msg":"trace[1987983858] transaction","detail":"{read_only:false; response_revision:25771; number_of_response:1; }","duration":"465.218304ms","start":"2026-10-04T02:11:33.551512Z","end":"2026-10-04T02:11:34.01673Z","steps":["trace[1987983858] 'process raft request' (duration: 464.727565ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:11:34.016823Z","caller":"traceutil/trace.go:171","msg":"trace[1060702461] transaction","detail":"{read_only:false; response_revision:25772; number_of_response:1; }","duration":"465.060881ms","start":"2026-10-04T02:11:33.551725Z","end":"2026-10-04T02:11:34.016786Z","steps":["trace[1060702461] 'process raft request' (duration: 464.866938ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:11:34.017139Z","caller":"traceutil/trace.go:171","msg":"trace[284359093] linearizableReadLoop","detail":"{readStateIndex:26617; appliedIndex:26612; }","duration":"351.831564ms","start":"2026-10-04T02:11:33.665292Z","end":"2026-10-04T02:11:34.017124Z","steps":["trace[284359093] 'read index received' (duration: 129.953309ms)","trace[284359093] 'applied index is now lower than readState.Index' (duration: 221.873035ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T02:11:34.017182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:11:33.551477Z","time spent":"465.397767ms","remote":"127.0.0.1:56236","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-10-04T02:11:34.017247Z","caller":"traceutil/trace.go:171","msg":"trace[636583426] transaction","detail":"{read_only:false; response_revision:25774; number_of_response:1; }","duration":"463.168275ms","start":"2026-10-04T02:11:33.554055Z","end":"2026-10-04T02:11:34.017223Z","steps":["trace[636583426] 'process raft request' (duration: 462.817259ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:11:34.017355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.103682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.69\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-10-04T02:11:34.017996Z","caller":"traceutil/trace.go:171","msg":"trace[1949006640] range","detail":"{range_begin:/registry/masterleases/162.253.55.69; range_end:; response_count:1; response_revision:25774; }","duration":"367.766824ms","start":"2026-10-04T02:11:33.650213Z","end":"2026-10-04T02:11:34.017979Z","steps":["trace[1949006640] 'agreement among raft nodes before linearized reading' (duration: 367.020411ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:11:34.017396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:11:33.554028Z","time spent":"463.285947ms","remote":"127.0.0.1:56236","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-10-04T02:11:34.018067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:11:33.650204Z","time spent":"367.850056ms","remote":"127.0.0.1:55996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":160,"request content":"key:\"/registry/masterleases/162.253.55.69\" "} {"level":"warn","ts":"2026-10-04T02:11:34.017372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:11:33.551708Z","time spent":"465.460078ms","remote":"127.0.0.1:56236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T02:11:34.017433Z","caller":"traceutil/trace.go:171","msg":"trace[1401660815] transaction","detail":"{read_only:false; response_revision:25773; number_of_response:1; }","duration":"464.260975ms","start":"2026-10-04T02:11:33.553165Z","end":"2026-10-04T02:11:34.017426Z","steps":["trace[1401660815] 'process raft request' (duration: 463.571982ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:11:34.018398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:11:33.553132Z","time spent":"465.157673ms","remote":"127.0.0.1:56236","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-10-04T02:11:34.017588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.034531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-04T02:11:34.01869Z","caller":"traceutil/trace.go:171","msg":"trace[559285047] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25774; }","duration":"441.113142ms","start":"2026-10-04T02:11:33.577546Z","end":"2026-10-04T02:11:34.018659Z","steps":["trace[559285047] 'agreement among raft nodes before linearized reading' (duration: 439.936759ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:11:34.018768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:11:33.577495Z","time spent":"441.255445ms","remote":"127.0.0.1:56236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-04T02:11:34.017627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.432667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-04T02:11:34.018918Z","caller":"traceutil/trace.go:171","msg":"trace[2084806536] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25774; }","duration":"116.752742ms","start":"2026-10-04T02:11:33.902136Z","end":"2026-10-04T02:11:34.018889Z","steps":["trace[2084806536] 'agreement among raft nodes before linearized reading' (duration: 115.353835ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:11:34.017654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.919977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T02:11:34.019178Z","caller":"traceutil/trace.go:171","msg":"trace[1574544130] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25774; }","duration":"116.496997ms","start":"2026-10-04T02:11:33.902667Z","end":"2026-10-04T02:11:34.019164Z","steps":["trace[1574544130] 'agreement among raft nodes before linearized reading' (duration: 114.962368ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:11:34.017786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.417727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-04T02:11:34.019357Z","caller":"traceutil/trace.go:171","msg":"trace[1613051704] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25774; }","duration":"116.039578ms","start":"2026-10-04T02:11:33.903301Z","end":"2026-10-04T02:11:34.019341Z","steps":["trace[1613051704] 'agreement among raft nodes before linearized reading' (duration: 114.314546ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:11:34.258886Z","caller":"traceutil/trace.go:171","msg":"trace[1011416393] transaction","detail":"{read_only:false; response_revision:25775; number_of_response:1; }","duration":"234.34676ms","start":"2026-10-04T02:11:34.024508Z","end":"2026-10-04T02:11:34.258855Z","steps":["trace[1011416393] 'process raft request' (duration: 218.629945ms)","trace[1011416393] 'compare' (duration: 15.182255ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T02:11:34.259514Z","caller":"traceutil/trace.go:171","msg":"trace[218153686] transaction","detail":"{read_only:false; response_revision:25777; number_of_response:1; }","duration":"234.935801ms","start":"2026-10-04T02:11:34.024565Z","end":"2026-10-04T02:11:34.2595Z","steps":["trace[218153686] 'process raft request' (duration: 234.749838ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:11:34.259538Z","caller":"traceutil/trace.go:171","msg":"trace[456291550] transaction","detail":"{read_only:false; response_revision:25778; number_of_response:1; }","duration":"222.132881ms","start":"2026-10-04T02:11:34.037385Z","end":"2026-10-04T02:11:34.259518Z","steps":["trace[456291550] 'process raft request' (duration: 221.986628ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:11:34.259426Z","caller":"traceutil/trace.go:171","msg":"trace[48540374] transaction","detail":"{read_only:false; response_revision:25776; number_of_response:1; }","duration":"234.84975ms","start":"2026-10-04T02:11:34.024543Z","end":"2026-10-04T02:11:34.259393Z","steps":["trace[48540374] 'process raft request' (duration: 234.253248ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:11:34.496825Z","caller":"traceutil/trace.go:171","msg":"trace[144146913] transaction","detail":"{read_only:false; response_revision:25781; number_of_response:1; }","duration":"229.558879ms","start":"2026-10-04T02:11:34.26723Z","end":"2026-10-04T02:11:34.496789Z","steps":["trace[144146913] 'process raft request' (duration: 229.298185ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:11:35.349252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.992738ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-04T02:11:35.349435Z","caller":"traceutil/trace.go:171","msg":"trace[1481653751] transaction","detail":"{read_only:false; response_revision:25783; number_of_response:1; }","duration":"131.399757ms","start":"2026-10-04T02:11:35.218Z","end":"2026-10-04T02:11:35.3494Z","steps":["trace[1481653751] 'process raft request' (duration: 15.143654ms)","trace[1481653751] 'compare' (duration: 115.752094ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T02:11:39.970508Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22953} {"level":"info","ts":"2026-10-04T02:11:40.150913Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22953,"took":"173.62106ms","hash":294548690,"current-db-size-bytes":59535360,"current-db-size":"60 MB","current-db-size-in-use-bytes":38219776,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-10-04T02:11:40.150988Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":294548690,"revision":22953,"compact-revision":20134} {"level":"info","ts":"2026-10-04T02:11:42.491312Z","caller":"traceutil/trace.go:171","msg":"trace[1470567068] transaction","detail":"{read_only:false; response_revision:25837; number_of_response:1; }","duration":"183.883562ms","start":"2026-10-04T02:11:42.307391Z","end":"2026-10-04T02:11:42.491274Z","steps":["trace[1470567068] 'process raft request' (duration: 102.26265ms)","trace[1470567068] 'compare' (duration: 81.316646ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T02:11:42.491485Z","caller":"traceutil/trace.go:171","msg":"trace[943208115] linearizableReadLoop","detail":"{readStateIndex:26684; appliedIndex:26682; }","duration":"145.648315ms","start":"2026-10-04T02:11:42.345774Z","end":"2026-10-04T02:11:42.491422Z","steps":["trace[943208115] 'read index received' (duration: 63.89309ms)","trace[943208115] 'applied index is now lower than readState.Index' (duration: 81.751835ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T02:11:42.491518Z","caller":"traceutil/trace.go:171","msg":"trace[1463215245] transaction","detail":"{read_only:false; response_revision:25838; number_of_response:1; }","duration":"183.025636ms","start":"2026-10-04T02:11:42.308467Z","end":"2026-10-04T02:11:42.491492Z","steps":["trace[1463215245] 'process raft request' (duration: 182.72853ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:11:42.49174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.95515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-04T02:11:42.491776Z","caller":"traceutil/trace.go:171","msg":"trace[133816386] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25838; }","duration":"146.047222ms","start":"2026-10-04T02:11:42.345718Z","end":"2026-10-04T02:11:42.491765Z","steps":["trace[133816386] 'agreement among raft nodes before linearized reading' (duration: 145.874819ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:11:42.491932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.644469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-04T02:11:42.491981Z","caller":"traceutil/trace.go:171","msg":"trace[1115599027] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25838; }","duration":"141.71627ms","start":"2026-10-04T02:11:42.350252Z","end":"2026-10-04T02:11:42.491968Z","steps":["trace[1115599027] 'agreement among raft nodes before linearized reading' (duration: 141.572247ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:12:12.561956Z","caller":"traceutil/trace.go:171","msg":"trace[560259474] transaction","detail":"{read_only:false; response_revision:26205; number_of_response:1; }","duration":"306.491334ms","start":"2026-10-04T02:12:12.25543Z","end":"2026-10-04T02:12:12.561921Z","steps":["trace[560259474] 'process raft request' (duration: 231.632549ms)","trace[560259474] 'compare' (duration: 74.498008ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T02:12:12.562147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:12:12.255407Z","time spent":"306.640236ms","remote":"127.0.0.1:56236","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-10-04T02:12:12.589714Z","caller":"traceutil/trace.go:171","msg":"trace[762588090] transaction","detail":"{read_only:false; response_revision:26206; number_of_response:1; }","duration":"324.664935ms","start":"2026-10-04T02:12:12.26498Z","end":"2026-10-04T02:12:12.589645Z","steps":["trace[762588090] 'process raft request' (duration: 324.168746ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:12:12.589911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:12:12.26495Z","time spent":"324.853069ms","remote":"127.0.0.1:56236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T02:12:12.589919Z","caller":"traceutil/trace.go:171","msg":"trace[384501856] transaction","detail":"{read_only:false; response_revision:26208; number_of_response:1; }","duration":"313.019306ms","start":"2026-10-04T02:12:12.276821Z","end":"2026-10-04T02:12:12.58984Z","steps":["trace[384501856] 'process raft request' (duration: 312.519177ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:12:12.589861Z","caller":"traceutil/trace.go:171","msg":"trace[1599944794] transaction","detail":"{read_only:false; response_revision:26207; number_of_response:1; }","duration":"317.736184ms","start":"2026-10-04T02:12:12.272023Z","end":"2026-10-04T02:12:12.589759Z","steps":["trace[1599944794] 'process raft request' (duration: 317.279556ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:12:12.590172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:12:12.271999Z","time spent":"318.088382ms","remote":"127.0.0.1:56236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T02:12:12.590193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:12:12.276805Z","time spent":"313.23905ms","remote":"127.0.0.1:56236","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-10-04T02:13:39.623901Z","caller":"traceutil/trace.go:171","msg":"trace[1132838894] transaction","detail":"{read_only:false; response_revision:26978; number_of_response:1; }","duration":"114.369795ms","start":"2026-10-04T02:13:39.5095Z","end":"2026-10-04T02:13:39.62387Z","steps":["trace[1132838894] 'process raft request' (duration: 113.528709ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:15:03.613006Z","caller":"traceutil/trace.go:171","msg":"trace[810925313] transaction","detail":"{read_only:false; response_revision:27837; number_of_response:1; }","duration":"149.16905ms","start":"2026-10-04T02:15:03.463807Z","end":"2026-10-04T02:15:03.612976Z","steps":["trace[810925313] 'process raft request' (duration: 148.938416ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:15:03.613397Z","caller":"traceutil/trace.go:171","msg":"trace[80501132] transaction","detail":"{read_only:false; response_revision:27838; number_of_response:1; }","duration":"148.638902ms","start":"2026-10-04T02:15:03.464735Z","end":"2026-10-04T02:15:03.613374Z","steps":["trace[80501132] 'process raft request' (duration: 148.192573ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:15:03.613476Z","caller":"traceutil/trace.go:171","msg":"trace[701798854] transaction","detail":"{read_only:false; response_revision:27839; number_of_response:1; }","duration":"148.64413ms","start":"2026-10-04T02:15:03.464815Z","end":"2026-10-04T02:15:03.613459Z","steps":["trace[701798854] 'process raft request' (duration: 148.397975ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:16:39.985453Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25815} {"level":"info","ts":"2026-10-04T02:16:40.141504Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25815,"took":"149.605778ms","hash":376844935,"current-db-size-bytes":59535360,"current-db-size":"60 MB","current-db-size-in-use-bytes":37756928,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-10-04T02:16:40.141601Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":376844935,"revision":25815,"compact-revision":22953} {"level":"info","ts":"2026-10-04T02:17:31.660248Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"905ccde8a677f143","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-04T02:17:31.667317Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-04T02:17:31.667459Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-04T02:18:19.183084Z","caller":"traceutil/trace.go:171","msg":"trace[1900564645] linearizableReadLoop","detail":"{readStateIndex:30361; appliedIndex:30359; }","duration":"137.696914ms","start":"2026-10-04T02:18:19.045358Z","end":"2026-10-04T02:18:19.183055Z","steps":["trace[1900564645] 'read index received' (duration: 34.236845ms)","trace[1900564645] 'applied index is now lower than readState.Index' (duration: 103.458319ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T02:18:19.183133Z","caller":"traceutil/trace.go:171","msg":"trace[170839926] transaction","detail":"{read_only:false; response_revision:29421; number_of_response:1; }","duration":"142.109318ms","start":"2026-10-04T02:18:19.040958Z","end":"2026-10-04T02:18:19.183068Z","steps":["trace[170839926] 'process raft request' (duration: 115.577407ms)","trace[170839926] 'compare' (duration: 26.176784ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T02:18:19.195602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.22242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-04T02:18:19.195686Z","caller":"traceutil/trace.go:171","msg":"trace[2051444405] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:29421; }","duration":"150.344331ms","start":"2026-10-04T02:18:19.045319Z","end":"2026-10-04T02:18:19.195663Z","steps":["trace[2051444405] 'agreement among raft nodes before linearized reading' (duration: 137.906578ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T02:21:39.992687Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28692} {"level":"info","ts":"2026-10-04T02:21:40.147125Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28692,"took":"150.041517ms","hash":3017569240,"current-db-size-bytes":59535360,"current-db-size":"60 MB","current-db-size-in-use-bytes":30486528,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-04T02:21:40.14721Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3017569240,"revision":28692,"compact-revision":25815} {"level":"info","ts":"2026-10-04T02:26:40.000723Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30915} {"level":"info","ts":"2026-10-04T02:26:40.147773Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30915,"took":"141.627667ms","hash":3225747101,"current-db-size-bytes":59535360,"current-db-size":"60 MB","current-db-size-in-use-bytes":30396416,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-04T02:26:40.147926Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3225747101,"revision":30915,"compact-revision":28692} {"level":"info","ts":"2026-10-04T02:31:40.011269Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33173} {"level":"info","ts":"2026-10-04T02:31:40.193155Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33173,"took":"176.237065ms","hash":4213260513,"current-db-size-bytes":59535360,"current-db-size":"60 MB","current-db-size-in-use-bytes":30212096,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-04T02:31:40.193309Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4213260513,"revision":33173,"compact-revision":30915} {"level":"info","ts":"2026-10-04T02:32:17.37534Z","caller":"traceutil/trace.go:171","msg":"trace[673838001] linearizableReadLoop","detail":"{readStateIndex:36846; appliedIndex:36845; }","duration":"107.752958ms","start":"2026-10-04T02:32:17.267566Z","end":"2026-10-04T02:32:17.375319Z","steps":["trace[673838001] 'read index received' (duration: 107.571234ms)","trace[673838001] 'applied index is now lower than readState.Index' (duration: 181.034µs)"],"step_count":2} {"level":"info","ts":"2026-10-04T02:32:17.375474Z","caller":"traceutil/trace.go:171","msg":"trace[998481067] transaction","detail":"{read_only:false; response_revision:35724; number_of_response:1; }","duration":"137.13971ms","start":"2026-10-04T02:32:17.238233Z","end":"2026-10-04T02:32:17.375373Z","steps":["trace[998481067] 'process raft request' (duration: 136.945846ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:32:17.375505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.91724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T02:32:17.375618Z","caller":"traceutil/trace.go:171","msg":"trace[1092170468] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:35724; }","duration":"108.050393ms","start":"2026-10-04T02:32:17.267552Z","end":"2026-10-04T02:32:17.375602Z","steps":["trace[1092170468] 'agreement among raft nodes before linearized reading' (duration: 107.863549ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:32:20.103336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.431486ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-04T02:32:20.103463Z","caller":"traceutil/trace.go:171","msg":"trace[599966738] linearizableReadLoop","detail":"{readStateIndex:36867; appliedIndex:36866; }","duration":"522.114591ms","start":"2026-10-04T02:32:19.581328Z","end":"2026-10-04T02:32:20.103443Z","steps":["trace[599966738] 'read index received' (duration: 123.617525ms)","trace[599966738] 'applied index is now lower than readState.Index' (duration: 398.494996ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T02:32:20.103534Z","caller":"traceutil/trace.go:171","msg":"trace[372174567] transaction","detail":"{read_only:false; response_revision:35744; number_of_response:1; }","duration":"628.220607ms","start":"2026-10-04T02:32:19.475291Z","end":"2026-10-04T02:32:20.103511Z","steps":["trace[372174567] 'process raft request' (duration: 229.65006ms)","trace[372174567] 'compare' (duration: 396.990917ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T02:32:20.10363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:32:19.475266Z","time spent":"628.321209ms","remote":"127.0.0.1:56236","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-10-04T02:32:20.103869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.529108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-04T02:32:20.104528Z","caller":"traceutil/trace.go:171","msg":"trace[1367615804] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:35744; }","duration":"523.219561ms","start":"2026-10-04T02:32:19.581278Z","end":"2026-10-04T02:32:20.104497Z","steps":["trace[1367615804] 'agreement among raft nodes before linearized reading' (duration: 522.375046ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:32:20.104665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:32:19.581259Z","time spent":"523.390054ms","remote":"127.0.0.1:56236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-04T02:32:20.104033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.761918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-04T02:32:20.105058Z","caller":"traceutil/trace.go:171","msg":"trace[1965430953] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:35744; }","duration":"116.819307ms","start":"2026-10-04T02:32:19.988223Z","end":"2026-10-04T02:32:20.105042Z","steps":["trace[1965430953] 'agreement among raft nodes before linearized reading' (duration: 115.698787ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:32:20.104083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.291664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T02:32:20.105314Z","caller":"traceutil/trace.go:171","msg":"trace[595477366] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:35744; }","duration":"204.558198ms","start":"2026-10-04T02:32:19.900732Z","end":"2026-10-04T02:32:20.10529Z","steps":["trace[595477366] 'agreement among raft nodes before linearized reading' (duration: 203.243484ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:32:20.104267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.879788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-04T02:32:20.105469Z","caller":"traceutil/trace.go:171","msg":"trace[58035522] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:35744; }","duration":"337.086949ms","start":"2026-10-04T02:32:19.768337Z","end":"2026-10-04T02:32:20.105423Z","steps":["trace[58035522] 'agreement among raft nodes before linearized reading' (duration: 335.583432ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T02:32:20.105569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T02:32:19.768318Z","time spent":"337.222303ms","remote":"127.0.0.1:56236","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-10-04T02:36:40.018786Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35401} {"level":"info","ts":"2026-10-04T02:36:40.17112Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35401,"took":"146.633619ms","hash":110837630,"current-db-size-bytes":60002304,"current-db-size":"60 MB","current-db-size-in-use-bytes":32923648,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-04T02:36:40.171206Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":110837630,"revision":35401,"compact-revision":33173} {"level":"info","ts":"2026-10-04T02:39:03.9954Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"905ccde8a677f143","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-04T02:39:04.000854Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-04T02:39:04.000946Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-04T02:41:40.037222Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37696} {"level":"info","ts":"2026-10-04T02:41:40.175027Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37696,"took":"133.089333ms","hash":1293587943,"current-db-size-bytes":62119936,"current-db-size":"62 MB","current-db-size-in-use-bytes":32804864,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-04T02:41:40.175127Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1293587943,"revision":37696,"compact-revision":35401} {"level":"info","ts":"2026-10-04T02:46:40.054954Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39969} {"level":"info","ts":"2026-10-04T02:46:40.197061Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39969,"took":"139.315691ms","hash":1906457042,"current-db-size-bytes":62119936,"current-db-size":"62 MB","current-db-size-in-use-bytes":30277632,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-04T02:46:40.197165Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1906457042,"revision":39969,"compact-revision":37696}