{"level":"warn","ts":"2026-09-19T10:31:49.058036Z","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-19T10:31:49.058136Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.156: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.156:2380","--initial-cluster=instance=https://162.253.55.156:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.156:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.156: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-19T10:31:49.058211Z","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-19T10:31:49.058223Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.156:2380"]} {"level":"info","ts":"2026-09-19T10:31:49.058247Z","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-19T10:31:49.059137Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.156:2379"]} {"level":"info","ts":"2026-09-19T10:31:49.059238Z","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.156:2380"],"listen-peer-urls":["https://162.253.55.156:2380"],"advertise-client-urls":["https://162.253.55.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.156: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-19T10:31:49.064434Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.929467ms"} {"level":"info","ts":"2026-09-19T10:31:49.071766Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"40fb1eca294b6a5a","cluster-id":"17c6999805fcc0eb"} {"level":"info","ts":"2026-09-19T10:31:49.071932Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a switched to configuration voters=()"} {"level":"info","ts":"2026-09-19T10:31:49.071989Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a became follower at term 0"} {"level":"info","ts":"2026-09-19T10:31:49.07201Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 40fb1eca294b6a5a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-19T10:31:49.072022Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a became follower at term 1"} {"level":"info","ts":"2026-09-19T10:31:49.072073Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a switched to configuration voters=(4682370091206797914)"} {"level":"warn","ts":"2026-09-19T10:31:49.087237Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-19T10:31:49.089973Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-19T10:31:49.092017Z","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-19T10:31:49.096173Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"40fb1eca294b6a5a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-19T10:31:49.096604Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"40fb1eca294b6a5a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-19T10:31:49.096779Z","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-19T10:31:49.096951Z","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-19T10:31:49.096981Z","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-19T10:31:49.103186Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a switched to configuration voters=(4682370091206797914)"} {"level":"info","ts":"2026-09-19T10:31:49.103433Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"17c6999805fcc0eb","local-member-id":"40fb1eca294b6a5a","added-peer-id":"40fb1eca294b6a5a","added-peer-peer-urls":["https://162.253.55.156:2380"]} {"level":"info","ts":"2026-09-19T10:31:49.1051Z","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-19T10:31:49.105433Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"40fb1eca294b6a5a","initial-advertise-peer-urls":["https://162.253.55.156:2380"],"listen-peer-urls":["https://162.253.55.156:2380"],"advertise-client-urls":["https://162.253.55.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-19T10:31:49.105489Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-19T10:31:49.106382Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.156:2380"} {"level":"info","ts":"2026-09-19T10:31:49.10643Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.156:2380"} {"level":"info","ts":"2026-09-19T10:31:49.472369Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a is starting a new election at term 1"} {"level":"info","ts":"2026-09-19T10:31:49.472458Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a became pre-candidate at term 1"} {"level":"info","ts":"2026-09-19T10:31:49.472514Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a received MsgPreVoteResp from 40fb1eca294b6a5a at term 1"} {"level":"info","ts":"2026-09-19T10:31:49.472537Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a became candidate at term 2"} {"level":"info","ts":"2026-09-19T10:31:49.472549Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a received MsgVoteResp from 40fb1eca294b6a5a at term 2"} {"level":"info","ts":"2026-09-19T10:31:49.472631Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a became leader at term 2"} {"level":"info","ts":"2026-09-19T10:31:49.472657Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 40fb1eca294b6a5a elected leader 40fb1eca294b6a5a at term 2"} {"level":"info","ts":"2026-09-19T10:31:49.474787Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"40fb1eca294b6a5a","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.156:2379]}","request-path":"/0/members/40fb1eca294b6a5a/attributes","cluster-id":"17c6999805fcc0eb","publish-timeout":"7s"} {"level":"info","ts":"2026-09-19T10:31:49.475414Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T10:31:49.475386Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-19T10:31:49.475732Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-19T10:31:49.475251Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-19T10:31:49.476111Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-19T10:31:49.477248Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"17c6999805fcc0eb","local-member-id":"40fb1eca294b6a5a","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T10:31:49.477578Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T10:31:49.47769Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T10:31:49.481503Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.156:2379"} {"level":"info","ts":"2026-09-19T10:31:49.484469Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-19T10:32:46.596824Z","caller":"traceutil/trace.go:171","msg":"trace[461453872] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"124.401363ms","start":"2026-09-19T10:32:46.472394Z","end":"2026-09-19T10:32:46.596795Z","steps":["trace[461453872] 'process raft request' (duration: 122.809086ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:32:46.81784Z","caller":"traceutil/trace.go:171","msg":"trace[744734826] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"144.684287ms","start":"2026-09-19T10:32:46.673101Z","end":"2026-09-19T10:32:46.817785Z","steps":["trace[744734826] 'process raft request' (duration: 117.092947ms)","trace[744734826] 'compare' (duration: 27.308134ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:32:47.280384Z","caller":"traceutil/trace.go:171","msg":"trace[1389624197] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"104.088258ms","start":"2026-09-19T10:32:47.176272Z","end":"2026-09-19T10:32:47.280361Z","steps":["trace[1389624197] 'process raft request' (duration: 103.954806ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:32:47.309629Z","caller":"traceutil/trace.go:171","msg":"trace[984905830] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"132.898687ms","start":"2026-09-19T10:32:47.176712Z","end":"2026-09-19T10:32:47.309611Z","steps":["trace[984905830] 'process raft request' (duration: 132.624ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:33:58.825102Z","caller":"traceutil/trace.go:171","msg":"trace[1756235268] linearizableReadLoop","detail":"{readStateIndex:1515; appliedIndex:1514; }","duration":"118.629021ms","start":"2026-09-19T10:33:58.706408Z","end":"2026-09-19T10:33:58.825037Z","steps":["trace[1756235268] 'read index received' (duration: 98.0163ms)","trace[1756235268] 'applied index is now lower than readState.Index' (duration: 20.610971ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:33:58.825317Z","caller":"traceutil/trace.go:171","msg":"trace[1972438533] transaction","detail":"{read_only:false; response_revision:1467; number_of_response:1; }","duration":"127.919074ms","start":"2026-09-19T10:33:58.697358Z","end":"2026-09-19T10:33:58.825277Z","steps":["trace[1972438533] 'process raft request' (duration: 107.002286ms)","trace[1972438533] 'compare' (duration: 20.454237ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T10:33:58.825508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.063211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-19T10:33:58.825621Z","caller":"traceutil/trace.go:171","msg":"trace[410067881] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1467; }","duration":"119.276365ms","start":"2026-09-19T10:33:58.706329Z","end":"2026-09-19T10:33:58.825605Z","steps":["trace[410067881] 'agreement among raft nodes before linearized reading' (duration: 118.961369ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:34:25.173359Z","caller":"traceutil/trace.go:171","msg":"trace[1624539314] linearizableReadLoop","detail":"{readStateIndex:1957; appliedIndex:1956; }","duration":"389.028453ms","start":"2026-09-19T10:34:24.784306Z","end":"2026-09-19T10:34:25.173334Z","steps":["trace[1624539314] 'read index received' (duration: 388.783429ms)","trace[1624539314] 'applied index is now lower than readState.Index' (duration: 243.884µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:34:25.17344Z","caller":"traceutil/trace.go:171","msg":"trace[333667086] transaction","detail":"{read_only:false; response_revision:1882; number_of_response:1; }","duration":"392.583161ms","start":"2026-09-19T10:34:24.780821Z","end":"2026-09-19T10:34:25.173404Z","steps":["trace[333667086] 'process raft request' (duration: 392.360536ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:34:25.173598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.271358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-19T10:34:25.173658Z","caller":"traceutil/trace.go:171","msg":"trace[1635863040] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1882; }","duration":"389.362839ms","start":"2026-09-19T10:34:24.784283Z","end":"2026-09-19T10:34:25.173645Z","steps":["trace[1635863040] 'agreement among raft nodes before linearized reading' (duration: 389.185886ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:34:25.173641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.611194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fbb0da80-6b73-41b3-835b-239cc98d4e2d\" ","response":"range_response_count:1 size:3744"} {"level":"info","ts":"2026-09-19T10:34:25.17374Z","caller":"traceutil/trace.go:171","msg":"trace[2142701841] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-fbb0da80-6b73-41b3-835b-239cc98d4e2d; range_end:; response_count:1; response_revision:1882; }","duration":"379.752408ms","start":"2026-09-19T10:34:24.793958Z","end":"2026-09-19T10:34:25.17371Z","steps":["trace[2142701841] 'agreement among raft nodes before linearized reading' (duration: 379.577564ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:34:25.1737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:34:24.784272Z","time spent":"389.419821ms","remote":"127.0.0.1:49564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-19T10:34:25.173789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:34:24.793944Z","time spent":"379.837059ms","remote":"127.0.0.1:49458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3766,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fbb0da80-6b73-41b3-835b-239cc98d4e2d\" "} {"level":"warn","ts":"2026-09-19T10:34:25.173841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.202084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-19T10:34:25.173906Z","caller":"traceutil/trace.go:171","msg":"trace[1105795815] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1882; }","duration":"144.332676ms","start":"2026-09-19T10:34:25.029559Z","end":"2026-09-19T10:34:25.173892Z","steps":["trace[1105795815] 'agreement among raft nodes before linearized reading' (duration: 144.104432ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:34:25.174308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:34:24.78079Z","time spent":"392.727314ms","remote":"127.0.0.1:49458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3729,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T10:35:20.377155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.093549ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T10:35:20.377377Z","caller":"traceutil/trace.go:171","msg":"trace[1275088458] transaction","detail":"{read_only:false; response_revision:2240; number_of_response:1; }","duration":"227.782738ms","start":"2026-09-19T10:35:20.149537Z","end":"2026-09-19T10:35:20.37732Z","steps":["trace[1275088458] 'process raft request' (duration: 111.158508ms)","trace[1275088458] 'compare' (duration: 115.870145ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:35:20.377714Z","caller":"traceutil/trace.go:171","msg":"trace[129789963] linearizableReadLoop","detail":"{readStateIndex:2327; appliedIndex:2326; }","duration":"199.157925ms","start":"2026-09-19T10:35:20.178526Z","end":"2026-09-19T10:35:20.377684Z","steps":["trace[129789963] 'read index received' (duration: 82.160067ms)","trace[129789963] 'applied index is now lower than readState.Index' (duration: 116.995338ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T10:35:20.377926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.40313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-19T10:35:20.378021Z","caller":"traceutil/trace.go:171","msg":"trace[1987030801] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2241; }","duration":"199.541262ms","start":"2026-09-19T10:35:20.178458Z","end":"2026-09-19T10:35:20.377999Z","steps":["trace[1987030801] 'agreement among raft nodes before linearized reading' (duration: 199.311148ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:35:20.650297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.555116ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T10:35:20.650411Z","caller":"traceutil/trace.go:171","msg":"trace[1288973933] transaction","detail":"{read_only:false; response_revision:2242; number_of_response:1; }","duration":"214.466265ms","start":"2026-09-19T10:35:20.435921Z","end":"2026-09-19T10:35:20.650388Z","steps":["trace[1288973933] 'process raft request' (duration: 103.698965ms)","trace[1288973933] 'compare' (duration: 110.304231ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:36:11.762931Z","caller":"traceutil/trace.go:171","msg":"trace[2071753894] transaction","detail":"{read_only:false; response_revision:2691; number_of_response:1; }","duration":"101.402433ms","start":"2026-09-19T10:36:11.661497Z","end":"2026-09-19T10:36:11.7629Z","steps":["trace[2071753894] 'process raft request' (duration: 101.22932ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:36:41.906744Z","caller":"traceutil/trace.go:171","msg":"trace[945337074] transaction","detail":"{read_only:false; response_revision:2873; number_of_response:1; }","duration":"122.288929ms","start":"2026-09-19T10:36:41.784427Z","end":"2026-09-19T10:36:41.906716Z","steps":["trace[945337074] 'process raft request' (duration: 121.869261ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:41:30.290535Z","caller":"traceutil/trace.go:171","msg":"trace[1934087622] transaction","detail":"{read_only:false; response_revision:5133; number_of_response:1; }","duration":"121.787608ms","start":"2026-09-19T10:41:30.168679Z","end":"2026-09-19T10:41:30.290467Z","steps":["trace[1934087622] 'process raft request' (duration: 93.962643ms)","trace[1934087622] 'compare' (duration: 27.600081ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T10:41:37.551196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.330132ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-19T10:41:37.551367Z","caller":"traceutil/trace.go:171","msg":"trace[79029889] transaction","detail":"{read_only:false; response_revision:5353; number_of_response:1; }","duration":"280.316621ms","start":"2026-09-19T10:41:37.271024Z","end":"2026-09-19T10:41:37.55134Z","steps":["trace[79029889] 'process raft request' (duration: 142.735364ms)","trace[79029889] 'compare' (duration: 137.106328ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:41:37.910099Z","caller":"traceutil/trace.go:171","msg":"trace[1506041042] transaction","detail":"{read_only:false; response_revision:5367; number_of_response:1; }","duration":"145.001457ms","start":"2026-09-19T10:41:37.765064Z","end":"2026-09-19T10:41:37.910066Z","steps":["trace[1506041042] 'process raft request' (duration: 71.567901ms)","trace[1506041042] 'compare' (duration: 73.294234ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:41:49.730226Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2918} {"level":"info","ts":"2026-09-19T10:41:49.794974Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2918,"took":"63.663121ms","hash":1551633550,"current-db-size-bytes":55676928,"current-db-size":"56 MB","current-db-size-in-use-bytes":30822400,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-19T10:41:49.795041Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1551633550,"revision":2918,"compact-revision":-1} {"level":"info","ts":"2026-09-19T10:41:52.557044Z","caller":"traceutil/trace.go:171","msg":"trace[376731836] transaction","detail":"{read_only:false; response_revision:5627; number_of_response:1; }","duration":"119.595568ms","start":"2026-09-19T10:41:52.437425Z","end":"2026-09-19T10:41:52.557021Z","steps":["trace[376731836] 'process raft request' (duration: 119.453245ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:42:00.395772Z","caller":"traceutil/trace.go:171","msg":"trace[1948635886] transaction","detail":"{read_only:false; response_revision:5815; number_of_response:1; }","duration":"105.987771ms","start":"2026-09-19T10:42:00.289749Z","end":"2026-09-19T10:42:00.395736Z","steps":["trace[1948635886] 'process raft request' (duration: 105.562923ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:42:00.783262Z","caller":"traceutil/trace.go:171","msg":"trace[34803793] linearizableReadLoop","detail":"{readStateIndex:6039; appliedIndex:6038; }","duration":"101.455345ms","start":"2026-09-19T10:42:00.681778Z","end":"2026-09-19T10:42:00.783234Z","steps":["trace[34803793] 'read index received' (duration: 101.249691ms)","trace[34803793] 'applied index is now lower than readState.Index' (duration: 204.654µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:42:00.783391Z","caller":"traceutil/trace.go:171","msg":"trace[331673839] transaction","detail":"{read_only:false; response_revision:5816; number_of_response:1; }","duration":"177.54608ms","start":"2026-09-19T10:42:00.605775Z","end":"2026-09-19T10:42:00.783321Z","steps":["trace[331673839] 'process raft request' (duration: 177.288765ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:42:00.783519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.72176ms","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-19T10:42:00.783564Z","caller":"traceutil/trace.go:171","msg":"trace[1487627011] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5816; }","duration":"101.798141ms","start":"2026-09-19T10:42:00.68175Z","end":"2026-09-19T10:42:00.783549Z","steps":["trace[1487627011] 'agreement among raft nodes before linearized reading' (duration: 101.645218ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:46:49.739912Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5572} {"level":"info","ts":"2026-09-19T10:46:49.829625Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5572,"took":"86.95778ms","hash":1421196308,"current-db-size-bytes":55676928,"current-db-size":"56 MB","current-db-size-in-use-bytes":27832320,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-19T10:46:49.829734Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1421196308,"revision":5572,"compact-revision":2918} {"level":"warn","ts":"2026-09-19T10:48:19.323557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T10:48:19.323924Z","caller":"traceutil/trace.go:171","msg":"trace[783509117] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:8685; }","duration":"134.881669ms","start":"2026-09-19T10:48:19.189015Z","end":"2026-09-19T10:48:19.323897Z","steps":["trace[783509117] 'count revisions from in-memory index tree' (duration: 134.224977ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:48:32.262875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.476709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T10:48:32.263001Z","caller":"traceutil/trace.go:171","msg":"trace[296903151] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:8750; }","duration":"175.615701ms","start":"2026-09-19T10:48:32.08736Z","end":"2026-09-19T10:48:32.262975Z","steps":["trace[296903151] 'range keys from in-memory index tree' (duration: 175.219224ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:48:32.262996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.27156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T10:48:32.263073Z","caller":"traceutil/trace.go:171","msg":"trace[1233953646] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:8750; }","duration":"248.411073ms","start":"2026-09-19T10:48:32.014647Z","end":"2026-09-19T10:48:32.263058Z","steps":["trace[1233953646] 'count revisions from in-memory index tree' (duration: 248.110028ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:48:32.263045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.112846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-19T10:48:32.263096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.232212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-19T10:48:32.263272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.151019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T10:48:32.263314Z","caller":"traceutil/trace.go:171","msg":"trace[1034095461] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:8750; }","duration":"248.277982ms","start":"2026-09-19T10:48:32.015023Z","end":"2026-09-19T10:48:32.263301Z","steps":["trace[1034095461] 'range keys from in-memory index tree' (duration: 248.098948ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:48:32.263318Z","caller":"traceutil/trace.go:171","msg":"trace[1920737673] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:8750; }","duration":"285.522427ms","start":"2026-09-19T10:48:31.977756Z","end":"2026-09-19T10:48:32.263278Z","steps":["trace[1920737673] 'range keys from in-memory index tree' (duration: 285.021858ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:48:32.263403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.108448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T10:48:32.26345Z","caller":"traceutil/trace.go:171","msg":"trace[481540210] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:8750; }","duration":"208.17192ms","start":"2026-09-19T10:48:32.055267Z","end":"2026-09-19T10:48:32.263439Z","steps":["trace[481540210] 'count revisions from in-memory index tree' (duration: 208.055107ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:48:32.263606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.135179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T10:48:32.26364Z","caller":"traceutil/trace.go:171","msg":"trace[221027999] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:8750; }","duration":"208.230301ms","start":"2026-09-19T10:48:32.055401Z","end":"2026-09-19T10:48:32.263631Z","steps":["trace[221027999] 'range keys from in-memory index tree' (duration: 208.026847ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:48:32.263342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.112066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T10:48:32.263727Z","caller":"traceutil/trace.go:171","msg":"trace[377702545] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:8750; }","duration":"230.502043ms","start":"2026-09-19T10:48:32.033198Z","end":"2026-09-19T10:48:32.2637Z","steps":["trace[377702545] 'range keys from in-memory index tree' (duration: 230.075925ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:48:32.263753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.769809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-19T10:48:32.26377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.011843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T10:48:32.263794Z","caller":"traceutil/trace.go:171","msg":"trace[225673462] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:8750; }","duration":"154.051103ms","start":"2026-09-19T10:48:32.109735Z","end":"2026-09-19T10:48:32.263786Z","steps":["trace[225673462] 'range keys from in-memory index tree' (duration: 153.92937ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:48:32.26379Z","caller":"traceutil/trace.go:171","msg":"trace[1907331115] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:8750; }","duration":"230.80822ms","start":"2026-09-19T10:48:32.032971Z","end":"2026-09-19T10:48:32.26378Z","steps":["trace[1907331115] 'range keys from in-memory index tree' (duration: 230.721368ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:48:32.26376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.15737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T10:48:32.263894Z","caller":"traceutil/trace.go:171","msg":"trace[494167241] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:8750; }","duration":"207.308203ms","start":"2026-09-19T10:48:32.056576Z","end":"2026-09-19T10:48:32.263884Z","steps":["trace[494167241] 'range keys from in-memory index tree' (duration: 207.059158ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:48:32.263119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.535701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-19T10:48:32.263921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.437337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T10:48:32.263934Z","caller":"traceutil/trace.go:171","msg":"trace[1156782746] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:8750; }","duration":"176.382247ms","start":"2026-09-19T10:48:32.087541Z","end":"2026-09-19T10:48:32.263924Z","steps":["trace[1156782746] 'range keys from in-memory index tree' (duration: 175.460178ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:48:32.263965Z","caller":"traceutil/trace.go:171","msg":"trace[848464530] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:8750; }","duration":"176.497879ms","start":"2026-09-19T10:48:32.087452Z","end":"2026-09-19T10:48:32.26395Z","steps":["trace[848464530] 'count revisions from in-memory index tree' (duration: 176.395656ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:48:32.263985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.634677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:20243"} {"level":"info","ts":"2026-09-19T10:48:32.264003Z","caller":"traceutil/trace.go:171","msg":"trace[589082099] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:8750; }","duration":"152.699797ms","start":"2026-09-19T10:48:32.111298Z","end":"2026-09-19T10:48:32.263998Z","steps":["trace[589082099] 'range keys from in-memory index tree' (duration: 152.463413ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:48:32.263169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.832186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-19T10:48:32.263186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.942197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T10:48:32.264118Z","caller":"traceutil/trace.go:171","msg":"trace[641955764] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:8750; }","duration":"157.789443ms","start":"2026-09-19T10:48:32.106313Z","end":"2026-09-19T10:48:32.264102Z","steps":["trace[641955764] 'count revisions from in-memory index tree' (duration: 156.799015ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:48:32.264135Z","caller":"traceutil/trace.go:171","msg":"trace[1356189174] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:8750; }","duration":"157.916985ms","start":"2026-09-19T10:48:32.106205Z","end":"2026-09-19T10:48:32.264122Z","steps":["trace[1356189174] 'range keys from in-memory index tree' (duration: 156.792074ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:48:32.264179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.469522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-19T10:48:32.264202Z","caller":"traceutil/trace.go:171","msg":"trace[575619602] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8750; }","duration":"201.548234ms","start":"2026-09-19T10:48:32.062642Z","end":"2026-09-19T10:48:32.26419Z","steps":["trace[575619602] 'range keys from in-memory index tree' (duration: 201.089896ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:48:32.263219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.297593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T10:48:32.264318Z","caller":"traceutil/trace.go:171","msg":"trace[468251410] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:8750; }","duration":"286.404083ms","start":"2026-09-19T10:48:31.977897Z","end":"2026-09-19T10:48:32.264301Z","steps":["trace[468251410] 'count revisions from in-memory index tree' (duration: 285.253572ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:48:32.263231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.298062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T10:48:32.264405Z","caller":"traceutil/trace.go:171","msg":"trace[446839804] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:8750; }","duration":"249.500314ms","start":"2026-09-19T10:48:32.014898Z","end":"2026-09-19T10:48:32.264398Z","steps":["trace[446839804] 'range keys from in-memory index tree' (duration: 248.242811ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:48:32.263158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.179732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T10:48:32.263174Z","caller":"traceutil/trace.go:171","msg":"trace[1201331290] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:8750; }","duration":"230.338161ms","start":"2026-09-19T10:48:32.032808Z","end":"2026-09-19T10:48:32.263146Z","steps":["trace[1201331290] 'count revisions from in-memory index tree' (duration: 229.877592ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:48:32.264461Z","caller":"traceutil/trace.go:171","msg":"trace[1761617562] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:8750; }","duration":"285.511217ms","start":"2026-09-19T10:48:31.978931Z","end":"2026-09-19T10:48:32.264442Z","steps":["trace[1761617562] 'range keys from in-memory index tree' (duration: 284.129021ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:48:32.264145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.299374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-19T10:48:32.264522Z","caller":"traceutil/trace.go:171","msg":"trace[825726260] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8750; }","duration":"196.705862ms","start":"2026-09-19T10:48:32.067804Z","end":"2026-09-19T10:48:32.26451Z","steps":["trace[825726260] 'range keys from in-memory index tree' (duration: 195.923657ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:48:32.392521Z","caller":"traceutil/trace.go:171","msg":"trace[920967972] transaction","detail":"{read_only:false; response_revision:8753; number_of_response:1; }","duration":"121.843052ms","start":"2026-09-19T10:48:32.270646Z","end":"2026-09-19T10:48:32.392489Z","steps":["trace[920967972] 'process raft request' (duration: 121.673089ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:48:32.392511Z","caller":"traceutil/trace.go:171","msg":"trace[2087995421] transaction","detail":"{read_only:false; response_revision:8752; number_of_response:1; }","duration":"122.626198ms","start":"2026-09-19T10:48:32.269824Z","end":"2026-09-19T10:48:32.39245Z","steps":["trace[2087995421] 'process raft request' (duration: 100.045059ms)","trace[2087995421] 'compare' (duration: 22.278893ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:48:32.392669Z","caller":"traceutil/trace.go:171","msg":"trace[1664184749] linearizableReadLoop","detail":"{readStateIndex:9070; appliedIndex:9068; }","duration":"122.658428ms","start":"2026-09-19T10:48:32.269968Z","end":"2026-09-19T10:48:32.392627Z","steps":["trace[1664184749] 'read index received' (duration: 68.626472ms)","trace[1664184749] 'applied index is now lower than readState.Index' (duration: 54.030646ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T10:48:32.392928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.938472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:20243"} {"level":"info","ts":"2026-09-19T10:48:32.392974Z","caller":"traceutil/trace.go:171","msg":"trace[732436293] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:8754; }","duration":"123.032704ms","start":"2026-09-19T10:48:32.269928Z","end":"2026-09-19T10:48:32.392961Z","steps":["trace[732436293] 'agreement among raft nodes before linearized reading' (duration: 122.825431ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:48:59.15027Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002439.wal"} {"level":"info","ts":"2026-09-19T10:49:02.555668Z","caller":"traceutil/trace.go:171","msg":"trace[1553359758] transaction","detail":"{read_only:false; response_revision:8967; number_of_response:1; }","duration":"133.976551ms","start":"2026-09-19T10:49:02.421657Z","end":"2026-09-19T10:49:02.555634Z","steps":["trace[1553359758] 'process raft request' (duration: 133.40819ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:50:18.379851Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"40fb1eca294b6a5a","local-member-applied-index":10002,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-19T10:50:18.389729Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10002} {"level":"info","ts":"2026-09-19T10:50:18.389854Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5002} {"level":"info","ts":"2026-09-19T10:51:49.754433Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7960} {"level":"info","ts":"2026-09-19T10:51:49.860245Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7960,"took":"103.132346ms","hash":1265570089,"current-db-size-bytes":55676928,"current-db-size":"56 MB","current-db-size-in-use-bytes":25006080,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-09-19T10:51:49.860325Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1265570089,"revision":7960,"compact-revision":5572} {"level":"info","ts":"2026-09-19T10:56:11.399362Z","caller":"traceutil/trace.go:171","msg":"trace[766309559] linearizableReadLoop","detail":"{readStateIndex:12688; appliedIndex:12686; }","duration":"123.968181ms","start":"2026-09-19T10:56:11.275362Z","end":"2026-09-19T10:56:11.39933Z","steps":["trace[766309559] 'read index received' (duration: 6.91194ms)","trace[766309559] 'applied index is now lower than readState.Index' (duration: 117.055291ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:56:11.399452Z","caller":"traceutil/trace.go:171","msg":"trace[1749046554] transaction","detail":"{read_only:false; response_revision:12258; number_of_response:1; }","duration":"137.539579ms","start":"2026-09-19T10:56:11.261879Z","end":"2026-09-19T10:56:11.399419Z","steps":["trace[1749046554] 'process raft request' (duration: 95.924099ms)","trace[1749046554] 'compare' (duration: 41.348716ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T10:56:11.399636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.495098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-19T10:56:11.399689Z","caller":"traceutil/trace.go:171","msg":"trace[1408762368] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:12258; }","duration":"118.58716ms","start":"2026-09-19T10:56:11.281085Z","end":"2026-09-19T10:56:11.399672Z","steps":["trace[1408762368] 'agreement among raft nodes before linearized reading' (duration: 118.424697ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:56:11.399687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.134968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-19T10:56:11.399807Z","caller":"traceutil/trace.go:171","msg":"trace[1707198918] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:12258; }","duration":"112.27771ms","start":"2026-09-19T10:56:11.287506Z","end":"2026-09-19T10:56:11.399784Z","steps":["trace[1707198918] 'agreement among raft nodes before linearized reading' (duration: 112.030185ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:56:11.400373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.976421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-19T10:56:11.400624Z","caller":"traceutil/trace.go:171","msg":"trace[731695534] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:12258; }","duration":"125.277207ms","start":"2026-09-19T10:56:11.275325Z","end":"2026-09-19T10:56:11.400602Z","steps":["trace[731695534] 'agreement among raft nodes before linearized reading' (duration: 124.754897ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:56:36.001628Z","caller":"traceutil/trace.go:171","msg":"trace[1740042451] transaction","detail":"{read_only:false; response_revision:12399; number_of_response:1; }","duration":"104.234557ms","start":"2026-09-19T10:56:35.897351Z","end":"2026-09-19T10:56:36.001586Z","steps":["trace[1740042451] 'process raft request' (duration: 84.572134ms)","trace[1740042451] 'compare' (duration: 19.396229ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:56:49.763558Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10352} {"level":"info","ts":"2026-09-19T10:56:49.858401Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10352,"took":"90.801793ms","hash":2634091620,"current-db-size-bytes":55676928,"current-db-size":"56 MB","current-db-size-in-use-bytes":26128384,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-19T10:56:49.858511Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2634091620,"revision":10352,"compact-revision":7960} {"level":"info","ts":"2026-09-19T11:01:49.772887Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12512} {"level":"info","ts":"2026-09-19T11:01:49.865834Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12512,"took":"89.735352ms","hash":1372440158,"current-db-size-bytes":55676928,"current-db-size":"56 MB","current-db-size-in-use-bytes":27742208,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-19T11:01:49.865923Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1372440158,"revision":12512,"compact-revision":10352} {"level":"warn","ts":"2026-09-19T11:02:38.860989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.249666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T11:02:38.861141Z","caller":"traceutil/trace.go:171","msg":"trace[1234071698] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14856; }","duration":"110.474709ms","start":"2026-09-19T11:02:38.750639Z","end":"2026-09-19T11:02:38.861114Z","steps":["trace[1234071698] 'range keys from in-memory index tree' (duration: 109.9936ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:03:18.907448Z","caller":"traceutil/trace.go:171","msg":"trace[294516615] transaction","detail":"{read_only:false; response_revision:15075; number_of_response:1; }","duration":"175.5989ms","start":"2026-09-19T11:03:18.731816Z","end":"2026-09-19T11:03:18.907415Z","steps":["trace[294516615] 'process raft request' (duration: 175.414236ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:03:19.461594Z","caller":"traceutil/trace.go:171","msg":"trace[1747322445] transaction","detail":"{read_only:false; response_revision:15078; number_of_response:1; }","duration":"144.360655ms","start":"2026-09-19T11:03:19.317192Z","end":"2026-09-19T11:03:19.461553Z","steps":["trace[1747322445] 'process raft request' (duration: 144.11185ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:03:19.627079Z","caller":"traceutil/trace.go:171","msg":"trace[1176028818] transaction","detail":"{read_only:false; response_revision:15080; number_of_response:1; }","duration":"182.705944ms","start":"2026-09-19T11:03:19.444314Z","end":"2026-09-19T11:03:19.627019Z","steps":["trace[1176028818] 'process raft request' (duration: 182.621852ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:03:19.62728Z","caller":"traceutil/trace.go:171","msg":"trace[985129132] transaction","detail":"{read_only:false; response_revision:15079; number_of_response:1; }","duration":"184.800883ms","start":"2026-09-19T11:03:19.442441Z","end":"2026-09-19T11:03:19.627242Z","steps":["trace[985129132] 'process raft request' (duration: 172.943357ms)","trace[985129132] 'compare' (duration: 11.220814ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T11:04:57.144228Z","caller":"traceutil/trace.go:171","msg":"trace[1792834288] transaction","detail":"{read_only:false; response_revision:16144; number_of_response:1; }","duration":"121.57272ms","start":"2026-09-19T11:04:57.022621Z","end":"2026-09-19T11:04:57.144194Z","steps":["trace[1792834288] 'process raft request' (duration: 121.348066ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:04:58.265669Z","caller":"traceutil/trace.go:171","msg":"trace[1075378720] transaction","detail":"{read_only:false; response_revision:16147; number_of_response:1; }","duration":"194.200922ms","start":"2026-09-19T11:04:58.071435Z","end":"2026-09-19T11:04:58.265636Z","steps":["trace[1075378720] 'process raft request' (duration: 193.969038ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:04:58.286161Z","caller":"traceutil/trace.go:171","msg":"trace[179113250] linearizableReadLoop","detail":"{readStateIndex:16714; appliedIndex:16712; }","duration":"201.508911ms","start":"2026-09-19T11:04:58.08462Z","end":"2026-09-19T11:04:58.286129Z","steps":["trace[179113250] 'read index received' (duration: 180.794068ms)","trace[179113250] 'applied index is now lower than readState.Index' (duration: 20.713783ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T11:04:58.286214Z","caller":"traceutil/trace.go:171","msg":"trace[1820424911] transaction","detail":"{read_only:false; response_revision:16148; number_of_response:1; }","duration":"210.786906ms","start":"2026-09-19T11:04:58.075393Z","end":"2026-09-19T11:04:58.28618Z","steps":["trace[1820424911] 'process raft request' (duration: 210.542822ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T11:04:58.286409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.767966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" ","response":"range_response_count:1 size:1857"} {"level":"info","ts":"2026-09-19T11:04:58.286456Z","caller":"traceutil/trace.go:171","msg":"trace[1234258992] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0; range_end:; response_count:1; response_revision:16148; }","duration":"201.876539ms","start":"2026-09-19T11:04:58.084567Z","end":"2026-09-19T11:04:58.286444Z","steps":["trace[1234258992] 'agreement among raft nodes before linearized reading' (duration: 201.681445ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:06:49.782661Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14524} {"level":"info","ts":"2026-09-19T11:06:49.871407Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14524,"took":"84.471026ms","hash":375779370,"current-db-size-bytes":55676928,"current-db-size":"56 MB","current-db-size-in-use-bytes":31051776,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-19T11:06:49.871493Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":375779370,"revision":14524,"compact-revision":12512} {"level":"info","ts":"2026-09-19T11:08:27.295894Z","caller":"traceutil/trace.go:171","msg":"trace[1606851091] transaction","detail":"{read_only:false; response_revision:17788; number_of_response:1; }","duration":"110.009382ms","start":"2026-09-19T11:08:27.185848Z","end":"2026-09-19T11:08:27.295857Z","steps":["trace[1606851091] 'process raft request' (duration: 89.471701ms)","trace[1606851091] 'compare' (duration: 20.207864ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T11:11:34.309005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.916187ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-19T11:11:34.309188Z","caller":"traceutil/trace.go:171","msg":"trace[1824930840] transaction","detail":"{read_only:false; response_revision:19015; number_of_response:1; }","duration":"192.182614ms","start":"2026-09-19T11:11:34.116979Z","end":"2026-09-19T11:11:34.309162Z","steps":["trace[1824930840] 'process raft request' (duration: 18.985061ms)","trace[1824930840] 'compare' (duration: 172.572651ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T11:11:34.577355Z","caller":"traceutil/trace.go:171","msg":"trace[1761260988] linearizableReadLoop","detail":"{readStateIndex:19679; appliedIndex:19678; }","duration":"248.704729ms","start":"2026-09-19T11:11:34.328618Z","end":"2026-09-19T11:11:34.577323Z","steps":["trace[1761260988] 'read index received' (duration: 248.488505ms)","trace[1761260988] 'applied index is now lower than readState.Index' (duration: 215.314µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T11:11:34.577444Z","caller":"traceutil/trace.go:171","msg":"trace[777010210] transaction","detail":"{read_only:false; response_revision:19016; number_of_response:1; }","duration":"407.753282ms","start":"2026-09-19T11:11:34.16964Z","end":"2026-09-19T11:11:34.577393Z","steps":["trace[777010210] 'process raft request' (duration: 407.476568ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T11:11:34.57776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.116366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-8gcdc\" ","response":"range_response_count:1 size:22132"} {"level":"info","ts":"2026-09-19T11:11:34.577871Z","caller":"traceutil/trace.go:171","msg":"trace[2094581470] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-8gcdc; range_end:; response_count:1; response_revision:19016; }","duration":"249.29996ms","start":"2026-09-19T11:11:34.328543Z","end":"2026-09-19T11:11:34.577843Z","steps":["trace[2094581470] 'agreement among raft nodes before linearized reading' (duration: 248.946363ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T11:11:34.577835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:11:34.169607Z","time spent":"408.030257ms","remote":"127.0.0.1:49564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T11:11:42.802313Z","caller":"traceutil/trace.go:171","msg":"trace[1847097591] transaction","detail":"{read_only:false; response_revision:19136; number_of_response:1; }","duration":"116.014065ms","start":"2026-09-19T11:11:42.686273Z","end":"2026-09-19T11:11:42.802287Z","steps":["trace[1847097591] 'process raft request' (duration: 115.749961ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:11:42.802551Z","caller":"traceutil/trace.go:171","msg":"trace[1057706658] transaction","detail":"{read_only:false; number_of_response:1; response_revision:19136; }","duration":"102.971178ms","start":"2026-09-19T11:11:42.699548Z","end":"2026-09-19T11:11:42.80252Z","steps":["trace[1057706658] 'process raft request' (duration: 102.698002ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T11:11:42.80282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.017579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-8gcdc\" ","response":"range_response_count:1 size:22132"} {"level":"info","ts":"2026-09-19T11:11:42.802898Z","caller":"traceutil/trace.go:171","msg":"trace[1986567077] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-8gcdc; range_end:; response_count:1; response_revision:19136; }","duration":"103.179542ms","start":"2026-09-19T11:11:42.6997Z","end":"2026-09-19T11:11:42.802879Z","steps":["trace[1986567077] 'agreement among raft nodes before linearized reading' (duration: 102.826375ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:11:49.79217Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16877} {"level":"info","ts":"2026-09-19T11:11:49.907052Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16877,"took":"110.810407ms","hash":2998538909,"current-db-size-bytes":55676928,"current-db-size":"56 MB","current-db-size-in-use-bytes":32665600,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-09-19T11:11:49.907135Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2998538909,"revision":16877,"compact-revision":14524} {"level":"info","ts":"2026-09-19T11:12:03.138565Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"40fb1eca294b6a5a","local-member-applied-index":20003,"local-member-snapshot-index":10002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-19T11:12:03.145983Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20003} {"level":"info","ts":"2026-09-19T11:12:03.146121Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15003} {"level":"info","ts":"2026-09-19T11:16:49.801536Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19257} {"level":"info","ts":"2026-09-19T11:16:49.910143Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19257,"took":"103.600914ms","hash":16793901,"current-db-size-bytes":55676928,"current-db-size":"56 MB","current-db-size-in-use-bytes":34045952,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-09-19T11:16:49.910638Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":16793901,"revision":19257,"compact-revision":16877} {"level":"info","ts":"2026-09-19T11:17:35.139788Z","caller":"traceutil/trace.go:171","msg":"trace[1432157901] linearizableReadLoop","detail":"{readStateIndex:22536; appliedIndex:22535; }","duration":"116.410577ms","start":"2026-09-19T11:17:35.023354Z","end":"2026-09-19T11:17:35.139765Z","steps":["trace[1432157901] 'read index received' (duration: 116.207923ms)","trace[1432157901] 'applied index is now lower than readState.Index' (duration: 201.594µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T11:17:35.140078Z","caller":"traceutil/trace.go:171","msg":"trace[1542891899] transaction","detail":"{read_only:false; response_revision:21780; number_of_response:1; }","duration":"150.117836ms","start":"2026-09-19T11:17:34.989948Z","end":"2026-09-19T11:17:35.140066Z","steps":["trace[1542891899] 'process raft request' (duration: 149.676337ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T11:17:35.140376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.005128ms","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-19T11:17:35.140421Z","caller":"traceutil/trace.go:171","msg":"trace[960912165] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21780; }","duration":"117.10122ms","start":"2026-09-19T11:17:35.023309Z","end":"2026-09-19T11:17:35.14041Z","steps":["trace[960912165] 'agreement among raft nodes before linearized reading' (duration: 116.969568ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:17:35.382241Z","caller":"traceutil/trace.go:171","msg":"trace[1072525993] transaction","detail":"{read_only:false; response_revision:21782; number_of_response:1; }","duration":"235.892042ms","start":"2026-09-19T11:17:35.146299Z","end":"2026-09-19T11:17:35.382191Z","steps":["trace[1072525993] 'process raft request' (duration: 235.79754ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:17:35.382525Z","caller":"traceutil/trace.go:171","msg":"trace[1478973219] transaction","detail":"{read_only:false; response_revision:21783; number_of_response:1; }","duration":"235.007034ms","start":"2026-09-19T11:17:35.147468Z","end":"2026-09-19T11:17:35.382475Z","steps":["trace[1478973219] 'process raft request' (duration: 234.685888ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:17:35.38264Z","caller":"traceutil/trace.go:171","msg":"trace[29189015] transaction","detail":"{read_only:false; response_revision:21781; number_of_response:1; }","duration":"237.579954ms","start":"2026-09-19T11:17:35.145022Z","end":"2026-09-19T11:17:35.382602Z","steps":["trace[29189015] 'process raft request' (duration: 168.877942ms)","trace[29189015] 'compare' (duration: 68.046579ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T11:17:35.382779Z","caller":"traceutil/trace.go:171","msg":"trace[1652705039] transaction","detail":"{read_only:false; response_revision:21784; number_of_response:1; }","duration":"235.297641ms","start":"2026-09-19T11:17:35.147457Z","end":"2026-09-19T11:17:35.382755Z","steps":["trace[1652705039] 'process raft request' (duration: 234.938804ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:17:35.749002Z","caller":"traceutil/trace.go:171","msg":"trace[1989037378] linearizableReadLoop","detail":"{readStateIndex:22541; appliedIndex:22540; }","duration":"100.509225ms","start":"2026-09-19T11:17:35.648473Z","end":"2026-09-19T11:17:35.748982Z","steps":["trace[1989037378] 'read index received' (duration: 100.330031ms)","trace[1989037378] 'applied index is now lower than readState.Index' (duration: 178.444µs)"],"step_count":2} {"level":"warn","ts":"2026-09-19T11:17:35.749145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.657558ms","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-19T11:17:35.749076Z","caller":"traceutil/trace.go:171","msg":"trace[523593680] transaction","detail":"{read_only:false; response_revision:21785; number_of_response:1; }","duration":"163.272306ms","start":"2026-09-19T11:17:35.585765Z","end":"2026-09-19T11:17:35.749037Z","steps":["trace[523593680] 'process raft request' (duration: 163.086922ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:17:35.74918Z","caller":"traceutil/trace.go:171","msg":"trace[1665358640] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:21785; }","duration":"100.730389ms","start":"2026-09-19T11:17:35.648439Z","end":"2026-09-19T11:17:35.74917Z","steps":["trace[1665358640] 'agreement among raft nodes before linearized reading' (duration: 100.652488ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T11:17:35.94595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.437321ms","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-19T11:17:35.946065Z","caller":"traceutil/trace.go:171","msg":"trace[80670816] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21785; }","duration":"170.570403ms","start":"2026-09-19T11:17:35.775467Z","end":"2026-09-19T11:17:35.946038Z","steps":["trace[80670816] 'range keys from in-memory index tree' (duration: 170.197406ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T11:17:35.94608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.3987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-09-19T11:17:35.946709Z","caller":"traceutil/trace.go:171","msg":"trace[500564636] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:21785; }","duration":"190.056564ms","start":"2026-09-19T11:17:35.756629Z","end":"2026-09-19T11:17:35.946686Z","steps":["trace[500564636] 'range keys from in-memory index tree' (duration: 188.783688ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:17:36.10638Z","caller":"traceutil/trace.go:171","msg":"trace[1473181063] linearizableReadLoop","detail":"{readStateIndex:22543; appliedIndex:22542; }","duration":"137.471077ms","start":"2026-09-19T11:17:35.96888Z","end":"2026-09-19T11:17:36.106351Z","steps":["trace[1473181063] 'read index received' (duration: 122.635085ms)","trace[1473181063] 'applied index is now lower than readState.Index' (duration: 14.834151ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T11:17:36.106426Z","caller":"traceutil/trace.go:171","msg":"trace[1699953202] transaction","detail":"{read_only:false; response_revision:21787; number_of_response:1; }","duration":"151.628684ms","start":"2026-09-19T11:17:35.954762Z","end":"2026-09-19T11:17:36.106391Z","steps":["trace[1699953202] 'process raft request' (duration: 136.815923ms)","trace[1699953202] 'compare' (duration: 14.470305ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T11:17:36.10665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.761151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-09-19T11:17:36.1067Z","caller":"traceutil/trace.go:171","msg":"trace[1995722481] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:21787; }","duration":"137.870163ms","start":"2026-09-19T11:17:35.968814Z","end":"2026-09-19T11:17:36.106684Z","steps":["trace[1995722481] 'agreement among raft nodes before linearized reading' (duration: 137.635779ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:21:49.815943Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21551} {"level":"info","ts":"2026-09-19T11:21:49.982177Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21551,"took":"161.797082ms","hash":2417462767,"current-db-size-bytes":55676928,"current-db-size":"56 MB","current-db-size-in-use-bytes":31723520,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-19T11:21:49.982254Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2417462767,"revision":21551,"compact-revision":19257} {"level":"info","ts":"2026-09-19T11:23:38.084892Z","caller":"traceutil/trace.go:171","msg":"trace[578053149] transaction","detail":"{read_only:false; response_revision:25401; number_of_response:1; }","duration":"117.233058ms","start":"2026-09-19T11:23:37.96761Z","end":"2026-09-19T11:23:38.084843Z","steps":["trace[578053149] 'process raft request' (duration: 116.974493ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:24:16.2591Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006809.wal"} {"level":"info","ts":"2026-09-19T11:25:38.98539Z","caller":"traceutil/trace.go:171","msg":"trace[420314130] transaction","detail":"{read_only:false; response_revision:26589; number_of_response:1; }","duration":"143.079067ms","start":"2026-09-19T11:25:38.842263Z","end":"2026-09-19T11:25:38.985342Z","steps":["trace[420314130] 'process raft request' (duration: 142.880733ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:25:38.990048Z","caller":"traceutil/trace.go:171","msg":"trace[673139604] transaction","detail":"{read_only:false; response_revision:26590; number_of_response:1; }","duration":"120.041791ms","start":"2026-09-19T11:25:38.869692Z","end":"2026-09-19T11:25:38.989734Z","steps":["trace[673139604] 'process raft request' (duration: 119.847657ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T11:25:44.479848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.634095ms","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-19T11:25:44.479963Z","caller":"traceutil/trace.go:171","msg":"trace[733571761] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26625; }","duration":"127.87997ms","start":"2026-09-19T11:25:44.35206Z","end":"2026-09-19T11:25:44.47994Z","steps":["trace[733571761] 'range keys from in-memory index tree' (duration: 127.34498ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T11:25:44.479978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.54854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-09-19T11:25:44.480045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.039781ms","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-19T11:25:44.480237Z","caller":"traceutil/trace.go:171","msg":"trace[1884592658] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26625; }","duration":"119.246916ms","start":"2026-09-19T11:25:44.360967Z","end":"2026-09-19T11:25:44.480214Z","steps":["trace[1884592658] 'range keys from in-memory index tree' (duration: 118.823308ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:25:44.480276Z","caller":"traceutil/trace.go:171","msg":"trace[275540064] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26625; }","duration":"186.776283ms","start":"2026-09-19T11:25:44.293339Z","end":"2026-09-19T11:25:44.480115Z","steps":["trace[275540064] 'range keys from in-memory index tree' (duration: 186.362346ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:25:44.696252Z","caller":"traceutil/trace.go:171","msg":"trace[1268207534] transaction","detail":"{read_only:false; response_revision:26627; number_of_response:1; }","duration":"211.236426ms","start":"2026-09-19T11:25:44.484978Z","end":"2026-09-19T11:25:44.696214Z","steps":["trace[1268207534] 'process raft request' (duration: 128.688424ms)","trace[1268207534] 'compare' (duration: 82.147844ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T11:25:44.696563Z","caller":"traceutil/trace.go:171","msg":"trace[471224831] transaction","detail":"{read_only:false; response_revision:26629; number_of_response:1; }","duration":"211.078573ms","start":"2026-09-19T11:25:44.48547Z","end":"2026-09-19T11:25:44.696549Z","steps":["trace[471224831] 'process raft request' (duration: 210.789367ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:25:44.696619Z","caller":"traceutil/trace.go:171","msg":"trace[503582463] transaction","detail":"{read_only:false; response_revision:26628; number_of_response:1; }","duration":"211.539872ms","start":"2026-09-19T11:25:44.485046Z","end":"2026-09-19T11:25:44.696586Z","steps":["trace[503582463] 'process raft request' (duration: 211.085823ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:25:44.696684Z","caller":"traceutil/trace.go:171","msg":"trace[952154239] transaction","detail":"{read_only:false; response_revision:26630; number_of_response:1; }","duration":"210.224917ms","start":"2026-09-19T11:25:44.486434Z","end":"2026-09-19T11:25:44.696659Z","steps":["trace[952154239] 'process raft request' (duration: 209.888981ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T11:25:44.971028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.225915ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-19T11:25:44.971297Z","caller":"traceutil/trace.go:171","msg":"trace[754837620] transaction","detail":"{read_only:false; response_revision:26632; number_of_response:1; }","duration":"245.990743ms","start":"2026-09-19T11:25:44.725263Z","end":"2026-09-19T11:25:44.971253Z","steps":["trace[754837620] 'process raft request' (duration: 98.384151ms)","trace[754837620] 'compare' (duration: 146.799646ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T11:25:44.971956Z","caller":"traceutil/trace.go:171","msg":"trace[1856388301] linearizableReadLoop","detail":"{readStateIndex:27532; appliedIndex:27531; }","duration":"225.640839ms","start":"2026-09-19T11:25:44.745538Z","end":"2026-09-19T11:25:44.971179Z","steps":["trace[1856388301] 'read index received' (duration: 78.000066ms)","trace[1856388301] 'applied index is now lower than readState.Index' (duration: 147.638683ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T11:25:44.971976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.417333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-19T11:25:44.972284Z","caller":"traceutil/trace.go:171","msg":"trace[1552787158] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26632; }","duration":"226.78173ms","start":"2026-09-19T11:25:44.745471Z","end":"2026-09-19T11:25:44.972253Z","steps":["trace[1552787158] 'agreement among raft nodes before linearized reading' (duration: 226.391803ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T11:25:49.369362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.054445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-19T11:25:49.369439Z","caller":"traceutil/trace.go:171","msg":"trace[1753712010] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:26664; }","duration":"200.209348ms","start":"2026-09-19T11:25:49.169212Z","end":"2026-09-19T11:25:49.369421Z","steps":["trace[1753712010] 'count revisions from in-memory index tree' (duration: 199.944973ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:25:50.201269Z","caller":"traceutil/trace.go:171","msg":"trace[942653428] transaction","detail":"{read_only:false; response_revision:26666; number_of_response:1; }","duration":"299.278911ms","start":"2026-09-19T11:25:49.901961Z","end":"2026-09-19T11:25:50.20124Z","steps":["trace[942653428] 'process raft request' (duration: 299.068037ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:25:50.201311Z","caller":"traceutil/trace.go:171","msg":"trace[834088942] linearizableReadLoop","detail":"{readStateIndex:27567; appliedIndex:27567; }","duration":"179.326032ms","start":"2026-09-19T11:25:50.021937Z","end":"2026-09-19T11:25:50.201263Z","steps":["trace[834088942] 'read index received' (duration: 179.306981ms)","trace[834088942] 'applied index is now lower than readState.Index' (duration: 15.431µs)"],"step_count":2} {"level":"warn","ts":"2026-09-19T11:25:50.201546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.587266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-19T11:25:50.201638Z","caller":"traceutil/trace.go:171","msg":"trace[460737414] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:26666; }","duration":"179.78111ms","start":"2026-09-19T11:25:50.021844Z","end":"2026-09-19T11:25:50.201625Z","steps":["trace[460737414] 'agreement among raft nodes before linearized reading' (duration: 179.554665ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T11:25:50.447702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.56201ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-19T11:25:50.447934Z","caller":"traceutil/trace.go:171","msg":"trace[2081461725] transaction","detail":"{read_only:false; response_revision:26668; number_of_response:1; }","duration":"226.741241ms","start":"2026-09-19T11:25:50.221177Z","end":"2026-09-19T11:25:50.447918Z","steps":["trace[2081461725] 'process raft request' (duration: 226.645539ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:25:50.448115Z","caller":"traceutil/trace.go:171","msg":"trace[533939945] transaction","detail":"{read_only:false; response_revision:26667; number_of_response:1; }","duration":"310.545784ms","start":"2026-09-19T11:25:50.137562Z","end":"2026-09-19T11:25:50.448108Z","steps":["trace[533939945] 'process raft request' (duration: 170.481254ms)","trace[533939945] 'compare' (duration: 139.00298ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T11:25:50.448147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:25:50.137528Z","time spent":"310.597186ms","remote":"127.0.0.1:49564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T11:25:50.729302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.590141ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-19T11:25:50.729456Z","caller":"traceutil/trace.go:171","msg":"trace[1452655331] linearizableReadLoop","detail":"{readStateIndex:27570; appliedIndex:27569; }","duration":"232.202253ms","start":"2026-09-19T11:25:50.497231Z","end":"2026-09-19T11:25:50.729433Z","steps":["trace[1452655331] 'read index received' (duration: 92.324927ms)","trace[1452655331] 'applied index is now lower than readState.Index' (duration: 139.875726ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T11:25:50.729558Z","caller":"traceutil/trace.go:171","msg":"trace[1509092399] transaction","detail":"{read_only:false; response_revision:26669; number_of_response:1; }","duration":"238.595144ms","start":"2026-09-19T11:25:50.490949Z","end":"2026-09-19T11:25:50.729544Z","steps":["trace[1509092399] 'process raft request' (duration: 98.627886ms)","trace[1509092399] 'compare' (duration: 139.412597ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T11:25:50.72974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.507859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-19T11:25:50.729789Z","caller":"traceutil/trace.go:171","msg":"trace[406918364] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:26669; }","duration":"232.5927ms","start":"2026-09-19T11:25:50.497178Z","end":"2026-09-19T11:25:50.72977Z","steps":["trace[406918364] 'agreement among raft nodes before linearized reading' (duration: 232.511009ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T11:25:50.731786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.260285ms","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-19T11:25:50.731935Z","caller":"traceutil/trace.go:171","msg":"trace[1027312871] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26670; }","duration":"175.451369ms","start":"2026-09-19T11:25:50.556425Z","end":"2026-09-19T11:25:50.731877Z","steps":["trace[1027312871] 'agreement among raft nodes before linearized reading' (duration: 175.135753ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T11:25:50.734353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.592688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-19T11:25:50.734419Z","caller":"traceutil/trace.go:171","msg":"trace[1728296958] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0; response_count:0; response_revision:26670; }","duration":"150.686561ms","start":"2026-09-19T11:25:50.583713Z","end":"2026-09-19T11:25:50.7344Z","steps":["trace[1728296958] 'agreement among raft nodes before linearized reading' (duration: 150.573829ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:25:50.968203Z","caller":"traceutil/trace.go:171","msg":"trace[1905341083] linearizableReadLoop","detail":"{readStateIndex:27572; appliedIndex:27571; }","duration":"228.762407ms","start":"2026-09-19T11:25:50.739423Z","end":"2026-09-19T11:25:50.968185Z","steps":["trace[1905341083] 'read index received' (duration: 165.690973ms)","trace[1905341083] 'applied index is now lower than readState.Index' (duration: 63.070344ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T11:25:50.968324Z","caller":"traceutil/trace.go:171","msg":"trace[605967518] transaction","detail":"{read_only:false; response_revision:26671; number_of_response:1; }","duration":"231.326757ms","start":"2026-09-19T11:25:50.73699Z","end":"2026-09-19T11:25:50.968317Z","steps":["trace[605967518] 'process raft request' (duration: 168.08877ms)","trace[605967518] 'compare' (duration: 62.959622ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T11:25:50.968516Z","caller":"traceutil/trace.go:171","msg":"trace[75785909] transaction","detail":"{read_only:false; response_revision:26672; number_of_response:1; }","duration":"228.286279ms","start":"2026-09-19T11:25:50.740204Z","end":"2026-09-19T11:25:50.96849Z","steps":["trace[75785909] 'process raft request' (duration: 227.959523ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T11:25:50.968601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.159785ms","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-19T11:25:50.968623Z","caller":"traceutil/trace.go:171","msg":"trace[237769351] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26672; }","duration":"229.221427ms","start":"2026-09-19T11:25:50.739395Z","end":"2026-09-19T11:25:50.968617Z","steps":["trace[237769351] 'agreement among raft nodes before linearized reading' (duration: 229.077174ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:25:50.968876Z","caller":"traceutil/trace.go:171","msg":"trace[2096579022] transaction","detail":"{read_only:false; response_revision:26673; number_of_response:1; }","duration":"227.967923ms","start":"2026-09-19T11:25:50.74082Z","end":"2026-09-19T11:25:50.968788Z","steps":["trace[2096579022] 'process raft request' (duration: 227.570655ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:25:50.96926Z","caller":"traceutil/trace.go:171","msg":"trace[635093790] transaction","detail":"{read_only:false; response_revision:26674; number_of_response:1; }","duration":"225.436016ms","start":"2026-09-19T11:25:50.743814Z","end":"2026-09-19T11:25:50.96925Z","steps":["trace[635093790] 'process raft request' (duration: 224.927996ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T11:25:50.970055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.911777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-19T11:25:50.970722Z","caller":"traceutil/trace.go:171","msg":"trace[527493090] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26674; }","duration":"225.260342ms","start":"2026-09-19T11:25:50.745403Z","end":"2026-09-19T11:25:50.970664Z","steps":["trace[527493090] 'agreement among raft nodes before linearized reading' (duration: 223.783255ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:25:51.191331Z","caller":"traceutil/trace.go:171","msg":"trace[325133571] transaction","detail":"{read_only:false; response_revision:26676; number_of_response:1; }","duration":"192.600464ms","start":"2026-09-19T11:25:50.998685Z","end":"2026-09-19T11:25:51.191285Z","steps":["trace[325133571] 'process raft request' (duration: 132.338725ms)","trace[325133571] 'compare' (duration: 60.019164ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T11:25:51.205494Z","caller":"traceutil/trace.go:171","msg":"trace[358849443] transaction","detail":"{read_only:false; response_revision:26677; number_of_response:1; }","duration":"175.314676ms","start":"2026-09-19T11:25:51.030157Z","end":"2026-09-19T11:25:51.205471Z","steps":["trace[358849443] 'process raft request' (duration: 175.137113ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:25:51.205693Z","caller":"traceutil/trace.go:171","msg":"trace[1361588513] transaction","detail":"{read_only:false; response_revision:26678; number_of_response:1; }","duration":"172.89825ms","start":"2026-09-19T11:25:51.032772Z","end":"2026-09-19T11:25:51.20567Z","steps":["trace[1361588513] 'process raft request' (duration: 172.640755ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:25:51.20568Z","caller":"traceutil/trace.go:171","msg":"trace[1672880537] linearizableReadLoop","detail":"{readStateIndex:27579; appliedIndex:27576; }","duration":"150.586889ms","start":"2026-09-19T11:25:51.055068Z","end":"2026-09-19T11:25:51.205655Z","steps":["trace[1672880537] 'read index received' (duration: 75.954458ms)","trace[1672880537] 'applied index is now lower than readState.Index' (duration: 74.630051ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T11:25:51.206061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.772117ms","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-19T11:25:51.206143Z","caller":"traceutil/trace.go:171","msg":"trace[1483997120] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26678; }","duration":"146.897509ms","start":"2026-09-19T11:25:51.059231Z","end":"2026-09-19T11:25:51.206128Z","steps":["trace[1483997120] 'agreement among raft nodes before linearized reading' (duration: 146.610563ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T11:25:51.206148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.029567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-19T11:25:51.206259Z","caller":"traceutil/trace.go:171","msg":"trace[1275827420] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:26678; }","duration":"151.221451ms","start":"2026-09-19T11:25:51.055013Z","end":"2026-09-19T11:25:51.206234Z","steps":["trace[1275827420] 'agreement among raft nodes before linearized reading' (duration: 150.813003ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:25:52.31237Z","caller":"traceutil/trace.go:171","msg":"trace[1382495354] transaction","detail":"{read_only:false; response_revision:26681; number_of_response:1; }","duration":"104.471636ms","start":"2026-09-19T11:25:52.207866Z","end":"2026-09-19T11:25:52.312338Z","steps":["trace[1382495354] 'process raft request' (duration: 104.266073ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:25:52.668414Z","caller":"traceutil/trace.go:171","msg":"trace[388498729] transaction","detail":"{read_only:false; response_revision:26682; number_of_response:1; }","duration":"213.981699ms","start":"2026-09-19T11:25:52.454385Z","end":"2026-09-19T11:25:52.668367Z","steps":["trace[388498729] 'process raft request' (duration: 213.700883ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:25:52.691873Z","caller":"traceutil/trace.go:171","msg":"trace[7003077] transaction","detail":"{read_only:false; response_revision:26683; number_of_response:1; }","duration":"237.416041ms","start":"2026-09-19T11:25:52.454426Z","end":"2026-09-19T11:25:52.691842Z","steps":["trace[7003077] 'process raft request' (duration: 237.240458ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:25:52.854761Z","caller":"traceutil/trace.go:171","msg":"trace[1375120100] linearizableReadLoop","detail":"{readStateIndex:27585; appliedIndex:27584; }","duration":"109.778107ms","start":"2026-09-19T11:25:52.74495Z","end":"2026-09-19T11:25:52.854728Z","steps":["trace[1375120100] 'read index received' (duration: 104.824263ms)","trace[1375120100] 'applied index is now lower than readState.Index' (duration: 4.952604ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T11:25:52.854808Z","caller":"traceutil/trace.go:171","msg":"trace[403232869] transaction","detail":"{read_only:false; response_revision:26684; number_of_response:1; }","duration":"113.927895ms","start":"2026-09-19T11:25:52.740835Z","end":"2026-09-19T11:25:52.854763Z","steps":["trace[403232869] 'process raft request' (duration: 109.021513ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T11:25:52.85494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.967051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-19T11:25:52.854976Z","caller":"traceutil/trace.go:171","msg":"trace[1638039198] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26684; }","duration":"110.082833ms","start":"2026-09-19T11:25:52.744881Z","end":"2026-09-19T11:25:52.854964Z","steps":["trace[1638039198] 'agreement among raft nodes before linearized reading' (duration: 109.969541ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T11:25:53.154883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.161659ms","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-19T11:25:53.155012Z","caller":"traceutil/trace.go:171","msg":"trace[69972348] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26684; }","duration":"179.345253ms","start":"2026-09-19T11:25:52.975633Z","end":"2026-09-19T11:25:53.154978Z","steps":["trace[69972348] 'range keys from in-memory index tree' (duration: 178.935604ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T11:25:53.155087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.290249ms","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-19T11:25:53.155219Z","caller":"traceutil/trace.go:171","msg":"trace[273810935] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26684; }","duration":"181.502813ms","start":"2026-09-19T11:25:52.973688Z","end":"2026-09-19T11:25:53.155191Z","steps":["trace[273810935] 'range keys from in-memory index tree' (duration: 180.853672ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T11:25:53.155186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.010757ms","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-19T11:25:53.155311Z","caller":"traceutil/trace.go:171","msg":"trace[501054933] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26684; }","duration":"170.198041ms","start":"2026-09-19T11:25:52.985084Z","end":"2026-09-19T11:25:53.155282Z","steps":["trace[501054933] 'range keys from in-memory index tree' (duration: 169.400485ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T11:25:53.155529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.32401ms","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-19T11:25:53.155659Z","caller":"traceutil/trace.go:171","msg":"trace[2112966228] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26684; }","duration":"181.494895ms","start":"2026-09-19T11:25:52.974137Z","end":"2026-09-19T11:25:53.155632Z","steps":["trace[2112966228] 'range keys from in-memory index tree' (duration: 180.629698ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:25:53.39383Z","caller":"traceutil/trace.go:171","msg":"trace[1093007261] transaction","detail":"{read_only:false; response_revision:26686; number_of_response:1; }","duration":"231.877896ms","start":"2026-09-19T11:25:53.161914Z","end":"2026-09-19T11:25:53.393792Z","steps":["trace[1093007261] 'process raft request' (duration: 231.603742ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:25:53.394239Z","caller":"traceutil/trace.go:171","msg":"trace[70843360] transaction","detail":"{read_only:false; response_revision:26688; number_of_response:1; }","duration":"231.849575ms","start":"2026-09-19T11:25:53.162358Z","end":"2026-09-19T11:25:53.394207Z","steps":["trace[70843360] 'process raft request' (duration: 231.680342ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:25:53.394302Z","caller":"traceutil/trace.go:171","msg":"trace[1680686497] transaction","detail":"{read_only:false; response_revision:26687; number_of_response:1; }","duration":"232.276155ms","start":"2026-09-19T11:25:53.161988Z","end":"2026-09-19T11:25:53.394264Z","steps":["trace[1680686497] 'process raft request' (duration: 231.752404ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:25:53.405849Z","caller":"traceutil/trace.go:171","msg":"trace[762742746] linearizableReadLoop","detail":"{readStateIndex:27592; appliedIndex:27586; }","duration":"172.176737ms","start":"2026-09-19T11:25:53.233646Z","end":"2026-09-19T11:25:53.405822Z","steps":["trace[762742746] 'read index received' (duration: 159.838714ms)","trace[762742746] 'applied index is now lower than readState.Index' (duration: 12.336303ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T11:25:53.405933Z","caller":"traceutil/trace.go:171","msg":"trace[1609281894] transaction","detail":"{read_only:false; response_revision:26691; number_of_response:1; }","duration":"194.115233ms","start":"2026-09-19T11:25:53.211786Z","end":"2026-09-19T11:25:53.405901Z","steps":["trace[1609281894] 'process raft request' (duration: 193.988341ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:25:53.405943Z","caller":"traceutil/trace.go:171","msg":"trace[1555473681] transaction","detail":"{read_only:false; response_revision:26690; number_of_response:1; }","duration":"194.569101ms","start":"2026-09-19T11:25:53.211304Z","end":"2026-09-19T11:25:53.405873Z","steps":["trace[1555473681] 'process raft request' (duration: 194.359577ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:25:53.405874Z","caller":"traceutil/trace.go:171","msg":"trace[2061448523] transaction","detail":"{read_only:false; response_revision:26689; number_of_response:1; }","duration":"208.682008ms","start":"2026-09-19T11:25:53.197148Z","end":"2026-09-19T11:25:53.40583Z","steps":["trace[2061448523] 'process raft request' (duration: 208.345081ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T11:25:53.406128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.441862ms","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-19T11:25:53.406177Z","caller":"traceutil/trace.go:171","msg":"trace[726605292] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26691; }","duration":"172.587365ms","start":"2026-09-19T11:25:53.233575Z","end":"2026-09-19T11:25:53.406162Z","steps":["trace[726605292] 'agreement among raft nodes before linearized reading' (duration: 172.380421ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T11:25:53.723887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.576455ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-19T11:25:53.724039Z","caller":"traceutil/trace.go:171","msg":"trace[401271183] transaction","detail":"{read_only:false; response_revision:26692; number_of_response:1; }","duration":"311.932772ms","start":"2026-09-19T11:25:53.412079Z","end":"2026-09-19T11:25:53.724012Z","steps":["trace[401271183] 'process raft request' (duration: 185.088171ms)","trace[401271183] 'compare' (duration: 126.034915ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T11:25:53.72413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:25:53.412044Z","time spent":"312.032793ms","remote":"127.0.0.1:49564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T11:25:53.766183Z","caller":"traceutil/trace.go:171","msg":"trace[1460390855] transaction","detail":"{read_only:false; response_revision:26693; number_of_response:1; }","duration":"317.555747ms","start":"2026-09-19T11:25:53.448576Z","end":"2026-09-19T11:25:53.766131Z","steps":["trace[1460390855] 'process raft request' (duration: 317.133399ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:25:53.766193Z","caller":"traceutil/trace.go:171","msg":"trace[1139654616] transaction","detail":"{read_only:false; response_revision:26694; number_of_response:1; }","duration":"170.819042ms","start":"2026-09-19T11:25:53.595342Z","end":"2026-09-19T11:25:53.766161Z","steps":["trace[1139654616] 'process raft request' (duration: 170.65521ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T11:25:53.766406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:25:53.448538Z","time spent":"317.74524ms","remote":"127.0.0.1:49300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T11:25:53.787174Z","caller":"traceutil/trace.go:171","msg":"trace[482644228] transaction","detail":"{read_only:false; response_revision:26695; number_of_response:1; }","duration":"140.281323ms","start":"2026-09-19T11:25:53.646865Z","end":"2026-09-19T11:25:53.787146Z","steps":["trace[482644228] 'process raft request' (duration: 139.995708ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T11:25:54.645838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.117625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-19T11:25:54.645955Z","caller":"traceutil/trace.go:171","msg":"trace[429305213] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:26696; }","duration":"220.333289ms","start":"2026-09-19T11:25:54.425597Z","end":"2026-09-19T11:25:54.645931Z","steps":["trace[429305213] 'count revisions from in-memory index tree' (duration: 219.929821ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:25:54.800261Z","caller":"traceutil/trace.go:171","msg":"trace[1008291787] transaction","detail":"{read_only:false; response_revision:26697; number_of_response:1; }","duration":"125.491924ms","start":"2026-09-19T11:25:54.674724Z","end":"2026-09-19T11:25:54.800216Z","steps":["trace[1008291787] 'process raft request' (duration: 125.204848ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:25:54.825749Z","caller":"traceutil/trace.go:171","msg":"trace[1979289881] transaction","detail":"{read_only:false; response_revision:26698; number_of_response:1; }","duration":"128.380159ms","start":"2026-09-19T11:25:54.697343Z","end":"2026-09-19T11:25:54.825724Z","steps":["trace[1979289881] 'process raft request' (duration: 128.150864ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:25:55.010874Z","caller":"traceutil/trace.go:171","msg":"trace[1427720229] transaction","detail":"{read_only:false; response_revision:26699; number_of_response:1; }","duration":"149.341266ms","start":"2026-09-19T11:25:54.861504Z","end":"2026-09-19T11:25:55.010846Z","steps":["trace[1427720229] 'process raft request' (duration: 149.144782ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T11:25:55.321559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.337606ms","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-19T11:25:55.321673Z","caller":"traceutil/trace.go:171","msg":"trace[399621402] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26699; }","duration":"121.491769ms","start":"2026-09-19T11:25:55.200154Z","end":"2026-09-19T11:25:55.321646Z","steps":["trace[399621402] 'range keys from in-memory index tree' (duration: 121.109581ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T11:25:55.321768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.22946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1936"} {"level":"info","ts":"2026-09-19T11:25:55.321836Z","caller":"traceutil/trace.go:171","msg":"trace[2133496909] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:26699; }","duration":"170.376203ms","start":"2026-09-19T11:25:55.15144Z","end":"2026-09-19T11:25:55.321817Z","steps":["trace[2133496909] 'range keys from in-memory index tree' (duration: 170.008996ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:26:03.903897Z","caller":"traceutil/trace.go:171","msg":"trace[308579663] linearizableReadLoop","detail":"{readStateIndex:27672; appliedIndex:27671; }","duration":"365.126107ms","start":"2026-09-19T11:26:03.538741Z","end":"2026-09-19T11:26:03.903867Z","steps":["trace[308579663] 'read index received' (duration: 364.989585ms)","trace[308579663] 'applied index is now lower than readState.Index' (duration: 134.732µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T11:26:03.904073Z","caller":"traceutil/trace.go:171","msg":"trace[1659720115] transaction","detail":"{read_only:false; response_revision:26767; number_of_response:1; }","duration":"405.205625ms","start":"2026-09-19T11:26:03.498826Z","end":"2026-09-19T11:26:03.904032Z","steps":["trace[1659720115] 'process raft request' (duration: 404.789717ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T11:26:03.904211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.177386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"} {"level":"warn","ts":"2026-09-19T11:26:03.904219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.689363ms","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-19T11:26:03.904283Z","caller":"traceutil/trace.go:171","msg":"trace[1334658030] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:26767; }","duration":"404.336099ms","start":"2026-09-19T11:26:03.499934Z","end":"2026-09-19T11:26:03.90427Z","steps":["trace[1334658030] 'agreement among raft nodes before linearized reading' (duration: 404.020923ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T11:26:03.904309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:26:03.498807Z","time spent":"405.378239ms","remote":"127.0.0.1:49564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T11:26:03.904338Z","caller":"traceutil/trace.go:171","msg":"trace[825653729] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26767; }","duration":"389.856976ms","start":"2026-09-19T11:26:03.514452Z","end":"2026-09-19T11:26:03.904309Z","steps":["trace[825653729] 'agreement among raft nodes before linearized reading' (duration: 389.459178ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T11:26:03.90435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:26:03.499919Z","time spent":"404.41916ms","remote":"127.0.0.1:49450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-09-19T11:26:03.904402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:26:03.51444Z","time spent":"389.952327ms","remote":"127.0.0.1:49564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-19T11:26:03.904407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.193815ms","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-19T11:26:03.904522Z","caller":"traceutil/trace.go:171","msg":"trace[319462234] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26767; }","duration":"397.412648ms","start":"2026-09-19T11:26:03.50708Z","end":"2026-09-19T11:26:03.904493Z","steps":["trace[319462234] 'agreement among raft nodes before linearized reading' (duration: 396.9971ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T11:26:03.90458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:26:03.50704Z","time spent":"397.529381ms","remote":"127.0.0.1:49564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-19T11:26:03.904817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.030906ms","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-19T11:26:03.904884Z","caller":"traceutil/trace.go:171","msg":"trace[466430113] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26767; }","duration":"392.157139ms","start":"2026-09-19T11:26:03.512713Z","end":"2026-09-19T11:26:03.90487Z","steps":["trace[466430113] 'agreement among raft nodes before linearized reading' (duration: 391.947425ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T11:26:03.904919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:26:03.512684Z","time spent":"392.22776ms","remote":"127.0.0.1:49564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-19T11:26:04.149248Z","caller":"traceutil/trace.go:171","msg":"trace[1279068234] transaction","detail":"{read_only:false; response_revision:26768; number_of_response:1; }","duration":"604.09666ms","start":"2026-09-19T11:26:03.545126Z","end":"2026-09-19T11:26:04.149222Z","steps":["trace[1279068234] 'process raft request' (duration: 589.664226ms)","trace[1279068234] 'compare' (duration: 13.889943ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T11:26:04.14936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:26:03.545071Z","time spent":"604.224272ms","remote":"127.0.0.1:49564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T11:26:04.149897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.720827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-19T11:26:04.149873Z","caller":"traceutil/trace.go:171","msg":"trace[1503731047] transaction","detail":"{read_only:false; response_revision:26769; number_of_response:1; }","duration":"348.802579ms","start":"2026-09-19T11:26:03.801015Z","end":"2026-09-19T11:26:04.149818Z","steps":["trace[1503731047] 'process raft request' (duration: 348.165308ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:26:04.149942Z","caller":"traceutil/trace.go:171","msg":"trace[1908029555] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26770; }","duration":"403.87629ms","start":"2026-09-19T11:26:03.746055Z","end":"2026-09-19T11:26:04.149932Z","steps":["trace[1908029555] 'agreement among raft nodes before linearized reading' (duration: 403.758098ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T11:26:04.149995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:26:03.745988Z","time spent":"403.992612ms","remote":"127.0.0.1:49288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-19T11:26:04.149969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.153391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-19T11:26:04.15001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.992421ms","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-19T11:26:04.150172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:26:03.800988Z","time spent":"349.010993ms","remote":"127.0.0.1:49564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T11:26:04.150268Z","caller":"traceutil/trace.go:171","msg":"trace[352335302] linearizableReadLoop","detail":"{readStateIndex:27675; appliedIndex:27672; }","duration":"245.746679ms","start":"2026-09-19T11:26:03.904036Z","end":"2026-09-19T11:26:04.149783Z","steps":["trace[352335302] 'read index received' (duration: 230.863668ms)","trace[352335302] 'applied index is now lower than readState.Index' (duration: 14.880631ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T11:26:04.150061Z","caller":"traceutil/trace.go:171","msg":"trace[337326204] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:26770; }","duration":"371.332284ms","start":"2026-09-19T11:26:03.778707Z","end":"2026-09-19T11:26:04.150039Z","steps":["trace[337326204] 'agreement among raft nodes before linearized reading' (duration: 371.09377ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T11:26:04.150426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:26:03.77868Z","time spent":"371.729282ms","remote":"127.0.0.1:39226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" count_only:true "} {"level":"info","ts":"2026-09-19T11:26:04.15043Z","caller":"traceutil/trace.go:171","msg":"trace[1971079038] transaction","detail":"{read_only:false; response_revision:26770; number_of_response:1; }","duration":"346.069636ms","start":"2026-09-19T11:26:03.803865Z","end":"2026-09-19T11:26:04.149935Z","steps":["trace[1971079038] 'process raft request' (duration: 345.806691ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T11:26:04.150686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.064232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-19T11:26:04.150801Z","caller":"traceutil/trace.go:171","msg":"trace[151629253] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26770; }","duration":"434.659574ms","start":"2026-09-19T11:26:03.716117Z","end":"2026-09-19T11:26:04.150776Z","steps":["trace[151629253] 'agreement among raft nodes before linearized reading' (duration: 433.811657ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T11:26:04.151076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:26:03.71607Z","time spent":"434.97972ms","remote":"127.0.0.1:49272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-19T11:26:04.151137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:26:03.803849Z","time spent":"346.74657ms","remote":"127.0.0.1:49564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T11:26:04.151194Z","caller":"traceutil/trace.go:171","msg":"trace[2042494849] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26770; }","duration":"368.240096ms","start":"2026-09-19T11:26:03.781957Z","end":"2026-09-19T11:26:04.150198Z","steps":["trace[2042494849] 'agreement among raft nodes before linearized reading' (duration: 367.830348ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T11:26:04.151327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:26:03.781947Z","time spent":"369.342527ms","remote":"127.0.0.1:49564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-09-19T11:26:04.241257Z","caller":"traceutil/trace.go:171","msg":"trace[316910765] transaction","detail":"{read_only:false; response_revision:26772; number_of_response:1; }","duration":"330.973772ms","start":"2026-09-19T11:26:03.910264Z","end":"2026-09-19T11:26:04.241238Z","steps":["trace[316910765] 'process raft request' (duration: 330.86341ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:26:04.241274Z","caller":"traceutil/trace.go:171","msg":"trace[1170653053] transaction","detail":"{read_only:false; response_revision:26771; number_of_response:1; }","duration":"331.022843ms","start":"2026-09-19T11:26:03.910211Z","end":"2026-09-19T11:26:04.241234Z","steps":["trace[1170653053] 'process raft request' (duration: 330.759418ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T11:26:04.24138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:26:03.910188Z","time spent":"331.138465ms","remote":"127.0.0.1:49564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T11:26:04.24141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:26:03.910229Z","time spent":"331.084414ms","remote":"127.0.0.1:49564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T11:26:04.241474Z","caller":"traceutil/trace.go:171","msg":"trace[1008837498] transaction","detail":"{read_only:false; response_revision:26773; number_of_response:1; }","duration":"330.333869ms","start":"2026-09-19T11:26:03.911104Z","end":"2026-09-19T11:26:04.241438Z","steps":["trace[1008837498] 'process raft request' (duration: 330.074294ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T11:26:04.241647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.591904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"warn","ts":"2026-09-19T11:26:04.241659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:26:03.911072Z","time spent":"330.486612ms","remote":"127.0.0.1:49564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T11:26:04.241671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.601966ms","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-19T11:26:04.241691Z","caller":"traceutil/trace.go:171","msg":"trace[1759961646] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26773; }","duration":"183.679836ms","start":"2026-09-19T11:26:04.057998Z","end":"2026-09-19T11:26:04.241678Z","steps":["trace[1759961646] 'agreement among raft nodes before linearized reading' (duration: 183.490872ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:26:04.241727Z","caller":"traceutil/trace.go:171","msg":"trace[2006037966] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0; range_end:; response_count:1; response_revision:26773; }","duration":"162.721788ms","start":"2026-09-19T11:26:04.07899Z","end":"2026-09-19T11:26:04.241712Z","steps":["trace[2006037966] 'agreement among raft nodes before linearized reading' (duration: 162.465683ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T11:26:04.241881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.277054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-09-19T11:26:04.241936Z","caller":"traceutil/trace.go:171","msg":"trace[1152387374] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:26773; }","duration":"335.403385ms","start":"2026-09-19T11:26:03.906519Z","end":"2026-09-19T11:26:04.241922Z","steps":["trace[1152387374] 'agreement among raft nodes before linearized reading' (duration: 335.028539ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T11:26:04.242006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:26:03.906471Z","time spent":"335.524398ms","remote":"127.0.0.1:49300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":162,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"info","ts":"2026-09-19T11:26:04.293209Z","caller":"traceutil/trace.go:171","msg":"trace[684971631] transaction","detail":"{read_only:false; response_revision:26774; number_of_response:1; }","duration":"136.671626ms","start":"2026-09-19T11:26:04.15651Z","end":"2026-09-19T11:26:04.293182Z","steps":["trace[684971631] 'process raft request' (duration: 122.867765ms)","trace[684971631] 'compare' (duration: 13.582346ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T11:26:04.294147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.835737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-09-19T11:26:04.294244Z","caller":"traceutil/trace.go:171","msg":"trace[378954426] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:26774; }","duration":"111.033971ms","start":"2026-09-19T11:26:04.183189Z","end":"2026-09-19T11:26:04.294223Z","steps":["trace[378954426] 'agreement among raft nodes before linearized reading' (duration: 110.012352ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T11:26:04.8793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.750208ms","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":"warn","ts":"2026-09-19T11:26:04.879338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.551126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-19T11:26:04.879415Z","caller":"traceutil/trace.go:171","msg":"trace[966815024] 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:26775; }","duration":"135.940952ms","start":"2026-09-19T11:26:04.743446Z","end":"2026-09-19T11:26:04.879387Z","steps":["trace[966815024] 'count revisions from in-memory index tree' (duration: 135.602076ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:26:04.879425Z","caller":"traceutil/trace.go:171","msg":"trace[1294832878] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26775; }","duration":"134.717889ms","start":"2026-09-19T11:26:04.744686Z","end":"2026-09-19T11:26:04.879404Z","steps":["trace[1294832878] 'range keys from in-memory index tree' (duration: 134.417203ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:26:05.538755Z","caller":"traceutil/trace.go:171","msg":"trace[387787115] transaction","detail":"{read_only:false; response_revision:26778; number_of_response:1; }","duration":"464.62878ms","start":"2026-09-19T11:26:05.074074Z","end":"2026-09-19T11:26:05.538703Z","steps":["trace[387787115] 'process raft request' (duration: 464.036799ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T11:26:05.538895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.31347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-19T11:26:05.538869Z","caller":"traceutil/trace.go:171","msg":"trace[2020659092] linearizableReadLoop","detail":"{readStateIndex:27683; appliedIndex:27682; }","duration":"209.045684ms","start":"2026-09-19T11:26:05.329562Z","end":"2026-09-19T11:26:05.538607Z","steps":["trace[2020659092] 'read index received' (duration: 208.566135ms)","trace[2020659092] 'applied index is now lower than readState.Index' (duration: 477.979µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T11:26:05.539074Z","caller":"traceutil/trace.go:171","msg":"trace[662454217] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:26778; }","duration":"209.572925ms","start":"2026-09-19T11:26:05.329485Z","end":"2026-09-19T11:26:05.539058Z","steps":["trace[662454217] 'agreement among raft nodes before linearized reading' (duration: 209.32646ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T11:26:05.53928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:26:05.07404Z","time spent":"464.963877ms","remote":"127.0.0.1:49564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T11:26:10.359729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.850514ms","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-19T11:26:10.359833Z","caller":"traceutil/trace.go:171","msg":"trace[1910350574] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26868; }","duration":"178.027077ms","start":"2026-09-19T11:26:10.181783Z","end":"2026-09-19T11:26:10.35981Z","steps":["trace[1910350574] 'range keys from in-memory index tree' (duration: 177.604949ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T11:26:10.359849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.6994ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-19T11:26:10.360035Z","caller":"traceutil/trace.go:171","msg":"trace[1993612973] transaction","detail":"{read_only:false; response_revision:26869; number_of_response:1; }","duration":"175.824506ms","start":"2026-09-19T11:26:10.18418Z","end":"2026-09-19T11:26:10.360005Z","steps":["trace[1993612973] 'process raft request' (duration: 70.83173ms)","trace[1993612973] 'compare' (duration: 104.473996ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T11:26:10.530308Z","caller":"traceutil/trace.go:171","msg":"trace[1937001425] transaction","detail":"{read_only:false; response_revision:26870; number_of_response:1; }","duration":"165.077663ms","start":"2026-09-19T11:26:10.365168Z","end":"2026-09-19T11:26:10.530246Z","steps":["trace[1937001425] 'process raft request' (duration: 130.368957ms)","trace[1937001425] 'compare' (duration: 34.517492ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T11:26:10.816772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.527298ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-19T11:26:10.817104Z","caller":"traceutil/trace.go:171","msg":"trace[1752844640] transaction","detail":"{read_only:false; response_revision:26871; number_of_response:1; }","duration":"450.746007ms","start":"2026-09-19T11:26:10.366309Z","end":"2026-09-19T11:26:10.817055Z","steps":["trace[1752844640] 'process raft request' (duration: 272.530845ms)","trace[1752844640] 'compare' (duration: 177.315285ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T11:26:10.817197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:26:10.366295Z","time spent":"450.86056ms","remote":"127.0.0.1:49564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T11:26:10.817757Z","caller":"traceutil/trace.go:171","msg":"trace[1422943628] linearizableReadLoop","detail":"{readStateIndex:27781; appliedIndex:27776; }","duration":"116.313271ms","start":"2026-09-19T11:26:10.701428Z","end":"2026-09-19T11:26:10.817741Z","steps":["trace[1422943628] 'read index received' (duration: 55.882µs)","trace[1422943628] 'applied index is now lower than readState.Index' (duration: 116.256239ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T11:26:10.81779Z","caller":"traceutil/trace.go:171","msg":"trace[1664699940] transaction","detail":"{read_only:false; response_revision:26872; number_of_response:1; }","duration":"451.331898ms","start":"2026-09-19T11:26:10.366408Z","end":"2026-09-19T11:26:10.81774Z","steps":["trace[1664699940] 'process raft request' (duration: 450.598384ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:26:10.81786Z","caller":"traceutil/trace.go:171","msg":"trace[1802006925] transaction","detail":"{read_only:false; response_revision:26873; number_of_response:1; }","duration":"450.279559ms","start":"2026-09-19T11:26:10.367563Z","end":"2026-09-19T11:26:10.817843Z","steps":["trace[1802006925] 'process raft request' (duration: 450.026764ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:26:10.817856Z","caller":"traceutil/trace.go:171","msg":"trace[1856219253] transaction","detail":"{read_only:false; response_revision:26874; number_of_response:1; }","duration":"450.174208ms","start":"2026-09-19T11:26:10.367638Z","end":"2026-09-19T11:26:10.817812Z","steps":["trace[1856219253] 'process raft request' (duration: 450.000134ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:26:10.817873Z","caller":"traceutil/trace.go:171","msg":"trace[962892856] transaction","detail":"{read_only:false; response_revision:26875; number_of_response:1; }","duration":"386.285728ms","start":"2026-09-19T11:26:10.431534Z","end":"2026-09-19T11:26:10.81782Z","steps":["trace[962892856] 'process raft request' (duration: 386.149165ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T11:26:10.817933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:26:10.367552Z","time spent":"450.33555ms","remote":"127.0.0.1:49564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T11:26:10.818019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:26:10.366401Z","time spent":"451.481121ms","remote":"127.0.0.1:49564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T11:26:10.818074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:26:10.367613Z","time spent":"450.3358ms","remote":"127.0.0.1:49564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T11:26:10.818103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:26:10.431504Z","time spent":"386.460932ms","remote":"127.0.0.1:49564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T11:26:10.818293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.83386ms","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-19T11:26:10.818397Z","caller":"traceutil/trace.go:171","msg":"trace[503035075] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:26875; }","duration":"117.011833ms","start":"2026-09-19T11:26:10.701357Z","end":"2026-09-19T11:26:10.818369Z","steps":["trace[503035075] 'agreement among raft nodes before linearized reading' (duration: 116.649396ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T11:26:10.818411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.401601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-19T11:26:10.818463Z","caller":"traceutil/trace.go:171","msg":"trace[1875216241] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:26875; }","duration":"107.481203ms","start":"2026-09-19T11:26:10.710969Z","end":"2026-09-19T11:26:10.818451Z","steps":["trace[1875216241] 'agreement among raft nodes before linearized reading' (duration: 107.369871ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:26:10.992503Z","caller":"traceutil/trace.go:171","msg":"trace[2044180406] linearizableReadLoop","detail":"{readStateIndex:27782; appliedIndex:27781; }","duration":"150.456707ms","start":"2026-09-19T11:26:10.842019Z","end":"2026-09-19T11:26:10.992476Z","steps":["trace[2044180406] 'read index received' (duration: 90.829718ms)","trace[2044180406] 'applied index is now lower than readState.Index' (duration: 59.625409ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T11:26:10.992506Z","caller":"traceutil/trace.go:171","msg":"trace[980628802] transaction","detail":"{read_only:false; response_revision:26876; number_of_response:1; }","duration":"153.8543ms","start":"2026-09-19T11:26:10.838607Z","end":"2026-09-19T11:26:10.992461Z","steps":["trace[980628802] 'process raft request' (duration: 94.206782ms)","trace[980628802] 'compare' (duration: 59.398703ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T11:26:10.992823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.788353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9604"} {"level":"info","ts":"2026-09-19T11:26:10.992953Z","caller":"traceutil/trace.go:171","msg":"trace[181205405] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:26877; }","duration":"150.987576ms","start":"2026-09-19T11:26:10.841943Z","end":"2026-09-19T11:26:10.992931Z","steps":["trace[181205405] 'agreement among raft nodes before linearized reading' (duration: 150.66205ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:26:18.088163Z","caller":"traceutil/trace.go:171","msg":"trace[441351459] transaction","detail":"{read_only:false; response_revision:26949; number_of_response:1; }","duration":"127.016243ms","start":"2026-09-19T11:26:17.961108Z","end":"2026-09-19T11:26:18.088124Z","steps":["trace[441351459] 'process raft request' (duration: 126.766368ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T11:26:22.973628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.826586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/neutron-dhcp-agent-default-lhwkx.18d6b4818feaef7c\" ","response":"range_response_count:1 size:957"} {"level":"info","ts":"2026-09-19T11:26:22.973757Z","caller":"traceutil/trace.go:171","msg":"trace[187763952] range","detail":"{range_begin:/registry/events/openstack/neutron-dhcp-agent-default-lhwkx.18d6b4818feaef7c; range_end:; response_count:1; response_revision:26991; }","duration":"241.038929ms","start":"2026-09-19T11:26:22.732691Z","end":"2026-09-19T11:26:22.97373Z","steps":["trace[187763952] 'range keys from in-memory index tree' (duration: 240.612893ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:26:22.987363Z","caller":"traceutil/trace.go:171","msg":"trace[289912232] linearizableReadLoop","detail":"{readStateIndex:27901; appliedIndex:27900; }","duration":"102.049151ms","start":"2026-09-19T11:26:22.885286Z","end":"2026-09-19T11:26:22.987335Z","steps":["trace[289912232] 'read index received' (duration: 101.929449ms)","trace[289912232] 'applied index is now lower than readState.Index' (duration: 118.292µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T11:26:22.987462Z","caller":"traceutil/trace.go:171","msg":"trace[29399417] transaction","detail":"{read_only:false; response_revision:26992; number_of_response:1; }","duration":"122.215612ms","start":"2026-09-19T11:26:22.865218Z","end":"2026-09-19T11:26:22.987433Z","steps":["trace[29399417] 'process raft request' (duration: 121.895276ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T11:26:22.987615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.313656ms","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-19T11:26:22.987663Z","caller":"traceutil/trace.go:171","msg":"trace[139716741] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26992; }","duration":"102.436427ms","start":"2026-09-19T11:26:22.885213Z","end":"2026-09-19T11:26:22.98765Z","steps":["trace[139716741] 'agreement among raft nodes before linearized reading' (duration: 102.263945ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T11:26:23.044734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.808924ms","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-19T11:26:23.044844Z","caller":"traceutil/trace.go:171","msg":"trace[1982703713] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26993; }","duration":"158.963098ms","start":"2026-09-19T11:26:22.885852Z","end":"2026-09-19T11:26:23.044815Z","steps":["trace[1982703713] 'agreement among raft nodes before linearized reading' (duration: 158.688822ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T11:26:23.045638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.436737ms","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-19T11:26:23.045681Z","caller":"traceutil/trace.go:171","msg":"trace[1460276029] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26993; }","duration":"149.489039ms","start":"2026-09-19T11:26:22.896174Z","end":"2026-09-19T11:26:23.045663Z","steps":["trace[1460276029] 'agreement among raft nodes before linearized reading' (duration: 149.388087ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T11:26:23.045932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.976543ms","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-19T11:26:23.046019Z","caller":"traceutil/trace.go:171","msg":"trace[238789648] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26993; }","duration":"154.143016ms","start":"2026-09-19T11:26:22.891857Z","end":"2026-09-19T11:26:23.046Z","steps":["trace[238789648] 'agreement among raft nodes before linearized reading' (duration: 153.82815ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:26:23.17572Z","caller":"traceutil/trace.go:171","msg":"trace[1546397036] transaction","detail":"{read_only:false; response_revision:26994; number_of_response:1; }","duration":"183.768636ms","start":"2026-09-19T11:26:22.991917Z","end":"2026-09-19T11:26:23.175686Z","steps":["trace[1546397036] 'process raft request' (duration: 183.472ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:26:23.176116Z","caller":"traceutil/trace.go:171","msg":"trace[325778926] transaction","detail":"{read_only:false; response_revision:26995; number_of_response:1; }","duration":"138.066271ms","start":"2026-09-19T11:26:23.037982Z","end":"2026-09-19T11:26:23.176048Z","steps":["trace[325778926] 'process raft request' (duration: 137.643223ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:26:23.176164Z","caller":"traceutil/trace.go:171","msg":"trace[990637463] transaction","detail":"{read_only:false; response_revision:26996; number_of_response:1; }","duration":"136.169176ms","start":"2026-09-19T11:26:23.039955Z","end":"2026-09-19T11:26:23.176124Z","steps":["trace[990637463] 'process raft request' (duration: 135.944751ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:26:23.215794Z","caller":"traceutil/trace.go:171","msg":"trace[1825110659] transaction","detail":"{read_only:false; response_revision:26998; number_of_response:1; }","duration":"165.684164ms","start":"2026-09-19T11:26:23.050071Z","end":"2026-09-19T11:26:23.215755Z","steps":["trace[1825110659] 'process raft request' (duration: 165.355968ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:26:23.215878Z","caller":"traceutil/trace.go:171","msg":"trace[432835377] linearizableReadLoop","detail":"{readStateIndex:27908; appliedIndex:27902; }","duration":"156.244025ms","start":"2026-09-19T11:26:23.059605Z","end":"2026-09-19T11:26:23.215849Z","steps":["trace[432835377] 'read index received' (duration: 115.757999ms)","trace[432835377] 'applied index is now lower than readState.Index' (duration: 40.484086ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T11:26:23.215941Z","caller":"traceutil/trace.go:171","msg":"trace[109490649] transaction","detail":"{read_only:false; response_revision:26999; number_of_response:1; }","duration":"164.160825ms","start":"2026-09-19T11:26:23.051742Z","end":"2026-09-19T11:26:23.215903Z","steps":["trace[109490649] 'process raft request' (duration: 163.932971ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:26:23.215503Z","caller":"traceutil/trace.go:171","msg":"trace[1179602824] transaction","detail":"{read_only:false; response_revision:26997; number_of_response:1; }","duration":"165.493101ms","start":"2026-09-19T11:26:23.04998Z","end":"2026-09-19T11:26:23.215473Z","steps":["trace[1179602824] 'process raft request' (duration: 165.273417ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T11:26:23.216263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.616902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-09-19T11:26:23.216337Z","caller":"traceutil/trace.go:171","msg":"trace[2103911433] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:26999; }","duration":"156.748325ms","start":"2026-09-19T11:26:23.05957Z","end":"2026-09-19T11:26:23.216319Z","steps":["trace[2103911433] 'agreement among raft nodes before linearized reading' (duration: 156.391198ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:26:41.834096Z","caller":"traceutil/trace.go:171","msg":"trace[840763169] transaction","detail":"{read_only:false; response_revision:27147; number_of_response:1; }","duration":"124.573497ms","start":"2026-09-19T11:26:41.709479Z","end":"2026-09-19T11:26:41.834052Z","steps":["trace[840763169] 'process raft request' (duration: 124.369723ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:26:41.834405Z","caller":"traceutil/trace.go:171","msg":"trace[1361404352] transaction","detail":"{read_only:false; response_revision:27148; number_of_response:1; }","duration":"122.992617ms","start":"2026-09-19T11:26:41.711332Z","end":"2026-09-19T11:26:41.834325Z","steps":["trace[1361404352] 'process raft request' (duration: 122.671751ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:26:41.897852Z","caller":"traceutil/trace.go:171","msg":"trace[1334336725] transaction","detail":"{read_only:false; response_revision:27149; number_of_response:1; }","duration":"142.064839ms","start":"2026-09-19T11:26:41.755756Z","end":"2026-09-19T11:26:41.897821Z","steps":["trace[1334336725] 'process raft request' (duration: 141.662811ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:26:43.035529Z","caller":"traceutil/trace.go:171","msg":"trace[983421791] transaction","detail":"{read_only:false; response_revision:27152; number_of_response:1; }","duration":"173.465242ms","start":"2026-09-19T11:26:42.862024Z","end":"2026-09-19T11:26:43.035489Z","steps":["trace[983421791] 'process raft request' (duration: 173.293459ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:26:43.45495Z","caller":"traceutil/trace.go:171","msg":"trace[1196033510] transaction","detail":"{read_only:false; response_revision:27157; number_of_response:1; }","duration":"115.22475ms","start":"2026-09-19T11:26:43.339645Z","end":"2026-09-19T11:26:43.45487Z","steps":["trace[1196033510] 'process raft request' (duration: 114.926764ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:26:43.455321Z","caller":"traceutil/trace.go:171","msg":"trace[212460209] transaction","detail":"{read_only:false; response_revision:27159; number_of_response:1; }","duration":"106.505146ms","start":"2026-09-19T11:26:43.3488Z","end":"2026-09-19T11:26:43.455305Z","steps":["trace[212460209] 'process raft request' (duration: 106.308952ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:26:43.455334Z","caller":"traceutil/trace.go:171","msg":"trace[1488083256] transaction","detail":"{read_only:false; response_revision:27158; number_of_response:1; }","duration":"115.578576ms","start":"2026-09-19T11:26:43.339718Z","end":"2026-09-19T11:26:43.455296Z","steps":["trace[1488083256] 'process raft request' (duration: 115.095838ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:26:43.61169Z","caller":"traceutil/trace.go:171","msg":"trace[1888959948] transaction","detail":"{read_only:false; response_revision:27160; number_of_response:1; }","duration":"142.363294ms","start":"2026-09-19T11:26:43.469285Z","end":"2026-09-19T11:26:43.611648Z","steps":["trace[1888959948] 'process raft request' (duration: 77.859334ms)","trace[1888959948] 'compare' (duration: 64.122993ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T11:26:44.030017Z","caller":"traceutil/trace.go:171","msg":"trace[794758967] transaction","detail":"{read_only:false; response_revision:27164; number_of_response:1; }","duration":"185.924627ms","start":"2026-09-19T11:26:43.844047Z","end":"2026-09-19T11:26:44.029972Z","steps":["trace[794758967] 'process raft request' (duration: 131.867574ms)","trace[794758967] 'compare' (duration: 53.869259ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T11:26:49.824718Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23846} {"level":"info","ts":"2026-09-19T11:26:49.947152Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23846,"took":"115.241459ms","hash":1817618878,"current-db-size-bytes":57802752,"current-db-size":"58 MB","current-db-size-in-use-bytes":45084672,"current-db-size-in-use":"45 MB"} {"level":"info","ts":"2026-09-19T11:26:49.947205Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1817618878,"revision":23846,"compact-revision":21551} {"level":"info","ts":"2026-09-19T11:26:59.422209Z","caller":"traceutil/trace.go:171","msg":"trace[769300095] transaction","detail":"{read_only:false; response_revision:27271; number_of_response:1; }","duration":"232.707503ms","start":"2026-09-19T11:26:59.189474Z","end":"2026-09-19T11:26:59.422181Z","steps":["trace[769300095] 'process raft request' (duration: 184.781886ms)","trace[769300095] 'compare' (duration: 47.689332ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T11:26:59.422514Z","caller":"traceutil/trace.go:171","msg":"trace[1441132415] transaction","detail":"{read_only:false; response_revision:27272; number_of_response:1; }","duration":"126.340891ms","start":"2026-09-19T11:26:59.296155Z","end":"2026-09-19T11:26:59.422496Z","steps":["trace[1441132415] 'process raft request' (duration: 125.977144ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T11:26:59.780507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.297946ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-19T11:26:59.780614Z","caller":"traceutil/trace.go:171","msg":"trace[592072585] linearizableReadLoop","detail":"{readStateIndex:28194; appliedIndex:28193; }","duration":"229.187706ms","start":"2026-09-19T11:26:59.551407Z","end":"2026-09-19T11:26:59.780595Z","steps":["trace[592072585] 'read index received' (duration: 109.646055ms)","trace[592072585] 'applied index is now lower than readState.Index' (duration: 119.539871ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T11:26:59.780799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.862295ms","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-19T11:26:59.780833Z","caller":"traceutil/trace.go:171","msg":"trace[1760541818] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27276; }","duration":"233.964157ms","start":"2026-09-19T11:26:59.546857Z","end":"2026-09-19T11:26:59.780821Z","steps":["trace[1760541818] 'agreement among raft nodes before linearized reading' (duration: 233.790093ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T11:26:59.781336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.261562ms","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-19T11:26:59.781447Z","caller":"traceutil/trace.go:171","msg":"trace[1100408916] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27276; }","duration":"234.434116ms","start":"2026-09-19T11:26:59.546988Z","end":"2026-09-19T11:26:59.781422Z","steps":["trace[1100408916] 'agreement among raft nodes before linearized reading' (duration: 234.004218ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:26:59.782361Z","caller":"traceutil/trace.go:171","msg":"trace[731945745] transaction","detail":"{read_only:false; response_revision:27276; number_of_response:1; }","duration":"240.989619ms","start":"2026-09-19T11:26:59.539963Z","end":"2026-09-19T11:26:59.780952Z","steps":["trace[731945745] 'process raft request' (duration: 121.148242ms)","trace[731945745] 'compare' (duration: 119.152084ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T11:26:59.798934Z","caller":"traceutil/trace.go:171","msg":"trace[590305975] transaction","detail":"{read_only:false; response_revision:27277; number_of_response:1; }","duration":"242.399855ms","start":"2026-09-19T11:26:59.556508Z","end":"2026-09-19T11:26:59.798908Z","steps":["trace[590305975] 'process raft request' (duration: 242.233482ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:26:59.996094Z","caller":"traceutil/trace.go:171","msg":"trace[2081455903] transaction","detail":"{read_only:false; response_revision:27280; number_of_response:1; }","duration":"134.937453ms","start":"2026-09-19T11:26:59.861096Z","end":"2026-09-19T11:26:59.996034Z","steps":["trace[2081455903] 'process raft request' (duration: 59.822222ms)","trace[2081455903] 'compare' (duration: 74.916437ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T11:27:00.260244Z","caller":"traceutil/trace.go:171","msg":"trace[1194801816] transaction","detail":"{read_only:false; response_revision:27283; number_of_response:1; }","duration":"109.309157ms","start":"2026-09-19T11:27:00.150906Z","end":"2026-09-19T11:27:00.260215Z","steps":["trace[1194801816] 'process raft request' (duration: 109.011802ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:27:21.898034Z","caller":"traceutil/trace.go:171","msg":"trace[1611711297] linearizableReadLoop","detail":"{readStateIndex:28370; appliedIndex:28368; }","duration":"196.995027ms","start":"2026-09-19T11:27:21.701005Z","end":"2026-09-19T11:27:21.898Z","steps":["trace[1611711297] 'read index received' (duration: 136.618905ms)","trace[1611711297] 'applied index is now lower than readState.Index' (duration: 60.371342ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T11:27:21.898392Z","caller":"traceutil/trace.go:171","msg":"trace[721564631] transaction","detail":"{read_only:false; response_revision:27446; number_of_response:1; }","duration":"358.433781ms","start":"2026-09-19T11:27:21.539939Z","end":"2026-09-19T11:27:21.898373Z","steps":["trace[721564631] 'process raft request' (duration: 297.716442ms)","trace[721564631] 'compare' (duration: 59.988616ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T11:27:21.898612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:27:21.539895Z","time spent":"358.539343ms","remote":"127.0.0.1:49564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T11:27:21.898858Z","caller":"traceutil/trace.go:171","msg":"trace[1651108742] transaction","detail":"{read_only:false; response_revision:27447; number_of_response:1; }","duration":"334.57369ms","start":"2026-09-19T11:27:21.564268Z","end":"2026-09-19T11:27:21.898841Z","steps":["trace[1651108742] 'process raft request' (duration: 333.661033ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T11:27:21.898928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:27:21.564249Z","time spent":"334.642161ms","remote":"127.0.0.1:49564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T11:27:21.899195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.187019ms","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-19T11:27:21.899242Z","caller":"traceutil/trace.go:171","msg":"trace[1164999511] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27447; }","duration":"198.281401ms","start":"2026-09-19T11:27:21.700945Z","end":"2026-09-19T11:27:21.899227Z","steps":["trace[1164999511] 'agreement among raft nodes before linearized reading' (duration: 198.097867ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T11:27:21.89951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.716527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-19T11:27:21.899536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.787153ms","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-19T11:27:21.899559Z","caller":"traceutil/trace.go:171","msg":"trace[1654534846] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27447; }","duration":"154.840839ms","start":"2026-09-19T11:27:21.744702Z","end":"2026-09-19T11:27:21.899543Z","steps":["trace[1654534846] 'agreement among raft nodes before linearized reading' (duration: 154.753257ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:27:21.899579Z","caller":"traceutil/trace.go:171","msg":"trace[735732316] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:27447; }","duration":"104.861675ms","start":"2026-09-19T11:27:21.794703Z","end":"2026-09-19T11:27:21.899565Z","steps":["trace[735732316] 'agreement among raft nodes before linearized reading' (duration: 104.762453ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T11:27:21.899741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.491943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/keypairs/\" range_end:\"/registry/openstack.k-orc.cloud/keypairs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-19T11:27:21.89985Z","caller":"traceutil/trace.go:171","msg":"trace[989894678] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/keypairs/; range_end:/registry/openstack.k-orc.cloud/keypairs0; response_count:0; response_revision:27447; }","duration":"144.654937ms","start":"2026-09-19T11:27:21.755173Z","end":"2026-09-19T11:27:21.899828Z","steps":["trace[989894678] 'agreement among raft nodes before linearized reading' (duration: 144.421352ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T11:27:21.899717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.942968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-19T11:27:21.899974Z","caller":"traceutil/trace.go:171","msg":"trace[1486973786] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:27447; }","duration":"140.293924ms","start":"2026-09-19T11:27:21.759643Z","end":"2026-09-19T11:27:21.899937Z","steps":["trace[1486973786] 'agreement among raft nodes before linearized reading' (duration: 139.854905ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T11:27:22.223601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.729194ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-19T11:27:22.223869Z","caller":"traceutil/trace.go:171","msg":"trace[1054488951] transaction","detail":"{read_only:false; response_revision:27449; number_of_response:1; }","duration":"133.436934ms","start":"2026-09-19T11:27:22.090407Z","end":"2026-09-19T11:27:22.223844Z","steps":["trace[1054488951] 'process raft request' (duration: 133.356753ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:27:22.224247Z","caller":"traceutil/trace.go:171","msg":"trace[1230941128] linearizableReadLoop","detail":"{readStateIndex:28371; appliedIndex:28370; }","duration":"295.979689ms","start":"2026-09-19T11:27:21.928222Z","end":"2026-09-19T11:27:22.224201Z","steps":["trace[1230941128] 'read index received' (duration: 118.494511ms)","trace[1230941128] 'applied index is now lower than readState.Index' (duration: 177.482288ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T11:27:22.224293Z","caller":"traceutil/trace.go:171","msg":"trace[1687422069] transaction","detail":"{read_only:false; response_revision:27448; number_of_response:1; }","duration":"314.226705ms","start":"2026-09-19T11:27:21.910019Z","end":"2026-09-19T11:27:22.224245Z","steps":["trace[1687422069] 'process raft request' (duration: 136.699326ms)","trace[1687422069] 'compare' (duration: 176.51474ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T11:27:22.22453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:27:21.909988Z","time spent":"314.43174ms","remote":"127.0.0.1:49564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T11:27:22.224557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.313696ms","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-19T11:27:22.224599Z","caller":"traceutil/trace.go:171","msg":"trace[654703900] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27449; }","duration":"296.433938ms","start":"2026-09-19T11:27:21.928153Z","end":"2026-09-19T11:27:22.224587Z","steps":["trace[654703900] 'agreement among raft nodes before linearized reading' (duration: 296.211824ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T11:27:22.310009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.166055ms","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-19T11:27:22.310143Z","caller":"traceutil/trace.go:171","msg":"trace[1302494038] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27450; }","duration":"331.363579ms","start":"2026-09-19T11:27:21.978751Z","end":"2026-09-19T11:27:22.310114Z","steps":["trace[1302494038] 'agreement among raft nodes before linearized reading' (duration: 331.052573ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T11:27:22.310192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:27:21.978723Z","time spent":"331.458251ms","remote":"127.0.0.1:49564","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-19T11:27:22.310207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.379626ms","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-19T11:27:22.310014Z","caller":"traceutil/trace.go:171","msg":"trace[1965236144] transaction","detail":"{read_only:false; response_revision:27450; number_of_response:1; }","duration":"131.244663ms","start":"2026-09-19T11:27:22.17873Z","end":"2026-09-19T11:27:22.309974Z","steps":["trace[1965236144] 'process raft request' (duration: 130.890817ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T11:27:22.310304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.252027ms","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-19T11:27:22.310311Z","caller":"traceutil/trace.go:171","msg":"trace[2069932728] 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:27450; }","duration":"158.55113ms","start":"2026-09-19T11:27:22.15173Z","end":"2026-09-19T11:27:22.310281Z","steps":["trace[2069932728] 'agreement among raft nodes before linearized reading' (duration: 158.300965ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:27:22.310359Z","caller":"traceutil/trace.go:171","msg":"trace[1583080214] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27450; }","duration":"331.353438ms","start":"2026-09-19T11:27:21.97899Z","end":"2026-09-19T11:27:22.310344Z","steps":["trace[1583080214] 'agreement among raft nodes before linearized reading' (duration: 331.124304ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T11:27:22.310392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T11:27:21.978979Z","time spent":"331.40512ms","remote":"127.0.0.1:49564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-19T11:27:22.463808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.714025ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-19T11:27:22.463956Z","caller":"traceutil/trace.go:171","msg":"trace[964623592] transaction","detail":"{read_only:false; response_revision:27451; number_of_response:1; }","duration":"234.607778ms","start":"2026-09-19T11:27:22.229322Z","end":"2026-09-19T11:27:22.46393Z","steps":["trace[964623592] 'process raft request' (duration: 96.634629ms)","trace[964623592] 'compare' (duration: 137.137334ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T11:27:22.472488Z","caller":"traceutil/trace.go:171","msg":"trace[2100381185] transaction","detail":"{read_only:false; response_revision:27452; number_of_response:1; }","duration":"156.005631ms","start":"2026-09-19T11:27:22.316457Z","end":"2026-09-19T11:27:22.472462Z","steps":["trace[2100381185] 'process raft request' (duration: 155.796787ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:27:22.472934Z","caller":"traceutil/trace.go:171","msg":"trace[327337770] transaction","detail":"{read_only:false; response_revision:27453; number_of_response:1; }","duration":"153.864411ms","start":"2026-09-19T11:27:22.31904Z","end":"2026-09-19T11:27:22.472904Z","steps":["trace[327337770] 'process raft request' (duration: 153.376812ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:27:22.472921Z","caller":"traceutil/trace.go:171","msg":"trace[1674661492] linearizableReadLoop","detail":"{readStateIndex:28375; appliedIndex:28373; }","duration":"153.793639ms","start":"2026-09-19T11:27:22.319048Z","end":"2026-09-19T11:27:22.472841Z","steps":["trace[1674661492] 'read index received' (duration: 6.924801ms)","trace[1674661492] 'applied index is now lower than readState.Index' (duration: 146.865608ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T11:27:22.473333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.208648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-19T11:27:22.473467Z","caller":"traceutil/trace.go:171","msg":"trace[1357656941] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:27453; }","duration":"154.377111ms","start":"2026-09-19T11:27:22.319036Z","end":"2026-09-19T11:27:22.473414Z","steps":["trace[1357656941] 'agreement among raft nodes before linearized reading' (duration: 153.958713ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:27:22.479481Z","caller":"traceutil/trace.go:171","msg":"trace[1079093870] transaction","detail":"{read_only:false; response_revision:27454; number_of_response:1; }","duration":"120.52217ms","start":"2026-09-19T11:27:22.358918Z","end":"2026-09-19T11:27:22.47944Z","steps":["trace[1079093870] 'process raft request' (duration: 120.348647ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:29:01.180885Z","caller":"traceutil/trace.go:171","msg":"trace[835525547] linearizableReadLoop","detail":"{readStateIndex:29399; appliedIndex:29397; }","duration":"147.131344ms","start":"2026-09-19T11:29:01.033727Z","end":"2026-09-19T11:29:01.180859Z","steps":["trace[835525547] 'read index received' (duration: 17.252386ms)","trace[835525547] 'applied index is now lower than readState.Index' (duration: 129.877648ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T11:29:01.181193Z","caller":"traceutil/trace.go:171","msg":"trace[249550550] transaction","detail":"{read_only:false; response_revision:28454; number_of_response:1; }","duration":"147.4995ms","start":"2026-09-19T11:29:01.033653Z","end":"2026-09-19T11:29:01.181152Z","steps":["trace[249550550] 'process raft request' (duration: 126.423822ms)","trace[249550550] 'compare' (duration: 20.655801ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T11:29:01.181695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.939978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-09-19T11:29:01.181774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.518917ms","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-19T11:29:01.181836Z","caller":"traceutil/trace.go:171","msg":"trace[231460381] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:28454; }","duration":"141.67648ms","start":"2026-09-19T11:29:01.040147Z","end":"2026-09-19T11:29:01.181823Z","steps":["trace[231460381] 'agreement among raft nodes before linearized reading' (duration: 141.471126ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:29:01.181752Z","caller":"traceutil/trace.go:171","msg":"trace[1062507977] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:28454; }","duration":"148.033111ms","start":"2026-09-19T11:29:01.033705Z","end":"2026-09-19T11:29:01.181738Z","steps":["trace[1062507977] 'agreement among raft nodes before linearized reading' (duration: 147.832697ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T11:30:05.073668Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"40fb1eca294b6a5a","local-member-applied-index":30004,"local-member-snapshot-index":20003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-19T11:30:05.080889Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30004} {"level":"info","ts":"2026-09-19T11:30:05.080988Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25004} {"level":"info","ts":"2026-09-19T11:31:49.833872Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27206} {"level":"info","ts":"2026-09-19T11:31:50.006228Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27206,"took":"166.938689ms","hash":703881192,"current-db-size-bytes":59912192,"current-db-size":"60 MB","current-db-size-in-use-bytes":36470784,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-09-19T11:31:50.006314Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":703881192,"revision":27206,"compact-revision":23846} {"level":"info","ts":"2026-09-19T11:36:49.846928Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30091} {"level":"info","ts":"2026-09-19T11:36:50.015731Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30091,"took":"153.248979ms","hash":2718650969,"current-db-size-bytes":59912192,"current-db-size":"60 MB","current-db-size-in-use-bytes":31260672,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-19T11:36:50.015826Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2718650969,"revision":30091,"compact-revision":27206} {"level":"info","ts":"2026-09-19T11:41:49.856679Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32346} {"level":"info","ts":"2026-09-19T11:41:50.00706Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32346,"took":"145.595604ms","hash":1627778647,"current-db-size-bytes":60952576,"current-db-size":"61 MB","current-db-size-in-use-bytes":30269440,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-19T11:41:50.007394Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1627778647,"revision":32346,"compact-revision":30091} {"level":"info","ts":"2026-09-19T11:46:49.864348Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34574} {"level":"info","ts":"2026-09-19T11:46:49.995787Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34574,"took":"125.77007ms","hash":3221779673,"current-db-size-bytes":60952576,"current-db-size":"61 MB","current-db-size-in-use-bytes":30347264,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-19T11:46:49.995874Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3221779673,"revision":34574,"compact-revision":32346} {"level":"info","ts":"2026-09-19T11:50:58.813394Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"40fb1eca294b6a5a","local-member-applied-index":40005,"local-member-snapshot-index":30004,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-19T11:50:58.820595Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40005} {"level":"info","ts":"2026-09-19T11:50:58.820721Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35005} {"level":"info","ts":"2026-09-19T11:51:49.878073Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":36806} {"level":"info","ts":"2026-09-19T11:51:50.027934Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":36806,"took":"143.462205ms","hash":4156704781,"current-db-size-bytes":60952576,"current-db-size":"61 MB","current-db-size-in-use-bytes":32182272,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-19T11:51:50.028024Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4156704781,"revision":36806,"compact-revision":34574} {"level":"info","ts":"2026-09-19T11:56:49.886966Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39143} {"level":"info","ts":"2026-09-19T11:56:50.029038Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39143,"took":"135.911862ms","hash":4229133985,"current-db-size-bytes":61378560,"current-db-size":"61 MB","current-db-size-in-use-bytes":31993856,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-19T11:56:50.029108Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4229133985,"revision":39143,"compact-revision":36806} {"level":"info","ts":"2026-09-19T12:01:49.896057Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":41376} {"level":"info","ts":"2026-09-19T12:01:50.024883Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":41376,"took":"122.344494ms","hash":1312536875,"current-db-size-bytes":61378560,"current-db-size":"61 MB","current-db-size-in-use-bytes":30887936,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-19T12:01:50.024975Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1312536875,"revision":41376,"compact-revision":39143}