{"level":"warn","ts":"2026-10-09T15:26:07.065358Z","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-09T15:26:07.065546Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.211: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.211:2380","--initial-cluster=instance=https://162.253.55.211:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.211:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.211: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-09T15:26:07.065677Z","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-09T15:26:07.065701Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.211:2380"]} {"level":"info","ts":"2026-10-09T15:26:07.065762Z","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-09T15:26:07.067101Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.211:2379"]} {"level":"info","ts":"2026-10-09T15:26:07.067554Z","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.211:2380"],"listen-peer-urls":["https://162.253.55.211:2380"],"advertise-client-urls":["https://162.253.55.211:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.211:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.211: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-09T15:26:07.073898Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.36436ms"} {"level":"info","ts":"2026-10-09T15:26:07.087328Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"931f6a422b4c426f","cluster-id":"8a95cf5058d193ed"} {"level":"info","ts":"2026-10-09T15:26:07.087437Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"931f6a422b4c426f switched to configuration voters=()"} {"level":"info","ts":"2026-10-09T15:26:07.087465Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"931f6a422b4c426f became follower at term 0"} {"level":"info","ts":"2026-10-09T15:26:07.087484Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 931f6a422b4c426f [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-09T15:26:07.0875Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"931f6a422b4c426f became follower at term 1"} {"level":"info","ts":"2026-10-09T15:26:07.087559Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"931f6a422b4c426f switched to configuration voters=(10601308880280240751)"} {"level":"warn","ts":"2026-10-09T15:26:07.092884Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-09T15:26:07.094557Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-09T15:26:07.096452Z","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-09T15:26:07.099311Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"931f6a422b4c426f","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-09T15:26:07.099524Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"931f6a422b4c426f","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-09T15:26:07.099602Z","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-09T15:26:07.099746Z","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-09T15:26:07.099766Z","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-09T15:26:07.102555Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"931f6a422b4c426f switched to configuration voters=(10601308880280240751)"} {"level":"info","ts":"2026-10-09T15:26:07.102979Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8a95cf5058d193ed","local-member-id":"931f6a422b4c426f","added-peer-id":"931f6a422b4c426f","added-peer-peer-urls":["https://162.253.55.211:2380"]} {"level":"info","ts":"2026-10-09T15:26:07.104515Z","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-09T15:26:07.104778Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"931f6a422b4c426f","initial-advertise-peer-urls":["https://162.253.55.211:2380"],"listen-peer-urls":["https://162.253.55.211:2380"],"advertise-client-urls":["https://162.253.55.211:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.211:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-09T15:26:07.104814Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-09T15:26:07.104895Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.211:2380"} {"level":"info","ts":"2026-10-09T15:26:07.10491Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.211:2380"} {"level":"info","ts":"2026-10-09T15:26:07.888803Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"931f6a422b4c426f is starting a new election at term 1"} {"level":"info","ts":"2026-10-09T15:26:07.888873Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"931f6a422b4c426f became pre-candidate at term 1"} {"level":"info","ts":"2026-10-09T15:26:07.888916Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"931f6a422b4c426f received MsgPreVoteResp from 931f6a422b4c426f at term 1"} {"level":"info","ts":"2026-10-09T15:26:07.888952Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"931f6a422b4c426f became candidate at term 2"} {"level":"info","ts":"2026-10-09T15:26:07.888963Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"931f6a422b4c426f received MsgVoteResp from 931f6a422b4c426f at term 2"} {"level":"info","ts":"2026-10-09T15:26:07.888978Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"931f6a422b4c426f became leader at term 2"} {"level":"info","ts":"2026-10-09T15:26:07.889007Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 931f6a422b4c426f elected leader 931f6a422b4c426f at term 2"} {"level":"info","ts":"2026-10-09T15:26:07.890343Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-09T15:26:07.891655Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-09T15:26:07.891909Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-09T15:26:07.891619Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"931f6a422b4c426f","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.211:2379]}","request-path":"/0/members/931f6a422b4c426f/attributes","cluster-id":"8a95cf5058d193ed","publish-timeout":"7s"} {"level":"info","ts":"2026-10-09T15:26:07.892115Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8a95cf5058d193ed","local-member-id":"931f6a422b4c426f","cluster-version":"3.5"} {"level":"info","ts":"2026-10-09T15:26:07.892211Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-09T15:26:07.892245Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-09T15:26:07.892257Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-09T15:26:07.892315Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-09T15:26:07.895152Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-09T15:26:07.895463Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.211:2379"} {"level":"info","ts":"2026-10-09T15:27:03.606285Z","caller":"traceutil/trace.go:171","msg":"trace[974332157] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:643; }","duration":"277.744582ms","start":"2026-10-09T15:27:03.328511Z","end":"2026-10-09T15:27:03.606256Z","steps":["trace[974332157] 'read index received' (duration: 277.62921ms)","trace[974332157] 'applied index is now lower than readState.Index' (duration: 114.372µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T15:27:03.606432Z","caller":"traceutil/trace.go:171","msg":"trace[1600437337] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"298.367684ms","start":"2026-10-09T15:27:03.307994Z","end":"2026-10-09T15:27:03.606361Z","steps":["trace[1600437337] 'process raft request' (duration: 298.043307ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T15:27:03.606485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.949197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-2nphc\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-10-09T15:27:03.606579Z","caller":"traceutil/trace.go:171","msg":"trace[1345637683] range","detail":"{range_begin:/registry/pods/kube-system/cilium-2nphc; range_end:; response_count:1; response_revision:618; }","duration":"278.09685ms","start":"2026-10-09T15:27:03.328471Z","end":"2026-10-09T15:27:03.606568Z","steps":["trace[1345637683] 'agreement among raft nodes before linearized reading' (duration: 277.892156ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:27:03.736388Z","caller":"traceutil/trace.go:171","msg":"trace[74031009] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"237.128174ms","start":"2026-10-09T15:27:03.499237Z","end":"2026-10-09T15:27:03.736365Z","steps":["trace[74031009] 'process raft request' (duration: 236.555872ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:27:03.74309Z","caller":"traceutil/trace.go:171","msg":"trace[1237638353] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"122.188529ms","start":"2026-10-09T15:27:03.620879Z","end":"2026-10-09T15:27:03.743068Z","steps":["trace[1237638353] 'process raft request' (duration: 122.074466ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:28:35.964879Z","caller":"traceutil/trace.go:171","msg":"trace[503055296] linearizableReadLoop","detail":"{readStateIndex:1932; appliedIndex:1931; }","duration":"179.848513ms","start":"2026-10-09T15:28:35.785004Z","end":"2026-10-09T15:28:35.964853Z","steps":["trace[503055296] 'read index received' (duration: 179.600137ms)","trace[503055296] 'applied index is now lower than readState.Index' (duration: 247.546µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T15:28:35.964935Z","caller":"traceutil/trace.go:171","msg":"trace[1734247358] transaction","detail":"{read_only:false; response_revision:1858; number_of_response:1; }","duration":"207.339229ms","start":"2026-10-09T15:28:35.757564Z","end":"2026-10-09T15:28:35.964903Z","steps":["trace[1734247358] 'process raft request' (duration: 207.122814ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T15:28:35.965138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.088799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-10-09T15:28:35.965177Z","caller":"traceutil/trace.go:171","msg":"trace[1044188309] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1858; }","duration":"180.194591ms","start":"2026-10-09T15:28:35.784969Z","end":"2026-10-09T15:28:35.965163Z","steps":["trace[1044188309] 'agreement among raft nodes before linearized reading' (duration: 180.021227ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T15:28:35.96523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.450732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-867e74e5-181e-4fd2-9948-2288caa274c2\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-10-09T15:28:35.965316Z","caller":"traceutil/trace.go:171","msg":"trace[1400218752] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-867e74e5-181e-4fd2-9948-2288caa274c2; range_end:; response_count:1; response_revision:1858; }","duration":"174.586095ms","start":"2026-10-09T15:28:35.79071Z","end":"2026-10-09T15:28:35.965296Z","steps":["trace[1400218752] 'agreement among raft nodes before linearized reading' (duration: 174.313209ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T15:28:36.419882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.106544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T15:28:36.419995Z","caller":"traceutil/trace.go:171","msg":"trace[284913792] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1865; }","duration":"212.285638ms","start":"2026-10-09T15:28:36.207686Z","end":"2026-10-09T15:28:36.419972Z","steps":["trace[284913792] 'range keys from in-memory index tree' (duration: 211.997602ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:28:51.432081Z","caller":"traceutil/trace.go:171","msg":"trace[1912583611] transaction","detail":"{read_only:false; response_revision:1994; number_of_response:1; }","duration":"171.959067ms","start":"2026-10-09T15:28:51.260088Z","end":"2026-10-09T15:28:51.432047Z","steps":["trace[1912583611] 'process raft request' (duration: 171.695461ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:28:51.434812Z","caller":"traceutil/trace.go:171","msg":"trace[455053435] transaction","detail":"{read_only:false; response_revision:1995; number_of_response:1; }","duration":"119.628227ms","start":"2026-10-09T15:28:51.315154Z","end":"2026-10-09T15:28:51.434782Z","steps":["trace[455053435] 'process raft request' (duration: 119.450003ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:29:31.303794Z","caller":"traceutil/trace.go:171","msg":"trace[1512405392] linearizableReadLoop","detail":"{readStateIndex:2298; appliedIndex:2297; }","duration":"121.110181ms","start":"2026-10-09T15:29:31.182659Z","end":"2026-10-09T15:29:31.303769Z","steps":["trace[1512405392] 'read index received' (duration: 120.987308ms)","trace[1512405392] 'applied index is now lower than readState.Index' (duration: 121.653µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T15:29:31.303858Z","caller":"traceutil/trace.go:171","msg":"trace[339688478] transaction","detail":"{read_only:false; response_revision:2212; number_of_response:1; }","duration":"132.778592ms","start":"2026-10-09T15:29:31.171044Z","end":"2026-10-09T15:29:31.303823Z","steps":["trace[339688478] 'process raft request' (duration: 132.529606ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T15:29:31.304053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.377376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.211\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-10-09T15:29:31.304176Z","caller":"traceutil/trace.go:171","msg":"trace[1974735622] range","detail":"{range_begin:/registry/masterleases/162.253.55.211; range_end:; response_count:1; response_revision:2212; }","duration":"121.511609ms","start":"2026-10-09T15:29:31.182642Z","end":"2026-10-09T15:29:31.304154Z","steps":["trace[1974735622] 'agreement among raft nodes before linearized reading' (duration: 121.232883ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:33:48.303111Z","caller":"traceutil/trace.go:171","msg":"trace[180170855] transaction","detail":"{read_only:false; response_revision:3977; number_of_response:1; }","duration":"189.961692ms","start":"2026-10-09T15:33:48.113126Z","end":"2026-10-09T15:33:48.303088Z","steps":["trace[180170855] 'process raft request' (duration: 189.781858ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:33:48.303359Z","caller":"traceutil/trace.go:171","msg":"trace[671653490] transaction","detail":"{read_only:false; response_revision:3978; number_of_response:1; }","duration":"163.973262ms","start":"2026-10-09T15:33:48.139345Z","end":"2026-10-09T15:33:48.303319Z","steps":["trace[671653490] 'process raft request' (duration: 163.700756ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:35:16.225387Z","caller":"traceutil/trace.go:171","msg":"trace[2092373553] linearizableReadLoop","detail":"{readStateIndex:5223; appliedIndex:5222; }","duration":"131.744794ms","start":"2026-10-09T15:35:16.093625Z","end":"2026-10-09T15:35:16.22537Z","steps":["trace[2092373553] 'read index received' (duration: 131.619152ms)","trace[2092373553] 'applied index is now lower than readState.Index' (duration: 122.422µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T15:35:16.225506Z","caller":"traceutil/trace.go:171","msg":"trace[780846091] transaction","detail":"{read_only:false; response_revision:5029; number_of_response:1; }","duration":"133.002551ms","start":"2026-10-09T15:35:16.092467Z","end":"2026-10-09T15:35:16.22547Z","steps":["trace[780846091] 'process raft request' (duration: 132.770726ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T15:35:16.225637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.99154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-10-09T15:35:16.225671Z","caller":"traceutil/trace.go:171","msg":"trace[788428189] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:5029; }","duration":"132.062422ms","start":"2026-10-09T15:35:16.0936Z","end":"2026-10-09T15:35:16.225662Z","steps":["trace[788428189] 'agreement among raft nodes before linearized reading' (duration: 131.905838ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T15:35:16.259892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.122279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T15:35:16.259966Z","caller":"traceutil/trace.go:171","msg":"trace[141102667] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:5030; }","duration":"135.242071ms","start":"2026-10-09T15:35:16.124704Z","end":"2026-10-09T15:35:16.259947Z","steps":["trace[141102667] 'agreement among raft nodes before linearized reading' (duration: 135.106839ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:35:16.26035Z","caller":"traceutil/trace.go:171","msg":"trace[2120164049] transaction","detail":"{read_only:false; response_revision:5030; number_of_response:1; }","duration":"161.71997ms","start":"2026-10-09T15:35:16.098597Z","end":"2026-10-09T15:35:16.260317Z","steps":["trace[2120164049] 'process raft request' (duration: 161.064634ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:35:19.605598Z","caller":"traceutil/trace.go:171","msg":"trace[610042459] transaction","detail":"{read_only:false; response_revision:5173; number_of_response:1; }","duration":"103.537799ms","start":"2026-10-09T15:35:19.502034Z","end":"2026-10-09T15:35:19.605572Z","steps":["trace[610042459] 'process raft request' (duration: 103.386335ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T15:35:21.2748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.882157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-09T15:35:21.274889Z","caller":"traceutil/trace.go:171","msg":"trace[1207715843] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:5201; }","duration":"104.02479ms","start":"2026-10-09T15:35:21.170847Z","end":"2026-10-09T15:35:21.274871Z","steps":["trace[1207715843] 'count revisions from in-memory index tree' (duration: 103.771223ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T15:35:21.275245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.979148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9954855d-b696-4aae-9f72-62ecd95378d8\" ","response":"range_response_count:1 size:3891"} {"level":"info","ts":"2026-10-09T15:35:21.275637Z","caller":"traceutil/trace.go:171","msg":"trace[724824112] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9954855d-b696-4aae-9f72-62ecd95378d8; range_end:; response_count:1; response_revision:5201; }","duration":"122.399667ms","start":"2026-10-09T15:35:21.153223Z","end":"2026-10-09T15:35:21.275623Z","steps":["trace[724824112] 'range keys from in-memory index tree' (duration: 121.861375ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:35:35.01581Z","caller":"traceutil/trace.go:171","msg":"trace[716693742] transaction","detail":"{read_only:false; response_revision:5450; number_of_response:1; }","duration":"137.183355ms","start":"2026-10-09T15:35:34.878568Z","end":"2026-10-09T15:35:35.015751Z","steps":["trace[716693742] 'process raft request' (duration: 84.434994ms)","trace[716693742] 'compare' (duration: 52.202088ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T15:35:35.563897Z","caller":"traceutil/trace.go:171","msg":"trace[1507756408] transaction","detail":"{read_only:false; response_revision:5460; number_of_response:1; }","duration":"202.360282ms","start":"2026-10-09T15:35:35.36151Z","end":"2026-10-09T15:35:35.563871Z","steps":["trace[1507756408] 'process raft request' (duration: 202.202109ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:35:35.848594Z","caller":"traceutil/trace.go:171","msg":"trace[745748831] linearizableReadLoop","detail":"{readStateIndex:5669; appliedIndex:5668; }","duration":"183.663457ms","start":"2026-10-09T15:35:35.664908Z","end":"2026-10-09T15:35:35.848572Z","steps":["trace[745748831] 'read index received' (duration: 183.463593ms)","trace[745748831] 'applied index is now lower than readState.Index' (duration: 198.984µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T15:35:35.848647Z","caller":"traceutil/trace.go:171","msg":"trace[872801275] transaction","detail":"{read_only:false; response_revision:5461; number_of_response:1; }","duration":"263.243323ms","start":"2026-10-09T15:35:35.585372Z","end":"2026-10-09T15:35:35.848615Z","steps":["trace[872801275] 'process raft request' (duration: 263.055809ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T15:35:35.848849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.916913ms","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-09T15:35:35.849103Z","caller":"traceutil/trace.go:171","msg":"trace[381053530] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5461; }","duration":"184.227169ms","start":"2026-10-09T15:35:35.664862Z","end":"2026-10-09T15:35:35.84909Z","steps":["trace[381053530] 'agreement among raft nodes before linearized reading' (duration: 183.81662ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T15:35:35.849241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.403362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-09T15:35:35.8493Z","caller":"traceutil/trace.go:171","msg":"trace[638182612] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5461; }","duration":"170.547387ms","start":"2026-10-09T15:35:35.678737Z","end":"2026-10-09T15:35:35.849284Z","steps":["trace[638182612] 'agreement among raft nodes before linearized reading' (duration: 170.23183ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:35:43.313431Z","caller":"traceutil/trace.go:171","msg":"trace[1938607305] transaction","detail":"{read_only:false; response_revision:5645; number_of_response:1; }","duration":"102.340612ms","start":"2026-10-09T15:35:43.211035Z","end":"2026-10-09T15:35:43.313375Z","steps":["trace[1938607305] 'process raft request' (duration: 93.66216ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T15:35:43.313535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.316154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T15:35:43.3136Z","caller":"traceutil/trace.go:171","msg":"trace[482727258] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5645; }","duration":"103.421606ms","start":"2026-10-09T15:35:43.210162Z","end":"2026-10-09T15:35:43.313584Z","steps":["trace[482727258] 'agreement among raft nodes before linearized reading' (duration: 103.220481ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T15:35:55.907251Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4787222093190603228,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-09T15:35:55.972366Z","caller":"traceutil/trace.go:171","msg":"trace[2348563] transaction","detail":"{read_only:false; response_revision:5767; number_of_response:1; }","duration":"607.433744ms","start":"2026-10-09T15:35:55.364912Z","end":"2026-10-09T15:35:55.972346Z","steps":["trace[2348563] 'process raft request' (duration: 607.29683ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T15:35:55.972981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T15:35:55.364885Z","time spent":"607.549156ms","remote":"127.0.0.1:56104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10667,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T15:35:55.973891Z","caller":"traceutil/trace.go:171","msg":"trace[727522887] linearizableReadLoop","detail":"{readStateIndex:5983; appliedIndex:5981; }","duration":"567.548798ms","start":"2026-10-09T15:35:55.406317Z","end":"2026-10-09T15:35:55.973865Z","steps":["trace[727522887] 'read index received' (duration: 565.980463ms)","trace[727522887] 'applied index is now lower than readState.Index' (duration: 1.567135ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T15:35:55.97432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.995908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-10-09T15:35:55.974404Z","caller":"traceutil/trace.go:171","msg":"trace[566846146] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5768; }","duration":"568.124001ms","start":"2026-10-09T15:35:55.406259Z","end":"2026-10-09T15:35:55.974383Z","steps":["trace[566846146] 'agreement among raft nodes before linearized reading' (duration: 567.894326ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T15:35:55.974433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T15:35:55.406238Z","time spent":"568.187122ms","remote":"127.0.0.1:56070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1889,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-10-09T15:35:55.97454Z","caller":"traceutil/trace.go:171","msg":"trace[331644179] transaction","detail":"{read_only:false; response_revision:5768; number_of_response:1; }","duration":"455.01739ms","start":"2026-10-09T15:35:55.519483Z","end":"2026-10-09T15:35:55.974501Z","steps":["trace[331644179] 'process raft request' (duration: 454.176761ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T15:35:55.974631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T15:35:55.519456Z","time spent":"455.128722ms","remote":"127.0.0.1:56192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T15:35:55.974738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.31793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T15:35:55.974906Z","caller":"traceutil/trace.go:171","msg":"trace[1600702650] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:5768; }","duration":"102.892363ms","start":"2026-10-09T15:35:55.871939Z","end":"2026-10-09T15:35:55.974831Z","steps":["trace[1600702650] 'agreement among raft nodes before linearized reading' (duration: 102.31672ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:36:07.92673Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2935} {"level":"info","ts":"2026-10-09T15:36:08.030328Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2935,"took":"101.496433ms","hash":2586324217,"current-db-size-bytes":57901056,"current-db-size":"58 MB","current-db-size-in-use-bytes":32374784,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-09T15:36:08.030518Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2586324217,"revision":2935,"compact-revision":-1} {"level":"info","ts":"2026-10-09T15:36:50.897655Z","caller":"traceutil/trace.go:171","msg":"trace[531309106] transaction","detail":"{read_only:false; response_revision:6229; number_of_response:1; }","duration":"102.423009ms","start":"2026-10-09T15:36:50.795189Z","end":"2026-10-09T15:36:50.897612Z","steps":["trace[531309106] 'process raft request' (duration: 102.071371ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:41:07.933849Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5854} {"level":"info","ts":"2026-10-09T15:41:08.023236Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5854,"took":"87.405154ms","hash":578750234,"current-db-size-bytes":57901056,"current-db-size":"58 MB","current-db-size-in-use-bytes":26218496,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-09T15:41:08.023312Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":578750234,"revision":5854,"compact-revision":2935} {"level":"info","ts":"2026-10-09T15:41:59.994328Z","caller":"traceutil/trace.go:171","msg":"trace[287468459] transaction","detail":"{read_only:false; response_revision:8499; number_of_response:1; }","duration":"108.076055ms","start":"2026-10-09T15:41:59.886221Z","end":"2026-10-09T15:41:59.994297Z","steps":["trace[287468459] 'process raft request' (duration: 107.889511ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:42:00.005042Z","caller":"traceutil/trace.go:171","msg":"trace[1302409349] transaction","detail":"{read_only:false; response_revision:8500; number_of_response:1; }","duration":"116.898951ms","start":"2026-10-09T15:41:59.888118Z","end":"2026-10-09T15:42:00.005017Z","steps":["trace[1302409349] 'process raft request' (duration: 116.561243ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:42:12.508699Z","caller":"traceutil/trace.go:171","msg":"trace[514151465] linearizableReadLoop","detail":"{readStateIndex:8871; appliedIndex:8870; }","duration":"213.705833ms","start":"2026-10-09T15:42:12.294957Z","end":"2026-10-09T15:42:12.508663Z","steps":["trace[514151465] 'read index received' (duration: 203.920074ms)","trace[514151465] 'applied index is now lower than readState.Index' (duration: 9.784279ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T15:42:12.508776Z","caller":"traceutil/trace.go:171","msg":"trace[61423151] transaction","detail":"{read_only:false; response_revision:8565; number_of_response:1; }","duration":"257.148594ms","start":"2026-10-09T15:42:12.2516Z","end":"2026-10-09T15:42:12.508748Z","steps":["trace[61423151] 'process raft request' (duration: 247.289703ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T15:42:12.508936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.969279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-10-09T15:42:12.508982Z","caller":"traceutil/trace.go:171","msg":"trace[976854661] 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:8565; }","duration":"214.030991ms","start":"2026-10-09T15:42:12.294936Z","end":"2026-10-09T15:42:12.508967Z","steps":["trace[976854661] 'agreement among raft nodes before linearized reading' (duration: 213.836216ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:42:12.523212Z","caller":"traceutil/trace.go:171","msg":"trace[897886253] transaction","detail":"{read_only:false; response_revision:8566; number_of_response:1; }","duration":"224.455144ms","start":"2026-10-09T15:42:12.29874Z","end":"2026-10-09T15:42:12.523195Z","steps":["trace[897886253] 'process raft request' (duration: 224.262459ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:42:18.051155Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000022cd.wal"} {"level":"info","ts":"2026-10-09T15:42:52.827567Z","caller":"traceutil/trace.go:171","msg":"trace[358028381] transaction","detail":"{read_only:false; response_revision:8836; number_of_response:1; }","duration":"129.527053ms","start":"2026-10-09T15:42:52.697977Z","end":"2026-10-09T15:42:52.827504Z","steps":["trace[358028381] 'process raft request' (duration: 129.367529ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:42:52.830214Z","caller":"traceutil/trace.go:171","msg":"trace[1066736553] transaction","detail":"{read_only:false; response_revision:8838; number_of_response:1; }","duration":"131.22863ms","start":"2026-10-09T15:42:52.698967Z","end":"2026-10-09T15:42:52.830196Z","steps":["trace[1066736553] 'process raft request' (duration: 131.20298ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:42:52.830285Z","caller":"traceutil/trace.go:171","msg":"trace[1915812477] transaction","detail":"{read_only:false; response_revision:8837; number_of_response:1; }","duration":"131.891656ms","start":"2026-10-09T15:42:52.698369Z","end":"2026-10-09T15:42:52.830261Z","steps":["trace[1915812477] 'process raft request' (duration: 131.705811ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:42:53.343754Z","caller":"traceutil/trace.go:171","msg":"trace[127190020] transaction","detail":"{read_only:false; response_revision:8842; number_of_response:1; }","duration":"104.282209ms","start":"2026-10-09T15:42:53.239446Z","end":"2026-10-09T15:42:53.343728Z","steps":["trace[127190020] 'process raft request' (duration: 104.132146ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:42:55.551957Z","caller":"traceutil/trace.go:171","msg":"trace[1869552001] transaction","detail":"{read_only:false; response_revision:8858; number_of_response:1; }","duration":"144.744363ms","start":"2026-10-09T15:42:55.407159Z","end":"2026-10-09T15:42:55.551903Z","steps":["trace[1869552001] 'process raft request' (duration: 108.929963ms)","trace[1869552001] 'compare' (duration: 35.034583ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T15:42:55.55798Z","caller":"traceutil/trace.go:171","msg":"trace[6844399] linearizableReadLoop","detail":"{readStateIndex:9176; appliedIndex:9174; }","duration":"147.27722ms","start":"2026-10-09T15:42:55.410679Z","end":"2026-10-09T15:42:55.557957Z","steps":["trace[6844399] 'read index received' (duration: 105.383144ms)","trace[6844399] 'applied index is now lower than readState.Index' (duration: 41.892465ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T15:42:55.558372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.640957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/rook-ceph-detect-version\" ","response":"range_response_count:1 size:3066"} {"level":"info","ts":"2026-10-09T15:42:55.558472Z","caller":"traceutil/trace.go:171","msg":"trace[974316789] range","detail":"{range_begin:/registry/jobs/openstack/rook-ceph-detect-version; range_end:; response_count:1; response_revision:8859; }","duration":"147.79331ms","start":"2026-10-09T15:42:55.410656Z","end":"2026-10-09T15:42:55.558449Z","steps":["trace[974316789] 'agreement among raft nodes before linearized reading' (duration: 147.471333ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:42:55.558857Z","caller":"traceutil/trace.go:171","msg":"trace[1153768716] transaction","detail":"{read_only:false; response_revision:8859; number_of_response:1; }","duration":"151.001262ms","start":"2026-10-09T15:42:55.407839Z","end":"2026-10-09T15:42:55.55884Z","steps":["trace[1153768716] 'process raft request' (duration: 150.00135ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:42:55.757638Z","caller":"traceutil/trace.go:171","msg":"trace[1449764731] transaction","detail":"{read_only:false; response_revision:8860; number_of_response:1; }","duration":"187.104749ms","start":"2026-10-09T15:42:55.570496Z","end":"2026-10-09T15:42:55.757601Z","steps":["trace[1449764731] 'process raft request' (duration: 127.12963ms)","trace[1449764731] 'compare' (duration: 58.927036ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T15:42:55.757633Z","caller":"traceutil/trace.go:171","msg":"trace[1365807076] linearizableReadLoop","detail":"{readStateIndex:9177; appliedIndex:9176; }","duration":"186.925785ms","start":"2026-10-09T15:42:55.570678Z","end":"2026-10-09T15:42:55.757604Z","steps":["trace[1365807076] 'read index received' (duration: 126.966996ms)","trace[1365807076] 'applied index is now lower than readState.Index' (duration: 59.957599ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T15:42:55.757894Z","caller":"traceutil/trace.go:171","msg":"trace[1926586830] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8861; }","duration":"186.384373ms","start":"2026-10-09T15:42:55.571483Z","end":"2026-10-09T15:42:55.757867Z","steps":["trace[1926586830] 'process raft request' (duration: 186.063165ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T15:42:55.758141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.408857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-config-override\" ","response":"range_response_count:1 size:626"} {"level":"info","ts":"2026-10-09T15:42:55.758212Z","caller":"traceutil/trace.go:171","msg":"trace[1561407043] range","detail":"{range_begin:/registry/configmaps/openstack/rook-config-override; range_end:; response_count:1; response_revision:8860; }","duration":"187.56238ms","start":"2026-10-09T15:42:55.570631Z","end":"2026-10-09T15:42:55.758193Z","steps":["trace[1561407043] 'agreement among raft nodes before linearized reading' (duration: 187.056278ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:44:15.171738Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"931f6a422b4c426f","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-09T15:44:15.179989Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-09T15:44:15.180078Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"warn","ts":"2026-10-09T15:44:22.251645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.836645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-4tlmr\" ","response":"range_response_count:1 size:6451"} {"level":"info","ts":"2026-10-09T15:44:22.251755Z","caller":"traceutil/trace.go:171","msg":"trace[1194338507] range","detail":"{range_begin:/registry/pods/openstack/keepalived-4tlmr; range_end:; response_count:1; response_revision:9695; }","duration":"113.027288ms","start":"2026-10-09T15:44:22.138703Z","end":"2026-10-09T15:44:22.25173Z","steps":["trace[1194338507] 'range keys from in-memory index tree' (duration: 112.600129ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:46:07.944343Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8024} {"level":"info","ts":"2026-10-09T15:46:08.014254Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8024,"took":"68.121384ms","hash":1852173512,"current-db-size-bytes":57901056,"current-db-size":"58 MB","current-db-size-in-use-bytes":28307456,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-09T15:46:08.014337Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1852173512,"revision":8024,"compact-revision":5854} {"level":"info","ts":"2026-10-09T15:51:07.951656Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10434} {"level":"info","ts":"2026-10-09T15:51:08.039318Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10434,"took":"84.50067ms","hash":2924269280,"current-db-size-bytes":57901056,"current-db-size":"58 MB","current-db-size-in-use-bytes":26009600,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-09T15:51:08.039391Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2924269280,"revision":10434,"compact-revision":8024} {"level":"info","ts":"2026-10-09T15:56:07.960139Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12588} {"level":"info","ts":"2026-10-09T15:56:08.040063Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12588,"took":"77.206017ms","hash":1949926930,"current-db-size-bytes":57901056,"current-db-size":"58 MB","current-db-size-in-use-bytes":26988544,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-09T15:56:08.040144Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1949926930,"revision":12588,"compact-revision":10434} {"level":"info","ts":"2026-10-09T15:56:42.709631Z","caller":"traceutil/trace.go:171","msg":"trace[700068297] linearizableReadLoop","detail":"{readStateIndex:15325; appliedIndex:15324; }","duration":"302.805381ms","start":"2026-10-09T15:56:42.406795Z","end":"2026-10-09T15:56:42.709601Z","steps":["trace[700068297] 'read index received' (duration: 302.599287ms)","trace[700068297] 'applied index is now lower than readState.Index' (duration: 204.204µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T15:56:42.709791Z","caller":"traceutil/trace.go:171","msg":"trace[476165476] transaction","detail":"{read_only:false; response_revision:14809; number_of_response:1; }","duration":"317.705975ms","start":"2026-10-09T15:56:42.392009Z","end":"2026-10-09T15:56:42.709715Z","steps":["trace[476165476] 'process raft request' (duration: 317.375977ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T15:56:42.709934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.109008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:356"} {"level":"info","ts":"2026-10-09T15:56:42.709982Z","caller":"traceutil/trace.go:171","msg":"trace[209178024] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:14809; }","duration":"303.200839ms","start":"2026-10-09T15:56:42.406767Z","end":"2026-10-09T15:56:42.709968Z","steps":["trace[209178024] 'agreement among raft nodes before linearized reading' (duration: 303.021945ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T15:56:42.710026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T15:56:42.406756Z","time spent":"303.26167ms","remote":"127.0.0.1:55990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":380,"request content":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" "} {"level":"warn","ts":"2026-10-09T15:56:42.710055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T15:56:42.391981Z","time spent":"317.93945ms","remote":"127.0.0.1:56104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11815,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T15:56:42.83034Z","caller":"traceutil/trace.go:171","msg":"trace[190150905] linearizableReadLoop","detail":"{readStateIndex:15326; appliedIndex:15325; }","duration":"120.574566ms","start":"2026-10-09T15:56:42.709734Z","end":"2026-10-09T15:56:42.830308Z","steps":["trace[190150905] 'read index received' (duration: 119.231546ms)","trace[190150905] 'applied index is now lower than readState.Index' (duration: 1.34148ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T15:56:42.830379Z","caller":"traceutil/trace.go:171","msg":"trace[1403241642] transaction","detail":"{read_only:false; response_revision:14810; number_of_response:1; }","duration":"350.967047ms","start":"2026-10-09T15:56:42.479386Z","end":"2026-10-09T15:56:42.830353Z","steps":["trace[1403241642] 'process raft request' (duration: 349.599066ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T15:56:42.830469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.923602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T15:56:42.830536Z","caller":"traceutil/trace.go:171","msg":"trace[1454304190] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:14810; }","duration":"209.056455ms","start":"2026-10-09T15:56:42.621462Z","end":"2026-10-09T15:56:42.830519Z","steps":["trace[1454304190] 'agreement among raft nodes before linearized reading' (duration: 208.908122ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T15:56:42.830542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T15:56:42.479361Z","time spent":"351.07864ms","remote":"127.0.0.1:56192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T15:56:42.830569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.39929ms","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-09T15:56:42.830612Z","caller":"traceutil/trace.go:171","msg":"trace[1901350645] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14810; }","duration":"225.492403ms","start":"2026-10-09T15:56:42.605105Z","end":"2026-10-09T15:56:42.830597Z","steps":["trace[1901350645] 'agreement among raft nodes before linearized reading' (duration: 225.314479ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T15:56:42.830875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.310912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-09T15:56:42.830999Z","caller":"traceutil/trace.go:171","msg":"trace[539369449] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14810; }","duration":"218.480826ms","start":"2026-10-09T15:56:42.612491Z","end":"2026-10-09T15:56:42.830972Z","steps":["trace[539369449] 'agreement among raft nodes before linearized reading' (duration: 218.125528ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:57:06.348829Z","caller":"traceutil/trace.go:171","msg":"trace[889241979] transaction","detail":"{read_only:false; response_revision:15123; number_of_response:1; }","duration":"261.928547ms","start":"2026-10-09T15:57:06.086869Z","end":"2026-10-09T15:57:06.348797Z","steps":["trace[889241979] 'process raft request' (duration: 247.742729ms)","trace[889241979] 'compare' (duration: 13.869511ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T15:57:06.35896Z","caller":"traceutil/trace.go:171","msg":"trace[1392132997] transaction","detail":"{read_only:false; response_revision:15124; number_of_response:1; }","duration":"194.852408ms","start":"2026-10-09T15:57:06.164083Z","end":"2026-10-09T15:57:06.358936Z","steps":["trace[1392132997] 'process raft request' (duration: 194.658343ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:57:06.359172Z","caller":"traceutil/trace.go:171","msg":"trace[921284762] transaction","detail":"{read_only:false; response_revision:15125; number_of_response:1; }","duration":"191.192655ms","start":"2026-10-09T15:57:06.16796Z","end":"2026-10-09T15:57:06.359153Z","steps":["trace[921284762] 'process raft request' (duration: 190.93925ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:57:06.359291Z","caller":"traceutil/trace.go:171","msg":"trace[472703871] transaction","detail":"{read_only:false; response_revision:15126; number_of_response:1; }","duration":"189.720353ms","start":"2026-10-09T15:57:06.169546Z","end":"2026-10-09T15:57:06.359266Z","steps":["trace[472703871] 'process raft request' (duration: 189.552749ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:57:06.413056Z","caller":"traceutil/trace.go:171","msg":"trace[1839383409] linearizableReadLoop","detail":"{readStateIndex:15654; appliedIndex:15650; }","duration":"205.388333ms","start":"2026-10-09T15:57:06.207636Z","end":"2026-10-09T15:57:06.413024Z","steps":["trace[1839383409] 'read index received' (duration: 126.99208ms)","trace[1839383409] 'applied index is now lower than readState.Index' (duration: 78.393973ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T15:57:06.413222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.540032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T15:57:06.413265Z","caller":"traceutil/trace.go:171","msg":"trace[628605483] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:15126; }","duration":"178.638875ms","start":"2026-10-09T15:57:06.234612Z","end":"2026-10-09T15:57:06.413251Z","steps":["trace[628605483] 'agreement among raft nodes before linearized reading' (duration: 178.555163ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T15:57:06.413252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.601897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T15:57:06.413333Z","caller":"traceutil/trace.go:171","msg":"trace[1554388280] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15126; }","duration":"205.793641ms","start":"2026-10-09T15:57:06.207516Z","end":"2026-10-09T15:57:06.413309Z","steps":["trace[1554388280] 'agreement among raft nodes before linearized reading' (duration: 205.638658ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T15:57:06.413447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.333544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-10-09T15:57:06.413544Z","caller":"traceutil/trace.go:171","msg":"trace[799119386] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:15126; }","duration":"143.500079ms","start":"2026-10-09T15:57:06.270019Z","end":"2026-10-09T15:57:06.413519Z","steps":["trace[799119386] 'agreement among raft nodes before linearized reading' (duration: 143.201072ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T15:57:43.54203Z","caller":"traceutil/trace.go:171","msg":"trace[990073121] linearizableReadLoop","detail":"{readStateIndex:16102; appliedIndex:16100; }","duration":"115.937142ms","start":"2026-10-09T15:57:43.426066Z","end":"2026-10-09T15:57:43.542004Z","steps":["trace[990073121] 'read index received' (duration: 87.885655ms)","trace[990073121] 'applied index is now lower than readState.Index' (duration: 28.049767ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T15:57:43.542062Z","caller":"traceutil/trace.go:171","msg":"trace[1906657403] transaction","detail":"{read_only:false; response_revision:15563; number_of_response:1; }","duration":"122.848698ms","start":"2026-10-09T15:57:43.419194Z","end":"2026-10-09T15:57:43.542043Z","steps":["trace[1906657403] 'process raft request' (duration: 94.743239ms)","trace[1906657403] 'compare' (duration: 27.853993ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T15:57:43.542062Z","caller":"traceutil/trace.go:171","msg":"trace[456820957] transaction","detail":"{read_only:false; response_revision:15564; number_of_response:1; }","duration":"119.674166ms","start":"2026-10-09T15:57:43.422341Z","end":"2026-10-09T15:57:43.542015Z","steps":["trace[456820957] 'process raft request' (duration: 119.542623ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T15:57:43.542308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.220118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/nova-db-init\" ","response":"range_response_count:1 size:524"} {"level":"info","ts":"2026-10-09T15:57:43.54239Z","caller":"traceutil/trace.go:171","msg":"trace[10302413] range","detail":"{range_begin:/registry/serviceaccounts/openstack/nova-db-init; range_end:; response_count:1; response_revision:15565; }","duration":"116.345271ms","start":"2026-10-09T15:57:43.426023Z","end":"2026-10-09T15:57:43.542368Z","steps":["trace[10302413] 'agreement among raft nodes before linearized reading' (duration: 116.132566ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:01:07.967779Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14609} {"level":"info","ts":"2026-10-09T16:01:08.053565Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14609,"took":"82.7489ms","hash":2780117341,"current-db-size-bytes":57901056,"current-db-size":"58 MB","current-db-size-in-use-bytes":32215040,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-09T16:01:08.053703Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2780117341,"revision":14609,"compact-revision":12588} {"level":"info","ts":"2026-10-09T16:04:34.23753Z","caller":"traceutil/trace.go:171","msg":"trace[864392791] transaction","detail":"{read_only:false; response_revision:18630; number_of_response:1; }","duration":"110.025689ms","start":"2026-10-09T16:04:34.127483Z","end":"2026-10-09T16:04:34.237509Z","steps":["trace[864392791] 'process raft request' (duration: 109.880736ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:04:34.378357Z","caller":"traceutil/trace.go:171","msg":"trace[212821890] transaction","detail":"{read_only:false; response_revision:18631; number_of_response:1; }","duration":"247.528472ms","start":"2026-10-09T16:04:34.130804Z","end":"2026-10-09T16:04:34.378333Z","steps":["trace[212821890] 'process raft request' (duration: 247.374628ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:04:34.378881Z","caller":"traceutil/trace.go:171","msg":"trace[1061438267] transaction","detail":"{read_only:false; response_revision:18632; number_of_response:1; }","duration":"247.760927ms","start":"2026-10-09T16:04:34.131087Z","end":"2026-10-09T16:04:34.378848Z","steps":["trace[1061438267] 'process raft request' (duration: 247.211124ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:04:34.379191Z","caller":"traceutil/trace.go:171","msg":"trace[1591104428] transaction","detail":"{read_only:false; response_revision:18633; number_of_response:1; }","duration":"243.794258ms","start":"2026-10-09T16:04:34.135376Z","end":"2026-10-09T16:04:34.379171Z","steps":["trace[1591104428] 'process raft request' (duration: 243.558203ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:04:34.379127Z","caller":"traceutil/trace.go:171","msg":"trace[778909362] transaction","detail":"{read_only:false; response_revision:18634; number_of_response:1; }","duration":"242.753946ms","start":"2026-10-09T16:04:34.136352Z","end":"2026-10-09T16:04:34.379106Z","steps":["trace[778909362] 'process raft request' (duration: 242.697754ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:04:35.081001Z","caller":"traceutil/trace.go:171","msg":"trace[1202574043] transaction","detail":"{read_only:false; response_revision:18635; number_of_response:1; }","duration":"417.202423ms","start":"2026-10-09T16:04:34.663753Z","end":"2026-10-09T16:04:35.080955Z","steps":["trace[1202574043] 'process raft request' (duration: 416.879126ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:04:35.081083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.392369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T16:04:35.081163Z","caller":"traceutil/trace.go:171","msg":"trace[1791837322] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:18635; }","duration":"286.542413ms","start":"2026-10-09T16:04:34.794602Z","end":"2026-10-09T16:04:35.081144Z","steps":["trace[1791837322] 'agreement among raft nodes before linearized reading' (duration: 286.39114ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:04:35.08119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:04:34.663719Z","time spent":"417.369917ms","remote":"127.0.0.1:56104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20785,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T16:04:35.081475Z","caller":"traceutil/trace.go:171","msg":"trace[979412707] linearizableReadLoop","detail":"{readStateIndex:19274; appliedIndex:19273; }","duration":"286.141124ms","start":"2026-10-09T16:04:34.794662Z","end":"2026-10-09T16:04:35.080803Z","steps":["trace[979412707] 'read index received' (duration: 285.867858ms)","trace[979412707] 'applied index is now lower than readState.Index' (duration: 272.306µs)"],"step_count":2} {"level":"warn","ts":"2026-10-09T16:04:35.081712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.425355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-29859360-d88hp\" ","response":"range_response_count:1 size:6960"} {"level":"info","ts":"2026-10-09T16:04:35.081752Z","caller":"traceutil/trace.go:171","msg":"trace[1977792406] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-29859360-d88hp; range_end:; response_count:1; response_revision:18635; }","duration":"258.509556ms","start":"2026-10-09T16:04:34.823231Z","end":"2026-10-09T16:04:35.08174Z","steps":["trace[1977792406] 'agreement among raft nodes before linearized reading' (duration: 258.359373ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:04:35.081748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.478291ms","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-09T16:04:35.081791Z","caller":"traceutil/trace.go:171","msg":"trace[53913453] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:18635; }","duration":"222.543823ms","start":"2026-10-09T16:04:34.859234Z","end":"2026-10-09T16:04:35.081778Z","steps":["trace[53913453] 'agreement among raft nodes before linearized reading' (duration: 222.477311ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:04:42.975026Z","caller":"traceutil/trace.go:171","msg":"trace[1010488291] transaction","detail":"{read_only:false; response_revision:18764; number_of_response:1; }","duration":"136.512191ms","start":"2026-10-09T16:04:42.838484Z","end":"2026-10-09T16:04:42.974997Z","steps":["trace[1010488291] 'process raft request' (duration: 136.45822ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:04:42.975081Z","caller":"traceutil/trace.go:171","msg":"trace[1330753473] transaction","detail":"{read_only:false; response_revision:18763; number_of_response:1; }","duration":"150.580935ms","start":"2026-10-09T16:04:42.824472Z","end":"2026-10-09T16:04:42.975053Z","steps":["trace[1330753473] 'process raft request' (duration: 95.100455ms)","trace[1330753473] 'compare' (duration: 55.172394ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:05:34.611056Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"931f6a422b4c426f","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-09T16:05:34.617196Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-09T16:05:34.617307Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-09T16:06:07.975871Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17096} {"level":"info","ts":"2026-10-09T16:06:08.079196Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17096,"took":"100.353143ms","hash":2344749127,"current-db-size-bytes":57901056,"current-db-size":"58 MB","current-db-size-in-use-bytes":33853440,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-09T16:06:08.079271Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2344749127,"revision":17096,"compact-revision":14609} {"level":"info","ts":"2026-10-09T16:10:25.943677Z","caller":"traceutil/trace.go:171","msg":"trace[148045448] transaction","detail":"{read_only:false; response_revision:21395; number_of_response:1; }","duration":"147.844714ms","start":"2026-10-09T16:10:25.795801Z","end":"2026-10-09T16:10:25.943646Z","steps":["trace[148045448] 'process raft request' (duration: 147.67713ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:10:25.95971Z","caller":"traceutil/trace.go:171","msg":"trace[1674445503] transaction","detail":"{read_only:false; response_revision:21396; number_of_response:1; }","duration":"154.537883ms","start":"2026-10-09T16:10:25.805134Z","end":"2026-10-09T16:10:25.959672Z","steps":["trace[1674445503] 'process raft request' (duration: 154.349489ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:10:26.165805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.168349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-10-09T16:10:26.165963Z","caller":"traceutil/trace.go:171","msg":"trace[1311850879] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:21396; }","duration":"126.382734ms","start":"2026-10-09T16:10:26.039545Z","end":"2026-10-09T16:10:26.165928Z","steps":["trace[1311850879] 'range keys from in-memory index tree' (duration: 125.915754ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:10:26.412112Z","caller":"traceutil/trace.go:171","msg":"trace[1237153601] linearizableReadLoop","detail":"{readStateIndex:22125; appliedIndex:22124; }","duration":"242.419207ms","start":"2026-10-09T16:10:26.169665Z","end":"2026-10-09T16:10:26.412084Z","steps":["trace[1237153601] 'read index received' (duration: 242.13859ms)","trace[1237153601] 'applied index is now lower than readState.Index' (duration: 279.547µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:10:26.41216Z","caller":"traceutil/trace.go:171","msg":"trace[1392429291] transaction","detail":"{read_only:false; response_revision:21397; number_of_response:1; }","duration":"244.185297ms","start":"2026-10-09T16:10:26.167939Z","end":"2026-10-09T16:10:26.412124Z","steps":["trace[1392429291] 'process raft request' (duration: 243.954081ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:10:26.41238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.691802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-10-09T16:10:26.41242Z","caller":"traceutil/trace.go:171","msg":"trace[2143689463] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:21397; }","duration":"242.784215ms","start":"2026-10-09T16:10:26.169626Z","end":"2026-10-09T16:10:26.41241Z","steps":["trace[2143689463] 'agreement among raft nodes before linearized reading' (duration: 242.599151ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:10:26.412403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.681346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T16:10:26.412476Z","caller":"traceutil/trace.go:171","msg":"trace[158367528] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21397; }","duration":"205.815549ms","start":"2026-10-09T16:10:26.206642Z","end":"2026-10-09T16:10:26.412458Z","steps":["trace[158367528] 'agreement among raft nodes before linearized reading' (duration: 205.694126ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:10:26.412646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.207603ms","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-09T16:10:26.412777Z","caller":"traceutil/trace.go:171","msg":"trace[1886556022] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21397; }","duration":"238.376506ms","start":"2026-10-09T16:10:26.174382Z","end":"2026-10-09T16:10:26.412758Z","steps":["trace[1886556022] 'agreement among raft nodes before linearized reading' (duration: 237.943947ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:10:26.611414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.031378ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T16:10:26.611532Z","caller":"traceutil/trace.go:171","msg":"trace[948972020] linearizableReadLoop","detail":"{readStateIndex:22127; appliedIndex:22126; }","duration":"126.364793ms","start":"2026-10-09T16:10:26.48515Z","end":"2026-10-09T16:10:26.611515Z","steps":["trace[948972020] 'read index received' (duration: 81.081µs)","trace[948972020] 'applied index is now lower than readState.Index' (duration: 126.279192ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T16:10:26.611664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.519197ms","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-09T16:10:26.611632Z","caller":"traceutil/trace.go:171","msg":"trace[675487378] transaction","detail":"{read_only:false; response_revision:21399; number_of_response:1; }","duration":"192.570913ms","start":"2026-10-09T16:10:26.419016Z","end":"2026-10-09T16:10:26.611587Z","steps":["trace[675487378] 'process raft request' (duration: 61.121985ms)","trace[675487378] 'compare' (duration: 130.842384ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:10:26.611702Z","caller":"traceutil/trace.go:171","msg":"trace[180676798] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:21399; }","duration":"126.598619ms","start":"2026-10-09T16:10:26.485094Z","end":"2026-10-09T16:10:26.611692Z","steps":["trace[180676798] 'agreement among raft nodes before linearized reading' (duration: 126.508627ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:10:27.064106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.181772ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-10-09T16:10:27.213498Z","caller":"traceutil/trace.go:171","msg":"trace[1500234722] transaction","detail":"{read_only:false; response_revision:21401; number_of_response:1; }","duration":"169.956917ms","start":"2026-10-09T16:10:27.043514Z","end":"2026-10-09T16:10:27.213471Z","steps":["trace[1500234722] 'process raft request' (duration: 169.664991ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:11:07.982983Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19596} {"level":"info","ts":"2026-10-09T16:11:08.11048Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19596,"took":"122.470907ms","hash":3183926269,"current-db-size-bytes":57901056,"current-db-size":"58 MB","current-db-size-in-use-bytes":30285824,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-09T16:11:08.110568Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3183926269,"revision":19596,"compact-revision":17096} {"level":"info","ts":"2026-10-09T16:14:31.904853Z","caller":"traceutil/trace.go:171","msg":"trace[673447382] transaction","detail":"{read_only:false; response_revision:23901; number_of_response:1; }","duration":"204.183783ms","start":"2026-10-09T16:14:31.700435Z","end":"2026-10-09T16:14:31.904619Z","steps":["trace[673447382] 'process raft request' (duration: 204.018419ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:16:07.990481Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21849} {"level":"info","ts":"2026-10-09T16:16:08.104107Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21849,"took":"109.579639ms","hash":4100095483,"current-db-size-bytes":57901056,"current-db-size":"58 MB","current-db-size-in-use-bytes":43835392,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-10-09T16:16:08.10418Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4100095483,"revision":21849,"compact-revision":19596} {"level":"info","ts":"2026-10-09T16:17:30.159179Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006789.wal"} {"level":"info","ts":"2026-10-09T16:18:09.53927Z","caller":"traceutil/trace.go:171","msg":"trace[897925484] linearizableReadLoop","detail":"{readStateIndex:26936; appliedIndex:26935; }","duration":"112.566445ms","start":"2026-10-09T16:18:09.426673Z","end":"2026-10-09T16:18:09.539239Z","steps":["trace[897925484] 'read index received' (duration: 60.765237ms)","trace[897925484] 'applied index is now lower than readState.Index' (duration: 51.800098ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:18:09.53941Z","caller":"traceutil/trace.go:171","msg":"trace[2048585123] transaction","detail":"{read_only:false; response_revision:26074; number_of_response:1; }","duration":"150.825221ms","start":"2026-10-09T16:18:09.388572Z","end":"2026-10-09T16:18:09.539397Z","steps":["trace[2048585123] 'process raft request' (duration: 98.927391ms)","trace[2048585123] 'compare' (duration: 51.401049ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T16:18:09.539851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.165449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11716"} {"level":"info","ts":"2026-10-09T16:18:09.539928Z","caller":"traceutil/trace.go:171","msg":"trace[412368954] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:26074; }","duration":"113.313911ms","start":"2026-10-09T16:18:09.426595Z","end":"2026-10-09T16:18:09.539909Z","steps":["trace[412368954] 'agreement among raft nodes before linearized reading' (duration: 113.103727ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:18:09.967086Z","caller":"traceutil/trace.go:171","msg":"trace[257808039] transaction","detail":"{read_only:false; response_revision:26075; number_of_response:1; }","duration":"273.202814ms","start":"2026-10-09T16:18:09.693855Z","end":"2026-10-09T16:18:09.967058Z","steps":["trace[257808039] 'process raft request' (duration: 273.03189ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:18:10.13015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.76561ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T16:18:10.130341Z","caller":"traceutil/trace.go:171","msg":"trace[446687157] transaction","detail":"{read_only:false; response_revision:26076; number_of_response:1; }","duration":"430.331797ms","start":"2026-10-09T16:18:09.699984Z","end":"2026-10-09T16:18:10.130316Z","steps":["trace[446687157] 'process raft request' (duration: 283.306631ms)","trace[446687157] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/openstack/messaging-topology-operator-leader-election; req_size:552; } (duration: 146.31954ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T16:18:10.130401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:18:09.699962Z","time spent":"430.409078ms","remote":"127.0.0.1:56192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T16:18:10.130634Z","caller":"traceutil/trace.go:171","msg":"trace[1718888833] transaction","detail":"{read_only:false; response_revision:26077; number_of_response:1; }","duration":"224.495246ms","start":"2026-10-09T16:18:09.90611Z","end":"2026-10-09T16:18:10.130605Z","steps":["trace[1718888833] 'process raft request' (duration: 224.125148ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:18:10.131011Z","caller":"traceutil/trace.go:171","msg":"trace[955052270] linearizableReadLoop","detail":"{readStateIndex:26938; appliedIndex:26936; }","duration":"286.40981ms","start":"2026-10-09T16:18:09.844578Z","end":"2026-10-09T16:18:10.130988Z","steps":["trace[955052270] 'read index received' (duration: 122.352613ms)","trace[955052270] 'applied index is now lower than readState.Index' (duration: 164.056007ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:18:10.131165Z","caller":"traceutil/trace.go:171","msg":"trace[1733076871] transaction","detail":"{read_only:false; response_revision:26078; number_of_response:1; }","duration":"212.630201ms","start":"2026-10-09T16:18:09.918502Z","end":"2026-10-09T16:18:10.131132Z","steps":["trace[1733076871] 'process raft request' (duration: 211.985577ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:18:10.131371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.787888ms","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-09T16:18:10.131423Z","caller":"traceutil/trace.go:171","msg":"trace[2031860068] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26078; }","duration":"286.86658ms","start":"2026-10-09T16:18:09.84454Z","end":"2026-10-09T16:18:10.131407Z","steps":["trace[2031860068] 'agreement among raft nodes before linearized reading' (duration: 286.718387ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:18:10.242882Z","caller":"traceutil/trace.go:171","msg":"trace[647262988] transaction","detail":"{read_only:false; response_revision:26080; number_of_response:1; }","duration":"107.548223ms","start":"2026-10-09T16:18:10.13531Z","end":"2026-10-09T16:18:10.242858Z","steps":["trace[647262988] 'process raft request' (duration: 107.371769ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:18:14.546386Z","caller":"traceutil/trace.go:171","msg":"trace[2008216035] transaction","detail":"{read_only:false; response_revision:26122; number_of_response:1; }","duration":"277.262156ms","start":"2026-10-09T16:18:14.269088Z","end":"2026-10-09T16:18:14.54635Z","steps":["trace[2008216035] 'process raft request' (duration: 265.575954ms)","trace[2008216035] 'compare' (duration: 10.922195ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:18:14.546825Z","caller":"traceutil/trace.go:171","msg":"trace[1676167273] transaction","detail":"{read_only:false; response_revision:26123; number_of_response:1; }","duration":"276.183582ms","start":"2026-10-09T16:18:14.27062Z","end":"2026-10-09T16:18:14.546803Z","steps":["trace[1676167273] 'process raft request' (duration: 275.65535ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:18:14.546862Z","caller":"traceutil/trace.go:171","msg":"trace[1072526131] linearizableReadLoop","detail":"{readStateIndex:26987; appliedIndex:26983; }","duration":"253.899733ms","start":"2026-10-09T16:18:14.292932Z","end":"2026-10-09T16:18:14.546831Z","steps":["trace[1072526131] 'read index received' (duration: 24.64334ms)","trace[1072526131] 'applied index is now lower than readState.Index' (duration: 229.255463ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:18:14.546947Z","caller":"traceutil/trace.go:171","msg":"trace[1975282730] transaction","detail":"{read_only:false; response_revision:26124; number_of_response:1; }","duration":"261.780899ms","start":"2026-10-09T16:18:14.285136Z","end":"2026-10-09T16:18:14.546917Z","steps":["trace[1975282730] 'process raft request' (duration: 261.548454ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:18:14.547008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.039176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T16:18:14.547049Z","caller":"traceutil/trace.go:171","msg":"trace[1763659074] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:26124; }","duration":"254.145889ms","start":"2026-10-09T16:18:14.292888Z","end":"2026-10-09T16:18:14.547034Z","steps":["trace[1763659074] 'agreement among raft nodes before linearized reading' (duration: 254.045746ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:18:14.586323Z","caller":"traceutil/trace.go:171","msg":"trace[1756108333] transaction","detail":"{read_only:false; response_revision:26125; number_of_response:1; }","duration":"139.525497ms","start":"2026-10-09T16:18:14.446766Z","end":"2026-10-09T16:18:14.586292Z","steps":["trace[1756108333] 'process raft request' (duration: 139.339323ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:18:15.185674Z","caller":"traceutil/trace.go:171","msg":"trace[1403066789] transaction","detail":"{read_only:false; response_revision:26127; number_of_response:1; }","duration":"114.823366ms","start":"2026-10-09T16:18:15.070823Z","end":"2026-10-09T16:18:15.185647Z","steps":["trace[1403066789] 'process raft request' (duration: 114.653002ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:18:15.392944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.097791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T16:18:15.393029Z","caller":"traceutil/trace.go:171","msg":"trace[2024574214] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:26127; }","duration":"123.198773ms","start":"2026-10-09T16:18:15.269808Z","end":"2026-10-09T16:18:15.393006Z","steps":["trace[2024574214] 'count revisions from in-memory index tree' (duration: 123.003208ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:18:15.558256Z","caller":"traceutil/trace.go:171","msg":"trace[1186085244] transaction","detail":"{read_only:false; response_revision:26128; number_of_response:1; }","duration":"132.671004ms","start":"2026-10-09T16:18:15.425553Z","end":"2026-10-09T16:18:15.558224Z","steps":["trace[1186085244] 'process raft request' (duration: 132.48302ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:18:15.899603Z","caller":"traceutil/trace.go:171","msg":"trace[1668008938] transaction","detail":"{read_only:false; response_revision:26129; number_of_response:1; }","duration":"339.66746ms","start":"2026-10-09T16:18:15.559908Z","end":"2026-10-09T16:18:15.899576Z","steps":["trace[1668008938] 'process raft request' (duration: 279.102807ms)","trace[1668008938] 'compare' (duration: 60.316247ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T16:18:15.899766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:18:15.55987Z","time spent":"339.801593ms","remote":"127.0.0.1:56192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T16:18:16.145558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.498494ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T16:18:16.146093Z","caller":"traceutil/trace.go:171","msg":"trace[759914139] transaction","detail":"{read_only:false; response_revision:26130; number_of_response:1; }","duration":"158.727207ms","start":"2026-10-09T16:18:15.987332Z","end":"2026-10-09T16:18:16.146059Z","steps":["trace[759914139] 'process raft request' (duration: 49.568098ms)","trace[759914139] 'compare' (duration: 108.353911ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:18:16.280373Z","caller":"traceutil/trace.go:171","msg":"trace[49883740] transaction","detail":"{read_only:false; response_revision:26131; number_of_response:1; }","duration":"129.80238ms","start":"2026-10-09T16:18:16.150548Z","end":"2026-10-09T16:18:16.280351Z","steps":["trace[49883740] 'process raft request' (duration: 125.74336ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:18:16.280698Z","caller":"traceutil/trace.go:171","msg":"trace[1423602384] transaction","detail":"{read_only:false; response_revision:26133; number_of_response:1; }","duration":"111.761138ms","start":"2026-10-09T16:18:16.16891Z","end":"2026-10-09T16:18:16.280671Z","steps":["trace[1423602384] 'process raft request' (duration: 111.600374ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:18:16.280856Z","caller":"traceutil/trace.go:171","msg":"trace[452646915] transaction","detail":"{read_only:false; response_revision:26132; number_of_response:1; }","duration":"127.1285ms","start":"2026-10-09T16:18:16.153686Z","end":"2026-10-09T16:18:16.280815Z","steps":["trace[452646915] 'process raft request' (duration: 126.620649ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:18:16.623756Z","caller":"traceutil/trace.go:171","msg":"trace[501968509] transaction","detail":"{read_only:false; response_revision:26134; number_of_response:1; }","duration":"296.867684ms","start":"2026-10-09T16:18:16.326859Z","end":"2026-10-09T16:18:16.623726Z","steps":["trace[501968509] 'process raft request' (duration: 296.678839ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:18:16.886067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.650678ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T16:18:16.886403Z","caller":"traceutil/trace.go:171","msg":"trace[1725285257] transaction","detail":"{read_only:false; response_revision:26135; number_of_response:1; }","duration":"480.398794ms","start":"2026-10-09T16:18:16.405953Z","end":"2026-10-09T16:18:16.886351Z","steps":["trace[1725285257] 'process raft request' (duration: 341.305837ms)","trace[1725285257] 'compare' (duration: 138.418173ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T16:18:16.886614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:18:16.405921Z","time spent":"480.569808ms","remote":"127.0.0.1:56192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T16:18:16.909424Z","caller":"traceutil/trace.go:171","msg":"trace[1457670701] linearizableReadLoop","detail":"{readStateIndex:26999; appliedIndex:26997; }","duration":"384.113814ms","start":"2026-10-09T16:18:16.525254Z","end":"2026-10-09T16:18:16.909367Z","steps":["trace[1457670701] 'read index received' (duration: 98.46624ms)","trace[1457670701] 'applied index is now lower than readState.Index' (duration: 285.645934ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:18:16.90942Z","caller":"traceutil/trace.go:171","msg":"trace[1769058093] transaction","detail":"{read_only:false; response_revision:26136; number_of_response:1; }","duration":"313.126048ms","start":"2026-10-09T16:18:16.596235Z","end":"2026-10-09T16:18:16.909361Z","steps":["trace[1769058093] 'process raft request' (duration: 312.852862ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:18:16.909601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.343578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T16:18:16.909659Z","caller":"traceutil/trace.go:171","msg":"trace[638520804] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:26137; }","duration":"384.449112ms","start":"2026-10-09T16:18:16.525194Z","end":"2026-10-09T16:18:16.909644Z","steps":["trace[638520804] 'agreement among raft nodes before linearized reading' (duration: 384.246337ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:18:16.9097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:18:16.525173Z","time spent":"384.515924ms","remote":"127.0.0.1:35458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":30,"request content":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true "} {"level":"warn","ts":"2026-10-09T16:18:16.909729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:18:16.596209Z","time spent":"313.393404ms","remote":"127.0.0.1:56192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T16:18:16.90991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.4516ms","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-09T16:18:16.909964Z","caller":"traceutil/trace.go:171","msg":"trace[659983185] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26138; }","duration":"358.557783ms","start":"2026-10-09T16:18:16.551395Z","end":"2026-10-09T16:18:16.909953Z","steps":["trace[659983185] 'agreement among raft nodes before linearized reading' (duration: 358.329448ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:18:16.910003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:18:16.551377Z","time spent":"358.617214ms","remote":"127.0.0.1:56192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-09T16:18:16.910016Z","caller":"traceutil/trace.go:171","msg":"trace[1196334000] transaction","detail":"{read_only:false; response_revision:26137; number_of_response:1; }","duration":"224.147139ms","start":"2026-10-09T16:18:16.685856Z","end":"2026-10-09T16:18:16.910003Z","steps":["trace[1196334000] 'process raft request' (duration: 223.464974ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:18:16.91006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.555013ms","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-09T16:18:16.910106Z","caller":"traceutil/trace.go:171","msg":"trace[1216528392] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26138; }","duration":"358.610394ms","start":"2026-10-09T16:18:16.551485Z","end":"2026-10-09T16:18:16.910095Z","steps":["trace[1216528392] 'agreement among raft nodes before linearized reading' (duration: 358.45305ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:18:16.910147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:18:16.551479Z","time spent":"358.660035ms","remote":"127.0.0.1:56192","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-09T16:18:16.910184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.705755ms","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-09T16:18:16.91022Z","caller":"traceutil/trace.go:171","msg":"trace[178135786] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26138; }","duration":"358.749737ms","start":"2026-10-09T16:18:16.551457Z","end":"2026-10-09T16:18:16.910207Z","steps":["trace[178135786] 'agreement among raft nodes before linearized reading' (duration: 358.657195ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:18:16.91025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:18:16.551452Z","time spent":"358.792428ms","remote":"127.0.0.1:56192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-09T16:18:17.220578Z","caller":"traceutil/trace.go:171","msg":"trace[905374894] transaction","detail":"{read_only:false; response_revision:26139; number_of_response:1; }","duration":"305.292562ms","start":"2026-10-09T16:18:16.915263Z","end":"2026-10-09T16:18:17.220555Z","steps":["trace[905374894] 'process raft request' (duration: 305.129969ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:18:17.22073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:18:16.915238Z","time spent":"305.413544ms","remote":"127.0.0.1:56192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T16:18:17.234072Z","caller":"traceutil/trace.go:171","msg":"trace[1232479697] linearizableReadLoop","detail":"{readStateIndex:27005; appliedIndex:27002; }","duration":"302.397018ms","start":"2026-10-09T16:18:16.931652Z","end":"2026-10-09T16:18:17.234049Z","steps":["trace[1232479697] 'read index received' (duration: 288.740683ms)","trace[1232479697] 'applied index is now lower than readState.Index' (duration: 13.655475ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:18:17.234108Z","caller":"traceutil/trace.go:171","msg":"trace[1351764821] transaction","detail":"{read_only:false; response_revision:26140; number_of_response:1; }","duration":"318.757564ms","start":"2026-10-09T16:18:16.91532Z","end":"2026-10-09T16:18:17.234078Z","steps":["trace[1351764821] 'process raft request' (duration: 318.539328ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:18:17.234194Z","caller":"traceutil/trace.go:171","msg":"trace[663259389] transaction","detail":"{read_only:false; response_revision:26141; number_of_response:1; }","duration":"318.865996ms","start":"2026-10-09T16:18:16.915316Z","end":"2026-10-09T16:18:17.234182Z","steps":["trace[663259389] 'process raft request' (duration: 318.691562ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:18:17.234274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:18:16.915304Z","time spent":"318.880676ms","remote":"127.0.0.1:56192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T16:18:17.234308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:18:16.915276Z","time spent":"318.951117ms","remote":"127.0.0.1:56192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T16:18:17.234475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.830887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T16:18:17.234534Z","caller":"traceutil/trace.go:171","msg":"trace[1421338584] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:26141; }","duration":"302.871608ms","start":"2026-10-09T16:18:16.931632Z","end":"2026-10-09T16:18:17.234504Z","steps":["trace[1421338584] 'agreement among raft nodes before linearized reading' (duration: 302.788806ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:18:17.234568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:18:16.931585Z","time spent":"302.9743ms","remote":"127.0.0.1:34786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":30,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-10-09T16:18:17.703377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.116805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:419209"} {"level":"info","ts":"2026-10-09T16:18:17.703459Z","caller":"traceutil/trace.go:171","msg":"trace[2101255375] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:26142; }","duration":"240.216078ms","start":"2026-10-09T16:18:17.463223Z","end":"2026-10-09T16:18:17.703439Z","steps":["trace[2101255375] 'range keys from in-memory index tree' (duration: 239.829469ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:18:17.703925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.041721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T16:18:17.703981Z","caller":"traceutil/trace.go:171","msg":"trace[1153629111] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:26142; }","duration":"162.161244ms","start":"2026-10-09T16:18:17.541807Z","end":"2026-10-09T16:18:17.703969Z","steps":["trace[1153629111] 'count revisions from in-memory index tree' (duration: 161.912899ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:18:17.704868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.38367ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T16:18:17.706164Z","caller":"traceutil/trace.go:171","msg":"trace[362251184] transaction","detail":"{read_only:false; response_revision:26143; number_of_response:1; }","duration":"140.53401ms","start":"2026-10-09T16:18:17.565606Z","end":"2026-10-09T16:18:17.70614Z","steps":["trace[362251184] 'process raft request' (duration: 23.787881ms)","trace[362251184] 'compare' (duration: 115.01524ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T16:18:17.951398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.216339ms","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-09T16:18:17.951482Z","caller":"traceutil/trace.go:171","msg":"trace[936485847] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26144; }","duration":"138.353052ms","start":"2026-10-09T16:18:17.813109Z","end":"2026-10-09T16:18:17.951462Z","steps":["trace[936485847] 'range keys from in-memory index tree' (duration: 138.143128ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:18:18.110618Z","caller":"traceutil/trace.go:171","msg":"trace[536977911] transaction","detail":"{read_only:false; response_revision:26145; number_of_response:1; }","duration":"202.439904ms","start":"2026-10-09T16:18:17.908156Z","end":"2026-10-09T16:18:18.110596Z","steps":["trace[536977911] 'process raft request' (duration: 202.308181ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:18:18.11596Z","caller":"traceutil/trace.go:171","msg":"trace[1131958339] linearizableReadLoop","detail":"{readStateIndex:27009; appliedIndex:27009; }","duration":"149.906459ms","start":"2026-10-09T16:18:17.966026Z","end":"2026-10-09T16:18:18.115933Z","steps":["trace[1131958339] 'read index received' (duration: 144.380566ms)","trace[1131958339] 'applied index is now lower than readState.Index' (duration: 5.523153ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T16:18:18.116282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.200676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T16:18:18.116325Z","caller":"traceutil/trace.go:171","msg":"trace[1343314179] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26145; }","duration":"150.34544ms","start":"2026-10-09T16:18:17.965969Z","end":"2026-10-09T16:18:18.116314Z","steps":["trace[1343314179] 'agreement among raft nodes before linearized reading' (duration: 150.210917ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:18:18.116935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.255678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T16:18:18.117131Z","caller":"traceutil/trace.go:171","msg":"trace[495609074] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:26145; }","duration":"103.429782ms","start":"2026-10-09T16:18:18.01361Z","end":"2026-10-09T16:18:18.11704Z","steps":["trace[495609074] 'agreement among raft nodes before linearized reading' (duration: 103.026773ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:18:18.409761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.629109ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T16:18:18.409881Z","caller":"traceutil/trace.go:171","msg":"trace[1991396389] linearizableReadLoop","detail":"{readStateIndex:27010; appliedIndex:27009; }","duration":"246.917617ms","start":"2026-10-09T16:18:18.162945Z","end":"2026-10-09T16:18:18.409863Z","steps":["trace[1991396389] 'read index received' (duration: 133.146825ms)","trace[1991396389] 'applied index is now lower than readState.Index' (duration: 113.769092ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:18:18.409952Z","caller":"traceutil/trace.go:171","msg":"trace[1144793062] transaction","detail":"{read_only:false; response_revision:26146; number_of_response:1; }","duration":"255.135181ms","start":"2026-10-09T16:18:18.154805Z","end":"2026-10-09T16:18:18.409941Z","steps":["trace[1144793062] 'process raft request' (duration: 141.229646ms)","trace[1144793062] 'compare' (duration: 113.531757ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T16:18:18.410169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.216764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T16:18:18.4102Z","caller":"traceutil/trace.go:171","msg":"trace[413040074] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:26146; }","duration":"247.278645ms","start":"2026-10-09T16:18:18.16291Z","end":"2026-10-09T16:18:18.410189Z","steps":["trace[413040074] 'agreement among raft nodes before linearized reading' (duration: 247.212564ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:18:18.702098Z","caller":"traceutil/trace.go:171","msg":"trace[300564783] transaction","detail":"{read_only:false; response_revision:26147; number_of_response:1; }","duration":"471.045117ms","start":"2026-10-09T16:18:18.231028Z","end":"2026-10-09T16:18:18.702073Z","steps":["trace[300564783] 'process raft request' (duration: 451.728385ms)","trace[300564783] 'compare' (duration: 19.019394ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T16:18:18.702249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:18:18.231002Z","time spent":"471.16411ms","remote":"127.0.0.1:55114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419267,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T16:18:18.702611Z","caller":"traceutil/trace.go:171","msg":"trace[1560009419] transaction","detail":"{read_only:false; response_revision:26149; number_of_response:1; }","duration":"416.708342ms","start":"2026-10-09T16:18:18.285879Z","end":"2026-10-09T16:18:18.702587Z","steps":["trace[1560009419] 'process raft request' (duration: 416.62149ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:18:18.702733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:18:18.285838Z","time spent":"416.829265ms","remote":"127.0.0.1:56192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T16:18:18.703316Z","caller":"traceutil/trace.go:171","msg":"trace[738862591] transaction","detail":"{read_only:false; response_revision:26150; number_of_response:1; }","duration":"416.078298ms","start":"2026-10-09T16:18:18.287196Z","end":"2026-10-09T16:18:18.703274Z","steps":["trace[738862591] 'process raft request' (duration: 415.363342ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:18:18.70339Z","caller":"traceutil/trace.go:171","msg":"trace[1762835906] linearizableReadLoop","detail":"{readStateIndex:27014; appliedIndex:27010; }","duration":"293.441268ms","start":"2026-10-09T16:18:18.409902Z","end":"2026-10-09T16:18:18.703344Z","steps":["trace[1762835906] 'read index received' (duration: 272.670384ms)","trace[1762835906] 'applied index is now lower than readState.Index' (duration: 20.768604ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T16:18:18.703507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:18:18.287183Z","time spent":"416.219981ms","remote":"127.0.0.1:56192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T16:18:18.703563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.328323ms","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-09T16:18:18.703612Z","caller":"traceutil/trace.go:171","msg":"trace[1012581754] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:26150; }","duration":"186.433915ms","start":"2026-10-09T16:18:18.517161Z","end":"2026-10-09T16:18:18.703595Z","steps":["trace[1012581754] 'agreement among raft nodes before linearized reading' (duration: 186.337423ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:18:18.703668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.706693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T16:18:18.703773Z","caller":"traceutil/trace.go:171","msg":"trace[329720922] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26150; }","duration":"493.991289ms","start":"2026-10-09T16:18:18.209759Z","end":"2026-10-09T16:18:18.70375Z","steps":["trace[329720922] 'agreement among raft nodes before linearized reading' (duration: 493.707602ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:18:18.703869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:18:18.209736Z","time spent":"494.119311ms","remote":"127.0.0.1:55870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-09T16:18:18.704379Z","caller":"traceutil/trace.go:171","msg":"trace[92476177] transaction","detail":"{read_only:false; response_revision:26148; number_of_response:1; }","duration":"417.921529ms","start":"2026-10-09T16:18:18.28584Z","end":"2026-10-09T16:18:18.703762Z","steps":["trace[92476177] 'process raft request' (duration: 416.17461ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:18:18.70455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:18:18.285801Z","time spent":"418.661395ms","remote":"127.0.0.1:56192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T16:18:19.102468Z","caller":"traceutil/trace.go:171","msg":"trace[1503484570] transaction","detail":"{read_only:false; response_revision:26153; number_of_response:1; }","duration":"187.07391ms","start":"2026-10-09T16:18:18.915371Z","end":"2026-10-09T16:18:19.102445Z","steps":["trace[1503484570] 'process raft request' (duration: 186.931547ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:18:19.213309Z","caller":"traceutil/trace.go:171","msg":"trace[418734269] linearizableReadLoop","detail":"{readStateIndex:27018; appliedIndex:27016; }","duration":"114.10971ms","start":"2026-10-09T16:18:19.099153Z","end":"2026-10-09T16:18:19.213263Z","steps":["trace[418734269] 'read index received' (duration: 3.152181ms)","trace[418734269] 'applied index is now lower than readState.Index' (duration: 110.955879ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:18:19.213332Z","caller":"traceutil/trace.go:171","msg":"trace[268598653] transaction","detail":"{read_only:false; response_revision:26154; number_of_response:1; }","duration":"295.973224ms","start":"2026-10-09T16:18:18.917328Z","end":"2026-10-09T16:18:19.213301Z","steps":["trace[268598653] 'process raft request' (duration: 295.092045ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:18:19.213646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.466028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19151"} {"level":"info","ts":"2026-10-09T16:18:19.213858Z","caller":"traceutil/trace.go:171","msg":"trace[2033566059] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:26154; }","duration":"114.729914ms","start":"2026-10-09T16:18:19.099097Z","end":"2026-10-09T16:18:19.213826Z","steps":["trace[2033566059] 'agreement among raft nodes before linearized reading' (duration: 114.331765ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:18:19.460034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.604615ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T16:18:19.460271Z","caller":"traceutil/trace.go:171","msg":"trace[1718454413] transaction","detail":"{read_only:false; response_revision:26158; number_of_response:1; }","duration":"155.696139ms","start":"2026-10-09T16:18:19.304556Z","end":"2026-10-09T16:18:19.460252Z","steps":["trace[1718454413] 'process raft request' (duration: 155.625837ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:18:19.460399Z","caller":"traceutil/trace.go:171","msg":"trace[881402152] transaction","detail":"{read_only:false; response_revision:26156; number_of_response:1; }","duration":"156.310863ms","start":"2026-10-09T16:18:19.304057Z","end":"2026-10-09T16:18:19.460368Z","steps":["trace[881402152] 'compare' (duration: 146.438352ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:18:19.460514Z","caller":"traceutil/trace.go:171","msg":"trace[2022955361] transaction","detail":"{read_only:false; response_revision:26157; number_of_response:1; }","duration":"156.023426ms","start":"2026-10-09T16:18:19.304481Z","end":"2026-10-09T16:18:19.460504Z","steps":["trace[2022955361] 'process raft request' (duration: 155.651438ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:18:27.486173Z","caller":"traceutil/trace.go:171","msg":"trace[1586335635] transaction","detail":"{read_only:false; response_revision:26211; number_of_response:1; }","duration":"247.039911ms","start":"2026-10-09T16:18:27.23909Z","end":"2026-10-09T16:18:27.48613Z","steps":["trace[1586335635] 'process raft request' (duration: 246.774395ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:18:27.997077Z","caller":"traceutil/trace.go:171","msg":"trace[943992448] transaction","detail":"{read_only:false; response_revision:26212; number_of_response:1; }","duration":"755.233017ms","start":"2026-10-09T16:18:27.241816Z","end":"2026-10-09T16:18:27.997049Z","steps":["trace[943992448] 'process raft request' (duration: 688.037286ms)","trace[943992448] 'compare' (duration: 66.846313ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T16:18:27.997238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:18:27.241799Z","time spent":"755.35306ms","remote":"127.0.0.1:56192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T16:18:27.99734Z","caller":"traceutil/trace.go:171","msg":"trace[996158945] transaction","detail":"{read_only:false; response_revision:26213; number_of_response:1; }","duration":"526.730081ms","start":"2026-10-09T16:18:27.470568Z","end":"2026-10-09T16:18:27.997298Z","steps":["trace[996158945] 'process raft request' (duration: 526.431404ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:18:27.997443Z","caller":"traceutil/trace.go:171","msg":"trace[1059979051] linearizableReadLoop","detail":"{readStateIndex:27078; appliedIndex:27076; }","duration":"666.33138ms","start":"2026-10-09T16:18:27.331102Z","end":"2026-10-09T16:18:27.997434Z","steps":["trace[1059979051] 'read index received' (duration: 154.810149ms)","trace[1059979051] 'applied index is now lower than readState.Index' (duration: 511.519991ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T16:18:27.997553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:18:27.470546Z","time spent":"526.915224ms","remote":"127.0.0.1:56192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T16:18:27.997353Z","caller":"traceutil/trace.go:171","msg":"trace[1514025607] transaction","detail":"{read_only:false; response_revision:26214; number_of_response:1; }","duration":"167.399801ms","start":"2026-10-09T16:18:27.82993Z","end":"2026-10-09T16:18:27.997329Z","steps":["trace[1514025607] 'process raft request' (duration: 167.294858ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:18:27.997674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"666.563906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-09T16:18:27.99771Z","caller":"traceutil/trace.go:171","msg":"trace[239858432] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26214; }","duration":"666.651637ms","start":"2026-10-09T16:18:27.331049Z","end":"2026-10-09T16:18:27.9977Z","steps":["trace[239858432] 'agreement among raft nodes before linearized reading' (duration: 666.467243ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:18:27.997741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:18:27.331026Z","time spent":"666.706628ms","remote":"127.0.0.1:56192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-09T16:18:27.997739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.129967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-09T16:18:27.997755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.495291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T16:18:27.99783Z","caller":"traceutil/trace.go:171","msg":"trace[1949551884] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:26214; }","duration":"496.252469ms","start":"2026-10-09T16:18:27.501559Z","end":"2026-10-09T16:18:27.997811Z","steps":["trace[1949551884] 'agreement among raft nodes before linearized reading' (duration: 496.093136ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:18:27.997843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.903786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-10-09T16:18:27.997837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.959266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-10-09T16:18:27.997873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:18:27.501555Z","time spent":"496.30816ms","remote":"127.0.0.1:56926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":30,"request content":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true "} {"level":"info","ts":"2026-10-09T16:18:27.997886Z","caller":"traceutil/trace.go:171","msg":"trace[1856468882] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26214; }","duration":"497.967257ms","start":"2026-10-09T16:18:27.499905Z","end":"2026-10-09T16:18:27.997872Z","steps":["trace[1856468882] 'agreement among raft nodes before linearized reading' (duration: 497.790944ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:18:27.997894Z","caller":"traceutil/trace.go:171","msg":"trace[2036159264] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26214; }","duration":"493.071019ms","start":"2026-10-09T16:18:27.50481Z","end":"2026-10-09T16:18:27.997881Z","steps":["trace[2036159264] 'agreement among raft nodes before linearized reading' (duration: 492.872854ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:18:27.997883Z","caller":"traceutil/trace.go:171","msg":"trace[322669237] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:26214; }","duration":"588.638344ms","start":"2026-10-09T16:18:27.409212Z","end":"2026-10-09T16:18:27.99785Z","steps":["trace[322669237] 'agreement among raft nodes before linearized reading' (duration: 588.428839ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:18:27.997923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:18:27.504772Z","time spent":"493.146889ms","remote":"127.0.0.1:56192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-09T16:18:27.997921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:18:27.4999Z","time spent":"498.010928ms","remote":"127.0.0.1:56192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":519,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-09T16:18:27.997911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.288067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-10-09T16:18:27.997954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:18:27.409117Z","time spent":"588.814468ms","remote":"127.0.0.1:57642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":128,"response count":0,"response size":30,"request content":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true "} {"level":"info","ts":"2026-10-09T16:18:27.998006Z","caller":"traceutil/trace.go:171","msg":"trace[401615896] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26214; }","duration":"503.42828ms","start":"2026-10-09T16:18:27.494556Z","end":"2026-10-09T16:18:27.997984Z","steps":["trace[401615896] 'agreement among raft nodes before linearized reading' (duration: 503.154464ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:18:27.998054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:18:27.494538Z","time spent":"503.505971ms","remote":"127.0.0.1:56192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-09T16:18:28.225317Z","caller":"traceutil/trace.go:171","msg":"trace[108365120] transaction","detail":"{read_only:false; response_revision:26215; number_of_response:1; }","duration":"222.077892ms","start":"2026-10-09T16:18:28.003209Z","end":"2026-10-09T16:18:28.225287Z","steps":["trace[108365120] 'process raft request' (duration: 221.54343ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:18:28.708969Z","caller":"traceutil/trace.go:171","msg":"trace[574279453] transaction","detail":"{read_only:false; response_revision:26216; number_of_response:1; }","duration":"705.384384ms","start":"2026-10-09T16:18:28.003555Z","end":"2026-10-09T16:18:28.708939Z","steps":["trace[574279453] 'process raft request' (duration: 625.961868ms)","trace[574279453] 'compare' (duration: 79.065438ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:18:28.709015Z","caller":"traceutil/trace.go:171","msg":"trace[508723398] transaction","detail":"{read_only:false; response_revision:26217; number_of_response:1; }","duration":"705.377973ms","start":"2026-10-09T16:18:28.003604Z","end":"2026-10-09T16:18:28.708982Z","steps":["trace[508723398] 'process raft request' (duration: 705.21051ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:18:28.709062Z","caller":"traceutil/trace.go:171","msg":"trace[1126081131] transaction","detail":"{read_only:false; response_revision:26218; number_of_response:1; }","duration":"705.411583ms","start":"2026-10-09T16:18:28.003611Z","end":"2026-10-09T16:18:28.709023Z","steps":["trace[1126081131] 'process raft request' (duration: 705.2631ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:18:28.709056Z","caller":"traceutil/trace.go:171","msg":"trace[662989739] linearizableReadLoop","detail":"{readStateIndex:27085; appliedIndex:27080; }","duration":"501.142439ms","start":"2026-10-09T16:18:28.207878Z","end":"2026-10-09T16:18:28.709021Z","steps":["trace[662989739] 'read index received' (duration: 16.818766ms)","trace[662989739] 'applied index is now lower than readState.Index' (duration: 484.321943ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:18:28.70908Z","caller":"traceutil/trace.go:171","msg":"trace[1135842202] transaction","detail":"{read_only:false; response_revision:26219; number_of_response:1; }","duration":"555.315569ms","start":"2026-10-09T16:18:28.153733Z","end":"2026-10-09T16:18:28.709049Z","steps":["trace[1135842202] 'process raft request' (duration: 555.190596ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:18:28.709177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:18:28.003576Z","time spent":"705.516036ms","remote":"127.0.0.1:56192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T16:18:28.709225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:18:28.003594Z","time spent":"705.539157ms","remote":"127.0.0.1:56192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T16:18:28.709249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:18:28.153704Z","time spent":"555.455533ms","remote":"127.0.0.1:56192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T16:18:28.709302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.402205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T16:18:28.709347Z","caller":"traceutil/trace.go:171","msg":"trace[1305300975] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26220; }","duration":"501.506037ms","start":"2026-10-09T16:18:28.207828Z","end":"2026-10-09T16:18:28.709334Z","steps":["trace[1305300975] 'agreement among raft nodes before linearized reading' (duration: 501.368894ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:18:28.709101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:18:28.003532Z","time spent":"705.497006ms","remote":"127.0.0.1:56192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T16:18:28.709354Z","caller":"traceutil/trace.go:171","msg":"trace[1466860175] transaction","detail":"{read_only:false; response_revision:26220; number_of_response:1; }","duration":"247.828029ms","start":"2026-10-09T16:18:28.461518Z","end":"2026-10-09T16:18:28.709346Z","steps":["trace[1466860175] 'process raft request' (duration: 247.45971ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:18:28.709385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:18:28.207806Z","time spent":"501.570108ms","remote":"127.0.0.1:55862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-09T16:18:28.709513Z","caller":"traceutil/trace.go:171","msg":"trace[1726550225] transaction","detail":"{read_only:false; response_revision:26221; number_of_response:1; }","duration":"158.190386ms","start":"2026-10-09T16:18:28.5513Z","end":"2026-10-09T16:18:28.70949Z","steps":["trace[1726550225] 'process raft request' (duration: 157.875579ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:18:28.709564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.878732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T16:18:28.709607Z","caller":"traceutil/trace.go:171","msg":"trace[1812938724] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:26221; }","duration":"346.949233ms","start":"2026-10-09T16:18:28.362646Z","end":"2026-10-09T16:18:28.709595Z","steps":["trace[1812938724] 'agreement among raft nodes before linearized reading' (duration: 346.861261ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:18:28.709637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:18:28.362635Z","time spent":"346.993105ms","remote":"127.0.0.1:34762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-10-09T16:18:28.709641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.167328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"warn","ts":"2026-10-09T16:18:28.709633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.516261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T16:18:28.709681Z","caller":"traceutil/trace.go:171","msg":"trace[2002783582] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26221; }","duration":"415.24852ms","start":"2026-10-09T16:18:28.294421Z","end":"2026-10-09T16:18:28.709669Z","steps":["trace[2002783582] 'agreement among raft nodes before linearized reading' (duration: 415.061056ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:18:28.709711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:18:28.294399Z","time spent":"415.30433ms","remote":"127.0.0.1:56104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12241,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-10-09T16:18:28.70971Z","caller":"traceutil/trace.go:171","msg":"trace[471171643] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:26221; }","duration":"285.660134ms","start":"2026-10-09T16:18:28.42403Z","end":"2026-10-09T16:18:28.70969Z","steps":["trace[471171643] 'agreement among raft nodes before linearized reading' (duration: 285.47526ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:18:28.933385Z","caller":"traceutil/trace.go:171","msg":"trace[639711479] transaction","detail":"{read_only:false; response_revision:26222; number_of_response:1; }","duration":"184.488543ms","start":"2026-10-09T16:18:28.748872Z","end":"2026-10-09T16:18:28.933361Z","steps":["trace[639711479] 'process raft request' (duration: 177.248051ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:18:29.34352Z","caller":"traceutil/trace.go:171","msg":"trace[13903903] transaction","detail":"{read_only:false; response_revision:26225; number_of_response:1; }","duration":"204.092631ms","start":"2026-10-09T16:18:29.139376Z","end":"2026-10-09T16:18:29.343469Z","steps":["trace[13903903] 'process raft request' (duration: 204.006159ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:18:29.343561Z","caller":"traceutil/trace.go:171","msg":"trace[111733622] transaction","detail":"{read_only:false; response_revision:26224; number_of_response:1; }","duration":"588.618314ms","start":"2026-10-09T16:18:28.754916Z","end":"2026-10-09T16:18:29.343534Z","steps":["trace[111733622] 'process raft request' (duration: 588.383308ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:18:29.343595Z","caller":"traceutil/trace.go:171","msg":"trace[1980971342] linearizableReadLoop","detail":"{readStateIndex:27090; appliedIndex:27087; }","duration":"577.76015ms","start":"2026-10-09T16:18:28.765795Z","end":"2026-10-09T16:18:29.343555Z","steps":["trace[1980971342] 'read index received' (duration: 160.343903ms)","trace[1980971342] 'applied index is now lower than readState.Index' (duration: 417.412697ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T16:18:29.343707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:18:28.754893Z","time spent":"588.733106ms","remote":"127.0.0.1:56192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T16:18:29.343806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.526592ms","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-09T16:18:29.343542Z","caller":"traceutil/trace.go:171","msg":"trace[2013076482] transaction","detail":"{read_only:false; response_revision:26223; number_of_response:1; }","duration":"591.998388ms","start":"2026-10-09T16:18:28.751517Z","end":"2026-10-09T16:18:29.343516Z","steps":["trace[2013076482] 'process raft request' (duration: 522.472784ms)","trace[2013076482] 'compare' (duration: 68.765458ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:18:29.343845Z","caller":"traceutil/trace.go:171","msg":"trace[1876272833] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26225; }","duration":"559.657335ms","start":"2026-10-09T16:18:28.784174Z","end":"2026-10-09T16:18:29.343832Z","steps":["trace[1876272833] 'agreement among raft nodes before linearized reading' (duration: 559.539653ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:18:29.343831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.437116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-09T16:18:29.343889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:18:28.784148Z","time spent":"559.731517ms","remote":"127.0.0.1:56410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":30,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-10-09T16:18:29.343902Z","caller":"traceutil/trace.go:171","msg":"trace[503390972] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26225; }","duration":"135.563529ms","start":"2026-10-09T16:18:29.20832Z","end":"2026-10-09T16:18:29.343883Z","steps":["trace[503390972] 'agreement among raft nodes before linearized reading' (duration: 135.406526ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:18:29.343919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.098098ms","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-09T16:18:29.344002Z","caller":"traceutil/trace.go:171","msg":"trace[507317385] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26225; }","duration":"578.21305ms","start":"2026-10-09T16:18:28.765771Z","end":"2026-10-09T16:18:29.343984Z","steps":["trace[507317385] 'agreement among raft nodes before linearized reading' (duration: 577.915614ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:18:29.344047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:18:28.765766Z","time spent":"578.271442ms","remote":"127.0.0.1:56192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-09T16:18:29.344035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:18:28.751493Z","time spent":"592.388037ms","remote":"127.0.0.1:56192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T16:18:29.585099Z","caller":"traceutil/trace.go:171","msg":"trace[1208310415] linearizableReadLoop","detail":"{readStateIndex:27092; appliedIndex:27091; }","duration":"219.255709ms","start":"2026-10-09T16:18:29.365813Z","end":"2026-10-09T16:18:29.585068Z","steps":["trace[1208310415] 'read index received' (duration: 218.504922ms)","trace[1208310415] 'applied index is now lower than readState.Index' (duration: 749.337µs)"],"step_count":2} {"level":"warn","ts":"2026-10-09T16:18:29.585375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.536067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-10-09T16:18:29.585435Z","caller":"traceutil/trace.go:171","msg":"trace[1944949724] transaction","detail":"{read_only:false; response_revision:26226; number_of_response:1; }","duration":"236.176969ms","start":"2026-10-09T16:18:29.349204Z","end":"2026-10-09T16:18:29.58538Z","steps":["trace[1944949724] 'process raft request' (duration: 235.129315ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:18:29.585462Z","caller":"traceutil/trace.go:171","msg":"trace[1914355804] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26226; }","duration":"219.69587ms","start":"2026-10-09T16:18:29.365749Z","end":"2026-10-09T16:18:29.585445Z","steps":["trace[1914355804] 'agreement among raft nodes before linearized reading' (duration: 219.434013ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:18:35.633476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.70634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bd1a7abc-c8bd-476f-99b8-4f238d3950c4\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-10-09T16:18:35.633586Z","caller":"traceutil/trace.go:171","msg":"trace[1119577797] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-bd1a7abc-c8bd-476f-99b8-4f238d3950c4; range_end:; response_count:1; response_revision:26324; }","duration":"117.847284ms","start":"2026-10-09T16:18:35.515695Z","end":"2026-10-09T16:18:35.633542Z","steps":["trace[1119577797] 'range keys from in-memory index tree' (duration: 117.458144ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:18:35.804482Z","caller":"traceutil/trace.go:171","msg":"trace[1896295173] transaction","detail":"{read_only:false; response_revision:26325; number_of_response:1; }","duration":"162.751138ms","start":"2026-10-09T16:18:35.641705Z","end":"2026-10-09T16:18:35.804457Z","steps":["trace[1896295173] 'process raft request' (duration: 162.604064ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:18:35.939144Z","caller":"traceutil/trace.go:171","msg":"trace[850807398] linearizableReadLoop","detail":"{readStateIndex:27194; appliedIndex:27192; }","duration":"255.754435ms","start":"2026-10-09T16:18:35.683352Z","end":"2026-10-09T16:18:35.939106Z","steps":["trace[850807398] 'read index received' (duration: 120.947623ms)","trace[850807398] 'applied index is now lower than readState.Index' (duration: 134.805222ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:18:35.939225Z","caller":"traceutil/trace.go:171","msg":"trace[2037150701] transaction","detail":"{read_only:false; response_revision:26326; number_of_response:1; }","duration":"294.561212ms","start":"2026-10-09T16:18:35.644613Z","end":"2026-10-09T16:18:35.939174Z","steps":["trace[2037150701] 'process raft request' (duration: 232.086585ms)","trace[2037150701] 'compare' (duration: 62.253942ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T16:18:35.939369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.982731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T16:18:35.939428Z","caller":"traceutil/trace.go:171","msg":"trace[976323267] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:26326; }","duration":"256.094073ms","start":"2026-10-09T16:18:35.683308Z","end":"2026-10-09T16:18:35.939402Z","steps":["trace[976323267] 'agreement among raft nodes before linearized reading' (duration: 255.9802ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:18:35.939679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.127081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:7914"} {"level":"info","ts":"2026-10-09T16:18:35.939811Z","caller":"traceutil/trace.go:171","msg":"trace[1743999744] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:26326; }","duration":"161.301915ms","start":"2026-10-09T16:18:35.778469Z","end":"2026-10-09T16:18:35.939771Z","steps":["trace[1743999744] 'agreement among raft nodes before linearized reading' (duration: 160.915456ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:18:42.954016Z","caller":"traceutil/trace.go:171","msg":"trace[938514682] linearizableReadLoop","detail":"{readStateIndex:27271; appliedIndex:27269; }","duration":"180.782121ms","start":"2026-10-09T16:18:42.773204Z","end":"2026-10-09T16:18:42.953986Z","steps":["trace[938514682] 'read index received' (duration: 567.643µs)","trace[938514682] 'applied index is now lower than readState.Index' (duration: 180.212898ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:18:42.954074Z","caller":"traceutil/trace.go:171","msg":"trace[1880936780] transaction","detail":"{read_only:false; response_revision:26400; number_of_response:1; }","duration":"185.67947ms","start":"2026-10-09T16:18:42.768362Z","end":"2026-10-09T16:18:42.954042Z","steps":["trace[1880936780] 'process raft request' (duration: 135.464288ms)","trace[1880936780] 'compare' (duration: 49.941336ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T16:18:42.954206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.975425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-09T16:18:42.954285Z","caller":"traceutil/trace.go:171","msg":"trace[1018654674] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:26400; }","duration":"181.103228ms","start":"2026-10-09T16:18:42.773164Z","end":"2026-10-09T16:18:42.954267Z","steps":["trace[1018654674] 'agreement among raft nodes before linearized reading' (duration: 180.955525ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:18:42.954459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.671083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-09T16:18:42.954552Z","caller":"traceutil/trace.go:171","msg":"trace[1666719789] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26400; }","duration":"178.785795ms","start":"2026-10-09T16:18:42.775738Z","end":"2026-10-09T16:18:42.954524Z","steps":["trace[1666719789] 'agreement among raft nodes before linearized reading' (duration: 178.505209ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:18:42.954867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.434563ms","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-09T16:18:42.954948Z","caller":"traceutil/trace.go:171","msg":"trace[1275150730] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26400; }","duration":"176.530935ms","start":"2026-10-09T16:18:42.778404Z","end":"2026-10-09T16:18:42.954935Z","steps":["trace[1275150730] 'agreement among raft nodes before linearized reading' (duration: 176.368092ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:18:42.955228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.043976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-09T16:18:42.955317Z","caller":"traceutil/trace.go:171","msg":"trace[1328407758] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26400; }","duration":"177.186899ms","start":"2026-10-09T16:18:42.778108Z","end":"2026-10-09T16:18:42.955295Z","steps":["trace[1328407758] 'agreement among raft nodes before linearized reading' (duration: 176.74708ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:18:47.071957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.707921ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T16:18:47.072023Z","caller":"traceutil/trace.go:171","msg":"trace[1383697473] transaction","detail":"{read_only:false; response_revision:26434; number_of_response:1; }","duration":"141.076433ms","start":"2026-10-09T16:18:46.930932Z","end":"2026-10-09T16:18:47.072009Z","steps":["trace[1383697473] 'process raft request' (duration: 10.255269ms)","trace[1383697473] 'compare' (duration: 130.554678ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:18:47.080851Z","caller":"traceutil/trace.go:171","msg":"trace[1591344042] transaction","detail":"{read_only:false; response_revision:26435; number_of_response:1; }","duration":"115.348838ms","start":"2026-10-09T16:18:46.965475Z","end":"2026-10-09T16:18:47.080824Z","steps":["trace[1591344042] 'process raft request' (duration: 115.204474ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:18:47.480015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.408628ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T16:18:47.480099Z","caller":"traceutil/trace.go:171","msg":"trace[1201748600] linearizableReadLoop","detail":"{readStateIndex:27308; appliedIndex:27306; }","duration":"452.46228ms","start":"2026-10-09T16:18:47.027625Z","end":"2026-10-09T16:18:47.480087Z","steps":["trace[1201748600] 'read index received' (duration: 53.087946ms)","trace[1201748600] 'applied index is now lower than readState.Index' (duration: 399.373604ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:18:47.480136Z","caller":"traceutil/trace.go:171","msg":"trace[139524720] transaction","detail":"{read_only:false; response_revision:26436; number_of_response:1; }","duration":"456.192613ms","start":"2026-10-09T16:18:47.023913Z","end":"2026-10-09T16:18:47.480105Z","steps":["trace[139524720] 'process raft request' (duration: 268.640942ms)","trace[139524720] 'compare' (duration: 187.02706ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T16:18:47.480279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:18:47.023884Z","time spent":"456.315747ms","remote":"127.0.0.1:56192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T16:18:47.480536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.886601ms","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-09T16:18:47.480631Z","caller":"traceutil/trace.go:171","msg":"trace[1270818705] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26436; }","duration":"453.023894ms","start":"2026-10-09T16:18:47.027584Z","end":"2026-10-09T16:18:47.480607Z","steps":["trace[1270818705] 'agreement among raft nodes before linearized reading' (duration: 452.539662ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:18:47.480718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:18:47.027566Z","time spent":"453.137777ms","remote":"127.0.0.1:56192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-09T16:18:47.48776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.676949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T16:18:47.48784Z","caller":"traceutil/trace.go:171","msg":"trace[1262198358] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:26437; }","duration":"172.802672ms","start":"2026-10-09T16:18:47.315021Z","end":"2026-10-09T16:18:47.487824Z","steps":["trace[1262198358] 'agreement among raft nodes before linearized reading' (duration: 172.589128ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:18:47.487816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.553487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T16:18:47.487893Z","caller":"traceutil/trace.go:171","msg":"trace[42273680] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26437; }","duration":"279.71682ms","start":"2026-10-09T16:18:47.208157Z","end":"2026-10-09T16:18:47.487874Z","steps":["trace[42273680] 'agreement among raft nodes before linearized reading' (duration: 279.475555ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:18:47.488011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.931829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-09T16:18:47.488036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.675432ms","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-09T16:18:47.48807Z","caller":"traceutil/trace.go:171","msg":"trace[732686942] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:26437; }","duration":"132.059141ms","start":"2026-10-09T16:18:47.355995Z","end":"2026-10-09T16:18:47.488054Z","steps":["trace[732686942] 'agreement among raft nodes before linearized reading' (duration: 131.926438ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:18:47.488092Z","caller":"traceutil/trace.go:171","msg":"trace[1988489815] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26437; }","duration":"459.743104ms","start":"2026-10-09T16:18:47.028333Z","end":"2026-10-09T16:18:47.488076Z","steps":["trace[1988489815] 'agreement among raft nodes before linearized reading' (duration: 459.56319ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:18:47.488127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:18:47.028326Z","time spent":"459.794015ms","remote":"127.0.0.1:56192","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-09T16:18:47.488566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.04036ms","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-09T16:18:47.488617Z","caller":"traceutil/trace.go:171","msg":"trace[1641293646] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26437; }","duration":"460.323166ms","start":"2026-10-09T16:18:47.028282Z","end":"2026-10-09T16:18:47.488605Z","steps":["trace[1641293646] 'agreement among raft nodes before linearized reading' (duration: 459.295284ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:18:47.48866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:18:47.028265Z","time spent":"460.381537ms","remote":"127.0.0.1:56192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-09T16:18:47.623758Z","caller":"traceutil/trace.go:171","msg":"trace[1323703743] transaction","detail":"{read_only:false; response_revision:26441; number_of_response:1; }","duration":"131.735884ms","start":"2026-10-09T16:18:47.491998Z","end":"2026-10-09T16:18:47.623733Z","steps":["trace[1323703743] 'process raft request' (duration: 131.521379ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:18:47.624002Z","caller":"traceutil/trace.go:171","msg":"trace[1919679311] transaction","detail":"{read_only:false; response_revision:26442; number_of_response:1; }","duration":"131.582011ms","start":"2026-10-09T16:18:47.492382Z","end":"2026-10-09T16:18:47.623964Z","steps":["trace[1919679311] 'process raft request' (duration: 131.310065ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:19:06.711415Z","caller":"traceutil/trace.go:171","msg":"trace[1041092222] linearizableReadLoop","detail":"{readStateIndex:27470; appliedIndex:27469; }","duration":"185.173678ms","start":"2026-10-09T16:19:06.526211Z","end":"2026-10-09T16:19:06.711384Z","steps":["trace[1041092222] 'read index received' (duration: 87.506595ms)","trace[1041092222] 'applied index is now lower than readState.Index' (duration: 97.665723ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T16:19:06.711708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.474024ms","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-09T16:19:06.711766Z","caller":"traceutil/trace.go:171","msg":"trace[2088733098] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26594; }","duration":"185.594007ms","start":"2026-10-09T16:19:06.526162Z","end":"2026-10-09T16:19:06.711756Z","steps":["trace[2088733098] 'agreement among raft nodes before linearized reading' (duration: 185.326931ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:19:06.851368Z","caller":"traceutil/trace.go:171","msg":"trace[1167253625] transaction","detail":"{read_only:false; response_revision:26595; number_of_response:1; }","duration":"135.003587ms","start":"2026-10-09T16:19:06.716339Z","end":"2026-10-09T16:19:06.851342Z","steps":["trace[1167253625] 'process raft request' (duration: 134.840533ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:19:07.181133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.857946ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T16:19:07.18123Z","caller":"traceutil/trace.go:171","msg":"trace[1798143525] linearizableReadLoop","detail":"{readStateIndex:27472; appliedIndex:27471; }","duration":"118.917537ms","start":"2026-10-09T16:19:07.062297Z","end":"2026-10-09T16:19:07.181214Z","steps":["trace[1798143525] 'read index received' (duration: 42.901µs)","trace[1798143525] 'applied index is now lower than readState.Index' (duration: 118.873236ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:19:07.1813Z","caller":"traceutil/trace.go:171","msg":"trace[1837156472] transaction","detail":"{read_only:false; response_revision:26596; number_of_response:1; }","duration":"308.994875ms","start":"2026-10-09T16:19:06.872292Z","end":"2026-10-09T16:19:07.181286Z","steps":["trace[1837156472] 'process raft request' (duration: 138.893804ms)","trace[1837156472] 'compare' (duration: 169.524258ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T16:19:07.181351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:19:06.872265Z","time spent":"309.055926ms","remote":"127.0.0.1:56192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T16:19:07.181455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.095871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs/\" range_end:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T16:19:07.181525Z","caller":"traceutil/trace.go:171","msg":"trace[710275951] range","detail":"{range_begin:/registry/runtime.cluster.x-k8s.io/extensionconfigs/; range_end:/registry/runtime.cluster.x-k8s.io/extensionconfigs0; response_count:0; response_revision:26596; }","duration":"119.267285ms","start":"2026-10-09T16:19:07.06224Z","end":"2026-10-09T16:19:07.181507Z","steps":["trace[710275951] 'agreement among raft nodes before linearized reading' (duration: 119.091991ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:19:07.858214Z","caller":"traceutil/trace.go:171","msg":"trace[415153479] linearizableReadLoop","detail":"{readStateIndex:27476; appliedIndex:27474; }","duration":"265.150126ms","start":"2026-10-09T16:19:07.593042Z","end":"2026-10-09T16:19:07.858192Z","steps":["trace[415153479] 'read index received' (duration: 82.3654ms)","trace[415153479] 'applied index is now lower than readState.Index' (duration: 182.783886ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:19:07.858364Z","caller":"traceutil/trace.go:171","msg":"trace[999119131] transaction","detail":"{read_only:false; response_revision:26600; number_of_response:1; }","duration":"270.847423ms","start":"2026-10-09T16:19:07.587494Z","end":"2026-10-09T16:19:07.858342Z","steps":["trace[999119131] 'process raft request' (duration: 270.513315ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:19:07.858482Z","caller":"traceutil/trace.go:171","msg":"trace[754443223] transaction","detail":"{read_only:false; response_revision:26601; number_of_response:1; }","duration":"245.810243ms","start":"2026-10-09T16:19:07.612633Z","end":"2026-10-09T16:19:07.858444Z","steps":["trace[754443223] 'process raft request' (duration: 245.489326ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:19:07.858538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.476233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T16:19:07.858574Z","caller":"traceutil/trace.go:171","msg":"trace[808859373] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:26602; }","duration":"265.553224ms","start":"2026-10-09T16:19:07.59301Z","end":"2026-10-09T16:19:07.858563Z","steps":["trace[808859373] 'agreement among raft nodes before linearized reading' (duration: 265.474942ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:19:07.858719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.378229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-09T16:19:07.858708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.04145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-09T16:19:07.858759Z","caller":"traceutil/trace.go:171","msg":"trace[1633228998] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:26602; }","duration":"162.4557ms","start":"2026-10-09T16:19:07.696288Z","end":"2026-10-09T16:19:07.858743Z","steps":["trace[1633228998] 'agreement among raft nodes before linearized reading' (duration: 162.363668ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:19:07.85881Z","caller":"traceutil/trace.go:171","msg":"trace[1649766470] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26602; }","duration":"166.198513ms","start":"2026-10-09T16:19:07.692572Z","end":"2026-10-09T16:19:07.85877Z","steps":["trace[1649766470] 'agreement among raft nodes before linearized reading' (duration: 165.936298ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:19:07.858885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.784416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-09T16:19:07.858924Z","caller":"traceutil/trace.go:171","msg":"trace[537988802] transaction","detail":"{read_only:false; response_revision:26602; number_of_response:1; }","duration":"241.863174ms","start":"2026-10-09T16:19:07.617045Z","end":"2026-10-09T16:19:07.858909Z","steps":["trace[537988802] 'process raft request' (duration: 241.114228ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:19:07.858745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.516372ms","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-09T16:19:07.858964Z","caller":"traceutil/trace.go:171","msg":"trace[1348109610] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26602; }","duration":"110.915959ms","start":"2026-10-09T16:19:07.748028Z","end":"2026-10-09T16:19:07.858943Z","steps":["trace[1348109610] 'agreement among raft nodes before linearized reading' (duration: 110.621133ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:19:07.859004Z","caller":"traceutil/trace.go:171","msg":"trace[343008883] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26602; }","duration":"107.824599ms","start":"2026-10-09T16:19:07.751155Z","end":"2026-10-09T16:19:07.858979Z","steps":["trace[343008883] 'agreement among raft nodes before linearized reading' (duration: 107.377349ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:19:08.113541Z","caller":"traceutil/trace.go:171","msg":"trace[1899456462] transaction","detail":"{read_only:false; response_revision:26604; number_of_response:1; }","duration":"249.824053ms","start":"2026-10-09T16:19:07.863677Z","end":"2026-10-09T16:19:08.113501Z","steps":["trace[1899456462] 'process raft request' (duration: 249.401093ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:19:08.113609Z","caller":"traceutil/trace.go:171","msg":"trace[828418579] transaction","detail":"{read_only:false; response_revision:26605; number_of_response:1; }","duration":"249.897024ms","start":"2026-10-09T16:19:07.863682Z","end":"2026-10-09T16:19:08.113579Z","steps":["trace[828418579] 'process raft request' (duration: 249.603408ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:19:08.328567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.801625ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T16:19:08.328846Z","caller":"traceutil/trace.go:171","msg":"trace[1652793377] transaction","detail":"{read_only:false; response_revision:26606; number_of_response:1; }","duration":"211.312802ms","start":"2026-10-09T16:19:08.117498Z","end":"2026-10-09T16:19:08.328811Z","steps":["trace[1652793377] 'process raft request' (duration: 61.636357ms)","trace[1652793377] 'compare' (duration: 148.59324ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:19:08.329325Z","caller":"traceutil/trace.go:171","msg":"trace[1280398136] transaction","detail":"{read_only:false; response_revision:26607; number_of_response:1; }","duration":"160.350774ms","start":"2026-10-09T16:19:08.168499Z","end":"2026-10-09T16:19:08.32885Z","steps":["trace[1280398136] 'process raft request' (duration: 160.197281ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:19:08.401713Z","caller":"traceutil/trace.go:171","msg":"trace[865513985] linearizableReadLoop","detail":"{readStateIndex:27484; appliedIndex:27481; }","duration":"194.245141ms","start":"2026-10-09T16:19:08.20745Z","end":"2026-10-09T16:19:08.401695Z","steps":["trace[865513985] 'read index received' (duration: 577.833µs)","trace[865513985] 'applied index is now lower than readState.Index' (duration: 193.666798ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T16:19:08.401829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.362023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T16:19:08.401852Z","caller":"traceutil/trace.go:171","msg":"trace[137674687] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26608; }","duration":"194.431555ms","start":"2026-10-09T16:19:08.207414Z","end":"2026-10-09T16:19:08.401846Z","steps":["trace[137674687] 'agreement among raft nodes before linearized reading' (duration: 194.347083ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:19:08.401899Z","caller":"traceutil/trace.go:171","msg":"trace[1113940964] transaction","detail":"{read_only:false; response_revision:26608; number_of_response:1; }","duration":"198.733341ms","start":"2026-10-09T16:19:08.203136Z","end":"2026-10-09T16:19:08.401869Z","steps":["trace[1113940964] 'process raft request' (duration: 198.455764ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:19:09.388066Z","caller":"traceutil/trace.go:171","msg":"trace[883197043] transaction","detail":"{read_only:false; response_revision:26611; number_of_response:1; }","duration":"200.601763ms","start":"2026-10-09T16:19:09.187442Z","end":"2026-10-09T16:19:09.388044Z","steps":["trace[883197043] 'process raft request' (duration: 200.418749ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:19:09.394859Z","caller":"traceutil/trace.go:171","msg":"trace[2042347009] transaction","detail":"{read_only:false; response_revision:26612; number_of_response:1; }","duration":"198.69887ms","start":"2026-10-09T16:19:09.196128Z","end":"2026-10-09T16:19:09.394827Z","steps":["trace[2042347009] 'process raft request' (duration: 198.460315ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:19:09.394921Z","caller":"traceutil/trace.go:171","msg":"trace[1737964453] linearizableReadLoop","detail":"{readStateIndex:27488; appliedIndex:27486; }","duration":"185.777711ms","start":"2026-10-09T16:19:09.208925Z","end":"2026-10-09T16:19:09.394703Z","steps":["trace[1737964453] 'read index received' (duration: 178.96634ms)","trace[1737964453] 'applied index is now lower than readState.Index' (duration: 6.810211ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T16:19:09.394992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.040427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T16:19:09.395064Z","caller":"traceutil/trace.go:171","msg":"trace[1551063198] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26612; }","duration":"186.147949ms","start":"2026-10-09T16:19:09.208898Z","end":"2026-10-09T16:19:09.395046Z","steps":["trace[1551063198] 'agreement among raft nodes before linearized reading' (duration: 186.023086ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:19:09.395047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.444586ms","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-09T16:19:09.39511Z","caller":"traceutil/trace.go:171","msg":"trace[1704245060] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/addressscopes/; range_end:/registry/openstack.k-orc.cloud/addressscopes0; response_count:0; response_revision:26612; }","duration":"109.560438ms","start":"2026-10-09T16:19:09.285537Z","end":"2026-10-09T16:19:09.395098Z","steps":["trace[1704245060] 'agreement among raft nodes before linearized reading' (duration: 109.467576ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:19:14.316035Z","caller":"traceutil/trace.go:171","msg":"trace[1783717022] linearizableReadLoop","detail":"{readStateIndex:27525; appliedIndex:27524; }","duration":"402.459104ms","start":"2026-10-09T16:19:13.913544Z","end":"2026-10-09T16:19:14.316003Z","steps":["trace[1783717022] 'read index received' (duration: 336.555371ms)","trace[1783717022] 'applied index is now lower than readState.Index' (duration: 65.902033ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:19:14.316065Z","caller":"traceutil/trace.go:171","msg":"trace[1511999743] transaction","detail":"{read_only:false; response_revision:26648; number_of_response:1; }","duration":"426.842698ms","start":"2026-10-09T16:19:13.889172Z","end":"2026-10-09T16:19:14.316015Z","steps":["trace[1511999743] 'process raft request' (duration: 360.893104ms)","trace[1511999743] 'compare' (duration: 65.796021ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T16:19:14.316237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.447521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T16:19:14.316284Z","caller":"traceutil/trace.go:171","msg":"trace[1044148250] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:26648; }","duration":"363.540904ms","start":"2026-10-09T16:19:13.952728Z","end":"2026-10-09T16:19:14.316269Z","steps":["trace[1044148250] 'agreement among raft nodes before linearized reading' (duration: 363.438872ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:19:14.316284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:19:13.889145Z","time spent":"427.008482ms","remote":"127.0.0.1:56192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T16:19:14.316296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.7408ms","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-09T16:19:14.316378Z","caller":"traceutil/trace.go:171","msg":"trace[1611223035] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26648; }","duration":"402.868433ms","start":"2026-10-09T16:19:13.913491Z","end":"2026-10-09T16:19:14.31636Z","steps":["trace[1611223035] 'agreement among raft nodes before linearized reading' (duration: 402.650348ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:19:14.316417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.527557ms","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-09T16:19:14.316432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:19:13.91347Z","time spent":"402.948664ms","remote":"127.0.0.1:56192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-09T16:19:14.316449Z","caller":"traceutil/trace.go:171","msg":"trace[1676804921] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26648; }","duration":"177.613789ms","start":"2026-10-09T16:19:14.138828Z","end":"2026-10-09T16:19:14.316442Z","steps":["trace[1676804921] 'agreement among raft nodes before linearized reading' (duration: 177.517697ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:19:14.316442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.505622ms","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-09T16:19:14.31652Z","caller":"traceutil/trace.go:171","msg":"trace[918269999] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26648; }","duration":"175.615185ms","start":"2026-10-09T16:19:14.140885Z","end":"2026-10-09T16:19:14.3165Z","steps":["trace[918269999] 'agreement among raft nodes before linearized reading' (duration: 175.414661ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:19:14.316325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:19:13.952717Z","time spent":"363.597465ms","remote":"127.0.0.1:56918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":30,"request content":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-10-09T16:19:14.316821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.684814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1987"} {"level":"info","ts":"2026-10-09T16:19:14.316888Z","caller":"traceutil/trace.go:171","msg":"trace[2128076550] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:26648; }","duration":"187.795836ms","start":"2026-10-09T16:19:14.12907Z","end":"2026-10-09T16:19:14.316865Z","steps":["trace[2128076550] 'agreement among raft nodes before linearized reading' (duration: 187.548431ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:19:14.528046Z","caller":"traceutil/trace.go:171","msg":"trace[1220796623] transaction","detail":"{read_only:false; response_revision:26650; number_of_response:1; }","duration":"207.144449ms","start":"2026-10-09T16:19:14.320871Z","end":"2026-10-09T16:19:14.528015Z","steps":["trace[1220796623] 'process raft request' (duration: 111.556633ms)","trace[1220796623] 'compare' (duration: 95.254419ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:19:14.528317Z","caller":"traceutil/trace.go:171","msg":"trace[1233337460] transaction","detail":"{read_only:false; response_revision:26651; number_of_response:1; }","duration":"207.420675ms","start":"2026-10-09T16:19:14.320878Z","end":"2026-10-09T16:19:14.528298Z","steps":["trace[1233337460] 'process raft request' (duration: 207.049157ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:19:14.528479Z","caller":"traceutil/trace.go:171","msg":"trace[433850990] linearizableReadLoop","detail":"{readStateIndex:27529; appliedIndex:27525; }","duration":"168.787192ms","start":"2026-10-09T16:19:14.359672Z","end":"2026-10-09T16:19:14.528459Z","steps":["trace[433850990] 'read index received' (duration: 34.306937ms)","trace[433850990] 'applied index is now lower than readState.Index' (duration: 134.479215ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T16:19:14.528643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.950215ms","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-09T16:19:14.528666Z","caller":"traceutil/trace.go:171","msg":"trace[423512059] transaction","detail":"{read_only:false; response_revision:26652; number_of_response:1; }","duration":"178.811376ms","start":"2026-10-09T16:19:14.349842Z","end":"2026-10-09T16:19:14.528653Z","steps":["trace[423512059] 'process raft request' (duration: 178.405407ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:19:14.528679Z","caller":"traceutil/trace.go:171","msg":"trace[953647432] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26652; }","duration":"169.022716ms","start":"2026-10-09T16:19:14.359646Z","end":"2026-10-09T16:19:14.528668Z","steps":["trace[953647432] 'agreement among raft nodes before linearized reading' (duration: 168.886153ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:19:20.151937Z","caller":"traceutil/trace.go:171","msg":"trace[741136832] linearizableReadLoop","detail":"{readStateIndex:27568; appliedIndex:27567; }","duration":"141.271426ms","start":"2026-10-09T16:19:20.010619Z","end":"2026-10-09T16:19:20.15189Z","steps":["trace[741136832] 'read index received' (duration: 86.029061ms)","trace[741136832] 'applied index is now lower than readState.Index' (duration: 55.240595ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:19:20.152083Z","caller":"traceutil/trace.go:171","msg":"trace[817508104] transaction","detail":"{read_only:false; response_revision:26690; number_of_response:1; }","duration":"230.589083ms","start":"2026-10-09T16:19:19.921422Z","end":"2026-10-09T16:19:20.152011Z","steps":["trace[817508104] 'process raft request' (duration: 175.270086ms)","trace[817508104] 'compare' (duration: 54.974509ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T16:19:20.15242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.781098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-09T16:19:20.152502Z","caller":"traceutil/trace.go:171","msg":"trace[548932638] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:26690; }","duration":"141.918801ms","start":"2026-10-09T16:19:20.010562Z","end":"2026-10-09T16:19:20.152481Z","steps":["trace[548932638] 'agreement among raft nodes before linearized reading' (duration: 141.527362ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:19:20.545832Z","caller":"traceutil/trace.go:171","msg":"trace[70197326] transaction","detail":"{read_only:false; response_revision:26692; number_of_response:1; }","duration":"120.662717ms","start":"2026-10-09T16:19:20.425132Z","end":"2026-10-09T16:19:20.545795Z","steps":["trace[70197326] 'process raft request' (duration: 113.170489ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:19:20.782729Z","caller":"traceutil/trace.go:171","msg":"trace[2106506090] transaction","detail":"{read_only:false; response_revision:26695; number_of_response:1; }","duration":"167.033282ms","start":"2026-10-09T16:19:20.615656Z","end":"2026-10-09T16:19:20.78269Z","steps":["trace[2106506090] 'process raft request' (duration: 139.422916ms)","trace[2106506090] 'compare' (duration: 27.369001ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:19:20.81754Z","caller":"traceutil/trace.go:171","msg":"trace[1647817077] transaction","detail":"{read_only:false; response_revision:26696; number_of_response:1; }","duration":"201.471213ms","start":"2026-10-09T16:19:20.61604Z","end":"2026-10-09T16:19:20.817511Z","steps":["trace[1647817077] 'process raft request' (duration: 201.268008ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:19:20.817636Z","caller":"traceutil/trace.go:171","msg":"trace[1089167336] linearizableReadLoop","detail":"{readStateIndex:27575; appliedIndex:27572; }","duration":"158.878821ms","start":"2026-10-09T16:19:20.658734Z","end":"2026-10-09T16:19:20.817613Z","steps":["trace[1089167336] 'read index received' (duration: 96.439006ms)","trace[1089167336] 'applied index is now lower than readState.Index' (duration: 62.438265ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:19:20.817646Z","caller":"traceutil/trace.go:171","msg":"trace[116534250] transaction","detail":"{read_only:false; response_revision:26697; number_of_response:1; }","duration":"201.556644ms","start":"2026-10-09T16:19:20.616062Z","end":"2026-10-09T16:19:20.817619Z","steps":["trace[116534250] 'process raft request' (duration: 201.406101ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:19:20.817737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.984783ms","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-09T16:19:20.817762Z","caller":"traceutil/trace.go:171","msg":"trace[1880533443] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26697; }","duration":"159.064405ms","start":"2026-10-09T16:19:20.658687Z","end":"2026-10-09T16:19:20.817751Z","steps":["trace[1880533443] 'agreement among raft nodes before linearized reading' (duration: 158.996203ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:19:24.980925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.064052ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T16:19:24.981103Z","caller":"traceutil/trace.go:171","msg":"trace[1157824048] transaction","detail":"{read_only:false; response_revision:26726; number_of_response:1; }","duration":"137.38527ms","start":"2026-10-09T16:19:24.843699Z","end":"2026-10-09T16:19:24.981084Z","steps":["trace[1157824048] 'process raft request' (duration: 137.319849ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:19:24.981306Z","caller":"traceutil/trace.go:171","msg":"trace[1182365086] transaction","detail":"{read_only:false; response_revision:26725; number_of_response:1; }","duration":"137.606065ms","start":"2026-10-09T16:19:24.843682Z","end":"2026-10-09T16:19:24.981288Z","steps":["trace[1182365086] 'process raft request' (duration: 30.152034ms)","trace[1182365086] 'compare' (duration: 106.800997ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:19:30.764029Z","caller":"traceutil/trace.go:171","msg":"trace[1979977618] linearizableReadLoop","detail":"{readStateIndex:27644; appliedIndex:27643; }","duration":"277.093322ms","start":"2026-10-09T16:19:30.486892Z","end":"2026-10-09T16:19:30.763985Z","steps":["trace[1979977618] 'read index received' (duration: 277.029061ms)","trace[1979977618] 'applied index is now lower than readState.Index' (duration: 63.201µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:19:30.764147Z","caller":"traceutil/trace.go:171","msg":"trace[2006134825] transaction","detail":"{read_only:false; response_revision:26764; number_of_response:1; }","duration":"315.349656ms","start":"2026-10-09T16:19:30.448764Z","end":"2026-10-09T16:19:30.764114Z","steps":["trace[2006134825] 'process raft request' (duration: 315.06052ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:19:30.76421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.286136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-09T16:19:30.764255Z","caller":"traceutil/trace.go:171","msg":"trace[1908247264] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:26764; }","duration":"277.400499ms","start":"2026-10-09T16:19:30.486844Z","end":"2026-10-09T16:19:30.764244Z","steps":["trace[1908247264] 'agreement among raft nodes before linearized reading' (duration: 277.302337ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:19:30.764383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:19:30.448728Z","time spent":"315.574281ms","remote":"127.0.0.1:56192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T16:19:31.033018Z","caller":"traceutil/trace.go:171","msg":"trace[1851146865] linearizableReadLoop","detail":"{readStateIndex:27646; appliedIndex:27644; }","duration":"268.886318ms","start":"2026-10-09T16:19:30.764108Z","end":"2026-10-09T16:19:31.032994Z","steps":["trace[1851146865] 'read index received' (duration: 205.600855ms)","trace[1851146865] 'applied index is now lower than readState.Index' (duration: 63.284143ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:19:31.033043Z","caller":"traceutil/trace.go:171","msg":"trace[1193249209] transaction","detail":"{read_only:false; response_revision:26765; number_of_response:1; }","duration":"438.998019ms","start":"2026-10-09T16:19:30.594017Z","end":"2026-10-09T16:19:31.033015Z","steps":["trace[1193249209] 'process raft request' (duration: 375.688525ms)","trace[1193249209] 'compare' (duration: 63.087699ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:19:31.033114Z","caller":"traceutil/trace.go:171","msg":"trace[1239778111] transaction","detail":"{read_only:false; response_revision:26766; number_of_response:1; }","duration":"367.365109ms","start":"2026-10-09T16:19:30.665716Z","end":"2026-10-09T16:19:31.033081Z","steps":["trace[1239778111] 'process raft request' (duration: 367.214326ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:19:31.033187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:19:30.593993Z","time spent":"439.112752ms","remote":"127.0.0.1:56192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T16:19:31.033256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.49538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-09T16:19:31.033291Z","caller":"traceutil/trace.go:171","msg":"trace[463869975] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26766; }","duration":"439.567422ms","start":"2026-10-09T16:19:30.593711Z","end":"2026-10-09T16:19:31.033279Z","steps":["trace[463869975] 'agreement among raft nodes before linearized reading' (duration: 439.409388ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:19:31.033318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:19:30.593691Z","time spent":"439.619334ms","remote":"127.0.0.1:56192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":519,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-09T16:19:31.033306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:19:30.665688Z","time spent":"367.509953ms","remote":"127.0.0.1:56192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T16:19:31.033575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.318189ms","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":"info","ts":"2026-10-09T16:19:31.033666Z","caller":"traceutil/trace.go:171","msg":"trace[1744048973] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:26766; }","duration":"184.438472ms","start":"2026-10-09T16:19:30.849206Z","end":"2026-10-09T16:19:31.033644Z","steps":["trace[1744048973] 'agreement among raft nodes before linearized reading' (duration: 184.189156ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:19:31.033751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.098474ms","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-09T16:19:31.03381Z","caller":"traceutil/trace.go:171","msg":"trace[1659075090] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26766; }","duration":"167.211827ms","start":"2026-10-09T16:19:30.866586Z","end":"2026-10-09T16:19:31.033798Z","steps":["trace[1659075090] 'agreement among raft nodes before linearized reading' (duration: 167.073514ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:19:31.25337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.70349ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T16:19:31.25361Z","caller":"traceutil/trace.go:171","msg":"trace[2094156332] transaction","detail":"{read_only:false; response_revision:26768; number_of_response:1; }","duration":"214.212216ms","start":"2026-10-09T16:19:31.039358Z","end":"2026-10-09T16:19:31.25357Z","steps":["trace[2094156332] 'process raft request' (duration: 96.208529ms)","trace[2094156332] 'compare' (duration: 117.572267ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:19:31.253854Z","caller":"traceutil/trace.go:171","msg":"trace[1380628011] linearizableReadLoop","detail":"{readStateIndex:27649; appliedIndex:27647; }","duration":"214.263498ms","start":"2026-10-09T16:19:31.039567Z","end":"2026-10-09T16:19:31.25383Z","steps":["trace[1380628011] 'read index received' (duration: 5.046943ms)","trace[1380628011] 'applied index is now lower than readState.Index' (duration: 209.214645ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:19:31.253876Z","caller":"traceutil/trace.go:171","msg":"trace[334196991] transaction","detail":"{read_only:false; response_revision:26770; number_of_response:1; }","duration":"214.233467ms","start":"2026-10-09T16:19:31.039602Z","end":"2026-10-09T16:19:31.253835Z","steps":["trace[334196991] 'process raft request' (duration: 214.083784ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:19:31.253861Z","caller":"traceutil/trace.go:171","msg":"trace[559927887] transaction","detail":"{read_only:false; response_revision:26769; number_of_response:1; }","duration":"214.414811ms","start":"2026-10-09T16:19:31.03939Z","end":"2026-10-09T16:19:31.253805Z","steps":["trace[559927887] 'process raft request' (duration: 214.171976ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:19:31.254113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.889476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-10-09T16:19:31.254129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.490933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19151"} {"level":"info","ts":"2026-10-09T16:19:31.254187Z","caller":"traceutil/trace.go:171","msg":"trace[754852616] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26770; }","duration":"153.014289ms","start":"2026-10-09T16:19:31.10116Z","end":"2026-10-09T16:19:31.254174Z","steps":["trace[754852616] 'agreement among raft nodes before linearized reading' (duration: 152.839575ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:19:31.254206Z","caller":"traceutil/trace.go:171","msg":"trace[1891249717] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:26770; }","duration":"214.642565ms","start":"2026-10-09T16:19:31.039543Z","end":"2026-10-09T16:19:31.254185Z","steps":["trace[1891249717] 'agreement among raft nodes before linearized reading' (duration: 214.351799ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:19:31.681929Z","caller":"traceutil/trace.go:171","msg":"trace[1800502590] transaction","detail":"{read_only:false; response_revision:26772; number_of_response:1; }","duration":"212.610061ms","start":"2026-10-09T16:19:31.469296Z","end":"2026-10-09T16:19:31.681906Z","steps":["trace[1800502590] 'process raft request' (duration: 149.843148ms)","trace[1800502590] 'compare' (duration: 62.556018ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:19:31.682111Z","caller":"traceutil/trace.go:171","msg":"trace[377772003] transaction","detail":"{read_only:false; response_revision:26773; number_of_response:1; }","duration":"170.378148ms","start":"2026-10-09T16:19:31.511716Z","end":"2026-10-09T16:19:31.682094Z","steps":["trace[377772003] 'process raft request' (duration: 170.139332ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:19:31.682197Z","caller":"traceutil/trace.go:171","msg":"trace[1163909067] transaction","detail":"{read_only:false; response_revision:26774; number_of_response:1; }","duration":"159.105505ms","start":"2026-10-09T16:19:31.523064Z","end":"2026-10-09T16:19:31.682169Z","steps":["trace[1163909067] 'process raft request' (duration: 158.982633ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:19:32.165552Z","caller":"traceutil/trace.go:171","msg":"trace[1218314930] transaction","detail":"{read_only:false; response_revision:26776; number_of_response:1; }","duration":"147.536017ms","start":"2026-10-09T16:19:32.017969Z","end":"2026-10-09T16:19:32.165505Z","steps":["trace[1218314930] 'process raft request' (duration: 107.730047ms)","trace[1218314930] 'compare' (duration: 39.514214ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:19:32.382534Z","caller":"traceutil/trace.go:171","msg":"trace[825382653] transaction","detail":"{read_only:false; response_revision:26777; number_of_response:1; }","duration":"179.198425ms","start":"2026-10-09T16:19:32.203288Z","end":"2026-10-09T16:19:32.382486Z","steps":["trace[825382653] 'process raft request' (duration: 92.356074ms)","trace[825382653] 'compare' (duration: 86.425122ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:19:32.382576Z","caller":"traceutil/trace.go:171","msg":"trace[748353277] linearizableReadLoop","detail":"{readStateIndex:27658; appliedIndex:27657; }","duration":"174.459898ms","start":"2026-10-09T16:19:32.208092Z","end":"2026-10-09T16:19:32.382552Z","steps":["trace[748353277] 'read index received' (duration: 87.70843ms)","trace[748353277] 'applied index is now lower than readState.Index' (duration: 86.749938ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:19:32.382539Z","caller":"traceutil/trace.go:171","msg":"trace[714174415] transaction","detail":"{read_only:false; response_revision:26778; number_of_response:1; }","duration":"153.271015ms","start":"2026-10-09T16:19:32.229245Z","end":"2026-10-09T16:19:32.382516Z","steps":["trace[714174415] 'process raft request' (duration: 153.158963ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:19:32.382747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.622532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-09T16:19:32.382809Z","caller":"traceutil/trace.go:171","msg":"trace[68439817] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:26778; }","duration":"174.774886ms","start":"2026-10-09T16:19:32.208021Z","end":"2026-10-09T16:19:32.382796Z","steps":["trace[68439817] 'agreement among raft nodes before linearized reading' (duration: 174.650803ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:19:32.383292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.808076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T16:19:32.383358Z","caller":"traceutil/trace.go:171","msg":"trace[195026048] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26778; }","duration":"174.914209ms","start":"2026-10-09T16:19:32.208423Z","end":"2026-10-09T16:19:32.383338Z","steps":["trace[195026048] 'agreement among raft nodes before linearized reading' (duration: 174.409367ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:19:55.441655Z","caller":"traceutil/trace.go:171","msg":"trace[1245915338] transaction","detail":"{read_only:false; response_revision:27095; number_of_response:1; }","duration":"584.880825ms","start":"2026-10-09T16:19:54.856713Z","end":"2026-10-09T16:19:55.441594Z","steps":["trace[1245915338] 'process raft request' (duration: 584.602199ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:19:55.441952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:19:54.856683Z","time spent":"585.132192ms","remote":"127.0.0.1:56192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T16:19:55.509985Z","caller":"traceutil/trace.go:171","msg":"trace[608573659] transaction","detail":"{read_only:false; response_revision:27096; number_of_response:1; }","duration":"365.441231ms","start":"2026-10-09T16:19:55.144495Z","end":"2026-10-09T16:19:55.509937Z","steps":["trace[608573659] 'process raft request' (duration: 363.761263ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:19:55.510178Z","caller":"traceutil/trace.go:171","msg":"trace[859209982] linearizableReadLoop","detail":"{readStateIndex:27984; appliedIndex:27981; }","duration":"318.474421ms","start":"2026-10-09T16:19:55.191679Z","end":"2026-10-09T16:19:55.510153Z","steps":["trace[859209982] 'read index received' (duration: 249.681642ms)","trace[859209982] 'applied index is now lower than readState.Index' (duration: 68.790629ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T16:19:55.510201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:19:55.144469Z","time spent":"365.628446ms","remote":"127.0.0.1:56192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T16:19:55.510248Z","caller":"traceutil/trace.go:171","msg":"trace[513503699] transaction","detail":"{read_only:false; response_revision:27098; number_of_response:1; }","duration":"159.368553ms","start":"2026-10-09T16:19:55.350847Z","end":"2026-10-09T16:19:55.510216Z","steps":["trace[513503699] 'process raft request' (duration: 159.059557ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:19:55.509991Z","caller":"traceutil/trace.go:171","msg":"trace[614263483] transaction","detail":"{read_only:false; response_revision:27097; number_of_response:1; }","duration":"361.688407ms","start":"2026-10-09T16:19:55.148264Z","end":"2026-10-09T16:19:55.509953Z","steps":["trace[614263483] 'process raft request' (duration: 361.591164ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:19:55.510364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.098375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T16:19:55.510412Z","caller":"traceutil/trace.go:171","msg":"trace[113607437] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27098; }","duration":"302.231087ms","start":"2026-10-09T16:19:55.208169Z","end":"2026-10-09T16:19:55.5104Z","steps":["trace[113607437] 'agreement among raft nodes before linearized reading' (duration: 302.117675ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:19:55.510394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.700996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-10-09T16:19:55.510458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:19:55.208137Z","time spent":"302.312279ms","remote":"127.0.0.1:55862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-09T16:19:55.510465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.903645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-09T16:19:55.510466Z","caller":"traceutil/trace.go:171","msg":"trace[1316597264] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27098; }","duration":"318.810179ms","start":"2026-10-09T16:19:55.19164Z","end":"2026-10-09T16:19:55.51045Z","steps":["trace[1316597264] 'agreement among raft nodes before linearized reading' (duration: 318.590074ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:19:55.510509Z","caller":"traceutil/trace.go:171","msg":"trace[1373090221] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27098; }","duration":"100.969297ms","start":"2026-10-09T16:19:55.409525Z","end":"2026-10-09T16:19:55.510494Z","steps":["trace[1373090221] 'agreement among raft nodes before linearized reading' (duration: 100.831434ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:19:55.510512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:19:55.191626Z","time spent":"318.87851ms","remote":"127.0.0.1:56192","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-09T16:19:55.510488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:19:55.148239Z","time spent":"362.128757ms","remote":"127.0.0.1:56192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T16:19:55.51072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.307045ms","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-09T16:19:55.510873Z","caller":"traceutil/trace.go:171","msg":"trace[200261204] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27098; }","duration":"139.512719ms","start":"2026-10-09T16:19:55.371328Z","end":"2026-10-09T16:19:55.510841Z","steps":["trace[200261204] 'agreement among raft nodes before linearized reading' (duration: 139.197232ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:19:55.846715Z","caller":"traceutil/trace.go:171","msg":"trace[1414901869] transaction","detail":"{read_only:false; response_revision:27100; number_of_response:1; }","duration":"330.894117ms","start":"2026-10-09T16:19:55.515796Z","end":"2026-10-09T16:19:55.84669Z","steps":["trace[1414901869] 'process raft request' (duration: 330.692252ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:19:55.84696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:19:55.51575Z","time spent":"331.126342ms","remote":"127.0.0.1:56192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T16:19:55.846963Z","caller":"traceutil/trace.go:171","msg":"trace[730590370] transaction","detail":"{read_only:false; response_revision:27101; number_of_response:1; }","duration":"331.0385ms","start":"2026-10-09T16:19:55.515892Z","end":"2026-10-09T16:19:55.84693Z","steps":["trace[730590370] 'process raft request' (duration: 330.756864ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:19:55.847099Z","caller":"traceutil/trace.go:171","msg":"trace[1085335621] transaction","detail":"{read_only:false; response_revision:27103; number_of_response:1; }","duration":"330.707063ms","start":"2026-10-09T16:19:55.516338Z","end":"2026-10-09T16:19:55.847045Z","steps":["trace[1085335621] 'process raft request' (duration: 330.652381ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:19:55.847113Z","caller":"traceutil/trace.go:171","msg":"trace[1417618011] transaction","detail":"{read_only:false; response_revision:27102; number_of_response:1; }","duration":"331.02859ms","start":"2026-10-09T16:19:55.516049Z","end":"2026-10-09T16:19:55.847078Z","steps":["trace[1417618011] 'process raft request' (duration: 330.831476ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:19:55.847321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:19:55.516329Z","time spent":"330.854137ms","remote":"127.0.0.1:56192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T16:19:55.847388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:19:55.51602Z","time spent":"331.255215ms","remote":"127.0.0.1:56192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T16:19:55.847475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T16:19:55.515884Z","time spent":"331.280405ms","remote":"127.0.0.1:56192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T16:19:56.067862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.484674ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T16:19:56.068077Z","caller":"traceutil/trace.go:171","msg":"trace[1501456691] transaction","detail":"{read_only:false; response_revision:27104; number_of_response:1; }","duration":"293.754118ms","start":"2026-10-09T16:19:55.774303Z","end":"2026-10-09T16:19:56.068058Z","steps":["trace[1501456691] 'process raft request' (duration: 189.942427ms)","trace[1501456691] 'compare' (duration: 103.241328ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:19:56.068253Z","caller":"traceutil/trace.go:171","msg":"trace[565471586] linearizableReadLoop","detail":"{readStateIndex:27992; appliedIndex:27990; }","duration":"171.821032ms","start":"2026-10-09T16:19:55.896407Z","end":"2026-10-09T16:19:56.068228Z","steps":["trace[565471586] 'read index received' (duration: 67.833887ms)","trace[565471586] 'applied index is now lower than readState.Index' (duration: 103.986305ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T16:19:56.068434Z","caller":"traceutil/trace.go:171","msg":"trace[828573465] transaction","detail":"{read_only:false; response_revision:27105; number_of_response:1; }","duration":"289.233916ms","start":"2026-10-09T16:19:55.77919Z","end":"2026-10-09T16:19:56.068424Z","steps":["trace[828573465] 'process raft request' (duration: 288.781986ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:19:56.068657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.24098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T16:19:56.068695Z","caller":"traceutil/trace.go:171","msg":"trace[1831023492] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:27105; }","duration":"172.337693ms","start":"2026-10-09T16:19:55.896348Z","end":"2026-10-09T16:19:56.068686Z","steps":["trace[1831023492] 'agreement among raft nodes before linearized reading' (duration: 172.258071ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:19:56.162961Z","caller":"traceutil/trace.go:171","msg":"trace[2143413827] transaction","detail":"{read_only:false; response_revision:27106; number_of_response:1; }","duration":"122.169623ms","start":"2026-10-09T16:19:56.04076Z","end":"2026-10-09T16:19:56.162929Z","steps":["trace[2143413827] 'process raft request' (duration: 121.905667ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:19:56.162984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.317083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-09T16:19:56.162983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.015148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T16:19:56.163056Z","caller":"traceutil/trace.go:171","msg":"trace[287313042] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:27106; }","duration":"265.444386ms","start":"2026-10-09T16:19:55.897592Z","end":"2026-10-09T16:19:56.163037Z","steps":["trace[287313042] 'agreement among raft nodes before linearized reading' (duration: 265.293493ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:19:56.163099Z","caller":"traceutil/trace.go:171","msg":"trace[2018782662] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:27106; }","duration":"266.231983ms","start":"2026-10-09T16:19:55.896842Z","end":"2026-10-09T16:19:56.163074Z","steps":["trace[2018782662] 'agreement among raft nodes before linearized reading' (duration: 266.022698ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T16:19:56.163141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.10807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T16:19:56.163209Z","caller":"traceutil/trace.go:171","msg":"trace[7375584] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:0; response_revision:27106; }","duration":"266.224372ms","start":"2026-10-09T16:19:55.896968Z","end":"2026-10-09T16:19:56.163192Z","steps":["trace[7375584] 'agreement among raft nodes before linearized reading' (duration: 266.11023ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T16:21:07.998491Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24925} {"level":"info","ts":"2026-10-09T16:21:08.156949Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24925,"took":"154.316551ms","hash":3657518025,"current-db-size-bytes":58343424,"current-db-size":"58 MB","current-db-size-in-use-bytes":36741120,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-10-09T16:21:08.157023Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3657518025,"revision":24925,"compact-revision":21849} {"level":"info","ts":"2026-10-09T16:23:16.010414Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"931f6a422b4c426f","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-09T16:23:16.020356Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-09T16:23:16.020476Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-09T16:26:08.005246Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27756} {"level":"info","ts":"2026-10-09T16:26:08.148628Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27756,"took":"138.20584ms","hash":4173368246,"current-db-size-bytes":58343424,"current-db-size":"58 MB","current-db-size-in-use-bytes":34553856,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-09T16:26:08.148701Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4173368246,"revision":27756,"compact-revision":24925} {"level":"info","ts":"2026-10-09T16:31:08.019099Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30404} {"level":"info","ts":"2026-10-09T16:31:08.164874Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30404,"took":"141.643646ms","hash":3223714632,"current-db-size-bytes":58343424,"current-db-size":"58 MB","current-db-size-in-use-bytes":30810112,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-09T16:31:08.164952Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3223714632,"revision":30404,"compact-revision":27756} {"level":"info","ts":"2026-10-09T16:36:08.027779Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32656} {"level":"info","ts":"2026-10-09T16:36:08.183665Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32656,"took":"151.071997ms","hash":1912814130,"current-db-size-bytes":59383808,"current-db-size":"59 MB","current-db-size-in-use-bytes":30511104,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-09T16:36:08.183732Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1912814130,"revision":32656,"compact-revision":30404} {"level":"info","ts":"2026-10-09T16:41:08.036946Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34888} {"level":"info","ts":"2026-10-09T16:41:08.1706Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34888,"took":"128.83504ms","hash":1965550979,"current-db-size-bytes":59383808,"current-db-size":"59 MB","current-db-size-in-use-bytes":31948800,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-09T16:41:08.170664Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1965550979,"revision":34888,"compact-revision":32656} {"level":"info","ts":"2026-10-09T16:44:41.590142Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"931f6a422b4c426f","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-09T16:44:41.596866Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-09T16:44:41.596992Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-09T16:46:08.046042Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37180} {"level":"info","ts":"2026-10-09T16:46:08.175708Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37180,"took":"126.115337ms","hash":1118101539,"current-db-size-bytes":59383808,"current-db-size":"59 MB","current-db-size-in-use-bytes":32563200,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-09T16:46:08.175773Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1118101539,"revision":37180,"compact-revision":34888} {"level":"info","ts":"2026-10-09T16:51:08.054408Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39463} {"level":"info","ts":"2026-10-09T16:51:08.191634Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39463,"took":"130.359332ms","hash":2296118190,"current-db-size-bytes":59383808,"current-db-size":"59 MB","current-db-size-in-use-bytes":30044160,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-09T16:51:08.191702Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2296118190,"revision":39463,"compact-revision":37180} {"level":"info","ts":"2026-10-09T16:56:08.062592Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":41692} {"level":"info","ts":"2026-10-09T16:56:08.185696Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":41692,"took":"119.93632ms","hash":2004082065,"current-db-size-bytes":59383808,"current-db-size":"59 MB","current-db-size-in-use-bytes":29126656,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-09T16:56:08.185853Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2004082065,"revision":41692,"compact-revision":39463} {"level":"info","ts":"2026-10-09T17:01:08.069949Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":43917} {"level":"info","ts":"2026-10-09T17:01:08.186837Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":43917,"took":"112.823821ms","hash":2019162685,"current-db-size-bytes":59383808,"current-db-size":"59 MB","current-db-size-in-use-bytes":29622272,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-09T17:01:08.186894Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2019162685,"revision":43917,"compact-revision":41692}