{"level":"warn","ts":"2026-09-14T16:21:42.307062Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-14T16:21:42.307188Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.164:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.164:2380","--initial-cluster=instance=https://162.253.55.164:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.164:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.164:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-09-14T16:21:42.307257Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-14T16:21:42.307273Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.164:2380"]} {"level":"info","ts":"2026-09-14T16:21:42.307293Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-14T16:21:42.30804Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.164:2379"]} {"level":"info","ts":"2026-09-14T16:21:42.30814Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.164:2380"],"listen-peer-urls":["https://162.253.55.164:2380"],"advertise-client-urls":["https://162.253.55.164:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.164:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.164:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-09-14T16:21:42.311285Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.889881ms"} {"level":"info","ts":"2026-09-14T16:21:42.318816Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"777352cb93f4c05d","cluster-id":"ebb6470dab83b2e8"} {"level":"info","ts":"2026-09-14T16:21:42.319196Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"777352cb93f4c05d switched to configuration voters=()"} {"level":"info","ts":"2026-09-14T16:21:42.319245Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"777352cb93f4c05d became follower at term 0"} {"level":"info","ts":"2026-09-14T16:21:42.319264Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 777352cb93f4c05d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-14T16:21:42.319284Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"777352cb93f4c05d became follower at term 1"} {"level":"info","ts":"2026-09-14T16:21:42.319354Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"777352cb93f4c05d switched to configuration voters=(8607314347149279325)"} {"level":"warn","ts":"2026-09-14T16:21:42.326757Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-14T16:21:42.331642Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-14T16:21:42.333908Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-09-14T16:21:42.338048Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"777352cb93f4c05d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-14T16:21:42.338255Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"777352cb93f4c05d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-14T16:21:42.338542Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-14T16:21:42.338637Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-14T16:21:42.338653Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-14T16:21:42.339489Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"777352cb93f4c05d switched to configuration voters=(8607314347149279325)"} {"level":"info","ts":"2026-09-14T16:21:42.339575Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ebb6470dab83b2e8","local-member-id":"777352cb93f4c05d","added-peer-id":"777352cb93f4c05d","added-peer-peer-urls":["https://162.253.55.164:2380"]} {"level":"info","ts":"2026-09-14T16:21:42.344388Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-14T16:21:42.344541Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.164:2380"} {"level":"info","ts":"2026-09-14T16:21:42.344599Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.164:2380"} {"level":"info","ts":"2026-09-14T16:21:42.34479Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"777352cb93f4c05d","initial-advertise-peer-urls":["https://162.253.55.164:2380"],"listen-peer-urls":["https://162.253.55.164:2380"],"advertise-client-urls":["https://162.253.55.164:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.164:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-14T16:21:42.344858Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-14T16:21:42.620562Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"777352cb93f4c05d is starting a new election at term 1"} {"level":"info","ts":"2026-09-14T16:21:42.620655Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"777352cb93f4c05d became pre-candidate at term 1"} {"level":"info","ts":"2026-09-14T16:21:42.620688Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"777352cb93f4c05d received MsgPreVoteResp from 777352cb93f4c05d at term 1"} {"level":"info","ts":"2026-09-14T16:21:42.620708Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"777352cb93f4c05d became candidate at term 2"} {"level":"info","ts":"2026-09-14T16:21:42.620724Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"777352cb93f4c05d received MsgVoteResp from 777352cb93f4c05d at term 2"} {"level":"info","ts":"2026-09-14T16:21:42.620739Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"777352cb93f4c05d became leader at term 2"} {"level":"info","ts":"2026-09-14T16:21:42.620755Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 777352cb93f4c05d elected leader 777352cb93f4c05d at term 2"} {"level":"info","ts":"2026-09-14T16:21:42.621811Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-14T16:21:42.622796Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"777352cb93f4c05d","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.164:2379]}","request-path":"/0/members/777352cb93f4c05d/attributes","cluster-id":"ebb6470dab83b2e8","publish-timeout":"7s"} {"level":"info","ts":"2026-09-14T16:21:42.622989Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-14T16:21:42.623033Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-14T16:21:42.62343Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-14T16:21:42.62347Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-14T16:21:42.624884Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.164:2379"} {"level":"info","ts":"2026-09-14T16:21:42.626679Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-14T16:21:42.637642Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ebb6470dab83b2e8","local-member-id":"777352cb93f4c05d","cluster-version":"3.5"} {"level":"info","ts":"2026-09-14T16:21:42.637745Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-14T16:21:42.637787Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-14T16:22:31.127188Z","caller":"traceutil/trace.go:171","msg":"trace[585327478] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"197.879564ms","start":"2026-09-14T16:22:30.929264Z","end":"2026-09-14T16:22:31.127143Z","steps":["trace[585327478] 'process raft request' (duration: 197.471315ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T16:22:31.538217Z","caller":"traceutil/trace.go:171","msg":"trace[1948721844] linearizableReadLoop","detail":"{readStateIndex:630; appliedIndex:629; }","duration":"378.849722ms","start":"2026-09-14T16:22:31.159341Z","end":"2026-09-14T16:22:31.538191Z","steps":["trace[1948721844] 'read index received' (duration: 378.142487ms)","trace[1948721844] 'applied index is now lower than readState.Index' (duration: 706.265µs)"],"step_count":2} {"level":"info","ts":"2026-09-14T16:22:31.538297Z","caller":"traceutil/trace.go:171","msg":"trace[447659966] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"549.639053ms","start":"2026-09-14T16:22:30.988614Z","end":"2026-09-14T16:22:31.538253Z","steps":["trace[447659966] 'process raft request' (duration: 549.063061ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T16:22:31.538597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.232799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-14T16:22:31.538778Z","caller":"traceutil/trace.go:171","msg":"trace[1344286417] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:605; }","duration":"379.467545ms","start":"2026-09-14T16:22:31.159288Z","end":"2026-09-14T16:22:31.538756Z","steps":["trace[1344286417] 'agreement among raft nodes before linearized reading' (duration: 379.091257ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T16:22:31.538926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-14T16:22:31.159266Z","time spent":"379.642978ms","remote":"127.0.0.1:53140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-14T16:22:31.538927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-14T16:22:30.988588Z","time spent":"549.786137ms","remote":"127.0.0.1:52934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036257,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-14T16:23:41.476515Z","caller":"traceutil/trace.go:171","msg":"trace[1013490470] transaction","detail":"{read_only:false; response_revision:1442; number_of_response:1; }","duration":"119.169311ms","start":"2026-09-14T16:23:41.357319Z","end":"2026-09-14T16:23:41.476488Z","steps":["trace[1013490470] 'process raft request' (duration: 89.558237ms)","trace[1013490470] 'compare' (duration: 29.475462ms)"],"step_count":2} {"level":"info","ts":"2026-09-14T16:24:08.361871Z","caller":"traceutil/trace.go:171","msg":"trace[1019062541] linearizableReadLoop","detail":"{readStateIndex:1938; appliedIndex:1937; }","duration":"140.624259ms","start":"2026-09-14T16:24:08.221198Z","end":"2026-09-14T16:24:08.361823Z","steps":["trace[1019062541] 'read index received' (duration: 140.298512ms)","trace[1019062541] 'applied index is now lower than readState.Index' (duration: 324.457µs)"],"step_count":2} {"level":"info","ts":"2026-09-14T16:24:08.361914Z","caller":"traceutil/trace.go:171","msg":"trace[642342593] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"212.793824ms","start":"2026-09-14T16:24:08.149082Z","end":"2026-09-14T16:24:08.361876Z","steps":["trace[642342593] 'process raft request' (duration: 212.450117ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T16:24:08.362085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.847264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-14T16:24:08.362153Z","caller":"traceutil/trace.go:171","msg":"trace[1214618029] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1863; }","duration":"140.989657ms","start":"2026-09-14T16:24:08.221147Z","end":"2026-09-14T16:24:08.362137Z","steps":["trace[1214618029] 'agreement among raft nodes before linearized reading' (duration: 140.835444ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T16:24:08.362235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.535057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-14T16:24:08.362364Z","caller":"traceutil/trace.go:171","msg":"trace[864457826] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1863; }","duration":"139.734541ms","start":"2026-09-14T16:24:08.222604Z","end":"2026-09-14T16:24:08.362339Z","steps":["trace[864457826] 'agreement among raft nodes before linearized reading' (duration: 139.499786ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T16:24:21.707613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.118066ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-14T16:24:21.708073Z","caller":"traceutil/trace.go:171","msg":"trace[612174019] transaction","detail":"{read_only:false; response_revision:1978; number_of_response:1; }","duration":"270.377327ms","start":"2026-09-14T16:24:21.437673Z","end":"2026-09-14T16:24:21.70805Z","steps":["trace[612174019] 'process raft request' (duration: 113.612217ms)","trace[612174019] 'compare' (duration: 156.002454ms)"],"step_count":2} {"level":"info","ts":"2026-09-14T16:24:21.708137Z","caller":"traceutil/trace.go:171","msg":"trace[1820699973] transaction","detail":"{read_only:false; response_revision:1979; number_of_response:1; }","duration":"206.455262ms","start":"2026-09-14T16:24:21.501661Z","end":"2026-09-14T16:24:21.708116Z","steps":["trace[1820699973] 'process raft request' (duration: 206.055083ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T16:24:58.935641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.402417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-14T16:24:58.935779Z","caller":"traceutil/trace.go:171","msg":"trace[1835990533] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:2193; }","duration":"138.598831ms","start":"2026-09-14T16:24:58.797162Z","end":"2026-09-14T16:24:58.935761Z","steps":["trace[1835990533] 'count revisions from in-memory index tree' (duration: 138.284354ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T16:25:04.123125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.641835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-14T16:25:04.123335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.381477ms","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":"info","ts":"2026-09-14T16:25:04.123399Z","caller":"traceutil/trace.go:171","msg":"trace[1702759806] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2221; }","duration":"124.48699ms","start":"2026-09-14T16:25:03.998894Z","end":"2026-09-14T16:25:04.12338Z","steps":["trace[1702759806] 'range keys from in-memory index tree' (duration: 124.128192ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T16:25:04.123586Z","caller":"traceutil/trace.go:171","msg":"trace[391384659] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2221; }","duration":"104.8413ms","start":"2026-09-14T16:25:04.018391Z","end":"2026-09-14T16:25:04.123232Z","steps":["trace[391384659] 'count revisions from in-memory index tree' (duration: 104.502793ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T16:26:24.863128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.18364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-14T16:26:24.863252Z","caller":"traceutil/trace.go:171","msg":"trace[1349295619] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2851; }","duration":"153.364714ms","start":"2026-09-14T16:26:24.709866Z","end":"2026-09-14T16:26:24.863231Z","steps":["trace[1349295619] 'range keys from in-memory index tree' (duration: 153.102648ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T16:26:30.061751Z","caller":"traceutil/trace.go:171","msg":"trace[145283147] transaction","detail":"{read_only:false; response_revision:2881; number_of_response:1; }","duration":"125.374893ms","start":"2026-09-14T16:26:29.936353Z","end":"2026-09-14T16:26:30.061728Z","steps":["trace[145283147] 'process raft request' (duration: 125.249451ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T16:31:09.190017Z","caller":"traceutil/trace.go:171","msg":"trace[1435120729] linearizableReadLoop","detail":"{readStateIndex:5459; appliedIndex:5457; }","duration":"333.568427ms","start":"2026-09-14T16:31:08.856428Z","end":"2026-09-14T16:31:09.189996Z","steps":["trace[1435120729] 'read index received' (duration: 17.613307ms)","trace[1435120729] 'applied index is now lower than readState.Index' (duration: 315.95413ms)"],"step_count":2} {"level":"info","ts":"2026-09-14T16:31:09.19023Z","caller":"traceutil/trace.go:171","msg":"trace[155891207] transaction","detail":"{read_only:false; response_revision:5260; number_of_response:1; }","duration":"345.669906ms","start":"2026-09-14T16:31:08.844545Z","end":"2026-09-14T16:31:09.190215Z","steps":["trace[155891207] 'process raft request' (duration: 292.571011ms)","trace[155891207] 'compare' (duration: 52.185896ms)"],"step_count":2} {"level":"warn","ts":"2026-09-14T16:31:09.190318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-14T16:31:08.844532Z","time spent":"345.727447ms","remote":"127.0.0.1:53140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-14T16:31:09.190457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.035998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-14T16:31:09.190488Z","caller":"traceutil/trace.go:171","msg":"trace[1443324646] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:5260; }","duration":"334.102698ms","start":"2026-09-14T16:31:08.856378Z","end":"2026-09-14T16:31:09.190481Z","steps":["trace[1443324646] 'agreement among raft nodes before linearized reading' (duration: 334.055047ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T16:31:09.190508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-14T16:31:08.856359Z","time spent":"334.14296ms","remote":"127.0.0.1:53038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":30,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-09-14T16:31:09.190674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.514879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-14T16:31:09.190702Z","caller":"traceutil/trace.go:171","msg":"trace[927685322] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5260; }","duration":"291.572931ms","start":"2026-09-14T16:31:08.899123Z","end":"2026-09-14T16:31:09.190696Z","steps":["trace[927685322] 'agreement among raft nodes before linearized reading' (duration: 291.484659ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T16:31:09.190956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.06866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-14T16:31:09.190985Z","caller":"traceutil/trace.go:171","msg":"trace[1969508513] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5260; }","duration":"189.124092ms","start":"2026-09-14T16:31:09.001855Z","end":"2026-09-14T16:31:09.190979Z","steps":["trace[1969508513] 'agreement among raft nodes before linearized reading' (duration: 189.05423ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T16:31:09.191122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.639346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-09-14T16:31:09.19115Z","caller":"traceutil/trace.go:171","msg":"trace[525725731] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:5260; }","duration":"243.696987ms","start":"2026-09-14T16:31:08.947446Z","end":"2026-09-14T16:31:09.191143Z","steps":["trace[525725731] 'agreement among raft nodes before linearized reading' (duration: 243.612115ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T16:31:10.950532Z","caller":"traceutil/trace.go:171","msg":"trace[1595804473] transaction","detail":"{read_only:false; response_revision:5289; number_of_response:1; }","duration":"138.227484ms","start":"2026-09-14T16:31:10.812278Z","end":"2026-09-14T16:31:10.950505Z","steps":["trace[1595804473] 'process raft request' (duration: 60.36466ms)","trace[1595804473] 'compare' (duration: 77.773502ms)"],"step_count":2} {"level":"info","ts":"2026-09-14T16:31:21.940384Z","caller":"traceutil/trace.go:171","msg":"trace[850257830] transaction","detail":"{read_only:false; response_revision:5481; number_of_response:1; }","duration":"123.145331ms","start":"2026-09-14T16:31:21.817194Z","end":"2026-09-14T16:31:21.940339Z","steps":["trace[850257830] 'process raft request' (duration: 122.857365ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T16:31:24.580001Z","caller":"traceutil/trace.go:171","msg":"trace[1479245835] linearizableReadLoop","detail":"{readStateIndex:5740; appliedIndex:5739; }","duration":"246.608529ms","start":"2026-09-14T16:31:24.333359Z","end":"2026-09-14T16:31:24.579968Z","steps":["trace[1479245835] 'read index received' (duration: 198.301197ms)","trace[1479245835] 'applied index is now lower than readState.Index' (duration: 48.306122ms)"],"step_count":2} {"level":"info","ts":"2026-09-14T16:31:24.580185Z","caller":"traceutil/trace.go:171","msg":"trace[1863775127] transaction","detail":"{read_only:false; response_revision:5531; number_of_response:1; }","duration":"249.162014ms","start":"2026-09-14T16:31:24.330989Z","end":"2026-09-14T16:31:24.580152Z","steps":["trace[1863775127] 'process raft request' (duration: 200.73834ms)","trace[1863775127] 'compare' (duration: 48.119208ms)"],"step_count":2} {"level":"warn","ts":"2026-09-14T16:31:24.580389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.013128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:8 size:71019"} {"level":"info","ts":"2026-09-14T16:31:24.580433Z","caller":"traceutil/trace.go:171","msg":"trace[691416995] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:8; response_revision:5531; }","duration":"247.10665ms","start":"2026-09-14T16:31:24.333315Z","end":"2026-09-14T16:31:24.580421Z","steps":["trace[691416995] 'agreement among raft nodes before linearized reading' (duration: 246.840324ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T16:31:24.58043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.602541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-14T16:31:24.58051Z","caller":"traceutil/trace.go:171","msg":"trace[286072037] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:5531; }","duration":"182.740634ms","start":"2026-09-14T16:31:24.397751Z","end":"2026-09-14T16:31:24.580492Z","steps":["trace[286072037] 'agreement among raft nodes before linearized reading' (duration: 182.597021ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T16:31:24.858277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.381359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-14T16:31:24.858363Z","caller":"traceutil/trace.go:171","msg":"trace[944178281] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5531; }","duration":"147.527552ms","start":"2026-09-14T16:31:24.710816Z","end":"2026-09-14T16:31:24.858344Z","steps":["trace[944178281] 'range keys from in-memory index tree' (duration: 147.291736ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T16:31:34.386925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.927354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-14T16:31:34.387032Z","caller":"traceutil/trace.go:171","msg":"trace[518935582] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:5740; }","duration":"113.123078ms","start":"2026-09-14T16:31:34.273884Z","end":"2026-09-14T16:31:34.387007Z","steps":["trace[518935582] 'count revisions from in-memory index tree' (duration: 112.749479ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T16:31:34.387162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.416393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-14T16:31:34.387295Z","caller":"traceutil/trace.go:171","msg":"trace[735984520] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:5740; }","duration":"164.627828ms","start":"2026-09-14T16:31:34.222638Z","end":"2026-09-14T16:31:34.387265Z","steps":["trace[735984520] 'count revisions from in-memory index tree' (duration: 164.23901ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T16:31:42.71823Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2949} {"level":"info","ts":"2026-09-14T16:31:42.801898Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2949,"took":"82.164287ms","hash":2201922502,"current-db-size-bytes":56803328,"current-db-size":"57 MB","current-db-size-in-use-bytes":32280576,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-14T16:31:42.801989Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2201922502,"revision":2949,"compact-revision":-1} {"level":"info","ts":"2026-09-14T16:36:42.725413Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5845} {"level":"info","ts":"2026-09-14T16:36:42.820234Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5845,"took":"92.222691ms","hash":1754856519,"current-db-size-bytes":56803328,"current-db-size":"57 MB","current-db-size-in-use-bytes":26193920,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-14T16:36:42.82032Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1754856519,"revision":5845,"compact-revision":2949} {"level":"warn","ts":"2026-09-14T16:37:55.216971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.2962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-7kjg5\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-09-14T16:37:55.217079Z","caller":"traceutil/trace.go:171","msg":"trace[1121141762] range","detail":"{range_begin:/registry/pods/openstack/keepalived-7kjg5; range_end:; response_count:1; response_revision:8620; }","duration":"177.458743ms","start":"2026-09-14T16:37:55.039599Z","end":"2026-09-14T16:37:55.217058Z","steps":["trace[1121141762] 'range keys from in-memory index tree' (duration: 177.008713ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T16:38:06.623773Z","caller":"traceutil/trace.go:171","msg":"trace[510669764] transaction","detail":"{read_only:false; response_revision:8678; number_of_response:1; }","duration":"119.304661ms","start":"2026-09-14T16:38:06.50444Z","end":"2026-09-14T16:38:06.623744Z","steps":["trace[510669764] 'process raft request' (duration: 119.134617ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T16:38:06.707672Z","caller":"traceutil/trace.go:171","msg":"trace[1041840337] transaction","detail":"{read_only:false; response_revision:8679; number_of_response:1; }","duration":"173.141602ms","start":"2026-09-14T16:38:06.53449Z","end":"2026-09-14T16:38:06.707632Z","steps":["trace[1041840337] 'process raft request' (duration: 162.995374ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T16:38:07.803059Z","caller":"traceutil/trace.go:171","msg":"trace[464126533] transaction","detail":"{read_only:false; response_revision:8686; number_of_response:1; }","duration":"123.48868ms","start":"2026-09-14T16:38:07.67954Z","end":"2026-09-14T16:38:07.803029Z","steps":["trace[464126533] 'process raft request' (duration: 122.762065ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T16:38:39.209472Z","caller":"traceutil/trace.go:171","msg":"trace[881204144] transaction","detail":"{read_only:false; response_revision:8906; number_of_response:1; }","duration":"156.456475ms","start":"2026-09-14T16:38:39.052995Z","end":"2026-09-14T16:38:39.209451Z","steps":["trace[881204144] 'process raft request' (duration: 156.260441ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T16:38:39.439201Z","caller":"traceutil/trace.go:171","msg":"trace[1682208031] linearizableReadLoop","detail":"{readStateIndex:9226; appliedIndex:9224; }","duration":"314.488755ms","start":"2026-09-14T16:38:39.124686Z","end":"2026-09-14T16:38:39.439175Z","steps":["trace[1682208031] 'read index received' (duration: 84.555918ms)","trace[1682208031] 'applied index is now lower than readState.Index' (duration: 229.931727ms)"],"step_count":2} {"level":"info","ts":"2026-09-14T16:38:39.439302Z","caller":"traceutil/trace.go:171","msg":"trace[885431681] transaction","detail":"{read_only:false; response_revision:8907; number_of_response:1; }","duration":"353.392166ms","start":"2026-09-14T16:38:39.085859Z","end":"2026-09-14T16:38:39.439251Z","steps":["trace[885431681] 'process raft request' (duration: 353.125591ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T16:38:39.439459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-14T16:38:39.085826Z","time spent":"353.54266ms","remote":"127.0.0.1:53140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-14T16:38:39.439516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.797231ms","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":"info","ts":"2026-09-14T16:38:39.439613Z","caller":"traceutil/trace.go:171","msg":"trace[178639531] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8907; }","duration":"314.963365ms","start":"2026-09-14T16:38:39.124627Z","end":"2026-09-14T16:38:39.43959Z","steps":["trace[178639531] 'agreement among raft nodes before linearized reading' (duration: 314.659408ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T16:38:39.439658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-14T16:38:39.12461Z","time spent":"315.039246ms","remote":"127.0.0.1:53140","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":"info","ts":"2026-09-14T16:38:52.876793Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002489.wal"} {"level":"info","ts":"2026-09-14T16:39:54.528378Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"777352cb93f4c05d","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-14T16:39:54.554594Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-14T16:39:54.554741Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-09-14T16:41:42.742039Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8006} {"level":"info","ts":"2026-09-14T16:41:42.812183Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8006,"took":"68.380051ms","hash":1452694635,"current-db-size-bytes":56803328,"current-db-size":"57 MB","current-db-size-in-use-bytes":28065792,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-14T16:41:42.812257Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1452694635,"revision":8006,"compact-revision":5845} {"level":"info","ts":"2026-09-14T16:45:36.489215Z","caller":"traceutil/trace.go:171","msg":"trace[1384553403] transaction","detail":"{read_only:false; response_revision:12136; number_of_response:1; }","duration":"102.024251ms","start":"2026-09-14T16:45:36.387164Z","end":"2026-09-14T16:45:36.489188Z","steps":["trace[1384553403] 'process raft request' (duration: 101.708754ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T16:45:58.29363Z","caller":"traceutil/trace.go:171","msg":"trace[1845198842] transaction","detail":"{read_only:false; response_revision:12256; number_of_response:1; }","duration":"137.707792ms","start":"2026-09-14T16:45:58.155877Z","end":"2026-09-14T16:45:58.293584Z","steps":["trace[1845198842] 'process raft request' (duration: 44.246435ms)","trace[1845198842] 'compare' (duration: 92.733641ms)"],"step_count":2} {"level":"info","ts":"2026-09-14T16:46:42.756497Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10400} {"level":"info","ts":"2026-09-14T16:46:42.850648Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10400,"took":"91.858422ms","hash":2239994250,"current-db-size-bytes":56803328,"current-db-size":"57 MB","current-db-size-in-use-bytes":25858048,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-14T16:46:42.850731Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2239994250,"revision":10400,"compact-revision":8006} {"level":"info","ts":"2026-09-14T16:51:42.764355Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12575} {"level":"info","ts":"2026-09-14T16:51:42.851053Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12575,"took":"83.929054ms","hash":2706855752,"current-db-size-bytes":56803328,"current-db-size":"57 MB","current-db-size-in-use-bytes":27815936,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-14T16:51:42.85116Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2706855752,"revision":12575,"compact-revision":10400} {"level":"info","ts":"2026-09-14T16:52:40.328644Z","caller":"traceutil/trace.go:171","msg":"trace[214589452] linearizableReadLoop","detail":"{readStateIndex:15451; appliedIndex:15450; }","duration":"147.528864ms","start":"2026-09-14T16:52:40.181087Z","end":"2026-09-14T16:52:40.328616Z","steps":["trace[214589452] 'read index received' (duration: 134.002054ms)","trace[214589452] 'applied index is now lower than readState.Index' (duration: 13.52508ms)"],"step_count":2} {"level":"info","ts":"2026-09-14T16:52:40.328809Z","caller":"traceutil/trace.go:171","msg":"trace[449874443] transaction","detail":"{read_only:false; response_revision:14930; number_of_response:1; }","duration":"159.57198ms","start":"2026-09-14T16:52:40.169218Z","end":"2026-09-14T16:52:40.32879Z","steps":["trace[449874443] 'process raft request' (duration: 146.00974ms)","trace[449874443] 'compare' (duration: 13.248374ms)"],"step_count":2} {"level":"warn","ts":"2026-09-14T16:52:40.329322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.182687ms","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-09-14T16:52:40.329497Z","caller":"traceutil/trace.go:171","msg":"trace[758517678] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14930; }","duration":"148.383742ms","start":"2026-09-14T16:52:40.181051Z","end":"2026-09-14T16:52:40.329435Z","steps":["trace[758517678] 'agreement among raft nodes before linearized reading' (duration: 147.967893ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T16:52:40.329594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.403574ms","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":"info","ts":"2026-09-14T16:52:40.329762Z","caller":"traceutil/trace.go:171","msg":"trace[304337454] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14930; }","duration":"143.089749ms","start":"2026-09-14T16:52:40.186622Z","end":"2026-09-14T16:52:40.329712Z","steps":["trace[304337454] 'agreement among raft nodes before linearized reading' (duration: 142.305432ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T16:52:41.062608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.956686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" ","response":"range_response_count:1 size:1857"} {"level":"info","ts":"2026-09-14T16:52:41.062743Z","caller":"traceutil/trace.go:171","msg":"trace[2130483755] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0; range_end:; response_count:1; response_revision:14939; }","duration":"216.16872ms","start":"2026-09-14T16:52:40.846547Z","end":"2026-09-14T16:52:41.062715Z","steps":["trace[2130483755] 'range keys from in-memory index tree' (duration: 215.802772ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T16:52:52.207156Z","caller":"traceutil/trace.go:171","msg":"trace[1670409984] transaction","detail":"{read_only:false; response_revision:15113; number_of_response:1; }","duration":"111.932272ms","start":"2026-09-14T16:52:52.095197Z","end":"2026-09-14T16:52:52.207129Z","steps":["trace[1670409984] 'process raft request' (duration: 111.739598ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T16:52:52.384063Z","caller":"traceutil/trace.go:171","msg":"trace[1343095942] linearizableReadLoop","detail":"{readStateIndex:15640; appliedIndex:15639; }","duration":"165.992248ms","start":"2026-09-14T16:52:52.218036Z","end":"2026-09-14T16:52:52.384028Z","steps":["trace[1343095942] 'read index received' (duration: 137.362447ms)","trace[1343095942] 'applied index is now lower than readState.Index' (duration: 28.628651ms)"],"step_count":2} {"level":"info","ts":"2026-09-14T16:52:52.384158Z","caller":"traceutil/trace.go:171","msg":"trace[290038026] transaction","detail":"{read_only:false; response_revision:15114; number_of_response:1; }","duration":"166.353216ms","start":"2026-09-14T16:52:52.217745Z","end":"2026-09-14T16:52:52.384098Z","steps":["trace[290038026] 'process raft request' (duration: 137.651343ms)","trace[290038026] 'compare' (duration: 28.477638ms)"],"step_count":2} {"level":"warn","ts":"2026-09-14T16:52:52.384444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.386046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/data-ovn-ovsdb-sb-0.18d53e287aa80e73\" ","response":"range_response_count:1 size:956"} {"level":"info","ts":"2026-09-14T16:52:52.384581Z","caller":"traceutil/trace.go:171","msg":"trace[1527500111] range","detail":"{range_begin:/registry/events/openstack/data-ovn-ovsdb-sb-0.18d53e287aa80e73; range_end:; response_count:1; response_revision:15114; }","duration":"166.56367ms","start":"2026-09-14T16:52:52.217986Z","end":"2026-09-14T16:52:52.384549Z","steps":["trace[1527500111] 'agreement among raft nodes before linearized reading' (duration: 166.234483ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T16:52:52.430509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.425407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/ovn-controller-default-8ft57.18d53e28867be609\" ","response":"range_response_count:1 size:783"} {"level":"info","ts":"2026-09-14T16:52:52.430668Z","caller":"traceutil/trace.go:171","msg":"trace[971904455] range","detail":"{range_begin:/registry/events/openstack/ovn-controller-default-8ft57.18d53e28867be609; range_end:; response_count:1; response_revision:15115; }","duration":"173.625931ms","start":"2026-09-14T16:52:52.257019Z","end":"2026-09-14T16:52:52.430645Z","steps":["trace[971904455] 'agreement among raft nodes before linearized reading' (duration: 173.218472ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T16:56:42.771646Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14579} {"level":"info","ts":"2026-09-14T16:56:42.88584Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14579,"took":"109.562382ms","hash":3613931530,"current-db-size-bytes":56803328,"current-db-size":"57 MB","current-db-size-in-use-bytes":32133120,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-14T16:56:42.88601Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3613931530,"revision":14579,"compact-revision":12575} {"level":"info","ts":"2026-09-14T17:00:12.538786Z","caller":"traceutil/trace.go:171","msg":"trace[440159520] transaction","detail":"{read_only:false; response_revision:18776; number_of_response:1; }","duration":"210.502699ms","start":"2026-09-14T17:00:12.32826Z","end":"2026-09-14T17:00:12.538763Z","steps":["trace[440159520] 'process raft request' (duration: 200.79102ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:00:12.544915Z","caller":"traceutil/trace.go:171","msg":"trace[729252268] transaction","detail":"{read_only:false; response_revision:18777; number_of_response:1; }","duration":"214.381551ms","start":"2026-09-14T17:00:12.330516Z","end":"2026-09-14T17:00:12.544897Z","steps":["trace[729252268] 'process raft request' (duration: 214.295299ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:00:20.201286Z","caller":"traceutil/trace.go:171","msg":"trace[506030903] linearizableReadLoop","detail":"{readStateIndex:19535; appliedIndex:19534; }","duration":"328.60424ms","start":"2026-09-14T17:00:19.872656Z","end":"2026-09-14T17:00:20.20126Z","steps":["trace[506030903] 'read index received' (duration: 328.365395ms)","trace[506030903] 'applied index is now lower than readState.Index' (duration: 237.475µs)"],"step_count":2} {"level":"info","ts":"2026-09-14T17:00:20.201902Z","caller":"traceutil/trace.go:171","msg":"trace[580135032] transaction","detail":"{read_only:false; response_revision:18890; number_of_response:1; }","duration":"332.988015ms","start":"2026-09-14T17:00:19.868898Z","end":"2026-09-14T17:00:20.201886Z","steps":["trace[580135032] 'process raft request' (duration: 332.180048ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:00:20.202069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-14T17:00:19.86884Z","time spent":"333.152218ms","remote":"127.0.0.1:53050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20869,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-14T17:00:20.202381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.725755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-848babb6-f90b-4318-9638-9af7deec119b\" ","response":"range_response_count:1 size:1318"} {"level":"info","ts":"2026-09-14T17:00:20.202551Z","caller":"traceutil/trace.go:171","msg":"trace[1323998509] range","detail":"{range_begin:/registry/persistentvolumes/pvc-848babb6-f90b-4318-9638-9af7deec119b; range_end:; response_count:1; response_revision:18890; }","duration":"329.919968ms","start":"2026-09-14T17:00:19.872615Z","end":"2026-09-14T17:00:20.202535Z","steps":["trace[1323998509] 'agreement among raft nodes before linearized reading' (duration: 329.661853ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:00:20.202629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-14T17:00:19.872607Z","time spent":"330.00797ms","remote":"127.0.0.1:53004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1341,"request content":"key:\"/registry/persistentvolumes/pvc-848babb6-f90b-4318-9638-9af7deec119b\" "} {"level":"warn","ts":"2026-09-14T17:00:20.202879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.307113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-14T17:00:20.202959Z","caller":"traceutil/trace.go:171","msg":"trace[828770664] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:18890; }","duration":"254.388005ms","start":"2026-09-14T17:00:19.948559Z","end":"2026-09-14T17:00:20.202947Z","steps":["trace[828770664] 'agreement among raft nodes before linearized reading' (duration: 254.281463ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:00:50.52994Z","caller":"traceutil/trace.go:171","msg":"trace[825298079] linearizableReadLoop","detail":"{readStateIndex:19970; appliedIndex:19969; }","duration":"131.267875ms","start":"2026-09-14T17:00:50.398636Z","end":"2026-09-14T17:00:50.529904Z","steps":["trace[825298079] 'read index received' (duration: 100.64284ms)","trace[825298079] 'applied index is now lower than readState.Index' (duration: 30.622385ms)"],"step_count":2} {"level":"warn","ts":"2026-09-14T17:00:50.530106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.439458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-14T17:00:50.530095Z","caller":"traceutil/trace.go:171","msg":"trace[1013866977] transaction","detail":"{read_only:false; response_revision:19311; number_of_response:1; }","duration":"137.717812ms","start":"2026-09-14T17:00:50.392336Z","end":"2026-09-14T17:00:50.530053Z","steps":["trace[1013866977] 'process raft request' (duration: 106.877853ms)","trace[1013866977] 'compare' (duration: 30.213596ms)"],"step_count":2} {"level":"info","ts":"2026-09-14T17:00:50.530146Z","caller":"traceutil/trace.go:171","msg":"trace[1305318791] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:19312; }","duration":"131.53894ms","start":"2026-09-14T17:00:50.398594Z","end":"2026-09-14T17:00:50.530133Z","steps":["trace[1305318791] 'agreement among raft nodes before linearized reading' (duration: 131.428038ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:00:54.806651Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"777352cb93f4c05d","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-14T17:00:54.813262Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-09-14T17:00:54.813387Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-09-14T17:01:42.779432Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17113} {"level":"info","ts":"2026-09-14T17:01:42.90431Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17113,"took":"120.967334ms","hash":3000974084,"current-db-size-bytes":56803328,"current-db-size":"57 MB","current-db-size-in-use-bytes":34385920,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-09-14T17:01:42.904363Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3000974084,"revision":17113,"compact-revision":14579} {"level":"info","ts":"2026-09-14T17:06:02.174828Z","caller":"traceutil/trace.go:171","msg":"trace[704304300] transaction","detail":"{read_only:false; response_revision:21534; number_of_response:1; }","duration":"130.248073ms","start":"2026-09-14T17:06:02.044542Z","end":"2026-09-14T17:06:02.17479Z","steps":["trace[704304300] 'process raft request' (duration: 129.955446ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:06:07.735375Z","caller":"traceutil/trace.go:171","msg":"trace[989195203] transaction","detail":"{read_only:false; response_revision:21561; number_of_response:1; }","duration":"153.205544ms","start":"2026-09-14T17:06:07.582131Z","end":"2026-09-14T17:06:07.735336Z","steps":["trace[989195203] 'process raft request' (duration: 153.009549ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:06:08.051845Z","caller":"traceutil/trace.go:171","msg":"trace[315112350] transaction","detail":"{read_only:false; response_revision:21562; number_of_response:1; }","duration":"443.049076ms","start":"2026-09-14T17:06:07.608741Z","end":"2026-09-14T17:06:08.05179Z","steps":["trace[315112350] 'process raft request' (duration: 442.679998ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:06:08.052075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-14T17:06:07.608728Z","time spent":"443.230511ms","remote":"127.0.0.1:53140","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-09-14T17:06:08.052078Z","caller":"traceutil/trace.go:171","msg":"trace[1826710913] transaction","detail":"{read_only:false; response_revision:21563; number_of_response:1; }","duration":"434.449684ms","start":"2026-09-14T17:06:07.617595Z","end":"2026-09-14T17:06:08.052044Z","steps":["trace[1826710913] 'process raft request' (duration: 434.156337ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:06:08.052287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-14T17:06:07.617574Z","time spent":"434.600517ms","remote":"127.0.0.1:35754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418229,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-14T17:06:08.218633Z","caller":"traceutil/trace.go:171","msg":"trace[119406884] linearizableReadLoop","detail":"{readStateIndex:22299; appliedIndex:22295; }","duration":"355.69088ms","start":"2026-09-14T17:06:07.862914Z","end":"2026-09-14T17:06:08.218605Z","steps":["trace[119406884] 'read index received' (duration: 188.547398ms)","trace[119406884] 'applied index is now lower than readState.Index' (duration: 167.142492ms)"],"step_count":2} {"level":"info","ts":"2026-09-14T17:06:08.219005Z","caller":"traceutil/trace.go:171","msg":"trace[2036637251] transaction","detail":"{read_only:false; response_revision:21564; number_of_response:1; }","duration":"447.503952ms","start":"2026-09-14T17:06:07.771485Z","end":"2026-09-14T17:06:08.218989Z","steps":["trace[2036637251] 'process raft request' (duration: 414.275401ms)","trace[2036637251] 'compare' (duration: 32.628568ms)"],"step_count":2} {"level":"warn","ts":"2026-09-14T17:06:08.219226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-14T17:06:07.771461Z","time spent":"447.584094ms","remote":"127.0.0.1:53140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-14T17:06:08.219469Z","caller":"traceutil/trace.go:171","msg":"trace[664891746] transaction","detail":"{read_only:false; response_revision:21565; number_of_response:1; }","duration":"432.162383ms","start":"2026-09-14T17:06:07.787295Z","end":"2026-09-14T17:06:08.219457Z","steps":["trace[664891746] 'process raft request' (duration: 431.230424ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:06:08.219528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-14T17:06:07.787258Z","time spent":"432.242195ms","remote":"127.0.0.1:53140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-14T17:06:08.219782Z","caller":"traceutil/trace.go:171","msg":"trace[1634850062] transaction","detail":"{read_only:false; response_revision:21566; number_of_response:1; }","duration":"286.386099ms","start":"2026-09-14T17:06:07.933385Z","end":"2026-09-14T17:06:08.219771Z","steps":["trace[1634850062] 'process raft request' (duration: 285.179534ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:06:08.220002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.084611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-14T17:06:08.220048Z","caller":"traceutil/trace.go:171","msg":"trace[944602151] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21566; }","duration":"357.166312ms","start":"2026-09-14T17:06:07.862869Z","end":"2026-09-14T17:06:08.220035Z","steps":["trace[944602151] 'agreement among raft nodes before linearized reading' (duration: 357.067568ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:06:08.220077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-14T17:06:07.862853Z","time spent":"357.216123ms","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-14T17:06:08.220366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.869733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-09-14T17:06:08.220404Z","caller":"traceutil/trace.go:171","msg":"trace[1443694101] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:21566; }","duration":"347.932905ms","start":"2026-09-14T17:06:07.87246Z","end":"2026-09-14T17:06:08.220393Z","steps":["trace[1443694101] 'agreement among raft nodes before linearized reading' (duration: 347.811023ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:06:08.220432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-14T17:06:07.872448Z","time spent":"347.974526ms","remote":"127.0.0.1:53018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1889,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-09-14T17:06:08.833521Z","caller":"traceutil/trace.go:171","msg":"trace[450693134] linearizableReadLoop","detail":"{readStateIndex:22301; appliedIndex:22300; }","duration":"123.241643ms","start":"2026-09-14T17:06:08.710257Z","end":"2026-09-14T17:06:08.833499Z","steps":["trace[450693134] 'read index received' (duration: 123.058188ms)","trace[450693134] 'applied index is now lower than readState.Index' (duration: 182.365µs)"],"step_count":2} {"level":"info","ts":"2026-09-14T17:06:08.833695Z","caller":"traceutil/trace.go:171","msg":"trace[1621517341] transaction","detail":"{read_only:false; response_revision:21567; number_of_response:1; }","duration":"205.798578ms","start":"2026-09-14T17:06:08.627884Z","end":"2026-09-14T17:06:08.833683Z","steps":["trace[1621517341] 'process raft request' (duration: 205.45557ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:06:08.833873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.6038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-14T17:06:08.83391Z","caller":"traceutil/trace.go:171","msg":"trace[1873343539] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21567; }","duration":"123.717084ms","start":"2026-09-14T17:06:08.710184Z","end":"2026-09-14T17:06:08.833901Z","steps":["trace[1873343539] 'agreement among raft nodes before linearized reading' (duration: 123.637133ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:06:08.834067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.419101ms","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-09-14T17:06:08.834163Z","caller":"traceutil/trace.go:171","msg":"trace[132147961] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21567; }","duration":"118.551973ms","start":"2026-09-14T17:06:08.715589Z","end":"2026-09-14T17:06:08.834141Z","steps":["trace[132147961] 'agreement among raft nodes before linearized reading' (duration: 118.322859ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:06:09.162786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.853686ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-14T17:06:09.163009Z","caller":"traceutil/trace.go:171","msg":"trace[507574717] transaction","detail":"{read_only:false; response_revision:21568; number_of_response:1; }","duration":"324.447632ms","start":"2026-09-14T17:06:08.838496Z","end":"2026-09-14T17:06:09.162943Z","steps":["trace[507574717] 'process raft request' (duration: 120.307641ms)","trace[507574717] 'compare' (duration: 203.61416ms)"],"step_count":2} {"level":"warn","ts":"2026-09-14T17:06:09.163188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-14T17:06:08.838471Z","time spent":"324.617766ms","remote":"127.0.0.1:53140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-14T17:06:09.16324Z","caller":"traceutil/trace.go:171","msg":"trace[983964059] linearizableReadLoop","detail":"{readStateIndex:22302; appliedIndex:22301; }","duration":"324.182167ms","start":"2026-09-14T17:06:08.839033Z","end":"2026-09-14T17:06:09.163215Z","steps":["trace[983964059] 'read index received' (duration: 119.817001ms)","trace[983964059] 'applied index is now lower than readState.Index' (duration: 204.363326ms)"],"step_count":2} {"level":"warn","ts":"2026-09-14T17:06:09.163579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.507834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19265"} {"level":"info","ts":"2026-09-14T17:06:09.163618Z","caller":"traceutil/trace.go:171","msg":"trace[903456731] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:21568; }","duration":"324.602735ms","start":"2026-09-14T17:06:08.839004Z","end":"2026-09-14T17:06:09.163607Z","steps":["trace[903456731] 'agreement among raft nodes before linearized reading' (duration: 324.35208ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:06:09.163685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-14T17:06:08.83899Z","time spent":"324.684088ms","remote":"127.0.0.1:53038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":19288,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-09-14T17:06:09.164036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.74837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-14T17:06:09.164036Z","caller":"traceutil/trace.go:171","msg":"trace[1324630613] transaction","detail":"{read_only:false; response_revision:21569; number_of_response:1; }","duration":"324.845299ms","start":"2026-09-14T17:06:08.839153Z","end":"2026-09-14T17:06:09.163998Z","steps":["trace[1324630613] 'process raft request' (duration: 323.750616ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:06:09.1641Z","caller":"traceutil/trace.go:171","msg":"trace[2070586831] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:21569; }","duration":"303.848892ms","start":"2026-09-14T17:06:08.860238Z","end":"2026-09-14T17:06:09.164087Z","steps":["trace[2070586831] 'agreement among raft nodes before linearized reading' (duration: 303.689738ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:06:09.164159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-14T17:06:08.860221Z","time spent":"303.904753ms","remote":"127.0.0.1:53332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":44,"response size":31,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-09-14T17:06:09.164319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.872774ms","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-09-14T17:06:09.16436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-14T17:06:08.839122Z","time spent":"324.993484ms","remote":"127.0.0.1:53140","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-09-14T17:06:09.164439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.255135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-09-14T17:06:09.16447Z","caller":"traceutil/trace.go:171","msg":"trace[1435372816] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21569; }","duration":"270.328536ms","start":"2026-09-14T17:06:08.894132Z","end":"2026-09-14T17:06:09.16446Z","steps":["trace[1435372816] 'agreement among raft nodes before linearized reading' (duration: 270.207594ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:06:09.164386Z","caller":"traceutil/trace.go:171","msg":"trace[1008960807] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21569; }","duration":"268.079578ms","start":"2026-09-14T17:06:08.896266Z","end":"2026-09-14T17:06:09.164346Z","steps":["trace[1008960807] 'agreement among raft nodes before linearized reading' (duration: 267.937815ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:06:42.786223Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19731} {"level":"info","ts":"2026-09-14T17:06:42.910923Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19731,"took":"120.756571ms","hash":72460826,"current-db-size-bytes":56803328,"current-db-size":"57 MB","current-db-size-in-use-bytes":30535680,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-14T17:06:42.911004Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":72460826,"revision":19731,"compact-revision":17113} {"level":"info","ts":"2026-09-14T17:10:03.403553Z","caller":"traceutil/trace.go:171","msg":"trace[567576207] transaction","detail":"{read_only:false; response_revision:23568; number_of_response:1; }","duration":"173.305255ms","start":"2026-09-14T17:10:03.230222Z","end":"2026-09-14T17:10:03.403528Z","steps":["trace[567576207] 'process raft request' (duration: 173.043248ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:10:03.403782Z","caller":"traceutil/trace.go:171","msg":"trace[627494198] transaction","detail":"{read_only:false; response_revision:23570; number_of_response:1; }","duration":"166.427568ms","start":"2026-09-14T17:10:03.237305Z","end":"2026-09-14T17:10:03.403733Z","steps":["trace[627494198] 'process raft request' (duration: 166.184973ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:10:03.403625Z","caller":"traceutil/trace.go:171","msg":"trace[1257321280] transaction","detail":"{read_only:false; response_revision:23571; number_of_response:1; }","duration":"161.431691ms","start":"2026-09-14T17:10:03.242173Z","end":"2026-09-14T17:10:03.403605Z","steps":["trace[1257321280] 'process raft request' (duration: 161.35281ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:10:03.403677Z","caller":"traceutil/trace.go:171","msg":"trace[1970515316] transaction","detail":"{read_only:false; response_revision:23569; number_of_response:1; }","duration":"166.360787ms","start":"2026-09-14T17:10:03.237306Z","end":"2026-09-14T17:10:03.403667Z","steps":["trace[1970515316] 'process raft request' (duration: 166.133762ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:10:03.403981Z","caller":"traceutil/trace.go:171","msg":"trace[661614552] transaction","detail":"{read_only:false; number_of_response:1; response_revision:23568; }","duration":"172.873246ms","start":"2026-09-14T17:10:03.231031Z","end":"2026-09-14T17:10:03.403905Z","steps":["trace[661614552] 'process raft request' (duration: 172.358125ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:10:03.404831Z","caller":"traceutil/trace.go:171","msg":"trace[1058803657] linearizableReadLoop","detail":"{readStateIndex:24377; appliedIndex:24369; }","duration":"124.851399ms","start":"2026-09-14T17:10:03.279968Z","end":"2026-09-14T17:10:03.404819Z","steps":["trace[1058803657] 'read index received' (duration: 123.239584ms)","trace[1058803657] 'applied index is now lower than readState.Index' (duration: 1.611285ms)"],"step_count":2} {"level":"info","ts":"2026-09-14T17:10:03.404834Z","caller":"traceutil/trace.go:171","msg":"trace[818735265] transaction","detail":"{read_only:false; response_revision:23572; number_of_response:1; }","duration":"151.882426ms","start":"2026-09-14T17:10:03.252909Z","end":"2026-09-14T17:10:03.404792Z","steps":["trace[818735265] 'process raft request' (duration: 150.659741ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:10:03.404957Z","caller":"traceutil/trace.go:171","msg":"trace[498046813] transaction","detail":"{read_only:false; number_of_response:1; response_revision:23573; }","duration":"135.21344ms","start":"2026-09-14T17:10:03.269697Z","end":"2026-09-14T17:10:03.404911Z","steps":["trace[498046813] 'process raft request' (duration: 135.033816ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:10:03.404847Z","caller":"traceutil/trace.go:171","msg":"trace[1653806592] transaction","detail":"{read_only:false; response_revision:23573; number_of_response:1; }","duration":"143.415626ms","start":"2026-09-14T17:10:03.261411Z","end":"2026-09-14T17:10:03.404827Z","steps":["trace[1653806592] 'process raft request' (duration: 143.200501ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:10:03.405124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.148904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1344"} {"level":"info","ts":"2026-09-14T17:10:03.406314Z","caller":"traceutil/trace.go:171","msg":"trace[1607526192] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:23573; }","duration":"184.366701ms","start":"2026-09-14T17:10:03.221916Z","end":"2026-09-14T17:10:03.406283Z","steps":["trace[1607526192] 'agreement among raft nodes before linearized reading' (duration: 183.011882ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:10:07.968093Z","caller":"traceutil/trace.go:171","msg":"trace[1187029117] linearizableReadLoop","detail":"{readStateIndex:24753; appliedIndex:24752; }","duration":"174.183633ms","start":"2026-09-14T17:10:07.793873Z","end":"2026-09-14T17:10:07.968056Z","steps":["trace[1187029117] 'read index received' (duration: 173.875167ms)","trace[1187029117] 'applied index is now lower than readState.Index' (duration: 307.206µs)"],"step_count":2} {"level":"info","ts":"2026-09-14T17:10:07.968221Z","caller":"traceutil/trace.go:171","msg":"trace[1221967369] transaction","detail":"{read_only:false; response_revision:23942; number_of_response:1; }","duration":"176.076854ms","start":"2026-09-14T17:10:07.792085Z","end":"2026-09-14T17:10:07.968162Z","steps":["trace[1221967369] 'process raft request' (duration: 175.755177ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:10:07.968625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.51795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/machinesets.cluster.x-k8s.io\" ","response":"range_response_count:1 size:60938"} {"level":"warn","ts":"2026-09-14T17:10:07.968655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.251276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/machinedeployments.cluster.x-k8s.io\" ","response":"range_response_count:1 size:67907"} {"level":"warn","ts":"2026-09-14T17:10:07.968648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.208161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" ","response":"range_response_count:1 size:206"} {"level":"info","ts":"2026-09-14T17:10:07.968704Z","caller":"traceutil/trace.go:171","msg":"trace[786793639] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/machinesets.cluster.x-k8s.io; range_end:; response_count:1; response_revision:23942; }","duration":"174.876498ms","start":"2026-09-14T17:10:07.793793Z","end":"2026-09-14T17:10:07.96867Z","steps":["trace[786793639] 'agreement among raft nodes before linearized reading' (duration: 174.427999ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:10:07.968741Z","caller":"traceutil/trace.go:171","msg":"trace[75039288] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/machinedeployments.cluster.x-k8s.io; range_end:; response_count:1; response_revision:23942; }","duration":"146.381419ms","start":"2026-09-14T17:10:07.822344Z","end":"2026-09-14T17:10:07.968725Z","steps":["trace[75039288] 'agreement among raft nodes before linearized reading' (duration: 146.116134ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:10:07.96876Z","caller":"traceutil/trace.go:171","msg":"trace[1892045569] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/namespace-controller; range_end:; response_count:1; response_revision:23942; }","duration":"150.395244ms","start":"2026-09-14T17:10:07.818344Z","end":"2026-09-14T17:10:07.968739Z","steps":["trace[1892045569] 'agreement among raft nodes before linearized reading' (duration: 150.096388ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:11:42.792646Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21807} {"level":"info","ts":"2026-09-14T17:11:42.904692Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21807,"took":"108.249013ms","hash":2981436619,"current-db-size-bytes":57647104,"current-db-size":"58 MB","current-db-size-in-use-bytes":43958272,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-09-14T17:11:42.904762Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2981436619,"revision":21807,"compact-revision":19731} {"level":"info","ts":"2026-09-14T17:13:04.071387Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000067dd.wal"} {"level":"info","ts":"2026-09-14T17:13:40.3786Z","caller":"traceutil/trace.go:171","msg":"trace[147432853] transaction","detail":"{read_only:false; response_revision:26140; number_of_response:1; }","duration":"123.96373ms","start":"2026-09-14T17:13:40.25461Z","end":"2026-09-14T17:13:40.378574Z","steps":["trace[147432853] 'process raft request' (duration: 123.801067ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:13:40.384339Z","caller":"traceutil/trace.go:171","msg":"trace[2065872705] transaction","detail":"{read_only:false; response_revision:26141; number_of_response:1; }","duration":"109.423019ms","start":"2026-09-14T17:13:40.274865Z","end":"2026-09-14T17:13:40.384288Z","steps":["trace[2065872705] 'process raft request' (duration: 109.267056ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:13:45.788895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.523497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-09-14T17:13:45.788998Z","caller":"traceutil/trace.go:171","msg":"trace[1288708553] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26183; }","duration":"157.64858ms","start":"2026-09-14T17:13:45.631329Z","end":"2026-09-14T17:13:45.788978Z","steps":["trace[1288708553] 'range keys from in-memory index tree' (duration: 157.322263ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:13:45.898582Z","caller":"traceutil/trace.go:171","msg":"trace[836132018] transaction","detail":"{read_only:false; response_revision:26184; number_of_response:1; }","duration":"105.472344ms","start":"2026-09-14T17:13:45.793091Z","end":"2026-09-14T17:13:45.898564Z","steps":["trace[836132018] 'process raft request' (duration: 105.338811ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:13:46.158016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.677313ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-14T17:13:46.158182Z","caller":"traceutil/trace.go:171","msg":"trace[622937582] linearizableReadLoop","detail":"{readStateIndex:27052; appliedIndex:27049; }","duration":"360.769052ms","start":"2026-09-14T17:13:45.797394Z","end":"2026-09-14T17:13:46.158163Z","steps":["trace[622937582] 'read index received' (duration: 101.04661ms)","trace[622937582] 'applied index is now lower than readState.Index' (duration: 259.721522ms)"],"step_count":2} {"level":"info","ts":"2026-09-14T17:13:46.158243Z","caller":"traceutil/trace.go:171","msg":"trace[2080503151] transaction","detail":"{read_only:false; response_revision:26186; number_of_response:1; }","duration":"362.496348ms","start":"2026-09-14T17:13:45.795738Z","end":"2026-09-14T17:13:46.158234Z","steps":["trace[2080503151] 'process raft request' (duration: 362.369555ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:13:46.158299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-14T17:13:45.795716Z","time spent":"362.549699ms","remote":"127.0.0.1:53140","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-09-14T17:13:46.158421Z","caller":"traceutil/trace.go:171","msg":"trace[964483853] transaction","detail":"{read_only:false; response_revision:26185; number_of_response:1; }","duration":"362.932728ms","start":"2026-09-14T17:13:45.795461Z","end":"2026-09-14T17:13:46.158394Z","steps":["trace[964483853] 'process raft request' (duration: 217.799456ms)","trace[964483853] 'compare' (duration: 144.55887ms)"],"step_count":2} {"level":"warn","ts":"2026-09-14T17:13:46.158508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-14T17:13:45.795435Z","time spent":"363.02983ms","remote":"127.0.0.1:53140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-14T17:13:46.1587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.173231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11795"} {"level":"info","ts":"2026-09-14T17:13:46.158811Z","caller":"traceutil/trace.go:171","msg":"trace[305673436] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:26186; }","duration":"361.428676ms","start":"2026-09-14T17:13:45.797358Z","end":"2026-09-14T17:13:46.158787Z","steps":["trace[305673436] 'agreement among raft nodes before linearized reading' (duration: 360.872644ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:13:46.158876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-14T17:13:45.797346Z","time spent":"361.510737ms","remote":"127.0.0.1:53332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":11818,"request content":"key:\"/registry/deployments/openstack/glance-api\" "} {"level":"info","ts":"2026-09-14T17:13:46.200185Z","caller":"traceutil/trace.go:171","msg":"trace[1126965761] transaction","detail":"{read_only:false; response_revision:26187; number_of_response:1; }","duration":"250.773931ms","start":"2026-09-14T17:13:45.949377Z","end":"2026-09-14T17:13:46.200151Z","steps":["trace[1126965761] 'process raft request' (duration: 250.443144ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:13:50.603103Z","caller":"traceutil/trace.go:171","msg":"trace[1704064577] transaction","detail":"{read_only:false; response_revision:26224; number_of_response:1; }","duration":"116.44905ms","start":"2026-09-14T17:13:50.486626Z","end":"2026-09-14T17:13:50.603075Z","steps":["trace[1704064577] 'process raft request' (duration: 116.299417ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:13:50.943888Z","caller":"traceutil/trace.go:171","msg":"trace[1325029771] transaction","detail":"{read_only:false; response_revision:26225; number_of_response:1; }","duration":"454.529066ms","start":"2026-09-14T17:13:50.489326Z","end":"2026-09-14T17:13:50.943855Z","steps":["trace[1325029771] 'process raft request' (duration: 454.220269ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:13:50.944055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-14T17:13:50.489295Z","time spent":"454.677339ms","remote":"127.0.0.1:53140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-14T17:13:50.944149Z","caller":"traceutil/trace.go:171","msg":"trace[365698178] linearizableReadLoop","detail":"{readStateIndex:27093; appliedIndex:27091; }","duration":"234.033482ms","start":"2026-09-14T17:13:50.710077Z","end":"2026-09-14T17:13:50.944111Z","steps":["trace[365698178] 'read index received' (duration: 233.499421ms)","trace[365698178] 'applied index is now lower than readState.Index' (duration: 532.871µs)"],"step_count":2} {"level":"warn","ts":"2026-09-14T17:13:50.944335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.235096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-14T17:13:50.944393Z","caller":"traceutil/trace.go:171","msg":"trace[1241215055] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26226; }","duration":"234.344019ms","start":"2026-09-14T17:13:50.710036Z","end":"2026-09-14T17:13:50.94438Z","steps":["trace[1241215055] 'agreement among raft nodes before linearized reading' (duration: 234.195526ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:13:50.944474Z","caller":"traceutil/trace.go:171","msg":"trace[1147958068] transaction","detail":"{read_only:false; response_revision:26226; number_of_response:1; }","duration":"404.570198ms","start":"2026-09-14T17:13:50.539892Z","end":"2026-09-14T17:13:50.944462Z","steps":["trace[1147958068] 'process raft request' (duration: 403.902454ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:13:50.944539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-14T17:13:50.539866Z","time spent":"404.63715ms","remote":"127.0.0.1:53140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-14T17:13:50.959079Z","caller":"traceutil/trace.go:171","msg":"trace[899444298] transaction","detail":"{read_only:false; response_revision:26227; number_of_response:1; }","duration":"138.770227ms","start":"2026-09-14T17:13:50.820261Z","end":"2026-09-14T17:13:50.959031Z","steps":["trace[899444298] 'process raft request' (duration: 138.573773ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:13:51.369839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.407004ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-09-14T17:13:51.369957Z","caller":"traceutil/trace.go:171","msg":"trace[1936533496] linearizableReadLoop","detail":"{readStateIndex:27095; appliedIndex:27094; }","duration":"188.494658ms","start":"2026-09-14T17:13:51.181442Z","end":"2026-09-14T17:13:51.369937Z","steps":["trace[1936533496] 'read index received' (duration: 37.929151ms)","trace[1936533496] 'applied index is now lower than readState.Index' (duration: 150.563957ms)"],"step_count":2} {"level":"warn","ts":"2026-09-14T17:13:51.370263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.238914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-14T17:13:51.370403Z","caller":"traceutil/trace.go:171","msg":"trace[2098032326] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:26227; }","duration":"137.507761ms","start":"2026-09-14T17:13:51.232861Z","end":"2026-09-14T17:13:51.370369Z","steps":["trace[2098032326] 'agreement among raft nodes before linearized reading' (duration: 137.215993ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:13:51.370283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.819926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-14T17:13:51.370697Z","caller":"traceutil/trace.go:171","msg":"trace[689683246] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:26227; }","duration":"189.323027ms","start":"2026-09-14T17:13:51.181359Z","end":"2026-09-14T17:13:51.370682Z","steps":["trace[689683246] 'agreement among raft nodes before linearized reading' (duration: 188.832016ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:13:51.508348Z","caller":"traceutil/trace.go:171","msg":"trace[527074006] transaction","detail":"{read_only:false; response_revision:26228; number_of_response:1; }","duration":"133.699188ms","start":"2026-09-14T17:13:51.374623Z","end":"2026-09-14T17:13:51.508322Z","steps":["trace[527074006] 'process raft request' (duration: 133.533695ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:13:51.732723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.25873ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-14T17:13:51.732946Z","caller":"traceutil/trace.go:171","msg":"trace[826317420] transaction","detail":"{read_only:false; response_revision:26230; number_of_response:1; }","duration":"297.008109ms","start":"2026-09-14T17:13:51.435916Z","end":"2026-09-14T17:13:51.732924Z","steps":["trace[826317420] 'process raft request' (duration: 296.931678ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:13:51.732988Z","caller":"traceutil/trace.go:171","msg":"trace[997001894] transaction","detail":"{read_only:false; response_revision:26229; number_of_response:1; }","duration":"302.880224ms","start":"2026-09-14T17:13:51.430075Z","end":"2026-09-14T17:13:51.732955Z","steps":["trace[997001894] 'process raft request' (duration: 153.270896ms)","trace[997001894] 'compare' (duration: 149.069547ms)"],"step_count":2} {"level":"warn","ts":"2026-09-14T17:13:51.733136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-14T17:13:51.430039Z","time spent":"303.013687ms","remote":"127.0.0.1:53140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-14T17:13:52.091978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.465548ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-14T17:13:52.092073Z","caller":"traceutil/trace.go:171","msg":"trace[1812806642] linearizableReadLoop","detail":"{readStateIndex:27099; appliedIndex:27098; }","duration":"489.274499ms","start":"2026-09-14T17:13:51.602783Z","end":"2026-09-14T17:13:52.092057Z","steps":["trace[1812806642] 'read index received' (duration: 293.603486ms)","trace[1812806642] 'applied index is now lower than readState.Index' (duration: 195.669573ms)"],"step_count":2} {"level":"info","ts":"2026-09-14T17:13:52.092163Z","caller":"traceutil/trace.go:171","msg":"trace[569122571] transaction","detail":"{read_only:false; response_revision:26231; number_of_response:1; }","duration":"508.633292ms","start":"2026-09-14T17:13:51.583516Z","end":"2026-09-14T17:13:52.092149Z","steps":["trace[569122571] 'process raft request' (duration: 312.908088ms)","trace[569122571] 'compare' (duration: 195.274974ms)"],"step_count":2} {"level":"warn","ts":"2026-09-14T17:13:52.092214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-14T17:13:51.583486Z","time spent":"508.698733ms","remote":"127.0.0.1:53140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-14T17:13:52.092499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.742739ms","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-09-14T17:13:52.092536Z","caller":"traceutil/trace.go:171","msg":"trace[1129797386] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26231; }","duration":"489.896083ms","start":"2026-09-14T17:13:51.602627Z","end":"2026-09-14T17:13:52.092523Z","steps":["trace[1129797386] 'agreement among raft nodes before linearized reading' (duration: 489.76559ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:13:52.092563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-14T17:13:51.602606Z","time spent":"489.950603ms","remote":"127.0.0.1:53140","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-09-14T17:13:52.092621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.483995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-14T17:13:52.092735Z","caller":"traceutil/trace.go:171","msg":"trace[987603934] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26231; }","duration":"382.643739ms","start":"2026-09-14T17:13:51.710074Z","end":"2026-09-14T17:13:52.092718Z","steps":["trace[987603934] 'agreement among raft nodes before linearized reading' (duration: 382.480895ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:13:52.092776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-14T17:13:51.710054Z","time spent":"382.71379ms","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-14T17:13:52.093023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.918992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-09-14T17:13:52.093061Z","caller":"traceutil/trace.go:171","msg":"trace[140001835] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26231; }","duration":"170.020764ms","start":"2026-09-14T17:13:51.923028Z","end":"2026-09-14T17:13:52.093049Z","steps":["trace[140001835] 'agreement among raft nodes before linearized reading' (duration: 169.910251ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:13:52.559266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.845635ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-14T17:13:52.559358Z","caller":"traceutil/trace.go:171","msg":"trace[2034217037] linearizableReadLoop","detail":"{readStateIndex:27103; appliedIndex:27102; }","duration":"283.765885ms","start":"2026-09-14T17:13:52.275577Z","end":"2026-09-14T17:13:52.559343Z","steps":["trace[2034217037] 'read index received' (duration: 117.733487ms)","trace[2034217037] 'applied index is now lower than readState.Index' (duration: 166.031098ms)"],"step_count":2} {"level":"warn","ts":"2026-09-14T17:13:52.559506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.973343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-09-14T17:13:52.559538Z","caller":"traceutil/trace.go:171","msg":"trace[981662558] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26235; }","duration":"301.043294ms","start":"2026-09-14T17:13:52.258486Z","end":"2026-09-14T17:13:52.559529Z","steps":["trace[981662558] 'agreement among raft nodes before linearized reading' (duration: 300.897401ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:13:52.559567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-14T17:13:52.258467Z","time spent":"301.093245ms","remote":"127.0.0.1:53050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12236,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-09-14T17:13:52.559588Z","caller":"traceutil/trace.go:171","msg":"trace[1255538406] transaction","detail":"{read_only:false; response_revision:26235; number_of_response:1; }","duration":"319.335586ms","start":"2026-09-14T17:13:52.240213Z","end":"2026-09-14T17:13:52.559548Z","steps":["trace[1255538406] 'process raft request' (duration: 153.131914ms)","trace[1255538406] 'compare' (duration: 165.733242ms)"],"step_count":2} {"level":"warn","ts":"2026-09-14T17:13:52.559786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-14T17:13:52.240197Z","time spent":"319.496099ms","remote":"127.0.0.1:53140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-14T17:13:52.563862Z","caller":"traceutil/trace.go:171","msg":"trace[679244959] transaction","detail":"{read_only:false; response_revision:26236; number_of_response:1; }","duration":"284.068363ms","start":"2026-09-14T17:13:52.279769Z","end":"2026-09-14T17:13:52.563838Z","steps":["trace[679244959] 'process raft request' (duration: 283.679714ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:13:52.563962Z","caller":"traceutil/trace.go:171","msg":"trace[206780146] transaction","detail":"{read_only:false; response_revision:26237; number_of_response:1; }","duration":"220.32791ms","start":"2026-09-14T17:13:52.343617Z","end":"2026-09-14T17:13:52.563944Z","steps":["trace[206780146] 'process raft request' (duration: 219.955021ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:13:53.315421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.01494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-14T17:13:53.315515Z","caller":"traceutil/trace.go:171","msg":"trace[1483622682] 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:26240; }","duration":"123.164073ms","start":"2026-09-14T17:13:53.192328Z","end":"2026-09-14T17:13:53.315492Z","steps":["trace[1483622682] 'range keys from in-memory index tree' (duration: 122.907517ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:13:53.719698Z","caller":"traceutil/trace.go:171","msg":"trace[979593619] transaction","detail":"{read_only:false; response_revision:26242; number_of_response:1; }","duration":"157.20265ms","start":"2026-09-14T17:13:53.562467Z","end":"2026-09-14T17:13:53.71967Z","steps":["trace[979593619] 'process raft request' (duration: 156.878274ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:13:54.220252Z","caller":"traceutil/trace.go:171","msg":"trace[587152190] linearizableReadLoop","detail":"{readStateIndex:27113; appliedIndex:27112; }","duration":"112.809761ms","start":"2026-09-14T17:13:54.107417Z","end":"2026-09-14T17:13:54.220226Z","steps":["trace[587152190] 'read index received' (duration: 112.668038ms)","trace[587152190] 'applied index is now lower than readState.Index' (duration: 140.693µs)"],"step_count":2} {"level":"info","ts":"2026-09-14T17:13:54.22061Z","caller":"traceutil/trace.go:171","msg":"trace[254378242] transaction","detail":"{read_only:false; response_revision:26245; number_of_response:1; }","duration":"122.695393ms","start":"2026-09-14T17:13:54.097891Z","end":"2026-09-14T17:13:54.220586Z","steps":["trace[254378242] 'process raft request' (duration: 122.182542ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:13:54.220765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.326412ms","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-09-14T17:13:54.220827Z","caller":"traceutil/trace.go:171","msg":"trace[1126476777] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26245; }","duration":"113.438464ms","start":"2026-09-14T17:13:54.107371Z","end":"2026-09-14T17:13:54.22081Z","steps":["trace[1126476777] 'agreement among raft nodes before linearized reading' (duration: 113.24037ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:13:54.220806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.36928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-09-14T17:13:54.220904Z","caller":"traceutil/trace.go:171","msg":"trace[1449648417] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26245; }","duration":"103.525094ms","start":"2026-09-14T17:13:54.117358Z","end":"2026-09-14T17:13:54.220883Z","steps":["trace[1449648417] 'agreement among raft nodes before linearized reading' (duration: 103.220407ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:13:54.55985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.579611ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-14T17:13:54.559948Z","caller":"traceutil/trace.go:171","msg":"trace[64191552] linearizableReadLoop","detail":"{readStateIndex:27115; appliedIndex:27114; }","duration":"280.68044ms","start":"2026-09-14T17:13:54.279251Z","end":"2026-09-14T17:13:54.559931Z","steps":["trace[64191552] 'read index received' (duration: 98.912394ms)","trace[64191552] 'applied index is now lower than readState.Index' (duration: 181.766776ms)"],"step_count":2} {"level":"warn","ts":"2026-09-14T17:13:54.560097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.848933ms","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-09-14T17:13:54.560078Z","caller":"traceutil/trace.go:171","msg":"trace[1437658150] transaction","detail":"{read_only:false; response_revision:26247; number_of_response:1; }","duration":"334.125672ms","start":"2026-09-14T17:13:54.22591Z","end":"2026-09-14T17:13:54.560035Z","steps":["trace[1437658150] 'process raft request' (duration: 152.277495ms)","trace[1437658150] 'compare' (duration: 181.150712ms)"],"step_count":2} {"level":"info","ts":"2026-09-14T17:13:54.560131Z","caller":"traceutil/trace.go:171","msg":"trace[757350411] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26247; }","duration":"280.922695ms","start":"2026-09-14T17:13:54.279198Z","end":"2026-09-14T17:13:54.560121Z","steps":["trace[757350411] 'agreement among raft nodes before linearized reading' (duration: 280.789752ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:13:54.560245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-14T17:13:54.225883Z","time spent":"334.269455ms","remote":"127.0.0.1:53140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-14T17:13:54.903078Z","caller":"traceutil/trace.go:171","msg":"trace[857586541] transaction","detail":"{read_only:false; response_revision:26248; number_of_response:1; }","duration":"338.20083ms","start":"2026-09-14T17:13:54.564843Z","end":"2026-09-14T17:13:54.903044Z","steps":["trace[857586541] 'process raft request' (duration: 310.835395ms)","trace[857586541] 'compare' (duration: 26.569927ms)"],"step_count":2} {"level":"warn","ts":"2026-09-14T17:13:54.903227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-14T17:13:54.564819Z","time spent":"338.329163ms","remote":"127.0.0.1:53140","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-09-14T17:13:54.940027Z","caller":"traceutil/trace.go:171","msg":"trace[272121215] transaction","detail":"{read_only:false; response_revision:26249; number_of_response:1; }","duration":"375.108608ms","start":"2026-09-14T17:13:54.564891Z","end":"2026-09-14T17:13:54.94Z","steps":["trace[272121215] 'process raft request' (duration: 374.923915ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:13:54.940174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-14T17:13:54.564865Z","time spent":"375.229781ms","remote":"127.0.0.1:53140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-14T17:13:54.940245Z","caller":"traceutil/trace.go:171","msg":"trace[1627639411] linearizableReadLoop","detail":"{readStateIndex:27117; appliedIndex:27115; }","duration":"373.017693ms","start":"2026-09-14T17:13:54.567207Z","end":"2026-09-14T17:13:54.940224Z","steps":["trace[1627639411] 'read index received' (duration: 308.567806ms)","trace[1627639411] 'applied index is now lower than readState.Index' (duration: 64.448867ms)"],"step_count":2} {"level":"info","ts":"2026-09-14T17:13:54.940319Z","caller":"traceutil/trace.go:171","msg":"trace[300621710] transaction","detail":"{read_only:false; response_revision:26250; number_of_response:1; }","duration":"371.536673ms","start":"2026-09-14T17:13:54.568755Z","end":"2026-09-14T17:13:54.940292Z","steps":["trace[300621710] 'process raft request' (duration: 371.188735ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:13:54.940367Z","caller":"traceutil/trace.go:171","msg":"trace[1103193420] transaction","detail":"{read_only:false; response_revision:26251; number_of_response:1; }","duration":"230.881656ms","start":"2026-09-14T17:13:54.709472Z","end":"2026-09-14T17:13:54.940354Z","steps":["trace[1103193420] 'process raft request' (duration: 230.659261ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:13:54.940446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-14T17:13:54.56874Z","time spent":"371.635314ms","remote":"127.0.0.1:53140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-14T17:13:54.940537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.405871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-14T17:13:54.940579Z","caller":"traceutil/trace.go:171","msg":"trace[2001488339] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26251; }","duration":"235.486713ms","start":"2026-09-14T17:13:54.705079Z","end":"2026-09-14T17:13:54.940566Z","steps":["trace[2001488339] 'agreement among raft nodes before linearized reading' (duration: 235.411862ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:13:54.940689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.463245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"info","ts":"2026-09-14T17:13:54.94073Z","caller":"traceutil/trace.go:171","msg":"trace[253241076] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0; range_end:; response_count:1; response_revision:26251; }","duration":"171.537037ms","start":"2026-09-14T17:13:54.769179Z","end":"2026-09-14T17:13:54.940716Z","steps":["trace[253241076] 'agreement among raft nodes before linearized reading' (duration: 171.387783ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:13:54.940843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.443015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-14T17:13:54.940878Z","caller":"traceutil/trace.go:171","msg":"trace[1031801549] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26251; }","duration":"229.510967ms","start":"2026-09-14T17:13:54.711354Z","end":"2026-09-14T17:13:54.940865Z","steps":["trace[1031801549] 'agreement among raft nodes before linearized reading' (duration: 229.457716ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:13:54.940939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.731438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-14T17:13:54.940967Z","caller":"traceutil/trace.go:171","msg":"trace[2129709158] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26251; }","duration":"373.79545ms","start":"2026-09-14T17:13:54.567163Z","end":"2026-09-14T17:13:54.940959Z","steps":["trace[2129709158] 'agreement among raft nodes before linearized reading' (duration: 373.717488ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:13:54.940993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-14T17:13:54.567149Z","time spent":"373.838161ms","remote":"127.0.0.1:53140","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-09-14T17:13:55.175327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.534094ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-14T17:13:55.175419Z","caller":"traceutil/trace.go:171","msg":"trace[1702472129] linearizableReadLoop","detail":"{readStateIndex:27120; appliedIndex:27119; }","duration":"226.769887ms","start":"2026-09-14T17:13:54.948633Z","end":"2026-09-14T17:13:55.175403Z","steps":["trace[1702472129] 'read index received' (duration: 108.09613ms)","trace[1702472129] 'applied index is now lower than readState.Index' (duration: 118.672497ms)"],"step_count":2} {"level":"info","ts":"2026-09-14T17:13:55.175481Z","caller":"traceutil/trace.go:171","msg":"trace[1898503704] transaction","detail":"{read_only:false; response_revision:26252; number_of_response:1; }","duration":"229.856213ms","start":"2026-09-14T17:13:54.945603Z","end":"2026-09-14T17:13:55.175459Z","steps":["trace[1898503704] 'process raft request' (duration: 111.113305ms)","trace[1898503704] 'compare' (duration: 118.393741ms)"],"step_count":2} {"level":"warn","ts":"2026-09-14T17:13:55.175564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.927301ms","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-09-14T17:13:55.175598Z","caller":"traceutil/trace.go:171","msg":"trace[1319504987] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:26252; }","duration":"226.988552ms","start":"2026-09-14T17:13:54.948599Z","end":"2026-09-14T17:13:55.175588Z","steps":["trace[1319504987] 'agreement among raft nodes before linearized reading' (duration: 226.843499ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:13:55.176053Z","caller":"traceutil/trace.go:171","msg":"trace[849629963] transaction","detail":"{read_only:false; response_revision:26254; number_of_response:1; }","duration":"194.200821ms","start":"2026-09-14T17:13:54.981835Z","end":"2026-09-14T17:13:55.176036Z","steps":["trace[849629963] 'process raft request' (duration: 194.14852ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:13:55.176092Z","caller":"traceutil/trace.go:171","msg":"trace[785296980] transaction","detail":"{read_only:false; response_revision:26253; number_of_response:1; }","duration":"194.753852ms","start":"2026-09-14T17:13:54.981314Z","end":"2026-09-14T17:13:55.176068Z","steps":["trace[785296980] 'process raft request' (duration: 194.557448ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:14:07.710399Z","caller":"traceutil/trace.go:171","msg":"trace[1335362126] linearizableReadLoop","detail":"{readStateIndex:27276; appliedIndex:27275; }","duration":"162.391102ms","start":"2026-09-14T17:14:07.547975Z","end":"2026-09-14T17:14:07.710366Z","steps":["trace[1335362126] 'read index received' (duration: 72.48807ms)","trace[1335362126] 'applied index is now lower than readState.Index' (duration: 89.901872ms)"],"step_count":2} {"level":"info","ts":"2026-09-14T17:14:07.710455Z","caller":"traceutil/trace.go:171","msg":"trace[867862117] transaction","detail":"{read_only:false; response_revision:26405; number_of_response:1; }","duration":"194.017137ms","start":"2026-09-14T17:14:07.516409Z","end":"2026-09-14T17:14:07.710426Z","steps":["trace[867862117] 'process raft request' (duration: 104.187547ms)","trace[867862117] 'compare' (duration: 89.514233ms)"],"step_count":2} {"level":"warn","ts":"2026-09-14T17:14:07.710722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.734658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d9203102-1569-44dc-95f1-b6a2af50f44a\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-09-14T17:14:07.710779Z","caller":"traceutil/trace.go:171","msg":"trace[1232779543] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d9203102-1569-44dc-95f1-b6a2af50f44a; range_end:; response_count:1; response_revision:26405; }","duration":"162.858682ms","start":"2026-09-14T17:14:07.547906Z","end":"2026-09-14T17:14:07.710765Z","steps":["trace[1232779543] 'agreement among raft nodes before linearized reading' (duration: 162.633706ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:14:07.710949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.914742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-09-14T17:14:07.711122Z","caller":"traceutil/trace.go:171","msg":"trace[302391626] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26405; }","duration":"119.144127ms","start":"2026-09-14T17:14:07.591959Z","end":"2026-09-14T17:14:07.711103Z","steps":["trace[302391626] 'agreement among raft nodes before linearized reading' (duration: 118.82593ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:14:07.889692Z","caller":"traceutil/trace.go:171","msg":"trace[275167583] transaction","detail":"{read_only:false; response_revision:26406; number_of_response:1; }","duration":"174.668674ms","start":"2026-09-14T17:14:07.714952Z","end":"2026-09-14T17:14:07.889621Z","steps":["trace[275167583] 'process raft request' (duration: 105.401043ms)","trace[275167583] 'compare' (duration: 68.998525ms)"],"step_count":2} {"level":"warn","ts":"2026-09-14T17:14:08.247413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.470196ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-14T17:14:08.247662Z","caller":"traceutil/trace.go:171","msg":"trace[1349670269] linearizableReadLoop","detail":"{readStateIndex:27279; appliedIndex:27277; }","duration":"292.696607ms","start":"2026-09-14T17:14:07.954932Z","end":"2026-09-14T17:14:08.247629Z","steps":["trace[1349670269] 'read index received' (duration: 76.030075ms)","trace[1349670269] 'applied index is now lower than readState.Index' (duration: 216.665642ms)"],"step_count":2} {"level":"info","ts":"2026-09-14T17:14:08.247701Z","caller":"traceutil/trace.go:171","msg":"trace[1471650279] transaction","detail":"{read_only:false; response_revision:26407; number_of_response:1; }","duration":"450.373437ms","start":"2026-09-14T17:14:07.797223Z","end":"2026-09-14T17:14:08.247597Z","steps":["trace[1471650279] 'process raft request' (duration: 233.768827ms)","trace[1471650279] 'compare' (duration: 215.212271ms)"],"step_count":2} {"level":"warn","ts":"2026-09-14T17:14:08.247925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.453824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19234"} {"level":"info","ts":"2026-09-14T17:14:08.24796Z","caller":"traceutil/trace.go:171","msg":"trace[1097087720] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:26408; }","duration":"178.533866ms","start":"2026-09-14T17:14:08.069415Z","end":"2026-09-14T17:14:08.247949Z","steps":["trace[1097087720] 'agreement among raft nodes before linearized reading' (duration: 178.402013ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:14:08.247969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.024473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"warn","ts":"2026-09-14T17:14:08.248019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-14T17:14:07.797196Z","time spent":"450.704934ms","remote":"127.0.0.1:53140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-14T17:14:08.248081Z","caller":"traceutil/trace.go:171","msg":"trace[886243675] transaction","detail":"{read_only:false; response_revision:26408; number_of_response:1; }","duration":"437.473982ms","start":"2026-09-14T17:14:07.810594Z","end":"2026-09-14T17:14:08.248068Z","steps":["trace[886243675] 'process raft request' (duration: 436.92555ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:14:08.248155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-14T17:14:07.810575Z","time spent":"437.536553ms","remote":"127.0.0.1:53140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-14T17:14:08.248031Z","caller":"traceutil/trace.go:171","msg":"trace[315188921] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26408; }","duration":"293.128026ms","start":"2026-09-14T17:14:07.954886Z","end":"2026-09-14T17:14:08.248014Z","steps":["trace[315188921] 'agreement among raft nodes before linearized reading' (duration: 292.897201ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:14:08.546629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.841105ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-14T17:14:08.546895Z","caller":"traceutil/trace.go:171","msg":"trace[608588352] transaction","detail":"{read_only:false; response_revision:26410; number_of_response:1; }","duration":"129.263202ms","start":"2026-09-14T17:14:08.417613Z","end":"2026-09-14T17:14:08.546876Z","steps":["trace[608588352] 'process raft request' (duration: 129.14204ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:14:08.546891Z","caller":"traceutil/trace.go:171","msg":"trace[1679107118] linearizableReadLoop","detail":"{readStateIndex:27280; appliedIndex:27279; }","duration":"258.572997ms","start":"2026-09-14T17:14:08.288294Z","end":"2026-09-14T17:14:08.546867Z","steps":["trace[1679107118] 'read index received' (duration: 121.389085ms)","trace[1679107118] 'applied index is now lower than readState.Index' (duration: 137.181962ms)"],"step_count":2} {"level":"info","ts":"2026-09-14T17:14:08.547061Z","caller":"traceutil/trace.go:171","msg":"trace[335201027] transaction","detail":"{read_only:false; response_revision:26409; number_of_response:1; }","duration":"271.006114ms","start":"2026-09-14T17:14:08.276032Z","end":"2026-09-14T17:14:08.547038Z","steps":["trace[335201027] 'process raft request' (duration: 133.663878ms)","trace[335201027] 'compare' (duration: 136.61523ms)"],"step_count":2} {"level":"warn","ts":"2026-09-14T17:14:08.547405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.504957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-14T17:14:08.547377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.060897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d9203102-1569-44dc-95f1-b6a2af50f44a\" ","response":"range_response_count:1 size:3644"} {"level":"warn","ts":"2026-09-14T17:14:08.547442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.64761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-09-14T17:14:08.547424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.86378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-14T17:14:08.547469Z","caller":"traceutil/trace.go:171","msg":"trace[2079546785] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:26410; }","duration":"121.609099ms","start":"2026-09-14T17:14:08.425843Z","end":"2026-09-14T17:14:08.547452Z","steps":["trace[2079546785] 'agreement among raft nodes before linearized reading' (duration: 121.497197ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:14:08.547485Z","caller":"traceutil/trace.go:171","msg":"trace[1551399369] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d9203102-1569-44dc-95f1-b6a2af50f44a; range_end:; response_count:1; response_revision:26410; }","duration":"259.221241ms","start":"2026-09-14T17:14:08.288242Z","end":"2026-09-14T17:14:08.547463Z","steps":["trace[1551399369] 'agreement among raft nodes before linearized reading' (duration: 258.942265ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:14:08.547502Z","caller":"traceutil/trace.go:171","msg":"trace[559003121] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26410; }","duration":"230.742272ms","start":"2026-09-14T17:14:08.316741Z","end":"2026-09-14T17:14:08.547483Z","steps":["trace[559003121] 'agreement among raft nodes before linearized reading' (duration: 230.561038ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:14:08.547513Z","caller":"traceutil/trace.go:171","msg":"trace[479036623] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26410; }","duration":"140.006792ms","start":"2026-09-14T17:14:08.407488Z","end":"2026-09-14T17:14:08.547495Z","steps":["trace[479036623] 'agreement among raft nodes before linearized reading' (duration: 139.827349ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:14:08.547951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.73961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:32 size:277960"} {"level":"info","ts":"2026-09-14T17:14:08.54802Z","caller":"traceutil/trace.go:171","msg":"trace[518189805] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:32; response_revision:26410; }","duration":"171.846413ms","start":"2026-09-14T17:14:08.376155Z","end":"2026-09-14T17:14:08.548001Z","steps":["trace[518189805] 'agreement among raft nodes before linearized reading' (duration: 171.194019ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:14:08.912395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.123852ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-14T17:14:08.912516Z","caller":"traceutil/trace.go:171","msg":"trace[1206984752] linearizableReadLoop","detail":"{readStateIndex:27284; appliedIndex:27283; }","duration":"200.19156ms","start":"2026-09-14T17:14:08.712298Z","end":"2026-09-14T17:14:08.91249Z","steps":["trace[1206984752] 'read index received' (duration: 47.829663ms)","trace[1206984752] 'applied index is now lower than readState.Index' (duration: 152.359977ms)"],"step_count":2} {"level":"info","ts":"2026-09-14T17:14:08.912629Z","caller":"traceutil/trace.go:171","msg":"trace[2002236075] transaction","detail":"{read_only:false; response_revision:26413; number_of_response:1; }","duration":"271.680608ms","start":"2026-09-14T17:14:08.640931Z","end":"2026-09-14T17:14:08.912611Z","steps":["trace[2002236075] 'process raft request' (duration: 119.249479ms)","trace[2002236075] 'compare' (duration: 151.694752ms)"],"step_count":2} {"level":"warn","ts":"2026-09-14T17:14:08.913032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.797068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d9203102-1569-44dc-95f1-b6a2af50f44a\" ","response":"range_response_count:1 size:3761"} {"level":"info","ts":"2026-09-14T17:14:08.91309Z","caller":"traceutil/trace.go:171","msg":"trace[1834090874] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d9203102-1569-44dc-95f1-b6a2af50f44a; range_end:; response_count:1; response_revision:26413; }","duration":"197.877811ms","start":"2026-09-14T17:14:08.715197Z","end":"2026-09-14T17:14:08.913075Z","steps":["trace[1834090874] 'agreement among raft nodes before linearized reading' (duration: 197.668166ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:14:08.912973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.65575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-14T17:14:08.913153Z","caller":"traceutil/trace.go:171","msg":"trace[1738557630] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26413; }","duration":"200.878214ms","start":"2026-09-14T17:14:08.712256Z","end":"2026-09-14T17:14:08.913134Z","steps":["trace[1738557630] 'agreement among raft nodes before linearized reading' (duration: 200.611508ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:14:09.263217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.688437ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-14T17:14:09.263318Z","caller":"traceutil/trace.go:171","msg":"trace[1712014071] transaction","detail":"{read_only:false; response_revision:26414; number_of_response:1; }","duration":"295.870325ms","start":"2026-09-14T17:14:08.967424Z","end":"2026-09-14T17:14:09.263295Z","steps":["trace[1712014071] 'process raft request' (duration: 75.027244ms)","trace[1712014071] 'compare' (duration: 220.403031ms)"],"step_count":2} {"level":"info","ts":"2026-09-14T17:14:09.332559Z","caller":"traceutil/trace.go:171","msg":"trace[1829882045] transaction","detail":"{read_only:false; response_revision:26417; number_of_response:1; }","duration":"337.222348ms","start":"2026-09-14T17:14:08.995307Z","end":"2026-09-14T17:14:09.33253Z","steps":["trace[1829882045] 'process raft request' (duration: 337.165917ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:14:09.332976Z","caller":"traceutil/trace.go:171","msg":"trace[658823871] transaction","detail":"{read_only:false; response_revision:26415; number_of_response:1; }","duration":"339.323004ms","start":"2026-09-14T17:14:08.993637Z","end":"2026-09-14T17:14:09.33296Z","steps":["trace[658823871] 'process raft request' (duration: 338.622118ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:14:09.333064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-14T17:14:08.995289Z","time spent":"337.393882ms","remote":"127.0.0.1:53140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-14T17:14:09.333104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-14T17:14:08.99361Z","time spent":"339.410076ms","remote":"127.0.0.1:53140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-14T17:14:09.33325Z","caller":"traceutil/trace.go:171","msg":"trace[2103017925] transaction","detail":"{read_only:false; response_revision:26416; number_of_response:1; }","duration":"337.963065ms","start":"2026-09-14T17:14:08.995268Z","end":"2026-09-14T17:14:09.333231Z","steps":["trace[2103017925] 'process raft request' (duration: 337.152537ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:14:09.33333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-14T17:14:08.995256Z","time spent":"338.033166ms","remote":"127.0.0.1:53140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-14T17:14:09.564205Z","caller":"traceutil/trace.go:171","msg":"trace[1548388800] linearizableReadLoop","detail":"{readStateIndex:27289; appliedIndex:27288; }","duration":"336.302179ms","start":"2026-09-14T17:14:09.227877Z","end":"2026-09-14T17:14:09.564179Z","steps":["trace[1548388800] 'read index received' (duration: 105.469065ms)","trace[1548388800] 'applied index is now lower than readState.Index' (duration: 230.832034ms)"],"step_count":2} {"level":"info","ts":"2026-09-14T17:14:09.564241Z","caller":"traceutil/trace.go:171","msg":"trace[497964774] transaction","detail":"{read_only:false; response_revision:26418; number_of_response:1; }","duration":"338.385664ms","start":"2026-09-14T17:14:09.225839Z","end":"2026-09-14T17:14:09.564225Z","steps":["trace[497964774] 'process raft request' (duration: 239.02286ms)","trace[497964774] 'compare' (duration: 99.057527ms)"],"step_count":2} {"level":"info","ts":"2026-09-14T17:14:09.564276Z","caller":"traceutil/trace.go:171","msg":"trace[894586753] transaction","detail":"{read_only:false; response_revision:26419; number_of_response:1; }","duration":"336.3512ms","start":"2026-09-14T17:14:09.227887Z","end":"2026-09-14T17:14:09.564239Z","steps":["trace[894586753] 'process raft request' (duration: 336.216217ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:14:09.564407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.081821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-14T17:14:09.564409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-14T17:14:09.225785Z","time spent":"338.552457ms","remote":"127.0.0.1:53140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-14T17:14:09.564438Z","caller":"traceutil/trace.go:171","msg":"trace[1233046826] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:26419; }","duration":"189.113812ms","start":"2026-09-14T17:14:09.375316Z","end":"2026-09-14T17:14:09.564429Z","steps":["trace[1233046826] 'agreement among raft nodes before linearized reading' (duration: 189.069981ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:14:09.564471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.630298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-09-14T17:14:09.564471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-14T17:14:09.227855Z","time spent":"336.499663ms","remote":"127.0.0.1:53140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-14T17:14:09.564523Z","caller":"traceutil/trace.go:171","msg":"trace[1385356232] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26419; }","duration":"323.716489ms","start":"2026-09-14T17:14:09.240792Z","end":"2026-09-14T17:14:09.564508Z","steps":["trace[1385356232] 'agreement among raft nodes before linearized reading' (duration: 323.586386ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:14:09.564567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-14T17:14:09.240772Z","time spent":"323.785781ms","remote":"127.0.0.1:53140","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-09-14T17:14:09.564315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.438951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-14T17:14:09.564716Z","caller":"traceutil/trace.go:171","msg":"trace[1786853417] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:26419; }","duration":"336.8487ms","start":"2026-09-14T17:14:09.227854Z","end":"2026-09-14T17:14:09.564703Z","steps":["trace[1786853417] 'agreement among raft nodes before linearized reading' (duration: 336.416361ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:14:09.564749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-14T17:14:09.227797Z","time spent":"336.940862ms","remote":"127.0.0.1:44734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true "} {"level":"info","ts":"2026-09-14T17:14:09.830446Z","caller":"traceutil/trace.go:171","msg":"trace[1766351080] linearizableReadLoop","detail":"{readStateIndex:27291; appliedIndex:27290; }","duration":"174.177323ms","start":"2026-09-14T17:14:09.656228Z","end":"2026-09-14T17:14:09.830406Z","steps":["trace[1766351080] 'read index received' (duration: 103.833469ms)","trace[1766351080] 'applied index is now lower than readState.Index' (duration: 70.342554ms)"],"step_count":2} {"level":"warn","ts":"2026-09-14T17:14:09.830614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.353136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-14T17:14:09.830725Z","caller":"traceutil/trace.go:171","msg":"trace[1790753926] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:26420; }","duration":"174.50576ms","start":"2026-09-14T17:14:09.656187Z","end":"2026-09-14T17:14:09.830692Z","steps":["trace[1790753926] 'agreement among raft nodes before linearized reading' (duration: 174.352057ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:14:09.830781Z","caller":"traceutil/trace.go:171","msg":"trace[1169638941] transaction","detail":"{read_only:false; response_revision:26420; number_of_response:1; }","duration":"260.394587ms","start":"2026-09-14T17:14:09.570331Z","end":"2026-09-14T17:14:09.830726Z","steps":["trace[1169638941] 'process raft request' (duration: 189.717046ms)","trace[1169638941] 'compare' (duration: 70.203451ms)"],"step_count":2} {"level":"info","ts":"2026-09-14T17:14:10.006633Z","caller":"traceutil/trace.go:171","msg":"trace[794051184] transaction","detail":"{read_only:false; response_revision:26421; number_of_response:1; }","duration":"108.50735ms","start":"2026-09-14T17:14:09.898091Z","end":"2026-09-14T17:14:10.006599Z","steps":["trace[794051184] 'process raft request' (duration: 107.407677ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:14:10.470555Z","caller":"traceutil/trace.go:171","msg":"trace[641130929] transaction","detail":"{read_only:false; response_revision:26423; number_of_response:1; }","duration":"213.621887ms","start":"2026-09-14T17:14:10.256899Z","end":"2026-09-14T17:14:10.470521Z","steps":["trace[641130929] 'process raft request' (duration: 199.382133ms)","trace[641130929] 'compare' (duration: 14.04282ms)"],"step_count":2} {"level":"info","ts":"2026-09-14T17:14:10.876622Z","caller":"traceutil/trace.go:171","msg":"trace[600266045] linearizableReadLoop","detail":"{readStateIndex:27296; appliedIndex:27295; }","duration":"302.299981ms","start":"2026-09-14T17:14:10.574296Z","end":"2026-09-14T17:14:10.876596Z","steps":["trace[600266045] 'read index received' (duration: 302.146608ms)","trace[600266045] 'applied index is now lower than readState.Index' (duration: 152.283µs)"],"step_count":2} {"level":"info","ts":"2026-09-14T17:14:10.876703Z","caller":"traceutil/trace.go:171","msg":"trace[1375718387] transaction","detail":"{read_only:false; response_revision:26425; number_of_response:1; }","duration":"324.413015ms","start":"2026-09-14T17:14:10.552251Z","end":"2026-09-14T17:14:10.876664Z","steps":["trace[1375718387] 'process raft request' (duration: 324.18728ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:14:10.876894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.577688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-09-14T17:14:10.876893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-14T17:14:10.552218Z","time spent":"324.569428ms","remote":"127.0.0.1:53140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-14T17:14:10.876947Z","caller":"traceutil/trace.go:171","msg":"trace[2049906165] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26425; }","duration":"302.684149ms","start":"2026-09-14T17:14:10.574246Z","end":"2026-09-14T17:14:10.87693Z","steps":["trace[2049906165] 'agreement among raft nodes before linearized reading' (duration: 302.496895ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:14:10.876988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-14T17:14:10.574227Z","time spent":"302.751711ms","remote":"127.0.0.1:53140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-14T17:14:10.876933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.660211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-14T17:14:10.877095Z","caller":"traceutil/trace.go:171","msg":"trace[1079286921] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26425; }","duration":"165.876925ms","start":"2026-09-14T17:14:10.711194Z","end":"2026-09-14T17:14:10.877071Z","steps":["trace[1079286921] 'agreement among raft nodes before linearized reading' (duration: 165.62516ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:14:11.075872Z","caller":"traceutil/trace.go:171","msg":"trace[1119052504] transaction","detail":"{read_only:false; response_revision:26427; number_of_response:1; }","duration":"109.311197ms","start":"2026-09-14T17:14:10.966531Z","end":"2026-09-14T17:14:11.075842Z","steps":["trace[1119052504] 'process raft request' (duration: 109.160993ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:14:11.27975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.661576ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-14T17:14:11.280128Z","caller":"traceutil/trace.go:171","msg":"trace[179231024] transaction","detail":"{read_only:false; response_revision:26428; number_of_response:1; }","duration":"297.46716ms","start":"2026-09-14T17:14:10.982634Z","end":"2026-09-14T17:14:11.280101Z","steps":["trace[179231024] 'process raft request' (duration: 171.383095ms)","trace[179231024] 'compare' (duration: 125.531062ms)"],"step_count":2} {"level":"info","ts":"2026-09-14T17:14:11.280152Z","caller":"traceutil/trace.go:171","msg":"trace[1781582748] linearizableReadLoop","detail":"{readStateIndex:27299; appliedIndex:27297; }","duration":"268.649873ms","start":"2026-09-14T17:14:11.011474Z","end":"2026-09-14T17:14:11.280124Z","steps":["trace[1781582748] 'read index received' (duration: 64.194933ms)","trace[1781582748] 'applied index is now lower than readState.Index' (duration: 204.45183ms)"],"step_count":2} {"level":"warn","ts":"2026-09-14T17:14:11.369474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.836177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"info","ts":"2026-09-14T17:14:11.369598Z","caller":"traceutil/trace.go:171","msg":"trace[626943497] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:26428; }","duration":"108.99968ms","start":"2026-09-14T17:14:11.260547Z","end":"2026-09-14T17:14:11.369546Z","steps":["trace[626943497] 'agreement among raft nodes before linearized reading' (duration: 108.794865ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:14:11.369826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.310159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d9203102-1569-44dc-95f1-b6a2af50f44a\" ","response":"range_response_count:1 size:3761"} {"level":"warn","ts":"2026-09-14T17:14:11.369861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.387859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-09-14T17:14:11.369947Z","caller":"traceutil/trace.go:171","msg":"trace[1419660749] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d9203102-1569-44dc-95f1-b6a2af50f44a; range_end:; response_count:1; response_revision:26428; }","duration":"358.491224ms","start":"2026-09-14T17:14:11.011423Z","end":"2026-09-14T17:14:11.369914Z","steps":["trace[1419660749] 'agreement among raft nodes before linearized reading' (duration: 268.811847ms)","trace[1419660749] 'range keys from in-memory index tree' (duration: 89.3498ms)"],"step_count":2} {"level":"info","ts":"2026-09-14T17:14:11.369956Z","caller":"traceutil/trace.go:171","msg":"trace[2086721727] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26428; }","duration":"102.538832ms","start":"2026-09-14T17:14:11.267393Z","end":"2026-09-14T17:14:11.369932Z","steps":["trace[2086721727] 'agreement among raft nodes before linearized reading' (duration: 102.219715ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:14:11.370014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-14T17:14:11.011404Z","time spent":"358.593015ms","remote":"127.0.0.1:53050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3784,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d9203102-1569-44dc-95f1-b6a2af50f44a\" "} {"level":"warn","ts":"2026-09-14T17:14:11.548349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.115987ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-14T17:14:11.548504Z","caller":"traceutil/trace.go:171","msg":"trace[1851854680] transaction","detail":"{read_only:false; response_revision:26431; number_of_response:1; }","duration":"208.476196ms","start":"2026-09-14T17:14:11.340011Z","end":"2026-09-14T17:14:11.548487Z","steps":["trace[1851854680] 'process raft request' (duration: 208.419195ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:14:11.548839Z","caller":"traceutil/trace.go:171","msg":"trace[1893700888] transaction","detail":"{read_only:false; response_revision:26430; number_of_response:1; }","duration":"209.778474ms","start":"2026-09-14T17:14:11.339028Z","end":"2026-09-14T17:14:11.548806Z","steps":["trace[1893700888] 'process raft request' (duration: 103.110004ms)","trace[1893700888] 'compare' (duration: 106.005955ms)"],"step_count":2} {"level":"info","ts":"2026-09-14T17:14:11.561841Z","caller":"traceutil/trace.go:171","msg":"trace[1612643790] linearizableReadLoop","detail":"{readStateIndex:27304; appliedIndex:27301; }","duration":"171.852053ms","start":"2026-09-14T17:14:11.389965Z","end":"2026-09-14T17:14:11.561817Z","steps":["trace[1612643790] 'read index received' (duration: 52.186126ms)","trace[1612643790] 'applied index is now lower than readState.Index' (duration: 119.664977ms)"],"step_count":2} {"level":"info","ts":"2026-09-14T17:14:11.562108Z","caller":"traceutil/trace.go:171","msg":"trace[2055999562] transaction","detail":"{read_only:false; response_revision:26432; number_of_response:1; }","duration":"186.489216ms","start":"2026-09-14T17:14:11.375412Z","end":"2026-09-14T17:14:11.561901Z","steps":["trace[2055999562] 'process raft request' (duration: 186.264001ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:14:11.56217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.18131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-09-14T17:14:11.562375Z","caller":"traceutil/trace.go:171","msg":"trace[263697312] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:26432; }","duration":"172.436236ms","start":"2026-09-14T17:14:11.389922Z","end":"2026-09-14T17:14:11.562358Z","steps":["trace[263697312] 'agreement among raft nodes before linearized reading' (duration: 172.097089ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:14:11.562736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.676601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-14T17:14:11.562776Z","caller":"traceutil/trace.go:171","msg":"trace[1545720873] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:26432; }","duration":"121.722542ms","start":"2026-09-14T17:14:11.441043Z","end":"2026-09-14T17:14:11.562766Z","steps":["trace[1545720873] 'agreement among raft nodes before linearized reading' (duration: 121.248221ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:14:11.764147Z","caller":"traceutil/trace.go:171","msg":"trace[1567365029] transaction","detail":"{read_only:false; response_revision:26435; number_of_response:1; }","duration":"194.590579ms","start":"2026-09-14T17:14:11.569531Z","end":"2026-09-14T17:14:11.764122Z","steps":["trace[1567365029] 'process raft request' (duration: 194.550439ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:14:11.764252Z","caller":"traceutil/trace.go:171","msg":"trace[443824008] transaction","detail":"{read_only:false; response_revision:26434; number_of_response:1; }","duration":"194.784074ms","start":"2026-09-14T17:14:11.569448Z","end":"2026-09-14T17:14:11.764232Z","steps":["trace[443824008] 'process raft request' (duration: 194.582289ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:14:11.764394Z","caller":"traceutil/trace.go:171","msg":"trace[1368058931] transaction","detail":"{read_only:false; response_revision:26433; number_of_response:1; }","duration":"197.203996ms","start":"2026-09-14T17:14:11.567127Z","end":"2026-09-14T17:14:11.764331Z","steps":["trace[1368058931] 'process raft request' (duration: 142.235891ms)","trace[1368058931] 'compare' (duration: 54.510904ms)"],"step_count":2} {"level":"info","ts":"2026-09-14T17:14:13.688952Z","caller":"traceutil/trace.go:171","msg":"trace[937715239] linearizableReadLoop","detail":"{readStateIndex:27318; appliedIndex:27316; }","duration":"122.56463ms","start":"2026-09-14T17:14:13.566364Z","end":"2026-09-14T17:14:13.688928Z","steps":["trace[937715239] 'read index received' (duration: 66.706296ms)","trace[937715239] 'applied index is now lower than readState.Index' (duration: 55.857024ms)"],"step_count":2} {"level":"info","ts":"2026-09-14T17:14:13.689034Z","caller":"traceutil/trace.go:171","msg":"trace[149659803] transaction","detail":"{read_only:false; response_revision:26446; number_of_response:1; }","duration":"135.097519ms","start":"2026-09-14T17:14:13.55388Z","end":"2026-09-14T17:14:13.688977Z","steps":["trace[149659803] 'process raft request' (duration: 134.908114ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:14:13.689186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.796795ms","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-09-14T17:14:13.689251Z","caller":"traceutil/trace.go:171","msg":"trace[1939181640] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26446; }","duration":"122.908517ms","start":"2026-09-14T17:14:13.566328Z","end":"2026-09-14T17:14:13.689236Z","steps":["trace[1939181640] 'agreement among raft nodes before linearized reading' (duration: 122.717723ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:14:17.772007Z","caller":"traceutil/trace.go:171","msg":"trace[531961391] transaction","detail":"{read_only:false; response_revision:26497; number_of_response:1; }","duration":"129.873306ms","start":"2026-09-14T17:14:17.642107Z","end":"2026-09-14T17:14:17.771981Z","steps":["trace[531961391] 'process raft request' (duration: 129.723033ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:14:17.904078Z","caller":"traceutil/trace.go:171","msg":"trace[189249084] linearizableReadLoop","detail":"{readStateIndex:27372; appliedIndex:27371; }","duration":"131.357168ms","start":"2026-09-14T17:14:17.772692Z","end":"2026-09-14T17:14:17.904049Z","steps":["trace[189249084] 'read index received' (duration: 127.120918ms)","trace[189249084] 'applied index is now lower than readState.Index' (duration: 4.23529ms)"],"step_count":2} {"level":"info","ts":"2026-09-14T17:14:17.904235Z","caller":"traceutil/trace.go:171","msg":"trace[557367252] transaction","detail":"{read_only:false; response_revision:26498; number_of_response:1; }","duration":"201.348984ms","start":"2026-09-14T17:14:17.702856Z","end":"2026-09-14T17:14:17.904205Z","steps":["trace[557367252] 'process raft request' (duration: 196.878209ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:14:17.904443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.734696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-09-14T17:14:17.904501Z","caller":"traceutil/trace.go:171","msg":"trace[431219417] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26498; }","duration":"131.838328ms","start":"2026-09-14T17:14:17.772634Z","end":"2026-09-14T17:14:17.904472Z","steps":["trace[431219417] 'agreement among raft nodes before linearized reading' (duration: 131.697505ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:14:17.980422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.353893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-14T17:14:17.980426Z","caller":"traceutil/trace.go:171","msg":"trace[467600570] transaction","detail":"{read_only:false; response_revision:26500; number_of_response:1; }","duration":"188.900277ms","start":"2026-09-14T17:14:17.791482Z","end":"2026-09-14T17:14:17.980382Z","steps":["trace[467600570] 'process raft request' (duration: 188.797455ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:14:17.980506Z","caller":"traceutil/trace.go:171","msg":"trace[1393632612] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:26500; }","duration":"121.496607ms","start":"2026-09-14T17:14:17.858988Z","end":"2026-09-14T17:14:17.980484Z","steps":["trace[1393632612] 'agreement among raft nodes before linearized reading' (duration: 121.338133ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:14:17.980559Z","caller":"traceutil/trace.go:171","msg":"trace[1916739839] transaction","detail":"{read_only:false; response_revision:26499; number_of_response:1; }","duration":"197.89784ms","start":"2026-09-14T17:14:17.782622Z","end":"2026-09-14T17:14:17.98052Z","steps":["trace[1916739839] 'process raft request' (duration: 197.513051ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:14:17.980668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.427066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-14T17:14:17.98076Z","caller":"traceutil/trace.go:171","msg":"trace[1365504374] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26500; }","duration":"114.58936ms","start":"2026-09-14T17:14:17.86615Z","end":"2026-09-14T17:14:17.980739Z","steps":["trace[1365504374] 'agreement among raft nodes before linearized reading' (duration: 114.279592ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:14:18.194845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.99424ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-14T17:14:18.195132Z","caller":"traceutil/trace.go:171","msg":"trace[1155230771] linearizableReadLoop","detail":"{readStateIndex:27377; appliedIndex:27374; }","duration":"160.513901ms","start":"2026-09-14T17:14:18.034598Z","end":"2026-09-14T17:14:18.195112Z","steps":["trace[1155230771] 'read index received' (duration: 14.183783ms)","trace[1155230771] 'applied index is now lower than readState.Index' (duration: 146.329198ms)"],"step_count":2} {"level":"info","ts":"2026-09-14T17:14:18.195241Z","caller":"traceutil/trace.go:171","msg":"trace[241634969] transaction","detail":"{read_only:false; response_revision:26502; number_of_response:1; }","duration":"219.91683ms","start":"2026-09-14T17:14:17.975307Z","end":"2026-09-14T17:14:18.195224Z","steps":["trace[241634969] 'process raft request' (duration: 219.651345ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:14:18.195226Z","caller":"traceutil/trace.go:171","msg":"trace[291514893] transaction","detail":"{read_only:false; response_revision:26501; number_of_response:1; }","duration":"286.191378ms","start":"2026-09-14T17:14:17.909Z","end":"2026-09-14T17:14:18.195192Z","steps":["trace[291514893] 'process raft request' (duration: 139.759478ms)","trace[291514893] 'compare' (duration: 145.769176ms)"],"step_count":2} {"level":"info","ts":"2026-09-14T17:14:18.195522Z","caller":"traceutil/trace.go:171","msg":"trace[865119089] transaction","detail":"{read_only:false; response_revision:26503; number_of_response:1; }","duration":"210.566591ms","start":"2026-09-14T17:14:17.984927Z","end":"2026-09-14T17:14:18.195494Z","steps":["trace[865119089] 'process raft request' (duration: 210.134192ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:14:18.195703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.090303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-09-14T17:14:18.195798Z","caller":"traceutil/trace.go:171","msg":"trace[1751455623] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26503; }","duration":"161.223396ms","start":"2026-09-14T17:14:18.034552Z","end":"2026-09-14T17:14:18.195776Z","steps":["trace[1751455623] 'agreement among raft nodes before linearized reading' (duration: 160.93709ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:14:18.324499Z","caller":"traceutil/trace.go:171","msg":"trace[650468503] linearizableReadLoop","detail":"{readStateIndex:27378; appliedIndex:27377; }","duration":"103.446841ms","start":"2026-09-14T17:14:18.221025Z","end":"2026-09-14T17:14:18.324472Z","steps":["trace[650468503] 'read index received' (duration: 100.255363ms)","trace[650468503] 'applied index is now lower than readState.Index' (duration: 3.190128ms)"],"step_count":2} {"level":"info","ts":"2026-09-14T17:14:18.324619Z","caller":"traceutil/trace.go:171","msg":"trace[1058917708] transaction","detail":"{read_only:false; response_revision:26504; number_of_response:1; }","duration":"124.231305ms","start":"2026-09-14T17:14:18.200356Z","end":"2026-09-14T17:14:18.324587Z","steps":["trace[1058917708] 'process raft request' (duration: 120.846673ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:14:18.324744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.695207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-14T17:14:18.324828Z","caller":"traceutil/trace.go:171","msg":"trace[2007180320] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26504; }","duration":"103.826359ms","start":"2026-09-14T17:14:18.220982Z","end":"2026-09-14T17:14:18.324808Z","steps":["trace[2007180320] 'agreement among raft nodes before linearized reading' (duration: 103.635775ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:14:30.649953Z","caller":"traceutil/trace.go:171","msg":"trace[2078323167] transaction","detail":"{read_only:false; response_revision:26601; number_of_response:1; }","duration":"150.268463ms","start":"2026-09-14T17:14:30.499657Z","end":"2026-09-14T17:14:30.649926Z","steps":["trace[2078323167] 'process raft request' (duration: 150.11542ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:14:30.911346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.475581ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-14T17:14:30.911463Z","caller":"traceutil/trace.go:171","msg":"trace[1315305183] linearizableReadLoop","detail":"{readStateIndex:27478; appliedIndex:27477; }","duration":"251.385703ms","start":"2026-09-14T17:14:30.660056Z","end":"2026-09-14T17:14:30.911441Z","steps":["trace[1315305183] 'read index received' (duration: 46.663526ms)","trace[1315305183] 'applied index is now lower than readState.Index' (duration: 204.720837ms)"],"step_count":2} {"level":"warn","ts":"2026-09-14T17:14:30.911605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.874623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-14T17:14:30.911607Z","caller":"traceutil/trace.go:171","msg":"trace[273078616] transaction","detail":"{read_only:false; response_revision:26602; number_of_response:1; }","duration":"366.140597ms","start":"2026-09-14T17:14:30.545436Z","end":"2026-09-14T17:14:30.911576Z","steps":["trace[273078616] 'process raft request' (duration: 161.344709ms)","trace[273078616] 'compare' (duration: 204.048852ms)"],"step_count":2} {"level":"info","ts":"2026-09-14T17:14:30.911667Z","caller":"traceutil/trace.go:171","msg":"trace[663068841] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26602; }","duration":"200.944225ms","start":"2026-09-14T17:14:30.710687Z","end":"2026-09-14T17:14:30.911631Z","steps":["trace[663068841] 'agreement among raft nodes before linearized reading' (duration: 200.848743ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:14:30.911756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-14T17:14:30.545414Z","time spent":"366.28343ms","remote":"127.0.0.1:53140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-14T17:14:30.911735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.526756ms","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":"warn","ts":"2026-09-14T17:14:30.911776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.7194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:19234"} {"level":"info","ts":"2026-09-14T17:14:30.91184Z","caller":"traceutil/trace.go:171","msg":"trace[297380622] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:26602; }","duration":"177.69178ms","start":"2026-09-14T17:14:30.734122Z","end":"2026-09-14T17:14:30.911814Z","steps":["trace[297380622] 'agreement among raft nodes before linearized reading' (duration: 177.465194ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:14:30.911862Z","caller":"traceutil/trace.go:171","msg":"trace[635299142] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:26602; }","duration":"251.845723ms","start":"2026-09-14T17:14:30.659998Z","end":"2026-09-14T17:14:30.911844Z","steps":["trace[635299142] 'agreement among raft nodes before linearized reading' (duration: 251.529666ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:14:31.122511Z","caller":"traceutil/trace.go:171","msg":"trace[357726000] transaction","detail":"{read_only:false; response_revision:26603; number_of_response:1; }","duration":"172.663131ms","start":"2026-09-14T17:14:30.949812Z","end":"2026-09-14T17:14:31.122475Z","steps":["trace[357726000] 'process raft request' (duration: 127.499966ms)","trace[357726000] 'compare' (duration: 44.703066ms)"],"step_count":2} {"level":"warn","ts":"2026-09-14T17:14:31.37625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.158095ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-14T17:14:31.376357Z","caller":"traceutil/trace.go:171","msg":"trace[1194665539] transaction","detail":"{read_only:false; response_revision:26604; number_of_response:1; }","duration":"271.428642ms","start":"2026-09-14T17:14:31.104908Z","end":"2026-09-14T17:14:31.376337Z","steps":["trace[1194665539] 'process raft request' (duration: 124.096613ms)","trace[1194665539] 'compare' (duration: 146.939361ms)"],"step_count":2} {"level":"info","ts":"2026-09-14T17:14:31.470436Z","caller":"traceutil/trace.go:171","msg":"trace[1147107521] transaction","detail":"{read_only:false; response_revision:26605; number_of_response:1; }","duration":"343.453942ms","start":"2026-09-14T17:14:31.126948Z","end":"2026-09-14T17:14:31.470402Z","steps":["trace[1147107521] 'process raft request' (duration: 343.027363ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:14:31.470593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-14T17:14:31.126922Z","time spent":"343.593225ms","remote":"127.0.0.1:53140","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-09-14T17:14:31.571776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.453449ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-09-14T17:14:31.571995Z","caller":"traceutil/trace.go:171","msg":"trace[407338624] linearizableReadLoop","detail":"{readStateIndex:27482; appliedIndex:27480; }","duration":"201.526018ms","start":"2026-09-14T17:14:31.370446Z","end":"2026-09-14T17:14:31.571972Z","steps":["trace[407338624] 'read index received' (duration: 99.511918ms)","trace[407338624] 'applied index is now lower than readState.Index' (duration: 102.01303ms)"],"step_count":2} {"level":"warn","ts":"2026-09-14T17:14:31.572325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.859075ms","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-09-14T17:14:31.572358Z","caller":"traceutil/trace.go:171","msg":"trace[611673156] transaction","detail":"{read_only:false; response_revision:26606; number_of_response:1; }","duration":"140.105725ms","start":"2026-09-14T17:14:31.43224Z","end":"2026-09-14T17:14:31.572346Z","steps":["trace[611673156] 'process raft request' (duration: 139.632705ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:14:31.572406Z","caller":"traceutil/trace.go:171","msg":"trace[665307376] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26605; }","duration":"201.980957ms","start":"2026-09-14T17:14:31.370401Z","end":"2026-09-14T17:14:31.572382Z","steps":["trace[665307376] 'agreement among raft nodes before linearized reading' (duration: 201.778463ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:14:31.798667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.125169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"info","ts":"2026-09-14T17:14:31.798746Z","caller":"traceutil/trace.go:171","msg":"trace[549916513] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:26606; }","duration":"390.357693ms","start":"2026-09-14T17:14:31.408368Z","end":"2026-09-14T17:14:31.798726Z","steps":["trace[549916513] 'agreement among raft nodes before linearized reading' (duration: 163.959954ms)","trace[549916513] 'range keys from in-memory index tree' (duration: 226.188825ms)"],"step_count":2} {"level":"warn","ts":"2026-09-14T17:14:31.798783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-14T17:14:31.408349Z","time spent":"390.425165ms","remote":"127.0.0.1:33974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":3332,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-09-14T17:14:31.799295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.194202ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-14T17:14:31.799376Z","caller":"traceutil/trace.go:171","msg":"trace[1715981151] transaction","detail":"{read_only:false; response_revision:26607; number_of_response:1; }","duration":"222.822803ms","start":"2026-09-14T17:14:31.576538Z","end":"2026-09-14T17:14:31.799361Z","steps":["trace[1715981151] 'process raft request' (duration: 114.495298ms)","trace[1715981151] 'compare' (duration: 107.981207ms)"],"step_count":2} {"level":"warn","ts":"2026-09-14T17:14:32.090259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.939955ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-14T17:14:32.090504Z","caller":"traceutil/trace.go:171","msg":"trace[248609951] transaction","detail":"{read_only:false; response_revision:26608; number_of_response:1; }","duration":"265.273069ms","start":"2026-09-14T17:14:31.825193Z","end":"2026-09-14T17:14:32.090466Z","steps":["trace[248609951] 'process raft request' (duration: 115.027728ms)","trace[248609951] 'compare' (duration: 149.668429ms)"],"step_count":2} {"level":"info","ts":"2026-09-14T17:14:32.090542Z","caller":"traceutil/trace.go:171","msg":"trace[1651320471] linearizableReadLoop","detail":"{readStateIndex:27485; appliedIndex:27484; }","duration":"174.232914ms","start":"2026-09-14T17:14:31.91611Z","end":"2026-09-14T17:14:32.090343Z","steps":["trace[1651320471] 'read index received' (duration: 24.008083ms)","trace[1651320471] 'applied index is now lower than readState.Index' (duration: 150.223491ms)"],"step_count":2} {"level":"warn","ts":"2026-09-14T17:14:32.0908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.650343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-14T17:14:32.090846Z","caller":"traceutil/trace.go:171","msg":"trace[1955080742] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:26608; }","duration":"174.776695ms","start":"2026-09-14T17:14:31.916055Z","end":"2026-09-14T17:14:32.090832Z","steps":["trace[1955080742] 'agreement among raft nodes before linearized reading' (duration: 174.51937ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:14:32.145014Z","caller":"traceutil/trace.go:171","msg":"trace[1448720386] transaction","detail":"{read_only:false; response_revision:26609; number_of_response:1; }","duration":"186.412036ms","start":"2026-09-14T17:14:31.95858Z","end":"2026-09-14T17:14:32.144992Z","steps":["trace[1448720386] 'process raft request' (duration: 186.270823ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:14:32.320946Z","caller":"traceutil/trace.go:171","msg":"trace[768851197] linearizableReadLoop","detail":"{readStateIndex:27488; appliedIndex:27485; }","duration":"230.236903ms","start":"2026-09-14T17:14:32.090687Z","end":"2026-09-14T17:14:32.320924Z","steps":["trace[768851197] 'read index received' (duration: 54.22708ms)","trace[768851197] 'applied index is now lower than readState.Index' (duration: 176.008832ms)"],"step_count":2} {"level":"info","ts":"2026-09-14T17:14:32.321063Z","caller":"traceutil/trace.go:171","msg":"trace[561072811] transaction","detail":"{read_only:false; response_revision:26611; number_of_response:1; }","duration":"284.152065ms","start":"2026-09-14T17:14:32.036879Z","end":"2026-09-14T17:14:32.321031Z","steps":["trace[561072811] 'process raft request' (duration: 283.955571ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:14:32.32117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.365079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-14T17:14:32.321209Z","caller":"traceutil/trace.go:171","msg":"trace[1587232559] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:26611; }","duration":"320.473551ms","start":"2026-09-14T17:14:32.000726Z","end":"2026-09-14T17:14:32.321199Z","steps":["trace[1587232559] 'agreement among raft nodes before linearized reading' (duration: 320.323208ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:14:32.321179Z","caller":"traceutil/trace.go:171","msg":"trace[1545893098] transaction","detail":"{read_only:false; response_revision:26610; number_of_response:1; }","duration":"296.017118ms","start":"2026-09-14T17:14:32.025123Z","end":"2026-09-14T17:14:32.32114Z","steps":["trace[1545893098] 'process raft request' (duration: 199.77333ms)","trace[1545893098] 'compare' (duration: 95.633514ms)"],"step_count":2} {"level":"warn","ts":"2026-09-14T17:14:32.321242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-14T17:14:32.000709Z","time spent":"320.523022ms","remote":"127.0.0.1:57276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":31,"request content":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true "} {"level":"warn","ts":"2026-09-14T17:14:32.321245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.602967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-14T17:14:32.321539Z","caller":"traceutil/trace.go:171","msg":"trace[781569854] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:26611; }","duration":"366.947374ms","start":"2026-09-14T17:14:31.954572Z","end":"2026-09-14T17:14:32.321519Z","steps":["trace[781569854] 'agreement among raft nodes before linearized reading' (duration: 366.579566ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:14:32.321687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-14T17:14:31.954552Z","time spent":"367.119977ms","remote":"127.0.0.1:52932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"info","ts":"2026-09-14T17:14:37.278067Z","caller":"traceutil/trace.go:171","msg":"trace[728080594] transaction","detail":"{read_only:false; response_revision:26656; number_of_response:1; }","duration":"255.167015ms","start":"2026-09-14T17:14:37.022833Z","end":"2026-09-14T17:14:37.278Z","steps":["trace[728080594] 'process raft request' (duration: 185.455215ms)","trace[728080594] 'compare' (duration: 68.836411ms)"],"step_count":2} {"level":"info","ts":"2026-09-14T17:14:37.278222Z","caller":"traceutil/trace.go:171","msg":"trace[1654344742] transaction","detail":"{read_only:false; response_revision:26657; number_of_response:1; }","duration":"134.629688ms","start":"2026-09-14T17:14:37.143567Z","end":"2026-09-14T17:14:37.278197Z","steps":["trace[1654344742] 'process raft request' (duration: 134.488565ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:14:37.495852Z","caller":"traceutil/trace.go:171","msg":"trace[676059373] linearizableReadLoop","detail":"{readStateIndex:27537; appliedIndex:27536; }","duration":"114.955447ms","start":"2026-09-14T17:14:37.380868Z","end":"2026-09-14T17:14:37.495824Z","steps":["trace[676059373] 'read index received' (duration: 103.38651ms)","trace[676059373] 'applied index is now lower than readState.Index' (duration: 11.568047ms)"],"step_count":2} {"level":"info","ts":"2026-09-14T17:14:37.495874Z","caller":"traceutil/trace.go:171","msg":"trace[255244662] transaction","detail":"{read_only:false; response_revision:26658; number_of_response:1; }","duration":"208.031207ms","start":"2026-09-14T17:14:37.287807Z","end":"2026-09-14T17:14:37.495838Z","steps":["trace[255244662] 'process raft request' (duration: 196.567912ms)","trace[255244662] 'compare' (duration: 11.330922ms)"],"step_count":2} {"level":"warn","ts":"2026-09-14T17:14:37.496037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.125641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-14T17:14:37.49614Z","caller":"traceutil/trace.go:171","msg":"trace[782050216] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26658; }","duration":"115.295944ms","start":"2026-09-14T17:14:37.38082Z","end":"2026-09-14T17:14:37.496116Z","steps":["trace[782050216] 'agreement among raft nodes before linearized reading' (duration: 115.09576ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:14:41.771211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.266361ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-14T17:14:41.771419Z","caller":"traceutil/trace.go:171","msg":"trace[584022263] transaction","detail":"{read_only:false; response_revision:26689; number_of_response:1; }","duration":"150.61145ms","start":"2026-09-14T17:14:41.620771Z","end":"2026-09-14T17:14:41.771383Z","steps":["trace[584022263] 'process raft request' (duration: 15.080263ms)","trace[584022263] 'compare' (duration: 135.163369ms)"],"step_count":2} {"level":"info","ts":"2026-09-14T17:14:44.474136Z","caller":"traceutil/trace.go:171","msg":"trace[723126062] transaction","detail":"{read_only:false; response_revision:26708; number_of_response:1; }","duration":"114.797945ms","start":"2026-09-14T17:14:44.359314Z","end":"2026-09-14T17:14:44.474112Z","steps":["trace[723126062] 'process raft request' (duration: 114.607181ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:14:44.51918Z","caller":"traceutil/trace.go:171","msg":"trace[1105374425] linearizableReadLoop","detail":"{readStateIndex:27589; appliedIndex:27587; }","duration":"154.944892ms","start":"2026-09-14T17:14:44.364211Z","end":"2026-09-14T17:14:44.519156Z","steps":["trace[1105374425] 'read index received' (duration: 109.707475ms)","trace[1105374425] 'applied index is now lower than readState.Index' (duration: 45.236397ms)"],"step_count":2} {"level":"info","ts":"2026-09-14T17:14:44.519279Z","caller":"traceutil/trace.go:171","msg":"trace[394626080] transaction","detail":"{read_only:false; response_revision:26709; number_of_response:1; }","duration":"155.224957ms","start":"2026-09-14T17:14:44.364042Z","end":"2026-09-14T17:14:44.519267Z","steps":["trace[394626080] 'process raft request' (duration: 154.986412ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:14:44.519589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.35665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-14T17:14:44.519701Z","caller":"traceutil/trace.go:171","msg":"trace[1929186771] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:26709; }","duration":"155.524774ms","start":"2026-09-14T17:14:44.364153Z","end":"2026-09-14T17:14:44.519678Z","steps":["trace[1929186771] 'agreement among raft nodes before linearized reading' (duration: 155.128566ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:14:44.655313Z","caller":"traceutil/trace.go:171","msg":"trace[987263413] transaction","detail":"{read_only:false; response_revision:26710; number_of_response:1; }","duration":"147.931002ms","start":"2026-09-14T17:14:44.507351Z","end":"2026-09-14T17:14:44.655282Z","steps":["trace[987263413] 'process raft request' (duration: 102.907959ms)","trace[987263413] 'compare' (duration: 44.832258ms)"],"step_count":2} {"level":"warn","ts":"2026-09-14T17:14:44.655535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.162462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"warn","ts":"2026-09-14T17:14:44.655525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.667974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-09-14T17:14:44.655586Z","caller":"traceutil/trace.go:171","msg":"trace[1763905685] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26710; }","duration":"253.262894ms","start":"2026-09-14T17:14:44.40231Z","end":"2026-09-14T17:14:44.655573Z","steps":["trace[1763905685] 'agreement among raft nodes before linearized reading' (duration: 253.05863ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:14:44.655602Z","caller":"traceutil/trace.go:171","msg":"trace[897391759] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26710; }","duration":"218.783357ms","start":"2026-09-14T17:14:44.436798Z","end":"2026-09-14T17:14:44.655582Z","steps":["trace[897391759] 'agreement among raft nodes before linearized reading' (duration: 218.596193ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:14:44.655881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.963997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-14T17:14:44.655974Z","caller":"traceutil/trace.go:171","msg":"trace[1702396976] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26710; }","duration":"275.09157ms","start":"2026-09-14T17:14:44.380865Z","end":"2026-09-14T17:14:44.655957Z","steps":["trace[1702396976] 'agreement among raft nodes before linearized reading' (duration: 274.818205ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:14:44.655965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.622207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-09-14T17:14:44.656057Z","caller":"traceutil/trace.go:171","msg":"trace[1414366861] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26710; }","duration":"279.77187ms","start":"2026-09-14T17:14:44.376266Z","end":"2026-09-14T17:14:44.656037Z","steps":["trace[1414366861] 'agreement among raft nodes before linearized reading' (duration: 279.497824ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:14:44.655316Z","caller":"traceutil/trace.go:171","msg":"trace[1933184737] linearizableReadLoop","detail":"{readStateIndex:27590; appliedIndex:27589; }","duration":"136.058219ms","start":"2026-09-14T17:14:44.519241Z","end":"2026-09-14T17:14:44.6553Z","steps":["trace[1933184737] 'read index received' (duration: 91.032025ms)","trace[1933184737] 'applied index is now lower than readState.Index' (duration: 45.025154ms)"],"step_count":2} {"level":"info","ts":"2026-09-14T17:15:00.004888Z","caller":"traceutil/trace.go:171","msg":"trace[1660208471] transaction","detail":"{read_only:false; response_revision:26822; number_of_response:1; }","duration":"129.325848ms","start":"2026-09-14T17:14:59.875515Z","end":"2026-09-14T17:15:00.00484Z","steps":["trace[1660208471] 'process raft request' (duration: 54.545537ms)","trace[1660208471] 'compare' (duration: 74.2547ms)"],"step_count":2} {"level":"info","ts":"2026-09-14T17:15:00.217579Z","caller":"traceutil/trace.go:171","msg":"trace[1675168435] linearizableReadLoop","detail":"{readStateIndex:27706; appliedIndex:27705; }","duration":"123.583075ms","start":"2026-09-14T17:15:00.093933Z","end":"2026-09-14T17:15:00.217516Z","steps":["trace[1675168435] 'read index received' (duration: 91.930427ms)","trace[1675168435] 'applied index is now lower than readState.Index' (duration: 31.650428ms)"],"step_count":2} {"level":"info","ts":"2026-09-14T17:15:00.217685Z","caller":"traceutil/trace.go:171","msg":"trace[958504949] transaction","detail":"{read_only:false; response_revision:26823; number_of_response:1; }","duration":"208.368371ms","start":"2026-09-14T17:15:00.009245Z","end":"2026-09-14T17:15:00.217613Z","steps":["trace[958504949] 'process raft request' (duration: 176.58938ms)","trace[958504949] 'compare' (duration: 31.24996ms)"],"step_count":2} {"level":"warn","ts":"2026-09-14T17:15:00.218038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.082326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-09-14T17:15:00.218175Z","caller":"traceutil/trace.go:171","msg":"trace[1828644200] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26823; }","duration":"124.294541ms","start":"2026-09-14T17:15:00.09386Z","end":"2026-09-14T17:15:00.218155Z","steps":["trace[1828644200] 'agreement among raft nodes before linearized reading' (duration: 123.885652ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:15:00.363261Z","caller":"traceutil/trace.go:171","msg":"trace[1254986118] transaction","detail":"{read_only:false; response_revision:26826; number_of_response:1; }","duration":"120.002379ms","start":"2026-09-14T17:15:00.243231Z","end":"2026-09-14T17:15:00.363234Z","steps":["trace[1254986118] 'process raft request' (duration: 119.947658ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:15:00.363432Z","caller":"traceutil/trace.go:171","msg":"trace[532037677] transaction","detail":"{read_only:false; response_revision:26825; number_of_response:1; }","duration":"124.110466ms","start":"2026-09-14T17:15:00.239291Z","end":"2026-09-14T17:15:00.363401Z","steps":["trace[532037677] 'process raft request' (duration: 123.81968ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:15:00.363547Z","caller":"traceutil/trace.go:171","msg":"trace[1414988976] linearizableReadLoop","detail":"{readStateIndex:27707; appliedIndex:27706; }","duration":"140.60064ms","start":"2026-09-14T17:15:00.222926Z","end":"2026-09-14T17:15:00.363526Z","steps":["trace[1414988976] 'read index received' (duration: 115.730677ms)","trace[1414988976] 'applied index is now lower than readState.Index' (duration: 24.868493ms)"],"step_count":2} {"level":"warn","ts":"2026-09-14T17:15:00.363845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.833338ms","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":"warn","ts":"2026-09-14T17:15:00.363857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.930216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-09-14T17:15:00.363909Z","caller":"traceutil/trace.go:171","msg":"trace[1939159385] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26826; }","duration":"141.076119ms","start":"2026-09-14T17:15:00.222821Z","end":"2026-09-14T17:15:00.363897Z","steps":["trace[1939159385] 'agreement among raft nodes before linearized reading' (duration: 140.899956ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:15:00.363209Z","caller":"traceutil/trace.go:171","msg":"trace[623679542] transaction","detail":"{read_only:false; response_revision:26824; number_of_response:1; }","duration":"256.961921ms","start":"2026-09-14T17:15:00.106216Z","end":"2026-09-14T17:15:00.363178Z","steps":["trace[623679542] 'process raft request' (duration: 232.399935ms)","trace[623679542] 'compare' (duration: 24.27946ms)"],"step_count":2} {"level":"warn","ts":"2026-09-14T17:15:00.363971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.80974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-14T17:15:00.364024Z","caller":"traceutil/trace.go:171","msg":"trace[1894308498] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26826; }","duration":"109.924473ms","start":"2026-09-14T17:15:00.254085Z","end":"2026-09-14T17:15:00.36401Z","steps":["trace[1894308498] 'agreement among raft nodes before linearized reading' (duration: 109.78551ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:15:00.363915Z","caller":"traceutil/trace.go:171","msg":"trace[1948727026] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:26826; }","duration":"113.952009ms","start":"2026-09-14T17:15:00.249941Z","end":"2026-09-14T17:15:00.363893Z","steps":["trace[1948727026] 'agreement among raft nodes before linearized reading' (duration: 113.822597ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:15:03.786357Z","caller":"traceutil/trace.go:171","msg":"trace[58687697] transaction","detail":"{read_only:false; response_revision:26864; number_of_response:1; }","duration":"106.340446ms","start":"2026-09-14T17:15:03.679993Z","end":"2026-09-14T17:15:03.786333Z","steps":["trace[58687697] 'process raft request' (duration: 105.946108ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:15:04.506178Z","caller":"traceutil/trace.go:171","msg":"trace[1576694004] transaction","detail":"{read_only:false; response_revision:26867; number_of_response:1; }","duration":"130.81275ms","start":"2026-09-14T17:15:04.375334Z","end":"2026-09-14T17:15:04.506147Z","steps":["trace[1576694004] 'process raft request' (duration: 118.413754ms)","trace[1576694004] 'compare' (duration: 12.003838ms)"],"step_count":2} {"level":"warn","ts":"2026-09-14T17:15:04.75412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.90149ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-14T17:15:04.754401Z","caller":"traceutil/trace.go:171","msg":"trace[532643408] transaction","detail":"{read_only:false; response_revision:26869; number_of_response:1; }","duration":"213.479619ms","start":"2026-09-14T17:15:04.540896Z","end":"2026-09-14T17:15:04.754376Z","steps":["trace[532643408] 'process raft request' (duration: 213.331056ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:15:04.754397Z","caller":"traceutil/trace.go:171","msg":"trace[1766987329] transaction","detail":"{read_only:false; response_revision:26868; number_of_response:1; }","duration":"378.945742ms","start":"2026-09-14T17:15:04.375406Z","end":"2026-09-14T17:15:04.754352Z","steps":["trace[1766987329] 'process raft request' (duration: 263.720025ms)","trace[1766987329] 'compare' (duration: 114.747347ms)"],"step_count":2} {"level":"info","ts":"2026-09-14T17:15:04.754409Z","caller":"traceutil/trace.go:171","msg":"trace[1418451079] transaction","detail":"{read_only:false; response_revision:26870; number_of_response:1; }","duration":"172.504153ms","start":"2026-09-14T17:15:04.581878Z","end":"2026-09-14T17:15:04.754382Z","steps":["trace[1418451079] 'process raft request' (duration: 172.40365ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:15:04.754561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-14T17:15:04.375372Z","time spent":"379.100905ms","remote":"127.0.0.1:53140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-14T17:15:04.827938Z","caller":"traceutil/trace.go:171","msg":"trace[344070242] transaction","detail":"{read_only:false; response_revision:26871; number_of_response:1; }","duration":"102.2733ms","start":"2026-09-14T17:15:04.725629Z","end":"2026-09-14T17:15:04.827903Z","steps":["trace[344070242] 'process raft request' (duration: 102.076425ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:15:05.045512Z","caller":"traceutil/trace.go:171","msg":"trace[1417477907] transaction","detail":"{read_only:false; response_revision:26873; number_of_response:1; }","duration":"212.980599ms","start":"2026-09-14T17:15:04.832505Z","end":"2026-09-14T17:15:05.045486Z","steps":["trace[1417477907] 'process raft request' (duration: 212.883797ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:15:05.045709Z","caller":"traceutil/trace.go:171","msg":"trace[707376228] transaction","detail":"{read_only:false; response_revision:26872; number_of_response:1; }","duration":"213.162593ms","start":"2026-09-14T17:15:04.832506Z","end":"2026-09-14T17:15:05.045668Z","steps":["trace[707376228] 'process raft request' (duration: 135.863219ms)","trace[707376228] 'compare' (duration: 76.846605ms)"],"step_count":2} {"level":"info","ts":"2026-09-14T17:15:05.055333Z","caller":"traceutil/trace.go:171","msg":"trace[1650342808] transaction","detail":"{read_only:false; response_revision:26874; number_of_response:1; }","duration":"222.627476ms","start":"2026-09-14T17:15:04.832686Z","end":"2026-09-14T17:15:05.055314Z","steps":["trace[1650342808] 'process raft request' (duration: 222.439292ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:15:05.13132Z","caller":"traceutil/trace.go:171","msg":"trace[1862635047] transaction","detail":"{read_only:false; response_revision:26875; number_of_response:1; }","duration":"158.838571ms","start":"2026-09-14T17:15:04.972451Z","end":"2026-09-14T17:15:05.13129Z","steps":["trace[1862635047] 'process raft request' (duration: 158.713698ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:15:05.255208Z","caller":"traceutil/trace.go:171","msg":"trace[314528041] transaction","detail":"{read_only:false; response_revision:26876; number_of_response:1; }","duration":"142.640633ms","start":"2026-09-14T17:15:05.112541Z","end":"2026-09-14T17:15:05.255182Z","steps":["trace[314528041] 'process raft request' (duration: 96.556307ms)","trace[314528041] 'compare' (duration: 45.977374ms)"],"step_count":2} {"level":"info","ts":"2026-09-14T17:15:06.059146Z","caller":"traceutil/trace.go:171","msg":"trace[752254762] transaction","detail":"{read_only:false; response_revision:26879; number_of_response:1; }","duration":"121.744076ms","start":"2026-09-14T17:15:05.937376Z","end":"2026-09-14T17:15:06.05912Z","steps":["trace[752254762] 'process raft request' (duration: 121.596543ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:15:06.935152Z","caller":"traceutil/trace.go:171","msg":"trace[670631536] transaction","detail":"{read_only:false; response_revision:26884; number_of_response:1; }","duration":"175.448087ms","start":"2026-09-14T17:15:06.759675Z","end":"2026-09-14T17:15:06.935123Z","steps":["trace[670631536] 'process raft request' (duration: 175.307524ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:15:07.055797Z","caller":"traceutil/trace.go:171","msg":"trace[942068270] transaction","detail":"{read_only:false; response_revision:26885; number_of_response:1; }","duration":"296.057298ms","start":"2026-09-14T17:15:06.759719Z","end":"2026-09-14T17:15:07.055776Z","steps":["trace[942068270] 'process raft request' (duration: 217.566208ms)","trace[942068270] 'compare' (duration: 78.034131ms)"],"step_count":2} {"level":"info","ts":"2026-09-14T17:15:07.056069Z","caller":"traceutil/trace.go:171","msg":"trace[282792720] transaction","detail":"{read_only:false; response_revision:26887; number_of_response:1; }","duration":"223.174318ms","start":"2026-09-14T17:15:06.832865Z","end":"2026-09-14T17:15:07.056039Z","steps":["trace[282792720] 'process raft request' (duration: 222.916472ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:15:07.056086Z","caller":"traceutil/trace.go:171","msg":"trace[938978102] linearizableReadLoop","detail":"{readStateIndex:27771; appliedIndex:27768; }","duration":"251.33851ms","start":"2026-09-14T17:15:06.804487Z","end":"2026-09-14T17:15:07.055826Z","steps":["trace[938978102] 'read index received' (duration: 130.438712ms)","trace[938978102] 'applied index is now lower than readState.Index' (duration: 120.898838ms)"],"step_count":2} {"level":"warn","ts":"2026-09-14T17:15:07.056226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.734189ms","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-09-14T17:15:07.056279Z","caller":"traceutil/trace.go:171","msg":"trace[1011025967] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:26887; }","duration":"251.834512ms","start":"2026-09-14T17:15:06.804431Z","end":"2026-09-14T17:15:07.056266Z","steps":["trace[1011025967] 'agreement among raft nodes before linearized reading' (duration: 251.701959ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:15:07.055946Z","caller":"traceutil/trace.go:171","msg":"trace[1784556509] transaction","detail":"{read_only:false; response_revision:26886; number_of_response:1; }","duration":"295.388754ms","start":"2026-09-14T17:15:06.760521Z","end":"2026-09-14T17:15:07.05591Z","steps":["trace[1784556509] 'process raft request' (duration: 295.1706ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:15:07.246662Z","caller":"traceutil/trace.go:171","msg":"trace[1556910022] linearizableReadLoop","detail":"{readStateIndex:27775; appliedIndex:27773; }","duration":"138.065915ms","start":"2026-09-14T17:15:07.108563Z","end":"2026-09-14T17:15:07.246629Z","steps":["trace[1556910022] 'read index received' (duration: 137.899401ms)","trace[1556910022] 'applied index is now lower than readState.Index' (duration: 165.654µs)"],"step_count":2} {"level":"info","ts":"2026-09-14T17:15:07.246729Z","caller":"traceutil/trace.go:171","msg":"trace[1722461526] transaction","detail":"{read_only:false; response_revision:26889; number_of_response:1; }","duration":"186.886861ms","start":"2026-09-14T17:15:07.059802Z","end":"2026-09-14T17:15:07.246689Z","steps":["trace[1722461526] 'process raft request' (duration: 186.679577ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:15:07.24694Z","caller":"traceutil/trace.go:171","msg":"trace[1868689371] transaction","detail":"{read_only:false; response_revision:26890; number_of_response:1; }","duration":"185.9598ms","start":"2026-09-14T17:15:07.060969Z","end":"2026-09-14T17:15:07.246928Z","steps":["trace[1868689371] 'process raft request' (duration: 185.626433ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:15:07.248296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.717401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:419274"} {"level":"info","ts":"2026-09-14T17:15:07.248337Z","caller":"traceutil/trace.go:171","msg":"trace[617882461] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:26890; }","duration":"139.829053ms","start":"2026-09-14T17:15:07.108494Z","end":"2026-09-14T17:15:07.248324Z","steps":["trace[617882461] 'agreement among raft nodes before linearized reading' (duration: 138.233089ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:15:08.121335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.530473ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-14T17:15:08.121542Z","caller":"traceutil/trace.go:171","msg":"trace[1838280206] transaction","detail":"{read_only:false; response_revision:26894; number_of_response:1; }","duration":"252.707769ms","start":"2026-09-14T17:15:07.8688Z","end":"2026-09-14T17:15:08.121508Z","steps":["trace[1838280206] 'process raft request' (duration: 51.870491ms)","trace[1838280206] 'compare' (duration: 199.932419ms)"],"step_count":2} {"level":"info","ts":"2026-09-14T17:15:12.781574Z","caller":"traceutil/trace.go:171","msg":"trace[288740560] linearizableReadLoop","detail":"{readStateIndex:27819; appliedIndex:27818; }","duration":"142.903369ms","start":"2026-09-14T17:15:12.638623Z","end":"2026-09-14T17:15:12.781526Z","steps":["trace[288740560] 'read index received' (duration: 142.734255ms)","trace[288740560] 'applied index is now lower than readState.Index' (duration: 167.774µs)"],"step_count":2} {"level":"info","ts":"2026-09-14T17:15:12.781699Z","caller":"traceutil/trace.go:171","msg":"trace[1779400992] transaction","detail":"{read_only:false; response_revision:26933; number_of_response:1; }","duration":"167.257011ms","start":"2026-09-14T17:15:12.614401Z","end":"2026-09-14T17:15:12.781658Z","steps":["trace[1779400992] 'process raft request' (duration: 166.977365ms)"],"step_count":1} {"level":"warn","ts":"2026-09-14T17:15:12.781854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.210726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-14T17:15:12.781959Z","caller":"traceutil/trace.go:171","msg":"trace[883824218] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:26933; }","duration":"143.323458ms","start":"2026-09-14T17:15:12.638606Z","end":"2026-09-14T17:15:12.78193Z","steps":["trace[883824218] 'agreement among raft nodes before linearized reading' (duration: 143.086253ms)"],"step_count":1} {"level":"info","ts":"2026-09-14T17:16:42.799659Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25021} {"level":"info","ts":"2026-09-14T17:16:42.951969Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25021,"took":"147.855735ms","hash":533554410,"current-db-size-bytes":59756544,"current-db-size":"60 MB","current-db-size-in-use-bytes":36687872,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-09-14T17:16:42.952039Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":533554410,"revision":25021,"compact-revision":21807} {"level":"info","ts":"2026-09-14T17:18:50.117766Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"777352cb93f4c05d","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-14T17:18:50.12519Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-09-14T17:18:50.125448Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-09-14T17:21:42.806762Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27851} {"level":"info","ts":"2026-09-14T17:21:42.951478Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27851,"took":"140.217962ms","hash":2600197846,"current-db-size-bytes":59756544,"current-db-size":"60 MB","current-db-size-in-use-bytes":34930688,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-09-14T17:21:42.951554Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2600197846,"revision":27851,"compact-revision":25021} {"level":"info","ts":"2026-09-14T17:26:42.813742Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30494} {"level":"info","ts":"2026-09-14T17:26:42.969296Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30494,"took":"150.568194ms","hash":2228162447,"current-db-size-bytes":59756544,"current-db-size":"60 MB","current-db-size-in-use-bytes":31907840,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-14T17:26:42.969351Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2228162447,"revision":30494,"compact-revision":27851} {"level":"info","ts":"2026-09-14T17:31:42.821336Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32745} {"level":"info","ts":"2026-09-14T17:31:42.993031Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32745,"took":"167.368934ms","hash":741012985,"current-db-size-bytes":60796928,"current-db-size":"61 MB","current-db-size-in-use-bytes":31514624,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-14T17:31:42.9931Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":741012985,"revision":32745,"compact-revision":30494} {"level":"info","ts":"2026-09-14T17:36:42.828738Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34974} {"level":"info","ts":"2026-09-14T17:36:42.968835Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34974,"took":"136.010522ms","hash":585707357,"current-db-size-bytes":60796928,"current-db-size":"61 MB","current-db-size-in-use-bytes":32632832,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-09-14T17:36:42.968906Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":585707357,"revision":34974,"compact-revision":32745} {"level":"info","ts":"2026-09-14T17:40:02.904776Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"777352cb93f4c05d","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-14T17:40:02.910064Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-09-14T17:40:02.910176Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-09-14T17:41:42.835618Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37270} {"level":"info","ts":"2026-09-14T17:41:42.972857Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37270,"took":"132.611589ms","hash":2732778916,"current-db-size-bytes":60796928,"current-db-size":"61 MB","current-db-size-in-use-bytes":33558528,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-09-14T17:41:42.972929Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2732778916,"revision":37270,"compact-revision":34974} {"level":"info","ts":"2026-09-14T17:46:42.845613Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39548} {"level":"info","ts":"2026-09-14T17:46:42.985582Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39548,"took":"135.069311ms","hash":3933294541,"current-db-size-bytes":61648896,"current-db-size":"62 MB","current-db-size-in-use-bytes":32043008,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-14T17:46:42.985642Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3933294541,"revision":39548,"compact-revision":37270}