{"level":"warn","ts":"2026-10-02T09:17:49.093548Z","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-02T09:17:49.093633Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.44: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://199.204.45.44:2380","--initial-cluster=instance=https://199.204.45.44:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.44:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.44: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-02T09:17:49.093713Z","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-02T09:17:49.09374Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.44:2380"]} {"level":"info","ts":"2026-10-02T09:17:49.093759Z","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-02T09:17:49.094685Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.44:2379"]} {"level":"info","ts":"2026-10-02T09:17:49.094774Z","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://199.204.45.44:2380"],"listen-peer-urls":["https://199.204.45.44:2380"],"advertise-client-urls":["https://199.204.45.44:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.44:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.44: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-02T09:17:49.098936Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.96472ms"} {"level":"info","ts":"2026-10-02T09:17:49.111461Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a0a01fc7fbf6be45","cluster-id":"92fa3fee501eaaec"} {"level":"info","ts":"2026-10-02T09:17:49.111555Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 switched to configuration voters=()"} {"level":"info","ts":"2026-10-02T09:17:49.111578Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became follower at term 0"} {"level":"info","ts":"2026-10-02T09:17:49.111591Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a0a01fc7fbf6be45 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-02T09:17:49.111611Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became follower at term 1"} {"level":"info","ts":"2026-10-02T09:17:49.111654Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 switched to configuration voters=(11574285986128379461)"} {"level":"warn","ts":"2026-10-02T09:17:49.116613Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-02T09:17:49.118156Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-02T09:17:49.119441Z","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-02T09:17:49.122517Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a0a01fc7fbf6be45","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-02T09:17:49.122733Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a0a01fc7fbf6be45","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-02T09:17:49.122883Z","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-02T09:17:49.122986Z","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-02T09:17:49.123002Z","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-02T09:17:49.123976Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 switched to configuration voters=(11574285986128379461)"} {"level":"info","ts":"2026-10-02T09:17:49.125515Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"92fa3fee501eaaec","local-member-id":"a0a01fc7fbf6be45","added-peer-id":"a0a01fc7fbf6be45","added-peer-peer-urls":["https://199.204.45.44:2380"]} {"level":"info","ts":"2026-10-02T09:17:49.128723Z","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-02T09:17:49.128922Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.44:2380"} {"level":"info","ts":"2026-10-02T09:17:49.129364Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.44:2380"} {"level":"info","ts":"2026-10-02T09:17:49.129125Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a0a01fc7fbf6be45","initial-advertise-peer-urls":["https://199.204.45.44:2380"],"listen-peer-urls":["https://199.204.45.44:2380"],"advertise-client-urls":["https://199.204.45.44:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.44:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-02T09:17:49.129213Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-02T09:17:49.612287Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 is starting a new election at term 1"} {"level":"info","ts":"2026-10-02T09:17:49.61234Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-02T09:17:49.612362Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 received MsgPreVoteResp from a0a01fc7fbf6be45 at term 1"} {"level":"info","ts":"2026-10-02T09:17:49.612375Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became candidate at term 2"} {"level":"info","ts":"2026-10-02T09:17:49.612381Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 received MsgVoteResp from a0a01fc7fbf6be45 at term 2"} {"level":"info","ts":"2026-10-02T09:17:49.612391Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became leader at term 2"} {"level":"info","ts":"2026-10-02T09:17:49.6124Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a0a01fc7fbf6be45 elected leader a0a01fc7fbf6be45 at term 2"} {"level":"info","ts":"2026-10-02T09:17:49.759561Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a0a01fc7fbf6be45","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.44:2379]}","request-path":"/0/members/a0a01fc7fbf6be45/attributes","cluster-id":"92fa3fee501eaaec","publish-timeout":"7s"} {"level":"info","ts":"2026-10-02T09:17:49.759607Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-02T09:17:49.759648Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-02T09:17:49.759864Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-02T09:17:49.762556Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-02T09:17:49.762907Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-02T09:17:49.762931Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-02T09:17:49.763377Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.44:2379"} {"level":"info","ts":"2026-10-02T09:17:49.765339Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"92fa3fee501eaaec","local-member-id":"a0a01fc7fbf6be45","cluster-version":"3.5"} {"level":"info","ts":"2026-10-02T09:17:49.76564Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-02T09:17:49.765734Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-02T09:18:05.061886Z","caller":"traceutil/trace.go:171","msg":"trace[2096089790] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"247.029342ms","start":"2026-10-02T09:18:04.814833Z","end":"2026-10-02T09:18:05.061862Z","steps":["trace[2096089790] 'process raft request' (duration: 246.768986ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:18:05.061831Z","caller":"traceutil/trace.go:171","msg":"trace[1895156282] linearizableReadLoop","detail":"{readStateIndex:381; appliedIndex:380; }","duration":"178.092565ms","start":"2026-10-02T09:18:04.883699Z","end":"2026-10-02T09:18:05.061791Z","steps":["trace[1895156282] 'read index received' (duration: 177.91568ms)","trace[1895156282] 'applied index is now lower than readState.Index' (duration: 176.005µs)"],"step_count":2} {"level":"warn","ts":"2026-10-02T09:18:05.062037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.273279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T09:18:05.062096Z","caller":"traceutil/trace.go:171","msg":"trace[1078543273] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:367; }","duration":"178.417343ms","start":"2026-10-02T09:18:04.883668Z","end":"2026-10-02T09:18:05.062086Z","steps":["trace[1078543273] 'agreement among raft nodes before linearized reading' (duration: 178.256959ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:18:05.307615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.384609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7294"} {"level":"info","ts":"2026-10-02T09:18:05.307734Z","caller":"traceutil/trace.go:171","msg":"trace[1572775762] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:367; }","duration":"241.546032ms","start":"2026-10-02T09:18:05.066155Z","end":"2026-10-02T09:18:05.307702Z","steps":["trace[1572775762] 'range keys from in-memory index tree' (duration: 241.264387ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:18:26.053041Z","caller":"traceutil/trace.go:171","msg":"trace[164258493] linearizableReadLoop","detail":"{readStateIndex:472; appliedIndex:471; }","duration":"133.372821ms","start":"2026-10-02T09:18:25.919632Z","end":"2026-10-02T09:18:26.053005Z","steps":["trace[164258493] 'read index received' (duration: 133.031662ms)","trace[164258493] 'applied index is now lower than readState.Index' (duration: 339.639µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:18:26.053161Z","caller":"traceutil/trace.go:171","msg":"trace[373685414] transaction","detail":"{read_only:false; response_revision:453; number_of_response:1; }","duration":"168.116502ms","start":"2026-10-02T09:18:25.885003Z","end":"2026-10-02T09:18:26.05312Z","steps":["trace[373685414] 'process raft request' (duration: 167.802064ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:18:26.053255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.561646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T09:18:26.053315Z","caller":"traceutil/trace.go:171","msg":"trace[1609571962] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:453; }","duration":"133.690799ms","start":"2026-10-02T09:18:25.919595Z","end":"2026-10-02T09:18:26.053286Z","steps":["trace[1609571962] 'agreement among raft nodes before linearized reading' (duration: 133.550495ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:18:34.437534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.740522ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-02T09:18:34.437637Z","caller":"traceutil/trace.go:171","msg":"trace[1780911230] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"231.580591ms","start":"2026-10-02T09:18:34.206039Z","end":"2026-10-02T09:18:34.43762Z","steps":["trace[1780911230] 'process raft request' (duration: 89.998998ms)","trace[1780911230] 'compare' (duration: 140.462275ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:18:35.022153Z","caller":"traceutil/trace.go:171","msg":"trace[801664812] linearizableReadLoop","detail":"{readStateIndex:623; appliedIndex:622; }","duration":"138.105945ms","start":"2026-10-02T09:18:34.88402Z","end":"2026-10-02T09:18:35.022126Z","steps":["trace[801664812] 'read index received' (duration: 115.868412ms)","trace[801664812] 'applied index is now lower than readState.Index' (duration: 22.236423ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:18:35.022242Z","caller":"traceutil/trace.go:171","msg":"trace[337372704] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"507.463773ms","start":"2026-10-02T09:18:34.514745Z","end":"2026-10-02T09:18:35.022208Z","steps":["trace[337372704] 'process raft request' (duration: 485.201679ms)","trace[337372704] 'compare' (duration: 22.041299ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T09:18:35.022302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.255388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T09:18:35.022339Z","caller":"traceutil/trace.go:171","msg":"trace[1386858206] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:599; }","duration":"138.349421ms","start":"2026-10-02T09:18:34.883979Z","end":"2026-10-02T09:18:35.022328Z","steps":["trace[1386858206] 'agreement among raft nodes before linearized reading' (duration: 138.242368ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:18:35.022423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:18:34.514718Z","time spent":"507.596196ms","remote":"127.0.0.1:47896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-02T09:18:50.368558Z","caller":"traceutil/trace.go:171","msg":"trace[1821028662] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"138.053914ms","start":"2026-10-02T09:18:50.230476Z","end":"2026-10-02T09:18:50.36853Z","steps":["trace[1821028662] 'process raft request' (duration: 137.866879ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:19:37.013385Z","caller":"traceutil/trace.go:171","msg":"trace[179849405] linearizableReadLoop","detail":"{readStateIndex:1347; appliedIndex:1346; }","duration":"129.179371ms","start":"2026-10-02T09:19:36.884177Z","end":"2026-10-02T09:19:37.013356Z","steps":["trace[179849405] 'read index received' (duration: 128.957965ms)","trace[179849405] 'applied index is now lower than readState.Index' (duration: 220.426µs)"],"step_count":2} {"level":"warn","ts":"2026-10-02T09:19:37.013511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.323056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T09:19:37.013542Z","caller":"traceutil/trace.go:171","msg":"trace[1522613500] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1304; }","duration":"129.413648ms","start":"2026-10-02T09:19:36.884119Z","end":"2026-10-02T09:19:37.013532Z","steps":["trace[1522613500] 'agreement among raft nodes before linearized reading' (duration: 129.341666ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:19:37.013444Z","caller":"traceutil/trace.go:171","msg":"trace[260074673] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"150.825382ms","start":"2026-10-02T09:19:36.862581Z","end":"2026-10-02T09:19:37.013406Z","steps":["trace[260074673] 'process raft request' (duration: 150.592785ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:20:10.478696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.794465ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-02T09:20:10.478825Z","caller":"traceutil/trace.go:171","msg":"trace[479860058] linearizableReadLoop","detail":"{readStateIndex:1916; appliedIndex:1915; }","duration":"295.119243ms","start":"2026-10-02T09:20:10.183689Z","end":"2026-10-02T09:20:10.478808Z","steps":["trace[479860058] 'read index received' (duration: 34.106762ms)","trace[479860058] 'applied index is now lower than readState.Index' (duration: 261.011061ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:20:10.478948Z","caller":"traceutil/trace.go:171","msg":"trace[276777105] transaction","detail":"{read_only:false; response_revision:1843; number_of_response:1; }","duration":"316.143098ms","start":"2026-10-02T09:20:10.162764Z","end":"2026-10-02T09:20:10.478907Z","steps":["trace[276777105] 'process raft request' (duration: 55.020964ms)","trace[276777105] 'compare' (duration: 260.477458ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T09:20:10.47906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.374571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-10-02T09:20:10.479182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:20:10.162738Z","time spent":"316.300012ms","remote":"127.0.0.1:47796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T09:20:10.479267Z","caller":"traceutil/trace.go:171","msg":"trace[751284392] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1843; }","duration":"295.433261ms","start":"2026-10-02T09:20:10.183647Z","end":"2026-10-02T09:20:10.479081Z","steps":["trace[751284392] 'agreement among raft nodes before linearized reading' (duration: 295.256017ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:20:10.47939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.740142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"warn","ts":"2026-10-02T09:20:10.479263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.237211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-10-02T09:20:10.479473Z","caller":"traceutil/trace.go:171","msg":"trace[388845447] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1843; }","duration":"239.850644ms","start":"2026-10-02T09:20:10.239602Z","end":"2026-10-02T09:20:10.479453Z","steps":["trace[388845447] 'agreement among raft nodes before linearized reading' (duration: 239.605208ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:20:10.479483Z","caller":"traceutil/trace.go:171","msg":"trace[1323835033] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1843; }","duration":"289.492788ms","start":"2026-10-02T09:20:10.189972Z","end":"2026-10-02T09:20:10.479465Z","steps":["trace[1323835033] 'agreement among raft nodes before linearized reading' (duration: 289.098118ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:20:10.479392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.257169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-02T09:20:10.479599Z","caller":"traceutil/trace.go:171","msg":"trace[1938112719] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1843; }","duration":"285.515056ms","start":"2026-10-02T09:20:10.194075Z","end":"2026-10-02T09:20:10.47959Z","steps":["trace[1938112719] 'agreement among raft nodes before linearized reading' (duration: 285.143626ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:20:10.860436Z","caller":"traceutil/trace.go:171","msg":"trace[1836230744] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"210.359051ms","start":"2026-10-02T09:20:10.650054Z","end":"2026-10-02T09:20:10.860413Z","steps":["trace[1836230744] 'process raft request' (duration: 210.210988ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:20:24.082785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.178625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T09:20:24.082878Z","caller":"traceutil/trace.go:171","msg":"trace[735431200] transaction","detail":"{read_only:false; response_revision:1961; number_of_response:1; }","duration":"112.422449ms","start":"2026-10-02T09:20:23.97043Z","end":"2026-10-02T09:20:24.082853Z","steps":["trace[735431200] 'process raft request' (duration: 111.140725ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:20:24.082906Z","caller":"traceutil/trace.go:171","msg":"trace[1171123781] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1960; }","duration":"201.38362ms","start":"2026-10-02T09:20:23.881495Z","end":"2026-10-02T09:20:24.082879Z","steps":["trace[1171123781] 'range keys from in-memory index tree' (duration: 201.02249ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:21:02.41153Z","caller":"traceutil/trace.go:171","msg":"trace[2041360361] linearizableReadLoop","detail":"{readStateIndex:2269; appliedIndex:2268; }","duration":"125.106219ms","start":"2026-10-02T09:21:02.286402Z","end":"2026-10-02T09:21:02.411508Z","steps":["trace[2041360361] 'read index received' (duration: 124.899963ms)","trace[2041360361] 'applied index is now lower than readState.Index' (duration: 205.276µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:21:02.411582Z","caller":"traceutil/trace.go:171","msg":"trace[1916137071] transaction","detail":"{read_only:false; response_revision:2184; number_of_response:1; }","duration":"198.135859ms","start":"2026-10-02T09:21:02.213424Z","end":"2026-10-02T09:21:02.41156Z","steps":["trace[1916137071] 'process raft request' (duration: 197.912913ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:21:02.411694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.275943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T09:21:02.411735Z","caller":"traceutil/trace.go:171","msg":"trace[716861330] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:2184; }","duration":"125.379275ms","start":"2026-10-02T09:21:02.286344Z","end":"2026-10-02T09:21:02.411723Z","steps":["trace[716861330] 'agreement among raft nodes before linearized reading' (duration: 125.297153ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:21:57.010182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.876006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T09:21:57.010301Z","caller":"traceutil/trace.go:171","msg":"trace[623881252] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:2649; }","duration":"107.010911ms","start":"2026-10-02T09:21:56.90326Z","end":"2026-10-02T09:21:57.010271Z","steps":["trace[623881252] 'count revisions from in-memory index tree' (duration: 106.767314ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:21:57.010321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.347815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T09:21:57.010378Z","caller":"traceutil/trace.go:171","msg":"trace[1390199275] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2649; }","duration":"126.447217ms","start":"2026-10-02T09:21:56.883915Z","end":"2026-10-02T09:21:57.010362Z","steps":["trace[1390199275] 'range keys from in-memory index tree' (duration: 126.232013ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:22:25.346702Z","caller":"traceutil/trace.go:171","msg":"trace[1641019277] transaction","detail":"{read_only:false; response_revision:2826; number_of_response:1; }","duration":"108.693536ms","start":"2026-10-02T09:22:25.237955Z","end":"2026-10-02T09:22:25.346649Z","steps":["trace[1641019277] 'process raft request' (duration: 108.545352ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:27:11.086509Z","caller":"traceutil/trace.go:171","msg":"trace[457570747] transaction","detail":"{read_only:false; response_revision:5149; number_of_response:1; }","duration":"129.824835ms","start":"2026-10-02T09:27:10.956663Z","end":"2026-10-02T09:27:11.086488Z","steps":["trace[457570747] 'process raft request' (duration: 129.436925ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:27:12.8355Z","caller":"traceutil/trace.go:171","msg":"trace[620144871] linearizableReadLoop","detail":"{readStateIndex:5453; appliedIndex:5452; }","duration":"140.536509ms","start":"2026-10-02T09:27:12.694947Z","end":"2026-10-02T09:27:12.835483Z","steps":["trace[620144871] 'read index received' (duration: 140.416625ms)","trace[620144871] 'applied index is now lower than readState.Index' (duration: 119.214µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:27:12.835576Z","caller":"traceutil/trace.go:171","msg":"trace[184460403] transaction","detail":"{read_only:false; response_revision:5254; number_of_response:1; }","duration":"157.570167ms","start":"2026-10-02T09:27:12.677971Z","end":"2026-10-02T09:27:12.835541Z","steps":["trace[184460403] 'process raft request' (duration: 157.407543ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:27:12.83568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.167639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-02T09:27:12.835706Z","caller":"traceutil/trace.go:171","msg":"trace[346884349] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5254; }","duration":"129.25484ms","start":"2026-10-02T09:27:12.706444Z","end":"2026-10-02T09:27:12.835699Z","steps":["trace[346884349] 'agreement among raft nodes before linearized reading' (duration: 129.160348ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:27:12.835744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.773515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-02T09:27:12.835825Z","caller":"traceutil/trace.go:171","msg":"trace[92580824] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5254; }","duration":"140.902158ms","start":"2026-10-02T09:27:12.694904Z","end":"2026-10-02T09:27:12.835806Z","steps":["trace[92580824] 'agreement among raft nodes before linearized reading' (duration: 140.653192ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:27:16.98621Z","caller":"traceutil/trace.go:171","msg":"trace[137729021] transaction","detail":"{read_only:false; response_revision:5343; number_of_response:1; }","duration":"115.9639ms","start":"2026-10-02T09:27:16.870222Z","end":"2026-10-02T09:27:16.986186Z","steps":["trace[137729021] 'process raft request' (duration: 20.142031ms)","trace[137729021] 'compare' (duration: 95.695535ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:27:16.989015Z","caller":"traceutil/trace.go:171","msg":"trace[1349040108] transaction","detail":"{read_only:false; response_revision:5345; number_of_response:1; }","duration":"116.74871ms","start":"2026-10-02T09:27:16.872248Z","end":"2026-10-02T09:27:16.988997Z","steps":["trace[1349040108] 'process raft request' (duration: 116.629947ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:27:16.989004Z","caller":"traceutil/trace.go:171","msg":"trace[142913642] transaction","detail":"{read_only:false; response_revision:5346; number_of_response:1; }","duration":"116.708569ms","start":"2026-10-02T09:27:16.87227Z","end":"2026-10-02T09:27:16.988979Z","steps":["trace[142913642] 'process raft request' (duration: 116.648198ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:27:16.989093Z","caller":"traceutil/trace.go:171","msg":"trace[2121755102] transaction","detail":"{read_only:false; response_revision:5344; number_of_response:1; }","duration":"116.937955ms","start":"2026-10-02T09:27:16.872121Z","end":"2026-10-02T09:27:16.989059Z","steps":["trace[2121755102] 'process raft request' (duration: 116.628037ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:27:36.581423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.819545ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T09:27:36.581631Z","caller":"traceutil/trace.go:171","msg":"trace[993164917] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5707; }","duration":"180.054531ms","start":"2026-10-02T09:27:36.401543Z","end":"2026-10-02T09:27:36.581598Z","steps":["trace[993164917] 'range keys from in-memory index tree' (duration: 179.789604ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:27:36.581719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.557722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-53916d52-59cf-4c68-8e9e-01a14e22694b\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-10-02T09:27:36.581803Z","caller":"traceutil/trace.go:171","msg":"trace[1372955633] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-53916d52-59cf-4c68-8e9e-01a14e22694b; range_end:; response_count:1; response_revision:5707; }","duration":"128.711166ms","start":"2026-10-02T09:27:36.45307Z","end":"2026-10-02T09:27:36.581781Z","steps":["trace[1372955633] 'range keys from in-memory index tree' (duration: 128.275004ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:27:49.792035Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2961} {"level":"info","ts":"2026-10-02T09:27:49.866353Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2961,"took":"72.742349ms","hash":1979946188,"current-db-size-bytes":55267328,"current-db-size":"55 MB","current-db-size-in-use-bytes":31911936,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-02T09:27:49.866415Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1979946188,"revision":2961,"compact-revision":-1} {"level":"info","ts":"2026-10-02T09:32:49.799721Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5868} {"level":"info","ts":"2026-10-02T09:32:49.891715Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5868,"took":"89.879175ms","hash":2849620845,"current-db-size-bytes":55267328,"current-db-size":"55 MB","current-db-size-in-use-bytes":26120192,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-02T09:32:49.891794Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2849620845,"revision":5868,"compact-revision":2961} {"level":"info","ts":"2026-10-02T09:34:05.486455Z","caller":"traceutil/trace.go:171","msg":"trace[1721966137] transaction","detail":"{read_only:false; response_revision:8646; number_of_response:1; }","duration":"255.113187ms","start":"2026-10-02T09:34:05.231308Z","end":"2026-10-02T09:34:05.486421Z","steps":["trace[1721966137] 'process raft request' (duration: 254.931552ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:34:05.498377Z","caller":"traceutil/trace.go:171","msg":"trace[699905645] linearizableReadLoop","detail":"{readStateIndex:8957; appliedIndex:8955; }","duration":"195.162549ms","start":"2026-10-02T09:34:05.303196Z","end":"2026-10-02T09:34:05.498359Z","steps":["trace[699905645] 'read index received' (duration: 183.024275ms)","trace[699905645] 'applied index is now lower than readState.Index' (duration: 12.137054ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:34:05.498484Z","caller":"traceutil/trace.go:171","msg":"trace[63435018] transaction","detail":"{read_only:false; response_revision:8647; number_of_response:1; }","duration":"252.786275ms","start":"2026-10-02T09:34:05.24567Z","end":"2026-10-02T09:34:05.498457Z","steps":["trace[63435018] 'process raft request' (duration: 252.581639ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:34:05.498582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.375425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-10-02T09:34:05.4986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.198074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-02T09:34:05.498616Z","caller":"traceutil/trace.go:171","msg":"trace[1172363192] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8647; }","duration":"195.503529ms","start":"2026-10-02T09:34:05.303102Z","end":"2026-10-02T09:34:05.498606Z","steps":["trace[1172363192] 'agreement among raft nodes before linearized reading' (duration: 195.345114ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:34:05.498642Z","caller":"traceutil/trace.go:171","msg":"trace[1456518946] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8647; }","duration":"182.304187ms","start":"2026-10-02T09:34:05.316325Z","end":"2026-10-02T09:34:05.498629Z","steps":["trace[1456518946] 'agreement among raft nodes before linearized reading' (duration: 182.181454ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:34:05.498757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.090516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-02T09:34:05.498779Z","caller":"traceutil/trace.go:171","msg":"trace[165929483] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8647; }","duration":"178.140116ms","start":"2026-10-02T09:34:05.320632Z","end":"2026-10-02T09:34:05.498772Z","steps":["trace[165929483] 'agreement among raft nodes before linearized reading' (duration: 178.068104ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:34:06.181404Z","caller":"traceutil/trace.go:171","msg":"trace[217265691] linearizableReadLoop","detail":"{readStateIndex:8962; appliedIndex:8961; }","duration":"243.998701ms","start":"2026-10-02T09:34:05.937368Z","end":"2026-10-02T09:34:06.181367Z","steps":["trace[217265691] 'read index received' (duration: 243.619901ms)","trace[217265691] 'applied index is now lower than readState.Index' (duration: 376.93µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:34:06.181473Z","caller":"traceutil/trace.go:171","msg":"trace[229583361] transaction","detail":"{read_only:false; response_revision:8652; number_of_response:1; }","duration":"273.040505ms","start":"2026-10-02T09:34:05.908384Z","end":"2026-10-02T09:34:06.181424Z","steps":["trace[229583361] 'process raft request' (duration: 272.695886ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:34:06.181648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.260888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-02T09:34:06.181695Z","caller":"traceutil/trace.go:171","msg":"trace[1880338408] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:8652; }","duration":"244.396641ms","start":"2026-10-02T09:34:05.937286Z","end":"2026-10-02T09:34:06.181682Z","steps":["trace[1880338408] 'agreement among raft nodes before linearized reading' (duration: 244.224847ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:34:46.105894Z","caller":"traceutil/trace.go:171","msg":"trace[164068440] transaction","detail":"{read_only:false; response_revision:8919; number_of_response:1; }","duration":"100.257551ms","start":"2026-10-02T09:34:46.005606Z","end":"2026-10-02T09:34:46.105863Z","steps":["trace[164068440] 'process raft request' (duration: 54.45053ms)","trace[164068440] 'compare' (duration: 45.703988ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:34:46.162587Z","caller":"traceutil/trace.go:171","msg":"trace[145261508] transaction","detail":"{read_only:false; response_revision:8922; number_of_response:1; }","duration":"156.306765ms","start":"2026-10-02T09:34:46.006255Z","end":"2026-10-02T09:34:46.162562Z","steps":["trace[145261508] 'process raft request' (duration: 156.254753ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:34:46.163072Z","caller":"traceutil/trace.go:171","msg":"trace[1732336818] transaction","detail":"{read_only:false; response_revision:8920; number_of_response:1; }","duration":"157.373813ms","start":"2026-10-02T09:34:46.005687Z","end":"2026-10-02T09:34:46.163061Z","steps":["trace[1732336818] 'process raft request' (duration: 156.595812ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:34:46.163298Z","caller":"traceutil/trace.go:171","msg":"trace[341992112] transaction","detail":"{read_only:false; response_revision:8921; number_of_response:1; }","duration":"157.591658ms","start":"2026-10-02T09:34:46.005692Z","end":"2026-10-02T09:34:46.163284Z","steps":["trace[341992112] 'process raft request' (duration: 156.761947ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:34:48.528051Z","caller":"traceutil/trace.go:171","msg":"trace[1652704853] linearizableReadLoop","detail":"{readStateIndex:9261; appliedIndex:9260; }","duration":"258.135328ms","start":"2026-10-02T09:34:48.269889Z","end":"2026-10-02T09:34:48.528024Z","steps":["trace[1652704853] 'read index received' (duration: 168.987423ms)","trace[1652704853] 'applied index is now lower than readState.Index' (duration: 89.146835ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:34:48.528279Z","caller":"traceutil/trace.go:171","msg":"trace[832616364] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8940; }","duration":"259.423382ms","start":"2026-10-02T09:34:48.268843Z","end":"2026-10-02T09:34:48.528266Z","steps":["trace[832616364] 'process raft request' (duration: 170.01765ms)","trace[832616364] 'compare' (duration: 88.799846ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T09:34:48.52858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.672552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-qxbrd\" ","response":"range_response_count:1 size:4664"} {"level":"info","ts":"2026-10-02T09:34:48.528619Z","caller":"traceutil/trace.go:171","msg":"trace[1719755596] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-qxbrd; range_end:; response_count:1; response_revision:8940; }","duration":"258.739014ms","start":"2026-10-02T09:34:48.26987Z","end":"2026-10-02T09:34:48.528609Z","steps":["trace[1719755596] 'agreement among raft nodes before linearized reading' (duration: 258.602501ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:34:48.564687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.599039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-qxbrd\" ","response":"range_response_count:1 size:2801"} {"level":"info","ts":"2026-10-02T09:34:48.56477Z","caller":"traceutil/trace.go:171","msg":"trace[301607798] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-qxbrd; range_end:; response_count:1; response_revision:8940; }","duration":"294.702232ms","start":"2026-10-02T09:34:48.270046Z","end":"2026-10-02T09:34:48.564748Z","steps":["trace[301607798] 'agreement among raft nodes before linearized reading' (duration: 294.449745ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:35:18.608005Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002582.wal"} {"level":"info","ts":"2026-10-02T09:35:55.911803Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a0a01fc7fbf6be45","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-02T09:35:55.918092Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-02T09:35:55.918185Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"warn","ts":"2026-10-02T09:36:13.005752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.779731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T09:36:13.005887Z","caller":"traceutil/trace.go:171","msg":"trace[626111543] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9764; }","duration":"122.974037ms","start":"2026-10-02T09:36:12.882884Z","end":"2026-10-02T09:36:13.005858Z","steps":["trace[626111543] 'range keys from in-memory index tree' (duration: 122.679389ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:37:49.811774Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8020} {"level":"info","ts":"2026-10-02T09:37:49.884773Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8020,"took":"70.371065ms","hash":149034737,"current-db-size-bytes":55267328,"current-db-size":"55 MB","current-db-size-in-use-bytes":27656192,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-02T09:37:49.88491Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":149034737,"revision":8020,"compact-revision":5868} {"level":"info","ts":"2026-10-02T09:42:49.819068Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10442} {"level":"info","ts":"2026-10-02T09:42:49.908956Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10442,"took":"87.626234ms","hash":1175510312,"current-db-size-bytes":55267328,"current-db-size":"55 MB","current-db-size-in-use-bytes":27566080,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-02T09:42:49.909024Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1175510312,"revision":10442,"compact-revision":8020} {"level":"warn","ts":"2026-10-02T09:46:03.8376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.195876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-10-02T09:46:03.837709Z","caller":"traceutil/trace.go:171","msg":"trace[1677413910] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:14092; }","duration":"140.32804ms","start":"2026-10-02T09:46:03.697362Z","end":"2026-10-02T09:46:03.83769Z","steps":["trace[1677413910] 'range keys from in-memory index tree' (duration: 139.94546ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:46:06.896368Z","caller":"traceutil/trace.go:171","msg":"trace[791774320] transaction","detail":"{read_only:false; response_revision:14105; number_of_response:1; }","duration":"175.614124ms","start":"2026-10-02T09:46:06.720722Z","end":"2026-10-02T09:46:06.896337Z","steps":["trace[791774320] 'process raft request' (duration: 175.271835ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:46:27.776399Z","caller":"traceutil/trace.go:171","msg":"trace[1946171980] linearizableReadLoop","detail":"{readStateIndex:14706; appliedIndex:14705; }","duration":"219.542381ms","start":"2026-10-02T09:46:27.556837Z","end":"2026-10-02T09:46:27.77638Z","steps":["trace[1946171980] 'read index received' (duration: 219.394407ms)","trace[1946171980] 'applied index is now lower than readState.Index' (duration: 147.054µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:46:27.776487Z","caller":"traceutil/trace.go:171","msg":"trace[354978984] transaction","detail":"{read_only:false; response_revision:14214; number_of_response:1; }","duration":"244.179922ms","start":"2026-10-02T09:46:27.5323Z","end":"2026-10-02T09:46:27.77648Z","steps":["trace[354978984] 'process raft request' (duration: 243.926055ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:46:27.776649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.807939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-02T09:46:27.776672Z","caller":"traceutil/trace.go:171","msg":"trace[2045377005] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14214; }","duration":"219.879091ms","start":"2026-10-02T09:46:27.556787Z","end":"2026-10-02T09:46:27.776667Z","steps":["trace[2045377005] 'agreement among raft nodes before linearized reading' (duration: 219.785019ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:46:27.77773Z","caller":"traceutil/trace.go:171","msg":"trace[1147987361] transaction","detail":"{read_only:false; response_revision:14215; number_of_response:1; }","duration":"192.125447ms","start":"2026-10-02T09:46:27.585595Z","end":"2026-10-02T09:46:27.77772Z","steps":["trace[1147987361] 'process raft request' (duration: 191.773447ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:46:27.777817Z","caller":"traceutil/trace.go:171","msg":"trace[1089257627] transaction","detail":"{read_only:false; response_revision:14216; number_of_response:1; }","duration":"102.281479ms","start":"2026-10-02T09:46:27.67553Z","end":"2026-10-02T09:46:27.777811Z","steps":["trace[1089257627] 'process raft request' (duration: 101.896829ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:46:44.867509Z","caller":"traceutil/trace.go:171","msg":"trace[604864323] transaction","detail":"{read_only:false; response_revision:14321; number_of_response:1; }","duration":"194.724543ms","start":"2026-10-02T09:46:44.672745Z","end":"2026-10-02T09:46:44.86747Z","steps":["trace[604864323] 'process raft request' (duration: 194.377964ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:46:55.253677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.597455ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-02T09:46:55.253794Z","caller":"traceutil/trace.go:171","msg":"trace[1728856538] linearizableReadLoop","detail":"{readStateIndex:14995; appliedIndex:14994; }","duration":"256.908497ms","start":"2026-10-02T09:46:54.996867Z","end":"2026-10-02T09:46:55.253776Z","steps":["trace[1728856538] 'read index received' (duration: 98.098636ms)","trace[1728856538] 'applied index is now lower than readState.Index' (duration: 158.808441ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:46:55.253881Z","caller":"traceutil/trace.go:171","msg":"trace[1925094117] transaction","detail":"{read_only:false; response_revision:14494; number_of_response:1; }","duration":"280.007245ms","start":"2026-10-02T09:46:54.973863Z","end":"2026-10-02T09:46:55.25387Z","steps":["trace[1925094117] 'process raft request' (duration: 121.094051ms)","trace[1925094117] 'compare' (duration: 158.450562ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T09:46:55.254115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.221985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-10-02T09:46:55.254178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.29766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-10-02T09:46:55.254231Z","caller":"traceutil/trace.go:171","msg":"trace[470699827] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:14494; }","duration":"257.39835ms","start":"2026-10-02T09:46:54.996804Z","end":"2026-10-02T09:46:55.254202Z","steps":["trace[470699827] 'agreement among raft nodes before linearized reading' (duration: 257.182384ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:46:55.254244Z","caller":"traceutil/trace.go:171","msg":"trace[407019296] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:14494; }","duration":"151.414753ms","start":"2026-10-02T09:46:55.102813Z","end":"2026-10-02T09:46:55.254228Z","steps":["trace[407019296] 'agreement among raft nodes before linearized reading' (duration: 151.156556ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:46:55.49468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.970029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0f86a2fe-3def-4f55-ab89-4c8d8d748c80\" ","response":"range_response_count:1 size:3606"} {"level":"info","ts":"2026-10-02T09:46:55.494766Z","caller":"traceutil/trace.go:171","msg":"trace[841383747] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0f86a2fe-3def-4f55-ab89-4c8d8d748c80; range_end:; response_count:1; response_revision:14494; }","duration":"127.101053ms","start":"2026-10-02T09:46:55.367644Z","end":"2026-10-02T09:46:55.494745Z","steps":["trace[841383747] 'range keys from in-memory index tree' (duration: 126.746603ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:46:55.494764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.775511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"info","ts":"2026-10-02T09:46:55.49484Z","caller":"traceutil/trace.go:171","msg":"trace[967879015] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:14494; }","duration":"136.920215ms","start":"2026-10-02T09:46:55.357898Z","end":"2026-10-02T09:46:55.494818Z","steps":["trace[967879015] 'range keys from in-memory index tree' (duration: 136.581625ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:47:49.831687Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12743} {"level":"info","ts":"2026-10-02T09:47:49.92546Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12743,"took":"91.211331ms","hash":3076869590,"current-db-size-bytes":55267328,"current-db-size":"55 MB","current-db-size-in-use-bytes":31088640,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-02T09:47:49.925535Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3076869590,"revision":12743,"compact-revision":10442} {"level":"warn","ts":"2026-10-02T09:48:37.581885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.291154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-10-02T09:48:37.581967Z","caller":"traceutil/trace.go:171","msg":"trace[955133256] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15601; }","duration":"177.397618ms","start":"2026-10-02T09:48:37.404552Z","end":"2026-10-02T09:48:37.58195Z","steps":["trace[955133256] 'range keys from in-memory index tree' (duration: 177.140451ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:52:49.843047Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15261} {"level":"info","ts":"2026-10-02T09:52:49.943918Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15261,"took":"97.435774ms","hash":2192728710,"current-db-size-bytes":55267328,"current-db-size":"55 MB","current-db-size-in-use-bytes":30932992,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-02T09:52:49.944014Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2192728710,"revision":15261,"compact-revision":12743} {"level":"info","ts":"2026-10-02T09:54:12.71167Z","caller":"traceutil/trace.go:171","msg":"trace[1833779754] transaction","detail":"{read_only:false; response_revision:18089; number_of_response:1; }","duration":"106.345952ms","start":"2026-10-02T09:54:12.605299Z","end":"2026-10-02T09:54:12.711645Z","steps":["trace[1833779754] 'process raft request' (duration: 106.205798ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:54:18.232916Z","caller":"traceutil/trace.go:171","msg":"trace[292423735] linearizableReadLoop","detail":"{readStateIndex:18750; appliedIndex:18749; }","duration":"108.890681ms","start":"2026-10-02T09:54:18.123998Z","end":"2026-10-02T09:54:18.232888Z","steps":["trace[292423735] 'read index received' (duration: 108.676655ms)","trace[292423735] 'applied index is now lower than readState.Index' (duration: 212.956µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:54:18.232985Z","caller":"traceutil/trace.go:171","msg":"trace[1682544100] transaction","detail":"{read_only:false; response_revision:18131; number_of_response:1; }","duration":"130.875567ms","start":"2026-10-02T09:54:18.10208Z","end":"2026-10-02T09:54:18.232956Z","steps":["trace[1682544100] 'process raft request' (duration: 130.635941ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:54:18.233202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.181448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-p66n9\" ","response":"range_response_count:1 size:9796"} {"level":"info","ts":"2026-10-02T09:54:18.233285Z","caller":"traceutil/trace.go:171","msg":"trace[146271596] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-p66n9; range_end:; response_count:1; response_revision:18131; }","duration":"109.312182ms","start":"2026-10-02T09:54:18.123954Z","end":"2026-10-02T09:54:18.233266Z","steps":["trace[146271596] 'agreement among raft nodes before linearized reading' (duration: 109.099166ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:54:18.661094Z","caller":"traceutil/trace.go:171","msg":"trace[577379445] transaction","detail":"{read_only:false; response_revision:18136; number_of_response:1; }","duration":"196.179444ms","start":"2026-10-02T09:54:18.464896Z","end":"2026-10-02T09:54:18.661076Z","steps":["trace[577379445] 'process raft request' (duration: 196.03419ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:54:19.263758Z","caller":"traceutil/trace.go:171","msg":"trace[187110322] linearizableReadLoop","detail":"{readStateIndex:18757; appliedIndex:18756; }","duration":"164.837846ms","start":"2026-10-02T09:54:19.098893Z","end":"2026-10-02T09:54:19.263731Z","steps":["trace[187110322] 'read index received' (duration: 164.60888ms)","trace[187110322] 'applied index is now lower than readState.Index' (duration: 228.026µs)"],"step_count":2} {"level":"warn","ts":"2026-10-02T09:54:19.263947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.039201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-02T09:54:19.263988Z","caller":"traceutil/trace.go:171","msg":"trace[1974953696] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:18137; }","duration":"165.148865ms","start":"2026-10-02T09:54:19.098826Z","end":"2026-10-02T09:54:19.263975Z","steps":["trace[1974953696] 'agreement among raft nodes before linearized reading' (duration: 165.051632ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:54:19.264445Z","caller":"traceutil/trace.go:171","msg":"trace[1819996864] transaction","detail":"{read_only:false; response_revision:18137; number_of_response:1; }","duration":"530.13805ms","start":"2026-10-02T09:54:18.733694Z","end":"2026-10-02T09:54:19.263832Z","steps":["trace[1819996864] 'process raft request' (duration: 529.865592ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:54:19.264638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:54:18.73367Z","time spent":"530.872869ms","remote":"127.0.0.1:47796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T09:56:27.091699Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a0a01fc7fbf6be45","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-02T09:56:27.100651Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-02T09:56:27.100786Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-02T09:57:49.850306Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17490} {"level":"info","ts":"2026-10-02T09:57:49.953106Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17490,"took":"99.349097ms","hash":4196210207,"current-db-size-bytes":55267328,"current-db-size":"55 MB","current-db-size-in-use-bytes":34304000,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-02T09:57:49.953259Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4196210207,"revision":17490,"compact-revision":15261} {"level":"warn","ts":"2026-10-02T10:00:04.580623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.461707ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T10:00:04.580959Z","caller":"traceutil/trace.go:171","msg":"trace[994923620] linearizableReadLoop","detail":"{readStateIndex:21565; appliedIndex:21561; }","duration":"201.723502ms","start":"2026-10-02T10:00:04.379217Z","end":"2026-10-02T10:00:04.58094Z","steps":["trace[994923620] 'read index received' (duration: 42.844204ms)","trace[994923620] 'applied index is now lower than readState.Index' (duration: 158.878418ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:00:04.581054Z","caller":"traceutil/trace.go:171","msg":"trace[1610283312] transaction","detail":"{read_only:false; response_revision:20855; number_of_response:1; }","duration":"235.941594ms","start":"2026-10-02T10:00:04.3451Z","end":"2026-10-02T10:00:04.581041Z","steps":["trace[1610283312] 'process raft request' (duration: 76.953083ms)","trace[1610283312] 'compare' (duration: 158.340484ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:00:04.581142Z","caller":"traceutil/trace.go:171","msg":"trace[1539966527] transaction","detail":"{read_only:false; response_revision:20858; number_of_response:1; }","duration":"235.345639ms","start":"2026-10-02T10:00:04.345777Z","end":"2026-10-02T10:00:04.581123Z","steps":["trace[1539966527] 'process raft request' (duration: 235.127383ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:00:04.581252Z","caller":"traceutil/trace.go:171","msg":"trace[2028746243] transaction","detail":"{read_only:false; response_revision:20856; number_of_response:1; }","duration":"236.089018ms","start":"2026-10-02T10:00:04.345131Z","end":"2026-10-02T10:00:04.58122Z","steps":["trace[2028746243] 'process raft request' (duration: 235.650176ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:00:04.581337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.123602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T10:00:04.58128Z","caller":"traceutil/trace.go:171","msg":"trace[57765044] transaction","detail":"{read_only:false; response_revision:20857; number_of_response:1; }","duration":"235.466042ms","start":"2026-10-02T10:00:04.345767Z","end":"2026-10-02T10:00:04.581233Z","steps":["trace[57765044] 'process raft request' (duration: 235.100942ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:00:04.581376Z","caller":"traceutil/trace.go:171","msg":"trace[1974316188] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20858; }","duration":"202.240595ms","start":"2026-10-02T10:00:04.379123Z","end":"2026-10-02T10:00:04.581364Z","steps":["trace[1974316188] 'agreement among raft nodes before linearized reading' (duration: 202.139632ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:00:04.581604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.255438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-02T10:00:04.583028Z","caller":"traceutil/trace.go:171","msg":"trace[1180378294] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:20858; }","duration":"123.464123ms","start":"2026-10-02T10:00:04.458236Z","end":"2026-10-02T10:00:04.5817Z","steps":["trace[1180378294] 'agreement among raft nodes before linearized reading' (duration: 123.034812ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:00:04.981911Z","caller":"traceutil/trace.go:171","msg":"trace[2053896688] linearizableReadLoop","detail":"{readStateIndex:21566; appliedIndex:21565; }","duration":"286.669648ms","start":"2026-10-02T10:00:04.695214Z","end":"2026-10-02T10:00:04.981884Z","steps":["trace[2053896688] 'read index received' (duration: 286.490683ms)","trace[2053896688] 'applied index is now lower than readState.Index' (duration: 177.875µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:00:04.98202Z","caller":"traceutil/trace.go:171","msg":"trace[1727394967] transaction","detail":"{read_only:false; response_revision:20859; number_of_response:1; }","duration":"390.710003ms","start":"2026-10-02T10:00:04.591288Z","end":"2026-10-02T10:00:04.981998Z","steps":["trace[1727394967] 'process raft request' (duration: 390.126777ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:00:04.982167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:00:04.591264Z","time spent":"390.803416ms","remote":"127.0.0.1:47796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T10:00:04.983618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.391293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-10-02T10:00:04.983725Z","caller":"traceutil/trace.go:171","msg":"trace[781253312] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:20859; }","duration":"288.672491ms","start":"2026-10-02T10:00:04.695034Z","end":"2026-10-02T10:00:04.983707Z","steps":["trace[781253312] 'agreement among raft nodes before linearized reading' (duration: 287.174761ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:00:05.110342Z","caller":"traceutil/trace.go:171","msg":"trace[756112668] linearizableReadLoop","detail":"{readStateIndex:21567; appliedIndex:21566; }","duration":"128.331214ms","start":"2026-10-02T10:00:04.981988Z","end":"2026-10-02T10:00:05.110319Z","steps":["trace[756112668] 'read index received' (duration: 111.304649ms)","trace[756112668] 'applied index is now lower than readState.Index' (duration: 17.025895ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T10:00:05.110476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.059065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T10:00:05.110508Z","caller":"traceutil/trace.go:171","msg":"trace[1158410434] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:20861; }","duration":"312.152288ms","start":"2026-10-02T10:00:04.798345Z","end":"2026-10-02T10:00:05.110498Z","steps":["trace[1158410434] 'agreement among raft nodes before linearized reading' (duration: 312.061835ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:00:05.11054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:00:04.798324Z","time spent":"312.206829ms","remote":"127.0.0.1:56236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":30,"request content":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true "} {"level":"warn","ts":"2026-10-02T10:00:05.110611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.022112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-02T10:00:05.110688Z","caller":"traceutil/trace.go:171","msg":"trace[1613907723] transaction","detail":"{read_only:false; response_revision:20860; number_of_response:1; }","duration":"413.393828ms","start":"2026-10-02T10:00:04.697278Z","end":"2026-10-02T10:00:05.110671Z","steps":["trace[1613907723] 'process raft request' (duration: 396.002824ms)","trace[1613907723] 'compare' (duration: 16.905181ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:00:05.110742Z","caller":"traceutil/trace.go:171","msg":"trace[103943259] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:20861; }","duration":"138.198896ms","start":"2026-10-02T10:00:04.972519Z","end":"2026-10-02T10:00:05.110718Z","steps":["trace[103943259] 'agreement among raft nodes before linearized reading' (duration: 137.95341ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:00:05.110794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:00:04.69726Z","time spent":"413.464221ms","remote":"127.0.0.1:47796","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-02T10:00:05.111095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.999884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T10:00:05.111202Z","caller":"traceutil/trace.go:171","msg":"trace[516406125] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:20862; }","duration":"176.136608ms","start":"2026-10-02T10:00:04.935051Z","end":"2026-10-02T10:00:05.111187Z","steps":["trace[516406125] 'agreement among raft nodes before linearized reading' (duration: 175.730808ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:00:05.111567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.727294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T10:00:05.111622Z","caller":"traceutil/trace.go:171","msg":"trace[653247520] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20863; }","duration":"227.781516ms","start":"2026-10-02T10:00:04.883827Z","end":"2026-10-02T10:00:05.111608Z","steps":["trace[653247520] 'agreement among raft nodes before linearized reading' (duration: 226.943144ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:02:49.861475Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19893} {"level":"info","ts":"2026-10-02T10:02:50.019204Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19893,"took":"154.153212ms","hash":4290775579,"current-db-size-bytes":55267328,"current-db-size":"55 MB","current-db-size-in-use-bytes":30908416,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-02T10:02:50.019267Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4290775579,"revision":19893,"compact-revision":17490} {"level":"warn","ts":"2026-10-02T10:04:12.13097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.833034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" ","response":"range_response_count:1 size:626"} {"level":"info","ts":"2026-10-02T10:04:12.131057Z","caller":"traceutil/trace.go:171","msg":"trace[543045750] range","detail":"{range_begin:/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi; range_end:; response_count:1; response_revision:23404; }","duration":"180.966057ms","start":"2026-10-02T10:04:11.950069Z","end":"2026-10-02T10:04:12.131035Z","steps":["trace[543045750] 'range keys from in-memory index tree' (duration: 180.652979ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:04:12.132421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.1521ms","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-02T10:04:12.132534Z","caller":"traceutil/trace.go:171","msg":"trace[394396900] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:23404; }","duration":"248.286894ms","start":"2026-10-02T10:04:11.884208Z","end":"2026-10-02T10:04:12.132495Z","steps":["trace[394396900] 'range keys from in-memory index tree' (duration: 247.04068ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:05:32.645878Z","caller":"traceutil/trace.go:171","msg":"trace[739259163] transaction","detail":"{read_only:false; response_revision:24345; number_of_response:1; }","duration":"150.352771ms","start":"2026-10-02T10:05:32.495492Z","end":"2026-10-02T10:05:32.645845Z","steps":["trace[739259163] 'process raft request' (duration: 147.563597ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:05:32.64612Z","caller":"traceutil/trace.go:171","msg":"trace[172994311] transaction","detail":"{read_only:false; response_revision:24346; number_of_response:1; }","duration":"150.538986ms","start":"2026-10-02T10:05:32.495562Z","end":"2026-10-02T10:05:32.646101Z","steps":["trace[172994311] 'process raft request' (duration: 150.168506ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:05:32.646389Z","caller":"traceutil/trace.go:171","msg":"trace[1891045725] linearizableReadLoop","detail":"{readStateIndex:25153; appliedIndex:25149; }","duration":"146.901578ms","start":"2026-10-02T10:05:32.499457Z","end":"2026-10-02T10:05:32.646359Z","steps":["trace[1891045725] 'read index received' (duration: 85.564062ms)","trace[1891045725] 'applied index is now lower than readState.Index' (duration: 61.335506ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:05:32.646472Z","caller":"traceutil/trace.go:171","msg":"trace[1233475488] transaction","detail":"{read_only:false; response_revision:24347; number_of_response:1; }","duration":"150.806933ms","start":"2026-10-02T10:05:32.49562Z","end":"2026-10-02T10:05:32.646427Z","steps":["trace[1233475488] 'process raft request' (duration: 150.413862ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:05:32.646596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.093534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-02T10:05:32.646763Z","caller":"traceutil/trace.go:171","msg":"trace[546745422] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24347; }","duration":"147.221377ms","start":"2026-10-02T10:05:32.499415Z","end":"2026-10-02T10:05:32.646636Z","steps":["trace[546745422] 'agreement among raft nodes before linearized reading' (duration: 147.083693ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:07:29.341078Z","caller":"traceutil/trace.go:171","msg":"trace[1177478031] transaction","detail":"{read_only:false; response_revision:25461; number_of_response:1; }","duration":"297.364613ms","start":"2026-10-02T10:07:29.043687Z","end":"2026-10-02T10:07:29.341051Z","steps":["trace[1177478031] 'process raft request' (duration: 297.105816ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:07:29.356179Z","caller":"traceutil/trace.go:171","msg":"trace[457133960] transaction","detail":"{read_only:false; response_revision:25464; number_of_response:1; }","duration":"215.291923ms","start":"2026-10-02T10:07:29.140845Z","end":"2026-10-02T10:07:29.356137Z","steps":["trace[457133960] 'process raft request' (duration: 215.186451ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:07:29.356212Z","caller":"traceutil/trace.go:171","msg":"trace[992497715] transaction","detail":"{read_only:false; response_revision:25462; number_of_response:1; }","duration":"216.408952ms","start":"2026-10-02T10:07:29.139776Z","end":"2026-10-02T10:07:29.356185Z","steps":["trace[992497715] 'process raft request' (duration: 216.045294ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:07:29.356297Z","caller":"traceutil/trace.go:171","msg":"trace[560864697] transaction","detail":"{read_only:false; response_revision:25463; number_of_response:1; }","duration":"216.027471ms","start":"2026-10-02T10:07:29.140231Z","end":"2026-10-02T10:07:29.356258Z","steps":["trace[560864697] 'process raft request' (duration: 215.761785ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:07:29.773048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.696134ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T10:07:29.773143Z","caller":"traceutil/trace.go:171","msg":"trace[495268303] transaction","detail":"{read_only:false; response_revision:25465; number_of_response:1; }","duration":"410.574703ms","start":"2026-10-02T10:07:29.362553Z","end":"2026-10-02T10:07:29.773127Z","steps":["trace[495268303] 'process raft request' (duration: 158.403006ms)","trace[495268303] 'compare' (duration: 251.566811ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T10:07:29.773195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:07:29.362526Z","time spent":"410.646145ms","remote":"127.0.0.1:47796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T10:07:29.774309Z","caller":"traceutil/trace.go:171","msg":"trace[1153254284] transaction","detail":"{read_only:false; response_revision:25466; number_of_response:1; }","duration":"411.741864ms","start":"2026-10-02T10:07:29.362554Z","end":"2026-10-02T10:07:29.774296Z","steps":["trace[1153254284] 'process raft request' (duration: 411.639651ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:07:29.774398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:07:29.362526Z","time spent":"411.837696ms","remote":"127.0.0.1:47796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T10:07:29.774605Z","caller":"traceutil/trace.go:171","msg":"trace[341494014] transaction","detail":"{read_only:false; response_revision:25467; number_of_response:1; }","duration":"411.045995ms","start":"2026-10-02T10:07:29.363517Z","end":"2026-10-02T10:07:29.774563Z","steps":["trace[341494014] 'process raft request' (duration: 410.749177ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:07:29.774669Z","caller":"traceutil/trace.go:171","msg":"trace[378972838] transaction","detail":"{read_only:false; response_revision:25468; number_of_response:1; }","duration":"409.316079ms","start":"2026-10-02T10:07:29.365326Z","end":"2026-10-02T10:07:29.774643Z","steps":["trace[378972838] 'process raft request' (duration: 409.135294ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:07:29.774675Z","caller":"traceutil/trace.go:171","msg":"trace[1134104049] linearizableReadLoop","detail":"{readStateIndex:26307; appliedIndex:26303; }","duration":"200.732565ms","start":"2026-10-02T10:07:29.573908Z","end":"2026-10-02T10:07:29.774641Z","steps":["trace[1134104049] 'read index received' (duration: 200.282703ms)","trace[1134104049] 'applied index is now lower than readState.Index' (duration: 448.592µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:07:29.77468Z","caller":"traceutil/trace.go:171","msg":"trace[1238656244] transaction","detail":"{read_only:false; response_revision:25469; number_of_response:1; }","duration":"409.148384ms","start":"2026-10-02T10:07:29.365502Z","end":"2026-10-02T10:07:29.774651Z","steps":["trace[1238656244] 'process raft request' (duration: 409.068142ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:07:29.774743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:07:29.365315Z","time spent":"409.390631ms","remote":"127.0.0.1:47796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T10:07:29.774762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:07:29.363503Z","time spent":"411.176979ms","remote":"127.0.0.1:47796","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-02T10:07:29.774825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:07:29.36548Z","time spent":"409.275558ms","remote":"127.0.0.1:47796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T10:07:29.774893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.93685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T10:07:29.77495Z","caller":"traceutil/trace.go:171","msg":"trace[268734360] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:25469; }","duration":"201.036483ms","start":"2026-10-02T10:07:29.573897Z","end":"2026-10-02T10:07:29.774934Z","steps":["trace[268734360] 'agreement among raft nodes before linearized reading' (duration: 200.845498ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:07:29.779217Z","caller":"traceutil/trace.go:171","msg":"trace[2061813179] transaction","detail":"{read_only:false; response_revision:25470; number_of_response:1; }","duration":"180.789702ms","start":"2026-10-02T10:07:29.598403Z","end":"2026-10-02T10:07:29.779193Z","steps":["trace[2061813179] 'process raft request' (duration: 180.540295ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:07:29.779207Z","caller":"traceutil/trace.go:171","msg":"trace[2041609689] transaction","detail":"{read_only:false; response_revision:25471; number_of_response:1; }","duration":"110.20731ms","start":"2026-10-02T10:07:29.668969Z","end":"2026-10-02T10:07:29.779176Z","steps":["trace[2041609689] 'process raft request' (duration: 110.101707ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:07:30.050372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.382506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T10:07:30.050441Z","caller":"traceutil/trace.go:171","msg":"trace[811323778] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25471; }","duration":"167.493837ms","start":"2026-10-02T10:07:29.882932Z","end":"2026-10-02T10:07:30.050425Z","steps":["trace[811323778] 'range keys from in-memory index tree' (duration: 167.223371ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:07:35.759923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.210468ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T10:07:35.76016Z","caller":"traceutil/trace.go:171","msg":"trace[519334133] transaction","detail":"{read_only:false; response_revision:25508; number_of_response:1; }","duration":"380.164341ms","start":"2026-10-02T10:07:35.379977Z","end":"2026-10-02T10:07:35.760141Z","steps":["trace[519334133] 'process raft request' (duration: 380.060968ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:07:35.760138Z","caller":"traceutil/trace.go:171","msg":"trace[1247396030] transaction","detail":"{read_only:false; response_revision:25507; number_of_response:1; }","duration":"385.0138ms","start":"2026-10-02T10:07:35.375092Z","end":"2026-10-02T10:07:35.760106Z","steps":["trace[1247396030] 'process raft request' (duration: 171.492114ms)","trace[1247396030] 'compare' (duration: 212.802917ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T10:07:35.76023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:07:35.379956Z","time spent":"380.240924ms","remote":"127.0.0.1:47796","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-02T10:07:35.760291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:07:35.375068Z","time spent":"385.141164ms","remote":"127.0.0.1:47796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T10:07:35.760343Z","caller":"traceutil/trace.go:171","msg":"trace[918255958] transaction","detail":"{read_only:false; response_revision:25509; number_of_response:1; }","duration":"379.151555ms","start":"2026-10-02T10:07:35.38116Z","end":"2026-10-02T10:07:35.760312Z","steps":["trace[918255958] 'process raft request' (duration: 378.937979ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:07:35.760571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:07:35.381146Z","time spent":"379.272138ms","remote":"127.0.0.1:47796","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-02T10:07:36.169983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.568567ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T10:07:36.170075Z","caller":"traceutil/trace.go:171","msg":"trace[493434523] linearizableReadLoop","detail":"{readStateIndex:26349; appliedIndex:26348; }","duration":"370.644498ms","start":"2026-10-02T10:07:35.799414Z","end":"2026-10-02T10:07:36.170058Z","steps":["trace[493434523] 'read index received' (duration: 267.904697ms)","trace[493434523] 'applied index is now lower than readState.Index' (duration: 102.738441ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:07:36.170143Z","caller":"traceutil/trace.go:171","msg":"trace[1914337196] transaction","detail":"{read_only:false; response_revision:25510; number_of_response:1; }","duration":"374.495042ms","start":"2026-10-02T10:07:35.795625Z","end":"2026-10-02T10:07:36.17012Z","steps":["trace[1914337196] 'process raft request' (duration: 271.709599ms)","trace[1914337196] 'compare' (duration: 102.261829ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T10:07:36.170244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:07:35.795599Z","time spent":"374.590003ms","remote":"127.0.0.1:47796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T10:07:36.17026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.860303ms","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-02T10:07:36.170288Z","caller":"traceutil/trace.go:171","msg":"trace[1912776530] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25510; }","duration":"370.925725ms","start":"2026-10-02T10:07:35.799352Z","end":"2026-10-02T10:07:36.170278Z","steps":["trace[1912776530] 'agreement among raft nodes before linearized reading' (duration: 370.772882ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:07:36.170324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:07:35.799333Z","time spent":"370.983956ms","remote":"127.0.0.1:47796","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":"info","ts":"2026-10-02T10:07:36.172482Z","caller":"traceutil/trace.go:171","msg":"trace[2114709433] transaction","detail":"{read_only:false; response_revision:25511; number_of_response:1; }","duration":"371.07525ms","start":"2026-10-02T10:07:35.801383Z","end":"2026-10-02T10:07:36.172459Z","steps":["trace[2114709433] 'process raft request' (duration: 370.909755ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:07:36.172639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:07:35.801365Z","time spent":"371.198313ms","remote":"127.0.0.1:47796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T10:07:36.173145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.144463ms","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-02T10:07:36.173187Z","caller":"traceutil/trace.go:171","msg":"trace[514962892] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25512; }","duration":"370.214225ms","start":"2026-10-02T10:07:35.802961Z","end":"2026-10-02T10:07:36.173175Z","steps":["trace[514962892] 'agreement among raft nodes before linearized reading' (duration: 370.059221ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:07:36.173216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:07:35.802952Z","time spent":"370.258306ms","remote":"127.0.0.1:47796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-02T10:07:36.173556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.111569ms","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-02T10:07:36.173617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.521168ms","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-02T10:07:36.173682Z","caller":"traceutil/trace.go:171","msg":"trace[1537292120] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25512; }","duration":"369.60449ms","start":"2026-10-02T10:07:35.804059Z","end":"2026-10-02T10:07:36.173663Z","steps":["trace[1537292120] 'agreement among raft nodes before linearized reading' (duration: 369.419995ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:07:36.173727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:07:35.804048Z","time spent":"369.669172ms","remote":"127.0.0.1:47796","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":"info","ts":"2026-10-02T10:07:36.173839Z","caller":"traceutil/trace.go:171","msg":"trace[259485083] transaction","detail":"{read_only:false; response_revision:25512; number_of_response:1; }","duration":"137.388325ms","start":"2026-10-02T10:07:36.036435Z","end":"2026-10-02T10:07:36.173824Z","steps":["trace[259485083] 'process raft request' (duration: 135.978458ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:07:36.174004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.827699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T10:07:36.17404Z","caller":"traceutil/trace.go:171","msg":"trace[1243868410] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25512; }","duration":"290.92351ms","start":"2026-10-02T10:07:35.883106Z","end":"2026-10-02T10:07:36.17403Z","steps":["trace[1243868410] 'agreement among raft nodes before linearized reading' (duration: 290.868379ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:07:36.173984Z","caller":"traceutil/trace.go:171","msg":"trace[851780089] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25512; }","duration":"365.171951ms","start":"2026-10-02T10:07:35.808427Z","end":"2026-10-02T10:07:36.173599Z","steps":["trace[851780089] 'agreement among raft nodes before linearized reading' (duration: 365.018807ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:07:36.174216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:07:35.808398Z","time spent":"365.737456ms","remote":"127.0.0.1:47796","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":"info","ts":"2026-10-02T10:07:36.323509Z","caller":"traceutil/trace.go:171","msg":"trace[791790605] linearizableReadLoop","detail":"{readStateIndex:26352; appliedIndex:26351; }","duration":"148.690797ms","start":"2026-10-02T10:07:36.174791Z","end":"2026-10-02T10:07:36.323482Z","steps":["trace[791790605] 'read index received' (duration: 146.120538ms)","trace[791790605] 'applied index is now lower than readState.Index' (duration: 2.569169ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:07:36.32363Z","caller":"traceutil/trace.go:171","msg":"trace[999399201] transaction","detail":"{read_only:false; response_revision:25513; number_of_response:1; }","duration":"149.682144ms","start":"2026-10-02T10:07:36.173901Z","end":"2026-10-02T10:07:36.323584Z","steps":["trace[999399201] 'process raft request' (duration: 146.992082ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:07:36.323834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.017785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T10:07:36.323933Z","caller":"traceutil/trace.go:171","msg":"trace[1943671427] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25513; }","duration":"149.152399ms","start":"2026-10-02T10:07:36.174759Z","end":"2026-10-02T10:07:36.323911Z","steps":["trace[1943671427] 'agreement among raft nodes before linearized reading' (duration: 148.829731ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:07:36.608695Z","caller":"traceutil/trace.go:171","msg":"trace[175218663] transaction","detail":"{read_only:false; response_revision:25514; number_of_response:1; }","duration":"430.526954ms","start":"2026-10-02T10:07:36.17814Z","end":"2026-10-02T10:07:36.608667Z","steps":["trace[175218663] 'process raft request' (duration: 430.249987ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:07:36.60889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:07:36.178122Z","time spent":"430.651028ms","remote":"127.0.0.1:47796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T10:07:36.608906Z","caller":"traceutil/trace.go:171","msg":"trace[961290574] linearizableReadLoop","detail":"{readStateIndex:26355; appliedIndex:26352; }","duration":"285.308341ms","start":"2026-10-02T10:07:36.323581Z","end":"2026-10-02T10:07:36.60889Z","steps":["trace[961290574] 'read index received' (duration: 284.737436ms)","trace[961290574] 'applied index is now lower than readState.Index' (duration: 570.165µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:07:36.60895Z","caller":"traceutil/trace.go:171","msg":"trace[1007480631] transaction","detail":"{read_only:false; response_revision:25515; number_of_response:1; }","duration":"430.780481ms","start":"2026-10-02T10:07:36.178148Z","end":"2026-10-02T10:07:36.608929Z","steps":["trace[1007480631] 'process raft request' (duration: 430.422852ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:07:36.609019Z","caller":"traceutil/trace.go:171","msg":"trace[1979956565] transaction","detail":"{read_only:false; response_revision:25516; number_of_response:1; }","duration":"430.804712ms","start":"2026-10-02T10:07:36.178179Z","end":"2026-10-02T10:07:36.608983Z","steps":["trace[1979956565] 'process raft request' (duration: 430.611257ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:07:36.609086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.793809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-fb306e45-affd-4c76-ab9e-16f036f4c4ce\" ","response":"range_response_count:1 size:1324"} {"level":"warn","ts":"2026-10-02T10:07:36.60906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:07:36.178127Z","time spent":"430.865574ms","remote":"127.0.0.1:47796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T10:07:36.609131Z","caller":"traceutil/trace.go:171","msg":"trace[1719156494] range","detail":"{range_begin:/registry/persistentvolumes/pvc-fb306e45-affd-4c76-ab9e-16f036f4c4ce; range_end:; response_count:1; response_revision:25516; }","duration":"431.889411ms","start":"2026-10-02T10:07:36.177228Z","end":"2026-10-02T10:07:36.609117Z","steps":["trace[1719156494] 'agreement among raft nodes before linearized reading' (duration: 431.734627ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:07:36.609164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:07:36.177206Z","time spent":"431.951053ms","remote":"127.0.0.1:47714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1348,"request content":"key:\"/registry/persistentvolumes/pvc-fb306e45-affd-4c76-ab9e-16f036f4c4ce\" "} {"level":"warn","ts":"2026-10-02T10:07:36.609181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:07:36.17816Z","time spent":"430.923195ms","remote":"127.0.0.1:47796","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-02T10:07:36.624529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.461553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-02T10:07:36.62453Z","caller":"traceutil/trace.go:171","msg":"trace[949523399] transaction","detail":"{read_only:false; response_revision:25517; number_of_response:1; }","duration":"234.30718ms","start":"2026-10-02T10:07:36.390186Z","end":"2026-10-02T10:07:36.624493Z","steps":["trace[949523399] 'process raft request' (duration: 234.047573ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:07:36.6246Z","caller":"traceutil/trace.go:171","msg":"trace[446477068] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:25518; }","duration":"144.589356ms","start":"2026-10-02T10:07:36.479993Z","end":"2026-10-02T10:07:36.624583Z","steps":["trace[446477068] 'agreement among raft nodes before linearized reading' (duration: 144.439072ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:07:36.624831Z","caller":"traceutil/trace.go:171","msg":"trace[89481022] transaction","detail":"{read_only:false; response_revision:25518; number_of_response:1; }","duration":"209.738695ms","start":"2026-10-02T10:07:36.415081Z","end":"2026-10-02T10:07:36.62482Z","steps":["trace[89481022] 'process raft request' (duration: 209.295983ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:07:37.00799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.842093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T10:07:37.008073Z","caller":"traceutil/trace.go:171","msg":"trace[825073636] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25518; }","duration":"123.978017ms","start":"2026-10-02T10:07:36.884076Z","end":"2026-10-02T10:07:37.008054Z","steps":["trace[825073636] 'range keys from in-memory index tree' (duration: 123.69976ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:07:37.008446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.995665ms","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-02T10:07:37.008521Z","caller":"traceutil/trace.go:171","msg":"trace[1199443524] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25518; }","duration":"191.119668ms","start":"2026-10-02T10:07:36.817381Z","end":"2026-10-02T10:07:37.0085Z","steps":["trace[1199443524] 'range keys from in-memory index tree' (duration: 190.81664ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:07:37.563834Z","caller":"traceutil/trace.go:171","msg":"trace[1234657317] transaction","detail":"{read_only:false; response_revision:25520; number_of_response:1; }","duration":"130.06805ms","start":"2026-10-02T10:07:37.43365Z","end":"2026-10-02T10:07:37.563718Z","steps":["trace[1234657317] 'process raft request' (duration: 129.871725ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:07:37.845901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.347604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T10:07:37.845981Z","caller":"traceutil/trace.go:171","msg":"trace[1084037431] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:25520; }","duration":"125.498358ms","start":"2026-10-02T10:07:37.720463Z","end":"2026-10-02T10:07:37.845961Z","steps":["trace[1084037431] 'count revisions from in-memory index tree' (duration: 125.206089ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:07:38.082265Z","caller":"traceutil/trace.go:171","msg":"trace[796100657] transaction","detail":"{read_only:false; response_revision:25521; number_of_response:1; }","duration":"316.308189ms","start":"2026-10-02T10:07:37.765929Z","end":"2026-10-02T10:07:38.082238Z","steps":["trace[796100657] 'process raft request' (duration: 316.153855ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:07:38.08242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:07:37.765899Z","time spent":"316.44403ms","remote":"127.0.0.1:47796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T10:07:38.100512Z","caller":"traceutil/trace.go:171","msg":"trace[135269692] transaction","detail":"{read_only:false; response_revision:25522; number_of_response:1; }","duration":"334.518734ms","start":"2026-10-02T10:07:37.765969Z","end":"2026-10-02T10:07:38.100488Z","steps":["trace[135269692] 'process raft request' (duration: 334.328649ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:07:38.100674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:07:37.765934Z","time spent":"334.654217ms","remote":"127.0.0.1:47796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T10:07:38.100713Z","caller":"traceutil/trace.go:171","msg":"trace[741261970] linearizableReadLoop","detail":"{readStateIndex:26362; appliedIndex:26360; }","duration":"215.758276ms","start":"2026-10-02T10:07:37.884929Z","end":"2026-10-02T10:07:38.100688Z","steps":["trace[741261970] 'read index received' (duration: 197.307564ms)","trace[741261970] 'applied index is now lower than readState.Index' (duration: 18.448822ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T10:07:38.100846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.9087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T10:07:38.100918Z","caller":"traceutil/trace.go:171","msg":"trace[1164022048] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25523; }","duration":"216.069534ms","start":"2026-10-02T10:07:37.884835Z","end":"2026-10-02T10:07:38.100905Z","steps":["trace[1164022048] 'agreement among raft nodes before linearized reading' (duration: 215.9338ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:07:38.101166Z","caller":"traceutil/trace.go:171","msg":"trace[317304279] transaction","detail":"{read_only:false; response_revision:25523; number_of_response:1; }","duration":"334.767491ms","start":"2026-10-02T10:07:37.766365Z","end":"2026-10-02T10:07:38.101132Z","steps":["trace[317304279] 'process raft request' (duration: 334.079543ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:07:38.101349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:07:37.766351Z","time spent":"334.894644ms","remote":"127.0.0.1:47796","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-02T10:07:38.484911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.448398ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T10:07:38.485338Z","caller":"traceutil/trace.go:171","msg":"trace[1923247601] transaction","detail":"{read_only:false; response_revision:25524; number_of_response:1; }","duration":"310.062252ms","start":"2026-10-02T10:07:38.175228Z","end":"2026-10-02T10:07:38.48529Z","steps":["trace[1923247601] 'process raft request' (duration: 109.125421ms)","trace[1923247601] 'compare' (duration: 200.069328ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T10:07:38.485556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:07:38.1752Z","time spent":"310.274937ms","remote":"127.0.0.1:47796","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-02T10:07:38.485559Z","caller":"traceutil/trace.go:171","msg":"trace[1940017307] linearizableReadLoop","detail":"{readStateIndex:26364; appliedIndex:26362; }","duration":"157.711617ms","start":"2026-10-02T10:07:38.327823Z","end":"2026-10-02T10:07:38.485535Z","steps":["trace[1940017307] 'read index received' (duration: 139.956694ms)","trace[1940017307] 'applied index is now lower than readState.Index' (duration: 17.751743ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:07:38.485546Z","caller":"traceutil/trace.go:171","msg":"trace[638082380] transaction","detail":"{read_only:false; response_revision:25525; number_of_response:1; }","duration":"307.860603ms","start":"2026-10-02T10:07:38.177663Z","end":"2026-10-02T10:07:38.485523Z","steps":["trace[638082380] 'process raft request' (duration: 307.656748ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:07:38.485976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:07:38.177648Z","time spent":"308.223822ms","remote":"127.0.0.1:47796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T10:07:38.663553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.725056ms","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-02T10:07:38.663626Z","caller":"traceutil/trace.go:171","msg":"trace[1930369853] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25525; }","duration":"335.834849ms","start":"2026-10-02T10:07:38.32777Z","end":"2026-10-02T10:07:38.663604Z","steps":["trace[1930369853] 'agreement among raft nodes before linearized reading' (duration: 157.82792ms)","trace[1930369853] 'range keys from in-memory index tree' (duration: 177.855515ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T10:07:38.663661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:07:38.327745Z","time spent":"335.907781ms","remote":"127.0.0.1:47796","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-02T10:07:38.663817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.380318ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-10-02T10:07:38.663948Z","caller":"traceutil/trace.go:171","msg":"trace[986277597] linearizableReadLoop","detail":"{readStateIndex:26365; appliedIndex:26364; }","duration":"178.160334ms","start":"2026-10-02T10:07:38.485767Z","end":"2026-10-02T10:07:38.663928Z","steps":["trace[986277597] 'read index received' (duration: 353.27µs)","trace[986277597] 'applied index is now lower than readState.Index' (duration: 177.805554ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T10:07:38.66403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.694891ms","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-02T10:07:38.664065Z","caller":"traceutil/trace.go:171","msg":"trace[2102346782] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:25525; }","duration":"222.767223ms","start":"2026-10-02T10:07:38.441285Z","end":"2026-10-02T10:07:38.664052Z","steps":["trace[2102346782] 'agreement among raft nodes before linearized reading' (duration: 222.701301ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:07:38.791483Z","caller":"traceutil/trace.go:171","msg":"trace[257415645] transaction","detail":"{read_only:false; response_revision:25526; number_of_response:1; }","duration":"161.864738ms","start":"2026-10-02T10:07:38.629587Z","end":"2026-10-02T10:07:38.791452Z","steps":["trace[257415645] 'process raft request' (duration: 161.658213ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:07:38.999206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.265368ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T10:07:38.999392Z","caller":"traceutil/trace.go:171","msg":"trace[314612470] linearizableReadLoop","detail":"{readStateIndex:26367; appliedIndex:26366; }","duration":"335.401577ms","start":"2026-10-02T10:07:38.663974Z","end":"2026-10-02T10:07:38.999375Z","steps":["trace[314612470] 'read index received' (duration: 128.092187ms)","trace[314612470] 'applied index is now lower than readState.Index' (duration: 207.30802ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:07:38.999396Z","caller":"traceutil/trace.go:171","msg":"trace[2091927857] transaction","detail":"{read_only:false; response_revision:25527; number_of_response:1; }","duration":"369.391264ms","start":"2026-10-02T10:07:38.629975Z","end":"2026-10-02T10:07:38.999366Z","steps":["trace[2091927857] 'process raft request' (duration: 242.864488ms)","trace[2091927857] 'compare' (duration: 126.054393ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T10:07:38.999527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.504877ms","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-02T10:07:38.999574Z","caller":"traceutil/trace.go:171","msg":"trace[594636017] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25528; }","duration":"384.59547ms","start":"2026-10-02T10:07:38.614965Z","end":"2026-10-02T10:07:38.99956Z","steps":["trace[594636017] 'agreement among raft nodes before linearized reading' (duration: 384.457556ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:07:38.999595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:07:38.614946Z","time spent":"384.644892ms","remote":"127.0.0.1:47796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-02T10:07:38.999624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:07:38.629948Z","time spent":"369.594959ms","remote":"127.0.0.1:47796","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-02T10:07:38.999614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.223617ms","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-02T10:07:38.999674Z","caller":"traceutil/trace.go:171","msg":"trace[608836612] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25528; }","duration":"385.310319ms","start":"2026-10-02T10:07:38.614345Z","end":"2026-10-02T10:07:38.999656Z","steps":["trace[608836612] 'agreement among raft nodes before linearized reading' (duration: 385.094044ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:07:38.999719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:07:38.614335Z","time spent":"385.374071ms","remote":"127.0.0.1:47796","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-02T10:07:38.999728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.861973ms","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-02T10:07:38.999804Z","caller":"traceutil/trace.go:171","msg":"trace[2142691182] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25528; }","duration":"385.994877ms","start":"2026-10-02T10:07:38.613793Z","end":"2026-10-02T10:07:38.999788Z","steps":["trace[2142691182] 'agreement among raft nodes before linearized reading' (duration: 385.73205ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:07:38.999891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:07:38.613773Z","time spent":"386.104809ms","remote":"127.0.0.1:47796","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":"info","ts":"2026-10-02T10:07:38.99948Z","caller":"traceutil/trace.go:171","msg":"trace[1564601994] transaction","detail":"{read_only:false; response_revision:25528; number_of_response:1; }","duration":"330.930268ms","start":"2026-10-02T10:07:38.668506Z","end":"2026-10-02T10:07:38.999436Z","steps":["trace[1564601994] 'process raft request' (duration: 330.808775ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:07:39.000144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:07:38.668476Z","time spent":"331.592866ms","remote":"127.0.0.1:47796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T10:07:39.314221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.324734ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T10:07:39.314454Z","caller":"traceutil/trace.go:171","msg":"trace[542113477] linearizableReadLoop","detail":"{readStateIndex:26370; appliedIndex:26368; }","duration":"308.92125ms","start":"2026-10-02T10:07:39.005517Z","end":"2026-10-02T10:07:39.314438Z","steps":["trace[542113477] 'read index received' (duration: 96.270598ms)","trace[542113477] 'applied index is now lower than readState.Index' (duration: 212.649772ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T10:07:39.314798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.734842ms","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-02T10:07:39.314837Z","caller":"traceutil/trace.go:171","msg":"trace[384089086] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25531; }","duration":"273.840265ms","start":"2026-10-02T10:07:39.040986Z","end":"2026-10-02T10:07:39.314826Z","steps":["trace[384089086] 'agreement among raft nodes before linearized reading' (duration: 273.705351ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:07:39.31502Z","caller":"traceutil/trace.go:171","msg":"trace[1509879715] transaction","detail":"{read_only:false; response_revision:25531; number_of_response:1; }","duration":"309.414693ms","start":"2026-10-02T10:07:39.005587Z","end":"2026-10-02T10:07:39.315002Z","steps":["trace[1509879715] 'process raft request' (duration: 308.805617ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:07:39.315026Z","caller":"traceutil/trace.go:171","msg":"trace[358244038] transaction","detail":"{read_only:false; response_revision:25530; number_of_response:1; }","duration":"309.714872ms","start":"2026-10-02T10:07:39.005276Z","end":"2026-10-02T10:07:39.314991Z","steps":["trace[358244038] 'process raft request' (duration: 309.036834ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:07:39.315027Z","caller":"traceutil/trace.go:171","msg":"trace[1341928119] transaction","detail":"{read_only:false; response_revision:25529; number_of_response:1; }","duration":"310.207505ms","start":"2026-10-02T10:07:39.004788Z","end":"2026-10-02T10:07:39.314995Z","steps":["trace[1341928119] 'process raft request' (duration: 97.028208ms)","trace[1341928119] 'compare' (duration: 212.004236ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T10:07:39.315142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:07:39.005578Z","time spent":"309.484265ms","remote":"127.0.0.1:47796","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-02T10:07:39.315199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:07:39.004769Z","time spent":"310.324968ms","remote":"127.0.0.1:47796","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-02T10:07:39.315206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:07:39.005252Z","time spent":"309.838355ms","remote":"127.0.0.1:47796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T10:07:39.316496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.966355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19190"} {"level":"info","ts":"2026-10-02T10:07:39.31656Z","caller":"traceutil/trace.go:171","msg":"trace[2070118804] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25531; }","duration":"311.031907ms","start":"2026-10-02T10:07:39.005512Z","end":"2026-10-02T10:07:39.316543Z","steps":["trace[2070118804] 'agreement among raft nodes before linearized reading' (duration: 309.173407ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:07:39.316608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:07:39.005475Z","time spent":"311.12278ms","remote":"127.0.0.1:47736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":19214,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-10-02T10:07:39.767386Z","caller":"traceutil/trace.go:171","msg":"trace[759171081] linearizableReadLoop","detail":"{readStateIndex:26372; appliedIndex:26371; }","duration":"418.682639ms","start":"2026-10-02T10:07:39.348677Z","end":"2026-10-02T10:07:39.76736Z","steps":["trace[759171081] 'read index received' (duration: 418.537065ms)","trace[759171081] 'applied index is now lower than readState.Index' (duration: 144.464µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:07:39.767482Z","caller":"traceutil/trace.go:171","msg":"trace[403994589] transaction","detail":"{read_only:false; response_revision:25532; number_of_response:1; }","duration":"448.425962ms","start":"2026-10-02T10:07:39.319047Z","end":"2026-10-02T10:07:39.767473Z","steps":["trace[403994589] 'process raft request' (duration: 448.152215ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:07:39.767577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:07:39.319026Z","time spent":"448.479754ms","remote":"127.0.0.1:47796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T10:07:39.767617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.895505ms","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-02T10:07:39.767714Z","caller":"traceutil/trace.go:171","msg":"trace[526053799] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:25532; }","duration":"419.052558ms","start":"2026-10-02T10:07:39.348633Z","end":"2026-10-02T10:07:39.767685Z","steps":["trace[526053799] 'agreement among raft nodes before linearized reading' (duration: 418.865314ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:07:39.767767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:07:39.348613Z","time spent":"419.140551ms","remote":"127.0.0.1:43746","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-02T10:07:39.82089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.92219ms","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-02T10:07:39.820911Z","caller":"traceutil/trace.go:171","msg":"trace[2003151477] transaction","detail":"{read_only:false; response_revision:25533; number_of_response:1; }","duration":"251.259882ms","start":"2026-10-02T10:07:39.56962Z","end":"2026-10-02T10:07:39.82088Z","steps":["trace[2003151477] 'process raft request' (duration: 250.982596ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:07:39.821052Z","caller":"traceutil/trace.go:171","msg":"trace[1158668458] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25533; }","duration":"345.069824ms","start":"2026-10-02T10:07:39.475882Z","end":"2026-10-02T10:07:39.820952Z","steps":["trace[1158668458] 'agreement among raft nodes before linearized reading' (duration: 344.904691ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:07:39.821105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:07:39.475837Z","time spent":"345.25505ms","remote":"127.0.0.1:47974","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":"warn","ts":"2026-10-02T10:07:39.958161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.802575ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T10:07:39.958248Z","caller":"traceutil/trace.go:171","msg":"trace[1596984542] linearizableReadLoop","detail":"{readStateIndex:26374; appliedIndex:26373; }","duration":"133.772378ms","start":"2026-10-02T10:07:39.824461Z","end":"2026-10-02T10:07:39.958234Z","steps":["trace[1596984542] 'read index received' (duration: 25.747417ms)","trace[1596984542] 'applied index is now lower than readState.Index' (duration: 108.023541ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:07:39.958344Z","caller":"traceutil/trace.go:171","msg":"trace[581585983] transaction","detail":"{read_only:false; response_revision:25534; number_of_response:1; }","duration":"158.867958ms","start":"2026-10-02T10:07:39.799464Z","end":"2026-10-02T10:07:39.958332Z","steps":["trace[581585983] 'process raft request' (duration: 50.818666ms)","trace[581585983] 'compare' (duration: 107.692623ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T10:07:39.958809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.350974ms","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-02T10:07:39.958847Z","caller":"traceutil/trace.go:171","msg":"trace[496382151] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25534; }","duration":"134.421666ms","start":"2026-10-02T10:07:39.824416Z","end":"2026-10-02T10:07:39.958837Z","steps":["trace[496382151] 'agreement among raft nodes before linearized reading' (duration: 134.365214ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:07:49.873811Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22177} {"level":"info","ts":"2026-10-02T10:07:49.987272Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22177,"took":"110.410315ms","hash":2405815566,"current-db-size-bytes":58662912,"current-db-size":"59 MB","current-db-size-in-use-bytes":45314048,"current-db-size-in-use":"45 MB"} {"level":"info","ts":"2026-10-02T10:07:49.987324Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2405815566,"revision":22177,"compact-revision":19893} {"level":"warn","ts":"2026-10-02T10:07:50.400171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.197621ms","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-02T10:07:50.400346Z","caller":"traceutil/trace.go:171","msg":"trace[875348471] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25615; }","duration":"161.415057ms","start":"2026-10-02T10:07:50.238913Z","end":"2026-10-02T10:07:50.400328Z","steps":["trace[875348471] 'range keys from in-memory index tree' (duration: 161.106079ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:07:50.400195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.515536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusterclasses/\" range_end:\"/registry/cluster.x-k8s.io/clusterclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T10:07:50.400454Z","caller":"traceutil/trace.go:171","msg":"trace[211936609] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusterclasses/; range_end:/registry/cluster.x-k8s.io/clusterclasses0; response_count:0; response_revision:25615; }","duration":"114.819283ms","start":"2026-10-02T10:07:50.285604Z","end":"2026-10-02T10:07:50.400423Z","steps":["trace[211936609] 'count revisions from in-memory index tree' (duration: 114.359922ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:07:53.604514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.100502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-cd963efe-30a1-4df9-bbf2-0590ec70e47c\" ","response":"range_response_count:1 size:3643"} {"level":"info","ts":"2026-10-02T10:07:53.60459Z","caller":"traceutil/trace.go:171","msg":"trace[1035901635] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-cd963efe-30a1-4df9-bbf2-0590ec70e47c; range_end:; response_count:1; response_revision:25694; }","duration":"286.213566ms","start":"2026-10-02T10:07:53.318357Z","end":"2026-10-02T10:07:53.604571Z","steps":["trace[1035901635] 'range keys from in-memory index tree' (duration: 285.957139ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:07:53.605178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.966711ms","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-02T10:07:53.605209Z","caller":"traceutil/trace.go:171","msg":"trace[1695419266] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25694; }","duration":"210.035653ms","start":"2026-10-02T10:07:53.395164Z","end":"2026-10-02T10:07:53.6052Z","steps":["trace[1695419266] 'range keys from in-memory index tree' (duration: 209.853648ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:07:53.605419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.071938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-02T10:07:53.605466Z","caller":"traceutil/trace.go:171","msg":"trace[1798698200] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/; range_end:/registry/monitoring.coreos.com/prometheuses0; response_count:0; response_revision:25694; }","duration":"206.206241ms","start":"2026-10-02T10:07:53.399245Z","end":"2026-10-02T10:07:53.605451Z","steps":["trace[1798698200] 'count revisions from in-memory index tree' (duration: 205.963784ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:07:53.605448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.13114ms","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-02T10:07:53.605522Z","caller":"traceutil/trace.go:171","msg":"trace[246146477] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25694; }","duration":"209.242893ms","start":"2026-10-02T10:07:53.396259Z","end":"2026-10-02T10:07:53.605502Z","steps":["trace[246146477] 'range keys from in-memory index tree' (duration: 208.976035ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:07:53.605735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.744399ms","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-02T10:07:53.605996Z","caller":"traceutil/trace.go:171","msg":"trace[42690870] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25694; }","duration":"205.863032ms","start":"2026-10-02T10:07:53.399933Z","end":"2026-10-02T10:07:53.605796Z","steps":["trace[42690870] 'range keys from in-memory index tree' (duration: 205.554413ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:07:53.974536Z","caller":"traceutil/trace.go:171","msg":"trace[839643723] transaction","detail":"{read_only:false; response_revision:25697; number_of_response:1; }","duration":"361.244196ms","start":"2026-10-02T10:07:53.612113Z","end":"2026-10-02T10:07:53.973357Z","steps":["trace[839643723] 'process raft request' (duration: 360.860156ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:07:53.974639Z","caller":"traceutil/trace.go:171","msg":"trace[1255247390] transaction","detail":"{read_only:false; response_revision:25698; number_of_response:1; }","duration":"359.956172ms","start":"2026-10-02T10:07:53.613581Z","end":"2026-10-02T10:07:53.973537Z","steps":["trace[1255247390] 'process raft request' (duration: 359.713146ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:07:53.974717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:07:53.61207Z","time spent":"362.555812ms","remote":"127.0.0.1:47796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T10:07:53.974784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:07:53.61357Z","time spent":"361.138664ms","remote":"127.0.0.1:47744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3745,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T10:07:53.97508Z","caller":"traceutil/trace.go:171","msg":"trace[2048447242] transaction","detail":"{read_only:false; response_revision:25696; number_of_response:1; }","duration":"361.144844ms","start":"2026-10-02T10:07:53.611928Z","end":"2026-10-02T10:07:53.973073Z","steps":["trace[2048447242] 'process raft request' (duration: 322.643487ms)","trace[2048447242] 'compare' (duration: 37.887781ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T10:07:53.975326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:07:53.611905Z","time spent":"363.366494ms","remote":"127.0.0.1:47796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T10:07:53.982256Z","caller":"traceutil/trace.go:171","msg":"trace[1906175797] linearizableReadLoop","detail":"{readStateIndex:26543; appliedIndex:26539; }","duration":"154.761539ms","start":"2026-10-02T10:07:53.827464Z","end":"2026-10-02T10:07:53.982226Z","steps":["trace[1906175797] 'read index received' (duration: 107.120138ms)","trace[1906175797] 'applied index is now lower than readState.Index' (duration: 47.636411ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:07:53.982321Z","caller":"traceutil/trace.go:171","msg":"trace[620051630] transaction","detail":"{read_only:false; response_revision:25699; number_of_response:1; }","duration":"243.017633ms","start":"2026-10-02T10:07:53.739264Z","end":"2026-10-02T10:07:53.982281Z","steps":["trace[620051630] 'process raft request' (duration: 242.756036ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:07:53.982387Z","caller":"traceutil/trace.go:171","msg":"trace[1873690820] transaction","detail":"{read_only:false; response_revision:25700; number_of_response:1; }","duration":"122.815666ms","start":"2026-10-02T10:07:53.859527Z","end":"2026-10-02T10:07:53.982343Z","steps":["trace[1873690820] 'process raft request' (duration: 122.642782ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:07:53.982564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.093798ms","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-02T10:07:53.982632Z","caller":"traceutil/trace.go:171","msg":"trace[2022001453] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25700; }","duration":"155.192951ms","start":"2026-10-02T10:07:53.827416Z","end":"2026-10-02T10:07:53.982609Z","steps":["trace[2022001453] 'agreement among raft nodes before linearized reading' (duration: 154.969025ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:07:54.18364Z","caller":"traceutil/trace.go:171","msg":"trace[1003416860] transaction","detail":"{read_only:false; response_revision:25701; number_of_response:1; }","duration":"195.467104ms","start":"2026-10-02T10:07:53.988143Z","end":"2026-10-02T10:07:54.183611Z","steps":["trace[1003416860] 'process raft request' (duration: 161.009345ms)","trace[1003416860] 'compare' (duration: 34.312455ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:07:54.407692Z","caller":"traceutil/trace.go:171","msg":"trace[2068531742] linearizableReadLoop","detail":"{readStateIndex:26548; appliedIndex:26546; }","duration":"220.925904ms","start":"2026-10-02T10:07:54.186744Z","end":"2026-10-02T10:07:54.407669Z","steps":["trace[2068531742] 'read index received' (duration: 220.644436ms)","trace[2068531742] 'applied index is now lower than readState.Index' (duration: 280.598µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:07:54.407732Z","caller":"traceutil/trace.go:171","msg":"trace[1777524394] transaction","detail":"{read_only:false; response_revision:25703; number_of_response:1; }","duration":"246.562217ms","start":"2026-10-02T10:07:54.161137Z","end":"2026-10-02T10:07:54.4077Z","steps":["trace[1777524394] 'process raft request' (duration: 246.331351ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:07:54.407736Z","caller":"traceutil/trace.go:171","msg":"trace[1692016113] transaction","detail":"{read_only:false; response_revision:25704; number_of_response:1; }","duration":"243.812594ms","start":"2026-10-02T10:07:54.163901Z","end":"2026-10-02T10:07:54.407713Z","steps":["trace[1692016113] 'process raft request' (duration: 243.723911ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:07:54.407981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.526792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-cd963efe-30a1-4df9-bbf2-0590ec70e47c\" ","response":"range_response_count:1 size:3763"} {"level":"warn","ts":"2026-10-02T10:07:54.407971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.195441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-cluster-operator-54f767cff8-2ml4d\" ","response":"range_response_count:1 size:5641"} {"level":"info","ts":"2026-10-02T10:07:54.408017Z","caller":"traceutil/trace.go:171","msg":"trace[1344230938] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-cd963efe-30a1-4df9-bbf2-0590ec70e47c; range_end:; response_count:1; response_revision:25704; }","duration":"154.622745ms","start":"2026-10-02T10:07:54.253382Z","end":"2026-10-02T10:07:54.408005Z","steps":["trace[1344230938] 'agreement among raft nodes before linearized reading' (duration: 154.413179ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:07:54.408074Z","caller":"traceutil/trace.go:171","msg":"trace[1651029853] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-cluster-operator-54f767cff8-2ml4d; range_end:; response_count:1; response_revision:25704; }","duration":"221.339125ms","start":"2026-10-02T10:07:54.186706Z","end":"2026-10-02T10:07:54.408045Z","steps":["trace[1651029853] 'agreement among raft nodes before linearized reading' (duration: 221.073348ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:07:55.469746Z","caller":"traceutil/trace.go:171","msg":"trace[1376850107] transaction","detail":"{read_only:false; response_revision:25708; number_of_response:1; }","duration":"491.43521ms","start":"2026-10-02T10:07:54.978282Z","end":"2026-10-02T10:07:55.469717Z","steps":["trace[1376850107] 'process raft request' (duration: 491.201734ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:07:55.469946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:07:54.978249Z","time spent":"491.575614ms","remote":"127.0.0.1:47796","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-02T10:07:55.505801Z","caller":"traceutil/trace.go:171","msg":"trace[858962749] linearizableReadLoop","detail":"{readStateIndex:26553; appliedIndex:26551; }","duration":"434.875201ms","start":"2026-10-02T10:07:55.070901Z","end":"2026-10-02T10:07:55.505776Z","steps":["trace[858962749] 'read index received' (duration: 398.701106ms)","trace[858962749] 'applied index is now lower than readState.Index' (duration: 36.172255ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:07:55.505885Z","caller":"traceutil/trace.go:171","msg":"trace[2062077230] transaction","detail":"{read_only:false; response_revision:25709; number_of_response:1; }","duration":"450.030735ms","start":"2026-10-02T10:07:55.055806Z","end":"2026-10-02T10:07:55.505837Z","steps":["trace[2062077230] 'process raft request' (duration: 449.824639ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:07:55.506035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:07:55.055735Z","time spent":"450.20785ms","remote":"127.0.0.1:47796","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-02T10:07:55.506109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.19457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-02T10:07:55.506166Z","caller":"traceutil/trace.go:171","msg":"trace[330355842] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25709; }","duration":"435.310503ms","start":"2026-10-02T10:07:55.070838Z","end":"2026-10-02T10:07:55.506149Z","steps":["trace[330355842] 'agreement among raft nodes before linearized reading' (duration: 435.095677ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:07:55.506211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:07:55.070817Z","time spent":"435.383745ms","remote":"127.0.0.1:47796","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-02T10:07:55.728206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.036298ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T10:07:55.728308Z","caller":"traceutil/trace.go:171","msg":"trace[1735843254] linearizableReadLoop","detail":"{readStateIndex:26554; appliedIndex:26553; }","duration":"148.337818ms","start":"2026-10-02T10:07:55.579953Z","end":"2026-10-02T10:07:55.728291Z","steps":["trace[1735843254] 'read index received' (duration: 33.126115ms)","trace[1735843254] 'applied index is now lower than readState.Index' (duration: 115.210383ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:07:55.728334Z","caller":"traceutil/trace.go:171","msg":"trace[1558033351] transaction","detail":"{read_only:false; response_revision:25710; number_of_response:1; }","duration":"212.130559ms","start":"2026-10-02T10:07:55.516168Z","end":"2026-10-02T10:07:55.728298Z","steps":["trace[1558033351] 'process raft request' (duration: 96.923357ms)","trace[1558033351] 'compare' (duration: 114.906634ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T10:07:55.728417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.485652ms","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-02T10:07:55.728448Z","caller":"traceutil/trace.go:171","msg":"trace[1290421957] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25710; }","duration":"148.519803ms","start":"2026-10-02T10:07:55.579919Z","end":"2026-10-02T10:07:55.728438Z","steps":["trace[1290421957] 'agreement among raft nodes before linearized reading' (duration: 148.456141ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:07:55.728594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.480436ms","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-02T10:07:55.728678Z","caller":"traceutil/trace.go:171","msg":"trace[1925630437] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25710; }","duration":"104.605129ms","start":"2026-10-02T10:07:55.62405Z","end":"2026-10-02T10:07:55.728655Z","steps":["trace[1925630437] 'agreement among raft nodes before linearized reading' (duration: 104.354053ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:07:56.161695Z","caller":"traceutil/trace.go:171","msg":"trace[725187199] transaction","detail":"{read_only:false; response_revision:25712; number_of_response:1; }","duration":"177.713422ms","start":"2026-10-02T10:07:55.983953Z","end":"2026-10-02T10:07:56.161666Z","steps":["trace[725187199] 'process raft request' (duration: 177.538257ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:07:56.602731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.614059ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T10:07:56.602919Z","caller":"traceutil/trace.go:171","msg":"trace[1855502170] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:25712; }","duration":"201.808914ms","start":"2026-10-02T10:07:56.401088Z","end":"2026-10-02T10:07:56.602897Z","steps":["trace[1855502170] 'range keys from in-memory index tree' (duration: 201.598878ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:07:56.60325Z","caller":"traceutil/trace.go:171","msg":"trace[679514311] transaction","detail":"{read_only:false; response_revision:25713; number_of_response:1; }","duration":"618.061558ms","start":"2026-10-02T10:07:55.985164Z","end":"2026-10-02T10:07:56.603225Z","steps":["trace[679514311] 'process raft request' (duration: 603.398126ms)","trace[679514311] 'compare' (duration: 14.260501ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:07:56.603276Z","caller":"traceutil/trace.go:171","msg":"trace[1311670757] transaction","detail":"{read_only:false; response_revision:25714; number_of_response:1; }","duration":"616.520737ms","start":"2026-10-02T10:07:55.986716Z","end":"2026-10-02T10:07:56.603237Z","steps":["trace[1311670757] 'process raft request' (duration: 616.318281ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:07:56.603394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:07:55.985136Z","time spent":"618.178231ms","remote":"127.0.0.1:47796","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-02T10:07:56.603451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:07:55.986705Z","time spent":"616.65312ms","remote":"127.0.0.1:47796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T10:07:56.603122Z","caller":"traceutil/trace.go:171","msg":"trace[1374646121] linearizableReadLoop","detail":"{readStateIndex:26558; appliedIndex:26555; }","duration":"459.806216ms","start":"2026-10-02T10:07:56.143287Z","end":"2026-10-02T10:07:56.603093Z","steps":["trace[1374646121] 'read index received' (duration: 18.34657ms)","trace[1374646121] 'applied index is now lower than readState.Index' (duration: 441.458296ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T10:07:56.603988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.68851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-02T10:07:56.604062Z","caller":"traceutil/trace.go:171","msg":"trace[1218354787] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25714; }","duration":"460.822144ms","start":"2026-10-02T10:07:56.143219Z","end":"2026-10-02T10:07:56.604041Z","steps":["trace[1218354787] 'agreement among raft nodes before linearized reading' (duration: 460.67416ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:07:56.604159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:07:56.143191Z","time spent":"460.947257ms","remote":"127.0.0.1:35636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":32,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-10-02T10:07:56.648327Z","caller":"traceutil/trace.go:171","msg":"trace[853590994] transaction","detail":"{read_only:false; response_revision:25715; number_of_response:1; }","duration":"455.628665ms","start":"2026-10-02T10:07:56.192677Z","end":"2026-10-02T10:07:56.648306Z","steps":["trace[853590994] 'process raft request' (duration: 455.348238ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:07:56.648381Z","caller":"traceutil/trace.go:171","msg":"trace[44042321] transaction","detail":"{read_only:false; response_revision:25716; number_of_response:1; }","duration":"233.845629ms","start":"2026-10-02T10:07:56.414527Z","end":"2026-10-02T10:07:56.648373Z","steps":["trace[44042321] 'process raft request' (duration: 233.637133ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:07:56.648448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:07:56.19265Z","time spent":"455.736218ms","remote":"127.0.0.1:47796","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-02T10:07:56.648328Z","caller":"traceutil/trace.go:171","msg":"trace[397872212] transaction","detail":"{read_only:false; response_revision:25717; number_of_response:1; }","duration":"233.186221ms","start":"2026-10-02T10:07:56.415122Z","end":"2026-10-02T10:07:56.648308Z","steps":["trace[397872212] 'process raft request' (duration: 233.123729ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:07:56.90929Z","caller":"traceutil/trace.go:171","msg":"trace[525550151] transaction","detail":"{read_only:false; response_revision:25718; number_of_response:1; }","duration":"311.387977ms","start":"2026-10-02T10:07:56.597878Z","end":"2026-10-02T10:07:56.909266Z","steps":["trace[525550151] 'process raft request' (duration: 311.177231ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:07:56.90949Z","caller":"traceutil/trace.go:171","msg":"trace[1227475994] transaction","detail":"{read_only:false; response_revision:25719; number_of_response:1; }","duration":"310.868373ms","start":"2026-10-02T10:07:56.598597Z","end":"2026-10-02T10:07:56.909466Z","steps":["trace[1227475994] 'process raft request' (duration: 310.623868ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:07:56.909635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.443258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-02T10:07:56.90968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"718.831985ms","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-02T10:07:56.909682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.905551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-cd963efe-30a1-4df9-bbf2-0590ec70e47c\" ","response":"range_response_count:1 size:3763"} {"level":"info","ts":"2026-10-02T10:07:56.909723Z","caller":"traceutil/trace.go:171","msg":"trace[693177157] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25719; }","duration":"718.941709ms","start":"2026-10-02T10:07:56.19077Z","end":"2026-10-02T10:07:56.909712Z","steps":["trace[693177157] 'agreement among raft nodes before linearized reading' (duration: 718.772334ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:07:56.909716Z","caller":"traceutil/trace.go:171","msg":"trace[1210446928] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachines/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachines0; response_count:0; response_revision:25719; }","duration":"597.584412ms","start":"2026-10-02T10:07:56.312112Z","end":"2026-10-02T10:07:56.909697Z","steps":["trace[1210446928] 'agreement among raft nodes before linearized reading' (duration: 597.422307ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:07:56.909735Z","caller":"traceutil/trace.go:171","msg":"trace[2109336518] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-cd963efe-30a1-4df9-bbf2-0590ec70e47c; range_end:; response_count:1; response_revision:25719; }","duration":"399.046424ms","start":"2026-10-02T10:07:56.510676Z","end":"2026-10-02T10:07:56.909722Z","steps":["trace[2109336518] 'agreement among raft nodes before linearized reading' (duration: 398.832629ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:07:56.90976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:07:56.190745Z","time spent":"719.00818ms","remote":"127.0.0.1:47796","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-02T10:07:56.909767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:07:56.510657Z","time spent":"399.102266ms","remote":"127.0.0.1:47744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3787,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-cd963efe-30a1-4df9-bbf2-0590ec70e47c\" "} {"level":"warn","ts":"2026-10-02T10:07:56.909773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:07:56.312093Z","time spent":"597.669563ms","remote":"127.0.0.1:41060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":126,"response count":0,"response size":30,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines0\" count_only:true "} {"level":"warn","ts":"2026-10-02T10:07:56.9098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:07:56.598579Z","time spent":"311.11771ms","remote":"127.0.0.1:47796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T10:07:56.909642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:07:56.597825Z","time spent":"311.740925ms","remote":"127.0.0.1:47796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T10:07:56.909492Z","caller":"traceutil/trace.go:171","msg":"trace[1937142093] linearizableReadLoop","detail":"{readStateIndex:26563; appliedIndex:26560; }","duration":"305.515009ms","start":"2026-10-02T10:07:56.603946Z","end":"2026-10-02T10:07:56.909461Z","steps":["trace[1937142093] 'read index received' (duration: 44.337843ms)","trace[1937142093] 'applied index is now lower than readState.Index' (duration: 261.175116ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T10:07:56.910314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.379335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T10:07:56.910371Z","caller":"traceutil/trace.go:171","msg":"trace[82281578] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:25719; }","duration":"179.472027ms","start":"2026-10-02T10:07:56.73088Z","end":"2026-10-02T10:07:56.910352Z","steps":["trace[82281578] 'agreement among raft nodes before linearized reading' (duration: 179.401175ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:07:56.910664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.290116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T10:07:56.910735Z","caller":"traceutil/trace.go:171","msg":"trace[1195004114] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:25719; }","duration":"551.850762ms","start":"2026-10-02T10:07:56.358842Z","end":"2026-10-02T10:07:56.910693Z","steps":["trace[1195004114] 'agreement among raft nodes before linearized reading' (duration: 551.321687ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:07:56.910769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:07:56.358821Z","time spent":"551.938144ms","remote":"127.0.0.1:40928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":30,"request content":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true "} {"level":"warn","ts":"2026-10-02T10:07:57.212796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.421585ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T10:07:57.21297Z","caller":"traceutil/trace.go:171","msg":"trace[982500596] transaction","detail":"{read_only:false; response_revision:25720; number_of_response:1; }","duration":"296.814599ms","start":"2026-10-02T10:07:56.916138Z","end":"2026-10-02T10:07:57.212953Z","steps":["trace[982500596] 'process raft request' (duration: 138.172837ms)","trace[982500596] 'compare' (duration: 158.096597ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:07:57.213279Z","caller":"traceutil/trace.go:171","msg":"trace[1440275500] linearizableReadLoop","detail":"{readStateIndex:26565; appliedIndex:26563; }","duration":"223.382279ms","start":"2026-10-02T10:07:56.989889Z","end":"2026-10-02T10:07:57.213271Z","steps":["trace[1440275500] 'read index received' (duration: 64.45489ms)","trace[1440275500] 'applied index is now lower than readState.Index' (duration: 158.926689ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:07:57.213341Z","caller":"traceutil/trace.go:171","msg":"trace[508749916] transaction","detail":"{read_only:false; response_revision:25721; number_of_response:1; }","duration":"269.831899ms","start":"2026-10-02T10:07:56.943503Z","end":"2026-10-02T10:07:57.213335Z","steps":["trace[508749916] 'process raft request' (duration: 269.384437ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:07:57.213946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.054667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"info","ts":"2026-10-02T10:07:57.214048Z","caller":"traceutil/trace.go:171","msg":"trace[1252038819] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25721; }","duration":"200.218211ms","start":"2026-10-02T10:07:57.0138Z","end":"2026-10-02T10:07:57.214019Z","steps":["trace[1252038819] 'agreement among raft nodes before linearized reading' (duration: 199.721218ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:07:57.214353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.489069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T10:07:57.21444Z","caller":"traceutil/trace.go:171","msg":"trace[470692533] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinesets/; range_end:/registry/cluster.x-k8s.io/machinesets0; response_count:0; response_revision:25721; }","duration":"224.620132ms","start":"2026-10-02T10:07:56.989802Z","end":"2026-10-02T10:07:57.214422Z","steps":["trace[470692533] 'agreement among raft nodes before linearized reading' (duration: 223.749759ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:07:57.214644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.990841ms","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-02T10:07:57.214716Z","caller":"traceutil/trace.go:171","msg":"trace[619344872] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:25721; }","duration":"162.101494ms","start":"2026-10-02T10:07:57.052593Z","end":"2026-10-02T10:07:57.214694Z","steps":["trace[619344872] 'agreement among raft nodes before linearized reading' (duration: 160.988545ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:07:57.637562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.883235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T10:07:57.637639Z","caller":"traceutil/trace.go:171","msg":"trace[364140102] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:25721; }","duration":"256.013399ms","start":"2026-10-02T10:07:57.381608Z","end":"2026-10-02T10:07:57.637621Z","steps":["trace[364140102] 'count revisions from in-memory index tree' (duration: 255.783873ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:07:57.693956Z","caller":"traceutil/trace.go:171","msg":"trace[963709739] transaction","detail":"{read_only:false; response_revision:25722; number_of_response:1; }","duration":"182.306133ms","start":"2026-10-02T10:07:57.51161Z","end":"2026-10-02T10:07:57.693916Z","steps":["trace[963709739] 'process raft request' (duration: 182.042026ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:07:57.895698Z","caller":"traceutil/trace.go:171","msg":"trace[1886540269] linearizableReadLoop","detail":"{readStateIndex:26567; appliedIndex:26566; }","duration":"131.525768ms","start":"2026-10-02T10:07:57.764153Z","end":"2026-10-02T10:07:57.895679Z","steps":["trace[1886540269] 'read index received' (duration: 83.902728ms)","trace[1886540269] 'applied index is now lower than readState.Index' (duration: 47.62216ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:07:57.895897Z","caller":"traceutil/trace.go:171","msg":"trace[2095323672] transaction","detail":"{read_only:false; response_revision:25723; number_of_response:1; }","duration":"159.341461ms","start":"2026-10-02T10:07:57.736543Z","end":"2026-10-02T10:07:57.895885Z","steps":["trace[2095323672] 'process raft request' (duration: 111.485244ms)","trace[2095323672] 'compare' (duration: 47.291772ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T10:07:57.896101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.93348ms","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-02T10:07:57.896122Z","caller":"traceutil/trace.go:171","msg":"trace[513840409] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25723; }","duration":"131.996241ms","start":"2026-10-02T10:07:57.764118Z","end":"2026-10-02T10:07:57.896115Z","steps":["trace[513840409] 'agreement among raft nodes before linearized reading' (duration: 131.891358ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:07:58.227851Z","caller":"traceutil/trace.go:171","msg":"trace[1296579619] transaction","detail":"{read_only:false; response_revision:25724; number_of_response:1; }","duration":"329.408029ms","start":"2026-10-02T10:07:57.898416Z","end":"2026-10-02T10:07:58.227824Z","steps":["trace[1296579619] 'process raft request' (duration: 324.845166ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:07:58.228032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:07:57.898392Z","time spent":"329.552421ms","remote":"127.0.0.1:47638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T10:07:58.237243Z","caller":"traceutil/trace.go:171","msg":"trace[893065631] linearizableReadLoop","detail":"{readStateIndex:26569; appliedIndex:26567; }","duration":"323.54593ms","start":"2026-10-02T10:07:57.913675Z","end":"2026-10-02T10:07:58.237221Z","steps":["trace[893065631] 'read index received' (duration: 309.605919ms)","trace[893065631] 'applied index is now lower than readState.Index' (duration: 13.938981ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:07:58.237337Z","caller":"traceutil/trace.go:171","msg":"trace[215352165] transaction","detail":"{read_only:false; response_revision:25725; number_of_response:1; }","duration":"337.183264ms","start":"2026-10-02T10:07:57.900117Z","end":"2026-10-02T10:07:58.2373Z","steps":["trace[215352165] 'process raft request' (duration: 336.999589ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:07:58.237499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.800507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-cd963efe-30a1-4df9-bbf2-0590ec70e47c\" ","response":"range_response_count:1 size:3763"} {"level":"warn","ts":"2026-10-02T10:07:58.237527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:07:57.900107Z","time spent":"337.317158ms","remote":"127.0.0.1:47796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T10:07:58.237568Z","caller":"traceutil/trace.go:171","msg":"trace[358824271] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-cd963efe-30a1-4df9-bbf2-0590ec70e47c; range_end:; response_count:1; response_revision:25725; }","duration":"323.90436ms","start":"2026-10-02T10:07:57.913645Z","end":"2026-10-02T10:07:58.237549Z","steps":["trace[358824271] 'agreement among raft nodes before linearized reading' (duration: 323.685934ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:07:58.237607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:07:57.913631Z","time spent":"323.967752ms","remote":"127.0.0.1:47744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3787,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-cd963efe-30a1-4df9-bbf2-0590ec70e47c\" "} {"level":"info","ts":"2026-10-02T10:08:02.243222Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000067f5.wal"} {"level":"info","ts":"2026-10-02T10:08:04.755688Z","caller":"traceutil/trace.go:171","msg":"trace[591563854] linearizableReadLoop","detail":"{readStateIndex:26637; appliedIndex:26636; }","duration":"126.189804ms","start":"2026-10-02T10:08:04.62947Z","end":"2026-10-02T10:08:04.75566Z","steps":["trace[591563854] 'read index received' (duration: 126.03391ms)","trace[591563854] 'applied index is now lower than readState.Index' (duration: 154.734µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:08:04.755755Z","caller":"traceutil/trace.go:171","msg":"trace[1888735852] transaction","detail":"{read_only:false; response_revision:25791; number_of_response:1; }","duration":"172.752735ms","start":"2026-10-02T10:08:04.582973Z","end":"2026-10-02T10:08:04.755726Z","steps":["trace[1888735852] 'process raft request' (duration: 172.529518ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:08:04.755839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.354488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T10:08:04.755896Z","caller":"traceutil/trace.go:171","msg":"trace[561232888] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:25791; }","duration":"126.491322ms","start":"2026-10-02T10:08:04.629393Z","end":"2026-10-02T10:08:04.755884Z","steps":["trace[561232888] 'agreement among raft nodes before linearized reading' (duration: 126.385909ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:08:04.927937Z","caller":"traceutil/trace.go:171","msg":"trace[1095887096] transaction","detail":"{read_only:false; response_revision:25792; number_of_response:1; }","duration":"295.903086ms","start":"2026-10-02T10:08:04.632009Z","end":"2026-10-02T10:08:04.927912Z","steps":["trace[1095887096] 'process raft request' (duration: 295.646309ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:08:04.92813Z","caller":"traceutil/trace.go:171","msg":"trace[1934573220] transaction","detail":"{read_only:false; response_revision:25793; number_of_response:1; }","duration":"250.128856ms","start":"2026-10-02T10:08:04.677972Z","end":"2026-10-02T10:08:04.9281Z","steps":["trace[1934573220] 'process raft request' (duration: 249.863369ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:08:04.928336Z","caller":"traceutil/trace.go:171","msg":"trace[1915868952] linearizableReadLoop","detail":"{readStateIndex:26641; appliedIndex:26637; }","duration":"172.561069ms","start":"2026-10-02T10:08:04.755752Z","end":"2026-10-02T10:08:04.928313Z","steps":["trace[1915868952] 'read index received' (duration: 171.898081ms)","trace[1915868952] 'applied index is now lower than readState.Index' (duration: 662.068µs)"],"step_count":2} {"level":"warn","ts":"2026-10-02T10:08:04.928384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.938765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T10:08:04.928374Z","caller":"traceutil/trace.go:171","msg":"trace[109329529] transaction","detail":"{read_only:false; response_revision:25795; number_of_response:1; }","duration":"249.233292ms","start":"2026-10-02T10:08:04.679108Z","end":"2026-10-02T10:08:04.928341Z","steps":["trace[109329529] 'process raft request' (duration: 249.13662ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:08:04.928419Z","caller":"traceutil/trace.go:171","msg":"trace[111592853] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:25795; }","duration":"216.019727ms","start":"2026-10-02T10:08:04.712387Z","end":"2026-10-02T10:08:04.928406Z","steps":["trace[111592853] 'agreement among raft nodes before linearized reading' (duration: 215.936575ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:08:04.928524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.727749ms","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-02T10:08:04.928564Z","caller":"traceutil/trace.go:171","msg":"trace[425154084] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25795; }","duration":"293.7966ms","start":"2026-10-02T10:08:04.63475Z","end":"2026-10-02T10:08:04.928547Z","steps":["trace[425154084] 'agreement among raft nodes before linearized reading' (duration: 293.654056ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:08:04.928593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.264023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-10-02T10:08:04.92862Z","caller":"traceutil/trace.go:171","msg":"trace[534002619] transaction","detail":"{read_only:false; response_revision:25794; number_of_response:1; }","duration":"249.547551ms","start":"2026-10-02T10:08:04.67906Z","end":"2026-10-02T10:08:04.928608Z","steps":["trace[534002619] 'process raft request' (duration: 248.995626ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:08:04.92864Z","caller":"traceutil/trace.go:171","msg":"trace[1853418179] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25795; }","duration":"279.354055ms","start":"2026-10-02T10:08:04.64927Z","end":"2026-10-02T10:08:04.928624Z","steps":["trace[1853418179] 'agreement among raft nodes before linearized reading' (duration: 279.194851ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:08:05.329406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.993632ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T10:08:05.329676Z","caller":"traceutil/trace.go:171","msg":"trace[1030733707] transaction","detail":"{read_only:false; response_revision:25796; number_of_response:1; }","duration":"566.274832ms","start":"2026-10-02T10:08:04.763362Z","end":"2026-10-02T10:08:05.329637Z","steps":["trace[1030733707] 'process raft request' (duration: 419.707355ms)","trace[1030733707] 'compare' (duration: 145.842768ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:08:05.329739Z","caller":"traceutil/trace.go:171","msg":"trace[1519387865] transaction","detail":"{read_only:false; response_revision:25797; number_of_response:1; }","duration":"566.037477ms","start":"2026-10-02T10:08:04.76368Z","end":"2026-10-02T10:08:05.329718Z","steps":["trace[1519387865] 'process raft request' (duration: 565.859972ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:08:05.329825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:08:04.763655Z","time spent":"566.127938ms","remote":"127.0.0.1:47810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1489,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T10:08:05.329856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:08:04.763321Z","time spent":"566.435667ms","remote":"127.0.0.1:47734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":884,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T10:08:05.330243Z","caller":"traceutil/trace.go:171","msg":"trace[903255596] transaction","detail":"{read_only:false; response_revision:25802; number_of_response:1; }","duration":"390.430257ms","start":"2026-10-02T10:08:04.939772Z","end":"2026-10-02T10:08:05.330203Z","steps":["trace[903255596] 'process raft request' (duration: 390.375705ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:08:05.330259Z","caller":"traceutil/trace.go:171","msg":"trace[2029804729] linearizableReadLoop","detail":"{readStateIndex:26645; appliedIndex:26641; }","duration":"398.704587ms","start":"2026-10-02T10:08:04.931535Z","end":"2026-10-02T10:08:05.33024Z","steps":["trace[2029804729] 'read index received' (duration: 251.552264ms)","trace[2029804729] 'applied index is now lower than readState.Index' (duration: 147.150653ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T10:08:05.330328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:08:04.939759Z","time spent":"390.528119ms","remote":"127.0.0.1:47796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T10:08:05.330378Z","caller":"traceutil/trace.go:171","msg":"trace[217911056] transaction","detail":"{read_only:false; response_revision:25799; number_of_response:1; }","duration":"566.585922ms","start":"2026-10-02T10:08:04.763758Z","end":"2026-10-02T10:08:05.330344Z","steps":["trace[217911056] 'process raft request' (duration: 566.052547ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:08:05.330464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.917241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-cd963efe-30a1-4df9-bbf2-0590ec70e47c\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-10-02T10:08:05.330807Z","caller":"traceutil/trace.go:171","msg":"trace[1657463548] range","detail":"{range_begin:/registry/persistentvolumes/pvc-cd963efe-30a1-4df9-bbf2-0590ec70e47c; range_end:; response_count:1; response_revision:25802; }","duration":"399.291061ms","start":"2026-10-02T10:08:04.931493Z","end":"2026-10-02T10:08:05.330784Z","steps":["trace[1657463548] 'agreement among raft nodes before linearized reading' (duration: 398.84137ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:08:05.330908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:08:04.931476Z","time spent":"399.378094ms","remote":"127.0.0.1:47714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1346,"request content":"key:\"/registry/persistentvolumes/pvc-cd963efe-30a1-4df9-bbf2-0590ec70e47c\" "} {"level":"warn","ts":"2026-10-02T10:08:05.330511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:08:04.763733Z","time spent":"566.704383ms","remote":"127.0.0.1:47734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":940,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T10:08:05.330491Z","caller":"traceutil/trace.go:171","msg":"trace[1147887451] transaction","detail":"{read_only:false; response_revision:25801; number_of_response:1; }","duration":"394.852363ms","start":"2026-10-02T10:08:04.935624Z","end":"2026-10-02T10:08:05.330476Z","steps":["trace[1147887451] 'process raft request' (duration: 394.444623ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:08:05.33137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:08:04.935602Z","time spent":"395.719697ms","remote":"127.0.0.1:47796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T10:08:05.330485Z","caller":"traceutil/trace.go:171","msg":"trace[1955929400] transaction","detail":"{read_only:false; response_revision:25800; number_of_response:1; }","duration":"398.728417ms","start":"2026-10-02T10:08:04.931733Z","end":"2026-10-02T10:08:05.330461Z","steps":["trace[1955929400] 'process raft request' (duration: 398.279486ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:08:05.330503Z","caller":"traceutil/trace.go:171","msg":"trace[653349741] transaction","detail":"{read_only:false; response_revision:25798; number_of_response:1; }","duration":"566.720133ms","start":"2026-10-02T10:08:04.763764Z","end":"2026-10-02T10:08:05.330484Z","steps":["trace[653349741] 'process raft request' (duration: 565.884642ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:08:05.33158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:08:04.931716Z","time spent":"399.796665ms","remote":"127.0.0.1:47796","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-02T10:08:05.331691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:08:04.763748Z","time spent":"567.896296ms","remote":"127.0.0.1:47810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1414,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T10:08:05.402037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.058148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T10:08:05.402052Z","caller":"traceutil/trace.go:171","msg":"trace[1593796221] transaction","detail":"{read_only:false; response_revision:25803; number_of_response:1; }","duration":"163.694163ms","start":"2026-10-02T10:08:05.23832Z","end":"2026-10-02T10:08:05.402014Z","steps":["trace[1593796221] 'process raft request' (duration: 163.482537ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:08:05.4021Z","caller":"traceutil/trace.go:171","msg":"trace[1347522099] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:25803; }","duration":"458.14892ms","start":"2026-10-02T10:08:04.943935Z","end":"2026-10-02T10:08:05.402084Z","steps":["trace[1347522099] 'agreement among raft nodes before linearized reading' (duration: 458.026227ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:08:05.402138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:08:04.943927Z","time spent":"458.199801ms","remote":"127.0.0.1:40724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":30,"request content":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true "} {"level":"warn","ts":"2026-10-02T10:08:05.402296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.956527ms","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-02T10:08:05.402372Z","caller":"traceutil/trace.go:171","msg":"trace[2013466114] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25803; }","duration":"159.08753ms","start":"2026-10-02T10:08:05.243266Z","end":"2026-10-02T10:08:05.402353Z","steps":["trace[2013466114] 'agreement among raft nodes before linearized reading' (duration: 158.826293ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:08:15.581851Z","caller":"traceutil/trace.go:171","msg":"trace[1476030796] linearizableReadLoop","detail":"{readStateIndex:26733; appliedIndex:26732; }","duration":"139.888048ms","start":"2026-10-02T10:08:15.441943Z","end":"2026-10-02T10:08:15.581831Z","steps":["trace[1476030796] 'read index received' (duration: 138.796319ms)","trace[1476030796] 'applied index is now lower than readState.Index' (duration: 1.090699ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:08:15.581937Z","caller":"traceutil/trace.go:171","msg":"trace[1110378136] transaction","detail":"{read_only:false; response_revision:25885; number_of_response:1; }","duration":"144.901652ms","start":"2026-10-02T10:08:15.436957Z","end":"2026-10-02T10:08:15.581859Z","steps":["trace[1110378136] 'process raft request' (duration: 143.767131ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:08:15.582024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.074343ms","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-02T10:08:15.58205Z","caller":"traceutil/trace.go:171","msg":"trace[706122319] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25885; }","duration":"140.144105ms","start":"2026-10-02T10:08:15.441895Z","end":"2026-10-02T10:08:15.582039Z","steps":["trace[706122319] 'agreement among raft nodes before linearized reading' (duration: 140.023902ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:08:15.943829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.50489ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T10:08:15.944039Z","caller":"traceutil/trace.go:171","msg":"trace[1938008218] transaction","detail":"{read_only:false; response_revision:25887; number_of_response:1; }","duration":"357.893929ms","start":"2026-10-02T10:08:15.586127Z","end":"2026-10-02T10:08:15.944021Z","steps":["trace[1938008218] 'process raft request' (duration: 357.814047ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:08:15.944104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:08:15.586108Z","time spent":"357.96422ms","remote":"127.0.0.1:47796","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-02T10:08:15.944114Z","caller":"traceutil/trace.go:171","msg":"trace[1837504413] transaction","detail":"{read_only:false; response_revision:25886; number_of_response:1; }","duration":"440.281304ms","start":"2026-10-02T10:08:15.503774Z","end":"2026-10-02T10:08:15.944055Z","steps":["trace[1837504413] 'process raft request' (duration: 196.466176ms)","trace[1837504413] 'compare' (duration: 243.322425ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T10:08:15.944292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:08:15.503748Z","time spent":"440.454349ms","remote":"127.0.0.1:47796","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-02T10:08:16.023957Z","caller":"traceutil/trace.go:171","msg":"trace[662530406] linearizableReadLoop","detail":"{readStateIndex:26736; appliedIndex:26733; }","duration":"139.638051ms","start":"2026-10-02T10:08:15.884291Z","end":"2026-10-02T10:08:16.023929Z","steps":["trace[662530406] 'read index received' (duration: 4.745216ms)","trace[662530406] 'applied index is now lower than readState.Index' (duration: 134.891835ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:08:16.024012Z","caller":"traceutil/trace.go:171","msg":"trace[1976374839] transaction","detail":"{read_only:false; response_revision:25888; number_of_response:1; }","duration":"249.852979ms","start":"2026-10-02T10:08:15.774126Z","end":"2026-10-02T10:08:16.023979Z","steps":["trace[1976374839] 'process raft request' (duration: 249.624143ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:08:16.024198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.875967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T10:08:16.02428Z","caller":"traceutil/trace.go:171","msg":"trace[1127955214] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25888; }","duration":"139.994731ms","start":"2026-10-02T10:08:15.884266Z","end":"2026-10-02T10:08:16.024261Z","steps":["trace[1127955214] 'agreement among raft nodes before linearized reading' (duration: 139.827557ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:08:16.229184Z","caller":"traceutil/trace.go:171","msg":"trace[627097132] linearizableReadLoop","detail":"{readStateIndex:26737; appliedIndex:26736; }","duration":"128.671259ms","start":"2026-10-02T10:08:16.100483Z","end":"2026-10-02T10:08:16.229154Z","steps":["trace[627097132] 'read index received' (duration: 58.812837ms)","trace[627097132] 'applied index is now lower than readState.Index' (duration: 69.853832ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T10:08:16.229434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.933356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-10-02T10:08:16.229483Z","caller":"traceutil/trace.go:171","msg":"trace[1969038933] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25889; }","duration":"129.028219ms","start":"2026-10-02T10:08:16.10044Z","end":"2026-10-02T10:08:16.229468Z","steps":["trace[1969038933] 'agreement among raft nodes before linearized reading' (duration: 128.819923ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:08:16.229632Z","caller":"traceutil/trace.go:171","msg":"trace[1249757252] transaction","detail":"{read_only:false; response_revision:25889; number_of_response:1; }","duration":"201.644884ms","start":"2026-10-02T10:08:16.027942Z","end":"2026-10-02T10:08:16.229587Z","steps":["trace[1249757252] 'process raft request' (duration: 131.432223ms)","trace[1249757252] 'compare' (duration: 69.647507ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T10:08:16.53923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.568396ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T10:08:16.539351Z","caller":"traceutil/trace.go:171","msg":"trace[319170114] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:25889; }","duration":"137.70779ms","start":"2026-10-02T10:08:16.401625Z","end":"2026-10-02T10:08:16.539332Z","steps":["trace[319170114] 'range keys from in-memory index tree' (duration: 137.548636ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:08:16.539566Z","caller":"traceutil/trace.go:171","msg":"trace[2063005123] transaction","detail":"{read_only:false; response_revision:25890; number_of_response:1; }","duration":"214.252991ms","start":"2026-10-02T10:08:16.32529Z","end":"2026-10-02T10:08:16.539543Z","steps":["trace[2063005123] 'process raft request' (duration: 116.814323ms)","trace[2063005123] 'compare' (duration: 97.16751ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T10:08:17.049373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.368455ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T10:08:17.049633Z","caller":"traceutil/trace.go:171","msg":"trace[1438978503] transaction","detail":"{read_only:false; response_revision:25891; number_of_response:1; }","duration":"723.959044ms","start":"2026-10-02T10:08:16.325659Z","end":"2026-10-02T10:08:17.049618Z","steps":["trace[1438978503] 'process raft request' (duration: 384.296712ms)","trace[1438978503] 'compare' (duration: 339.286793ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T10:08:17.049682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:08:16.325642Z","time spent":"724.021837ms","remote":"127.0.0.1:47796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T10:08:17.049803Z","caller":"traceutil/trace.go:171","msg":"trace[1812970151] linearizableReadLoop","detail":"{readStateIndex:26739; appliedIndex:26737; }","duration":"667.626093ms","start":"2026-10-02T10:08:16.382169Z","end":"2026-10-02T10:08:17.049796Z","steps":["trace[1812970151] 'read index received' (duration: 59.891296ms)","trace[1812970151] 'applied index is now lower than readState.Index' (duration: 607.733967ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T10:08:17.050001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.839469ms","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-02T10:08:17.050026Z","caller":"traceutil/trace.go:171","msg":"trace[464325367] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25892; }","duration":"667.90427ms","start":"2026-10-02T10:08:16.382116Z","end":"2026-10-02T10:08:17.05002Z","steps":["trace[464325367] 'agreement among raft nodes before linearized reading' (duration: 667.858729ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:08:17.05004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:08:16.382095Z","time spent":"667.941922ms","remote":"127.0.0.1:47974","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":"warn","ts":"2026-10-02T10:08:17.067106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.22391ms","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-02T10:08:17.067248Z","caller":"traceutil/trace.go:171","msg":"trace[1043608451] 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:25895; }","duration":"351.407266ms","start":"2026-10-02T10:08:16.715812Z","end":"2026-10-02T10:08:17.067219Z","steps":["trace[1043608451] 'agreement among raft nodes before linearized reading' (duration: 351.226551ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:08:17.067256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"666.418871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:561"} {"level":"warn","ts":"2026-10-02T10:08:17.067323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:08:16.715793Z","time spent":"351.509808ms","remote":"127.0.0.1:40958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":126,"response count":0,"response size":30,"request content":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true "} {"level":"warn","ts":"2026-10-02T10:08:17.067385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.511414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-02T10:08:17.067398Z","caller":"traceutil/trace.go:171","msg":"trace[752932004] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:25895; }","duration":"666.568685ms","start":"2026-10-02T10:08:16.400779Z","end":"2026-10-02T10:08:17.067348Z","steps":["trace[752932004] 'agreement among raft nodes before linearized reading' (duration: 666.337089ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:08:17.06744Z","caller":"traceutil/trace.go:171","msg":"trace[591207205] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:25895; }","duration":"215.604416ms","start":"2026-10-02T10:08:16.851823Z","end":"2026-10-02T10:08:17.067427Z","steps":["trace[591207205] 'agreement among raft nodes before linearized reading' (duration: 215.422062ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:08:17.067449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:08:16.400768Z","time spent":"666.669349ms","remote":"127.0.0.1:47796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":585,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-10-02T10:08:17.296975Z","caller":"traceutil/trace.go:171","msg":"trace[1129956486] linearizableReadLoop","detail":"{readStateIndex:26744; appliedIndex:26743; }","duration":"214.056035ms","start":"2026-10-02T10:08:17.082893Z","end":"2026-10-02T10:08:17.296949Z","steps":["trace[1129956486] 'read index received' (duration: 213.806898ms)","trace[1129956486] 'applied index is now lower than readState.Index' (duration: 247.927µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:08:17.297073Z","caller":"traceutil/trace.go:171","msg":"trace[1532300466] transaction","detail":"{read_only:false; response_revision:25896; number_of_response:1; }","duration":"222.870729ms","start":"2026-10-02T10:08:17.074169Z","end":"2026-10-02T10:08:17.297039Z","steps":["trace[1532300466] 'process raft request' (duration: 222.554761ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:08:17.297271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.039005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/routerinterfaces/\" range_end:\"/registry/openstack.k-orc.cloud/routerinterfaces0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T10:08:17.29735Z","caller":"traceutil/trace.go:171","msg":"trace[1663302553] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/routerinterfaces/; range_end:/registry/openstack.k-orc.cloud/routerinterfaces0; response_count:0; response_revision:25896; }","duration":"172.180299ms","start":"2026-10-02T10:08:17.125152Z","end":"2026-10-02T10:08:17.297332Z","steps":["trace[1663302553] 'agreement among raft nodes before linearized reading' (duration: 172.007934ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:08:17.297268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.345862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-10-02T10:08:17.297442Z","caller":"traceutil/trace.go:171","msg":"trace[1431839427] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:25896; }","duration":"214.573688ms","start":"2026-10-02T10:08:17.082845Z","end":"2026-10-02T10:08:17.297418Z","steps":["trace[1431839427] 'agreement among raft nodes before linearized reading' (duration: 214.206018ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:08:23.472662Z","caller":"traceutil/trace.go:171","msg":"trace[293369777] linearizableReadLoop","detail":"{readStateIndex:26802; appliedIndex:26801; }","duration":"106.56162ms","start":"2026-10-02T10:08:23.366071Z","end":"2026-10-02T10:08:23.472632Z","steps":["trace[293369777] 'read index received' (duration: 75.741289ms)","trace[293369777] 'applied index is now lower than readState.Index' (duration: 30.817131ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:08:23.472672Z","caller":"traceutil/trace.go:171","msg":"trace[162449800] transaction","detail":"{read_only:false; response_revision:25951; number_of_response:1; }","duration":"179.623197ms","start":"2026-10-02T10:08:23.29301Z","end":"2026-10-02T10:08:23.472633Z","steps":["trace[162449800] 'process raft request' (duration: 148.784255ms)","trace[162449800] 'compare' (duration: 30.40768ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T10:08:23.472792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.691333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T10:08:23.472845Z","caller":"traceutil/trace.go:171","msg":"trace[879258840] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools0; response_count:0; response_revision:25953; }","duration":"106.769166ms","start":"2026-10-02T10:08:23.366063Z","end":"2026-10-02T10:08:23.472832Z","steps":["trace[879258840] 'agreement among raft nodes before linearized reading' (duration: 106.647222ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:08:23.830687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.419991ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T10:08:23.83081Z","caller":"traceutil/trace.go:171","msg":"trace[1769982860] linearizableReadLoop","detail":"{readStateIndex:26805; appliedIndex:26804; }","duration":"352.616088ms","start":"2026-10-02T10:08:23.478181Z","end":"2026-10-02T10:08:23.830797Z","steps":["trace[1769982860] 'read index received' (duration: 106.92393ms)","trace[1769982860] 'applied index is now lower than readState.Index' (duration: 245.691338ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:08:23.830851Z","caller":"traceutil/trace.go:171","msg":"trace[1973842460] transaction","detail":"{read_only:false; response_revision:25954; number_of_response:1; }","duration":"353.07181ms","start":"2026-10-02T10:08:23.47774Z","end":"2026-10-02T10:08:23.830812Z","steps":["trace[1973842460] 'process raft request' (duration: 107.457754ms)","trace[1973842460] 'compare' (duration: 245.320188ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:08:23.830992Z","caller":"traceutil/trace.go:171","msg":"trace[2050602616] transaction","detail":"{read_only:false; response_revision:25955; number_of_response:1; }","duration":"222.021388ms","start":"2026-10-02T10:08:23.608961Z","end":"2026-10-02T10:08:23.830983Z","steps":["trace[2050602616] 'process raft request' (duration: 221.806342ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:08:23.830996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:08:23.477725Z","time spent":"353.202543ms","remote":"127.0.0.1:47796","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-02T10:08:23.831102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.347139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T10:08:23.831218Z","caller":"traceutil/trace.go:171","msg":"trace[949214911] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:25955; }","duration":"263.516853ms","start":"2026-10-02T10:08:23.567672Z","end":"2026-10-02T10:08:23.831189Z","steps":["trace[949214911] 'agreement among raft nodes before linearized reading' (duration: 263.311378ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:08:23.8312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.014448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-02T10:08:23.831282Z","caller":"traceutil/trace.go:171","msg":"trace[2065976481] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:25955; }","duration":"353.124481ms","start":"2026-10-02T10:08:23.478139Z","end":"2026-10-02T10:08:23.831263Z","steps":["trace[2065976481] 'agreement among raft nodes before linearized reading' (duration: 352.756132ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:08:23.831373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:08:23.478112Z","time spent":"353.227675ms","remote":"127.0.0.1:47810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"info","ts":"2026-10-02T10:08:24.049415Z","caller":"traceutil/trace.go:171","msg":"trace[1419125598] transaction","detail":"{read_only:false; response_revision:25956; number_of_response:1; }","duration":"172.473897ms","start":"2026-10-02T10:08:23.876917Z","end":"2026-10-02T10:08:24.049391Z","steps":["trace[1419125598] 'process raft request' (duration: 114.868272ms)","trace[1419125598] 'compare' (duration: 57.281516ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:08:24.42574Z","caller":"traceutil/trace.go:171","msg":"trace[1497442018] transaction","detail":"{read_only:false; response_revision:25958; number_of_response:1; }","duration":"371.853242ms","start":"2026-10-02T10:08:24.05384Z","end":"2026-10-02T10:08:24.425693Z","steps":["trace[1497442018] 'process raft request' (duration: 273.094499ms)","trace[1497442018] 'compare' (duration: 98.182077ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:08:24.425815Z","caller":"traceutil/trace.go:171","msg":"trace[462521957] linearizableReadLoop","detail":"{readStateIndex:26810; appliedIndex:26808; }","duration":"164.733111ms","start":"2026-10-02T10:08:24.261057Z","end":"2026-10-02T10:08:24.42579Z","steps":["trace[462521957] 'read index received' (duration: 65.922558ms)","trace[462521957] 'applied index is now lower than readState.Index' (duration: 98.809513ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T10:08:24.42592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:08:24.053817Z","time spent":"371.992585ms","remote":"127.0.0.1:47796","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-02T10:08:24.42598Z","caller":"traceutil/trace.go:171","msg":"trace[1629770027] transaction","detail":"{read_only:false; response_revision:25959; number_of_response:1; }","duration":"366.57535ms","start":"2026-10-02T10:08:24.059389Z","end":"2026-10-02T10:08:24.425964Z","steps":["trace[1629770027] 'process raft request' (duration: 366.304213ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:08:24.426115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:08:24.059376Z","time spent":"366.644591ms","remote":"127.0.0.1:35556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3290,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T10:08:24.426409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.358728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-02T10:08:24.42646Z","caller":"traceutil/trace.go:171","msg":"trace[1210962066] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25959; }","duration":"165.44592ms","start":"2026-10-02T10:08:24.261Z","end":"2026-10-02T10:08:24.426446Z","steps":["trace[1210962066] 'agreement among raft nodes before linearized reading' (duration: 165.308526ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:08:24.650695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.001622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-10-02T10:08:24.650806Z","caller":"traceutil/trace.go:171","msg":"trace[223026161] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25960; }","duration":"125.180716ms","start":"2026-10-02T10:08:24.525596Z","end":"2026-10-02T10:08:24.650777Z","steps":["trace[223026161] 'range keys from in-memory index tree' (duration: 124.773735ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:08:25.58307Z","caller":"traceutil/trace.go:171","msg":"trace[900725172] transaction","detail":"{read_only:false; response_revision:25969; number_of_response:1; }","duration":"104.869475ms","start":"2026-10-02T10:08:25.478166Z","end":"2026-10-02T10:08:25.583035Z","steps":["trace[900725172] 'process raft request' (duration: 104.729691ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:08:28.255302Z","caller":"traceutil/trace.go:171","msg":"trace[2038452477] transaction","detail":"{read_only:false; response_revision:25987; number_of_response:1; }","duration":"105.830141ms","start":"2026-10-02T10:08:28.149424Z","end":"2026-10-02T10:08:28.255254Z","steps":["trace[2038452477] 'process raft request' (duration: 105.287606ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:08:35.549239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.005794ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T10:08:35.549489Z","caller":"traceutil/trace.go:171","msg":"trace[362098474] linearizableReadLoop","detail":"{readStateIndex:26893; appliedIndex:26889; }","duration":"361.891965ms","start":"2026-10-02T10:08:35.187581Z","end":"2026-10-02T10:08:35.549473Z","steps":["trace[362098474] 'read index received' (duration: 18.532184ms)","trace[362098474] 'applied index is now lower than readState.Index' (duration: 343.358871ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:08:35.549759Z","caller":"traceutil/trace.go:171","msg":"trace[1362979735] transaction","detail":"{read_only:false; response_revision:26038; number_of_response:1; }","duration":"362.325616ms","start":"2026-10-02T10:08:35.187419Z","end":"2026-10-02T10:08:35.549744Z","steps":["trace[1362979735] 'process raft request' (duration: 198.715096ms)","trace[1362979735] 'compare' (duration: 162.556833ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:08:35.549773Z","caller":"traceutil/trace.go:171","msg":"trace[538017628] transaction","detail":"{read_only:false; response_revision:26040; number_of_response:1; }","duration":"362.249484ms","start":"2026-10-02T10:08:35.187492Z","end":"2026-10-02T10:08:35.549742Z","steps":["trace[538017628] 'process raft request' (duration: 361.937796ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:08:35.549783Z","caller":"traceutil/trace.go:171","msg":"trace[1295319948] transaction","detail":"{read_only:false; response_revision:26039; number_of_response:1; }","duration":"362.267655ms","start":"2026-10-02T10:08:35.18748Z","end":"2026-10-02T10:08:35.549747Z","steps":["trace[1295319948] 'process raft request' (duration: 361.866954ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:08:35.549829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:08:35.187389Z","time spent":"362.405128ms","remote":"127.0.0.1:47796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T10:08:35.549919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.290725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-10-02T10:08:35.549945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:08:35.187454Z","time spent":"362.402248ms","remote":"127.0.0.1:47796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T10:08:35.55002Z","caller":"traceutil/trace.go:171","msg":"trace[204969576] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26040; }","duration":"362.45011ms","start":"2026-10-02T10:08:35.187543Z","end":"2026-10-02T10:08:35.549993Z","steps":["trace[204969576] 'agreement among raft nodes before linearized reading' (duration: 362.155502ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:08:35.550084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:08:35.187529Z","time spent":"362.540072ms","remote":"127.0.0.1:47796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-02T10:08:35.549944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:08:35.187467Z","time spent":"362.387958ms","remote":"127.0.0.1:47796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T10:08:35.550216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.887701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:32 size:277957"} {"level":"info","ts":"2026-10-02T10:08:35.550276Z","caller":"traceutil/trace.go:171","msg":"trace[2091667753] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:32; response_revision:26040; }","duration":"147.991594ms","start":"2026-10-02T10:08:35.402269Z","end":"2026-10-02T10:08:35.550261Z","steps":["trace[2091667753] 'agreement among raft nodes before linearized reading' (duration: 147.48631ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:08:35.551112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.228716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-10-02T10:08:35.551236Z","caller":"traceutil/trace.go:171","msg":"trace[808822297] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:26040; }","duration":"260.421441ms","start":"2026-10-02T10:08:35.290797Z","end":"2026-10-02T10:08:35.551219Z","steps":["trace[808822297] 'agreement among raft nodes before linearized reading' (duration: 258.962942ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:08:35.769322Z","caller":"traceutil/trace.go:171","msg":"trace[1716812913] transaction","detail":"{read_only:false; response_revision:26042; number_of_response:1; }","duration":"139.783455ms","start":"2026-10-02T10:08:35.629513Z","end":"2026-10-02T10:08:35.769297Z","steps":["trace[1716812913] 'process raft request' (duration: 139.030575ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:08:35.780377Z","caller":"traceutil/trace.go:171","msg":"trace[1018875622] linearizableReadLoop","detail":"{readStateIndex:26896; appliedIndex:26894; }","duration":"109.654192ms","start":"2026-10-02T10:08:35.670681Z","end":"2026-10-02T10:08:35.780336Z","steps":["trace[1018875622] 'read index received' (duration: 97.95348ms)","trace[1018875622] 'applied index is now lower than readState.Index' (duration: 11.698752ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:08:35.780483Z","caller":"traceutil/trace.go:171","msg":"trace[211065730] transaction","detail":"{read_only:false; response_revision:26043; number_of_response:1; }","duration":"139.424815ms","start":"2026-10-02T10:08:35.641021Z","end":"2026-10-02T10:08:35.780446Z","steps":["trace[211065730] 'process raft request' (duration: 139.086426ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:08:35.780599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.879569ms","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-02T10:08:35.78069Z","caller":"traceutil/trace.go:171","msg":"trace[520750424] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:26043; }","duration":"110.039953ms","start":"2026-10-02T10:08:35.670629Z","end":"2026-10-02T10:08:35.780669Z","steps":["trace[520750424] 'agreement among raft nodes before linearized reading' (duration: 109.854528ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:08:37.568182Z","caller":"traceutil/trace.go:171","msg":"trace[1723777245] transaction","detail":"{read_only:false; response_revision:26052; number_of_response:1; }","duration":"355.32962ms","start":"2026-10-02T10:08:37.212823Z","end":"2026-10-02T10:08:37.568152Z","steps":["trace[1723777245] 'process raft request' (duration: 354.661892ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:08:37.568354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:08:37.212798Z","time spent":"355.458303ms","remote":"127.0.0.1:47796","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-02T10:08:37.568641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.455824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3308"} {"level":"info","ts":"2026-10-02T10:08:37.568737Z","caller":"traceutil/trace.go:171","msg":"trace[1740693905] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:26052; }","duration":"265.713402ms","start":"2026-10-02T10:08:37.303006Z","end":"2026-10-02T10:08:37.568719Z","steps":["trace[1740693905] 'agreement among raft nodes before linearized reading' (duration: 265.237419ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:08:37.568206Z","caller":"traceutil/trace.go:171","msg":"trace[1483736882] linearizableReadLoop","detail":"{readStateIndex:26905; appliedIndex:26904; }","duration":"265.033244ms","start":"2026-10-02T10:08:37.303094Z","end":"2026-10-02T10:08:37.568128Z","steps":["trace[1483736882] 'read index received' (duration: 264.375156ms)","trace[1483736882] 'applied index is now lower than readState.Index' (duration: 656.148µs)"],"step_count":2} {"level":"warn","ts":"2026-10-02T10:08:37.882565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.211925ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T10:08:37.882783Z","caller":"traceutil/trace.go:171","msg":"trace[1178685911] linearizableReadLoop","detail":"{readStateIndex:26908; appliedIndex:26905; }","duration":"306.800518ms","start":"2026-10-02T10:08:37.575966Z","end":"2026-10-02T10:08:37.882766Z","steps":["trace[1178685911] 'read index received' (duration: 25.245473ms)","trace[1178685911] 'applied index is now lower than readState.Index' (duration: 281.554075ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:08:37.882844Z","caller":"traceutil/trace.go:171","msg":"trace[1548293207] transaction","detail":"{read_only:false; response_revision:26053; number_of_response:1; }","duration":"326.929883ms","start":"2026-10-02T10:08:37.555903Z","end":"2026-10-02T10:08:37.882832Z","steps":["trace[1548293207] 'process raft request' (duration: 45.379369ms)","trace[1548293207] 'compare' (duration: 280.884176ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T10:08:37.882923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:08:37.55586Z","time spent":"327.002655ms","remote":"127.0.0.1:47796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T10:08:37.88316Z","caller":"traceutil/trace.go:171","msg":"trace[1899344012] transaction","detail":"{read_only:false; response_revision:26055; number_of_response:1; }","duration":"324.052337ms","start":"2026-10-02T10:08:37.559069Z","end":"2026-10-02T10:08:37.883122Z","steps":["trace[1899344012] 'process raft request' (duration: 323.655927ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:08:37.883262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:08:37.559033Z","time spent":"324.17355ms","remote":"127.0.0.1:47796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T10:08:37.883402Z","caller":"traceutil/trace.go:171","msg":"trace[1509856635] transaction","detail":"{read_only:false; response_revision:26054; number_of_response:1; }","duration":"325.140116ms","start":"2026-10-02T10:08:37.558254Z","end":"2026-10-02T10:08:37.883394Z","steps":["trace[1509856635] 'process raft request' (duration: 324.390335ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:08:37.883446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:08:37.558246Z","time spent":"325.170966ms","remote":"127.0.0.1:47796","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-02T10:08:37.884942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.946485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" ","response":"range_response_count:1 size:1312"} {"level":"info","ts":"2026-10-02T10:08:37.885026Z","caller":"traceutil/trace.go:171","msg":"trace[1842322943] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:1; response_revision:26055; }","duration":"309.075918ms","start":"2026-10-02T10:08:37.575932Z","end":"2026-10-02T10:08:37.885008Z","steps":["trace[1842322943] 'agreement among raft nodes before linearized reading' (duration: 308.752809ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:08:37.885067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:08:37.575917Z","time spent":"309.14291ms","remote":"127.0.0.1:47646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":1336,"request content":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" "} {"level":"info","ts":"2026-10-02T10:08:37.940508Z","caller":"traceutil/trace.go:171","msg":"trace[1139186113] transaction","detail":"{read_only:false; response_revision:26056; number_of_response:1; }","duration":"164.973997ms","start":"2026-10-02T10:08:37.775514Z","end":"2026-10-02T10:08:37.940488Z","steps":["trace[1139186113] 'process raft request' (duration: 164.858054ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:08:38.19398Z","caller":"traceutil/trace.go:171","msg":"trace[1943807333] linearizableReadLoop","detail":"{readStateIndex:26911; appliedIndex:26908; }","duration":"311.149323ms","start":"2026-10-02T10:08:37.882809Z","end":"2026-10-02T10:08:38.193958Z","steps":["trace[1943807333] 'read index received' (duration: 57.654266ms)","trace[1943807333] 'applied index is now lower than readState.Index' (duration: 253.493957ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:08:38.194056Z","caller":"traceutil/trace.go:171","msg":"trace[1775610478] transaction","detail":"{read_only:false; response_revision:26058; number_of_response:1; }","duration":"311.951085ms","start":"2026-10-02T10:08:37.882051Z","end":"2026-10-02T10:08:38.194002Z","steps":["trace[1775610478] 'process raft request' (duration: 311.854142ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:08:38.194081Z","caller":"traceutil/trace.go:171","msg":"trace[837676162] transaction","detail":"{read_only:false; response_revision:26057; number_of_response:1; }","duration":"408.397014ms","start":"2026-10-02T10:08:37.785648Z","end":"2026-10-02T10:08:38.194045Z","steps":["trace[837676162] 'process raft request' (duration: 408.091966ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:08:38.194217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.976414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-10-02T10:08:38.194222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:08:37.882021Z","time spent":"312.109019ms","remote":"127.0.0.1:47796","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-02T10:08:38.194251Z","caller":"traceutil/trace.go:171","msg":"trace[1201903429] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26058; }","duration":"569.077128ms","start":"2026-10-02T10:08:37.625163Z","end":"2026-10-02T10:08:38.19424Z","steps":["trace[1201903429] 'agreement among raft nodes before linearized reading' (duration: 568.925963ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:08:38.194253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:08:37.785622Z","time spent":"408.529188ms","remote":"127.0.0.1:47796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T10:08:38.194281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:08:37.625139Z","time spent":"569.134028ms","remote":"127.0.0.1:47796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-02T10:08:38.19425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.451988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T10:08:38.194335Z","caller":"traceutil/trace.go:171","msg":"trace[651828804] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26058; }","duration":"309.574192ms","start":"2026-10-02T10:08:37.884741Z","end":"2026-10-02T10:08:38.194315Z","steps":["trace[651828804] 'agreement among raft nodes before linearized reading' (duration: 309.424428ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:08:38.19438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:08:37.884725Z","time spent":"309.647273ms","remote":"127.0.0.1:47542","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-02T10:08:38.194355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.790469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"warn","ts":"2026-10-02T10:08:38.194444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.115136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1634"} {"level":"info","ts":"2026-10-02T10:08:38.194462Z","caller":"traceutil/trace.go:171","msg":"trace[1173036520] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26058; }","duration":"497.964502ms","start":"2026-10-02T10:08:37.69647Z","end":"2026-10-02T10:08:38.194435Z","steps":["trace[1173036520] 'agreement among raft nodes before linearized reading' (duration: 497.625614ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:08:38.19451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:08:37.696442Z","time spent":"498.058135ms","remote":"127.0.0.1:47744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-10-02T10:08:38.194526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.911179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-manila-nodes\" ","response":"range_response_count:1 size:1258"} {"level":"info","ts":"2026-10-02T10:08:38.194476Z","caller":"traceutil/trace.go:171","msg":"trace[1025695649] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:26058; }","duration":"199.179398ms","start":"2026-10-02T10:08:37.995284Z","end":"2026-10-02T10:08:38.194464Z","steps":["trace[1025695649] 'agreement among raft nodes before linearized reading' (duration: 199.092756ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:08:38.194561Z","caller":"traceutil/trace.go:171","msg":"trace[1452633219] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-manila-nodes; range_end:; response_count:1; response_revision:26058; }","duration":"303.971011ms","start":"2026-10-02T10:08:37.89058Z","end":"2026-10-02T10:08:38.194551Z","steps":["trace[1452633219] 'agreement among raft nodes before linearized reading' (duration: 303.801067ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:08:38.194588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T10:08:37.890558Z","time spent":"304.024733ms","remote":"127.0.0.1:47750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":1282,"request content":"key:\"/registry/services/specs/openstack/rabbitmq-manila-nodes\" "} {"level":"warn","ts":"2026-10-02T10:08:38.19454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.660242ms","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-02T10:08:38.19468Z","caller":"traceutil/trace.go:171","msg":"trace[1296954998] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26058; }","duration":"285.832487ms","start":"2026-10-02T10:08:37.908806Z","end":"2026-10-02T10:08:38.194638Z","steps":["trace[1296954998] 'agreement among raft nodes before linearized reading' (duration: 285.457938ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:08:39.770319Z","caller":"traceutil/trace.go:171","msg":"trace[301769076] transaction","detail":"{read_only:false; response_revision:26065; number_of_response:1; }","duration":"190.820686ms","start":"2026-10-02T10:08:39.579455Z","end":"2026-10-02T10:08:39.770275Z","steps":["trace[301769076] 'process raft request' (duration: 190.546579ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:08:40.047173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.359634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T10:08:40.047292Z","caller":"traceutil/trace.go:171","msg":"trace[308010489] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26065; }","duration":"163.54914ms","start":"2026-10-02T10:08:39.88372Z","end":"2026-10-02T10:08:40.047269Z","steps":["trace[308010489] 'range keys from in-memory index tree' (duration: 163.271342ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:08:40.047373Z","caller":"traceutil/trace.go:171","msg":"trace[640950902] transaction","detail":"{read_only:false; response_revision:26066; number_of_response:1; }","duration":"158.144465ms","start":"2026-10-02T10:08:39.889183Z","end":"2026-10-02T10:08:40.047328Z","steps":["trace[640950902] 'process raft request' (duration: 123.002828ms)","trace[640950902] 'compare' (duration: 35.012274ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:08:40.057221Z","caller":"traceutil/trace.go:171","msg":"trace[418923558] transaction","detail":"{read_only:false; response_revision:26068; number_of_response:1; }","duration":"167.280558ms","start":"2026-10-02T10:08:39.889916Z","end":"2026-10-02T10:08:40.057196Z","steps":["trace[418923558] 'process raft request' (duration: 167.189975ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:08:40.057249Z","caller":"traceutil/trace.go:171","msg":"trace[316360568] transaction","detail":"{read_only:false; response_revision:26067; number_of_response:1; }","duration":"167.74194ms","start":"2026-10-02T10:08:39.889472Z","end":"2026-10-02T10:08:40.057214Z","steps":["trace[316360568] 'process raft request' (duration: 167.479673ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:08:40.057541Z","caller":"traceutil/trace.go:171","msg":"trace[443887731] transaction","detail":"{read_only:false; response_revision:26069; number_of_response:1; }","duration":"112.207241ms","start":"2026-10-02T10:08:39.945314Z","end":"2026-10-02T10:08:40.057521Z","steps":["trace[443887731] 'process raft request' (duration: 111.834ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:08:46.207662Z","caller":"traceutil/trace.go:171","msg":"trace[94111409] transaction","detail":"{read_only:false; response_revision:26113; number_of_response:1; }","duration":"132.909011ms","start":"2026-10-02T10:08:46.074725Z","end":"2026-10-02T10:08:46.207634Z","steps":["trace[94111409] 'process raft request' (duration: 95.337101ms)","trace[94111409] 'compare' (duration: 37.226181ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:08:46.207996Z","caller":"traceutil/trace.go:171","msg":"trace[510210064] transaction","detail":"{read_only:false; response_revision:26114; number_of_response:1; }","duration":"130.475407ms","start":"2026-10-02T10:08:46.07745Z","end":"2026-10-02T10:08:46.207925Z","steps":["trace[510210064] 'process raft request' (duration: 130.096527ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:08:46.208065Z","caller":"traceutil/trace.go:171","msg":"trace[690654118] transaction","detail":"{read_only:false; response_revision:26115; number_of_response:1; }","duration":"121.462027ms","start":"2026-10-02T10:08:46.086562Z","end":"2026-10-02T10:08:46.208024Z","steps":["trace[690654118] 'process raft request' (duration: 121.229661ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:09:04.886415Z","caller":"traceutil/trace.go:171","msg":"trace[32535049] transaction","detail":"{read_only:false; response_revision:26263; number_of_response:1; }","duration":"166.379484ms","start":"2026-10-02T10:09:04.72Z","end":"2026-10-02T10:09:04.88638Z","steps":["trace[32535049] 'process raft request' (duration: 166.149817ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T10:09:05.152806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.493528ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T10:09:05.152971Z","caller":"traceutil/trace.go:171","msg":"trace[98235136] transaction","detail":"{read_only:false; response_revision:26264; number_of_response:1; }","duration":"222.693846ms","start":"2026-10-02T10:09:04.930241Z","end":"2026-10-02T10:09:05.152935Z","steps":["trace[98235136] 'process raft request' (duration: 73.978252ms)","trace[98235136] 'compare' (duration: 148.326433ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:09:05.409523Z","caller":"traceutil/trace.go:171","msg":"trace[1275583633] transaction","detail":"{read_only:false; response_revision:26265; number_of_response:1; }","duration":"199.739123ms","start":"2026-10-02T10:09:05.209745Z","end":"2026-10-02T10:09:05.409484Z","steps":["trace[1275583633] 'process raft request' (duration: 120.84781ms)","trace[1275583633] 'compare' (duration: 78.720519ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T10:09:06.299159Z","caller":"traceutil/trace.go:171","msg":"trace[1289569969] transaction","detail":"{read_only:false; response_revision:26268; number_of_response:1; }","duration":"129.802949ms","start":"2026-10-02T10:09:06.169301Z","end":"2026-10-02T10:09:06.299104Z","steps":["trace[1289569969] 'process raft request' (duration: 127.144927ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:09:06.422692Z","caller":"traceutil/trace.go:171","msg":"trace[1284420108] transaction","detail":"{read_only:false; response_revision:26271; number_of_response:1; }","duration":"103.325974ms","start":"2026-10-02T10:09:06.319338Z","end":"2026-10-02T10:09:06.422664Z","steps":["trace[1284420108] 'process raft request' (duration: 103.055346ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:09:06.422841Z","caller":"traceutil/trace.go:171","msg":"trace[1092602222] transaction","detail":"{read_only:false; response_revision:26272; number_of_response:1; }","duration":"100.861269ms","start":"2026-10-02T10:09:06.321948Z","end":"2026-10-02T10:09:06.42281Z","steps":["trace[1092602222] 'process raft request' (duration: 100.675684ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T10:12:49.881452Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25611} {"level":"info","ts":"2026-10-02T10:12:50.051411Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25611,"took":"166.877928ms","hash":2116182916,"current-db-size-bytes":60325888,"current-db-size":"60 MB","current-db-size-in-use-bytes":35938304,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-02T10:12:50.051478Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2116182916,"revision":25611,"compact-revision":22177} {"level":"info","ts":"2026-10-02T10:13:56.385313Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a0a01fc7fbf6be45","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-02T10:13:56.391736Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-02T10:13:56.391855Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-02T10:17:49.892973Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28568} {"level":"info","ts":"2026-10-02T10:17:50.036745Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28568,"took":"140.746001ms","hash":2998823602,"current-db-size-bytes":60747776,"current-db-size":"61 MB","current-db-size-in-use-bytes":32346112,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-02T10:17:50.036809Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2998823602,"revision":28568,"compact-revision":25611} {"level":"info","ts":"2026-10-02T10:22:49.900221Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30811} {"level":"info","ts":"2026-10-02T10:22:50.048368Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30811,"took":"143.293069ms","hash":4003031758,"current-db-size-bytes":60747776,"current-db-size":"61 MB","current-db-size-in-use-bytes":31420416,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-02T10:22:50.048437Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4003031758,"revision":30811,"compact-revision":28568} {"level":"info","ts":"2026-10-02T10:27:49.907757Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33073} {"level":"info","ts":"2026-10-02T10:27:50.085774Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33073,"took":"173.215579ms","hash":371999398,"current-db-size-bytes":61788160,"current-db-size":"62 MB","current-db-size-in-use-bytes":31154176,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-02T10:27:50.08584Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":371999398,"revision":33073,"compact-revision":30811} {"level":"info","ts":"2026-10-02T10:32:49.924578Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35300} {"level":"info","ts":"2026-10-02T10:32:50.060895Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35300,"took":"133.189593ms","hash":2512025591,"current-db-size-bytes":63102976,"current-db-size":"63 MB","current-db-size-in-use-bytes":32903168,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-02T10:32:50.060962Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2512025591,"revision":35300,"compact-revision":33073} {"level":"info","ts":"2026-10-02T10:35:22.484414Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a0a01fc7fbf6be45","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-02T10:35:22.490035Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-02T10:35:22.49027Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-02T10:37:49.93683Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37595} {"level":"info","ts":"2026-10-02T10:37:50.06911Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37595,"took":"126.664909ms","hash":3036644896,"current-db-size-bytes":65224704,"current-db-size":"65 MB","current-db-size-in-use-bytes":33136640,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-02T10:37:50.069184Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3036644896,"revision":37595,"compact-revision":35300} {"level":"info","ts":"2026-10-02T10:42:49.942845Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39875} {"level":"info","ts":"2026-10-02T10:42:50.082756Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39875,"took":"130.053453ms","hash":3510323156,"current-db-size-bytes":65224704,"current-db-size":"65 MB","current-db-size-in-use-bytes":31281152,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-02T10:42:50.082893Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3510323156,"revision":39875,"compact-revision":37595}