{"level":"warn","ts":"2026-10-19T01:56:48.998431Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-19T01:56:48.998552Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.14: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.14:2380","--initial-cluster=instance=https://162.253.55.14:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.14:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.14:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-10-19T01:56:48.99865Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-19T01:56:48.99867Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.14:2380"]} {"level":"info","ts":"2026-10-19T01:56:48.998706Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-19T01:56:48.999617Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.14:2379"]} {"level":"info","ts":"2026-10-19T01:56:48.999724Z","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.14:2380"],"listen-peer-urls":["https://162.253.55.14:2380"],"advertise-client-urls":["https://162.253.55.14:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.14:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.14:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-10-19T01:56:49.002782Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.745286ms"} {"level":"info","ts":"2026-10-19T01:56:49.00936Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5db0a1b394e67b72","cluster-id":"cfda6ccfe21bda40"} {"level":"info","ts":"2026-10-19T01:56:49.009464Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5db0a1b394e67b72 switched to configuration voters=()"} {"level":"info","ts":"2026-10-19T01:56:49.009496Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5db0a1b394e67b72 became follower at term 0"} {"level":"info","ts":"2026-10-19T01:56:49.009514Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5db0a1b394e67b72 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-19T01:56:49.009534Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5db0a1b394e67b72 became follower at term 1"} {"level":"info","ts":"2026-10-19T01:56:49.009582Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5db0a1b394e67b72 switched to configuration voters=(6751073634097724274)"} {"level":"warn","ts":"2026-10-19T01:56:49.020312Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-19T01:56:49.022756Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-19T01:56:49.024763Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-10-19T01:56:49.027268Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5db0a1b394e67b72","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-19T01:56:49.027575Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5db0a1b394e67b72","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-19T01:56:49.027714Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-19T01:56:49.027813Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-19T01:56:49.027829Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-19T01:56:49.029197Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5db0a1b394e67b72 switched to configuration voters=(6751073634097724274)"} {"level":"info","ts":"2026-10-19T01:56:49.029795Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cfda6ccfe21bda40","local-member-id":"5db0a1b394e67b72","added-peer-id":"5db0a1b394e67b72","added-peer-peer-urls":["https://162.253.55.14:2380"]} {"level":"info","ts":"2026-10-19T01:56:49.030584Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-19T01:56:49.030779Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5db0a1b394e67b72","initial-advertise-peer-urls":["https://162.253.55.14:2380"],"listen-peer-urls":["https://162.253.55.14:2380"],"advertise-client-urls":["https://162.253.55.14:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.14:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-19T01:56:49.030773Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.14:2380"} {"level":"info","ts":"2026-10-19T01:56:49.030807Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-19T01:56:49.030809Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.14:2380"} {"level":"info","ts":"2026-10-19T01:56:49.31112Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5db0a1b394e67b72 is starting a new election at term 1"} {"level":"info","ts":"2026-10-19T01:56:49.311155Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5db0a1b394e67b72 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-19T01:56:49.31117Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5db0a1b394e67b72 received MsgPreVoteResp from 5db0a1b394e67b72 at term 1"} {"level":"info","ts":"2026-10-19T01:56:49.311181Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5db0a1b394e67b72 became candidate at term 2"} {"level":"info","ts":"2026-10-19T01:56:49.311186Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5db0a1b394e67b72 received MsgVoteResp from 5db0a1b394e67b72 at term 2"} {"level":"info","ts":"2026-10-19T01:56:49.311194Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5db0a1b394e67b72 became leader at term 2"} {"level":"info","ts":"2026-10-19T01:56:49.311201Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5db0a1b394e67b72 elected leader 5db0a1b394e67b72 at term 2"} {"level":"info","ts":"2026-10-19T01:56:49.316938Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-19T01:56:49.319791Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-19T01:56:49.319887Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-19T01:56:49.31976Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5db0a1b394e67b72","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.14:2379]}","request-path":"/0/members/5db0a1b394e67b72/attributes","cluster-id":"cfda6ccfe21bda40","publish-timeout":"7s"} {"level":"info","ts":"2026-10-19T01:56:49.320244Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cfda6ccfe21bda40","local-member-id":"5db0a1b394e67b72","cluster-version":"3.5"} {"level":"info","ts":"2026-10-19T01:56:49.320439Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-19T01:56:49.32047Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-19T01:56:49.320475Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-19T01:56:49.320592Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-19T01:56:49.324249Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.14:2379"} {"level":"info","ts":"2026-10-19T01:56:49.324477Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-19T01:57:07.397914Z","caller":"traceutil/trace.go:171","msg":"trace[784683276] linearizableReadLoop","detail":"{readStateIndex:388; appliedIndex:387; }","duration":"116.956019ms","start":"2026-10-19T01:57:07.280934Z","end":"2026-10-19T01:57:07.39789Z","steps":["trace[784683276] 'read index received' (duration: 116.856796ms)","trace[784683276] 'applied index is now lower than readState.Index' (duration: 98.373µs)"],"step_count":2} {"level":"info","ts":"2026-10-19T01:57:07.398044Z","caller":"traceutil/trace.go:171","msg":"trace[1776129484] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"273.206951ms","start":"2026-10-19T01:57:07.124786Z","end":"2026-10-19T01:57:07.397993Z","steps":["trace[1776129484] 'process raft request' (duration: 272.954925ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:57:07.398214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.116623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-19T01:57:07.398291Z","caller":"traceutil/trace.go:171","msg":"trace[1858401066] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:373; }","duration":"117.41816ms","start":"2026-10-19T01:57:07.28086Z","end":"2026-10-19T01:57:07.398278Z","steps":["trace[1858401066] 'agreement among raft nodes before linearized reading' (duration: 117.144283ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:57:13.046298Z","caller":"traceutil/trace.go:171","msg":"trace[818439644] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"150.130444ms","start":"2026-10-19T01:57:12.8954Z","end":"2026-10-19T01:57:13.045531Z","steps":["trace[818439644] 'process raft request' (duration: 149.720244ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:57:42.532478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.052043ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-19T01:57:42.532622Z","caller":"traceutil/trace.go:171","msg":"trace[351043666] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:638; }","duration":"373.65361ms","start":"2026-10-19T01:57:42.158948Z","end":"2026-10-19T01:57:42.532602Z","steps":["trace[351043666] 'read index received' (duration: 168.443018ms)","trace[351043666] 'applied index is now lower than readState.Index' (duration: 205.208802ms)"],"step_count":2} {"level":"warn","ts":"2026-10-19T01:57:42.532727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.782992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-f4kdq\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-10-19T01:57:42.532728Z","caller":"traceutil/trace.go:171","msg":"trace[1986421332] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"393.002474ms","start":"2026-10-19T01:57:42.139661Z","end":"2026-10-19T01:57:42.532663Z","steps":["trace[1986421332] 'process raft request' (duration: 188.007508ms)","trace[1986421332] 'compare' (duration: 203.025219ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T01:57:42.532785Z","caller":"traceutil/trace.go:171","msg":"trace[2051730580] range","detail":"{range_begin:/registry/pods/kube-system/cilium-f4kdq; range_end:; response_count:1; response_revision:612; }","duration":"373.880735ms","start":"2026-10-19T01:57:42.15889Z","end":"2026-10-19T01:57:42.53277Z","steps":["trace[2051730580] 'agreement among raft nodes before linearized reading' (duration: 373.764752ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:57:42.533744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T01:57:42.158871Z","time spent":"374.575622ms","remote":"127.0.0.1:39466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-f4kdq\" "} {"level":"warn","ts":"2026-10-19T01:57:42.533871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T01:57:42.139644Z","time spent":"393.247041ms","remote":"127.0.0.1:39388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036265,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-19T01:57:42.915611Z","caller":"traceutil/trace.go:171","msg":"trace[1278455467] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"421.663923ms","start":"2026-10-19T01:57:42.493904Z","end":"2026-10-19T01:57:42.915568Z","steps":["trace[1278455467] 'process raft request' (duration: 386.380075ms)","trace[1278455467] 'compare' (duration: 35.108484ms)"],"step_count":2} {"level":"warn","ts":"2026-10-19T01:57:42.915819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T01:57:42.493868Z","time spent":"421.841607ms","remote":"127.0.0.1:39550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-19T01:57:43.659492Z","caller":"traceutil/trace.go:171","msg":"trace[1480165224] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"1.08860969s","start":"2026-10-19T01:57:42.57084Z","end":"2026-10-19T01:57:43.65945Z","steps":["trace[1480165224] 'process raft request' (duration: 996.423605ms)","trace[1480165224] 'compare' (duration: 91.860958ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T01:57:43.659628Z","caller":"traceutil/trace.go:171","msg":"trace[1198766123] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:639; }","duration":"926.191817ms","start":"2026-10-19T01:57:42.733384Z","end":"2026-10-19T01:57:43.659576Z","steps":["trace[1198766123] 'read index received' (duration: 146.940091ms)","trace[1198766123] 'applied index is now lower than readState.Index' (duration: 779.248746ms)"],"step_count":2} {"level":"warn","ts":"2026-10-19T01:57:43.659694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T01:57:42.57081Z","time spent":"1.088779815s","remote":"127.0.0.1:39466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-19T01:57:43.659823Z","caller":"traceutil/trace.go:171","msg":"trace[1633234167] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"1.036048258s","start":"2026-10-19T01:57:42.623762Z","end":"2026-10-19T01:57:43.65981Z","steps":["trace[1633234167] 'process raft request' (duration: 1.035582747s)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:57:43.659964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T01:57:42.623739Z","time spent":"1.036124249s","remote":"127.0.0.1:39642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-19T01:57:43.659988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"926.588417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-19T01:57:43.660063Z","caller":"traceutil/trace.go:171","msg":"trace[661526189] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:615; }","duration":"926.689279ms","start":"2026-10-19T01:57:42.733347Z","end":"2026-10-19T01:57:43.660036Z","steps":["trace[661526189] 'agreement among raft nodes before linearized reading' (duration: 926.538546ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:57:43.660118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T01:57:42.733327Z","time spent":"926.778461ms","remote":"127.0.0.1:39306","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-10-19T01:57:43.660904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.678993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-19T01:57:43.66101Z","caller":"traceutil/trace.go:171","msg":"trace[2145248758] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:615; }","duration":"334.828746ms","start":"2026-10-19T01:57:43.326154Z","end":"2026-10-19T01:57:43.660983Z","steps":["trace[2145248758] 'agreement among raft nodes before linearized reading' (duration: 334.677553ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:57:43.661056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T01:57:43.326116Z","time spent":"334.930729ms","remote":"127.0.0.1:39550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-19T01:57:43.660786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.322553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.14\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-10-19T01:57:43.66192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.729184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-10-19T01:57:43.661939Z","caller":"traceutil/trace.go:171","msg":"trace[1010542213] range","detail":"{range_begin:/registry/masterleases/162.253.55.14; range_end:; response_count:1; response_revision:615; }","duration":"687.505071ms","start":"2026-10-19T01:57:42.97441Z","end":"2026-10-19T01:57:43.661915Z","steps":["trace[1010542213] 'agreement among raft nodes before linearized reading' (duration: 686.235781ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:57:43.661989Z","caller":"traceutil/trace.go:171","msg":"trace[1862863784] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:615; }","duration":"396.831666ms","start":"2026-10-19T01:57:43.265136Z","end":"2026-10-19T01:57:43.661968Z","steps":["trace[1862863784] 'agreement among raft nodes before linearized reading' (duration: 396.690882ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:57:43.662058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T01:57:43.265115Z","time spent":"396.930849ms","remote":"127.0.0.1:39550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-19T01:57:43.661995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T01:57:42.974254Z","time spent":"687.724487ms","remote":"127.0.0.1:39336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/162.253.55.14\" "} {"level":"info","ts":"2026-10-19T01:57:49.358479Z","caller":"traceutil/trace.go:171","msg":"trace[903823547] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"120.828274ms","start":"2026-10-19T01:57:49.23762Z","end":"2026-10-19T01:57:49.358448Z","steps":["trace[903823547] 'process raft request' (duration: 120.145138ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:57:59.996573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.232589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-19T01:57:59.996616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.6694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-19T01:57:59.996646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.841993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-19T01:57:59.99669Z","caller":"traceutil/trace.go:171","msg":"trace[1882827950] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:784; }","duration":"264.364152ms","start":"2026-10-19T01:57:59.732296Z","end":"2026-10-19T01:57:59.99666Z","steps":["trace[1882827950] 'range keys from in-memory index tree' (duration: 264.169377ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:57:59.996734Z","caller":"traceutil/trace.go:171","msg":"trace[800134630] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:784; }","duration":"264.980166ms","start":"2026-10-19T01:57:59.731723Z","end":"2026-10-19T01:57:59.996703Z","steps":["trace[800134630] 'range keys from in-memory index tree' (duration: 264.768212ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:57:59.996791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.057116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-19T01:57:59.996771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.152582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-19T01:57:59.996814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.813263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-19T01:57:59.996853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.228641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-19T01:57:59.996771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.711979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-19T01:57:59.996907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.167161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-19T01:57:59.99691Z","caller":"traceutil/trace.go:171","msg":"trace[849984080] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:784; }","duration":"443.330343ms","start":"2026-10-19T01:57:59.553561Z","end":"2026-10-19T01:57:59.996891Z","steps":["trace[849984080] 'range keys from in-memory index tree' (duration: 443.042276ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:57:59.996926Z","caller":"traceutil/trace.go:171","msg":"trace[1143499417] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:784; }","duration":"265.208343ms","start":"2026-10-19T01:57:59.731713Z","end":"2026-10-19T01:57:59.996921Z","steps":["trace[1143499417] 'count revisions from in-memory index tree' (duration: 265.068929ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:57:59.996914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.563653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-19T01:57:59.997347Z","caller":"traceutil/trace.go:171","msg":"trace[1495166444] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:784; }","duration":"212.012224ms","start":"2026-10-19T01:57:59.785317Z","end":"2026-10-19T01:57:59.997329Z","steps":["trace[1495166444] 'range keys from in-memory index tree' (duration: 211.503422ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:57:59.997368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.003219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-19T01:57:59.996857Z","caller":"traceutil/trace.go:171","msg":"trace[685344059] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:784; }","duration":"443.137938ms","start":"2026-10-19T01:57:59.553692Z","end":"2026-10-19T01:57:59.99683Z","steps":["trace[685344059] 'count revisions from in-memory index tree' (duration: 442.989085ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:57:59.997444Z","caller":"traceutil/trace.go:171","msg":"trace[2074473907] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:784; }","duration":"243.087631ms","start":"2026-10-19T01:57:59.754334Z","end":"2026-10-19T01:57:59.997422Z","steps":["trace[2074473907] 'range keys from in-memory index tree' (duration: 242.535618ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:57:59.997474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T01:57:59.553684Z","time spent":"443.767183ms","remote":"127.0.0.1:46114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-10-19T01:57:59.996914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.578338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-19T01:57:59.997556Z","caller":"traceutil/trace.go:171","msg":"trace[129641256] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:784; }","duration":"404.225033ms","start":"2026-10-19T01:57:59.5933Z","end":"2026-10-19T01:57:59.997525Z","steps":["trace[129641256] 'range keys from in-memory index tree' (duration: 403.538997ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:57:59.997601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T01:57:59.593267Z","time spent":"404.315495ms","remote":"127.0.0.1:46124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 "} {"level":"info","ts":"2026-10-19T01:57:59.99687Z","caller":"traceutil/trace.go:171","msg":"trace[197935970] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:784; }","duration":"403.916486ms","start":"2026-10-19T01:57:59.59293Z","end":"2026-10-19T01:57:59.996847Z","steps":["trace[197935970] 'range keys from in-memory index tree' (duration: 403.751982ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:57:59.997775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T01:57:59.592917Z","time spent":"404.847098ms","remote":"127.0.0.1:46124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 "} {"level":"info","ts":"2026-10-19T01:57:59.996919Z","caller":"traceutil/trace.go:171","msg":"trace[2095809868] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:784; }","duration":"420.910224ms","start":"2026-10-19T01:57:59.57599Z","end":"2026-10-19T01:57:59.9969Z","steps":["trace[2095809868] 'range keys from in-memory index tree' (duration: 420.630238ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:57:59.997843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T01:57:59.575972Z","time spent":"421.844437ms","remote":"127.0.0.1:46116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 "} {"level":"info","ts":"2026-10-19T01:57:59.996862Z","caller":"traceutil/trace.go:171","msg":"trace[1547841032] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:784; }","duration":"243.278506ms","start":"2026-10-19T01:57:59.753562Z","end":"2026-10-19T01:57:59.99684Z","steps":["trace[1547841032] 'range keys from in-memory index tree' (duration: 243.105621ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:57:59.996942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.187719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-19T01:57:59.998033Z","caller":"traceutil/trace.go:171","msg":"trace[1804489208] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:784; }","duration":"213.293215ms","start":"2026-10-19T01:57:59.784729Z","end":"2026-10-19T01:57:59.998023Z","steps":["trace[1804489208] 'count revisions from in-memory index tree' (duration: 212.141357ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:57:59.99696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T01:57:59.553538Z","time spent":"443.405965ms","remote":"127.0.0.1:46114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 "} {"level":"warn","ts":"2026-10-19T01:57:59.99698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.585909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-19T01:57:59.99822Z","caller":"traceutil/trace.go:171","msg":"trace[475964389] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:784; }","duration":"243.851759ms","start":"2026-10-19T01:57:59.754357Z","end":"2026-10-19T01:57:59.998209Z","steps":["trace[475964389] 'range keys from in-memory index tree' (duration: 242.462565ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:57:59.996983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.804312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-19T01:57:59.998287Z","caller":"traceutil/trace.go:171","msg":"trace[1153970090] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:784; }","duration":"422.113553ms","start":"2026-10-19T01:57:59.576154Z","end":"2026-10-19T01:57:59.998268Z","steps":["trace[1153970090] 'count revisions from in-memory index tree' (duration: 420.756301ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:57:59.998308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T01:57:59.576148Z","time spent":"422.150564ms","remote":"127.0.0.1:46116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"warn","ts":"2026-10-19T01:57:59.997004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.468429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-19T01:57:59.998363Z","caller":"traceutil/trace.go:171","msg":"trace[1776929424] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:784; }","duration":"244.828463ms","start":"2026-10-19T01:57:59.753515Z","end":"2026-10-19T01:57:59.998344Z","steps":["trace[1776929424] 'count revisions from in-memory index tree' (duration: 243.345777ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:57:59.99701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.596497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-19T01:57:59.99847Z","caller":"traceutil/trace.go:171","msg":"trace[1274458585] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:784; }","duration":"422.077882ms","start":"2026-10-19T01:57:59.576375Z","end":"2026-10-19T01:57:59.998453Z","steps":["trace[1274458585] 'range keys from in-memory index tree' (duration: 420.515224ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:57:59.998531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T01:57:59.576359Z","time spent":"422.154064ms","remote":"127.0.0.1:46116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 "} {"level":"warn","ts":"2026-10-19T01:57:59.997061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.541907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-19T01:57:59.998637Z","caller":"traceutil/trace.go:171","msg":"trace[1131160975] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:784; }","duration":"214.150535ms","start":"2026-10-19T01:57:59.784474Z","end":"2026-10-19T01:57:59.998624Z","steps":["trace[1131160975] 'range keys from in-memory index tree' (duration: 212.473055ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:57:59.997074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.988667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-19T01:57:59.99869Z","caller":"traceutil/trace.go:171","msg":"trace[2079085818] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:784; }","duration":"266.625596ms","start":"2026-10-19T01:57:59.732055Z","end":"2026-10-19T01:57:59.998681Z","steps":["trace[2079085818] 'range keys from in-memory index tree' (duration: 264.943986ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:57:59.997121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.194823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-19T01:57:59.998766Z","caller":"traceutil/trace.go:171","msg":"trace[398263340] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:784; }","duration":"229.854783ms","start":"2026-10-19T01:57:59.768868Z","end":"2026-10-19T01:57:59.998722Z","steps":["trace[398263340] 'range keys from in-memory index tree' (duration: 228.018528ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:57:59.996692Z","caller":"traceutil/trace.go:171","msg":"trace[521858240] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:784; }","duration":"403.795223ms","start":"2026-10-19T01:57:59.592879Z","end":"2026-10-19T01:57:59.996674Z","steps":["trace[521858240] 'count revisions from in-memory index tree' (duration: 403.592818ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:57:59.998871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T01:57:59.592863Z","time spent":"405.998045ms","remote":"127.0.0.1:46124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-10-19T01:57:59.997206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.832739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-19T01:57:59.998965Z","caller":"traceutil/trace.go:171","msg":"trace[1487522675] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:784; }","duration":"151.608202ms","start":"2026-10-19T01:57:59.847342Z","end":"2026-10-19T01:57:59.99895Z","steps":["trace[1487522675] 'range keys from in-memory index tree' (duration: 149.610094ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:58:30.615794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.356255ms","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-10-19T01:58:30.615884Z","caller":"traceutil/trace.go:171","msg":"trace[1788161788] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1186; }","duration":"200.512369ms","start":"2026-10-19T01:58:30.415353Z","end":"2026-10-19T01:58:30.615865Z","steps":["trace[1788161788] 'range keys from in-memory index tree' (duration: 200.16265ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:58:35.483501Z","caller":"traceutil/trace.go:171","msg":"trace[728472976] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"101.395776ms","start":"2026-10-19T01:58:35.382071Z","end":"2026-10-19T01:58:35.483466Z","steps":["trace[728472976] 'process raft request' (duration: 101.12294ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:58:35.483723Z","caller":"traceutil/trace.go:171","msg":"trace[990449328] transaction","detail":"{read_only:false; response_revision:1229; number_of_response:1; }","duration":"107.411502ms","start":"2026-10-19T01:58:35.376269Z","end":"2026-10-19T01:58:35.48368Z","steps":["trace[990449328] 'process raft request' (duration: 105.066815ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:58:51.793684Z","caller":"traceutil/trace.go:171","msg":"trace[2094506134] transaction","detail":"{read_only:false; response_revision:1438; number_of_response:1; }","duration":"186.626027ms","start":"2026-10-19T01:58:51.607026Z","end":"2026-10-19T01:58:51.793652Z","steps":["trace[2094506134] 'process raft request' (duration: 175.800209ms)","trace[2094506134] 'compare' (duration: 10.560442ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T01:59:01.39754Z","caller":"traceutil/trace.go:171","msg":"trace[777458755] transaction","detail":"{read_only:false; response_revision:1585; number_of_response:1; }","duration":"123.604725ms","start":"2026-10-19T01:59:01.273907Z","end":"2026-10-19T01:59:01.397512Z","steps":["trace[777458755] 'process raft request' (duration: 70.853658ms)","trace[777458755] 'compare' (duration: 52.524142ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T01:59:01.397682Z","caller":"traceutil/trace.go:171","msg":"trace[1060141191] transaction","detail":"{read_only:false; response_revision:1587; number_of_response:1; }","duration":"121.616318ms","start":"2026-10-19T01:59:01.27605Z","end":"2026-10-19T01:59:01.397666Z","steps":["trace[1060141191] 'process raft request' (duration: 121.395812ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:59:01.397807Z","caller":"traceutil/trace.go:171","msg":"trace[404987112] transaction","detail":"{read_only:false; response_revision:1586; number_of_response:1; }","duration":"122.032088ms","start":"2026-10-19T01:59:01.27571Z","end":"2026-10-19T01:59:01.397742Z","steps":["trace[404987112] 'process raft request' (duration: 121.69877ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:59:01.397989Z","caller":"traceutil/trace.go:171","msg":"trace[648362808] linearizableReadLoop","detail":"{readStateIndex:1640; appliedIndex:1637; }","duration":"120.134432ms","start":"2026-10-19T01:59:01.277842Z","end":"2026-10-19T01:59:01.397976Z","steps":["trace[648362808] 'read index received' (duration: 14.063035ms)","trace[648362808] 'applied index is now lower than readState.Index' (duration: 106.070207ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T01:59:01.3981Z","caller":"traceutil/trace.go:171","msg":"trace[221354400] transaction","detail":"{read_only:false; response_revision:1588; number_of_response:1; }","duration":"119.123369ms","start":"2026-10-19T01:59:01.278916Z","end":"2026-10-19T01:59:01.398039Z","steps":["trace[221354400] 'process raft request' (duration: 118.608306ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:59:01.398294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.432959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-messaging-topology-operator-69996b7f7f-4rzm6\" ","response":"range_response_count:1 size:4254"} {"level":"info","ts":"2026-10-19T01:59:01.398346Z","caller":"traceutil/trace.go:171","msg":"trace[1749733577] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-messaging-topology-operator-69996b7f7f-4rzm6; range_end:; response_count:1; response_revision:1589; }","duration":"120.578453ms","start":"2026-10-19T01:59:01.27775Z","end":"2026-10-19T01:59:01.398329Z","steps":["trace[1749733577] 'agreement among raft nodes before linearized reading' (duration: 120.291926ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:59:01.418664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/openstack/rabbitmq-messaging-topology-operator-69996b7f7f\" ","response":"range_response_count:1 size:5187"} {"level":"info","ts":"2026-10-19T01:59:01.418762Z","caller":"traceutil/trace.go:171","msg":"trace[772571202] range","detail":"{range_begin:/registry/replicasets/openstack/rabbitmq-messaging-topology-operator-69996b7f7f; range_end:; response_count:1; response_revision:1590; }","duration":"139.482723ms","start":"2026-10-19T01:59:01.279253Z","end":"2026-10-19T01:59:01.418736Z","steps":["trace[772571202] 'agreement among raft nodes before linearized reading' (duration: 139.209877ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:59:14.762245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.266966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:552"} {"level":"info","ts":"2026-10-19T01:59:14.762377Z","caller":"traceutil/trace.go:171","msg":"trace[658230518] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1827; }","duration":"188.46099ms","start":"2026-10-19T01:59:14.573885Z","end":"2026-10-19T01:59:14.762346Z","steps":["trace[658230518] 'range keys from in-memory index tree' (duration: 188.044101ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:59:15.309762Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8895349587035750525,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-10-19T01:59:15.79586Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.026560998s","expected-duration":"1s"} {"level":"info","ts":"2026-10-19T01:59:15.796476Z","caller":"traceutil/trace.go:171","msg":"trace[2062396525] transaction","detail":"{read_only:false; response_revision:1828; number_of_response:1; }","duration":"1.027403758s","start":"2026-10-19T01:59:14.76904Z","end":"2026-10-19T01:59:15.796443Z","steps":["trace[2062396525] 'process raft request' (duration: 1.027143212s)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:59:15.796667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T01:59:14.76901Z","time spent":"1.027538272s","remote":"127.0.0.1:39392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-19T01:59:15.94906Z","caller":"traceutil/trace.go:171","msg":"trace[259242598] transaction","detail":"{read_only:false; response_revision:1829; number_of_response:1; }","duration":"1.167961037s","start":"2026-10-19T01:59:14.781068Z","end":"2026-10-19T01:59:15.949029Z","steps":["trace[259242598] 'process raft request' (duration: 1.167464286s)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:59:15.949211Z","caller":"traceutil/trace.go:171","msg":"trace[802848937] linearizableReadLoop","detail":"{readStateIndex:1890; appliedIndex:1887; }","duration":"1.140198186s","start":"2026-10-19T01:59:14.808988Z","end":"2026-10-19T01:59:15.949186Z","steps":["trace[802848937] 'read index received' (duration: 987.129779ms)","trace[802848937] 'applied index is now lower than readState.Index' (duration: 153.066807ms)"],"step_count":2} {"level":"warn","ts":"2026-10-19T01:59:15.949298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T01:59:14.781028Z","time spent":"1.168145542s","remote":"127.0.0.1:39466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9689,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-19T01:59:15.949342Z","caller":"traceutil/trace.go:171","msg":"trace[883744532] transaction","detail":"{read_only:false; response_revision:1830; number_of_response:1; }","duration":"1.075495024s","start":"2026-10-19T01:59:14.873829Z","end":"2026-10-19T01:59:15.949324Z","steps":["trace[883744532] 'process raft request' (duration: 1.074938171s)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:59:15.949495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T01:59:14.8738Z","time spent":"1.075577936s","remote":"127.0.0.1:39550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-19T01:59:15.94966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.140660947s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-19T01:59:15.949707Z","caller":"traceutil/trace.go:171","msg":"trace[74684520] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1830; }","duration":"1.140741258s","start":"2026-10-19T01:59:14.808952Z","end":"2026-10-19T01:59:15.949693Z","steps":["trace[74684520] 'agreement among raft nodes before linearized reading' (duration: 1.140662406s)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:59:15.94974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T01:59:14.808938Z","time spent":"1.14079307s","remote":"127.0.0.1:46158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-10-19T01:59:15.949725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.558329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:552"} {"level":"info","ts":"2026-10-19T01:59:15.949815Z","caller":"traceutil/trace.go:171","msg":"trace[139260130] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1830; }","duration":"148.698063ms","start":"2026-10-19T01:59:15.801097Z","end":"2026-10-19T01:59:15.949795Z","steps":["trace[139260130] 'agreement among raft nodes before linearized reading' (duration: 148.475367ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:59:15.949879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.392279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4807"} {"level":"warn","ts":"2026-10-19T01:59:15.9499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"839.41459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-822b9456-c220-492a-95e2-b2b5b9ddb453\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-10-19T01:59:15.949951Z","caller":"traceutil/trace.go:171","msg":"trace[1329479927] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1830; }","duration":"533.520762ms","start":"2026-10-19T01:59:15.416412Z","end":"2026-10-19T01:59:15.949933Z","steps":["trace[1329479927] 'agreement among raft nodes before linearized reading' (duration: 533.348548ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:59:15.949972Z","caller":"traceutil/trace.go:171","msg":"trace[426208994] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-822b9456-c220-492a-95e2-b2b5b9ddb453; range_end:; response_count:1; response_revision:1830; }","duration":"839.547512ms","start":"2026-10-19T01:59:15.110404Z","end":"2026-10-19T01:59:15.949951Z","steps":["trace[426208994] 'agreement among raft nodes before linearized reading' (duration: 839.360108ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:59:15.94999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T01:59:15.41638Z","time spent":"533.601563ms","remote":"127.0.0.1:47110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":4830,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-10-19T01:59:15.950018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T01:59:15.110373Z","time spent":"839.632515ms","remote":"127.0.0.1:39466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-822b9456-c220-492a-95e2-b2b5b9ddb453\" "} {"level":"warn","ts":"2026-10-19T01:59:15.950449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.046869302s","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-10-19T01:59:15.9505Z","caller":"traceutil/trace.go:171","msg":"trace[1213509752] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1830; }","duration":"1.046954424s","start":"2026-10-19T01:59:14.90353Z","end":"2026-10-19T01:59:15.950484Z","steps":["trace[1213509752] 'agreement among raft nodes before linearized reading' (duration: 1.045827497s)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:59:15.950541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T01:59:14.903506Z","time spent":"1.047024725s","remote":"127.0.0.1:39550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-19T01:59:16.155564Z","caller":"traceutil/trace.go:171","msg":"trace[943907882] transaction","detail":"{read_only:false; response_revision:1832; number_of_response:1; }","duration":"200.416735ms","start":"2026-10-19T01:59:15.955116Z","end":"2026-10-19T01:59:16.155533Z","steps":["trace[943907882] 'process raft request' (duration: 187.955968ms)","trace[943907882] 'compare' (duration: 12.053037ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T01:59:16.156286Z","caller":"traceutil/trace.go:171","msg":"trace[1721358286] transaction","detail":"{read_only:false; response_revision:1834; number_of_response:1; }","duration":"200.538339ms","start":"2026-10-19T01:59:15.955734Z","end":"2026-10-19T01:59:16.156272Z","steps":["trace[1721358286] 'process raft request' (duration: 200.357244ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:59:16.15637Z","caller":"traceutil/trace.go:171","msg":"trace[1388444832] linearizableReadLoop","detail":"{readStateIndex:1895; appliedIndex:1890; }","duration":"199.505564ms","start":"2026-10-19T01:59:15.956859Z","end":"2026-10-19T01:59:16.156364Z","steps":["trace[1388444832] 'read index received' (duration: 28.021918ms)","trace[1388444832] 'applied index is now lower than readState.Index' (duration: 171.482686ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T01:59:16.156338Z","caller":"traceutil/trace.go:171","msg":"trace[547075074] transaction","detail":"{read_only:false; response_revision:1833; number_of_response:1; }","duration":"201.076752ms","start":"2026-10-19T01:59:15.955195Z","end":"2026-10-19T01:59:16.156272Z","steps":["trace[547075074] 'process raft request' (duration: 200.765534ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:59:16.156475Z","caller":"traceutil/trace.go:171","msg":"trace[955694177] transaction","detail":"{read_only:false; response_revision:1835; number_of_response:1; }","duration":"200.453856ms","start":"2026-10-19T01:59:15.955849Z","end":"2026-10-19T01:59:16.156303Z","steps":["trace[955694177] 'process raft request' (duration: 200.270592ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:59:16.156608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.745259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:9704"} {"level":"info","ts":"2026-10-19T01:59:16.156642Z","caller":"traceutil/trace.go:171","msg":"trace[1866657515] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:1837; }","duration":"199.836971ms","start":"2026-10-19T01:59:15.956791Z","end":"2026-10-19T01:59:16.156628Z","steps":["trace[1866657515] 'agreement among raft nodes before linearized reading' (duration: 199.595235ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:59:16.156724Z","caller":"traceutil/trace.go:171","msg":"trace[2010585977] transaction","detail":"{read_only:false; response_revision:1837; number_of_response:1; }","duration":"199.236237ms","start":"2026-10-19T01:59:15.957465Z","end":"2026-10-19T01:59:16.156701Z","steps":["trace[2010585977] 'process raft request' (duration: 198.725115ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:59:16.157186Z","caller":"traceutil/trace.go:171","msg":"trace[1141665969] transaction","detail":"{read_only:false; response_revision:1836; number_of_response:1; }","duration":"199.862242ms","start":"2026-10-19T01:59:15.957256Z","end":"2026-10-19T01:59:16.157118Z","steps":["trace[1141665969] 'process raft request' (duration: 198.906849ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:00:35.673354Z","caller":"traceutil/trace.go:171","msg":"trace[1210915826] transaction","detail":"{read_only:false; response_revision:2477; number_of_response:1; }","duration":"129.91536ms","start":"2026-10-19T02:00:35.543401Z","end":"2026-10-19T02:00:35.673316Z","steps":["trace[1210915826] 'process raft request' (duration: 129.653694ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:01:10.236836Z","caller":"traceutil/trace.go:171","msg":"trace[1510139052] transaction","detail":"{read_only:false; response_revision:2712; number_of_response:1; }","duration":"165.895869ms","start":"2026-10-19T02:01:10.07092Z","end":"2026-10-19T02:01:10.236816Z","steps":["trace[1510139052] 'process raft request' (duration: 165.773266ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:01:10.264982Z","caller":"traceutil/trace.go:171","msg":"trace[2073255333] transaction","detail":"{read_only:false; response_revision:2713; number_of_response:1; }","duration":"151.164677ms","start":"2026-10-19T02:01:10.113776Z","end":"2026-10-19T02:01:10.26494Z","steps":["trace[2073255333] 'process raft request' (duration: 150.890201ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:01:10.414095Z","caller":"traceutil/trace.go:171","msg":"trace[530034780] linearizableReadLoop","detail":"{readStateIndex:2824; appliedIndex:2823; }","duration":"115.435583ms","start":"2026-10-19T02:01:10.298628Z","end":"2026-10-19T02:01:10.414064Z","steps":["trace[530034780] 'read index received' (duration: 115.204657ms)","trace[530034780] 'applied index is now lower than readState.Index' (duration: 229.616µs)"],"step_count":2} {"level":"warn","ts":"2026-10-19T02:01:10.414242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.590406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-19T02:01:10.414289Z","caller":"traceutil/trace.go:171","msg":"trace[1739396690] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2714; }","duration":"115.678008ms","start":"2026-10-19T02:01:10.298598Z","end":"2026-10-19T02:01:10.414276Z","steps":["trace[1739396690] 'agreement among raft nodes before linearized reading' (duration: 115.586846ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:01:10.414259Z","caller":"traceutil/trace.go:171","msg":"trace[171706081] transaction","detail":"{read_only:false; response_revision:2714; number_of_response:1; }","duration":"144.450127ms","start":"2026-10-19T02:01:10.269779Z","end":"2026-10-19T02:01:10.414229Z","steps":["trace[171706081] 'process raft request' (duration: 144.047797ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:01:10.719123Z","caller":"traceutil/trace.go:171","msg":"trace[207116654] linearizableReadLoop","detail":"{readStateIndex:2825; appliedIndex:2824; }","duration":"121.996399ms","start":"2026-10-19T02:01:10.597078Z","end":"2026-10-19T02:01:10.719075Z","steps":["trace[207116654] 'read index received' (duration: 121.831355ms)","trace[207116654] 'applied index is now lower than readState.Index' (duration: 163.954µs)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:01:10.719163Z","caller":"traceutil/trace.go:171","msg":"trace[1392326406] transaction","detail":"{read_only:false; response_revision:2715; number_of_response:1; }","duration":"133.893095ms","start":"2026-10-19T02:01:10.585244Z","end":"2026-10-19T02:01:10.719137Z","steps":["trace[1392326406] 'process raft request' (duration: 133.657689ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:01:10.719271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.176804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-19T02:01:10.719308Z","caller":"traceutil/trace.go:171","msg":"trace[1439368134] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:2715; }","duration":"122.267686ms","start":"2026-10-19T02:01:10.597027Z","end":"2026-10-19T02:01:10.719295Z","steps":["trace[1439368134] 'agreement among raft nodes before linearized reading' (duration: 122.171374ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:03:41.405012Z","caller":"traceutil/trace.go:171","msg":"trace[1403099444] transaction","detail":"{read_only:false; response_revision:4513; number_of_response:1; }","duration":"105.998637ms","start":"2026-10-19T02:03:41.298989Z","end":"2026-10-19T02:03:41.404987Z","steps":["trace[1403099444] 'process raft request' (duration: 105.774511ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:03:46.125311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.862336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:790736"} {"level":"info","ts":"2026-10-19T02:03:46.125388Z","caller":"traceutil/trace.go:171","msg":"trace[1811935720] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:4642; }","duration":"118.979299ms","start":"2026-10-19T02:03:46.006392Z","end":"2026-10-19T02:03:46.125372Z","steps":["trace[1811935720] 'range keys from in-memory index tree' (duration: 118.384534ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:03:46.329276Z","caller":"traceutil/trace.go:171","msg":"trace[1037483836] transaction","detail":"{read_only:false; response_revision:4644; number_of_response:1; }","duration":"195.276233ms","start":"2026-10-19T02:03:46.133966Z","end":"2026-10-19T02:03:46.329243Z","steps":["trace[1037483836] 'process raft request' (duration: 195.042047ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:03:46.329403Z","caller":"traceutil/trace.go:171","msg":"trace[158759059] linearizableReadLoop","detail":"{readStateIndex:4849; appliedIndex:4847; }","duration":"153.941889ms","start":"2026-10-19T02:03:46.175437Z","end":"2026-10-19T02:03:46.329378Z","steps":["trace[158759059] 'read index received' (duration: 153.519949ms)","trace[158759059] 'applied index is now lower than readState.Index' (duration: 420.71µs)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:03:46.329519Z","caller":"traceutil/trace.go:171","msg":"trace[1275839382] transaction","detail":"{read_only:false; response_revision:4645; number_of_response:1; }","duration":"194.658757ms","start":"2026-10-19T02:03:46.134849Z","end":"2026-10-19T02:03:46.329508Z","steps":["trace[1275839382] 'process raft request' (duration: 194.316139ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:03:46.329664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.582347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-10-19T02:03:46.329779Z","caller":"traceutil/trace.go:171","msg":"trace[505765711] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4645; }","duration":"130.737011ms","start":"2026-10-19T02:03:46.199021Z","end":"2026-10-19T02:03:46.329758Z","steps":["trace[505765711] 'agreement among raft nodes before linearized reading' (duration: 130.508716ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:03:46.329939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.479681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:1 size:894"} {"level":"info","ts":"2026-10-19T02:03:46.330018Z","caller":"traceutil/trace.go:171","msg":"trace[1713521255] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:1; response_revision:4645; }","duration":"154.577334ms","start":"2026-10-19T02:03:46.175424Z","end":"2026-10-19T02:03:46.330001Z","steps":["trace[1713521255] 'agreement among raft nodes before linearized reading' (duration: 154.303237ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:03:48.393897Z","caller":"traceutil/trace.go:171","msg":"trace[2107048154] transaction","detail":"{read_only:false; response_revision:4670; number_of_response:1; }","duration":"148.296063ms","start":"2026-10-19T02:03:48.245565Z","end":"2026-10-19T02:03:48.393861Z","steps":["trace[2107048154] 'process raft request' (duration: 148.15984ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:03:48.393989Z","caller":"traceutil/trace.go:171","msg":"trace[1326909363] transaction","detail":"{read_only:false; response_revision:4669; number_of_response:1; }","duration":"158.472738ms","start":"2026-10-19T02:03:48.23548Z","end":"2026-10-19T02:03:48.393953Z","steps":["trace[1326909363] 'process raft request' (duration: 158.018147ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:04:02.328156Z","caller":"traceutil/trace.go:171","msg":"trace[1597680119] linearizableReadLoop","detail":"{readStateIndex:5146; appliedIndex:5145; }","duration":"351.465103ms","start":"2026-10-19T02:04:01.976664Z","end":"2026-10-19T02:04:02.328129Z","steps":["trace[1597680119] 'read index received' (duration: 351.305709ms)","trace[1597680119] 'applied index is now lower than readState.Index' (duration: 158.084µs)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:04:02.328221Z","caller":"traceutil/trace.go:171","msg":"trace[143387920] transaction","detail":"{read_only:false; response_revision:4931; number_of_response:1; }","duration":"379.428557ms","start":"2026-10-19T02:04:01.948758Z","end":"2026-10-19T02:04:02.328186Z","steps":["trace[143387920] 'process raft request' (duration: 379.186692ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:04:02.328427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:04:01.948735Z","time spent":"379.572721ms","remote":"127.0.0.1:39466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11654,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-19T02:04:02.328467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.423018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-10-19T02:04:02.328444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.75825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:524"} {"level":"info","ts":"2026-10-19T02:04:02.328601Z","caller":"traceutil/trace.go:171","msg":"trace[671922846] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:4931; }","duration":"351.957915ms","start":"2026-10-19T02:04:01.97662Z","end":"2026-10-19T02:04:02.328578Z","steps":["trace[671922846] 'agreement among raft nodes before linearized reading' (duration: 351.650518ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:04:02.328534Z","caller":"traceutil/trace.go:171","msg":"trace[378633512] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4931; }","duration":"345.54268ms","start":"2026-10-19T02:04:01.982973Z","end":"2026-10-19T02:04:02.328516Z","steps":["trace[378633512] 'agreement among raft nodes before linearized reading' (duration: 345.341796ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:04:02.3287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:04:01.982948Z","time spent":"345.738866ms","remote":"127.0.0.1:39550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-19T02:04:02.328712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:04:01.976603Z","time spent":"352.053458ms","remote":"127.0.0.1:39550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":547,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"info","ts":"2026-10-19T02:04:02.648275Z","caller":"traceutil/trace.go:171","msg":"trace[1281093707] transaction","detail":"{read_only:false; response_revision:4932; number_of_response:1; }","duration":"315.042076ms","start":"2026-10-19T02:04:02.333209Z","end":"2026-10-19T02:04:02.648251Z","steps":["trace[1281093707] 'process raft request' (duration: 291.017567ms)","trace[1281093707] 'compare' (duration: 23.925026ms)"],"step_count":2} {"level":"warn","ts":"2026-10-19T02:04:02.648426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:04:02.333192Z","time spent":"315.158228ms","remote":"127.0.0.1:39550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-19T02:04:02.827636Z","caller":"traceutil/trace.go:171","msg":"trace[1570459149] transaction","detail":"{read_only:false; response_revision:4934; number_of_response:1; }","duration":"494.01254ms","start":"2026-10-19T02:04:02.333585Z","end":"2026-10-19T02:04:02.827598Z","steps":["trace[1570459149] 'process raft request' (duration: 493.863047ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:04:02.827738Z","caller":"traceutil/trace.go:171","msg":"trace[2087289570] transaction","detail":"{read_only:false; response_revision:4935; number_of_response:1; }","duration":"491.757035ms","start":"2026-10-19T02:04:02.335909Z","end":"2026-10-19T02:04:02.827666Z","steps":["trace[2087289570] 'process raft request' (duration: 491.600151ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:04:02.827827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:04:02.333546Z","time spent":"494.197054ms","remote":"127.0.0.1:39550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-19T02:04:02.827819Z","caller":"traceutil/trace.go:171","msg":"trace[710280348] linearizableReadLoop","detail":"{readStateIndex:5153; appliedIndex:5146; }","duration":"349.481265ms","start":"2026-10-19T02:04:02.47831Z","end":"2026-10-19T02:04:02.827791Z","steps":["trace[710280348] 'read index received' (duration: 145.938238ms)","trace[710280348] 'applied index is now lower than readState.Index' (duration: 203.541587ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:04:02.827894Z","caller":"traceutil/trace.go:171","msg":"trace[595770591] transaction","detail":"{read_only:false; response_revision:4937; number_of_response:1; }","duration":"485.706799ms","start":"2026-10-19T02:04:02.342128Z","end":"2026-10-19T02:04:02.827835Z","steps":["trace[595770591] 'process raft request' (duration: 485.500724ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:04:02.827936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:04:02.335898Z","time spent":"491.93372ms","remote":"127.0.0.1:39562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1563,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-19T02:04:02.827978Z","caller":"traceutil/trace.go:171","msg":"trace[894510195] transaction","detail":"{read_only:false; response_revision:4938; number_of_response:1; }","duration":"485.532595ms","start":"2026-10-19T02:04:02.342429Z","end":"2026-10-19T02:04:02.827962Z","steps":["trace[894510195] 'process raft request' (duration: 485.289699ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:04:02.828013Z","caller":"traceutil/trace.go:171","msg":"trace[2134891641] transaction","detail":"{read_only:false; response_revision:4933; number_of_response:1; }","duration":"494.510702ms","start":"2026-10-19T02:04:02.333484Z","end":"2026-10-19T02:04:02.827995Z","steps":["trace[2134891641] 'process raft request' (duration: 492.402891ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:04:02.827756Z","caller":"traceutil/trace.go:171","msg":"trace[1808334464] transaction","detail":"{read_only:false; response_revision:4936; number_of_response:1; }","duration":"490.360782ms","start":"2026-10-19T02:04:02.337371Z","end":"2026-10-19T02:04:02.827731Z","steps":["trace[1808334464] 'process raft request' (duration: 490.206278ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:04:02.828022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:04:02.342112Z","time spent":"485.845383ms","remote":"127.0.0.1:39466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8334,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-19T02:04:02.828135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:04:02.342401Z","time spent":"485.622038ms","remote":"127.0.0.1:39764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10497,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-19T02:04:02.828189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:04:02.337359Z","time spent":"490.793522ms","remote":"127.0.0.1:39454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-19T02:04:02.828309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.956707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"warn","ts":"2026-10-19T02:04:02.828315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.287555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-smff4\" ","response":"range_response_count:1 size:6527"} {"level":"warn","ts":"2026-10-19T02:04:02.828115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:04:02.333467Z","time spent":"494.592294ms","remote":"127.0.0.1:39550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-19T02:04:02.828379Z","caller":"traceutil/trace.go:171","msg":"trace[1197095631] range","detail":"{range_begin:/registry/pods/openstack/keepalived-smff4; range_end:; response_count:1; response_revision:4938; }","duration":"265.389298ms","start":"2026-10-19T02:04:02.562975Z","end":"2026-10-19T02:04:02.828364Z","steps":["trace[1197095631] 'agreement among raft nodes before linearized reading' (duration: 265.214274ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:04:02.828395Z","caller":"traceutil/trace.go:171","msg":"trace[1502628506] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4938; }","duration":"187.06421ms","start":"2026-10-19T02:04:02.64129Z","end":"2026-10-19T02:04:02.828354Z","steps":["trace[1502628506] 'agreement among raft nodes before linearized reading' (duration: 186.933147ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:04:02.828469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.129911ms","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-10-19T02:04:02.828576Z","caller":"traceutil/trace.go:171","msg":"trace[1510068778] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4938; }","duration":"350.307045ms","start":"2026-10-19T02:04:02.478242Z","end":"2026-10-19T02:04:02.828549Z","steps":["trace[1510068778] 'agreement among raft nodes before linearized reading' (duration: 349.979067ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:04:02.828632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:04:02.478215Z","time spent":"350.404428ms","remote":"127.0.0.1:39550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-19T02:04:09.500368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.864364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:13433"} {"level":"info","ts":"2026-10-19T02:04:09.50047Z","caller":"traceutil/trace.go:171","msg":"trace[1953334751] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5100; }","duration":"121.036178ms","start":"2026-10-19T02:04:09.379412Z","end":"2026-10-19T02:04:09.500448Z","steps":["trace[1953334751] 'range keys from in-memory index tree' (duration: 120.633288ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:04:09.696534Z","caller":"traceutil/trace.go:171","msg":"trace[1684042919] transaction","detail":"{read_only:false; response_revision:5104; number_of_response:1; }","duration":"108.935116ms","start":"2026-10-19T02:04:09.587571Z","end":"2026-10-19T02:04:09.696507Z","steps":["trace[1684042919] 'process raft request' (duration: 108.761472ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:05:12.012621Z","caller":"traceutil/trace.go:171","msg":"trace[1898636511] transaction","detail":"{read_only:false; response_revision:5744; number_of_response:1; }","duration":"112.04542ms","start":"2026-10-19T02:05:11.900549Z","end":"2026-10-19T02:05:12.012594Z","steps":["trace[1898636511] 'process raft request' (duration: 108.889253ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:06:49.559956Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3118} {"level":"info","ts":"2026-10-19T02:06:49.659555Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3118,"took":"97.430365ms","hash":2399009263,"current-db-size-bytes":61022208,"current-db-size":"61 MB","current-db-size-in-use-bytes":33640448,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-19T02:06:49.659639Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2399009263,"revision":3118,"compact-revision":-1} {"level":"info","ts":"2026-10-19T02:07:08.402508Z","caller":"traceutil/trace.go:171","msg":"trace[2127768303] transaction","detail":"{read_only:false; response_revision:6613; number_of_response:1; }","duration":"110.963253ms","start":"2026-10-19T02:07:08.291513Z","end":"2026-10-19T02:07:08.402476Z","steps":["trace[2127768303] 'process raft request' (duration: 45.615903ms)","trace[2127768303] 'compare' (duration: 65.080003ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:10:09.132864Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002205.wal"} {"level":"warn","ts":"2026-10-19T02:10:11.734555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.941477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-19T02:10:11.734733Z","caller":"traceutil/trace.go:171","msg":"trace[1375870002] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:8358; }","duration":"131.206504ms","start":"2026-10-19T02:10:11.60349Z","end":"2026-10-19T02:10:11.734696Z","steps":["trace[1375870002] 'count revisions from in-memory index tree' (duration: 130.716122ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:10:13.522985Z","caller":"traceutil/trace.go:171","msg":"trace[277469008] linearizableReadLoop","detail":"{readStateIndex:8750; appliedIndex:8749; }","duration":"265.454209ms","start":"2026-10-19T02:10:13.25751Z","end":"2026-10-19T02:10:13.522964Z","steps":["trace[277469008] 'read index received' (duration: 265.285185ms)","trace[277469008] 'applied index is now lower than readState.Index' (duration: 168.494µs)"],"step_count":2} {"level":"warn","ts":"2026-10-19T02:10:13.523268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.737076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-smff4\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-10-19T02:10:13.523345Z","caller":"traceutil/trace.go:171","msg":"trace[1314464704] range","detail":"{range_begin:/registry/pods/openstack/keepalived-smff4; range_end:; response_count:1; response_revision:8371; }","duration":"265.865299ms","start":"2026-10-19T02:10:13.257461Z","end":"2026-10-19T02:10:13.523326Z","steps":["trace[1314464704] 'agreement among raft nodes before linearized reading' (duration: 265.575272ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:10:13.523062Z","caller":"traceutil/trace.go:171","msg":"trace[824036669] transaction","detail":"{read_only:false; response_revision:8371; number_of_response:1; }","duration":"298.210216ms","start":"2026-10-19T02:10:13.224803Z","end":"2026-10-19T02:10:13.523014Z","steps":["trace[824036669] 'process raft request' (duration: 298.043182ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:10:13.684848Z","caller":"traceutil/trace.go:171","msg":"trace[1689318014] transaction","detail":"{read_only:false; response_revision:8372; number_of_response:1; }","duration":"154.974694ms","start":"2026-10-19T02:10:13.529845Z","end":"2026-10-19T02:10:13.68482Z","steps":["trace[1689318014] 'process raft request' (duration: 154.828861ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:10:25.305105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.104605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-19T02:10:25.305254Z","caller":"traceutil/trace.go:171","msg":"trace[1774508610] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:8444; }","duration":"343.311861ms","start":"2026-10-19T02:10:24.961914Z","end":"2026-10-19T02:10:25.305226Z","steps":["trace[1774508610] 'count revisions from in-memory index tree' (duration: 342.89561ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:10:25.305312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:10:24.961893Z","time spent":"343.401952ms","remote":"127.0.0.1:59196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true "} {"level":"info","ts":"2026-10-19T02:10:25.466194Z","caller":"traceutil/trace.go:171","msg":"trace[2076115347] transaction","detail":"{read_only:false; response_revision:8445; number_of_response:1; }","duration":"157.172487ms","start":"2026-10-19T02:10:25.308982Z","end":"2026-10-19T02:10:25.466155Z","steps":["trace[2076115347] 'process raft request' (duration: 157.002523ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:10:25.481662Z","caller":"traceutil/trace.go:171","msg":"trace[750044404] transaction","detail":"{read_only:false; response_revision:8447; number_of_response:1; }","duration":"172.366272ms","start":"2026-10-19T02:10:25.309255Z","end":"2026-10-19T02:10:25.481621Z","steps":["trace[750044404] 'process raft request' (duration: 172.29318ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:10:25.481696Z","caller":"traceutil/trace.go:171","msg":"trace[1990877261] transaction","detail":"{read_only:false; response_revision:8446; number_of_response:1; }","duration":"172.439403ms","start":"2026-10-19T02:10:25.309212Z","end":"2026-10-19T02:10:25.481651Z","steps":["trace[1990877261] 'process raft request' (duration: 172.141806ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:10:26.150057Z","caller":"traceutil/trace.go:171","msg":"trace[1140590715] transaction","detail":"{read_only:false; response_revision:8449; number_of_response:1; }","duration":"322.353636ms","start":"2026-10-19T02:10:25.827661Z","end":"2026-10-19T02:10:26.150015Z","steps":["trace[1140590715] 'process raft request' (duration: 322.07515ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:10:26.150298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:10:25.827635Z","time spent":"322.51248ms","remote":"127.0.0.1:39392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":644,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-19T02:10:26.280968Z","caller":"traceutil/trace.go:171","msg":"trace[514284441] transaction","detail":"{read_only:false; response_revision:8451; number_of_response:1; }","duration":"122.875733ms","start":"2026-10-19T02:10:26.158039Z","end":"2026-10-19T02:10:26.280914Z","steps":["trace[514284441] 'process raft request' (duration: 122.675888ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:10:54.663212Z","caller":"traceutil/trace.go:171","msg":"trace[285257841] transaction","detail":"{read_only:false; response_revision:8693; number_of_response:1; }","duration":"172.329051ms","start":"2026-10-19T02:10:54.488947Z","end":"2026-10-19T02:10:54.661276Z","steps":["trace[285257841] 'process raft request' (duration: 136.554421ms)","trace[285257841] 'compare' (duration: 35.597586ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:10:54.669986Z","caller":"traceutil/trace.go:171","msg":"trace[674823524] transaction","detail":"{read_only:false; response_revision:8694; number_of_response:1; }","duration":"180.135079ms","start":"2026-10-19T02:10:54.489821Z","end":"2026-10-19T02:10:54.669956Z","steps":["trace[674823524] 'process raft request' (duration: 178.559451ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:11:07.842391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.798507ms","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-10-19T02:11:07.84249Z","caller":"traceutil/trace.go:171","msg":"trace[1885016921] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8821; }","duration":"136.940851ms","start":"2026-10-19T02:11:07.705521Z","end":"2026-10-19T02:11:07.842461Z","steps":["trace[1885016921] 'range keys from in-memory index tree' (duration: 136.518221ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:11:07.842522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.432023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:524"} {"level":"info","ts":"2026-10-19T02:11:07.842596Z","caller":"traceutil/trace.go:171","msg":"trace[1266891582] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:8821; }","duration":"132.577757ms","start":"2026-10-19T02:11:07.71Z","end":"2026-10-19T02:11:07.842578Z","steps":["trace[1266891582] 'range keys from in-memory index tree' (duration: 132.274939ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:11:07.84269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.505984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-19T02:11:07.842781Z","caller":"traceutil/trace.go:171","msg":"trace[96288467] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:8821; }","duration":"179.643227ms","start":"2026-10-19T02:11:07.663119Z","end":"2026-10-19T02:11:07.842762Z","steps":["trace[96288467] 'count revisions from in-memory index tree' (duration: 179.35336ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:11:07.842835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.832083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-19T02:11:07.842923Z","caller":"traceutil/trace.go:171","msg":"trace[4293534] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8821; }","duration":"110.969996ms","start":"2026-10-19T02:11:07.731932Z","end":"2026-10-19T02:11:07.842902Z","steps":["trace[4293534] 'range keys from in-memory index tree' (duration: 110.758672ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:11:07.843159Z","caller":"traceutil/trace.go:171","msg":"trace[2095290715] transaction","detail":"{read_only:false; response_revision:8822; number_of_response:1; }","duration":"106.599202ms","start":"2026-10-19T02:11:07.736538Z","end":"2026-10-19T02:11:07.843138Z","steps":["trace[2095290715] 'process raft request' (duration: 62.035661ms)","trace[2095290715] 'compare' (duration: 44.170772ms)"],"step_count":2} {"level":"warn","ts":"2026-10-19T02:11:07.843313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.237088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-19T02:11:07.843374Z","caller":"traceutil/trace.go:171","msg":"trace[1361072555] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8821; }","duration":"142.342531ms","start":"2026-10-19T02:11:07.701019Z","end":"2026-10-19T02:11:07.843361Z","steps":["trace[1361072555] 'range keys from in-memory index tree' (duration: 142.049683ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:11:08.115647Z","caller":"traceutil/trace.go:171","msg":"trace[144019110] linearizableReadLoop","detail":"{readStateIndex:9229; appliedIndex:9226; }","duration":"241.146206ms","start":"2026-10-19T02:11:07.874455Z","end":"2026-10-19T02:11:08.115601Z","steps":["trace[144019110] 'read index received' (duration: 22.909411ms)","trace[144019110] 'applied index is now lower than readState.Index' (duration: 218.235315ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:11:08.11573Z","caller":"traceutil/trace.go:171","msg":"trace[494986542] transaction","detail":"{read_only:false; response_revision:8825; number_of_response:1; }","duration":"268.063702ms","start":"2026-10-19T02:11:07.847635Z","end":"2026-10-19T02:11:08.115698Z","steps":["trace[494986542] 'process raft request' (duration: 267.790235ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:11:08.115982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.509454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-x4qws\" ","response":"range_response_count:1 size:4754"} {"level":"info","ts":"2026-10-19T02:11:08.116042Z","caller":"traceutil/trace.go:171","msg":"trace[1741431245] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-x4qws; range_end:; response_count:1; response_revision:8825; }","duration":"241.620067ms","start":"2026-10-19T02:11:07.874409Z","end":"2026-10-19T02:11:08.116029Z","steps":["trace[1741431245] 'agreement among raft nodes before linearized reading' (duration: 241.399812ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:11:08.141386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.781023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-smff4\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-10-19T02:11:08.141438Z","caller":"traceutil/trace.go:171","msg":"trace[1470569211] range","detail":"{range_begin:/registry/pods/openstack/keepalived-smff4; range_end:; response_count:1; response_revision:8825; }","duration":"259.858194ms","start":"2026-10-19T02:11:07.881566Z","end":"2026-10-19T02:11:08.141424Z","steps":["trace[1470569211] 'agreement among raft nodes before linearized reading' (duration: 259.6949ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:11:08.811992Z","caller":"traceutil/trace.go:171","msg":"trace[1016032104] linearizableReadLoop","detail":"{readStateIndex:9232; appliedIndex:9231; }","duration":"262.9762ms","start":"2026-10-19T02:11:08.548993Z","end":"2026-10-19T02:11:08.811969Z","steps":["trace[1016032104] 'read index received' (duration: 262.765564ms)","trace[1016032104] 'applied index is now lower than readState.Index' (duration: 209.796µs)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:11:08.812165Z","caller":"traceutil/trace.go:171","msg":"trace[657144208] transaction","detail":"{read_only:false; response_revision:8827; number_of_response:1; }","duration":"387.80229ms","start":"2026-10-19T02:11:08.42434Z","end":"2026-10-19T02:11:08.812142Z","steps":["trace[657144208] 'process raft request' (duration: 387.476822ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:11:08.812307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:11:08.424313Z","time spent":"387.885501ms","remote":"127.0.0.1:39518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3071,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-19T02:11:08.812543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.541374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-19T02:11:08.812597Z","caller":"traceutil/trace.go:171","msg":"trace[1383008449] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8827; }","duration":"263.646776ms","start":"2026-10-19T02:11:08.548937Z","end":"2026-10-19T02:11:08.812584Z","steps":["trace[1383008449] 'agreement among raft nodes before linearized reading' (duration: 263.477932ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:11:09.346665Z","caller":"traceutil/trace.go:171","msg":"trace[378570692] linearizableReadLoop","detail":"{readStateIndex:9241; appliedIndex:9240; }","duration":"137.829072ms","start":"2026-10-19T02:11:09.2088Z","end":"2026-10-19T02:11:09.34663Z","steps":["trace[378570692] 'read index received' (duration: 86.761615ms)","trace[378570692] 'applied index is now lower than readState.Index' (duration: 51.066007ms)"],"step_count":2} {"level":"warn","ts":"2026-10-19T02:11:09.346891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.061528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-19T02:11:09.346897Z","caller":"traceutil/trace.go:171","msg":"trace[1205441371] transaction","detail":"{read_only:false; response_revision:8835; number_of_response:1; }","duration":"143.118139ms","start":"2026-10-19T02:11:09.203633Z","end":"2026-10-19T02:11:09.346751Z","steps":["trace[1205441371] 'process raft request' (duration: 91.95659ms)","trace[1205441371] 'compare' (duration: 50.840612ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:11:09.346951Z","caller":"traceutil/trace.go:171","msg":"trace[1093224231] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:8835; }","duration":"138.176921ms","start":"2026-10-19T02:11:09.208755Z","end":"2026-10-19T02:11:09.346931Z","steps":["trace[1093224231] 'agreement among raft nodes before linearized reading' (duration: 138.052328ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:11:09.347254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.804207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-10-19T02:11:09.347379Z","caller":"traceutil/trace.go:171","msg":"trace[655356154] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8835; }","duration":"111.995282ms","start":"2026-10-19T02:11:09.235359Z","end":"2026-10-19T02:11:09.347355Z","steps":["trace[655356154] 'agreement among raft nodes before linearized reading' (duration: 111.678544ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:11:49.567317Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6477} {"level":"info","ts":"2026-10-19T02:11:49.677562Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6477,"took":"108.296643ms","hash":574893171,"current-db-size-bytes":61022208,"current-db-size":"61 MB","current-db-size-in-use-bytes":25886720,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-19T02:11:49.677632Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":574893171,"revision":6477,"compact-revision":3118} {"level":"info","ts":"2026-10-19T02:12:17.840393Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5db0a1b394e67b72","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-19T02:12:17.852354Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-19T02:12:17.852489Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-19T02:12:25.55333Z","caller":"traceutil/trace.go:171","msg":"trace[2072552872] transaction","detail":"{read_only:false; response_revision:9619; number_of_response:1; }","duration":"236.3059ms","start":"2026-10-19T02:12:25.316994Z","end":"2026-10-19T02:12:25.553299Z","steps":["trace[2072552872] 'process raft request' (duration: 236.133616ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:13:58.69645Z","caller":"traceutil/trace.go:171","msg":"trace[929293489] transaction","detail":"{read_only:false; response_revision:10460; number_of_response:1; }","duration":"123.567659ms","start":"2026-10-19T02:13:58.572858Z","end":"2026-10-19T02:13:58.696426Z","steps":["trace[929293489] 'process raft request' (duration: 123.435046ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:16:06.010276Z","caller":"traceutil/trace.go:171","msg":"trace[563996873] transaction","detail":"{read_only:false; response_revision:11807; number_of_response:1; }","duration":"201.365985ms","start":"2026-10-19T02:16:05.808854Z","end":"2026-10-19T02:16:06.010219Z","steps":["trace[563996873] 'process raft request' (duration: 175.202059ms)","trace[563996873] 'compare' (duration: 26.003322ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:16:49.574592Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9251} {"level":"info","ts":"2026-10-19T02:16:49.667863Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9251,"took":"91.246385ms","hash":4238700941,"current-db-size-bytes":61022208,"current-db-size":"61 MB","current-db-size-in-use-bytes":28258304,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-19T02:16:49.667936Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4238700941,"revision":9251,"compact-revision":6477} {"level":"info","ts":"2026-10-19T02:21:49.587358Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12181} {"level":"info","ts":"2026-10-19T02:21:49.700748Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12181,"took":"110.736368ms","hash":2934330605,"current-db-size-bytes":61022208,"current-db-size":"61 MB","current-db-size-in-use-bytes":25853952,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-19T02:21:49.700845Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2934330605,"revision":12181,"compact-revision":9251} {"level":"info","ts":"2026-10-19T02:21:57.159044Z","caller":"traceutil/trace.go:171","msg":"trace[2125290355] transaction","detail":"{read_only:false; response_revision:14686; number_of_response:1; }","duration":"114.711102ms","start":"2026-10-19T02:21:57.044296Z","end":"2026-10-19T02:21:57.159007Z","steps":["trace[2125290355] 'process raft request' (duration: 114.405694ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:22:20.876044Z","caller":"traceutil/trace.go:171","msg":"trace[728092938] linearizableReadLoop","detail":"{readStateIndex:15543; appliedIndex:15542; }","duration":"144.86009ms","start":"2026-10-19T02:22:20.731159Z","end":"2026-10-19T02:22:20.876019Z","steps":["trace[728092938] 'read index received' (duration: 144.666785ms)","trace[728092938] 'applied index is now lower than readState.Index' (duration: 192.475µs)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:22:20.876404Z","caller":"traceutil/trace.go:171","msg":"trace[810217445] transaction","detail":"{read_only:false; response_revision:14843; number_of_response:1; }","duration":"162.049389ms","start":"2026-10-19T02:22:20.714343Z","end":"2026-10-19T02:22:20.876392Z","steps":["trace[810217445] 'process raft request' (duration: 161.550327ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:22:20.87664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.465274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-19T02:22:20.876679Z","caller":"traceutil/trace.go:171","msg":"trace[242537238] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14843; }","duration":"145.550546ms","start":"2026-10-19T02:22:20.731119Z","end":"2026-10-19T02:22:20.876669Z","steps":["trace[242537238] 'agreement among raft nodes before linearized reading' (duration: 145.445344ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:22:21.00532Z","caller":"traceutil/trace.go:171","msg":"trace[1161440282] transaction","detail":"{read_only:false; response_revision:14844; number_of_response:1; }","duration":"122.895866ms","start":"2026-10-19T02:22:20.882403Z","end":"2026-10-19T02:22:21.005299Z","steps":["trace[1161440282] 'process raft request' (duration: 120.310375ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:22:38.843739Z","caller":"traceutil/trace.go:171","msg":"trace[606092335] transaction","detail":"{read_only:false; response_revision:14981; number_of_response:1; }","duration":"157.05975ms","start":"2026-10-19T02:22:38.686651Z","end":"2026-10-19T02:22:38.843711Z","steps":["trace[606092335] 'process raft request' (duration: 156.867545ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:22:39.564699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.900344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-19T02:22:39.564793Z","caller":"traceutil/trace.go:171","msg":"trace[1987395656] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14981; }","duration":"466.038208ms","start":"2026-10-19T02:22:39.098732Z","end":"2026-10-19T02:22:39.56477Z","steps":["trace[1987395656] 'range keys from in-memory index tree' (duration: 465.71517ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:22:39.564783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.396635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"warn","ts":"2026-10-19T02:22:39.564832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:22:39.098718Z","time spent":"466.104969ms","remote":"127.0.0.1:39550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-19T02:22:39.564864Z","caller":"traceutil/trace.go:171","msg":"trace[662074792] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:14981; }","duration":"101.545878ms","start":"2026-10-19T02:22:39.463295Z","end":"2026-10-19T02:22:39.564841Z","steps":["trace[662074792] 'range keys from in-memory index tree' (duration: 101.18738ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:22:39.564845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.691637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-19T02:22:39.565168Z","caller":"traceutil/trace.go:171","msg":"trace[633142770] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14981; }","duration":"607.051036ms","start":"2026-10-19T02:22:38.958085Z","end":"2026-10-19T02:22:39.565136Z","steps":["trace[633142770] 'range keys from in-memory index tree' (duration: 606.445171ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:22:39.565226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:22:38.958064Z","time spent":"607.142048ms","remote":"127.0.0.1:39550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-19T02:22:39.565213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.792528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-10-19T02:22:39.565322Z","caller":"traceutil/trace.go:171","msg":"trace[1750246906] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14981; }","duration":"472.949982ms","start":"2026-10-19T02:22:39.092342Z","end":"2026-10-19T02:22:39.565292Z","steps":["trace[1750246906] 'range keys from in-memory index tree' (duration: 472.522452ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:22:39.565375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:22:39.092323Z","time spent":"473.037304ms","remote":"127.0.0.1:39550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-19T02:24:26.465131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.292113ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-19T02:24:26.465285Z","caller":"traceutil/trace.go:171","msg":"trace[358975291] transaction","detail":"{read_only:false; response_revision:16237; number_of_response:1; }","duration":"339.097595ms","start":"2026-10-19T02:24:26.126164Z","end":"2026-10-19T02:24:26.465261Z","steps":["trace[358975291] 'process raft request' (duration: 132.551027ms)","trace[358975291] 'compare' (duration: 205.887582ms)"],"step_count":2} {"level":"warn","ts":"2026-10-19T02:24:26.465351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:24:26.12613Z","time spent":"339.188078ms","remote":"127.0.0.1:39550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-19T02:24:26.485287Z","caller":"traceutil/trace.go:171","msg":"trace[1373036397] transaction","detail":"{read_only:false; response_revision:16238; number_of_response:1; }","duration":"286.592545ms","start":"2026-10-19T02:24:26.198647Z","end":"2026-10-19T02:24:26.485239Z","steps":["trace[1373036397] 'process raft request' (duration: 286.277677ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:24:26.485595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.350997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-19T02:24:26.485719Z","caller":"traceutil/trace.go:171","msg":"trace[1964353196] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:16238; }","duration":"241.493221ms","start":"2026-10-19T02:24:26.244193Z","end":"2026-10-19T02:24:26.485686Z","steps":["trace[1964353196] 'agreement among raft nodes before linearized reading' (duration: 241.190813ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:24:26.486443Z","caller":"traceutil/trace.go:171","msg":"trace[967275499] linearizableReadLoop","detail":"{readStateIndex:17004; appliedIndex:17002; }","duration":"240.912757ms","start":"2026-10-19T02:24:26.244214Z","end":"2026-10-19T02:24:26.485126Z","steps":["trace[967275499] 'read index received' (duration: 14.524436ms)","trace[967275499] 'applied index is now lower than readState.Index' (duration: 226.387251ms)"],"step_count":2} {"level":"warn","ts":"2026-10-19T02:24:26.486763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.085557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-19T02:24:26.487023Z","caller":"traceutil/trace.go:171","msg":"trace[2068547858] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:16238; }","duration":"224.404704ms","start":"2026-10-19T02:24:26.262602Z","end":"2026-10-19T02:24:26.487007Z","steps":["trace[2068547858] 'agreement among raft nodes before linearized reading' (duration: 223.998564ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:24:26.486872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.711718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-19T02:24:26.487193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.322388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5520"} {"level":"warn","ts":"2026-10-19T02:24:26.487213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.800911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-19T02:24:26.487225Z","caller":"traceutil/trace.go:171","msg":"trace[1979634950] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16238; }","duration":"224.046215ms","start":"2026-10-19T02:24:26.263128Z","end":"2026-10-19T02:24:26.487174Z","steps":["trace[1979634950] 'agreement among raft nodes before linearized reading' (duration: 223.709777ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:24:26.487258Z","caller":"traceutil/trace.go:171","msg":"trace[1534351573] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16238; }","duration":"223.40321ms","start":"2026-10-19T02:24:26.263834Z","end":"2026-10-19T02:24:26.487238Z","steps":["trace[1534351573] 'agreement among raft nodes before linearized reading' (duration: 222.98166ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:24:26.487274Z","caller":"traceutil/trace.go:171","msg":"trace[286513977] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:16238; }","duration":"113.867982ms","start":"2026-10-19T02:24:26.373388Z","end":"2026-10-19T02:24:26.487256Z","steps":["trace[286513977] 'agreement among raft nodes before linearized reading' (duration: 113.533484ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:24:26.487696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.227349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:681"} {"level":"info","ts":"2026-10-19T02:24:26.487742Z","caller":"traceutil/trace.go:171","msg":"trace[1798402797] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:16238; }","duration":"224.306711ms","start":"2026-10-19T02:24:26.26342Z","end":"2026-10-19T02:24:26.487727Z","steps":["trace[1798402797] 'agreement among raft nodes before linearized reading' (duration: 223.479312ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:24:26.48807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.008708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:666"} {"level":"info","ts":"2026-10-19T02:24:26.488151Z","caller":"traceutil/trace.go:171","msg":"trace[1074608875] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16238; }","duration":"225.106351ms","start":"2026-10-19T02:24:26.263028Z","end":"2026-10-19T02:24:26.488134Z","steps":["trace[1074608875] 'agreement among raft nodes before linearized reading' (duration: 223.845201ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:26:49.596859Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14601} {"level":"info","ts":"2026-10-19T02:26:49.69406Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14601,"took":"94.611698ms","hash":1288196079,"current-db-size-bytes":61022208,"current-db-size":"61 MB","current-db-size-in-use-bytes":29569024,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-19T02:26:49.694147Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1288196079,"revision":14601,"compact-revision":12181} {"level":"warn","ts":"2026-10-19T02:27:13.65659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.177671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-19T02:27:13.656681Z","caller":"traceutil/trace.go:171","msg":"trace[397080846] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:17868; }","duration":"193.340925ms","start":"2026-10-19T02:27:13.46332Z","end":"2026-10-19T02:27:13.656661Z","steps":["trace[397080846] 'range keys from in-memory index tree' (duration: 193.033087ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:29:43.003338Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5db0a1b394e67b72","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-19T02:29:43.011409Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-19T02:29:43.011515Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-19T02:29:46.696908Z","caller":"traceutil/trace.go:171","msg":"trace[75924677] linearizableReadLoop","detail":"{readStateIndex:20026; appliedIndex:20025; }","duration":"118.065583ms","start":"2026-10-19T02:29:46.578818Z","end":"2026-10-19T02:29:46.696883Z","steps":["trace[75924677] 'read index received' (duration: 117.871179ms)","trace[75924677] 'applied index is now lower than readState.Index' (duration: 193.494µs)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:29:46.696952Z","caller":"traceutil/trace.go:171","msg":"trace[992869893] transaction","detail":"{read_only:false; response_revision:19118; number_of_response:1; }","duration":"157.963471ms","start":"2026-10-19T02:29:46.538972Z","end":"2026-10-19T02:29:46.696935Z","steps":["trace[992869893] 'process raft request' (duration: 157.724885ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:29:46.697114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.272148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-10-19T02:29:46.697166Z","caller":"traceutil/trace.go:171","msg":"trace[437416916] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:19118; }","duration":"118.34197ms","start":"2026-10-19T02:29:46.578798Z","end":"2026-10-19T02:29:46.69714Z","steps":["trace[437416916] 'agreement among raft nodes before linearized reading' (duration: 118.203687ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:29:46.751459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.030021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-19T02:29:46.751533Z","caller":"traceutil/trace.go:171","msg":"trace[1654720285] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:19119; }","duration":"135.133084ms","start":"2026-10-19T02:29:46.616377Z","end":"2026-10-19T02:29:46.75151Z","steps":["trace[1654720285] 'agreement among raft nodes before linearized reading' (duration: 135.013581ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:29:52.137098Z","caller":"traceutil/trace.go:171","msg":"trace[1108721642] transaction","detail":"{read_only:false; response_revision:19153; number_of_response:1; }","duration":"105.894397ms","start":"2026-10-19T02:29:52.031177Z","end":"2026-10-19T02:29:52.137071Z","steps":["trace[1108721642] 'process raft request' (duration: 105.744844ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:29:53.046675Z","caller":"traceutil/trace.go:171","msg":"trace[192463910] linearizableReadLoop","detail":"{readStateIndex:20067; appliedIndex:20066; }","duration":"322.70699ms","start":"2026-10-19T02:29:52.723946Z","end":"2026-10-19T02:29:53.046653Z","steps":["trace[192463910] 'read index received' (duration: 322.629429ms)","trace[192463910] 'applied index is now lower than readState.Index' (duration: 76.691µs)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:29:53.04698Z","caller":"traceutil/trace.go:171","msg":"trace[1907462406] transaction","detail":"{read_only:false; response_revision:19157; number_of_response:1; }","duration":"325.682ms","start":"2026-10-19T02:29:52.721289Z","end":"2026-10-19T02:29:53.046971Z","steps":["trace[1907462406] 'process raft request' (duration: 325.24474ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:29:53.047084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.13439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-10-19T02:29:53.04713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:29:52.721256Z","time spent":"325.741802ms","remote":"127.0.0.1:39466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":21000,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-19T02:29:53.047161Z","caller":"traceutil/trace.go:171","msg":"trace[790469275] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:19157; }","duration":"323.252083ms","start":"2026-10-19T02:29:52.723899Z","end":"2026-10-19T02:29:53.047151Z","steps":["trace[790469275] 'agreement among raft nodes before linearized reading' (duration: 323.12218ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:29:53.047182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:29:52.723878Z","time spent":"323.298304ms","remote":"127.0.0.1:39550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-19T02:29:53.059907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.766622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-10-19T02:29:53.060007Z","caller":"traceutil/trace.go:171","msg":"trace[2110181374] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:19158; }","duration":"282.930686ms","start":"2026-10-19T02:29:52.777068Z","end":"2026-10-19T02:29:53.059999Z","steps":["trace[2110181374] 'agreement among raft nodes before linearized reading' (duration: 282.792963ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:29:53.059955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.894199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:662"} {"level":"warn","ts":"2026-10-19T02:29:53.059886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.504944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11716"} {"level":"info","ts":"2026-10-19T02:29:53.060124Z","caller":"traceutil/trace.go:171","msg":"trace[1944969631] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:19158; }","duration":"181.105784ms","start":"2026-10-19T02:29:52.878994Z","end":"2026-10-19T02:29:53.0601Z","steps":["trace[1944969631] 'agreement among raft nodes before linearized reading' (duration: 180.828508ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:29:53.06024Z","caller":"traceutil/trace.go:171","msg":"trace[1758041596] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:19158; }","duration":"313.807112ms","start":"2026-10-19T02:29:52.746316Z","end":"2026-10-19T02:29:53.060123Z","steps":["trace[1758041596] 'agreement among raft nodes before linearized reading' (duration: 313.461094ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:29:53.060326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:29:52.746293Z","time spent":"314.005856ms","remote":"127.0.0.1:39466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":11739,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-10-19T02:29:53.059947Z","caller":"traceutil/trace.go:171","msg":"trace[1508028622] transaction","detail":"{read_only:false; response_revision:19158; number_of_response:1; }","duration":"290.489703ms","start":"2026-10-19T02:29:52.769445Z","end":"2026-10-19T02:29:53.059934Z","steps":["trace[1508028622] 'process raft request' (duration: 290.231367ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:31:49.604241Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17503} {"level":"info","ts":"2026-10-19T02:31:49.704437Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17503,"took":"96.700302ms","hash":91864044,"current-db-size-bytes":61022208,"current-db-size":"61 MB","current-db-size-in-use-bytes":35049472,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-19T02:31:49.7045Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":91864044,"revision":17503,"compact-revision":14601} {"level":"info","ts":"2026-10-19T02:36:49.617798Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20431} {"level":"info","ts":"2026-10-19T02:36:49.748039Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20431,"took":"126.781397ms","hash":1088518224,"current-db-size-bytes":61022208,"current-db-size":"61 MB","current-db-size-in-use-bytes":32952320,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-19T02:36:49.748195Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1088518224,"revision":20431,"compact-revision":17503} {"level":"warn","ts":"2026-10-19T02:37:30.03784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.922798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-19T02:37:30.037929Z","caller":"traceutil/trace.go:171","msg":"trace[1671754675] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23551; }","duration":"305.063942ms","start":"2026-10-19T02:37:29.732845Z","end":"2026-10-19T02:37:30.037909Z","steps":["trace[1671754675] 'range keys from in-memory index tree' (duration: 304.846667ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:37:30.037967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:37:29.732823Z","time spent":"305.135194ms","remote":"127.0.0.1:39306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-19T02:37:30.037997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.025657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-10-19T02:37:30.038079Z","caller":"traceutil/trace.go:171","msg":"trace[643940471] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:23551; }","duration":"356.153629ms","start":"2026-10-19T02:37:29.681905Z","end":"2026-10-19T02:37:30.038059Z","steps":["trace[643940471] 'range keys from in-memory index tree' (duration: 355.874522ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:37:30.03812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:37:29.68189Z","time spent":"356.220981ms","remote":"127.0.0.1:39550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":549,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"info","ts":"2026-10-19T02:37:30.217256Z","caller":"traceutil/trace.go:171","msg":"trace[294034943] transaction","detail":"{read_only:false; response_revision:23552; number_of_response:1; }","duration":"173.127723ms","start":"2026-10-19T02:37:30.044109Z","end":"2026-10-19T02:37:30.217237Z","steps":["trace[294034943] 'process raft request' (duration: 173.00591ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:37:30.758964Z","caller":"traceutil/trace.go:171","msg":"trace[229237057] transaction","detail":"{read_only:false; response_revision:23553; number_of_response:1; }","duration":"152.354852ms","start":"2026-10-19T02:37:30.606587Z","end":"2026-10-19T02:37:30.758942Z","steps":["trace[229237057] 'process raft request' (duration: 151.692256ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:37:30.909889Z","caller":"traceutil/trace.go:171","msg":"trace[1819916259] transaction","detail":"{read_only:false; response_revision:23554; number_of_response:1; }","duration":"141.175238ms","start":"2026-10-19T02:37:30.768692Z","end":"2026-10-19T02:37:30.909867Z","steps":["trace[1819916259] 'process raft request' (duration: 137.561742ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:37:30.92257Z","caller":"traceutil/trace.go:171","msg":"trace[2075022039] transaction","detail":"{read_only:false; response_revision:23555; number_of_response:1; }","duration":"127.627567ms","start":"2026-10-19T02:37:30.794919Z","end":"2026-10-19T02:37:30.922546Z","steps":["trace[2075022039] 'process raft request' (duration: 127.218658ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:37:31.248592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.427177ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-19T02:37:31.248912Z","caller":"traceutil/trace.go:171","msg":"trace[219127447] transaction","detail":"{read_only:false; response_revision:23556; number_of_response:1; }","duration":"322.268119ms","start":"2026-10-19T02:37:30.926617Z","end":"2026-10-19T02:37:31.248885Z","steps":["trace[219127447] 'process raft request' (duration: 168.251348ms)","trace[219127447] 'compare' (duration: 153.045768ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:37:31.248956Z","caller":"traceutil/trace.go:171","msg":"trace[1199398125] linearizableReadLoop","detail":"{readStateIndex:24672; appliedIndex:24670; }","duration":"311.998886ms","start":"2026-10-19T02:37:30.936941Z","end":"2026-10-19T02:37:31.24894Z","steps":["trace[1199398125] 'read index received' (duration: 157.899614ms)","trace[1199398125] 'applied index is now lower than readState.Index' (duration: 154.098392ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:37:31.24904Z","caller":"traceutil/trace.go:171","msg":"trace[366384470] transaction","detail":"{read_only:false; response_revision:23557; number_of_response:1; }","duration":"321.317396ms","start":"2026-10-19T02:37:30.927711Z","end":"2026-10-19T02:37:31.249029Z","steps":["trace[366384470] 'process raft request' (duration: 321.145302ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:37:31.249127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:37:30.926594Z","time spent":"322.390251ms","remote":"127.0.0.1:39550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-19T02:37:31.249123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:37:30.927692Z","time spent":"321.385227ms","remote":"127.0.0.1:39550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":537,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-19T02:37:31.249282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.519625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-19T02:37:31.249351Z","caller":"traceutil/trace.go:171","msg":"trace[1642735012] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23557; }","duration":"311.622827ms","start":"2026-10-19T02:37:30.937713Z","end":"2026-10-19T02:37:31.249335Z","steps":["trace[1642735012] 'agreement among raft nodes before linearized reading' (duration: 311.436692ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:37:31.24939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:37:30.937705Z","time spent":"311.677758ms","remote":"127.0.0.1:39550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-19T02:37:31.249583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.717983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-10-19T02:37:31.249584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.630581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:662"} {"level":"info","ts":"2026-10-19T02:37:31.249621Z","caller":"traceutil/trace.go:171","msg":"trace[1906238998] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23557; }","duration":"301.783124ms","start":"2026-10-19T02:37:30.947826Z","end":"2026-10-19T02:37:31.249609Z","steps":["trace[1906238998] 'agreement among raft nodes before linearized reading' (duration: 301.669401ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:37:31.249637Z","caller":"traceutil/trace.go:171","msg":"trace[208610649] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:23557; }","duration":"312.683662ms","start":"2026-10-19T02:37:30.936936Z","end":"2026-10-19T02:37:31.24962Z","steps":["trace[208610649] 'agreement among raft nodes before linearized reading' (duration: 312.226252ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:37:31.249685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:37:30.936922Z","time spent":"312.752384ms","remote":"127.0.0.1:39392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":685,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-10-19T02:37:31.249647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:37:30.947814Z","time spent":"301.827645ms","remote":"127.0.0.1:39550","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":"info","ts":"2026-10-19T02:37:31.541659Z","caller":"traceutil/trace.go:171","msg":"trace[896991716] transaction","detail":"{read_only:false; response_revision:23560; number_of_response:1; }","duration":"279.113498ms","start":"2026-10-19T02:37:31.262528Z","end":"2026-10-19T02:37:31.541642Z","steps":["trace[896991716] 'process raft request' (duration: 279.060016ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:37:31.541675Z","caller":"traceutil/trace.go:171","msg":"trace[854152401] transaction","detail":"{read_only:false; response_revision:23559; number_of_response:1; }","duration":"286.890122ms","start":"2026-10-19T02:37:31.25476Z","end":"2026-10-19T02:37:31.54165Z","steps":["trace[854152401] 'process raft request' (duration: 210.381523ms)","trace[854152401] 'compare' (duration: 76.210502ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:37:31.639256Z","caller":"traceutil/trace.go:171","msg":"trace[1524218484] linearizableReadLoop","detail":"{readStateIndex:24676; appliedIndex:24675; }","duration":"107.895051ms","start":"2026-10-19T02:37:31.531331Z","end":"2026-10-19T02:37:31.639226Z","steps":["trace[1524218484] 'read index received' (duration: 107.675045ms)","trace[1524218484] 'applied index is now lower than readState.Index' (duration: 218.546µs)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:37:31.639347Z","caller":"traceutil/trace.go:171","msg":"trace[2071507231] transaction","detail":"{read_only:false; response_revision:23561; number_of_response:1; }","duration":"235.321263ms","start":"2026-10-19T02:37:31.404001Z","end":"2026-10-19T02:37:31.639322Z","steps":["trace[2071507231] 'process raft request' (duration: 235.037756ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:37:31.639616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.2534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11716"} {"level":"info","ts":"2026-10-19T02:37:31.639696Z","caller":"traceutil/trace.go:171","msg":"trace[932511227] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:23561; }","duration":"108.394622ms","start":"2026-10-19T02:37:31.531284Z","end":"2026-10-19T02:37:31.639678Z","steps":["trace[932511227] 'agreement among raft nodes before linearized reading' (duration: 108.105546ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:37:31.765462Z","caller":"traceutil/trace.go:171","msg":"trace[1971174818] transaction","detail":"{read_only:false; response_revision:23563; number_of_response:1; }","duration":"121.276617ms","start":"2026-10-19T02:37:31.644162Z","end":"2026-10-19T02:37:31.765439Z","steps":["trace[1971174818] 'process raft request' (duration: 121.221886ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:37:31.765497Z","caller":"traceutil/trace.go:171","msg":"trace[1763140621] transaction","detail":"{read_only:false; response_revision:23562; number_of_response:1; }","duration":"121.3883ms","start":"2026-10-19T02:37:31.644046Z","end":"2026-10-19T02:37:31.765434Z","steps":["trace[1763140621] 'process raft request' (duration: 119.28774ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:37:31.765514Z","caller":"traceutil/trace.go:171","msg":"trace[722340020] linearizableReadLoop","detail":"{readStateIndex:24677; appliedIndex:24676; }","duration":"121.36861ms","start":"2026-10-19T02:37:31.644083Z","end":"2026-10-19T02:37:31.765452Z","steps":["trace[722340020] 'read index received' (duration: 119.221369ms)","trace[722340020] 'applied index is now lower than readState.Index' (duration: 2.1441ms)"],"step_count":2} {"level":"warn","ts":"2026-10-19T02:37:31.765854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.739858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11716"} {"level":"info","ts":"2026-10-19T02:37:31.765933Z","caller":"traceutil/trace.go:171","msg":"trace[1042641666] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:23563; }","duration":"121.85891ms","start":"2026-10-19T02:37:31.644053Z","end":"2026-10-19T02:37:31.765912Z","steps":["trace[1042641666] 'agreement among raft nodes before linearized reading' (duration: 121.586144ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:38:37.820129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.338019ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-19T02:38:37.820303Z","caller":"traceutil/trace.go:171","msg":"trace[910964950] transaction","detail":"{read_only:false; response_revision:24255; number_of_response:1; }","duration":"163.873774ms","start":"2026-10-19T02:38:37.656406Z","end":"2026-10-19T02:38:37.82028Z","steps":["trace[910964950] 'process raft request' (duration: 50.299229ms)","trace[910964950] 'compare' (duration: 113.214137ms)"],"step_count":2} {"level":"warn","ts":"2026-10-19T02:38:38.076282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.677611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-19T02:38:38.076396Z","caller":"traceutil/trace.go:171","msg":"trace[699525483] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24255; }","duration":"136.835425ms","start":"2026-10-19T02:38:37.939519Z","end":"2026-10-19T02:38:38.076354Z","steps":["trace[699525483] 'range keys from in-memory index tree' (duration: 136.443886ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:38:38.375895Z","caller":"traceutil/trace.go:171","msg":"trace[1536660998] transaction","detail":"{read_only:false; response_revision:24259; number_of_response:1; }","duration":"196.847213ms","start":"2026-10-19T02:38:38.17902Z","end":"2026-10-19T02:38:38.375867Z","steps":["trace[1536660998] 'process raft request' (duration: 196.670809ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:41:06.65932Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006a5f.wal"} {"level":"warn","ts":"2026-10-19T02:41:15.366252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.240628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-19T02:41:15.366385Z","caller":"traceutil/trace.go:171","msg":"trace[24046066] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:26283; }","duration":"170.404862ms","start":"2026-10-19T02:41:15.195952Z","end":"2026-10-19T02:41:15.366357Z","steps":["trace[24046066] 'count revisions from in-memory index tree' (duration: 170.114554ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:41:49.626147Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23288} {"level":"info","ts":"2026-10-19T02:41:49.755511Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23288,"took":"125.65704ms","hash":1069052042,"current-db-size-bytes":61022208,"current-db-size":"61 MB","current-db-size-in-use-bytes":45182976,"current-db-size-in-use":"45 MB"} {"level":"info","ts":"2026-10-19T02:41:49.75562Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1069052042,"revision":23288,"compact-revision":20431} {"level":"warn","ts":"2026-10-19T02:42:54.310228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.451918ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-19T02:42:54.310344Z","caller":"traceutil/trace.go:171","msg":"trace[933785543] linearizableReadLoop","detail":"{readStateIndex:28721; appliedIndex:28720; }","duration":"153.720939ms","start":"2026-10-19T02:42:54.156607Z","end":"2026-10-19T02:42:54.310328Z","steps":["trace[933785543] 'read index received' (duration: 7.020155ms)","trace[933785543] 'applied index is now lower than readState.Index' (duration: 146.697884ms)"],"step_count":2} {"level":"warn","ts":"2026-10-19T02:42:54.31041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.79987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-10-19T02:42:54.310384Z","caller":"traceutil/trace.go:171","msg":"trace[1728780040] transaction","detail":"{read_only:false; response_revision:27446; number_of_response:1; }","duration":"191.530602ms","start":"2026-10-19T02:42:54.11882Z","end":"2026-10-19T02:42:54.310351Z","steps":["trace[1728780040] 'process raft request' (duration: 44.867109ms)","trace[1728780040] 'compare' (duration: 146.302575ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:42:54.310585Z","caller":"traceutil/trace.go:171","msg":"trace[1282476725] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:27446; }","duration":"153.941993ms","start":"2026-10-19T02:42:54.156602Z","end":"2026-10-19T02:42:54.310544Z","steps":["trace[1282476725] 'agreement among raft nodes before linearized reading' (duration: 153.76953ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:43:41.35077Z","caller":"traceutil/trace.go:171","msg":"trace[1476167661] transaction","detail":"{read_only:false; response_revision:27940; number_of_response:1; }","duration":"103.043901ms","start":"2026-10-19T02:43:41.24769Z","end":"2026-10-19T02:43:41.350734Z","steps":["trace[1476167661] 'process raft request' (duration: 102.833217ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:44:49.833626Z","caller":"traceutil/trace.go:171","msg":"trace[263982717] linearizableReadLoop","detail":"{readStateIndex:29979; appliedIndex:29978; }","duration":"205.386445ms","start":"2026-10-19T02:44:49.628199Z","end":"2026-10-19T02:44:49.833586Z","steps":["trace[263982717] 'read index received' (duration: 193.300847ms)","trace[263982717] 'applied index is now lower than readState.Index' (duration: 12.084518ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:44:49.833723Z","caller":"traceutil/trace.go:171","msg":"trace[634218484] transaction","detail":"{read_only:false; response_revision:28654; number_of_response:1; }","duration":"211.936701ms","start":"2026-10-19T02:44:49.621698Z","end":"2026-10-19T02:44:49.833635Z","steps":["trace[634218484] 'process raft request' (duration: 199.869234ms)","trace[634218484] 'compare' (duration: 11.477163ms)"],"step_count":2} {"level":"warn","ts":"2026-10-19T02:44:49.834008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.710663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:662"} {"level":"info","ts":"2026-10-19T02:44:49.834132Z","caller":"traceutil/trace.go:171","msg":"trace[876411968] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:28654; }","duration":"205.951869ms","start":"2026-10-19T02:44:49.628152Z","end":"2026-10-19T02:44:49.834104Z","steps":["trace[876411968] 'agreement among raft nodes before linearized reading' (duration: 205.552619ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:44:49.92611Z","caller":"traceutil/trace.go:171","msg":"trace[740229506] transaction","detail":"{read_only:false; response_revision:28655; number_of_response:1; }","duration":"250.520967ms","start":"2026-10-19T02:44:49.675548Z","end":"2026-10-19T02:44:49.926069Z","steps":["trace[740229506] 'process raft request' (duration: 250.314142ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:44:49.926234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.661317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-19T02:44:49.926262Z","caller":"traceutil/trace.go:171","msg":"trace[1540722677] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:28655; }","duration":"158.685107ms","start":"2026-10-19T02:44:49.767566Z","end":"2026-10-19T02:44:49.926251Z","steps":["trace[1540722677] 'agreement among raft nodes before linearized reading' (duration: 158.629236ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:44:49.926172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.438768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-19T02:44:49.926437Z","caller":"traceutil/trace.go:171","msg":"trace[1383147912] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:28655; }","duration":"117.728435ms","start":"2026-10-19T02:44:49.808669Z","end":"2026-10-19T02:44:49.926398Z","steps":["trace[1383147912] 'agreement among raft nodes before linearized reading' (duration: 117.351516ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:44:50.357909Z","caller":"traceutil/trace.go:171","msg":"trace[1176382725] transaction","detail":"{read_only:false; response_revision:28656; number_of_response:1; }","duration":"427.415247ms","start":"2026-10-19T02:44:49.93047Z","end":"2026-10-19T02:44:50.357885Z","steps":["trace[1176382725] 'process raft request' (duration: 427.250613ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:44:50.358089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:44:49.930456Z","time spent":"427.54741ms","remote":"127.0.0.1:39392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":644,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-19T02:44:50.376648Z","caller":"traceutil/trace.go:171","msg":"trace[551554398] transaction","detail":"{read_only:false; response_revision:28657; number_of_response:1; }","duration":"445.829734ms","start":"2026-10-19T02:44:49.930777Z","end":"2026-10-19T02:44:50.376607Z","steps":["trace[551554398] 'process raft request' (duration: 445.579278ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:44:50.376755Z","caller":"traceutil/trace.go:171","msg":"trace[2041947024] linearizableReadLoop","detail":"{readStateIndex:29983; appliedIndex:29980; }","duration":"222.555384ms","start":"2026-10-19T02:44:50.154171Z","end":"2026-10-19T02:44:50.376726Z","steps":["trace[2041947024] 'read index received' (duration: 203.576543ms)","trace[2041947024] 'applied index is now lower than readState.Index' (duration: 18.97601ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:44:50.376855Z","caller":"traceutil/trace.go:171","msg":"trace[1443160909] transaction","detail":"{read_only:false; response_revision:28658; number_of_response:1; }","duration":"445.098007ms","start":"2026-10-19T02:44:49.931737Z","end":"2026-10-19T02:44:50.376835Z","steps":["trace[1443160909] 'process raft request' (duration: 444.81169ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:44:50.376979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:44:49.93173Z","time spent":"445.171979ms","remote":"127.0.0.1:39550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-19T02:44:50.376842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:44:49.930764Z","time spent":"445.974338ms","remote":"127.0.0.1:39550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-19T02:44:50.377153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.955723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11716"} {"level":"info","ts":"2026-10-19T02:44:50.377242Z","caller":"traceutil/trace.go:171","msg":"trace[231238975] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:28658; }","duration":"223.081326ms","start":"2026-10-19T02:44:50.154136Z","end":"2026-10-19T02:44:50.377218Z","steps":["trace[231238975] 'agreement among raft nodes before linearized reading' (duration: 222.731578ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:44:50.672758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.769894ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-19T02:44:50.673093Z","caller":"traceutil/trace.go:171","msg":"trace[1777469382] linearizableReadLoop","detail":"{readStateIndex:29986; appliedIndex:29983; }","duration":"288.043988ms","start":"2026-10-19T02:44:50.385028Z","end":"2026-10-19T02:44:50.673072Z","steps":["trace[1777469382] 'read index received' (duration: 141.180651ms)","trace[1777469382] 'applied index is now lower than readState.Index' (duration: 146.862157ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:44:50.673197Z","caller":"traceutil/trace.go:171","msg":"trace[482183072] transaction","detail":"{read_only:false; response_revision:28659; number_of_response:1; }","duration":"290.932516ms","start":"2026-10-19T02:44:50.382247Z","end":"2026-10-19T02:44:50.673179Z","steps":["trace[482183072] 'process raft request' (duration: 143.63716ms)","trace[482183072] 'compare' (duration: 146.20349ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:44:50.673311Z","caller":"traceutil/trace.go:171","msg":"trace[1063813885] transaction","detail":"{read_only:false; response_revision:28660; number_of_response:1; }","duration":"290.793584ms","start":"2026-10-19T02:44:50.382484Z","end":"2026-10-19T02:44:50.673278Z","steps":["trace[1063813885] 'process raft request' (duration: 290.381144ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:44:50.673337Z","caller":"traceutil/trace.go:171","msg":"trace[1282827496] transaction","detail":"{read_only:false; response_revision:28661; number_of_response:1; }","duration":"290.545617ms","start":"2026-10-19T02:44:50.382768Z","end":"2026-10-19T02:44:50.673314Z","steps":["trace[1282827496] 'process raft request' (duration: 290.22967ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:44:50.673481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.435058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11716"} {"level":"info","ts":"2026-10-19T02:44:50.673553Z","caller":"traceutil/trace.go:171","msg":"trace[1681219751] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:28661; }","duration":"288.556561ms","start":"2026-10-19T02:44:50.384976Z","end":"2026-10-19T02:44:50.673533Z","steps":["trace[1681219751] 'agreement among raft nodes before linearized reading' (duration: 288.296524ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:44:50.67615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.761095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/magnum-api-6cdd98cb-wwmbb\" ","response":"range_response_count:1 size:8635"} {"level":"info","ts":"2026-10-19T02:44:50.676224Z","caller":"traceutil/trace.go:171","msg":"trace[314524018] range","detail":"{range_begin:/registry/pods/openstack/magnum-api-6cdd98cb-wwmbb; range_end:; response_count:1; response_revision:28662; }","duration":"166.468102ms","start":"2026-10-19T02:44:50.509737Z","end":"2026-10-19T02:44:50.676205Z","steps":["trace[314524018] 'agreement among raft nodes before linearized reading' (duration: 166.300578ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:44:51.150687Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5db0a1b394e67b72","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-19T02:44:51.159028Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-19T02:44:51.159326Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-19T02:45:03.323702Z","caller":"traceutil/trace.go:171","msg":"trace[1226732982] transaction","detail":"{read_only:false; response_revision:28814; number_of_response:1; }","duration":"107.066372ms","start":"2026-10-19T02:45:03.21661Z","end":"2026-10-19T02:45:03.323676Z","steps":["trace[1226732982] 'process raft request' (duration: 106.932299ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:45:50.305943Z","caller":"traceutil/trace.go:171","msg":"trace[1444543511] transaction","detail":"{read_only:false; response_revision:29352; number_of_response:1; }","duration":"218.436186ms","start":"2026-10-19T02:45:50.087476Z","end":"2026-10-19T02:45:50.305912Z","steps":["trace[1444543511] 'process raft request' (duration: 218.285932ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:45:50.531704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.034559ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-19T02:45:50.531773Z","caller":"traceutil/trace.go:171","msg":"trace[1800291071] linearizableReadLoop","detail":"{readStateIndex:30703; appliedIndex:30702; }","duration":"109.939849ms","start":"2026-10-19T02:45:50.421822Z","end":"2026-10-19T02:45:50.531762Z","steps":["trace[1800291071] 'read index received' (duration: 35.101µs)","trace[1800291071] 'applied index is now lower than readState.Index' (duration: 109.904018ms)"],"step_count":2} {"level":"warn","ts":"2026-10-19T02:45:50.531823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.00037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-19T02:45:50.53184Z","caller":"traceutil/trace.go:171","msg":"trace[1572759694] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:29353; }","duration":"110.053361ms","start":"2026-10-19T02:45:50.42178Z","end":"2026-10-19T02:45:50.531834Z","steps":["trace[1572759694] 'agreement among raft nodes before linearized reading' (duration: 110.00687ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:45:50.531841Z","caller":"traceutil/trace.go:171","msg":"trace[257759785] transaction","detail":"{read_only:false; response_revision:29353; number_of_response:1; }","duration":"378.31837ms","start":"2026-10-19T02:45:50.153492Z","end":"2026-10-19T02:45:50.53181Z","steps":["trace[257759785] 'process raft request' (duration: 191.121027ms)","trace[257759785] 'compare' (duration: 186.961347ms)"],"step_count":2} {"level":"warn","ts":"2026-10-19T02:45:50.531977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:45:50.153469Z","time spent":"378.433443ms","remote":"127.0.0.1:39550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-19T02:45:50.954548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.132201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-19T02:45:50.954634Z","caller":"traceutil/trace.go:171","msg":"trace[1325940544] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:29353; }","duration":"240.263904ms","start":"2026-10-19T02:45:50.714349Z","end":"2026-10-19T02:45:50.954613Z","steps":["trace[1325940544] 'range keys from in-memory index tree' (duration: 239.928966ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:45:50.954712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.614906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-19T02:45:50.954824Z","caller":"traceutil/trace.go:171","msg":"trace[517860028] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:29353; }","duration":"241.76426ms","start":"2026-10-19T02:45:50.713034Z","end":"2026-10-19T02:45:50.954798Z","steps":["trace[517860028] 'range keys from in-memory index tree' (duration: 240.975421ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:45:51.141598Z","caller":"traceutil/trace.go:171","msg":"trace[1588312806] transaction","detail":"{read_only:false; response_revision:29354; number_of_response:1; }","duration":"181.757615ms","start":"2026-10-19T02:45:50.959814Z","end":"2026-10-19T02:45:51.141572Z","steps":["trace[1588312806] 'process raft request' (duration: 181.629761ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:45:51.145655Z","caller":"traceutil/trace.go:171","msg":"trace[1131493380] linearizableReadLoop","detail":"{readStateIndex:30705; appliedIndex:30704; }","duration":"164.507165ms","start":"2026-10-19T02:45:50.981132Z","end":"2026-10-19T02:45:51.145639Z","steps":["trace[1131493380] 'read index received' (duration: 161.048883ms)","trace[1131493380] 'applied index is now lower than readState.Index' (duration: 3.457172ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:45:51.145909Z","caller":"traceutil/trace.go:171","msg":"trace[1421698900] transaction","detail":"{read_only:false; response_revision:29355; number_of_response:1; }","duration":"184.343136ms","start":"2026-10-19T02:45:50.961546Z","end":"2026-10-19T02:45:51.145889Z","steps":["trace[1421698900] 'process raft request' (duration: 183.970437ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:45:51.146108Z","caller":"traceutil/trace.go:171","msg":"trace[356460539] transaction","detail":"{read_only:false; response_revision:29356; number_of_response:1; }","duration":"149.601292ms","start":"2026-10-19T02:45:50.996493Z","end":"2026-10-19T02:45:51.146095Z","steps":["trace[356460539] 'process raft request' (duration: 149.10642ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:45:51.146373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.233403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-19T02:45:51.146407Z","caller":"traceutil/trace.go:171","msg":"trace[444463168] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:29356; }","duration":"165.315644ms","start":"2026-10-19T02:45:50.981082Z","end":"2026-10-19T02:45:51.146397Z","steps":["trace[444463168] 'agreement among raft nodes before linearized reading' (duration: 165.15632ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:45:51.329015Z","caller":"traceutil/trace.go:171","msg":"trace[2104506134] linearizableReadLoop","detail":"{readStateIndex:30707; appliedIndex:30706; }","duration":"183.302742ms","start":"2026-10-19T02:45:51.145695Z","end":"2026-10-19T02:45:51.328997Z","steps":["trace[2104506134] 'read index received' (duration: 183.194309ms)","trace[2104506134] 'applied index is now lower than readState.Index' (duration: 107.713µs)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:45:51.329098Z","caller":"traceutil/trace.go:171","msg":"trace[114592236] transaction","detail":"{read_only:false; response_revision:29357; number_of_response:1; }","duration":"187.718436ms","start":"2026-10-19T02:45:51.141364Z","end":"2026-10-19T02:45:51.329083Z","steps":["trace[114592236] 'process raft request' (duration: 187.533182ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:45:51.329184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.750746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-19T02:45:51.329212Z","caller":"traceutil/trace.go:171","msg":"trace[861776381] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:29357; }","duration":"331.826068ms","start":"2026-10-19T02:45:50.997378Z","end":"2026-10-19T02:45:51.329204Z","steps":["trace[861776381] 'agreement among raft nodes before linearized reading' (duration: 331.700605ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:45:51.329231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:45:50.997359Z","time spent":"331.867258ms","remote":"127.0.0.1:39550","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":"info","ts":"2026-10-19T02:45:51.356321Z","caller":"traceutil/trace.go:171","msg":"trace[1602035331] transaction","detail":"{read_only:false; response_revision:29358; number_of_response:1; }","duration":"205.031567ms","start":"2026-10-19T02:45:51.151234Z","end":"2026-10-19T02:45:51.356265Z","steps":["trace[1602035331] 'process raft request' (duration: 204.827992ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:45:51.356537Z","caller":"traceutil/trace.go:171","msg":"trace[1969954379] transaction","detail":"{read_only:false; response_revision:29359; number_of_response:1; }","duration":"197.098239ms","start":"2026-10-19T02:45:51.159411Z","end":"2026-10-19T02:45:51.35651Z","steps":["trace[1969954379] 'process raft request' (duration: 196.799152ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:45:51.356629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.763775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-19T02:45:51.356674Z","caller":"traceutil/trace.go:171","msg":"trace[400485940] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesets/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesets0; response_count:0; response_revision:29360; }","duration":"181.862618ms","start":"2026-10-19T02:45:51.1748Z","end":"2026-10-19T02:45:51.356663Z","steps":["trace[400485940] 'agreement among raft nodes before linearized reading' (duration: 181.742825ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:46:49.633324Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26650} {"level":"info","ts":"2026-10-19T02:46:49.782984Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26650,"took":"145.372892ms","hash":3697645314,"current-db-size-bytes":61022208,"current-db-size":"61 MB","current-db-size-in-use-bytes":45367296,"current-db-size-in-use":"45 MB"} {"level":"info","ts":"2026-10-19T02:46:49.783072Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3697645314,"revision":26650,"compact-revision":23288} {"level":"info","ts":"2026-10-19T02:49:07.019482Z","caller":"traceutil/trace.go:171","msg":"trace[1720594252] transaction","detail":"{read_only:false; response_revision:31571; number_of_response:1; }","duration":"127.882946ms","start":"2026-10-19T02:49:06.891574Z","end":"2026-10-19T02:49:07.019457Z","steps":["trace[1720594252] 'process raft request' (duration: 124.384803ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:49:24.791366Z","caller":"traceutil/trace.go:171","msg":"trace[1279919688] transaction","detail":"{read_only:false; response_revision:31818; number_of_response:1; }","duration":"113.280259ms","start":"2026-10-19T02:49:24.678049Z","end":"2026-10-19T02:49:24.791329Z","steps":["trace[1279919688] 'process raft request' (duration: 109.980751ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:50:03.927918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.782816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-10-19T02:50:03.927998Z","caller":"traceutil/trace.go:171","msg":"trace[912127930] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:32213; }","duration":"582.897878ms","start":"2026-10-19T02:50:03.345083Z","end":"2026-10-19T02:50:03.927981Z","steps":["trace[912127930] 'range keys from in-memory index tree' (duration: 582.622591ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:50:03.928345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:50:03.345066Z","time spent":"582.9768ms","remote":"127.0.0.1:39550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":549,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"warn","ts":"2026-10-19T02:50:03.928968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.488499ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-19T02:50:03.929103Z","caller":"traceutil/trace.go:171","msg":"trace[1181444978] linearizableReadLoop","detail":"{readStateIndex:33676; appliedIndex:33675; }","duration":"313.37653ms","start":"2026-10-19T02:50:03.615705Z","end":"2026-10-19T02:50:03.929081Z","steps":["trace[1181444978] 'read index received' (duration: 128.660395ms)","trace[1181444978] 'applied index is now lower than readState.Index' (duration: 184.714335ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T02:50:03.929137Z","caller":"traceutil/trace.go:171","msg":"trace[1714441542] transaction","detail":"{read_only:false; response_revision:32214; number_of_response:1; }","duration":"558.175211ms","start":"2026-10-19T02:50:03.370922Z","end":"2026-10-19T02:50:03.929098Z","steps":["trace[1714441542] 'process raft request' (duration: 373.442226ms)","trace[1714441542] 'compare' (duration: 183.671ms)"],"step_count":2} {"level":"warn","ts":"2026-10-19T02:50:03.929299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:50:03.370899Z","time spent":"558.301424ms","remote":"127.0.0.1:39550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-19T02:50:03.929344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.642876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-10-19T02:50:03.929397Z","caller":"traceutil/trace.go:171","msg":"trace[1961353658] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:32214; }","duration":"313.740688ms","start":"2026-10-19T02:50:03.615637Z","end":"2026-10-19T02:50:03.929377Z","steps":["trace[1961353658] 'agreement among raft nodes before linearized reading' (duration: 313.532743ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T02:50:03.929442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T02:50:03.615613Z","time spent":"313.81683ms","remote":"127.0.0.1:39392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":380,"request content":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" "} {"level":"warn","ts":"2026-10-19T02:50:03.98314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.643348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-19T02:50:03.983164Z","caller":"traceutil/trace.go:171","msg":"trace[2020501111] transaction","detail":"{read_only:false; response_revision:32215; number_of_response:1; }","duration":"261.122609ms","start":"2026-10-19T02:50:03.721953Z","end":"2026-10-19T02:50:03.983075Z","steps":["trace[2020501111] 'process raft request' (duration: 260.908384ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:50:03.983236Z","caller":"traceutil/trace.go:171","msg":"trace[90039311] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:32215; }","duration":"252.819512ms","start":"2026-10-19T02:50:03.730398Z","end":"2026-10-19T02:50:03.983217Z","steps":["trace[90039311] 'agreement among raft nodes before linearized reading' (duration: 252.612907ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:50:04.182501Z","caller":"traceutil/trace.go:171","msg":"trace[1686290058] linearizableReadLoop","detail":"{readStateIndex:33678; appliedIndex:33677; }","duration":"149.670744ms","start":"2026-10-19T02:50:04.032803Z","end":"2026-10-19T02:50:04.182473Z","steps":["trace[1686290058] 'read index received' (duration: 143.025756ms)","trace[1686290058] 'applied index is now lower than readState.Index' (duration: 6.644048ms)"],"step_count":2} {"level":"warn","ts":"2026-10-19T02:50:04.182627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.798367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-19T02:50:04.182585Z","caller":"traceutil/trace.go:171","msg":"trace[589845411] transaction","detail":"{read_only:false; response_revision:32216; number_of_response:1; }","duration":"250.225671ms","start":"2026-10-19T02:50:03.932298Z","end":"2026-10-19T02:50:04.182524Z","steps":["trace[589845411] 'process raft request' (duration: 243.619624ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:50:04.182664Z","caller":"traceutil/trace.go:171","msg":"trace[1724122246] 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:32216; }","duration":"149.856008ms","start":"2026-10-19T02:50:04.032796Z","end":"2026-10-19T02:50:04.182652Z","steps":["trace[1724122246] 'agreement among raft nodes before linearized reading' (duration: 149.772916ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T02:51:49.641908Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30042} {"level":"info","ts":"2026-10-19T02:51:49.810857Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30042,"took":"165.065177ms","hash":1088939458,"current-db-size-bytes":61022208,"current-db-size":"61 MB","current-db-size-in-use-bytes":37556224,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-10-19T02:51:49.811Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1088939458,"revision":30042,"compact-revision":26650} {"level":"info","ts":"2026-10-19T02:56:49.656607Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33179} {"level":"info","ts":"2026-10-19T02:56:49.806913Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33179,"took":"143.302129ms","hash":1756016889,"current-db-size-bytes":61022208,"current-db-size":"61 MB","current-db-size-in-use-bytes":34660352,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-19T02:56:49.806997Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1756016889,"revision":33179,"compact-revision":30042} {"level":"info","ts":"2026-10-19T03:01:08.739278Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5db0a1b394e67b72","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-19T03:01:08.746482Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-19T03:01:08.746683Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-19T03:01:49.664907Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35849} {"level":"info","ts":"2026-10-19T03:01:49.804049Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35849,"took":"134.929308ms","hash":3672626095,"current-db-size-bytes":61022208,"current-db-size":"61 MB","current-db-size-in-use-bytes":34820096,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-19T03:01:49.804118Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3672626095,"revision":35849,"compact-revision":33179} {"level":"info","ts":"2026-10-19T03:06:49.673613Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":38628} {"level":"info","ts":"2026-10-19T03:06:49.827041Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":38628,"took":"150.356906ms","hash":3611784011,"current-db-size-bytes":61022208,"current-db-size":"61 MB","current-db-size-in-use-bytes":35471360,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-19T03:06:49.827135Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3611784011,"revision":38628,"compact-revision":35849} {"level":"info","ts":"2026-10-19T03:11:49.679732Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":41400} {"level":"info","ts":"2026-10-19T03:11:49.853177Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":41400,"took":"166.167379ms","hash":2489078385,"current-db-size-bytes":61022208,"current-db-size":"61 MB","current-db-size-in-use-bytes":34144256,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-19T03:11:49.853278Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2489078385,"revision":41400,"compact-revision":38628} {"level":"info","ts":"2026-10-19T03:16:49.686494Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":44078} {"level":"info","ts":"2026-10-19T03:16:49.816523Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":44078,"took":"127.395049ms","hash":4023557449,"current-db-size-bytes":61022208,"current-db-size":"61 MB","current-db-size-in-use-bytes":33267712,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-19T03:16:49.816594Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4023557449,"revision":44078,"compact-revision":41400} {"level":"info","ts":"2026-10-19T03:18:46.188175Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5db0a1b394e67b72","local-member-applied-index":50005,"local-member-snapshot-index":40004,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-19T03:18:46.194034Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":50005} {"level":"info","ts":"2026-10-19T03:18:46.194121Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":45005}