{"level":"warn","ts":"2026-06-08T06:23:32.040794Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-08T06:23:32.040887Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.113: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.113:2380","--initial-cluster=instance=https://199.204.45.113:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.113:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.113: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-06-08T06:23:32.040961Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-08T06:23:32.040971Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-06-08T06:23:32.04099Z","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-06-08T06:23:32.041546Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"]} {"level":"info","ts":"2026-06-08T06:23:32.041616Z","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.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.113: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-06-08T06:23:32.045796Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.939908ms"} {"level":"info","ts":"2026-06-08T06:23:32.052372Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a31e123290fcf0df","cluster-id":"37661d6ee45ed9e8"} {"level":"info","ts":"2026-06-08T06:23:32.052497Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=()"} {"level":"info","ts":"2026-06-08T06:23:32.0526Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 0"} {"level":"info","ts":"2026-06-08T06:23:32.052633Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a31e123290fcf0df [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-08T06:23:32.052668Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 1"} {"level":"info","ts":"2026-06-08T06:23:32.052791Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"warn","ts":"2026-06-08T06:23:32.061844Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-08T06:23:32.063394Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-08T06:23:32.064682Z","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-06-08T06:23:32.066726Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a31e123290fcf0df","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-08T06:23:32.067005Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a31e123290fcf0df","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-08T06:23:32.067065Z","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-06-08T06:23:32.067309Z","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-06-08T06:23:32.067339Z","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-06-08T06:23:32.0677Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"info","ts":"2026-06-08T06:23:32.06832Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","added-peer-id":"a31e123290fcf0df","added-peer-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-06-08T06:23:32.070528Z","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-06-08T06:23:32.070662Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-06-08T06:23:32.070704Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-06-08T06:23:32.070727Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a31e123290fcf0df","initial-advertise-peer-urls":["https://199.204.45.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-08T06:23:32.070785Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-08T06:23:32.754221Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df is starting a new election at term 1"} {"level":"info","ts":"2026-06-08T06:23:32.754281Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became pre-candidate at term 1"} {"level":"info","ts":"2026-06-08T06:23:32.754305Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgPreVoteResp from a31e123290fcf0df at term 1"} {"level":"info","ts":"2026-06-08T06:23:32.754325Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became candidate at term 2"} {"level":"info","ts":"2026-06-08T06:23:32.754337Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgVoteResp from a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-06-08T06:23:32.754353Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became leader at term 2"} {"level":"info","ts":"2026-06-08T06:23:32.754367Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a31e123290fcf0df elected leader a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-06-08T06:23:32.755419Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a31e123290fcf0df","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.113:2379]}","request-path":"/0/members/a31e123290fcf0df/attributes","cluster-id":"37661d6ee45ed9e8","publish-timeout":"7s"} {"level":"info","ts":"2026-06-08T06:23:32.75547Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-08T06:23:32.755634Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-08T06:23:32.755731Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-08T06:23:32.755788Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-08T06:23:32.755826Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-08T06:23:32.759035Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.113:2379"} {"level":"info","ts":"2026-06-08T06:23:32.759279Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-08T06:23:32.759655Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","cluster-version":"3.5"} {"level":"info","ts":"2026-06-08T06:23:32.759863Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-08T06:23:32.75991Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-08T06:23:52.717502Z","caller":"traceutil/trace.go:171","msg":"trace[852254855] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"121.762095ms","start":"2026-06-08T06:23:52.595711Z","end":"2026-06-08T06:23:52.717473Z","steps":["trace[852254855] 'process raft request' (duration: 121.596771ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T06:24:25.461428Z","caller":"traceutil/trace.go:171","msg":"trace[752546262] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"173.882847ms","start":"2026-06-08T06:24:25.287509Z","end":"2026-06-08T06:24:25.461391Z","steps":["trace[752546262] 'process raft request' (duration: 173.243684ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T06:24:25.813718Z","caller":"traceutil/trace.go:171","msg":"trace[54118532] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:634; }","duration":"234.009028ms","start":"2026-06-08T06:24:25.579679Z","end":"2026-06-08T06:24:25.813689Z","steps":["trace[54118532] 'read index received' (duration: 211.809308ms)","trace[54118532] 'applied index is now lower than readState.Index' (duration: 22.19868ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T06:24:25.813832Z","caller":"traceutil/trace.go:171","msg":"trace[482357664] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"295.921146ms","start":"2026-06-08T06:24:25.517864Z","end":"2026-06-08T06:24:25.813785Z","steps":["trace[482357664] 'process raft request' (duration: 273.45346ms)","trace[482357664] 'compare' (duration: 22.228171ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T06:24:25.813955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.463838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.113\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-08T06:24:25.81404Z","caller":"traceutil/trace.go:171","msg":"trace[972603833] range","detail":"{range_begin:/registry/masterleases/199.204.45.113; range_end:; response_count:1; response_revision:612; }","duration":"119.605191ms","start":"2026-06-08T06:24:25.694424Z","end":"2026-06-08T06:24:25.814029Z","steps":["trace[972603833] 'agreement among raft nodes before linearized reading' (duration: 119.424387ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T06:24:25.814134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.448467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-08T06:24:25.814277Z","caller":"traceutil/trace.go:171","msg":"trace[1895117512] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:612; }","duration":"234.571919ms","start":"2026-06-08T06:24:25.579657Z","end":"2026-06-08T06:24:25.814229Z","steps":["trace[1895117512] 'agreement among raft nodes before linearized reading' (duration: 234.184452ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T06:24:25.97094Z","caller":"traceutil/trace.go:171","msg":"trace[428078948] linearizableReadLoop","detail":"{readStateIndex:637; appliedIndex:635; }","duration":"142.448464ms","start":"2026-06-08T06:24:25.828468Z","end":"2026-06-08T06:24:25.970916Z","steps":["trace[428078948] 'read index received' (duration: 114.608981ms)","trace[428078948] 'applied index is now lower than readState.Index' (duration: 27.838302ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T06:24:25.97107Z","caller":"traceutil/trace.go:171","msg":"trace[1445471370] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"151.702317ms","start":"2026-06-08T06:24:25.819358Z","end":"2026-06-08T06:24:25.971061Z","steps":["trace[1445471370] 'process raft request' (duration: 151.436782ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T06:24:25.971254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.779371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T06:24:25.971297Z","caller":"traceutil/trace.go:171","msg":"trace[1071327599] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:613; }","duration":"142.873072ms","start":"2026-06-08T06:24:25.828411Z","end":"2026-06-08T06:24:25.971284Z","steps":["trace[1071327599] 'agreement among raft nodes before linearized reading' (duration: 142.80268ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T06:24:25.971307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.874774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-q7fvv\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-06-08T06:24:25.971366Z","caller":"traceutil/trace.go:171","msg":"trace[67077195] range","detail":"{range_begin:/registry/pods/kube-system/cilium-q7fvv; range_end:; response_count:1; response_revision:613; }","duration":"126.002417ms","start":"2026-06-08T06:24:25.845341Z","end":"2026-06-08T06:24:25.971343Z","steps":["trace[67077195] 'agreement among raft nodes before linearized reading' (duration: 125.794243ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T06:24:41.131428Z","caller":"traceutil/trace.go:171","msg":"trace[1726497839] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"106.818168ms","start":"2026-06-08T06:24:41.024576Z","end":"2026-06-08T06:24:41.131394Z","steps":["trace[1726497839] 'process raft request' (duration: 106.642695ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T06:25:58.413676Z","caller":"traceutil/trace.go:171","msg":"trace[1511727416] linearizableReadLoop","detail":"{readStateIndex:1920; appliedIndex:1919; }","duration":"124.19943ms","start":"2026-06-08T06:25:58.289455Z","end":"2026-06-08T06:25:58.413654Z","steps":["trace[1511727416] 'read index received' (duration: 124.058276ms)","trace[1511727416] 'applied index is now lower than readState.Index' (duration: 140.234µs)"],"step_count":2} {"level":"info","ts":"2026-06-08T06:25:58.413754Z","caller":"traceutil/trace.go:171","msg":"trace[1362056560] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"166.096529ms","start":"2026-06-08T06:25:58.247625Z","end":"2026-06-08T06:25:58.413721Z","steps":["trace[1362056560] 'process raft request' (duration: 165.876884ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T06:25:58.413927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.460165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-829e17e7-d1f4-457c-b697-19320209f51f\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-06-08T06:25:58.413954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.089101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-829e17e7-d1f4-457c-b697-19320209f51f\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-06-08T06:25:58.414009Z","caller":"traceutil/trace.go:171","msg":"trace[253722752] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-829e17e7-d1f4-457c-b697-19320209f51f; range_end:; response_count:1; response_revision:1848; }","duration":"103.197934ms","start":"2026-06-08T06:25:58.310799Z","end":"2026-06-08T06:25:58.413996Z","steps":["trace[253722752] 'agreement among raft nodes before linearized reading' (duration: 103.00299ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T06:25:58.414011Z","caller":"traceutil/trace.go:171","msg":"trace[1392390934] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-829e17e7-d1f4-457c-b697-19320209f51f; range_end:; response_count:1; response_revision:1848; }","duration":"124.603077ms","start":"2026-06-08T06:25:58.289389Z","end":"2026-06-08T06:25:58.413992Z","steps":["trace[1392390934] 'agreement among raft nodes before linearized reading' (duration: 124.365093ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T06:25:58.53234Z","caller":"traceutil/trace.go:171","msg":"trace[708628228] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"113.909871ms","start":"2026-06-08T06:25:58.418389Z","end":"2026-06-08T06:25:58.532299Z","steps":["trace[708628228] 'process raft request' (duration: 80.456642ms)","trace[708628228] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/plndr-svcs-lock; req_size:420; } (duration: 33.265744ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T06:25:58.532558Z","caller":"traceutil/trace.go:171","msg":"trace[1476256446] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"113.447281ms","start":"2026-06-08T06:25:58.41909Z","end":"2026-06-08T06:25:58.532537Z","steps":["trace[1476256446] 'process raft request' (duration: 113.147685ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T06:25:58.532651Z","caller":"traceutil/trace.go:171","msg":"trace[841571544] transaction","detail":"{read_only:false; response_revision:1852; number_of_response:1; }","duration":"111.786197ms","start":"2026-06-08T06:25:58.420851Z","end":"2026-06-08T06:25:58.532637Z","steps":["trace[841571544] 'process raft request' (duration: 111.627744ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T06:25:58.826347Z","caller":"traceutil/trace.go:171","msg":"trace[1186411059] linearizableReadLoop","detail":"{readStateIndex:1925; appliedIndex:1924; }","duration":"247.785906ms","start":"2026-06-08T06:25:58.578498Z","end":"2026-06-08T06:25:58.826284Z","steps":["trace[1186411059] 'read index received' (duration: 151.276898ms)","trace[1186411059] 'applied index is now lower than readState.Index' (duration: 96.505648ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T06:25:58.826494Z","caller":"traceutil/trace.go:171","msg":"trace[295511895] transaction","detail":"{read_only:false; response_revision:1853; number_of_response:1; }","duration":"281.929838ms","start":"2026-06-08T06:25:58.544537Z","end":"2026-06-08T06:25:58.826467Z","steps":["trace[295511895] 'process raft request' (duration: 185.223426ms)","trace[295511895] 'compare' (duration: 96.207252ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T06:25:58.826529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.021991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T06:25:58.826566Z","caller":"traceutil/trace.go:171","msg":"trace[287890868] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1854; }","duration":"248.107253ms","start":"2026-06-08T06:25:58.578448Z","end":"2026-06-08T06:25:58.826555Z","steps":["trace[287890868] 'agreement among raft nodes before linearized reading' (duration: 248.021521ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T06:25:58.82673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.086159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-08T06:25:58.826812Z","caller":"traceutil/trace.go:171","msg":"trace[460327937] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1854; }","duration":"164.1936ms","start":"2026-06-08T06:25:58.662597Z","end":"2026-06-08T06:25:58.82679Z","steps":["trace[460327937] 'agreement among raft nodes before linearized reading' (duration: 163.991356ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T06:26:49.033893Z","caller":"traceutil/trace.go:171","msg":"trace[649078067] transaction","detail":"{read_only:false; response_revision:2180; number_of_response:1; }","duration":"186.737477ms","start":"2026-06-08T06:26:48.847084Z","end":"2026-06-08T06:26:49.033821Z","steps":["trace[649078067] 'process raft request' (duration: 103.735774ms)","trace[649078067] 'compare' (duration: 82.685556ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T06:28:11.426311Z","caller":"traceutil/trace.go:171","msg":"trace[2087736117] transaction","detail":"{read_only:false; response_revision:2818; number_of_response:1; }","duration":"176.006779ms","start":"2026-06-08T06:28:11.250272Z","end":"2026-06-08T06:28:11.426279Z","steps":["trace[2087736117] 'process raft request' (duration: 149.803365ms)","trace[2087736117] 'compare' (duration: 26.01614ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T06:32:00.828546Z","caller":"traceutil/trace.go:171","msg":"trace[1128752368] transaction","detail":"{read_only:false; response_revision:4851; number_of_response:1; }","duration":"117.349505ms","start":"2026-06-08T06:32:00.71117Z","end":"2026-06-08T06:32:00.82852Z","steps":["trace[1128752368] 'process raft request' (duration: 102.207037ms)","trace[1128752368] 'compare' (duration: 14.945044ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T06:32:19.597737Z","caller":"traceutil/trace.go:171","msg":"trace[196281997] linearizableReadLoop","detail":"{readStateIndex:5465; appliedIndex:5464; }","duration":"264.458547ms","start":"2026-06-08T06:32:19.333245Z","end":"2026-06-08T06:32:19.597704Z","steps":["trace[196281997] 'read index received' (duration: 264.256542ms)","trace[196281997] 'applied index is now lower than readState.Index' (duration: 201.044µs)"],"step_count":2} {"level":"info","ts":"2026-06-08T06:32:19.597801Z","caller":"traceutil/trace.go:171","msg":"trace[611883904] transaction","detail":"{read_only:false; response_revision:5267; number_of_response:1; }","duration":"265.302584ms","start":"2026-06-08T06:32:19.332462Z","end":"2026-06-08T06:32:19.597764Z","steps":["trace[611883904] 'process raft request' (duration: 265.068519ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T06:32:19.597968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.975666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-06-08T06:32:19.597969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.711942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18015"} {"level":"info","ts":"2026-06-08T06:32:19.598009Z","caller":"traceutil/trace.go:171","msg":"trace[2055947383] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5267; }","duration":"190.073658ms","start":"2026-06-08T06:32:19.407921Z","end":"2026-06-08T06:32:19.597995Z","steps":["trace[2055947383] 'agreement among raft nodes before linearized reading' (duration: 189.931906ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T06:32:19.598025Z","caller":"traceutil/trace.go:171","msg":"trace[53536574] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:5267; }","duration":"264.819374ms","start":"2026-06-08T06:32:19.333192Z","end":"2026-06-08T06:32:19.598011Z","steps":["trace[53536574] 'agreement among raft nodes before linearized reading' (duration: 264.607889ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T06:32:19.598289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.608326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-w5v8q\" ","response":"range_response_count:1 size:6451"} {"level":"info","ts":"2026-06-08T06:32:19.598345Z","caller":"traceutil/trace.go:171","msg":"trace[974344477] range","detail":"{range_begin:/registry/pods/openstack/keepalived-w5v8q; range_end:; response_count:1; response_revision:5267; }","duration":"132.7357ms","start":"2026-06-08T06:32:19.465595Z","end":"2026-06-08T06:32:19.59833Z","steps":["trace[974344477] 'agreement among raft nodes before linearized reading' (duration: 132.442103ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T06:32:26.56234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.556812ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-08T06:32:26.562542Z","caller":"traceutil/trace.go:171","msg":"trace[607900176] transaction","detail":"{read_only:false; response_revision:5450; number_of_response:1; }","duration":"173.47233ms","start":"2026-06-08T06:32:26.389022Z","end":"2026-06-08T06:32:26.562494Z","steps":["trace[607900176] 'process raft request' (duration: 24.110112ms)","trace[607900176] 'compare' (duration: 148.435119ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T06:32:26.562629Z","caller":"traceutil/trace.go:171","msg":"trace[1448287924] linearizableReadLoop","detail":"{readStateIndex:5653; appliedIndex:5652; }","duration":"145.439838ms","start":"2026-06-08T06:32:26.417158Z","end":"2026-06-08T06:32:26.562598Z","steps":["trace[1448287924] 'read index received' (duration: 41.77µs)","trace[1448287924] 'applied index is now lower than readState.Index' (duration: 145.394588ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T06:32:26.562678Z","caller":"traceutil/trace.go:171","msg":"trace[1281476055] transaction","detail":"{read_only:false; response_revision:5452; number_of_response:1; }","duration":"138.780463ms","start":"2026-06-08T06:32:26.423879Z","end":"2026-06-08T06:32:26.56266Z","steps":["trace[1281476055] 'process raft request' (duration: 138.735252ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T06:32:26.562786Z","caller":"traceutil/trace.go:171","msg":"trace[1070670351] transaction","detail":"{read_only:false; response_revision:5451; number_of_response:1; }","duration":"143.906438ms","start":"2026-06-08T06:32:26.418837Z","end":"2026-06-08T06:32:26.562743Z","steps":["trace[1070670351] 'process raft request' (duration: 143.693023ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T06:32:26.562868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.691693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:13289"} {"level":"info","ts":"2026-06-08T06:32:26.562918Z","caller":"traceutil/trace.go:171","msg":"trace[934386141] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5452; }","duration":"145.801425ms","start":"2026-06-08T06:32:26.417102Z","end":"2026-06-08T06:32:26.562904Z","steps":["trace[934386141] 'agreement among raft nodes before linearized reading' (duration: 145.57739ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T06:32:26.800045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.101455ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-08T06:32:26.800273Z","caller":"traceutil/trace.go:171","msg":"trace[1858548073] transaction","detail":"{read_only:false; response_revision:5455; number_of_response:1; }","duration":"165.767873ms","start":"2026-06-08T06:32:26.634468Z","end":"2026-06-08T06:32:26.800236Z","steps":["trace[1858548073] 'process raft request' (duration: 36.378502ms)","trace[1858548073] 'compare' (duration: 128.84079ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T06:32:26.800431Z","caller":"traceutil/trace.go:171","msg":"trace[256434163] transaction","detail":"{read_only:false; response_revision:5456; number_of_response:1; }","duration":"149.875758ms","start":"2026-06-08T06:32:26.650523Z","end":"2026-06-08T06:32:26.800399Z","steps":["trace[256434163] 'process raft request' (duration: 149.630713ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T06:32:26.800619Z","caller":"traceutil/trace.go:171","msg":"trace[2088146067] transaction","detail":"{read_only:false; response_revision:5457; number_of_response:1; }","duration":"134.074565ms","start":"2026-06-08T06:32:26.666508Z","end":"2026-06-08T06:32:26.800582Z","steps":["trace[2088146067] 'process raft request' (duration: 133.768089ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T06:33:16.867363Z","caller":"traceutil/trace.go:171","msg":"trace[691986853] transaction","detail":"{read_only:false; response_revision:5906; number_of_response:1; }","duration":"107.516743ms","start":"2026-06-08T06:33:16.759824Z","end":"2026-06-08T06:33:16.867341Z","steps":["trace[691986853] 'process raft request' (duration: 71.169718ms)","trace[691986853] 'compare' (duration: 36.217703ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T06:33:32.780358Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2937} {"level":"info","ts":"2026-06-08T06:33:32.848546Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2937,"took":"66.606965ms","hash":3942461785,"current-db-size-bytes":58105856,"current-db-size":"58 MB","current-db-size-in-use-bytes":33763328,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-08T06:33:32.848607Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3942461785,"revision":2937,"compact-revision":-1} {"level":"info","ts":"2026-06-08T06:38:32.816907Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6027} {"level":"info","ts":"2026-06-08T06:38:32.984677Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6027,"took":"165.435399ms","hash":2313463194,"current-db-size-bytes":58105856,"current-db-size":"58 MB","current-db-size-in-use-bytes":21336064,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-06-08T06:38:32.984793Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2313463194,"revision":6027,"compact-revision":2937} {"level":"info","ts":"2026-06-08T06:38:39.379023Z","caller":"traceutil/trace.go:171","msg":"trace[267868569] transaction","detail":"{read_only:false; response_revision:8279; number_of_response:1; }","duration":"221.859812ms","start":"2026-06-08T06:38:39.157141Z","end":"2026-06-08T06:38:39.379Z","steps":["trace[267868569] 'process raft request' (duration: 221.700169ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T06:38:39.570905Z","caller":"traceutil/trace.go:171","msg":"trace[870465207] linearizableReadLoop","detail":"{readStateIndex:8575; appliedIndex:8574; }","duration":"191.882836ms","start":"2026-06-08T06:38:39.378998Z","end":"2026-06-08T06:38:39.570881Z","steps":["trace[870465207] 'read index received' (duration: 160.96901ms)","trace[870465207] 'applied index is now lower than readState.Index' (duration: 30.912966ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T06:38:39.570954Z","caller":"traceutil/trace.go:171","msg":"trace[1368794704] transaction","detail":"{read_only:false; response_revision:8280; number_of_response:1; }","duration":"222.126877ms","start":"2026-06-08T06:38:39.34877Z","end":"2026-06-08T06:38:39.570897Z","steps":["trace[1368794704] 'process raft request' (duration: 191.205171ms)","trace[1368794704] 'compare' (duration: 30.456297ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T06:38:39.571035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.738439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-08T06:38:39.57107Z","caller":"traceutil/trace.go:171","msg":"trace[1320561006] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:8280; }","duration":"219.822371ms","start":"2026-06-08T06:38:39.351237Z","end":"2026-06-08T06:38:39.571059Z","steps":["trace[1320561006] 'agreement among raft nodes before linearized reading' (duration: 219.74417ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T06:38:51.311925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.938088ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-08T06:38:52.30872Z","caller":"traceutil/trace.go:171","msg":"trace[1753577479] linearizableReadLoop","detail":"{readStateIndex:8644; appliedIndex:8644; }","duration":"296.387601ms","start":"2026-06-08T06:38:52.012272Z","end":"2026-06-08T06:38:52.30866Z","steps":["trace[1753577479] 'read index received' (duration: 296.375201ms)","trace[1753577479] 'applied index is now lower than readState.Index' (duration: 10.2µs)"],"step_count":2} {"level":"info","ts":"2026-06-08T06:38:52.308764Z","caller":"traceutil/trace.go:171","msg":"trace[1808778892] transaction","detail":"{read_only:false; response_revision:8346; number_of_response:1; }","duration":"297.062814ms","start":"2026-06-08T06:38:52.011596Z","end":"2026-06-08T06:38:52.308658Z","steps":["trace[1808778892] 'process raft request' (duration: 296.912461ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T06:38:52.308935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.641946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-06-08T06:38:52.309002Z","caller":"traceutil/trace.go:171","msg":"trace[645167023] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8346; }","duration":"296.741219ms","start":"2026-06-08T06:38:52.012246Z","end":"2026-06-08T06:38:52.308988Z","steps":["trace[645167023] 'agreement among raft nodes before linearized reading' (duration: 296.546225ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T06:38:52.314805Z","caller":"traceutil/trace.go:171","msg":"trace[1921150726] transaction","detail":"{read_only:false; response_revision:8347; number_of_response:1; }","duration":"180.465984ms","start":"2026-06-08T06:38:52.134308Z","end":"2026-06-08T06:38:52.314774Z","steps":["trace[1921150726] 'process raft request' (duration: 180.223779ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T06:38:52.314955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.15267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-b586bd458-zk2wl.18b707c313f7526d\" ","response":"range_response_count:1 size:1132"} {"level":"info","ts":"2026-06-08T06:38:52.315036Z","caller":"traceutil/trace.go:171","msg":"trace[1863033289] range","detail":"{range_begin:/registry/events/openstack/barbican-api-b586bd458-zk2wl.18b707c313f7526d; range_end:; response_count:1; response_revision:8347; }","duration":"229.287753ms","start":"2026-06-08T06:38:52.085725Z","end":"2026-06-08T06:38:52.315013Z","steps":["trace[1863033289] 'agreement among raft nodes before linearized reading' (duration: 228.994877ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T06:38:53.763072Z","caller":"traceutil/trace.go:171","msg":"trace[1558699729] transaction","detail":"{read_only:false; response_revision:8353; number_of_response:1; }","duration":"131.555354ms","start":"2026-06-08T06:38:53.631478Z","end":"2026-06-08T06:38:53.763033Z","steps":["trace[1558699729] 'process raft request' (duration: 131.180536ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T06:39:21.541331Z","caller":"traceutil/trace.go:171","msg":"trace[2123431398] linearizableReadLoop","detail":"{readStateIndex:8861; appliedIndex:8860; }","duration":"369.04869ms","start":"2026-06-08T06:39:21.17225Z","end":"2026-06-08T06:39:21.541299Z","steps":["trace[2123431398] 'read index received' (duration: 368.772784ms)","trace[2123431398] 'applied index is now lower than readState.Index' (duration: 274.336µs)"],"step_count":2} {"level":"info","ts":"2026-06-08T06:39:21.54142Z","caller":"traceutil/trace.go:171","msg":"trace[1637829814] transaction","detail":"{read_only:false; response_revision:8556; number_of_response:1; }","duration":"401.023967ms","start":"2026-06-08T06:39:21.140334Z","end":"2026-06-08T06:39:21.541358Z","steps":["trace[1637829814] 'process raft request' (duration: 400.762062ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T06:39:21.541606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.331476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-08T06:39:21.541654Z","caller":"traceutil/trace.go:171","msg":"trace[1674992589] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8556; }","duration":"369.439588ms","start":"2026-06-08T06:39:21.1722Z","end":"2026-06-08T06:39:21.541639Z","steps":["trace[1674992589] 'agreement among raft nodes before linearized reading' (duration: 369.252054ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T06:39:21.541698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T06:39:21.172178Z","time spent":"369.50992ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-08T06:39:21.541829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T06:39:21.140312Z","time spent":"401.306903ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T06:39:21.895316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.806719ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-08T06:39:21.895548Z","caller":"traceutil/trace.go:171","msg":"trace[1459091446] linearizableReadLoop","detail":"{readStateIndex:8863; appliedIndex:8861; }","duration":"297.773647ms","start":"2026-06-08T06:39:21.597758Z","end":"2026-06-08T06:39:21.895532Z","steps":["trace[1459091446] 'read index received' (duration: 289.566841ms)","trace[1459091446] 'applied index is now lower than readState.Index' (duration: 8.205646ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T06:39:21.895788Z","caller":"traceutil/trace.go:171","msg":"trace[706523555] transaction","detail":"{read_only:false; response_revision:8557; number_of_response:1; }","duration":"347.698297ms","start":"2026-06-08T06:39:21.548082Z","end":"2026-06-08T06:39:21.89578Z","steps":["trace[706523555] 'process raft request' (duration: 347.33504ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T06:39:21.895865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T06:39:21.548045Z","time spent":"347.767389ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T06:39:21.895974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.226367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-08T06:39:21.896004Z","caller":"traceutil/trace.go:171","msg":"trace[1775166964] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:8557; }","duration":"298.301748ms","start":"2026-06-08T06:39:21.597692Z","end":"2026-06-08T06:39:21.895993Z","steps":["trace[1775166964] 'agreement among raft nodes before linearized reading' (duration: 298.252017ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T06:39:21.897019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.208841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T06:39:21.897093Z","caller":"traceutil/trace.go:171","msg":"trace[537831611] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8558; }","duration":"156.323634ms","start":"2026-06-08T06:39:21.740753Z","end":"2026-06-08T06:39:21.897077Z","steps":["trace[537831611] 'agreement among raft nodes before linearized reading' (duration: 156.193421ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T06:39:35.139613Z","caller":"traceutil/trace.go:171","msg":"trace[805177122] transaction","detail":"{read_only:false; response_revision:8674; number_of_response:1; }","duration":"124.891007ms","start":"2026-06-08T06:39:35.014693Z","end":"2026-06-08T06:39:35.139584Z","steps":["trace[805177122] 'process raft request' (duration: 124.726954ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T06:40:02.224093Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000244b.wal"} {"level":"info","ts":"2026-06-08T06:40:53.778283Z","caller":"traceutil/trace.go:171","msg":"trace[530384917] transaction","detail":"{read_only:false; response_revision:9431; number_of_response:1; }","duration":"191.65534ms","start":"2026-06-08T06:40:53.586601Z","end":"2026-06-08T06:40:53.778256Z","steps":["trace[530384917] 'process raft request' (duration: 191.480377ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T06:40:54.315658Z","caller":"traceutil/trace.go:171","msg":"trace[1958679301] transaction","detail":"{read_only:false; response_revision:9432; number_of_response:1; }","duration":"241.310644ms","start":"2026-06-08T06:40:54.07431Z","end":"2026-06-08T06:40:54.31562Z","steps":["trace[1958679301] 'process raft request' (duration: 241.186532ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T06:40:54.40836Z","caller":"traceutil/trace.go:171","msg":"trace[685376510] transaction","detail":"{read_only:false; response_revision:9433; number_of_response:1; }","duration":"144.544196ms","start":"2026-06-08T06:40:54.263786Z","end":"2026-06-08T06:40:54.40833Z","steps":["trace[685376510] 'process raft request' (duration: 127.693365ms)","trace[685376510] 'compare' (duration: 16.710718ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T06:40:54.686841Z","caller":"traceutil/trace.go:171","msg":"trace[102682247] transaction","detail":"{read_only:false; response_revision:9434; number_of_response:1; }","duration":"200.617021ms","start":"2026-06-08T06:40:54.486206Z","end":"2026-06-08T06:40:54.686823Z","steps":["trace[102682247] 'process raft request' (duration: 200.505029ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T06:40:54.895306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.982041ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-08T06:40:54.895574Z","caller":"traceutil/trace.go:171","msg":"trace[1008389522] linearizableReadLoop","detail":"{readStateIndex:9767; appliedIndex:9765; }","duration":"405.228842ms","start":"2026-06-08T06:40:54.490319Z","end":"2026-06-08T06:40:54.895548Z","steps":["trace[1008389522] 'read index received' (duration: 196.463556ms)","trace[1008389522] 'applied index is now lower than readState.Index' (duration: 208.764406ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T06:40:54.895697Z","caller":"traceutil/trace.go:171","msg":"trace[1558292370] transaction","detail":"{read_only:false; response_revision:9436; number_of_response:1; }","duration":"396.304202ms","start":"2026-06-08T06:40:54.499383Z","end":"2026-06-08T06:40:54.895687Z","steps":["trace[1558292370] 'process raft request' (duration: 396.036506ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T06:40:54.895764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T06:40:54.499365Z","time spent":"396.364253ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T06:40:54.895904Z","caller":"traceutil/trace.go:171","msg":"trace[1302943761] transaction","detail":"{read_only:false; response_revision:9435; number_of_response:1; }","duration":"407.783444ms","start":"2026-06-08T06:40:54.48809Z","end":"2026-06-08T06:40:54.895873Z","steps":["trace[1302943761] 'process raft request' (duration: 276.145119ms)","trace[1302943761] 'compare' (duration: 130.807958ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T06:40:54.896081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.758213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-06-08T06:40:54.896066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.157776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-08T06:40:54.896121Z","caller":"traceutil/trace.go:171","msg":"trace[1603484687] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9437; }","duration":"405.826755ms","start":"2026-06-08T06:40:54.490283Z","end":"2026-06-08T06:40:54.89611Z","steps":["trace[1603484687] 'agreement among raft nodes before linearized reading' (duration: 405.704202ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T06:40:54.896131Z","caller":"traceutil/trace.go:171","msg":"trace[188747539] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:9437; }","duration":"384.255527ms","start":"2026-06-08T06:40:54.511862Z","end":"2026-06-08T06:40:54.896118Z","steps":["trace[188747539] 'agreement among raft nodes before linearized reading' (duration: 384.153575ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T06:40:54.896152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T06:40:54.49027Z","time spent":"405.874026ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-08T06:40:54.896174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T06:40:54.51185Z","time spent":"384.314779ms","remote":"127.0.0.1:51644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-06-08T06:40:54.896184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.503491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-w5v8q\" ","response":"range_response_count:1 size:6451"} {"level":"warn","ts":"2026-06-08T06:40:54.896161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.932827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T06:40:54.896239Z","caller":"traceutil/trace.go:171","msg":"trace[2143326101] range","detail":"{range_begin:/registry/pods/openstack/keepalived-w5v8q; range_end:; response_count:1; response_revision:9437; }","duration":"209.587882ms","start":"2026-06-08T06:40:54.686636Z","end":"2026-06-08T06:40:54.896223Z","steps":["trace[2143326101] 'agreement among raft nodes before linearized reading' (duration: 209.440159ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T06:40:54.896102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T06:40:54.488077Z","time spent":"407.935178ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T06:40:54.896274Z","caller":"traceutil/trace.go:171","msg":"trace[934158769] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9437; }","duration":"156.09069ms","start":"2026-06-08T06:40:54.74016Z","end":"2026-06-08T06:40:54.89625Z","steps":["trace[934158769] 'agreement among raft nodes before linearized reading' (duration: 155.920316ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T06:40:54.895965Z","caller":"traceutil/trace.go:171","msg":"trace[1044278221] transaction","detail":"{read_only:false; response_revision:9437; number_of_response:1; }","duration":"376.944431ms","start":"2026-06-08T06:40:54.519011Z","end":"2026-06-08T06:40:54.895955Z","steps":["trace[1044278221] 'process raft request' (duration: 376.494441ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T06:40:54.896505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T06:40:54.518994Z","time spent":"377.468331ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T06:40:55.073077Z","caller":"traceutil/trace.go:171","msg":"trace[130337583] transaction","detail":"{read_only:false; response_revision:9440; number_of_response:1; }","duration":"130.057293ms","start":"2026-06-08T06:40:54.942983Z","end":"2026-06-08T06:40:55.07304Z","steps":["trace[130337583] 'process raft request' (duration: 113.382455ms)","trace[130337583] 'compare' (duration: 16.498454ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T06:41:27.019875Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a31e123290fcf0df","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-08T06:41:27.0269Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-06-08T06:41:27.027007Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-06-08T06:43:32.824398Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8114} {"level":"info","ts":"2026-06-08T06:43:32.884478Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8114,"took":"58.236223ms","hash":2194618490,"current-db-size-bytes":58105856,"current-db-size":"58 MB","current-db-size-in-use-bytes":29515776,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-08T06:43:32.884561Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2194618490,"revision":8114,"compact-revision":6027} {"level":"info","ts":"2026-06-08T06:44:37.204222Z","caller":"traceutil/trace.go:171","msg":"trace[1648442108] linearizableReadLoop","detail":"{readStateIndex:11666; appliedIndex:11665; }","duration":"135.21904ms","start":"2026-06-08T06:44:37.068975Z","end":"2026-06-08T06:44:37.204194Z","steps":["trace[1648442108] 'read index received' (duration: 135.036436ms)","trace[1648442108] 'applied index is now lower than readState.Index' (duration: 181.474µs)"],"step_count":2} {"level":"info","ts":"2026-06-08T06:44:37.204282Z","caller":"traceutil/trace.go:171","msg":"trace[1289776432] transaction","detail":"{read_only:false; response_revision:11280; number_of_response:1; }","duration":"136.224061ms","start":"2026-06-08T06:44:37.068015Z","end":"2026-06-08T06:44:37.204239Z","steps":["trace[1289776432] 'process raft request' (duration: 136.041517ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T06:44:37.204505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.531127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5004"} {"level":"info","ts":"2026-06-08T06:44:37.204582Z","caller":"traceutil/trace.go:171","msg":"trace[2080307813] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:11280; }","duration":"135.672459ms","start":"2026-06-08T06:44:37.068892Z","end":"2026-06-08T06:44:37.204565Z","steps":["trace[2080307813] 'agreement among raft nodes before linearized reading' (duration: 135.437035ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T06:48:32.831188Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10721} {"level":"info","ts":"2026-06-08T06:48:32.911863Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10721,"took":"77.323839ms","hash":248909394,"current-db-size-bytes":58105856,"current-db-size":"58 MB","current-db-size-in-use-bytes":27222016,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-06-08T06:48:32.911925Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":248909394,"revision":10721,"compact-revision":8114} {"level":"info","ts":"2026-06-08T06:51:05.25222Z","caller":"traceutil/trace.go:171","msg":"trace[822148959] transaction","detail":"{read_only:false; response_revision:13884; number_of_response:1; }","duration":"655.941202ms","start":"2026-06-08T06:51:04.596247Z","end":"2026-06-08T06:51:05.252188Z","steps":["trace[822148959] 'process raft request' (duration: 655.737427ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T06:51:05.252397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T06:51:04.596221Z","time spent":"656.089945ms","remote":"127.0.0.1:56688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11815,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T06:51:05.455575Z","caller":"traceutil/trace.go:171","msg":"trace[519273025] linearizableReadLoop","detail":"{readStateIndex:14362; appliedIndex:14360; }","duration":"685.041984ms","start":"2026-06-08T06:51:04.770501Z","end":"2026-06-08T06:51:05.455543Z","steps":["trace[519273025] 'read index received' (duration: 481.497666ms)","trace[519273025] 'applied index is now lower than readState.Index' (duration: 203.541178ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T06:51:05.455701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.177457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T06:51:05.455725Z","caller":"traceutil/trace.go:171","msg":"trace[1278088851] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:13885; }","duration":"685.248669ms","start":"2026-06-08T06:51:04.770469Z","end":"2026-06-08T06:51:05.455718Z","steps":["trace[1278088851] 'agreement among raft nodes before linearized reading' (duration: 685.182257ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T06:51:05.455695Z","caller":"traceutil/trace.go:171","msg":"trace[239047618] transaction","detail":"{read_only:false; response_revision:13885; number_of_response:1; }","duration":"705.91323ms","start":"2026-06-08T06:51:04.749749Z","end":"2026-06-08T06:51:05.455662Z","steps":["trace[239047618] 'process raft request' (duration: 681.231037ms)","trace[239047618] 'compare' (duration: 24.431888ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T06:51:05.455753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T06:51:04.770457Z","time spent":"685.288949ms","remote":"127.0.0.1:33518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-06-08T06:51:05.455843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T06:51:04.74972Z","time spent":"706.045463ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T06:51:05.461112Z","caller":"traceutil/trace.go:171","msg":"trace[650138752] transaction","detail":"{read_only:false; response_revision:13887; number_of_response:1; }","duration":"189.074233ms","start":"2026-06-08T06:51:05.272013Z","end":"2026-06-08T06:51:05.461088Z","steps":["trace[650138752] 'process raft request' (duration: 188.958421ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T06:51:05.461143Z","caller":"traceutil/trace.go:171","msg":"trace[1504142317] transaction","detail":"{read_only:false; response_revision:13886; number_of_response:1; }","duration":"189.535393ms","start":"2026-06-08T06:51:05.271555Z","end":"2026-06-08T06:51:05.46109Z","steps":["trace[1504142317] 'process raft request' (duration: 189.258808ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T06:51:05.461344Z","caller":"traceutil/trace.go:171","msg":"trace[2117663995] transaction","detail":"{read_only:false; response_revision:13888; number_of_response:1; }","duration":"188.808318ms","start":"2026-06-08T06:51:05.27252Z","end":"2026-06-08T06:51:05.461328Z","steps":["trace[2117663995] 'process raft request' (duration: 188.517832ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T06:51:05.461636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.105068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-08T06:51:05.461698Z","caller":"traceutil/trace.go:171","msg":"trace[1578483430] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:13888; }","duration":"327.21548ms","start":"2026-06-08T06:51:05.134466Z","end":"2026-06-08T06:51:05.461682Z","steps":["trace[1578483430] 'agreement among raft nodes before linearized reading' (duration: 327.025546ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T06:51:05.461745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T06:51:05.134443Z","time spent":"327.290671ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-08T06:51:26.839633Z","caller":"traceutil/trace.go:171","msg":"trace[2134054914] transaction","detail":"{read_only:false; response_revision:14193; number_of_response:1; }","duration":"149.628661ms","start":"2026-06-08T06:51:26.689987Z","end":"2026-06-08T06:51:26.839616Z","steps":["trace[2134054914] 'process raft request' (duration: 149.414427ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T06:51:26.839603Z","caller":"traceutil/trace.go:171","msg":"trace[609629855] linearizableReadLoop","detail":"{readStateIndex:14684; appliedIndex:14683; }","duration":"130.734275ms","start":"2026-06-08T06:51:26.708842Z","end":"2026-06-08T06:51:26.839576Z","steps":["trace[609629855] 'read index received' (duration: 130.519861ms)","trace[609629855] 'applied index is now lower than readState.Index' (duration: 212.894µs)"],"step_count":2} {"level":"warn","ts":"2026-06-08T06:51:26.839724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.859668ms","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:5"} {"level":"info","ts":"2026-06-08T06:51:26.839782Z","caller":"traceutil/trace.go:171","msg":"trace[33096062] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:14193; }","duration":"130.97236ms","start":"2026-06-08T06:51:26.708793Z","end":"2026-06-08T06:51:26.839766Z","steps":["trace[33096062] 'agreement among raft nodes before linearized reading' (duration: 130.874288ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T06:52:43.98339Z","caller":"traceutil/trace.go:171","msg":"trace[1806204253] transaction","detail":"{read_only:false; response_revision:14953; number_of_response:1; }","duration":"138.341241ms","start":"2026-06-08T06:52:43.84502Z","end":"2026-06-08T06:52:43.983361Z","steps":["trace[1806204253] 'process raft request' (duration: 138.198128ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T06:52:44.11507Z","caller":"traceutil/trace.go:171","msg":"trace[525286879] linearizableReadLoop","detail":"{readStateIndex:15467; appliedIndex:15464; }","duration":"236.928531ms","start":"2026-06-08T06:52:43.878112Z","end":"2026-06-08T06:52:44.11504Z","steps":["trace[525286879] 'read index received' (duration: 106.253007ms)","trace[525286879] 'applied index is now lower than readState.Index' (duration: 130.674604ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T06:52:44.115211Z","caller":"traceutil/trace.go:171","msg":"trace[1711505210] transaction","detail":"{read_only:false; response_revision:14956; number_of_response:1; }","duration":"267.546095ms","start":"2026-06-08T06:52:43.847653Z","end":"2026-06-08T06:52:44.115199Z","steps":["trace[1711505210] 'process raft request' (duration: 267.347351ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T06:52:44.115231Z","caller":"traceutil/trace.go:171","msg":"trace[1812138042] transaction","detail":"{read_only:false; response_revision:14955; number_of_response:1; }","duration":"268.212797ms","start":"2026-06-08T06:52:43.846966Z","end":"2026-06-08T06:52:44.115178Z","steps":["trace[1812138042] 'process raft request' (duration: 267.991023ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T06:52:44.11546Z","caller":"traceutil/trace.go:171","msg":"trace[1144767383] transaction","detail":"{read_only:false; response_revision:14954; number_of_response:1; }","duration":"269.866152ms","start":"2026-06-08T06:52:43.845582Z","end":"2026-06-08T06:52:44.115448Z","steps":["trace[1144767383] 'process raft request' (duration: 269.212569ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T06:52:44.115474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.337699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"info","ts":"2026-06-08T06:52:44.115509Z","caller":"traceutil/trace.go:171","msg":"trace[348837331] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14956; }","duration":"237.430301ms","start":"2026-06-08T06:52:43.878069Z","end":"2026-06-08T06:52:44.115499Z","steps":["trace[348837331] 'agreement among raft nodes before linearized reading' (duration: 237.269728ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T06:52:44.115754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.343595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-06-08T06:52:44.115816Z","caller":"traceutil/trace.go:171","msg":"trace[278146758] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:14956; }","duration":"205.438998ms","start":"2026-06-08T06:52:43.910348Z","end":"2026-06-08T06:52:44.115787Z","steps":["trace[278146758] 'agreement among raft nodes before linearized reading' (duration: 205.225883ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T06:52:44.115948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.921214ms","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-06-08T06:52:44.116021Z","caller":"traceutil/trace.go:171","msg":"trace[874252386] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14956; }","duration":"170.011306ms","start":"2026-06-08T06:52:43.945981Z","end":"2026-06-08T06:52:44.115992Z","steps":["trace[874252386] 'agreement among raft nodes before linearized reading' (duration: 169.872003ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T06:52:44.115959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.058895ms","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-06-08T06:52:44.116445Z","caller":"traceutil/trace.go:171","msg":"trace[1710421816] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14956; }","duration":"168.570646ms","start":"2026-06-08T06:52:43.947848Z","end":"2026-06-08T06:52:44.116418Z","steps":["trace[1710421816] 'agreement among raft nodes before linearized reading' (duration: 167.860452ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T06:53:32.838411Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12809} {"level":"info","ts":"2026-06-08T06:53:32.912856Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12809,"took":"71.926096ms","hash":3013487832,"current-db-size-bytes":58105856,"current-db-size":"58 MB","current-db-size-in-use-bytes":30203904,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-08T06:53:32.912929Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3013487832,"revision":12809,"compact-revision":10721} {"level":"info","ts":"2026-06-08T06:58:32.846211Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15270} {"level":"info","ts":"2026-06-08T06:58:32.943617Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15270,"took":"94.342037ms","hash":1623054900,"current-db-size-bytes":58105856,"current-db-size":"58 MB","current-db-size-in-use-bytes":31313920,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-08T06:58:32.943709Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1623054900,"revision":15270,"compact-revision":12809} {"level":"info","ts":"2026-06-08T06:58:38.3472Z","caller":"traceutil/trace.go:171","msg":"trace[1881414297] transaction","detail":"{read_only:false; response_revision:17571; number_of_response:1; }","duration":"184.929379ms","start":"2026-06-08T06:58:38.162239Z","end":"2026-06-08T06:58:38.347168Z","steps":["trace[1881414297] 'process raft request' (duration: 183.426629ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T06:58:44.050604Z","caller":"traceutil/trace.go:171","msg":"trace[358663778] transaction","detail":"{read_only:false; response_revision:17603; number_of_response:1; }","duration":"128.014167ms","start":"2026-06-08T06:58:43.922567Z","end":"2026-06-08T06:58:44.050582Z","steps":["trace[358663778] 'process raft request' (duration: 127.509997ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T06:58:44.400367Z","caller":"traceutil/trace.go:171","msg":"trace[567413289] linearizableReadLoop","detail":"{readStateIndex:18206; appliedIndex:18205; }","duration":"140.819807ms","start":"2026-06-08T06:58:44.25953Z","end":"2026-06-08T06:58:44.40035Z","steps":["trace[567413289] 'read index received' (duration: 140.580712ms)","trace[567413289] 'applied index is now lower than readState.Index' (duration: 238.575µs)"],"step_count":2} {"level":"info","ts":"2026-06-08T06:58:44.400459Z","caller":"traceutil/trace.go:171","msg":"trace[565824356] transaction","detail":"{read_only:false; response_revision:17606; number_of_response:1; }","duration":"216.19732ms","start":"2026-06-08T06:58:44.184254Z","end":"2026-06-08T06:58:44.400452Z","steps":["trace[565824356] 'process raft request' (duration: 215.979456ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T06:58:44.400656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.113193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-jmwhm\" ","response":"range_response_count:1 size:20780"} {"level":"info","ts":"2026-06-08T06:58:44.400696Z","caller":"traceutil/trace.go:171","msg":"trace[744575070] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-jmwhm; range_end:; response_count:1; response_revision:17606; }","duration":"141.164594ms","start":"2026-06-08T06:58:44.259524Z","end":"2026-06-08T06:58:44.400689Z","steps":["trace[744575070] 'agreement among raft nodes before linearized reading' (duration: 141.024881ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T06:58:44.707138Z","caller":"traceutil/trace.go:171","msg":"trace[785437734] transaction","detail":"{read_only:false; response_revision:17607; number_of_response:1; }","duration":"291.221207ms","start":"2026-06-08T06:58:44.415885Z","end":"2026-06-08T06:58:44.707106Z","steps":["trace[785437734] 'process raft request' (duration: 263.68047ms)","trace[785437734] 'compare' (duration: 27.355773ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T06:58:44.893482Z","caller":"traceutil/trace.go:171","msg":"trace[795240848] linearizableReadLoop","detail":"{readStateIndex:18208; appliedIndex:18206; }","duration":"228.939589ms","start":"2026-06-08T06:58:44.664475Z","end":"2026-06-08T06:58:44.893414Z","steps":["trace[795240848] 'read index received' (duration: 15.075896ms)","trace[795240848] 'applied index is now lower than readState.Index' (duration: 213.862743ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T06:58:44.893556Z","caller":"traceutil/trace.go:171","msg":"trace[2011726648] transaction","detail":"{read_only:false; response_revision:17608; number_of_response:1; }","duration":"466.086572ms","start":"2026-06-08T06:58:44.42743Z","end":"2026-06-08T06:58:44.893517Z","steps":["trace[2011726648] 'process raft request' (duration: 416.178113ms)","trace[2011726648] 'compare' (duration: 49.629664ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T06:58:44.893914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T06:58:44.427401Z","time spent":"466.44684ms","remote":"127.0.0.1:56688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20897,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T06:58:44.89368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.184004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-08T06:58:44.894004Z","caller":"traceutil/trace.go:171","msg":"trace[1893626063] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:17608; }","duration":"229.547521ms","start":"2026-06-08T06:58:44.664422Z","end":"2026-06-08T06:58:44.89397Z","steps":["trace[1893626063] 'agreement among raft nodes before linearized reading' (duration: 229.182914ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T07:02:09.148609Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a31e123290fcf0df","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-08T07:02:09.153218Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-06-08T07:02:09.153322Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-06-08T07:03:32.855481Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17509} {"level":"info","ts":"2026-06-08T07:03:32.946967Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17509,"took":"88.136801ms","hash":1224239337,"current-db-size-bytes":58105856,"current-db-size":"58 MB","current-db-size-in-use-bytes":34537472,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-08T07:03:32.947044Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1224239337,"revision":17509,"compact-revision":15270} {"level":"info","ts":"2026-06-08T07:04:37.977829Z","caller":"traceutil/trace.go:171","msg":"trace[1656736024] transaction","detail":"{read_only:false; response_revision:20397; number_of_response:1; }","duration":"115.160018ms","start":"2026-06-08T07:04:37.862637Z","end":"2026-06-08T07:04:37.977797Z","steps":["trace[1656736024] 'process raft request' (duration: 114.951654ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T07:04:44.312786Z","caller":"traceutil/trace.go:171","msg":"trace[1563772918] transaction","detail":"{read_only:false; response_revision:20429; number_of_response:1; }","duration":"233.048263ms","start":"2026-06-08T07:04:44.079706Z","end":"2026-06-08T07:04:44.312754Z","steps":["trace[1563772918] 'process raft request' (duration: 153.774749ms)","trace[1563772918] 'compare' (duration: 78.996408ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T07:08:28.389127Z","caller":"traceutil/trace.go:171","msg":"trace[1100806917] transaction","detail":"{read_only:false; response_revision:22850; number_of_response:1; }","duration":"105.034884ms","start":"2026-06-08T07:08:28.284063Z","end":"2026-06-08T07:08:28.389098Z","steps":["trace[1100806917] 'process raft request' (duration: 104.85993ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T07:08:32.862061Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20037} {"level":"info","ts":"2026-06-08T07:08:32.969849Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20037,"took":"104.127835ms","hash":297806518,"current-db-size-bytes":58105856,"current-db-size":"58 MB","current-db-size-in-use-bytes":41553920,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-06-08T07:08:32.969902Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":297806518,"revision":20037,"compact-revision":17509} {"level":"warn","ts":"2026-06-08T07:11:57.159259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.285518ms","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-06-08T07:11:57.159354Z","caller":"traceutil/trace.go:171","msg":"trace[138004626] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:24976; }","duration":"200.393301ms","start":"2026-06-08T07:11:56.958948Z","end":"2026-06-08T07:11:57.159341Z","steps":["trace[138004626] 'range keys from in-memory index tree' (duration: 200.268718ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:11:57.159697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.845677ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-08T07:11:57.159828Z","caller":"traceutil/trace.go:171","msg":"trace[937430636] transaction","detail":"{read_only:false; response_revision:24977; number_of_response:1; }","duration":"342.313301ms","start":"2026-06-08T07:11:56.817485Z","end":"2026-06-08T07:11:57.159798Z","steps":["trace[937430636] 'process raft request' (duration: 157.250438ms)","trace[937430636] 'compare' (duration: 184.578142ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T07:11:57.159956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:11:56.817451Z","time spent":"342.416443ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T07:11:57.428924Z","caller":"traceutil/trace.go:171","msg":"trace[1855484280] linearizableReadLoop","detail":"{readStateIndex:25791; appliedIndex:25790; }","duration":"105.31231ms","start":"2026-06-08T07:11:57.323585Z","end":"2026-06-08T07:11:57.428897Z","steps":["trace[1855484280] 'read index received' (duration: 105.072284ms)","trace[1855484280] 'applied index is now lower than readState.Index' (duration: 238.736µs)"],"step_count":2} {"level":"info","ts":"2026-06-08T07:11:57.429015Z","caller":"traceutil/trace.go:171","msg":"trace[448268066] transaction","detail":"{read_only:false; response_revision:24978; number_of_response:1; }","duration":"111.109157ms","start":"2026-06-08T07:11:57.317849Z","end":"2026-06-08T07:11:57.428958Z","steps":["trace[448268066] 'process raft request' (duration: 110.883492ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:11:57.429111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.515073ms","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-06-08T07:11:57.42922Z","caller":"traceutil/trace.go:171","msg":"trace[2004061503] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24978; }","duration":"105.674187ms","start":"2026-06-08T07:11:57.323515Z","end":"2026-06-08T07:11:57.429189Z","steps":["trace[2004061503] 'agreement among raft nodes before linearized reading' (duration: 105.489353ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T07:11:57.655308Z","caller":"traceutil/trace.go:171","msg":"trace[330348813] transaction","detail":"{read_only:false; response_revision:24979; number_of_response:1; }","duration":"158.857781ms","start":"2026-06-08T07:11:57.496381Z","end":"2026-06-08T07:11:57.655238Z","steps":["trace[330348813] 'process raft request' (duration: 157.374201ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T07:11:58.265595Z","caller":"traceutil/trace.go:171","msg":"trace[794551175] linearizableReadLoop","detail":"{readStateIndex:25793; appliedIndex:25792; }","duration":"205.792359ms","start":"2026-06-08T07:11:58.059786Z","end":"2026-06-08T07:11:58.265579Z","steps":["trace[794551175] 'read index received' (duration: 205.634016ms)","trace[794551175] 'applied index is now lower than readState.Index' (duration: 157.543µs)"],"step_count":2} {"level":"info","ts":"2026-06-08T07:11:58.265852Z","caller":"traceutil/trace.go:171","msg":"trace[1428171145] transaction","detail":"{read_only:false; response_revision:24980; number_of_response:1; }","duration":"369.953229ms","start":"2026-06-08T07:11:57.895891Z","end":"2026-06-08T07:11:58.265844Z","steps":["trace[1428171145] 'process raft request' (duration: 369.586911ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:11:58.265925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:11:57.895865Z","time spent":"370.00806ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T07:11:58.266157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.365791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-08T07:11:58.26619Z","caller":"traceutil/trace.go:171","msg":"trace[575167524] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:24980; }","duration":"206.436032ms","start":"2026-06-08T07:11:58.059744Z","end":"2026-06-08T07:11:58.26618Z","steps":["trace[575167524] 'agreement among raft nodes before linearized reading' (duration: 206.373321ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:11:58.659231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.756625ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-08T07:11:58.659397Z","caller":"traceutil/trace.go:171","msg":"trace[1348449184] transaction","detail":"{read_only:false; response_revision:24981; number_of_response:1; }","duration":"599.082301ms","start":"2026-06-08T07:11:58.060275Z","end":"2026-06-08T07:11:58.659357Z","steps":["trace[1348449184] 'process raft request' (duration: 277.077131ms)","trace[1348449184] 'compare' (duration: 321.593182ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T07:11:58.659478Z","caller":"traceutil/trace.go:171","msg":"trace[1652673669] transaction","detail":"{read_only:false; response_revision:24982; number_of_response:1; }","duration":"293.527284ms","start":"2026-06-08T07:11:58.365927Z","end":"2026-06-08T07:11:58.659454Z","steps":["trace[1652673669] 'process raft request' (duration: 293.436292ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T07:11:58.659518Z","caller":"traceutil/trace.go:171","msg":"trace[632073596] linearizableReadLoop","detail":"{readStateIndex:25794; appliedIndex:25793; }","duration":"393.842392ms","start":"2026-06-08T07:11:58.265645Z","end":"2026-06-08T07:11:58.659488Z","steps":["trace[632073596] 'read index received' (duration: 71.682609ms)","trace[632073596] 'applied index is now lower than readState.Index' (duration: 322.157303ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T07:11:58.659651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:11:58.060255Z","time spent":"599.233704ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":626,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T07:11:58.659703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.273173ms","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-06-08T07:11:58.659744Z","caller":"traceutil/trace.go:171","msg":"trace[134370965] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusterclasses/; range_end:/registry/cluster.x-k8s.io/clusterclasses0; response_count:0; response_revision:24982; }","duration":"540.330164ms","start":"2026-06-08T07:11:58.1194Z","end":"2026-06-08T07:11:58.65973Z","steps":["trace[134370965] 'agreement among raft nodes before linearized reading' (duration: 540.216711ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:11:58.659781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:11:58.119346Z","time spent":"540.423795ms","remote":"127.0.0.1:54518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/cluster.x-k8s.io/clusterclasses/\" range_end:\"/registry/cluster.x-k8s.io/clusterclasses0\" count_only:true "} {"level":"info","ts":"2026-06-08T07:11:58.688974Z","caller":"traceutil/trace.go:171","msg":"trace[1950911132] transaction","detail":"{read_only:false; response_revision:24983; number_of_response:1; }","duration":"322.929518ms","start":"2026-06-08T07:11:58.366003Z","end":"2026-06-08T07:11:58.688932Z","steps":["trace[1950911132] 'process raft request' (duration: 322.658143ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T07:11:58.689102Z","caller":"traceutil/trace.go:171","msg":"trace[865985914] transaction","detail":"{read_only:false; response_revision:24984; number_of_response:1; }","duration":"287.956221ms","start":"2026-06-08T07:11:58.401123Z","end":"2026-06-08T07:11:58.689079Z","steps":["trace[865985914] 'process raft request' (duration: 287.890429ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:11:58.689149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:11:58.365977Z","time spent":"323.079711ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T07:11:59.073739Z","caller":"traceutil/trace.go:171","msg":"trace[1682241077] linearizableReadLoop","detail":"{readStateIndex:25800; appliedIndex:25796; }","duration":"414.131022ms","start":"2026-06-08T07:11:58.659587Z","end":"2026-06-08T07:11:59.073718Z","steps":["trace[1682241077] 'read index received' (duration: 29.22326ms)","trace[1682241077] 'applied index is now lower than readState.Index' (duration: 384.906922ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T07:11:59.073877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.738472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-08T07:11:59.073934Z","caller":"traceutil/trace.go:171","msg":"trace[1129893220] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24987; }","duration":"491.848623ms","start":"2026-06-08T07:11:58.582074Z","end":"2026-06-08T07:11:59.073922Z","steps":["trace[1129893220] 'agreement among raft nodes before linearized reading' (duration: 491.725491ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:11:59.073959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.8192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-08T07:11:59.073982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:11:58.582056Z","time spent":"491.918035ms","remote":"127.0.0.1:56494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-08T07:11:59.074019Z","caller":"traceutil/trace.go:171","msg":"trace[636761336] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24987; }","duration":"522.913182ms","start":"2026-06-08T07:11:58.551094Z","end":"2026-06-08T07:11:59.074007Z","steps":["trace[636761336] 'agreement among raft nodes before linearized reading' (duration: 522.751479ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:11:59.074063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:11:58.551084Z","time spent":"522.969743ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-08T07:11:59.074051Z","caller":"traceutil/trace.go:171","msg":"trace[353346411] transaction","detail":"{read_only:false; response_revision:24986; number_of_response:1; }","duration":"490.73341ms","start":"2026-06-08T07:11:58.583294Z","end":"2026-06-08T07:11:59.074028Z","steps":["trace[353346411] 'process raft request' (duration: 490.295602ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:11:59.07414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.935151ms","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-06-08T07:11:59.074123Z","caller":"traceutil/trace.go:171","msg":"trace[71732605] transaction","detail":"{read_only:false; response_revision:24987; number_of_response:1; }","duration":"420.666725ms","start":"2026-06-08T07:11:58.65342Z","end":"2026-06-08T07:11:59.074087Z","steps":["trace[71732605] 'process raft request' (duration: 420.253956ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:11:59.074172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:11:58.583277Z","time spent":"490.833762ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T07:11:59.073725Z","caller":"traceutil/trace.go:171","msg":"trace[439470243] transaction","detail":"{read_only:false; response_revision:24985; number_of_response:1; }","duration":"496.747053ms","start":"2026-06-08T07:11:58.576943Z","end":"2026-06-08T07:11:59.07369Z","steps":["trace[439470243] 'process raft request' (duration: 429.484503ms)","trace[439470243] 'compare' (duration: 66.946354ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T07:11:59.074189Z","caller":"traceutil/trace.go:171","msg":"trace[1172293946] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24987; }","duration":"707.010332ms","start":"2026-06-08T07:11:58.367165Z","end":"2026-06-08T07:11:59.074175Z","steps":["trace[1172293946] 'agreement among raft nodes before linearized reading' (duration: 706.89906ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:11:59.074228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:11:58.36715Z","time spent":"707.069845ms","remote":"127.0.0.1:56754","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-06-08T07:11:59.074239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.809061ms","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-06-08T07:11:59.074232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"698.362858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-06-08T07:11:59.074176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.289428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-08T07:11:59.0743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.194146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-06-08T07:11:59.074291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:11:58.653394Z","time spent":"420.804637ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T07:11:59.074307Z","caller":"traceutil/trace.go:171","msg":"trace[776874123] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24987; }","duration":"530.914314ms","start":"2026-06-08T07:11:58.543373Z","end":"2026-06-08T07:11:59.074287Z","steps":["trace[776874123] 'agreement among raft nodes before linearized reading' (duration: 530.697479ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T07:11:59.074306Z","caller":"traceutil/trace.go:171","msg":"trace[456647453] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24987; }","duration":"698.4496ms","start":"2026-06-08T07:11:58.375842Z","end":"2026-06-08T07:11:59.074292Z","steps":["trace[456647453] 'agreement among raft nodes before linearized reading' (duration: 698.241986ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T07:11:59.074326Z","caller":"traceutil/trace.go:171","msg":"trace[2011009728] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24987; }","duration":"334.484782ms","start":"2026-06-08T07:11:58.739829Z","end":"2026-06-08T07:11:59.074314Z","steps":["trace[2011009728] 'agreement among raft nodes before linearized reading' (duration: 334.267338ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T07:11:59.074342Z","caller":"traceutil/trace.go:171","msg":"trace[572221821] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24987; }","duration":"282.260156ms","start":"2026-06-08T07:11:58.792064Z","end":"2026-06-08T07:11:59.074324Z","steps":["trace[572221821] 'agreement among raft nodes before linearized reading' (duration: 282.138604ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:11:59.074347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:11:58.37579Z","time spent":"698.548382ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-08T07:11:59.074347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:11:58.543351Z","time spent":"530.986845ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-08T07:11:59.074363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:11:58.739812Z","time spent":"334.540803ms","remote":"127.0.0.1:56480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-08T07:11:59.074362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:11:58.576915Z","time spent":"497.316715ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T07:11:59.511299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.577583ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-08T07:11:59.511405Z","caller":"traceutil/trace.go:171","msg":"trace[1075668940] transaction","detail":"{read_only:false; response_revision:24988; number_of_response:1; }","duration":"433.138097ms","start":"2026-06-08T07:11:59.078246Z","end":"2026-06-08T07:11:59.511384Z","steps":["trace[1075668940] 'process raft request' (duration: 192.39133ms)","trace[1075668940] 'compare' (duration: 240.093454ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T07:11:59.511468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:11:59.078221Z","time spent":"433.215618ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T07:11:59.832871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.391469ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-08T07:11:59.833342Z","caller":"traceutil/trace.go:171","msg":"trace[1164480235] transaction","detail":"{read_only:false; response_revision:24989; number_of_response:1; }","duration":"754.295469ms","start":"2026-06-08T07:11:59.079006Z","end":"2026-06-08T07:11:59.833302Z","steps":["trace[1164480235] 'process raft request' (duration: 560.390189ms)","trace[1164480235] 'compare' (duration: 192.91857ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T07:11:59.833391Z","caller":"traceutil/trace.go:171","msg":"trace[697074975] transaction","detail":"{read_only:false; response_revision:24990; number_of_response:1; }","duration":"754.065504ms","start":"2026-06-08T07:11:59.079305Z","end":"2026-06-08T07:11:59.833371Z","steps":["trace[697074975] 'process raft request' (duration: 753.665836ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:11:59.833567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:11:59.078984Z","time spent":"754.433291ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T07:11:59.833625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:11:59.079296Z","time spent":"754.139545ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T07:11:59.83381Z","caller":"traceutil/trace.go:171","msg":"trace[1571862309] transaction","detail":"{read_only:false; response_revision:24992; number_of_response:1; }","duration":"754.363019ms","start":"2026-06-08T07:11:59.079428Z","end":"2026-06-08T07:11:59.833791Z","steps":["trace[1571862309] 'process raft request' (duration: 754.253077ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T07:11:59.833818Z","caller":"traceutil/trace.go:171","msg":"trace[1207658023] transaction","detail":"{read_only:false; response_revision:24993; number_of_response:1; }","duration":"399.392674ms","start":"2026-06-08T07:11:59.434397Z","end":"2026-06-08T07:11:59.83379Z","steps":["trace[1207658023] 'process raft request' (duration: 399.368444ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T07:11:59.833807Z","caller":"traceutil/trace.go:171","msg":"trace[796585143] transaction","detail":"{read_only:false; response_revision:24991; number_of_response:1; }","duration":"754.39051ms","start":"2026-06-08T07:11:59.079383Z","end":"2026-06-08T07:11:59.833774Z","steps":["trace[796585143] 'process raft request' (duration: 753.850619ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:11:59.833863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:11:59.079422Z","time spent":"754.41603ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T07:11:59.833849Z","caller":"traceutil/trace.go:171","msg":"trace[533014451] linearizableReadLoop","detail":"{readStateIndex:25805; appliedIndex:25800; }","duration":"662.681658ms","start":"2026-06-08T07:11:59.171111Z","end":"2026-06-08T07:11:59.833792Z","steps":["trace[533014451] 'read index received' (duration: 99.719437ms)","trace[533014451] 'applied index is now lower than readState.Index' (duration: 562.96013ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T07:11:59.833924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:11:59.434372Z","time spent":"399.496037ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T07:11:59.833946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:11:59.079377Z","time spent":"754.503792ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T07:11:59.834018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.901572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-08T07:11:59.8341Z","caller":"traceutil/trace.go:171","msg":"trace[1349572374] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesets/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesets0; response_count:0; response_revision:24993; }","duration":"662.989653ms","start":"2026-06-08T07:11:59.171096Z","end":"2026-06-08T07:11:59.834086Z","steps":["trace[1349572374] 'agreement among raft nodes before linearized reading' (duration: 662.84212ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:11:59.834172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:11:59.171031Z","time spent":"663.127366ms","remote":"127.0.0.1:54554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":29,"request content":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true "} {"level":"warn","ts":"2026-06-08T07:12:00.05055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.609106ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-08T07:12:00.050658Z","caller":"traceutil/trace.go:171","msg":"trace[2134671187] linearizableReadLoop","detail":"{readStateIndex:25807; appliedIndex:25806; }","duration":"216.595398ms","start":"2026-06-08T07:11:59.834045Z","end":"2026-06-08T07:12:00.050641Z","steps":["trace[2134671187] 'read index received' (duration: 102.797548ms)","trace[2134671187] 'applied index is now lower than readState.Index' (duration: 113.79662ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T07:12:00.05089Z","caller":"traceutil/trace.go:171","msg":"trace[1527143847] transaction","detail":"{read_only:false; response_revision:24994; number_of_response:1; }","duration":"389.250779ms","start":"2026-06-08T07:11:59.66161Z","end":"2026-06-08T07:12:00.050861Z","steps":["trace[1527143847] 'process raft request' (duration: 275.256235ms)","trace[1527143847] 'compare' (duration: 113.490473ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T07:12:00.050912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.149283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/services/\" range_end:\"/registry/openstack.k-orc.cloud/services0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-08T07:12:00.05091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.433568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/volumes/\" range_end:\"/registry/openstack.k-orc.cloud/volumes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-08T07:12:00.050942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.508837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-08T07:12:00.050975Z","caller":"traceutil/trace.go:171","msg":"trace[253645532] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/services/; range_end:/registry/openstack.k-orc.cloud/services0; response_count:0; response_revision:24994; }","duration":"277.257285ms","start":"2026-06-08T07:11:59.773703Z","end":"2026-06-08T07:12:00.05096Z","steps":["trace[253645532] 'agreement among raft nodes before linearized reading' (duration: 277.137553ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:00.050913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.611144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-08T07:12:00.051001Z","caller":"traceutil/trace.go:171","msg":"trace[1332887123] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/volumes/; range_end:/registry/openstack.k-orc.cloud/volumes0; response_count:0; response_revision:24994; }","duration":"461.587702ms","start":"2026-06-08T07:11:59.589389Z","end":"2026-06-08T07:12:00.050977Z","steps":["trace[1332887123] 'agreement among raft nodes before linearized reading' (duration: 461.409348ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:00.051024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:11:59.661586Z","time spent":"389.366391ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T07:12:00.051021Z","caller":"traceutil/trace.go:171","msg":"trace[1705594108] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24994; }","duration":"310.64284ms","start":"2026-06-08T07:11:59.740355Z","end":"2026-06-08T07:12:00.050998Z","steps":["trace[1705594108] 'agreement among raft nodes before linearized reading' (duration: 310.489057ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T07:12:00.05105Z","caller":"traceutil/trace.go:171","msg":"trace[1359166937] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:24994; }","duration":"610.797638ms","start":"2026-06-08T07:11:59.440238Z","end":"2026-06-08T07:12:00.051035Z","steps":["trace[1359166937] 'agreement among raft nodes before linearized reading' (duration: 610.632535ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:00.05106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:11:59.589369Z","time spent":"461.675353ms","remote":"127.0.0.1:54322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/volumes/\" range_end:\"/registry/openstack.k-orc.cloud/volumes0\" count_only:true "} {"level":"warn","ts":"2026-06-08T07:12:00.051066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:11:59.740336Z","time spent":"310.721202ms","remote":"127.0.0.1:56494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-08T07:12:00.051103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:11:59.440217Z","time spent":"610.87063ms","remote":"127.0.0.1:33494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-06-08T07:12:00.672318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.852713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-08T07:12:00.672426Z","caller":"traceutil/trace.go:171","msg":"trace[804149319] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:24994; }","duration":"214.016017ms","start":"2026-06-08T07:12:00.458385Z","end":"2026-06-08T07:12:00.672401Z","steps":["trace[804149319] 'range keys from in-memory index tree' (duration: 213.767632ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T07:12:00.792992Z","caller":"traceutil/trace.go:171","msg":"trace[1244745078] linearizableReadLoop","detail":"{readStateIndex:25808; appliedIndex:25807; }","duration":"113.888312ms","start":"2026-06-08T07:12:00.679082Z","end":"2026-06-08T07:12:00.792971Z","steps":["trace[1244745078] 'read index received' (duration: 113.77497ms)","trace[1244745078] 'applied index is now lower than readState.Index' (duration: 112.392µs)"],"step_count":2} {"level":"warn","ts":"2026-06-08T07:12:00.793095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.997474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-08T07:12:00.793065Z","caller":"traceutil/trace.go:171","msg":"trace[1142647532] transaction","detail":"{read_only:false; response_revision:24995; number_of_response:1; }","duration":"127.973997ms","start":"2026-06-08T07:12:00.665055Z","end":"2026-06-08T07:12:00.793029Z","steps":["trace[1142647532] 'process raft request' (duration: 127.773773ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T07:12:00.793126Z","caller":"traceutil/trace.go:171","msg":"trace[1014161893] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:24995; }","duration":"114.042165ms","start":"2026-06-08T07:12:00.679074Z","end":"2026-06-08T07:12:00.793116Z","steps":["trace[1014161893] 'agreement among raft nodes before linearized reading' (duration: 113.977273ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T07:12:01.123632Z","caller":"traceutil/trace.go:171","msg":"trace[479003630] transaction","detail":"{read_only:false; response_revision:24996; number_of_response:1; }","duration":"429.3945ms","start":"2026-06-08T07:12:00.694214Z","end":"2026-06-08T07:12:01.123608Z","steps":["trace[479003630] 'process raft request' (duration: 429.215147ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:01.12377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:12:00.694188Z","time spent":"429.512803ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T07:12:01.123806Z","caller":"traceutil/trace.go:171","msg":"trace[246733006] linearizableReadLoop","detail":"{readStateIndex:25810; appliedIndex:25808; }","duration":"326.581473ms","start":"2026-06-08T07:12:00.797197Z","end":"2026-06-08T07:12:01.123779Z","steps":["trace[246733006] 'read index received' (duration: 326.356847ms)","trace[246733006] 'applied index is now lower than readState.Index' (duration: 222.536µs)"],"step_count":2} {"level":"info","ts":"2026-06-08T07:12:01.123843Z","caller":"traceutil/trace.go:171","msg":"trace[2031032256] transaction","detail":"{read_only:false; response_revision:24997; number_of_response:1; }","duration":"429.34764ms","start":"2026-06-08T07:12:00.694469Z","end":"2026-06-08T07:12:01.123817Z","steps":["trace[2031032256] 'process raft request' (duration: 429.103385ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:01.123973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:12:00.694452Z","time spent":"429.449022ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T07:12:01.123981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.771226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-08T07:12:01.124027Z","caller":"traceutil/trace.go:171","msg":"trace[764477438] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:24997; }","duration":"326.908409ms","start":"2026-06-08T07:12:00.797106Z","end":"2026-06-08T07:12:01.124014Z","steps":["trace[764477438] 'agreement among raft nodes before linearized reading' (duration: 326.809617ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:01.124079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:12:00.797086Z","time spent":"326.98439ms","remote":"127.0.0.1:56770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" "} {"level":"info","ts":"2026-06-08T07:12:01.429332Z","caller":"traceutil/trace.go:171","msg":"trace[1322367638] transaction","detail":"{read_only:false; response_revision:24998; number_of_response:1; }","duration":"597.030881ms","start":"2026-06-08T07:12:00.832277Z","end":"2026-06-08T07:12:01.429308Z","steps":["trace[1322367638] 'process raft request' (duration: 596.760425ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:01.429488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:12:00.832257Z","time spent":"597.148653ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T07:12:01.429561Z","caller":"traceutil/trace.go:171","msg":"trace[1405653652] transaction","detail":"{read_only:false; response_revision:24999; number_of_response:1; }","duration":"350.856764ms","start":"2026-06-08T07:12:01.078682Z","end":"2026-06-08T07:12:01.429538Z","steps":["trace[1405653652] 'process raft request' (duration: 350.571788ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T07:12:01.429541Z","caller":"traceutil/trace.go:171","msg":"trace[208772507] linearizableReadLoop","detail":"{readStateIndex:25814; appliedIndex:25810; }","duration":"305.637538ms","start":"2026-06-08T07:12:01.123887Z","end":"2026-06-08T07:12:01.429524Z","steps":["trace[208772507] 'read index received' (duration: 305.269441ms)","trace[208772507] 'applied index is now lower than readState.Index' (duration: 367.517µs)"],"step_count":2} {"level":"info","ts":"2026-06-08T07:12:01.429668Z","caller":"traceutil/trace.go:171","msg":"trace[641146257] transaction","detail":"{read_only:false; response_revision:25001; number_of_response:1; }","duration":"348.882234ms","start":"2026-06-08T07:12:01.080778Z","end":"2026-06-08T07:12:01.42966Z","steps":["trace[641146257] 'process raft request' (duration: 348.718621ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T07:12:01.429635Z","caller":"traceutil/trace.go:171","msg":"trace[1039295452] transaction","detail":"{read_only:false; response_revision:25000; number_of_response:1; }","duration":"350.240231ms","start":"2026-06-08T07:12:01.079357Z","end":"2026-06-08T07:12:01.429597Z","steps":["trace[1039295452] 'process raft request' (duration: 350.080468ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:01.42968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:12:01.078663Z","time spent":"350.960286ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T07:12:01.429747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:12:01.080768Z","time spent":"348.924695ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T07:12:01.429803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:12:01.079329Z","time spent":"350.385333ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T07:12:01.429867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.474115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-08T07:12:01.429936Z","caller":"traceutil/trace.go:171","msg":"trace[1875346010] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:25001; }","duration":"535.577197ms","start":"2026-06-08T07:12:00.894339Z","end":"2026-06-08T07:12:01.429916Z","steps":["trace[1875346010] 'agreement among raft nodes before linearized reading' (duration: 535.439924ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:01.429988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:12:00.894319Z","time spent":"535.655958ms","remote":"127.0.0.1:58930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true "} {"level":"warn","ts":"2026-06-08T07:12:01.50089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.116812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-08T07:12:01.500968Z","caller":"traceutil/trace.go:171","msg":"trace[658828600] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25001; }","duration":"371.240575ms","start":"2026-06-08T07:12:01.12971Z","end":"2026-06-08T07:12:01.500951Z","steps":["trace[658828600] 'agreement among raft nodes before linearized reading' (duration: 371.112582ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:01.501009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:12:01.129692Z","time spent":"371.307376ms","remote":"127.0.0.1:56770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" "} {"level":"info","ts":"2026-06-08T07:12:01.64372Z","caller":"traceutil/trace.go:171","msg":"trace[735932217] transaction","detail":"{read_only:false; response_revision:25002; number_of_response:1; }","duration":"122.848024ms","start":"2026-06-08T07:12:01.52056Z","end":"2026-06-08T07:12:01.643408Z","steps":["trace[735932217] 'process raft request' (duration: 122.558368ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:01.903819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.979805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-08T07:12:01.903925Z","caller":"traceutil/trace.go:171","msg":"trace[1265799016] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25002; }","duration":"163.123668ms","start":"2026-06-08T07:12:01.740778Z","end":"2026-06-08T07:12:01.903901Z","steps":["trace[1265799016] 'range keys from in-memory index tree' (duration: 162.897673ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T07:12:02.164123Z","caller":"traceutil/trace.go:171","msg":"trace[1144848550] transaction","detail":"{read_only:false; response_revision:25005; number_of_response:1; }","duration":"250.505905ms","start":"2026-06-08T07:12:01.91359Z","end":"2026-06-08T07:12:02.164096Z","steps":["trace[1144848550] 'process raft request' (duration: 227.883368ms)","trace[1144848550] 'compare' (duration: 21.992265ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T07:12:02.164533Z","caller":"traceutil/trace.go:171","msg":"trace[563314817] transaction","detail":"{read_only:false; response_revision:25006; number_of_response:1; }","duration":"250.901133ms","start":"2026-06-08T07:12:01.913603Z","end":"2026-06-08T07:12:02.164504Z","steps":["trace[563314817] 'process raft request' (duration: 250.313582ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T07:12:02.164736Z","caller":"traceutil/trace.go:171","msg":"trace[470300348] transaction","detail":"{read_only:false; response_revision:25008; number_of_response:1; }","duration":"109.037895ms","start":"2026-06-08T07:12:02.055681Z","end":"2026-06-08T07:12:02.164719Z","steps":["trace[470300348] 'process raft request' (duration: 108.81765ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T07:12:02.164542Z","caller":"traceutil/trace.go:171","msg":"trace[647143268] transaction","detail":"{read_only:false; response_revision:25007; number_of_response:1; }","duration":"250.840372ms","start":"2026-06-08T07:12:01.913689Z","end":"2026-06-08T07:12:02.164529Z","steps":["trace[647143268] 'process raft request' (duration: 250.74874ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T07:12:02.750123Z","caller":"traceutil/trace.go:171","msg":"trace[384378411] transaction","detail":"{read_only:false; response_revision:25009; number_of_response:1; }","duration":"125.701162ms","start":"2026-06-08T07:12:02.624399Z","end":"2026-06-08T07:12:02.7501Z","steps":["trace[384378411] 'process raft request' (duration: 125.422106ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T07:12:02.945653Z","caller":"traceutil/trace.go:171","msg":"trace[1624771114] transaction","detail":"{read_only:false; response_revision:25010; number_of_response:1; }","duration":"147.477742ms","start":"2026-06-08T07:12:02.798155Z","end":"2026-06-08T07:12:02.945633Z","steps":["trace[1624771114] 'process raft request' (duration: 147.341259ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:03.639704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.153143ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-08T07:12:03.63988Z","caller":"traceutil/trace.go:171","msg":"trace[1636551734] linearizableReadLoop","detail":"{readStateIndex:25826; appliedIndex:25824; }","duration":"430.539354ms","start":"2026-06-08T07:12:03.209323Z","end":"2026-06-08T07:12:03.639862Z","steps":["trace[1636551734] 'read index received' (duration: 765.755µs)","trace[1636551734] 'applied index is now lower than readState.Index' (duration: 429.772569ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T07:12:03.639863Z","caller":"traceutil/trace.go:171","msg":"trace[241717849] transaction","detail":"{read_only:false; response_revision:25012; number_of_response:1; }","duration":"510.152283ms","start":"2026-06-08T07:12:03.129663Z","end":"2026-06-08T07:12:03.639815Z","steps":["trace[241717849] 'process raft request' (duration: 234.791586ms)","trace[241717849] 'compare' (duration: 274.9993ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T07:12:03.640049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:12:03.129655Z","time spent":"510.294316ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T07:12:03.640196Z","caller":"traceutil/trace.go:171","msg":"trace[1320085679] transaction","detail":"{read_only:false; response_revision:25013; number_of_response:1; }","duration":"206.100237ms","start":"2026-06-08T07:12:03.434062Z","end":"2026-06-08T07:12:03.640162Z","steps":["trace[1320085679] 'process raft request' (duration: 205.75311ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:03.640604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.945572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/trunks/\" range_end:\"/registry/openstack.k-orc.cloud/trunks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-08T07:12:03.640709Z","caller":"traceutil/trace.go:171","msg":"trace[1133452984] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/trunks/; range_end:/registry/openstack.k-orc.cloud/trunks0; response_count:0; response_revision:25013; }","duration":"431.404581ms","start":"2026-06-08T07:12:03.209258Z","end":"2026-06-08T07:12:03.640662Z","steps":["trace[1133452984] 'agreement among raft nodes before linearized reading' (duration: 430.656646ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:03.641395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:12:03.209228Z","time spent":"432.127386ms","remote":"127.0.0.1:54356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/trunks/\" range_end:\"/registry/openstack.k-orc.cloud/trunks0\" count_only:true "} {"level":"info","ts":"2026-06-08T07:12:03.646316Z","caller":"traceutil/trace.go:171","msg":"trace[1286033723] transaction","detail":"{read_only:false; response_revision:25014; number_of_response:1; }","duration":"211.362072ms","start":"2026-06-08T07:12:03.434941Z","end":"2026-06-08T07:12:03.646303Z","steps":["trace[1286033723] 'process raft request' (duration: 211.148988ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T07:12:03.646494Z","caller":"traceutil/trace.go:171","msg":"trace[1949385151] transaction","detail":"{read_only:false; response_revision:25015; number_of_response:1; }","duration":"211.029976ms","start":"2026-06-08T07:12:03.435445Z","end":"2026-06-08T07:12:03.646475Z","steps":["trace[1949385151] 'process raft request' (duration: 210.779741ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T07:12:03.940101Z","caller":"traceutil/trace.go:171","msg":"trace[951400556] transaction","detail":"{read_only:false; response_revision:25017; number_of_response:1; }","duration":"287.90123ms","start":"2026-06-08T07:12:03.652178Z","end":"2026-06-08T07:12:03.940079Z","steps":["trace[951400556] 'process raft request' (duration: 191.539692ms)","trace[951400556] 'compare' (duration: 95.845028ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T07:12:03.940154Z","caller":"traceutil/trace.go:171","msg":"trace[946981922] transaction","detail":"{read_only:false; response_revision:25018; number_of_response:1; }","duration":"272.494478ms","start":"2026-06-08T07:12:03.667629Z","end":"2026-06-08T07:12:03.940124Z","steps":["trace[946981922] 'process raft request' (duration: 272.388936ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:03.940187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.775118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-08T07:12:03.940101Z","caller":"traceutil/trace.go:171","msg":"trace[27580564] linearizableReadLoop","detail":"{readStateIndex:25832; appliedIndex:25830; }","duration":"198.674116ms","start":"2026-06-08T07:12:03.741406Z","end":"2026-06-08T07:12:03.94008Z","steps":["trace[27580564] 'read index received' (duration: 102.326388ms)","trace[27580564] 'applied index is now lower than readState.Index' (duration: 96.346868ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T07:12:03.940229Z","caller":"traceutil/trace.go:171","msg":"trace[312409811] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25018; }","duration":"198.86491ms","start":"2026-06-08T07:12:03.741355Z","end":"2026-06-08T07:12:03.940219Z","steps":["trace[312409811] 'agreement among raft nodes before linearized reading' (duration: 198.783659ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T07:12:04.015406Z","caller":"traceutil/trace.go:171","msg":"trace[1319258540] transaction","detail":"{read_only:false; response_revision:25019; number_of_response:1; }","duration":"103.37781ms","start":"2026-06-08T07:12:03.911997Z","end":"2026-06-08T07:12:04.015375Z","steps":["trace[1319258540] 'process raft request' (duration: 103.161555ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:04.339924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.625233ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-08T07:12:04.340016Z","caller":"traceutil/trace.go:171","msg":"trace[1708238123] linearizableReadLoop","detail":"{readStateIndex:25834; appliedIndex:25833; }","duration":"171.100068ms","start":"2026-06-08T07:12:04.1689Z","end":"2026-06-08T07:12:04.34Z","steps":["trace[1708238123] 'read index received' (duration: 34.01µs)","trace[1708238123] 'applied index is now lower than readState.Index' (duration: 171.064788ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T07:12:04.340078Z","caller":"traceutil/trace.go:171","msg":"trace[1541630234] transaction","detail":"{read_only:false; response_revision:25020; number_of_response:1; }","duration":"320.352377ms","start":"2026-06-08T07:12:04.019714Z","end":"2026-06-08T07:12:04.340067Z","steps":["trace[1541630234] 'process raft request' (duration: 137.495859ms)","trace[1541630234] 'compare' (duration: 182.146802ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T07:12:04.340155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:12:04.019699Z","time spent":"320.423948ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T07:12:04.340462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.547857ms","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-06-08T07:12:04.340604Z","caller":"traceutil/trace.go:171","msg":"trace[676513357] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25020; }","duration":"171.725801ms","start":"2026-06-08T07:12:04.168859Z","end":"2026-06-08T07:12:04.340584Z","steps":["trace[676513357] 'agreement among raft nodes before linearized reading' (duration: 171.450235ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T07:12:04.401011Z","caller":"traceutil/trace.go:171","msg":"trace[830958349] transaction","detail":"{read_only:false; response_revision:25021; number_of_response:1; }","duration":"229.778495ms","start":"2026-06-08T07:12:04.171198Z","end":"2026-06-08T07:12:04.400976Z","steps":["trace[830958349] 'process raft request' (duration: 229.552931ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:04.401275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.842477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-06-08T07:12:04.401377Z","caller":"traceutil/trace.go:171","msg":"trace[1751075329] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25021; }","duration":"229.9891ms","start":"2026-06-08T07:12:04.171369Z","end":"2026-06-08T07:12:04.401358Z","steps":["trace[1751075329] 'agreement among raft nodes before linearized reading' (duration: 229.618452ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:04.401761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.553361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-08T07:12:04.401815Z","caller":"traceutil/trace.go:171","msg":"trace[2042661742] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25021; }","duration":"230.658904ms","start":"2026-06-08T07:12:04.171144Z","end":"2026-06-08T07:12:04.401803Z","steps":["trace[2042661742] 'agreement among raft nodes before linearized reading' (duration: 229.787675ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:04.719244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.891127ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-08T07:12:04.719383Z","caller":"traceutil/trace.go:171","msg":"trace[974691042] transaction","detail":"{read_only:false; response_revision:25022; number_of_response:1; }","duration":"375.425229ms","start":"2026-06-08T07:12:04.343931Z","end":"2026-06-08T07:12:04.719356Z","steps":["trace[974691042] 'process raft request' (duration: 102.330758ms)","trace[974691042] 'compare' (duration: 272.155972ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T07:12:04.71948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:12:04.343908Z","time spent":"375.525891ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T07:12:04.817367Z","caller":"traceutil/trace.go:171","msg":"trace[1405770389] linearizableReadLoop","detail":"{readStateIndex:25838; appliedIndex:25836; }","duration":"346.453754ms","start":"2026-06-08T07:12:04.470883Z","end":"2026-06-08T07:12:04.817336Z","steps":["trace[1405770389] 'read index received' (duration: 346.26658ms)","trace[1405770389] 'applied index is now lower than readState.Index' (duration: 186.024µs)"],"step_count":2} {"level":"warn","ts":"2026-06-08T07:12:04.817524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.621978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-08T07:12:04.817578Z","caller":"traceutil/trace.go:171","msg":"trace[1011436580] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:25024; }","duration":"346.726559ms","start":"2026-06-08T07:12:04.470833Z","end":"2026-06-08T07:12:04.81756Z","steps":["trace[1011436580] 'agreement among raft nodes before linearized reading' (duration: 346.620007ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T07:12:04.817602Z","caller":"traceutil/trace.go:171","msg":"trace[1452966981] transaction","detail":"{read_only:false; response_revision:25023; number_of_response:1; }","duration":"410.334005ms","start":"2026-06-08T07:12:04.407247Z","end":"2026-06-08T07:12:04.817581Z","steps":["trace[1452966981] 'process raft request' (duration: 409.888686ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:04.817646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:12:04.470814Z","time spent":"346.812342ms","remote":"127.0.0.1:56900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":31,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-06-08T07:12:04.817678Z","caller":"traceutil/trace.go:171","msg":"trace[1753473846] transaction","detail":"{read_only:false; response_revision:25024; number_of_response:1; }","duration":"410.332745ms","start":"2026-06-08T07:12:04.407294Z","end":"2026-06-08T07:12:04.817627Z","steps":["trace[1753473846] 'process raft request' (duration: 409.985828ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:04.817708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:12:04.407227Z","time spent":"410.418087ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T07:12:04.817863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:12:04.407271Z","time spent":"410.499619ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T07:12:04.866827Z","caller":"traceutil/trace.go:171","msg":"trace[657983975] transaction","detail":"{read_only:false; response_revision:25025; number_of_response:1; }","duration":"202.125466ms","start":"2026-06-08T07:12:04.664672Z","end":"2026-06-08T07:12:04.866798Z","steps":["trace[657983975] 'process raft request' (duration: 171.333334ms)","trace[657983975] 'compare' (duration: 30.67318ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T07:12:05.193695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.276302ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-08T07:12:05.193823Z","caller":"traceutil/trace.go:171","msg":"trace[744909111] linearizableReadLoop","detail":"{readStateIndex:25840; appliedIndex:25839; }","duration":"121.529157ms","start":"2026-06-08T07:12:05.072275Z","end":"2026-06-08T07:12:05.193804Z","steps":["trace[744909111] 'read index received' (duration: 49.671µs)","trace[744909111] 'applied index is now lower than readState.Index' (duration: 121.477536ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T07:12:05.193921Z","caller":"traceutil/trace.go:171","msg":"trace[1406447402] transaction","detail":"{read_only:false; response_revision:25026; number_of_response:1; }","duration":"242.359769ms","start":"2026-06-08T07:12:04.95153Z","end":"2026-06-08T07:12:05.19389Z","steps":["trace[1406447402] 'process raft request' (duration: 117.797061ms)","trace[1406447402] 'compare' (duration: 124.053708ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T07:12:05.193985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.69722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-08T07:12:05.19402Z","caller":"traceutil/trace.go:171","msg":"trace[1461290345] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:25026; }","duration":"121.779032ms","start":"2026-06-08T07:12:05.072228Z","end":"2026-06-08T07:12:05.194007Z","steps":["trace[1461290345] 'agreement among raft nodes before linearized reading' (duration: 121.6719ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:05.441846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.168033ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-08T07:12:05.442034Z","caller":"traceutil/trace.go:171","msg":"trace[685253371] transaction","detail":"{read_only:false; response_revision:25027; number_of_response:1; }","duration":"245.096634ms","start":"2026-06-08T07:12:05.196908Z","end":"2026-06-08T07:12:05.442005Z","steps":["trace[685253371] 'process raft request' (duration: 108.629365ms)","trace[685253371] 'compare' (duration: 135.959379ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T07:12:05.912753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.998248ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-08T07:12:05.912834Z","caller":"traceutil/trace.go:171","msg":"trace[462163933] linearizableReadLoop","detail":"{readStateIndex:25842; appliedIndex:25841; }","duration":"525.386711ms","start":"2026-06-08T07:12:05.387434Z","end":"2026-06-08T07:12:05.912821Z","steps":["trace[462163933] 'read index received' (duration: 350.20119ms)","trace[462163933] 'applied index is now lower than readState.Index' (duration: 175.184361ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T07:12:05.91289Z","caller":"traceutil/trace.go:171","msg":"trace[122773501] transaction","detail":"{read_only:false; response_revision:25028; number_of_response:1; }","duration":"696.02625ms","start":"2026-06-08T07:12:05.216853Z","end":"2026-06-08T07:12:05.91288Z","steps":["trace[122773501] 'process raft request' (duration: 520.813569ms)","trace[122773501] 'compare' (duration: 174.731712ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T07:12:05.912937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:12:05.216839Z","time spent":"696.073931ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T07:12:05.913003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.545424ms","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-06-08T07:12:05.913084Z","caller":"traceutil/trace.go:171","msg":"trace[1334974366] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:25028; }","duration":"525.677047ms","start":"2026-06-08T07:12:05.387389Z","end":"2026-06-08T07:12:05.913066Z","steps":["trace[1334974366] 'agreement among raft nodes before linearized reading' (duration: 525.544484ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:05.913125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:12:05.387367Z","time spent":"525.749489ms","remote":"127.0.0.1:45442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true "} {"level":"warn","ts":"2026-06-08T07:12:06.142399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.859193ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-08T07:12:06.14266Z","caller":"traceutil/trace.go:171","msg":"trace[1028614899] linearizableReadLoop","detail":"{readStateIndex:25846; appliedIndex:25842; }","duration":"229.797915ms","start":"2026-06-08T07:12:05.912849Z","end":"2026-06-08T07:12:06.142647Z","steps":["trace[1028614899] 'read index received' (duration: 108.704767ms)","trace[1028614899] 'applied index is now lower than readState.Index' (duration: 121.092438ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T07:12:06.142723Z","caller":"traceutil/trace.go:171","msg":"trace[1702952711] transaction","detail":"{read_only:false; response_revision:25029; number_of_response:1; }","duration":"497.249042ms","start":"2026-06-08T07:12:05.645465Z","end":"2026-06-08T07:12:06.142714Z","steps":["trace[1702952711] 'process raft request' (duration: 376.003152ms)","trace[1702952711] 'compare' (duration: 120.663249ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T07:12:06.142763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:12:05.645437Z","time spent":"497.302963ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T07:12:06.14277Z","caller":"traceutil/trace.go:171","msg":"trace[891401852] transaction","detail":"{read_only:false; response_revision:25031; number_of_response:1; }","duration":"492.780022ms","start":"2026-06-08T07:12:05.649966Z","end":"2026-06-08T07:12:06.142746Z","steps":["trace[891401852] 'process raft request' (duration: 492.601228ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:06.142911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:12:05.649931Z","time spent":"492.900905ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T07:12:06.142986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.810663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-08T07:12:06.14302Z","caller":"traceutil/trace.go:171","msg":"trace[912103214] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25032; }","duration":"401.876044ms","start":"2026-06-08T07:12:05.741137Z","end":"2026-06-08T07:12:06.143013Z","steps":["trace[912103214] 'agreement among raft nodes before linearized reading' (duration: 401.798593ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:06.143041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:12:05.74112Z","time spent":"401.916385ms","remote":"127.0.0.1:56494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-08T07:12:06.14311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.305886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.113\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-06-08T07:12:06.143148Z","caller":"traceutil/trace.go:171","msg":"trace[424903566] transaction","detail":"{read_only:false; response_revision:25032; number_of_response:1; }","duration":"492.148059ms","start":"2026-06-08T07:12:05.65099Z","end":"2026-06-08T07:12:06.143138Z","steps":["trace[424903566] 'process raft request' (duration: 491.625338ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T07:12:06.143104Z","caller":"traceutil/trace.go:171","msg":"trace[1083604155] transaction","detail":"{read_only:false; response_revision:25030; number_of_response:1; }","duration":"496.569099ms","start":"2026-06-08T07:12:05.646506Z","end":"2026-06-08T07:12:06.143075Z","steps":["trace[1083604155] 'process raft request' (duration: 495.975877ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T07:12:06.143173Z","caller":"traceutil/trace.go:171","msg":"trace[1687537272] range","detail":"{range_begin:/registry/masterleases/199.204.45.113; range_end:; response_count:1; response_revision:25032; }","duration":"277.424098ms","start":"2026-06-08T07:12:05.865735Z","end":"2026-06-08T07:12:06.143159Z","steps":["trace[1687537272] 'agreement among raft nodes before linearized reading' (duration: 277.190574ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:06.143193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:12:05.650977Z","time spent":"492.193689ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T07:12:06.143254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:12:05.646487Z","time spent":"496.697761ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T07:12:06.14983Z","caller":"traceutil/trace.go:171","msg":"trace[583545231] transaction","detail":"{read_only:false; response_revision:25033; number_of_response:1; }","duration":"129.673942ms","start":"2026-06-08T07:12:06.020135Z","end":"2026-06-08T07:12:06.149809Z","steps":["trace[583545231] 'process raft request' (duration: 129.526989ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:06.149957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.626798ms","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-06-08T07:12:06.15003Z","caller":"traceutil/trace.go:171","msg":"trace[384555781] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25033; }","duration":"205.75078ms","start":"2026-06-08T07:12:05.94426Z","end":"2026-06-08T07:12:06.150011Z","steps":["trace[384555781] 'agreement among raft nodes before linearized reading' (duration: 205.561316ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T07:12:21.362766Z","caller":"traceutil/trace.go:171","msg":"trace[2080531303] linearizableReadLoop","detail":"{readStateIndex:25959; appliedIndex:25958; }","duration":"477.954962ms","start":"2026-06-08T07:12:20.884786Z","end":"2026-06-08T07:12:21.362741Z","steps":["trace[2080531303] 'read index received' (duration: 477.84074ms)","trace[2080531303] 'applied index is now lower than readState.Index' (duration: 113.262µs)"],"step_count":2} {"level":"info","ts":"2026-06-08T07:12:21.362857Z","caller":"traceutil/trace.go:171","msg":"trace[1014303958] transaction","detail":"{read_only:false; response_revision:25142; number_of_response:1; }","duration":"573.627627ms","start":"2026-06-08T07:12:20.789196Z","end":"2026-06-08T07:12:21.362824Z","steps":["trace[1014303958] 'process raft request' (duration: 573.393222ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:21.363026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:12:20.789185Z","time spent":"573.753599ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T07:12:21.363083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.281818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-08T07:12:21.363149Z","caller":"traceutil/trace.go:171","msg":"trace[2126293028] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25142; }","duration":"478.376301ms","start":"2026-06-08T07:12:20.884756Z","end":"2026-06-08T07:12:21.363132Z","steps":["trace[2126293028] 'agreement among raft nodes before linearized reading' (duration: 478.089855ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:21.363189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:12:20.88474Z","time spent":"478.441572ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-08T07:12:21.363165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.328139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-06-08T07:12:21.363292Z","caller":"traceutil/trace.go:171","msg":"trace[1452865554] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25142; }","duration":"478.519493ms","start":"2026-06-08T07:12:20.884756Z","end":"2026-06-08T07:12:21.363275Z","steps":["trace[1452865554] 'agreement among raft nodes before linearized reading' (duration: 478.212817ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:21.363364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:12:20.88474Z","time spent":"478.610915ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-08T07:12:21.777179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.255982ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-08T07:12:21.777366Z","caller":"traceutil/trace.go:171","msg":"trace[12894009] transaction","detail":"{read_only:false; response_revision:25143; number_of_response:1; }","duration":"511.417268ms","start":"2026-06-08T07:12:21.265907Z","end":"2026-06-08T07:12:21.777325Z","steps":["trace[12894009] 'process raft request' (duration: 291.927421ms)","trace[12894009] 'compare' (duration: 219.11982ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T07:12:21.777524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:12:21.265885Z","time spent":"511.558161ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T07:12:22.033136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.848673ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-06-08T07:12:22.033404Z","caller":"traceutil/trace.go:171","msg":"trace[2135798387] linearizableReadLoop","detail":"{readStateIndex:25961; appliedIndex:25959; }","duration":"670.469295ms","start":"2026-06-08T07:12:21.362911Z","end":"2026-06-08T07:12:22.03338Z","steps":["trace[2135798387] 'read index received' (duration: 195.140876ms)","trace[2135798387] 'applied index is now lower than readState.Index' (duration: 475.326939ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T07:12:22.033643Z","caller":"traceutil/trace.go:171","msg":"trace[1138105861] transaction","detail":"{read_only:false; response_revision:25144; number_of_response:1; }","duration":"666.632627ms","start":"2026-06-08T07:12:21.366979Z","end":"2026-06-08T07:12:22.033612Z","steps":["trace[1138105861] 'process raft request' (duration: 666.27177ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:22.033784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:12:21.366959Z","time spent":"666.744909ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T07:12:22.233105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.229549537s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-08T07:12:22.233207Z","caller":"traceutil/trace.go:171","msg":"trace[980783862] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:25144; }","duration":"1.22969297s","start":"2026-06-08T07:12:21.00349Z","end":"2026-06-08T07:12:22.233183Z","steps":["trace[980783862] 'agreement among raft nodes before linearized reading' (duration: 1.030025354s)","trace[980783862] 'count revisions from in-memory index tree' (duration: 199.530134ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T07:12:22.233252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:12:21.003473Z","time spent":"1.229767771s","remote":"127.0.0.1:45486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" count_only:true "} {"level":"warn","ts":"2026-06-08T07:12:22.233691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.998333ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-08T07:12:22.234023Z","caller":"traceutil/trace.go:171","msg":"trace[513192156] transaction","detail":"{read_only:false; response_revision:25146; number_of_response:1; }","duration":"567.160206ms","start":"2026-06-08T07:12:21.666844Z","end":"2026-06-08T07:12:22.234004Z","steps":["trace[513192156] 'process raft request' (duration: 566.975552ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T07:12:22.234048Z","caller":"traceutil/trace.go:171","msg":"trace[173683278] transaction","detail":"{read_only:false; response_revision:25145; number_of_response:1; }","duration":"864.076468ms","start":"2026-06-08T07:12:21.369905Z","end":"2026-06-08T07:12:22.233981Z","steps":["trace[173683278] 'process raft request' (duration: 663.691067ms)","trace[173683278] 'compare' (duration: 199.398051ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T07:12:22.234145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:12:21.666819Z","time spent":"567.250647ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T07:12:22.234017Z","caller":"traceutil/trace.go:171","msg":"trace[2072955926] linearizableReadLoop","detail":"{readStateIndex:25965; appliedIndex:25962; }","duration":"200.560174ms","start":"2026-06-08T07:12:22.033435Z","end":"2026-06-08T07:12:22.233995Z","steps":["trace[2072955926] 'read index received' (duration: 193.054523ms)","trace[2072955926] 'applied index is now lower than readState.Index' (duration: 7.504401ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T07:12:22.234209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:12:21.369891Z","time spent":"864.240721ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T07:12:22.234291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.713481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-08T07:12:22.234293Z","caller":"traceutil/trace.go:171","msg":"trace[1116303793] transaction","detail":"{read_only:false; response_revision:25147; number_of_response:1; }","duration":"263.333074ms","start":"2026-06-08T07:12:21.970934Z","end":"2026-06-08T07:12:22.234267Z","steps":["trace[1116303793] 'process raft request' (duration: 263.010597ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T07:12:22.23433Z","caller":"traceutil/trace.go:171","msg":"trace[1852235724] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25147; }","duration":"493.794363ms","start":"2026-06-08T07:12:21.740522Z","end":"2026-06-08T07:12:22.234316Z","steps":["trace[1852235724] 'agreement among raft nodes before linearized reading' (duration: 493.67905ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:22.234355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:12:21.740497Z","time spent":"493.853273ms","remote":"127.0.0.1:56494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-08T07:12:22.234354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.852414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-08T07:12:22.234431Z","caller":"traceutil/trace.go:171","msg":"trace[2072179741] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:25147; }","duration":"633.983167ms","start":"2026-06-08T07:12:21.600427Z","end":"2026-06-08T07:12:22.23441Z","steps":["trace[2072179741] 'agreement among raft nodes before linearized reading' (duration: 633.780953ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:22.234495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:12:21.600337Z","time spent":"634.12491ms","remote":"127.0.0.1:56980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":39,"response size":31,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-06-08T07:12:22.234529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.229303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-08T07:12:22.234603Z","caller":"traceutil/trace.go:171","msg":"trace[1216396421] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:25147; }","duration":"687.331875ms","start":"2026-06-08T07:12:21.547225Z","end":"2026-06-08T07:12:22.234557Z","steps":["trace[1216396421] 'agreement among raft nodes before linearized reading' (duration: 686.828244ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:22.234629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:12:21.547207Z","time spent":"687.415467ms","remote":"127.0.0.1:54892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"warn","ts":"2026-06-08T07:12:22.273066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.037639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-08T07:12:22.273145Z","caller":"traceutil/trace.go:171","msg":"trace[298236056] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:25152; }","duration":"129.13046ms","start":"2026-06-08T07:12:22.143996Z","end":"2026-06-08T07:12:22.273126Z","steps":["trace[298236056] 'agreement among raft nodes before linearized reading' (duration: 128.905766ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T07:12:22.628887Z","caller":"traceutil/trace.go:171","msg":"trace[1799248843] linearizableReadLoop","detail":"{readStateIndex:25971; appliedIndex:25970; }","duration":"350.367373ms","start":"2026-06-08T07:12:22.278499Z","end":"2026-06-08T07:12:22.628866Z","steps":["trace[1799248843] 'read index received' (duration: 350.265161ms)","trace[1799248843] 'applied index is now lower than readState.Index' (duration: 100.832µs)"],"step_count":2} {"level":"info","ts":"2026-06-08T07:12:22.63094Z","caller":"traceutil/trace.go:171","msg":"trace[71538757] transaction","detail":"{read_only:false; response_revision:25153; number_of_response:1; }","duration":"354.328664ms","start":"2026-06-08T07:12:22.27655Z","end":"2026-06-08T07:12:22.630879Z","steps":["trace[71538757] 'process raft request' (duration: 352.200971ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:22.631108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.569478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-08T07:12:22.631494Z","caller":"traceutil/trace.go:171","msg":"trace[806158125] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25153; }","duration":"352.933225ms","start":"2026-06-08T07:12:22.278469Z","end":"2026-06-08T07:12:22.631402Z","steps":["trace[806158125] 'agreement among raft nodes before linearized reading' (duration: 350.491996ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:22.631455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:12:22.276519Z","time spent":"354.738462ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T07:12:22.631641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:12:22.278453Z","time spent":"353.146469ms","remote":"127.0.0.1:56770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" "} {"level":"warn","ts":"2026-06-08T07:12:22.965394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.505456ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-08T07:12:22.965515Z","caller":"traceutil/trace.go:171","msg":"trace[1070243354] linearizableReadLoop","detail":"{readStateIndex:25972; appliedIndex:25971; }","duration":"336.560924ms","start":"2026-06-08T07:12:22.628934Z","end":"2026-06-08T07:12:22.965495Z","steps":["trace[1070243354] 'read index received' (duration: 121.696001ms)","trace[1070243354] 'applied index is now lower than readState.Index' (duration: 214.863313ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T07:12:22.965745Z","caller":"traceutil/trace.go:171","msg":"trace[1229506338] transaction","detail":"{read_only:false; response_revision:25154; number_of_response:1; }","duration":"501.209092ms","start":"2026-06-08T07:12:22.464524Z","end":"2026-06-08T07:12:22.965733Z","steps":["trace[1229506338] 'process raft request' (duration: 286.200646ms)","trace[1229506338] 'compare' (duration: 214.044056ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T07:12:22.965809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:12:22.4645Z","time spent":"501.278494ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T07:12:22.966038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.6405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-06-08T07:12:22.966065Z","caller":"traceutil/trace.go:171","msg":"trace[1100494606] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25154; }","duration":"538.705461ms","start":"2026-06-08T07:12:22.427351Z","end":"2026-06-08T07:12:22.966056Z","steps":["trace[1100494606] 'agreement among raft nodes before linearized reading' (duration: 538.603388ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:22.966084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:12:22.42733Z","time spent":"538.749832ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-08T07:12:22.966318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.744556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-06-08T07:12:22.966356Z","caller":"traceutil/trace.go:171","msg":"trace[371440667] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:25154; }","duration":"650.816168ms","start":"2026-06-08T07:12:22.31553Z","end":"2026-06-08T07:12:22.966347Z","steps":["trace[371440667] 'agreement among raft nodes before linearized reading' (duration: 650.652595ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:22.966383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:12:22.315517Z","time spent":"650.858028ms","remote":"127.0.0.1:51662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":163,"response size":32,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-06-08T07:12:22.966544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.606733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-08T07:12:22.966573Z","caller":"traceutil/trace.go:171","msg":"trace[850078935] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25154; }","duration":"326.636823ms","start":"2026-06-08T07:12:22.63993Z","end":"2026-06-08T07:12:22.966567Z","steps":["trace[850078935] 'agreement among raft nodes before linearized reading' (duration: 326.591232ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:22.966596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:12:22.639911Z","time spent":"326.678245ms","remote":"127.0.0.1:56770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" "} {"level":"warn","ts":"2026-06-08T07:12:22.966939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.714517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-08T07:12:22.96697Z","caller":"traceutil/trace.go:171","msg":"trace[2114400358] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:25154; }","duration":"239.746727ms","start":"2026-06-08T07:12:22.727215Z","end":"2026-06-08T07:12:22.966962Z","steps":["trace[2114400358] 'agreement among raft nodes before linearized reading' (duration: 239.704016ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:23.640103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.795174ms","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-06-08T07:12:23.640245Z","caller":"traceutil/trace.go:171","msg":"trace[184788981] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25155; }","duration":"269.980788ms","start":"2026-06-08T07:12:23.370245Z","end":"2026-06-08T07:12:23.640226Z","steps":["trace[184788981] 'range keys from in-memory index tree' (duration: 269.649521ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:23.64056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.319011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-08T07:12:23.640597Z","caller":"traceutil/trace.go:171","msg":"trace[2140718435] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:25155; }","duration":"481.401083ms","start":"2026-06-08T07:12:23.159184Z","end":"2026-06-08T07:12:23.640585Z","steps":["trace[2140718435] 'count revisions from in-memory index tree' (duration: 481.240878ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:23.640618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:12:23.159161Z","time spent":"481.449943ms","remote":"127.0.0.1:58872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true "} {"level":"warn","ts":"2026-06-08T07:12:23.640155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.565775ms","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-06-08T07:12:23.641041Z","caller":"traceutil/trace.go:171","msg":"trace[628755174] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25155; }","duration":"571.465363ms","start":"2026-06-08T07:12:23.069535Z","end":"2026-06-08T07:12:23.641Z","steps":["trace[628755174] 'range keys from in-memory index tree' (duration: 570.461702ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:23.641163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:12:23.06952Z","time spent":"571.607657ms","remote":"127.0.0.1:56932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-06-08T07:12:23.822668Z","caller":"traceutil/trace.go:171","msg":"trace[415664289] linearizableReadLoop","detail":"{readStateIndex:25974; appliedIndex:25973; }","duration":"177.276173ms","start":"2026-06-08T07:12:23.645372Z","end":"2026-06-08T07:12:23.822649Z","steps":["trace[415664289] 'read index received' (duration: 177.076989ms)","trace[415664289] 'applied index is now lower than readState.Index' (duration: 198.154µs)"],"step_count":2} {"level":"info","ts":"2026-06-08T07:12:23.822729Z","caller":"traceutil/trace.go:171","msg":"trace[2099832243] transaction","detail":"{read_only:false; response_revision:25156; number_of_response:1; }","duration":"178.002688ms","start":"2026-06-08T07:12:23.644694Z","end":"2026-06-08T07:12:23.822697Z","steps":["trace[2099832243] 'process raft request' (duration: 177.795474ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:23.82281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.420076ms","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-06-08T07:12:23.822848Z","caller":"traceutil/trace.go:171","msg":"trace[1667368772] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25156; }","duration":"177.499798ms","start":"2026-06-08T07:12:23.645337Z","end":"2026-06-08T07:12:23.822837Z","steps":["trace[1667368772] 'agreement among raft nodes before linearized reading' (duration: 177.406796ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T07:12:24.212912Z","caller":"traceutil/trace.go:171","msg":"trace[319316069] linearizableReadLoop","detail":"{readStateIndex:25975; appliedIndex:25974; }","duration":"390.166148ms","start":"2026-06-08T07:12:23.82272Z","end":"2026-06-08T07:12:24.212886Z","steps":["trace[319316069] 'read index received' (duration: 318.57438ms)","trace[319316069] 'applied index is now lower than readState.Index' (duration: 71.590738ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T07:12:24.212984Z","caller":"traceutil/trace.go:171","msg":"trace[198120305] transaction","detail":"{read_only:false; response_revision:25157; number_of_response:1; }","duration":"431.428832ms","start":"2026-06-08T07:12:23.781508Z","end":"2026-06-08T07:12:24.212937Z","steps":["trace[198120305] 'process raft request' (duration: 359.824694ms)","trace[198120305] 'compare' (duration: 70.510595ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T07:12:24.213067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.083314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-08T07:12:24.213111Z","caller":"traceutil/trace.go:171","msg":"trace[1329278861] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25157; }","duration":"472.182166ms","start":"2026-06-08T07:12:23.740917Z","end":"2026-06-08T07:12:24.213099Z","steps":["trace[1329278861] 'agreement among raft nodes before linearized reading' (duration: 472.092294ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:24.21315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:12:23.740894Z","time spent":"472.248967ms","remote":"127.0.0.1:56494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-08T07:12:24.213167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:12:23.781486Z","time spent":"431.576754ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T07:12:24.547789Z","caller":"traceutil/trace.go:171","msg":"trace[1791636188] linearizableReadLoop","detail":"{readStateIndex:25976; appliedIndex:25975; }","duration":"334.772787ms","start":"2026-06-08T07:12:24.212981Z","end":"2026-06-08T07:12:24.547754Z","steps":["trace[1791636188] 'read index received' (duration: 303.219439ms)","trace[1791636188] 'applied index is now lower than readState.Index' (duration: 31.550928ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T07:12:24.547867Z","caller":"traceutil/trace.go:171","msg":"trace[336856537] transaction","detail":"{read_only:false; response_revision:25158; number_of_response:1; }","duration":"582.409094ms","start":"2026-06-08T07:12:23.965398Z","end":"2026-06-08T07:12:24.547807Z","steps":["trace[336856537] 'process raft request' (duration: 550.856626ms)","trace[336856537] 'compare' (duration: 30.853064ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T07:12:24.548081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:12:23.965378Z","time spent":"582.550146ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T07:12:24.548091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.833539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:521"} {"level":"info","ts":"2026-06-08T07:12:24.548174Z","caller":"traceutil/trace.go:171","msg":"trace[1225014360] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:25158; }","duration":"707.944991ms","start":"2026-06-08T07:12:23.840198Z","end":"2026-06-08T07:12:24.548143Z","steps":["trace[1225014360] 'agreement among raft nodes before linearized reading' (duration: 707.814298ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:24.548211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:12:23.840171Z","time spent":"708.032273ms","remote":"127.0.0.1:56700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":544,"request content":"key:\"/registry/serviceaccounts/kube-system/cilium\" "} {"level":"warn","ts":"2026-06-08T07:12:24.548214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"722.033746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-06-08T07:12:24.548277Z","caller":"traceutil/trace.go:171","msg":"trace[898935388] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25158; }","duration":"722.111477ms","start":"2026-06-08T07:12:23.826149Z","end":"2026-06-08T07:12:24.548261Z","steps":["trace[898935388] 'agreement among raft nodes before linearized reading' (duration: 721.979074ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:24.548091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.314706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-08T07:12:24.548331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:12:23.82614Z","time spent":"722.178478ms","remote":"127.0.0.1:56688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-06-08T07:12:24.548404Z","caller":"traceutil/trace.go:171","msg":"trace[1647960830] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25158; }","duration":"510.647913ms","start":"2026-06-08T07:12:24.037723Z","end":"2026-06-08T07:12:24.548371Z","steps":["trace[1647960830] 'agreement among raft nodes before linearized reading' (duration: 510.201214ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:24.548548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:12:24.037707Z","time spent":"510.804446ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-08T07:12:24.770471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.090965ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-08T07:12:24.77088Z","caller":"traceutil/trace.go:171","msg":"trace[391614733] transaction","detail":"{read_only:false; response_revision:25159; number_of_response:1; }","duration":"531.904294ms","start":"2026-06-08T07:12:24.2389Z","end":"2026-06-08T07:12:24.770804Z","steps":["trace[391614733] 'process raft request' (duration: 425.38968ms)","trace[391614733] 'compare' (duration: 105.912352ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T07:12:24.771165Z","caller":"traceutil/trace.go:171","msg":"trace[298696407] transaction","detail":"{read_only:false; response_revision:25160; number_of_response:1; }","duration":"493.415935ms","start":"2026-06-08T07:12:24.27771Z","end":"2026-06-08T07:12:24.771125Z","steps":["trace[298696407] 'process raft request' (duration: 492.871424ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:24.771132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:12:24.238878Z","time spent":"532.090437ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T07:12:24.771328Z","caller":"traceutil/trace.go:171","msg":"trace[873665722] transaction","detail":"{read_only:false; response_revision:25161; number_of_response:1; }","duration":"493.529077ms","start":"2026-06-08T07:12:24.277769Z","end":"2026-06-08T07:12:24.771298Z","steps":["trace[873665722] 'process raft request' (duration: 493.14442ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:24.771501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:12:24.277741Z","time spent":"493.68467ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T07:12:24.771703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:12:24.277691Z","time spent":"493.547217ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T07:12:24.772176Z","caller":"traceutil/trace.go:171","msg":"trace[556764210] transaction","detail":"{read_only:false; response_revision:25162; number_of_response:1; }","duration":"494.314242ms","start":"2026-06-08T07:12:24.277821Z","end":"2026-06-08T07:12:24.772135Z","steps":["trace[556764210] 'process raft request' (duration: 493.151179ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T07:12:24.77221Z","caller":"traceutil/trace.go:171","msg":"trace[499845137] transaction","detail":"{read_only:false; response_revision:25164; number_of_response:1; }","duration":"493.569368ms","start":"2026-06-08T07:12:24.27862Z","end":"2026-06-08T07:12:24.772189Z","steps":["trace[499845137] 'process raft request' (duration: 493.497697ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T07:12:24.772215Z","caller":"traceutil/trace.go:171","msg":"trace[537242668] transaction","detail":"{read_only:false; response_revision:25163; number_of_response:1; }","duration":"494.286412ms","start":"2026-06-08T07:12:24.277902Z","end":"2026-06-08T07:12:24.772188Z","steps":["trace[537242668] 'process raft request' (duration: 494.132659ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T07:12:24.772256Z","caller":"traceutil/trace.go:171","msg":"trace[991994374] transaction","detail":"{read_only:false; response_revision:25165; number_of_response:1; }","duration":"219.787303ms","start":"2026-06-08T07:12:24.55245Z","end":"2026-06-08T07:12:24.772237Z","steps":["trace[991994374] 'process raft request' (duration: 219.728462ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:24.772313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:12:24.278613Z","time spent":"493.65164ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T07:12:24.772309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.728161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-06-08T07:12:24.772344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:12:24.277892Z","time spent":"494.385864ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T07:12:24.772345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:12:24.277798Z","time spent":"494.465766ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T07:12:24.772372Z","caller":"traceutil/trace.go:171","msg":"trace[740667529] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25165; }","duration":"400.844843ms","start":"2026-06-08T07:12:24.371511Z","end":"2026-06-08T07:12:24.772356Z","steps":["trace[740667529] 'agreement among raft nodes before linearized reading' (duration: 400.721301ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:24.772403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:12:24.37149Z","time spent":"400.906064ms","remote":"127.0.0.1:51274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-06-08T07:12:24.772403Z","caller":"traceutil/trace.go:171","msg":"trace[1721883448] linearizableReadLoop","detail":"{readStateIndex:25982; appliedIndex:25976; }","duration":"224.355336ms","start":"2026-06-08T07:12:24.547875Z","end":"2026-06-08T07:12:24.77223Z","steps":["trace[1721883448] 'read index received' (duration: 116.569197ms)","trace[1721883448] 'applied index is now lower than readState.Index' (duration: 107.784369ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T07:12:24.772542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.418199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-08T07:12:24.772591Z","caller":"traceutil/trace.go:171","msg":"trace[2053011192] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25165; }","duration":"558.49609ms","start":"2026-06-08T07:12:24.214083Z","end":"2026-06-08T07:12:24.77258Z","steps":["trace[2053011192] 'agreement among raft nodes before linearized reading' (duration: 558.410448ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:24.772623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:12:24.214029Z","time spent":"558.587542ms","remote":"127.0.0.1:56480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-08T07:12:24.772674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.825934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18542"} {"level":"info","ts":"2026-06-08T07:12:24.77277Z","caller":"traceutil/trace.go:171","msg":"trace[204850695] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25165; }","duration":"222.946136ms","start":"2026-06-08T07:12:24.549807Z","end":"2026-06-08T07:12:24.772753Z","steps":["trace[204850695] 'agreement among raft nodes before linearized reading' (duration: 222.752842ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:24.772828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.874315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-q7fvv\" ","response":"range_response_count:1 size:18526"} {"level":"info","ts":"2026-06-08T07:12:24.772893Z","caller":"traceutil/trace.go:171","msg":"trace[1103306637] range","detail":"{range_begin:/registry/pods/kube-system/cilium-q7fvv; range_end:; response_count:1; response_revision:25165; }","duration":"222.982048ms","start":"2026-06-08T07:12:24.549895Z","end":"2026-06-08T07:12:24.772877Z","steps":["trace[1103306637] 'agreement among raft nodes before linearized reading' (duration: 222.675581ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:24.772923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.831209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-06-08T07:12:24.772996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.58328ms","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-06-08T07:12:24.773111Z","caller":"traceutil/trace.go:171","msg":"trace[1685469804] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25165; }","duration":"243.110455ms","start":"2026-06-08T07:12:24.529971Z","end":"2026-06-08T07:12:24.773081Z","steps":["trace[1685469804] 'agreement among raft nodes before linearized reading' (duration: 242.778937ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T07:12:24.773131Z","caller":"traceutil/trace.go:171","msg":"trace[753612514] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25165; }","duration":"131.798404ms","start":"2026-06-08T07:12:24.641315Z","end":"2026-06-08T07:12:24.773113Z","steps":["trace[753612514] 'agreement among raft nodes before linearized reading' (duration: 131.253263ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:24.773018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.948414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-06-08T07:12:24.773867Z","caller":"traceutil/trace.go:171","msg":"trace[1229130829] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25165; }","duration":"534.433475ms","start":"2026-06-08T07:12:24.238791Z","end":"2026-06-08T07:12:24.773224Z","steps":["trace[1229130829] 'agreement among raft nodes before linearized reading' (duration: 533.540976ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:24.773934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:12:24.23877Z","time spent":"535.148588ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-08T07:12:25.03172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.184761ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-08T07:12:25.031867Z","caller":"traceutil/trace.go:171","msg":"trace[1805513976] transaction","detail":"{read_only:false; response_revision:25166; number_of_response:1; }","duration":"254.860193ms","start":"2026-06-08T07:12:24.776981Z","end":"2026-06-08T07:12:25.031842Z","steps":["trace[1805513976] 'process raft request' (duration: 130.303064ms)","trace[1805513976] 'compare' (duration: 123.853745ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T07:12:25.03194Z","caller":"traceutil/trace.go:171","msg":"trace[1131576963] transaction","detail":"{read_only:false; response_revision:25167; number_of_response:1; }","duration":"252.440083ms","start":"2026-06-08T07:12:24.779482Z","end":"2026-06-08T07:12:25.031922Z","steps":["trace[1131576963] 'process raft request' (duration: 252.350101ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T07:12:25.031972Z","caller":"traceutil/trace.go:171","msg":"trace[1287746988] linearizableReadLoop","detail":"{readStateIndex:25984; appliedIndex:25983; }","duration":"252.665838ms","start":"2026-06-08T07:12:24.779292Z","end":"2026-06-08T07:12:25.031958Z","steps":["trace[1287746988] 'read index received' (duration: 128.001417ms)","trace[1287746988] 'applied index is now lower than readState.Index' (duration: 124.662051ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T07:12:25.032101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.806211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-08T07:12:25.032129Z","caller":"traceutil/trace.go:171","msg":"trace[696102358] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25167; }","duration":"252.861832ms","start":"2026-06-08T07:12:24.77926Z","end":"2026-06-08T07:12:25.032122Z","steps":["trace[696102358] 'agreement among raft nodes before linearized reading' (duration: 252.76653ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:25.060827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.567732ms","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-06-08T07:12:25.060898Z","caller":"traceutil/trace.go:171","msg":"trace[51821946] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:25168; }","duration":"279.657184ms","start":"2026-06-08T07:12:24.781222Z","end":"2026-06-08T07:12:25.060879Z","steps":["trace[51821946] 'agreement among raft nodes before linearized reading' (duration: 279.369529ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:25.061531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.682355ms","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-06-08T07:12:25.06157Z","caller":"traceutil/trace.go:171","msg":"trace[2032253027] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:25168; }","duration":"277.721206ms","start":"2026-06-08T07:12:24.783839Z","end":"2026-06-08T07:12:25.06156Z","steps":["trace[2032253027] 'agreement among raft nodes before linearized reading' (duration: 277.621924ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T07:12:26.16612Z","caller":"traceutil/trace.go:171","msg":"trace[2112438154] linearizableReadLoop","detail":"{readStateIndex:25990; appliedIndex:25989; }","duration":"236.103612ms","start":"2026-06-08T07:12:25.929988Z","end":"2026-06-08T07:12:26.166092Z","steps":["trace[2112438154] 'read index received' (duration: 150.627544ms)","trace[2112438154] 'applied index is now lower than readState.Index' (duration: 85.473768ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T07:12:26.166381Z","caller":"traceutil/trace.go:171","msg":"trace[1773334191] transaction","detail":"{read_only:false; response_revision:25171; number_of_response:1; }","duration":"242.171576ms","start":"2026-06-08T07:12:25.924172Z","end":"2026-06-08T07:12:26.166343Z","steps":["trace[1773334191] 'process raft request' (duration: 156.449653ms)","trace[1773334191] 'compare' (duration: 84.836456ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T07:12:26.166472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.457299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11716"} {"level":"info","ts":"2026-06-08T07:12:26.166539Z","caller":"traceutil/trace.go:171","msg":"trace[786651007] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:25171; }","duration":"236.564782ms","start":"2026-06-08T07:12:25.929959Z","end":"2026-06-08T07:12:26.166524Z","steps":["trace[786651007] 'agreement among raft nodes before linearized reading' (duration: 236.246606ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T07:12:26.372028Z","caller":"traceutil/trace.go:171","msg":"trace[1495668888] transaction","detail":"{read_only:false; response_revision:25172; number_of_response:1; }","duration":"143.44552ms","start":"2026-06-08T07:12:26.228547Z","end":"2026-06-08T07:12:26.371993Z","steps":["trace[1495668888] 'process raft request' (duration: 121.272091ms)","trace[1495668888] 'compare' (duration: 22.031686ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T07:12:27.065311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.579085ms","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-06-08T07:12:27.065499Z","caller":"traceutil/trace.go:171","msg":"trace[1663384541] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:25173; }","duration":"106.84003ms","start":"2026-06-08T07:12:26.95864Z","end":"2026-06-08T07:12:27.06548Z","steps":["trace[1663384541] 'range keys from in-memory index tree' (duration: 106.561474ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T07:12:27.083627Z","caller":"traceutil/trace.go:171","msg":"trace[465883870] transaction","detail":"{read_only:false; response_revision:25174; number_of_response:1; }","duration":"305.720811ms","start":"2026-06-08T07:12:26.777881Z","end":"2026-06-08T07:12:27.083601Z","steps":["trace[465883870] 'process raft request' (duration: 305.384754ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:27.083767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:12:26.777848Z","time spent":"305.852753ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T07:12:27.083976Z","caller":"traceutil/trace.go:171","msg":"trace[1177290646] transaction","detail":"{read_only:false; response_revision:25175; number_of_response:1; }","duration":"305.480025ms","start":"2026-06-08T07:12:26.778465Z","end":"2026-06-08T07:12:27.083945Z","steps":["trace[1177290646] 'process raft request' (duration: 305.051347ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:27.084134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:12:26.778434Z","time spent":"305.6196ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T07:12:27.084805Z","caller":"traceutil/trace.go:171","msg":"trace[189361060] linearizableReadLoop","detail":"{readStateIndex:25993; appliedIndex:25991; }","duration":"306.776702ms","start":"2026-06-08T07:12:26.778012Z","end":"2026-06-08T07:12:27.084789Z","steps":["trace[189361060] 'read index received' (duration: 71.609178ms)","trace[189361060] 'applied index is now lower than readState.Index' (duration: 235.166744ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T07:12:27.084846Z","caller":"traceutil/trace.go:171","msg":"trace[1534764964] transaction","detail":"{read_only:false; response_revision:25176; number_of_response:1; }","duration":"305.409794ms","start":"2026-06-08T07:12:26.779418Z","end":"2026-06-08T07:12:27.084828Z","steps":["trace[1534764964] 'process raft request' (duration: 304.465024ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T07:12:27.084891Z","caller":"traceutil/trace.go:171","msg":"trace[1574492603] transaction","detail":"{read_only:false; response_revision:25177; number_of_response:1; }","duration":"297.858921ms","start":"2026-06-08T07:12:26.787021Z","end":"2026-06-08T07:12:27.08488Z","steps":["trace[1574492603] 'process raft request' (duration: 297.604206ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:27.084918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:12:26.779409Z","time spent":"305.470225ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T07:12:27.085091Z","caller":"traceutil/trace.go:171","msg":"trace[284831643] transaction","detail":"{read_only:false; response_revision:25178; number_of_response:1; }","duration":"297.867442ms","start":"2026-06-08T07:12:26.787214Z","end":"2026-06-08T07:12:27.085081Z","steps":["trace[284831643] 'process raft request' (duration: 297.455584ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:27.085292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.276612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-08T07:12:27.085334Z","caller":"traceutil/trace.go:171","msg":"trace[45004723] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25178; }","duration":"307.345424ms","start":"2026-06-08T07:12:26.777978Z","end":"2026-06-08T07:12:27.085324Z","steps":["trace[45004723] 'agreement among raft nodes before linearized reading' (duration: 307.225791ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:27.085363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:12:26.777969Z","time spent":"307.384404ms","remote":"127.0.0.1:56754","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-06-08T07:12:27.095676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.407906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/endpoints/\" range_end:\"/registry/openstack.k-orc.cloud/endpoints0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-08T07:12:27.095743Z","caller":"traceutil/trace.go:171","msg":"trace[2109435016] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/endpoints/; range_end:/registry/openstack.k-orc.cloud/endpoints0; response_count:0; response_revision:25179; }","duration":"264.532998ms","start":"2026-06-08T07:12:26.831192Z","end":"2026-06-08T07:12:27.095725Z","steps":["trace[2109435016] 'agreement among raft nodes before linearized reading' (duration: 264.401776ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T07:12:29.103893Z","caller":"traceutil/trace.go:171","msg":"trace[1732837885] transaction","detail":"{read_only:false; response_revision:25187; number_of_response:1; }","duration":"243.713357ms","start":"2026-06-08T07:12:28.860162Z","end":"2026-06-08T07:12:29.103875Z","steps":["trace[1732837885] 'process raft request' (duration: 243.579283ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T07:12:29.222063Z","caller":"traceutil/trace.go:171","msg":"trace[608961224] transaction","detail":"{read_only:false; response_revision:25188; number_of_response:1; }","duration":"253.567247ms","start":"2026-06-08T07:12:28.968474Z","end":"2026-06-08T07:12:29.222041Z","steps":["trace[608961224] 'process raft request' (duration: 253.086517ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T07:12:29.222221Z","caller":"traceutil/trace.go:171","msg":"trace[1394505068] transaction","detail":"{read_only:false; response_revision:25189; number_of_response:1; }","duration":"133.304325ms","start":"2026-06-08T07:12:29.088897Z","end":"2026-06-08T07:12:29.222201Z","steps":["trace[1394505068] 'process raft request' (duration: 133.093821ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T07:12:29.22235Z","caller":"traceutil/trace.go:171","msg":"trace[1992160243] transaction","detail":"{read_only:false; response_revision:25190; number_of_response:1; }","duration":"132.753775ms","start":"2026-06-08T07:12:29.08958Z","end":"2026-06-08T07:12:29.222334Z","steps":["trace[1992160243] 'process raft request' (duration: 132.54936ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T07:12:29.222508Z","caller":"traceutil/trace.go:171","msg":"trace[1814804248] transaction","detail":"{read_only:false; response_revision:25191; number_of_response:1; }","duration":"131.976258ms","start":"2026-06-08T07:12:29.090521Z","end":"2026-06-08T07:12:29.222498Z","steps":["trace[1814804248] 'process raft request' (duration: 131.747663ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T07:12:29.222605Z","caller":"traceutil/trace.go:171","msg":"trace[1316507781] transaction","detail":"{read_only:false; response_revision:25192; number_of_response:1; }","duration":"132.006819ms","start":"2026-06-08T07:12:29.090586Z","end":"2026-06-08T07:12:29.222593Z","steps":["trace[1316507781] 'process raft request' (duration: 131.884286ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T07:12:29.22267Z","caller":"traceutil/trace.go:171","msg":"trace[838211309] linearizableReadLoop","detail":"{readStateIndex:26013; appliedIndex:26006; }","duration":"105.84013ms","start":"2026-06-08T07:12:29.116822Z","end":"2026-06-08T07:12:29.222662Z","steps":["trace[838211309] 'read index received' (duration: 104.637296ms)","trace[838211309] 'applied index is now lower than readState.Index' (duration: 1.202334ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T07:12:29.222725Z","caller":"traceutil/trace.go:171","msg":"trace[1412091135] transaction","detail":"{read_only:false; response_revision:25193; number_of_response:1; }","duration":"132.09171ms","start":"2026-06-08T07:12:29.090627Z","end":"2026-06-08T07:12:29.222719Z","steps":["trace[1412091135] 'process raft request' (duration: 131.920497ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:29.22289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.717216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-08T07:12:29.222918Z","caller":"traceutil/trace.go:171","msg":"trace[109273454] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25194; }","duration":"104.800198ms","start":"2026-06-08T07:12:29.11811Z","end":"2026-06-08T07:12:29.22291Z","steps":["trace[109273454] 'agreement among raft nodes before linearized reading' (duration: 104.695936ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:29.222997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.163526ms","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-06-08T07:12:29.223023Z","caller":"traceutil/trace.go:171","msg":"trace[477198143] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25194; }","duration":"106.232267ms","start":"2026-06-08T07:12:29.116784Z","end":"2026-06-08T07:12:29.223016Z","steps":["trace[477198143] 'agreement among raft nodes before linearized reading' (duration: 106.135145ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T07:12:29.223058Z","caller":"traceutil/trace.go:171","msg":"trace[2012577544] transaction","detail":"{read_only:false; response_revision:25194; number_of_response:1; }","duration":"123.12428ms","start":"2026-06-08T07:12:29.099915Z","end":"2026-06-08T07:12:29.22304Z","steps":["trace[2012577544] 'process raft request' (duration: 122.710861ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:29.223147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.934121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-06-08T07:12:29.223211Z","caller":"traceutil/trace.go:171","msg":"trace[142931370] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25194; }","duration":"105.012753ms","start":"2026-06-08T07:12:29.118184Z","end":"2026-06-08T07:12:29.223197Z","steps":["trace[142931370] 'agreement among raft nodes before linearized reading' (duration: 104.810019ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T07:12:44.661731Z","caller":"traceutil/trace.go:171","msg":"trace[1202920067] transaction","detail":"{read_only:false; response_revision:25366; number_of_response:1; }","duration":"106.556404ms","start":"2026-06-08T07:12:44.555147Z","end":"2026-06-08T07:12:44.661703Z","steps":["trace[1202920067] 'process raft request' (duration: 65.049795ms)","trace[1202920067] 'compare' (duration: 41.293305ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T07:12:45.40957Z","caller":"traceutil/trace.go:171","msg":"trace[143812480] transaction","detail":"{read_only:false; response_revision:25369; number_of_response:1; }","duration":"102.347248ms","start":"2026-06-08T07:12:45.307195Z","end":"2026-06-08T07:12:45.409543Z","steps":["trace[143812480] 'process raft request' (duration: 102.133504ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T07:12:45.570418Z","caller":"traceutil/trace.go:171","msg":"trace[204976234] transaction","detail":"{read_only:false; response_revision:25370; number_of_response:1; }","duration":"252.435384ms","start":"2026-06-08T07:12:45.317958Z","end":"2026-06-08T07:12:45.570393Z","steps":["trace[204976234] 'process raft request' (duration: 156.776549ms)","trace[204976234] 'compare' (duration: 95.215935ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T07:12:45.57042Z","caller":"traceutil/trace.go:171","msg":"trace[203914397] transaction","detail":"{read_only:false; response_revision:25371; number_of_response:1; }","duration":"225.204723ms","start":"2026-06-08T07:12:45.345184Z","end":"2026-06-08T07:12:45.570389Z","steps":["trace[203914397] 'process raft request' (duration: 225.002449ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T07:12:45.57052Z","caller":"traceutil/trace.go:171","msg":"trace[18916304] transaction","detail":"{read_only:false; response_revision:25372; number_of_response:1; }","duration":"224.677542ms","start":"2026-06-08T07:12:45.34581Z","end":"2026-06-08T07:12:45.570487Z","steps":["trace[18916304] 'process raft request' (duration: 224.459458ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T07:12:45.57071Z","caller":"traceutil/trace.go:171","msg":"trace[321398229] transaction","detail":"{read_only:false; response_revision:25373; number_of_response:1; }","duration":"216.19599ms","start":"2026-06-08T07:12:45.354474Z","end":"2026-06-08T07:12:45.57067Z","steps":["trace[321398229] 'process raft request' (duration: 215.829363ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T07:12:45.570968Z","caller":"traceutil/trace.go:171","msg":"trace[1760050876] linearizableReadLoop","detail":"{readStateIndex:26197; appliedIndex:26191; }","duration":"211.157339ms","start":"2026-06-08T07:12:45.359795Z","end":"2026-06-08T07:12:45.570952Z","steps":["trace[1760050876] 'read index received' (duration: 49.585412ms)","trace[1760050876] 'applied index is now lower than readState.Index' (duration: 161.571077ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T07:12:45.571035Z","caller":"traceutil/trace.go:171","msg":"trace[201287275] transaction","detail":"{read_only:false; response_revision:25374; number_of_response:1; }","duration":"216.430345ms","start":"2026-06-08T07:12:45.35459Z","end":"2026-06-08T07:12:45.571021Z","steps":["trace[201287275] 'process raft request' (duration: 215.755702ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:45.571221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.173849ms","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-06-08T07:12:45.571265Z","caller":"traceutil/trace.go:171","msg":"trace[148720085] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25374; }","duration":"210.351962ms","start":"2026-06-08T07:12:45.360901Z","end":"2026-06-08T07:12:45.571253Z","steps":["trace[148720085] 'agreement among raft nodes before linearized reading' (duration: 210.165038ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:45.571278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.768781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-06-08T07:12:45.571239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.422985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-08T07:12:45.571334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.177248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-06-08T07:12:45.571392Z","caller":"traceutil/trace.go:171","msg":"trace[1225121403] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25374; }","duration":"207.27031ms","start":"2026-06-08T07:12:45.364107Z","end":"2026-06-08T07:12:45.571377Z","steps":["trace[1225121403] 'agreement among raft nodes before linearized reading' (duration: 207.029315ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T07:12:45.571399Z","caller":"traceutil/trace.go:171","msg":"trace[30429548] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25374; }","duration":"211.631869ms","start":"2026-06-08T07:12:45.359739Z","end":"2026-06-08T07:12:45.571371Z","steps":["trace[30429548] 'agreement among raft nodes before linearized reading' (duration: 211.296182ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:45.57139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.022348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-06-08T07:12:45.571531Z","caller":"traceutil/trace.go:171","msg":"trace[489318725] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25374; }","duration":"129.226653ms","start":"2026-06-08T07:12:45.442279Z","end":"2026-06-08T07:12:45.571506Z","steps":["trace[489318725] 'agreement among raft nodes before linearized reading' (duration: 128.839905ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T07:12:45.571313Z","caller":"traceutil/trace.go:171","msg":"trace[1264081670] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25374; }","duration":"208.845703ms","start":"2026-06-08T07:12:45.362459Z","end":"2026-06-08T07:12:45.571305Z","steps":["trace[1264081670] 'agreement among raft nodes before linearized reading' (duration: 208.70756ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T07:12:47.024283Z","caller":"traceutil/trace.go:171","msg":"trace[2118995921] transaction","detail":"{read_only:false; response_revision:25381; number_of_response:1; }","duration":"530.488485ms","start":"2026-06-08T07:12:46.493778Z","end":"2026-06-08T07:12:47.024266Z","steps":["trace[2118995921] 'process raft request' (duration: 530.30031ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:47.024365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.630914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-08T07:12:47.0244Z","caller":"traceutil/trace.go:171","msg":"trace[636555317] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25381; }","duration":"282.713556ms","start":"2026-06-08T07:12:46.741677Z","end":"2026-06-08T07:12:47.024391Z","steps":["trace[636555317] 'agreement among raft nodes before linearized reading' (duration: 282.632304ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:47.024386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:12:46.493758Z","time spent":"530.570936ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T07:12:47.024261Z","caller":"traceutil/trace.go:171","msg":"trace[1675804936] linearizableReadLoop","detail":"{readStateIndex:26205; appliedIndex:26204; }","duration":"282.501372ms","start":"2026-06-08T07:12:46.741737Z","end":"2026-06-08T07:12:47.024239Z","steps":["trace[1675804936] 'read index received' (duration: 282.328437ms)","trace[1675804936] 'applied index is now lower than readState.Index' (duration: 172.114µs)"],"step_count":2} {"level":"warn","ts":"2026-06-08T07:12:47.02459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.720487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0fad0d83-6f58-41cf-8ed0-0ee39fde4145\" ","response":"range_response_count:1 size:3761"} {"level":"info","ts":"2026-06-08T07:12:47.024635Z","caller":"traceutil/trace.go:171","msg":"trace[791348446] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0fad0d83-6f58-41cf-8ed0-0ee39fde4145; range_end:; response_count:1; response_revision:25381; }","duration":"102.805798ms","start":"2026-06-08T07:12:46.921816Z","end":"2026-06-08T07:12:47.024622Z","steps":["trace[791348446] 'agreement among raft nodes before linearized reading' (duration: 102.662285ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T07:12:47.334665Z","caller":"traceutil/trace.go:171","msg":"trace[1134961086] transaction","detail":"{read_only:false; response_revision:25382; number_of_response:1; }","duration":"131.710213ms","start":"2026-06-08T07:12:47.202927Z","end":"2026-06-08T07:12:47.334638Z","steps":["trace[1134961086] 'process raft request' (duration: 131.540409ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:52.91576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.11766ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-08T07:12:52.915854Z","caller":"traceutil/trace.go:171","msg":"trace[291647941] linearizableReadLoop","detail":"{readStateIndex:26274; appliedIndex:26273; }","duration":"133.469179ms","start":"2026-06-08T07:12:52.782365Z","end":"2026-06-08T07:12:52.915835Z","steps":["trace[291647941] 'read index received' (duration: 4.116253ms)","trace[291647941] 'applied index is now lower than readState.Index' (duration: 129.351616ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T07:12:52.915893Z","caller":"traceutil/trace.go:171","msg":"trace[158236731] transaction","detail":"{read_only:false; response_revision:25448; number_of_response:1; }","duration":"157.727069ms","start":"2026-06-08T07:12:52.758129Z","end":"2026-06-08T07:12:52.915856Z","steps":["trace[158236731] 'process raft request' (duration: 28.428145ms)","trace[158236731] 'compare' (duration: 129.022329ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T07:12:52.916019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.657202ms","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-06-08T07:12:52.916048Z","caller":"traceutil/trace.go:171","msg":"trace[1894725185] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25448; }","duration":"133.745144ms","start":"2026-06-08T07:12:52.782291Z","end":"2026-06-08T07:12:52.916036Z","steps":["trace[1894725185] 'agreement among raft nodes before linearized reading' (duration: 133.622891ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:53.1597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.289862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-08T07:12:53.159754Z","caller":"traceutil/trace.go:171","msg":"trace[105710303] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:25448; }","duration":"119.381064ms","start":"2026-06-08T07:12:53.04036Z","end":"2026-06-08T07:12:53.159741Z","steps":["trace[105710303] 'range keys from in-memory index tree' (duration: 119.234691ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T07:12:53.254288Z","caller":"traceutil/trace.go:171","msg":"trace[1751401509] transaction","detail":"{read_only:false; response_revision:25449; number_of_response:1; }","duration":"177.147491ms","start":"2026-06-08T07:12:53.077071Z","end":"2026-06-08T07:12:53.254218Z","steps":["trace[1751401509] 'process raft request' (duration: 176.818474ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:53.376112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.288341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-08T07:12:53.376363Z","caller":"traceutil/trace.go:171","msg":"trace[151981492] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25449; }","duration":"116.604868ms","start":"2026-06-08T07:12:53.25973Z","end":"2026-06-08T07:12:53.376335Z","steps":["trace[151981492] 'range keys from in-memory index tree' (duration: 116.20898ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T07:12:53.474849Z","caller":"traceutil/trace.go:171","msg":"trace[1132102184] transaction","detail":"{read_only:false; response_revision:25450; number_of_response:1; }","duration":"120.852762ms","start":"2026-06-08T07:12:53.353963Z","end":"2026-06-08T07:12:53.474816Z","steps":["trace[1132102184] 'process raft request' (duration: 120.611088ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:12:53.510714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.043763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-08T07:12:53.510838Z","caller":"traceutil/trace.go:171","msg":"trace[42337357] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:25451; }","duration":"104.227147ms","start":"2026-06-08T07:12:53.406582Z","end":"2026-06-08T07:12:53.510809Z","steps":["trace[42337357] 'agreement among raft nodes before linearized reading' (duration: 104.025683ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:13:30.140898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.443302ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-08T07:13:30.141025Z","caller":"traceutil/trace.go:171","msg":"trace[1606080935] transaction","detail":"{read_only:false; response_revision:25748; number_of_response:1; }","duration":"419.299647ms","start":"2026-06-08T07:13:29.721706Z","end":"2026-06-08T07:13:30.141006Z","steps":["trace[1606080935] 'process raft request' (duration: 177.678863ms)","trace[1606080935] 'compare' (duration: 241.160115ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T07:13:30.141085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:13:29.721679Z","time spent":"419.376789ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T07:13:30.348389Z","caller":"traceutil/trace.go:171","msg":"trace[1956844728] transaction","detail":"{read_only:false; response_revision:25750; number_of_response:1; }","duration":"624.090727ms","start":"2026-06-08T07:13:29.724274Z","end":"2026-06-08T07:13:30.348365Z","steps":["trace[1956844728] 'process raft request' (duration: 624.020385ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T07:13:30.348414Z","caller":"traceutil/trace.go:171","msg":"trace[1844064930] transaction","detail":"{read_only:false; response_revision:25749; number_of_response:1; }","duration":"624.974444ms","start":"2026-06-08T07:13:29.723397Z","end":"2026-06-08T07:13:30.348371Z","steps":["trace[1844064930] 'process raft request' (duration: 579.279571ms)","trace[1844064930] 'compare' (duration: 45.495849ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T07:13:30.348815Z","caller":"traceutil/trace.go:171","msg":"trace[1208247268] transaction","detail":"{read_only:false; response_revision:25751; number_of_response:1; }","duration":"592.559568ms","start":"2026-06-08T07:13:29.756226Z","end":"2026-06-08T07:13:30.348785Z","steps":["trace[1208247268] 'process raft request' (duration: 592.104439ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:13:30.348588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:13:29.724248Z","time spent":"624.252349ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T07:13:30.348824Z","caller":"traceutil/trace.go:171","msg":"trace[1458045890] transaction","detail":"{read_only:false; response_revision:25752; number_of_response:1; }","duration":"590.053189ms","start":"2026-06-08T07:13:29.758731Z","end":"2026-06-08T07:13:30.348785Z","steps":["trace[1458045890] 'process raft request' (duration: 589.793824ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:13:30.348938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:13:29.756212Z","time spent":"592.65174ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T07:13:30.349014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:13:29.758722Z","time spent":"590.195522ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T07:13:30.349211Z","caller":"traceutil/trace.go:171","msg":"trace[1176086701] linearizableReadLoop","detail":"{readStateIndex:26583; appliedIndex:26580; }","duration":"607.720906ms","start":"2026-06-08T07:13:29.741468Z","end":"2026-06-08T07:13:30.349189Z","steps":["trace[1176086701] 'read index received' (duration: 157.936474ms)","trace[1176086701] 'applied index is now lower than readState.Index' (duration: 449.783442ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T07:13:30.349295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:13:29.723332Z","time spent":"625.27133ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T07:13:30.349224Z","caller":"traceutil/trace.go:171","msg":"trace[1327021275] transaction","detail":"{read_only:false; response_revision:25753; number_of_response:1; }","duration":"590.329224ms","start":"2026-06-08T07:13:29.758876Z","end":"2026-06-08T07:13:30.349205Z","steps":["trace[1327021275] 'process raft request' (duration: 589.845775ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:13:30.349496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:13:29.758851Z","time spent":"590.61927ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T07:13:30.349314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.851818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-08T07:13:30.349568Z","caller":"traceutil/trace.go:171","msg":"trace[393785009] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25753; }","duration":"608.137674ms","start":"2026-06-08T07:13:29.741423Z","end":"2026-06-08T07:13:30.349561Z","steps":["trace[393785009] 'agreement among raft nodes before linearized reading' (duration: 607.837728ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:13:30.349588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:13:29.741411Z","time spent":"608.172825ms","remote":"127.0.0.1:56494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-08T07:13:30.349879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.223425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-06-08T07:13:30.349912Z","caller":"traceutil/trace.go:171","msg":"trace[541968377] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25753; }","duration":"513.274825ms","start":"2026-06-08T07:13:29.836629Z","end":"2026-06-08T07:13:30.349904Z","steps":["trace[541968377] 'agreement among raft nodes before linearized reading' (duration: 513.162513ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:13:30.349909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.652254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-08T07:13:30.349932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:13:29.836621Z","time spent":"513.306877ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-08T07:13:30.349874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.368432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-06-08T07:13:30.35Z","caller":"traceutil/trace.go:171","msg":"trace[548588455] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25753; }","duration":"580.529896ms","start":"2026-06-08T07:13:29.769456Z","end":"2026-06-08T07:13:30.349985Z","steps":["trace[548588455] 'agreement among raft nodes before linearized reading' (duration: 580.296131ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:13:30.350005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.301298ms","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-06-08T07:13:30.350102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:13:29.769438Z","time spent":"580.621938ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-08T07:13:30.350124Z","caller":"traceutil/trace.go:171","msg":"trace[526821725] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25753; }","duration":"518.461702ms","start":"2026-06-08T07:13:29.831645Z","end":"2026-06-08T07:13:30.350107Z","steps":["trace[526821725] 'agreement among raft nodes before linearized reading' (duration: 518.239957ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:13:30.350183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:13:29.831623Z","time spent":"518.546824ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-08T07:13:30.349952Z","caller":"traceutil/trace.go:171","msg":"trace[2028939190] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25753; }","duration":"516.724955ms","start":"2026-06-08T07:13:29.833214Z","end":"2026-06-08T07:13:30.349939Z","steps":["trace[2028939190] 'agreement among raft nodes before linearized reading' (duration: 516.601833ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:13:30.350239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T07:13:29.833198Z","time spent":"517.026893ms","remote":"127.0.0.1:56754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-08T07:13:32.868532Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22895} {"level":"info","ts":"2026-06-08T07:13:33.000806Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22895,"took":"127.977117ms","hash":1975716751,"current-db-size-bytes":60231680,"current-db-size":"60 MB","current-db-size-in-use-bytes":35024896,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-06-08T07:13:33.000879Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1975716751,"revision":22895,"compact-revision":20037} {"level":"info","ts":"2026-06-08T07:13:34.53546Z","caller":"traceutil/trace.go:171","msg":"trace[401720501] transaction","detail":"{read_only:false; response_revision:25783; number_of_response:1; }","duration":"172.193442ms","start":"2026-06-08T07:13:34.363238Z","end":"2026-06-08T07:13:34.535431Z","steps":["trace[401720501] 'process raft request' (duration: 92.47468ms)","trace[401720501] 'compare' (duration: 79.344613ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T07:13:34.535735Z","caller":"traceutil/trace.go:171","msg":"trace[487689764] transaction","detail":"{read_only:false; response_revision:25784; number_of_response:1; }","duration":"171.722922ms","start":"2026-06-08T07:13:34.363981Z","end":"2026-06-08T07:13:34.535704Z","steps":["trace[487689764] 'process raft request' (duration: 171.374165ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T07:13:34.53612Z","caller":"traceutil/trace.go:171","msg":"trace[1511512076] linearizableReadLoop","detail":"{readStateIndex:26620; appliedIndex:26616; }","duration":"162.788ms","start":"2026-06-08T07:13:34.373315Z","end":"2026-06-08T07:13:34.536103Z","steps":["trace[1511512076] 'read index received' (duration: 38.043598ms)","trace[1511512076] 'applied index is now lower than readState.Index' (duration: 124.743132ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T07:13:34.536155Z","caller":"traceutil/trace.go:171","msg":"trace[783632069] transaction","detail":"{read_only:false; response_revision:25785; number_of_response:1; }","duration":"171.457837ms","start":"2026-06-08T07:13:34.364661Z","end":"2026-06-08T07:13:34.536119Z","steps":["trace[783632069] 'process raft request' (duration: 170.955027ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:13:34.536303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.979165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-06-08T07:13:34.536343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.947442ms","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-06-08T07:13:34.536359Z","caller":"traceutil/trace.go:171","msg":"trace[780352007] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25785; }","duration":"163.082776ms","start":"2026-06-08T07:13:34.373261Z","end":"2026-06-08T07:13:34.536344Z","steps":["trace[780352007] 'agreement among raft nodes before linearized reading' (duration: 162.924323ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:13:34.536361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.233518ms","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-06-08T07:13:34.536383Z","caller":"traceutil/trace.go:171","msg":"trace[1214124649] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25785; }","duration":"106.995763ms","start":"2026-06-08T07:13:34.429376Z","end":"2026-06-08T07:13:34.536372Z","steps":["trace[1214124649] 'agreement among raft nodes before linearized reading' (duration: 106.87791ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T07:13:34.536416Z","caller":"traceutil/trace.go:171","msg":"trace[1476935724] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25785; }","duration":"151.3434ms","start":"2026-06-08T07:13:34.385057Z","end":"2026-06-08T07:13:34.536401Z","steps":["trace[1476935724] 'agreement among raft nodes before linearized reading' (duration: 151.167567ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:13:34.536598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.793189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-06-08T07:13:34.536641Z","caller":"traceutil/trace.go:171","msg":"trace[1386475027] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25785; }","duration":"150.86361ms","start":"2026-06-08T07:13:34.385764Z","end":"2026-06-08T07:13:34.536628Z","steps":["trace[1386475027] 'agreement among raft nodes before linearized reading' (duration: 150.764148ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:13:34.536613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.471081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-08T07:13:34.536768Z","caller":"traceutil/trace.go:171","msg":"trace[1890314711] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25785; }","duration":"149.660045ms","start":"2026-06-08T07:13:34.387072Z","end":"2026-06-08T07:13:34.536732Z","steps":["trace[1890314711] 'agreement among raft nodes before linearized reading' (duration: 149.207176ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T07:13:34.769987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.171187ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-08T07:13:34.77028Z","caller":"traceutil/trace.go:171","msg":"trace[946277452] transaction","detail":"{read_only:false; response_revision:25788; number_of_response:1; }","duration":"229.06302ms","start":"2026-06-08T07:13:34.541188Z","end":"2026-06-08T07:13:34.770251Z","steps":["trace[946277452] 'process raft request' (duration: 228.884417ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T07:13:34.770325Z","caller":"traceutil/trace.go:171","msg":"trace[1313581663] transaction","detail":"{read_only:false; response_revision:25787; number_of_response:1; }","duration":"229.193953ms","start":"2026-06-08T07:13:34.541107Z","end":"2026-06-08T07:13:34.7703Z","steps":["trace[1313581663] 'process raft request' (duration: 121.306573ms)","trace[1313581663] 'compare' (duration: 106.948031ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T07:13:34.770334Z","caller":"traceutil/trace.go:171","msg":"trace[1076614163] transaction","detail":"{read_only:false; response_revision:25789; number_of_response:1; }","duration":"228.300865ms","start":"2026-06-08T07:13:34.542002Z","end":"2026-06-08T07:13:34.770303Z","steps":["trace[1076614163] 'process raft request' (duration: 228.160452ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T07:13:34.770386Z","caller":"traceutil/trace.go:171","msg":"trace[310585302] transaction","detail":"{read_only:false; response_revision:25790; number_of_response:1; }","duration":"228.319235ms","start":"2026-06-08T07:13:34.542031Z","end":"2026-06-08T07:13:34.77035Z","steps":["trace[310585302] 'process raft request' (duration: 228.169692ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T07:14:02.180715Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006939.wal"} {"level":"info","ts":"2026-06-08T07:18:32.874707Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25774} {"level":"info","ts":"2026-06-08T07:18:33.000552Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25774,"took":"121.718425ms","hash":342848431,"current-db-size-bytes":60653568,"current-db-size":"61 MB","current-db-size-in-use-bytes":37572608,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-06-08T07:18:33.000624Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":342848431,"revision":25774,"compact-revision":22895} {"level":"info","ts":"2026-06-08T07:19:31.30531Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a31e123290fcf0df","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-08T07:19:31.311488Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-06-08T07:19:31.311591Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-06-08T07:23:32.880488Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28654} {"level":"info","ts":"2026-06-08T07:23:33.02626Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28654,"took":"139.217948ms","hash":3272217746,"current-db-size-bytes":60653568,"current-db-size":"61 MB","current-db-size-in-use-bytes":31645696,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-08T07:23:33.026336Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3272217746,"revision":28654,"compact-revision":25774} {"level":"info","ts":"2026-06-08T07:28:32.887006Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30881} {"level":"info","ts":"2026-06-08T07:28:33.009573Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30881,"took":"119.305006ms","hash":2210444650,"current-db-size-bytes":60653568,"current-db-size":"61 MB","current-db-size-in-use-bytes":32493568,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-08T07:28:33.009635Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2210444650,"revision":30881,"compact-revision":28654} {"level":"info","ts":"2026-06-08T07:33:32.894801Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33150} {"level":"info","ts":"2026-06-08T07:33:33.053909Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33150,"took":"154.912256ms","hash":827744436,"current-db-size-bytes":61698048,"current-db-size":"62 MB","current-db-size-in-use-bytes":32223232,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-08T07:33:33.053966Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":827744436,"revision":33150,"compact-revision":30881} {"level":"info","ts":"2026-06-08T07:38:32.90149Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35446} {"level":"info","ts":"2026-06-08T07:38:33.04209Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35446,"took":"137.077405ms","hash":2585348678,"current-db-size-bytes":61698048,"current-db-size":"62 MB","current-db-size-in-use-bytes":32002048,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-08T07:38:33.042256Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2585348678,"revision":35446,"compact-revision":33150} {"level":"info","ts":"2026-06-08T07:40:55.255216Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a31e123290fcf0df","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-08T07:40:55.261292Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-06-08T07:40:55.261484Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-06-08T07:43:32.907263Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37678} {"level":"info","ts":"2026-06-08T07:43:33.026914Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37678,"took":"116.817021ms","hash":2021632373,"current-db-size-bytes":61698048,"current-db-size":"62 MB","current-db-size-in-use-bytes":31993856,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-08T07:43:33.026986Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2021632373,"revision":37678,"compact-revision":35446}