{"level":"warn","ts":"2026-10-15T19:04:16.14213Z","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-15T19:04:16.142248Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.17: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.17:2380","--initial-cluster=instance=https://162.253.55.17:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.17:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.17: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-15T19:04:16.142328Z","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-15T19:04:16.142347Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.17:2380"]} {"level":"info","ts":"2026-10-15T19:04:16.142372Z","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-15T19:04:16.143272Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.17:2379"]} {"level":"info","ts":"2026-10-15T19:04:16.143368Z","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.17:2380"],"listen-peer-urls":["https://162.253.55.17:2380"],"advertise-client-urls":["https://162.253.55.17:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.17:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.17: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-15T19:04:16.147855Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.928555ms"} {"level":"info","ts":"2026-10-15T19:04:16.155678Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9d6ea9653fb28c79","cluster-id":"57f89a414bd43f68"} {"level":"info","ts":"2026-10-15T19:04:16.155818Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9d6ea9653fb28c79 switched to configuration voters=()"} {"level":"info","ts":"2026-10-15T19:04:16.155846Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9d6ea9653fb28c79 became follower at term 0"} {"level":"info","ts":"2026-10-15T19:04:16.155909Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9d6ea9653fb28c79 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-15T19:04:16.155922Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9d6ea9653fb28c79 became follower at term 1"} {"level":"info","ts":"2026-10-15T19:04:16.155991Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9d6ea9653fb28c79 switched to configuration voters=(11344190763718315129)"} {"level":"warn","ts":"2026-10-15T19:04:16.162936Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-15T19:04:16.165813Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-15T19:04:16.167611Z","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-15T19:04:16.171855Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9d6ea9653fb28c79","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-15T19:04:16.172057Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9d6ea9653fb28c79","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-15T19:04:16.172194Z","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-15T19:04:16.172281Z","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-15T19:04:16.172308Z","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-15T19:04:16.175591Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9d6ea9653fb28c79 switched to configuration voters=(11344190763718315129)"} {"level":"info","ts":"2026-10-15T19:04:16.175872Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"57f89a414bd43f68","local-member-id":"9d6ea9653fb28c79","added-peer-id":"9d6ea9653fb28c79","added-peer-peer-urls":["https://162.253.55.17:2380"]} {"level":"info","ts":"2026-10-15T19:04:16.179437Z","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-15T19:04:16.179593Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.17:2380"} {"level":"info","ts":"2026-10-15T19:04:16.17965Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.17:2380"} {"level":"info","ts":"2026-10-15T19:04:16.17989Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9d6ea9653fb28c79","initial-advertise-peer-urls":["https://162.253.55.17:2380"],"listen-peer-urls":["https://162.253.55.17:2380"],"advertise-client-urls":["https://162.253.55.17:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.17:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-15T19:04:16.179988Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-15T19:04:16.756514Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9d6ea9653fb28c79 is starting a new election at term 1"} {"level":"info","ts":"2026-10-15T19:04:16.756615Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9d6ea9653fb28c79 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-15T19:04:16.756637Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9d6ea9653fb28c79 received MsgPreVoteResp from 9d6ea9653fb28c79 at term 1"} {"level":"info","ts":"2026-10-15T19:04:16.756655Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9d6ea9653fb28c79 became candidate at term 2"} {"level":"info","ts":"2026-10-15T19:04:16.756666Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9d6ea9653fb28c79 received MsgVoteResp from 9d6ea9653fb28c79 at term 2"} {"level":"info","ts":"2026-10-15T19:04:16.756681Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9d6ea9653fb28c79 became leader at term 2"} {"level":"info","ts":"2026-10-15T19:04:16.756693Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9d6ea9653fb28c79 elected leader 9d6ea9653fb28c79 at term 2"} {"level":"info","ts":"2026-10-15T19:04:16.761416Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9d6ea9653fb28c79","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.17:2379]}","request-path":"/0/members/9d6ea9653fb28c79/attributes","cluster-id":"57f89a414bd43f68","publish-timeout":"7s"} {"level":"info","ts":"2026-10-15T19:04:16.761602Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-15T19:04:16.761711Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-15T19:04:16.761773Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T19:04:16.765179Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.17:2379"} {"level":"info","ts":"2026-10-15T19:04:16.765325Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-15T19:04:16.765361Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-15T19:04:16.765724Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-15T19:04:16.768784Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"57f89a414bd43f68","local-member-id":"9d6ea9653fb28c79","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T19:04:16.768991Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T19:04:16.769111Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T19:04:33.496711Z","caller":"traceutil/trace.go:171","msg":"trace[1425130847] linearizableReadLoop","detail":"{readStateIndex:425; appliedIndex:424; }","duration":"125.533291ms","start":"2026-10-15T19:04:33.371149Z","end":"2026-10-15T19:04:33.496683Z","steps":["trace[1425130847] 'read index received' (duration: 125.297746ms)","trace[1425130847] 'applied index is now lower than readState.Index' (duration: 234.675µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:04:33.496852Z","caller":"traceutil/trace.go:171","msg":"trace[121611175] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"166.354219ms","start":"2026-10-15T19:04:33.330476Z","end":"2026-10-15T19:04:33.49683Z","steps":["trace[121611175] 'process raft request' (duration: 166.074853ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:04:33.496869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.694895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T19:04:33.496966Z","caller":"traceutil/trace.go:171","msg":"trace[694928121] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:411; }","duration":"125.846258ms","start":"2026-10-15T19:04:33.371104Z","end":"2026-10-15T19:04:33.49695Z","steps":["trace[694928121] 'agreement among raft nodes before linearized reading' (duration: 125.692565ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:04:38.916247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.825984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T19:04:38.916336Z","caller":"traceutil/trace.go:171","msg":"trace[1295441614] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:426; }","duration":"157.956507ms","start":"2026-10-15T19:04:38.758361Z","end":"2026-10-15T19:04:38.916318Z","steps":["trace[1295441614] 'range keys from in-memory index tree' (duration: 157.764312ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:04:38.934755Z","caller":"traceutil/trace.go:171","msg":"trace[1644115392] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"124.65128ms","start":"2026-10-15T19:04:38.810056Z","end":"2026-10-15T19:04:38.934707Z","steps":["trace[1644115392] 'process raft request' (duration: 124.305461ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:04:46.750369Z","caller":"traceutil/trace.go:171","msg":"trace[124863125] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"108.796726ms","start":"2026-10-15T19:04:46.641542Z","end":"2026-10-15T19:04:46.750339Z","steps":["trace[124863125] 'process raft request' (duration: 42.122671ms)","trace[124863125] 'compare' (duration: 66.499681ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:04:46.763908Z","caller":"traceutil/trace.go:171","msg":"trace[1276291514] transaction","detail":"{read_only:false; response_revision:451; number_of_response:1; }","duration":"113.756035ms","start":"2026-10-15T19:04:46.650132Z","end":"2026-10-15T19:04:46.763888Z","steps":["trace[1276291514] 'process raft request' (duration: 113.492309ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:04:46.764077Z","caller":"traceutil/trace.go:171","msg":"trace[1153785765] transaction","detail":"{read_only:false; response_revision:453; number_of_response:1; }","duration":"111.707286ms","start":"2026-10-15T19:04:46.65229Z","end":"2026-10-15T19:04:46.763997Z","steps":["trace[1153785765] 'process raft request' (duration: 111.534931ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:04:46.764413Z","caller":"traceutil/trace.go:171","msg":"trace[1939652763] transaction","detail":"{read_only:false; response_revision:452; number_of_response:1; }","duration":"112.187657ms","start":"2026-10-15T19:04:46.651762Z","end":"2026-10-15T19:04:46.763949Z","steps":["trace[1939652763] 'process raft request' (duration: 112.020543ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:04:46.763993Z","caller":"traceutil/trace.go:171","msg":"trace[1185521147] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"109.643406ms","start":"2026-10-15T19:04:46.654314Z","end":"2026-10-15T19:04:46.763958Z","steps":["trace[1185521147] 'process raft request' (duration: 109.586004ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:04:46.879999Z","caller":"traceutil/trace.go:171","msg":"trace[26642265] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"107.915114ms","start":"2026-10-15T19:04:46.772045Z","end":"2026-10-15T19:04:46.87996Z","steps":["trace[26642265] 'process raft request' (duration: 79.963856ms)","trace[26642265] 'compare' (duration: 27.745983ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:05:07.807529Z","caller":"traceutil/trace.go:171","msg":"trace[369358741] linearizableReadLoop","detail":"{readStateIndex:672; appliedIndex:671; }","duration":"116.691321ms","start":"2026-10-15T19:05:07.690813Z","end":"2026-10-15T19:05:07.807505Z","steps":["trace[369358741] 'read index received' (duration: 116.605219ms)","trace[369358741] 'applied index is now lower than readState.Index' (duration: 84.832µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:05:07.807567Z","caller":"traceutil/trace.go:171","msg":"trace[1379760966] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"172.105711ms","start":"2026-10-15T19:05:07.635433Z","end":"2026-10-15T19:05:07.807539Z","steps":["trace[1379760966] 'process raft request' (duration: 171.903536ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:05:07.808031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.206473ms","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-15T19:05:07.808216Z","caller":"traceutil/trace.go:171","msg":"trace[1373394882] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:648; }","duration":"117.434209ms","start":"2026-10-15T19:05:07.690756Z","end":"2026-10-15T19:05:07.80819Z","steps":["trace[1373394882] 'agreement among raft nodes before linearized reading' (duration: 116.877116ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:05:08.105147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.222179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-x6ww5\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-10-15T19:05:08.105247Z","caller":"traceutil/trace.go:171","msg":"trace[1409560407] range","detail":"{range_begin:/registry/pods/kube-system/cilium-x6ww5; range_end:; response_count:1; response_revision:649; }","duration":"171.364892ms","start":"2026-10-15T19:05:07.933857Z","end":"2026-10-15T19:05:08.105222Z","steps":["trace[1409560407] 'range keys from in-memory index tree' (duration: 170.932152ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:05:08.10555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.560588ms","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-15T19:05:08.105609Z","caller":"traceutil/trace.go:171","msg":"trace[296404323] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:649; }","duration":"169.677271ms","start":"2026-10-15T19:05:07.935917Z","end":"2026-10-15T19:05:08.105595Z","steps":["trace[296404323] 'range keys from in-memory index tree' (duration: 169.399585ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:05:08.215365Z","caller":"traceutil/trace.go:171","msg":"trace[336969627] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"105.551351ms","start":"2026-10-15T19:05:08.109784Z","end":"2026-10-15T19:05:08.215335Z","steps":["trace[336969627] 'process raft request' (duration: 105.395057ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:05:08.291196Z","caller":"traceutil/trace.go:171","msg":"trace[1930920420] linearizableReadLoop","detail":"{readStateIndex:675; appliedIndex:673; }","duration":"142.843973ms","start":"2026-10-15T19:05:08.148304Z","end":"2026-10-15T19:05:08.291148Z","steps":["trace[1930920420] 'read index received' (duration: 66.987779ms)","trace[1930920420] 'applied index is now lower than readState.Index' (duration: 75.855174ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:05:08.291383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.048418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-10-15T19:05:08.291547Z","caller":"traceutil/trace.go:171","msg":"trace[448664389] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"167.045558ms","start":"2026-10-15T19:05:08.124449Z","end":"2026-10-15T19:05:08.291494Z","steps":["trace[448664389] 'process raft request' (duration: 166.514185ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:05:08.2917Z","caller":"traceutil/trace.go:171","msg":"trace[1740895584] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:651; }","duration":"143.13866ms","start":"2026-10-15T19:05:08.14827Z","end":"2026-10-15T19:05:08.291409Z","steps":["trace[1740895584] 'agreement among raft nodes before linearized reading' (duration: 142.993407ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:05:08.962056Z","caller":"traceutil/trace.go:171","msg":"trace[127948589] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"719.664946ms","start":"2026-10-15T19:05:08.242362Z","end":"2026-10-15T19:05:08.962027Z","steps":["trace[127948589] 'process raft request' (duration: 719.083162ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:05:08.962756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:05:08.242329Z","time spent":"719.841189ms","remote":"127.0.0.1:58870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T19:05:09.005135Z","caller":"traceutil/trace.go:171","msg":"trace[2022664711] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"708.190467ms","start":"2026-10-15T19:05:08.296875Z","end":"2026-10-15T19:05:09.005065Z","steps":["trace[2022664711] 'process raft request' (duration: 708.028273ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:05:09.005298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:05:08.296853Z","time spent":"708.361752ms","remote":"127.0.0.1:59034","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-15T19:05:25.366359Z","caller":"traceutil/trace.go:171","msg":"trace[1620761859] linearizableReadLoop","detail":"{readStateIndex:849; appliedIndex:848; }","duration":"224.357824ms","start":"2026-10-15T19:05:25.141972Z","end":"2026-10-15T19:05:25.36633Z","steps":["trace[1620761859] 'read index received' (duration: 224.19494ms)","trace[1620761859] 'applied index is now lower than readState.Index' (duration: 161.694µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:05:25.366406Z","caller":"traceutil/trace.go:171","msg":"trace[1094409592] transaction","detail":"{read_only:false; response_revision:820; number_of_response:1; }","duration":"314.100662ms","start":"2026-10-15T19:05:25.052285Z","end":"2026-10-15T19:05:25.366386Z","steps":["trace[1094409592] 'process raft request' (duration: 313.880487ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:05:25.366539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:05:25.052258Z","time spent":"314.206875ms","remote":"127.0.0.1:58954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4027,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T19:05:25.366602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.61665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-10-15T19:05:25.36665Z","caller":"traceutil/trace.go:171","msg":"trace[329847655] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:820; }","duration":"224.694651ms","start":"2026-10-15T19:05:25.141942Z","end":"2026-10-15T19:05:25.366637Z","steps":["trace[329847655] 'agreement among raft nodes before linearized reading' (duration: 224.537257ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:05:25.387753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.517283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-15T19:05:25.387944Z","caller":"traceutil/trace.go:171","msg":"trace[2096744424] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:821; }","duration":"156.749979ms","start":"2026-10-15T19:05:25.231171Z","end":"2026-10-15T19:05:25.387921Z","steps":["trace[2096744424] 'agreement among raft nodes before linearized reading' (duration: 156.36928ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:06:45.428661Z","caller":"traceutil/trace.go:171","msg":"trace[1817337130] transaction","detail":"{read_only:false; response_revision:1909; number_of_response:1; }","duration":"194.710237ms","start":"2026-10-15T19:06:45.233926Z","end":"2026-10-15T19:06:45.428637Z","steps":["trace[1817337130] 'process raft request' (duration: 194.553234ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:06:45.430478Z","caller":"traceutil/trace.go:171","msg":"trace[94821757] transaction","detail":"{read_only:false; response_revision:1910; number_of_response:1; }","duration":"157.324864ms","start":"2026-10-15T19:06:45.273136Z","end":"2026-10-15T19:06:45.430461Z","steps":["trace[94821757] 'process raft request' (duration: 157.204541ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:06:45.534531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.006924ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-15T19:06:45.534601Z","caller":"traceutil/trace.go:171","msg":"trace[362042664] linearizableReadLoop","detail":"{readStateIndex:1983; appliedIndex:1980; }","duration":"135.729812ms","start":"2026-10-15T19:06:45.398858Z","end":"2026-10-15T19:06:45.534587Z","steps":["trace[362042664] 'read index received' (duration: 29.687587ms)","trace[362042664] 'applied index is now lower than readState.Index' (duration: 106.040685ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:06:45.534753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.900755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-10-15T19:06:45.534773Z","caller":"traceutil/trace.go:171","msg":"trace[1919692864] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1910; }","duration":"135.964867ms","start":"2026-10-15T19:06:45.398802Z","end":"2026-10-15T19:06:45.534767Z","steps":["trace[1919692864] 'agreement among raft nodes before linearized reading' (duration: 135.844374ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:06:45.534927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.900089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0260d683-253f-44dc-a2a2-774e31993eef\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-10-15T19:06:45.535014Z","caller":"traceutil/trace.go:171","msg":"trace[520501649] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0260d683-253f-44dc-a2a2-774e31993eef; range_end:; response_count:1; response_revision:1910; }","duration":"101.065174ms","start":"2026-10-15T19:06:45.433928Z","end":"2026-10-15T19:06:45.534993Z","steps":["trace[520501649] 'agreement among raft nodes before linearized reading' (duration: 100.803387ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:06:46.294846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.367856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-10-15T19:06:46.294877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.574602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-10-15T19:06:46.294888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.579342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T19:06:46.294925Z","caller":"traceutil/trace.go:171","msg":"trace[1533086372] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1911; }","duration":"435.500309ms","start":"2026-10-15T19:06:45.859406Z","end":"2026-10-15T19:06:46.294906Z","steps":["trace[1533086372] 'range keys from in-memory index tree' (duration: 435.172691ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:06:46.294938Z","caller":"traceutil/trace.go:171","msg":"trace[251153566] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1911; }","duration":"428.666604ms","start":"2026-10-15T19:06:45.866255Z","end":"2026-10-15T19:06:46.294922Z","steps":["trace[251153566] 'range keys from in-memory index tree' (duration: 428.450169ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:06:46.294969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:06:45.866246Z","time spent":"428.716055ms","remote":"127.0.0.1:59034","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":"warn","ts":"2026-10-15T19:06:46.294969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:06:45.859384Z","time spent":"435.576562ms","remote":"127.0.0.1:59034","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-15T19:06:46.294974Z","caller":"traceutil/trace.go:171","msg":"trace[1938572918] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1911; }","duration":"536.661084ms","start":"2026-10-15T19:06:45.758283Z","end":"2026-10-15T19:06:46.294944Z","steps":["trace[1938572918] 'range keys from in-memory index tree' (duration: 536.50211ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:06:46.295011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:06:45.758225Z","time spent":"536.778467ms","remote":"127.0.0.1:58736","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-15T19:06:46.29504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.167267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0260d683-253f-44dc-a2a2-774e31993eef\" ","response":"range_response_count:1 size:3746"} {"level":"info","ts":"2026-10-15T19:06:46.295114Z","caller":"traceutil/trace.go:171","msg":"trace[1828875256] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0260d683-253f-44dc-a2a2-774e31993eef; range_end:; response_count:1; response_revision:1911; }","duration":"518.255629ms","start":"2026-10-15T19:06:45.776814Z","end":"2026-10-15T19:06:46.29507Z","steps":["trace[1828875256] 'range keys from in-memory index tree' (duration: 517.975283ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:06:46.295156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:06:45.776785Z","time spent":"518.362562ms","remote":"127.0.0.1:58954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3769,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0260d683-253f-44dc-a2a2-774e31993eef\" "} {"level":"info","ts":"2026-10-15T19:06:46.295165Z","caller":"traceutil/trace.go:171","msg":"trace[1257700917] transaction","detail":"{read_only:false; response_revision:1912; number_of_response:1; }","duration":"228.232118ms","start":"2026-10-15T19:06:46.066899Z","end":"2026-10-15T19:06:46.295131Z","steps":["trace[1257700917] 'process raft request' (duration: 177.677656ms)","trace[1257700917] 'compare' (duration: 50.119191ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:06:46.298718Z","caller":"traceutil/trace.go:171","msg":"trace[2124201768] transaction","detail":"{read_only:false; response_revision:1913; number_of_response:1; }","duration":"127.581165ms","start":"2026-10-15T19:06:46.171115Z","end":"2026-10-15T19:06:46.298696Z","steps":["trace[2124201768] 'process raft request' (duration: 127.269108ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:06:53.949456Z","caller":"traceutil/trace.go:171","msg":"trace[1187644666] transaction","detail":"{read_only:false; response_revision:1985; number_of_response:1; }","duration":"146.201925ms","start":"2026-10-15T19:06:53.803225Z","end":"2026-10-15T19:06:53.949426Z","steps":["trace[1187644666] 'process raft request' (duration: 146.051731ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:06:58.459372Z","caller":"traceutil/trace.go:171","msg":"trace[2006996632] linearizableReadLoop","detail":"{readStateIndex:2088; appliedIndex:2087; }","duration":"110.455411ms","start":"2026-10-15T19:06:58.348893Z","end":"2026-10-15T19:06:58.459349Z","steps":["trace[2006996632] 'read index received' (duration: 110.285716ms)","trace[2006996632] 'applied index is now lower than readState.Index' (duration: 168.735µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:06:58.459435Z","caller":"traceutil/trace.go:171","msg":"trace[1251297932] transaction","detail":"{read_only:false; response_revision:2012; number_of_response:1; }","duration":"123.099265ms","start":"2026-10-15T19:06:58.336316Z","end":"2026-10-15T19:06:58.459415Z","steps":["trace[1251297932] 'process raft request' (duration: 122.90341ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:06:58.459529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.625605ms","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-15T19:06:58.459589Z","caller":"traceutil/trace.go:171","msg":"trace[2006149169] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2012; }","duration":"110.724877ms","start":"2026-10-15T19:06:58.34885Z","end":"2026-10-15T19:06:58.459575Z","steps":["trace[2006149169] 'agreement among raft nodes before linearized reading' (duration: 110.582544ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:06:58.459881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.942548ms","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-15T19:06:58.459923Z","caller":"traceutil/trace.go:171","msg":"trace[1360933756] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2012; }","duration":"110.001199ms","start":"2026-10-15T19:06:58.349908Z","end":"2026-10-15T19:06:58.459909Z","steps":["trace[1360933756] 'agreement among raft nodes before linearized reading' (duration: 109.558599ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:06:58.752456Z","caller":"traceutil/trace.go:171","msg":"trace[1687797229] linearizableReadLoop","detail":"{readStateIndex:2091; appliedIndex:2090; }","duration":"178.483825ms","start":"2026-10-15T19:06:58.573943Z","end":"2026-10-15T19:06:58.752427Z","steps":["trace[1687797229] 'read index received' (duration: 178.29575ms)","trace[1687797229] 'applied index is now lower than readState.Index' (duration: 187.075µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:06:58.752584Z","caller":"traceutil/trace.go:171","msg":"trace[1373368033] transaction","detail":"{read_only:false; response_revision:2015; number_of_response:1; }","duration":"182.131873ms","start":"2026-10-15T19:06:58.570422Z","end":"2026-10-15T19:06:58.752554Z","steps":["trace[1373368033] 'process raft request' (duration: 181.878097ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:06:58.752693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.727231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-10-15T19:06:58.752738Z","caller":"traceutil/trace.go:171","msg":"trace[1252982940] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2015; }","duration":"178.809412ms","start":"2026-10-15T19:06:58.573915Z","end":"2026-10-15T19:06:58.752725Z","steps":["trace[1252982940] 'agreement among raft nodes before linearized reading' (duration: 178.630258ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:06:58.752828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.328069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-10-15T19:06:58.752905Z","caller":"traceutil/trace.go:171","msg":"trace[1652366361] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2015; }","duration":"127.449852ms","start":"2026-10-15T19:06:58.625438Z","end":"2026-10-15T19:06:58.752888Z","steps":["trace[1652366361] 'agreement among raft nodes before linearized reading' (duration: 127.267358ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:07:30.167937Z","caller":"traceutil/trace.go:171","msg":"trace[276177970] transaction","detail":"{read_only:false; response_revision:2202; number_of_response:1; }","duration":"116.122385ms","start":"2026-10-15T19:07:30.051785Z","end":"2026-10-15T19:07:30.167907Z","steps":["trace[276177970] 'process raft request' (duration: 108.197333ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:07:30.97391Z","caller":"traceutil/trace.go:171","msg":"trace[146875147] linearizableReadLoop","detail":"{readStateIndex:2287; appliedIndex:2286; }","duration":"346.998402ms","start":"2026-10-15T19:07:30.626888Z","end":"2026-10-15T19:07:30.973887Z","steps":["trace[146875147] 'read index received' (duration: 346.760836ms)","trace[146875147] 'applied index is now lower than readState.Index' (duration: 236.386µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:07:30.973973Z","caller":"traceutil/trace.go:171","msg":"trace[267406268] transaction","detail":"{read_only:false; response_revision:2203; number_of_response:1; }","duration":"406.059889ms","start":"2026-10-15T19:07:30.56789Z","end":"2026-10-15T19:07:30.97395Z","steps":["trace[267406268] 'process raft request' (duration: 405.824513ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:07:30.974164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.371817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-15T19:07:30.974165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.584031ms","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-15T19:07:30.974206Z","caller":"traceutil/trace.go:171","msg":"trace[1555795084] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2203; }","duration":"216.442108ms","start":"2026-10-15T19:07:30.757754Z","end":"2026-10-15T19:07:30.974196Z","steps":["trace[1555795084] 'agreement among raft nodes before linearized reading' (duration: 216.352757ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:07:30.974217Z","caller":"traceutil/trace.go:171","msg":"trace[1786390043] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2203; }","duration":"341.651432ms","start":"2026-10-15T19:07:30.632554Z","end":"2026-10-15T19:07:30.974205Z","steps":["trace[1786390043] 'agreement among raft nodes before linearized reading' (duration: 341.496669ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:07:30.974245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:07:30.632549Z","time spent":"341.689093ms","remote":"127.0.0.1:59034","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-15T19:07:30.974244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:07:30.567872Z","time spent":"406.179082ms","remote":"127.0.0.1:59034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T19:07:30.974449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.568365ms","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-15T19:07:30.974478Z","caller":"traceutil/trace.go:171","msg":"trace[1974442033] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2203; }","duration":"347.623817ms","start":"2026-10-15T19:07:30.626847Z","end":"2026-10-15T19:07:30.974471Z","steps":["trace[1974442033] 'agreement among raft nodes before linearized reading' (duration: 347.192267ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:07:30.974498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:07:30.626832Z","time spent":"347.659277ms","remote":"127.0.0.1:59034","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-15T19:07:31.244674Z","caller":"traceutil/trace.go:171","msg":"trace[116951842] transaction","detail":"{read_only:false; response_revision:2204; number_of_response:1; }","duration":"266.061667ms","start":"2026-10-15T19:07:30.978579Z","end":"2026-10-15T19:07:31.244641Z","steps":["trace[116951842] 'process raft request' (duration: 262.06968ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:07:31.323654Z","caller":"traceutil/trace.go:171","msg":"trace[1621925534] transaction","detail":"{read_only:false; response_revision:2206; number_of_response:1; }","duration":"340.197229ms","start":"2026-10-15T19:07:30.983437Z","end":"2026-10-15T19:07:31.323635Z","steps":["trace[1621925534] 'process raft request' (duration: 340.139527ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:07:31.323768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:07:30.983413Z","time spent":"340.29546ms","remote":"127.0.0.1:59034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T19:07:31.323716Z","caller":"traceutil/trace.go:171","msg":"trace[1343781582] transaction","detail":"{read_only:false; response_revision:2205; number_of_response:1; }","duration":"344.968204ms","start":"2026-10-15T19:07:30.97871Z","end":"2026-10-15T19:07:31.323678Z","steps":["trace[1343781582] 'process raft request' (duration: 344.766639ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:07:31.323939Z","caller":"traceutil/trace.go:171","msg":"trace[1041363047] transaction","detail":"{read_only:false; response_revision:2207; number_of_response:1; }","duration":"292.736732ms","start":"2026-10-15T19:07:31.031183Z","end":"2026-10-15T19:07:31.32392Z","steps":["trace[1041363047] 'process raft request' (duration: 292.416414ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:07:31.324168Z","caller":"traceutil/trace.go:171","msg":"trace[714289956] linearizableReadLoop","detail":"{readStateIndex:2292; appliedIndex:2287; }","duration":"231.199515ms","start":"2026-10-15T19:07:31.092937Z","end":"2026-10-15T19:07:31.324136Z","steps":["trace[714289956] 'read index received' (duration: 147.726879ms)","trace[714289956] 'applied index is now lower than readState.Index' (duration: 83.471436ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:07:31.324238Z","caller":"traceutil/trace.go:171","msg":"trace[1144283471] transaction","detail":"{read_only:false; response_revision:2208; number_of_response:1; }","duration":"258.270959ms","start":"2026-10-15T19:07:31.065934Z","end":"2026-10-15T19:07:31.324205Z","steps":["trace[1144283471] 'process raft request' (duration: 257.938401ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:07:31.32446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.508062ms","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-15T19:07:31.324615Z","caller":"traceutil/trace.go:171","msg":"trace[912994488] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2208; }","duration":"231.677776ms","start":"2026-10-15T19:07:31.092885Z","end":"2026-10-15T19:07:31.324563Z","steps":["trace[912994488] 'agreement among raft nodes before linearized reading' (duration: 231.384499ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:07:31.325014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:07:30.978686Z","time spent":"345.169178ms","remote":"127.0.0.1:59034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T19:07:37.161489Z","caller":"traceutil/trace.go:171","msg":"trace[1711984604] linearizableReadLoop","detail":"{readStateIndex:2321; appliedIndex:2320; }","duration":"161.953472ms","start":"2026-10-15T19:07:36.999512Z","end":"2026-10-15T19:07:37.161465Z","steps":["trace[1711984604] 'read index received' (duration: 161.768507ms)","trace[1711984604] 'applied index is now lower than readState.Index' (duration: 183.845µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:07:37.161562Z","caller":"traceutil/trace.go:171","msg":"trace[1125545025] transaction","detail":"{read_only:false; response_revision:2236; number_of_response:1; }","duration":"164.684358ms","start":"2026-10-15T19:07:36.99685Z","end":"2026-10-15T19:07:37.161534Z","steps":["trace[1125545025] 'process raft request' (duration: 164.482663ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:07:37.16167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.140817ms","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-15T19:07:37.161764Z","caller":"traceutil/trace.go:171","msg":"trace[138256391] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:2236; }","duration":"162.28287ms","start":"2026-10-15T19:07:36.999456Z","end":"2026-10-15T19:07:37.161739Z","steps":["trace[138256391] 'agreement among raft nodes before linearized reading' (duration: 162.126576ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:07:37.161837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.503166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-10-15T19:07:37.161911Z","caller":"traceutil/trace.go:171","msg":"trace[405567192] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2236; }","duration":"143.609828ms","start":"2026-10-15T19:07:37.01828Z","end":"2026-10-15T19:07:37.16189Z","steps":["trace[405567192] 'agreement among raft nodes before linearized reading' (duration: 143.365702ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:07:37.499838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.759703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-15T19:07:37.49992Z","caller":"traceutil/trace.go:171","msg":"trace[697304848] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2236; }","duration":"227.877014ms","start":"2026-10-15T19:07:37.272023Z","end":"2026-10-15T19:07:37.4999Z","steps":["trace[697304848] 'range keys from in-memory index tree' (duration: 227.577438ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:07:37.568135Z","caller":"traceutil/trace.go:171","msg":"trace[744123359] transaction","detail":"{read_only:false; response_revision:2237; number_of_response:1; }","duration":"227.886814ms","start":"2026-10-15T19:07:37.340224Z","end":"2026-10-15T19:07:37.56811Z","steps":["trace[744123359] 'process raft request' (duration: 227.72799ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:07:37.800539Z","caller":"traceutil/trace.go:171","msg":"trace[1110760319] linearizableReadLoop","detail":"{readStateIndex:2323; appliedIndex:2321; }","duration":"453.032254ms","start":"2026-10-15T19:07:37.347479Z","end":"2026-10-15T19:07:37.800512Z","steps":["trace[1110760319] 'read index received' (duration: 220.573369ms)","trace[1110760319] 'applied index is now lower than readState.Index' (duration: 232.457795ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:07:37.800601Z","caller":"traceutil/trace.go:171","msg":"trace[1688112662] transaction","detail":"{read_only:false; response_revision:2239; number_of_response:1; }","duration":"296.262277ms","start":"2026-10-15T19:07:37.504296Z","end":"2026-10-15T19:07:37.800558Z","steps":["trace[1688112662] 'process raft request' (duration: 296.174315ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:07:37.800795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.29265ms","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-15T19:07:37.800872Z","caller":"traceutil/trace.go:171","msg":"trace[325548265] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2239; }","duration":"453.405212ms","start":"2026-10-15T19:07:37.34745Z","end":"2026-10-15T19:07:37.800855Z","steps":["trace[325548265] 'agreement among raft nodes before linearized reading' (duration: 453.192627ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:07:37.800904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:07:37.34744Z","time spent":"453.455434ms","remote":"127.0.0.1:59034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-15T19:07:37.800596Z","caller":"traceutil/trace.go:171","msg":"trace[1977926980] transaction","detail":"{read_only:false; response_revision:2238; number_of_response:1; }","duration":"456.997639ms","start":"2026-10-15T19:07:37.343574Z","end":"2026-10-15T19:07:37.800571Z","steps":["trace[1977926980] 'process raft request' (duration: 456.747113ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:07:37.801162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:07:37.343553Z","time spent":"457.504102ms","remote":"127.0.0.1:59034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T19:07:37.826318Z","caller":"traceutil/trace.go:171","msg":"trace[730476016] transaction","detail":"{read_only:false; response_revision:2240; number_of_response:1; }","duration":"209.88013ms","start":"2026-10-15T19:07:37.616412Z","end":"2026-10-15T19:07:37.826292Z","steps":["trace[730476016] 'process raft request' (duration: 209.592723ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:07:37.826397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.713395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T19:07:37.826448Z","caller":"traceutil/trace.go:171","msg":"trace[1058226789] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:2240; }","duration":"418.797397ms","start":"2026-10-15T19:07:37.407637Z","end":"2026-10-15T19:07:37.826434Z","steps":["trace[1058226789] 'agreement among raft nodes before linearized reading' (duration: 418.709365ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:07:37.826493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:07:37.407623Z","time spent":"418.856758ms","remote":"127.0.0.1:53196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true "} {"level":"warn","ts":"2026-10-15T19:07:37.826515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.878656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-10-15T19:07:37.826551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.77888ms","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-15T19:07:37.826564Z","caller":"traceutil/trace.go:171","msg":"trace[1991554849] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2240; }","duration":"143.961728ms","start":"2026-10-15T19:07:37.682584Z","end":"2026-10-15T19:07:37.826545Z","steps":["trace[1991554849] 'agreement among raft nodes before linearized reading' (duration: 143.827375ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:07:37.82659Z","caller":"traceutil/trace.go:171","msg":"trace[1191561756] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2240; }","duration":"467.819041ms","start":"2026-10-15T19:07:37.35876Z","end":"2026-10-15T19:07:37.826579Z","steps":["trace[1191561756] 'agreement among raft nodes before linearized reading' (duration: 467.695568ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:07:37.826341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.829608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-10-15T19:07:37.826726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:07:37.358703Z","time spent":"468.011326ms","remote":"127.0.0.1:59034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-15T19:07:37.82673Z","caller":"traceutil/trace.go:171","msg":"trace[1896908354] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2240; }","duration":"477.235649ms","start":"2026-10-15T19:07:37.349482Z","end":"2026-10-15T19:07:37.826718Z","steps":["trace[1896908354] 'agreement among raft nodes before linearized reading' (duration: 476.690306ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:07:37.826768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:07:37.349439Z","time spent":"477.319121ms","remote":"127.0.0.1:59034","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-15T19:08:28.346799Z","caller":"traceutil/trace.go:171","msg":"trace[488437818] linearizableReadLoop","detail":"{readStateIndex:2787; appliedIndex:2786; }","duration":"209.275917ms","start":"2026-10-15T19:08:28.137489Z","end":"2026-10-15T19:08:28.346765Z","steps":["trace[488437818] 'read index received' (duration: 191.777085ms)","trace[488437818] 'applied index is now lower than readState.Index' (duration: 17.497292ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:08:28.346832Z","caller":"traceutil/trace.go:171","msg":"trace[939574189] transaction","detail":"{read_only:false; response_revision:2683; number_of_response:1; }","duration":"260.088089ms","start":"2026-10-15T19:08:28.086711Z","end":"2026-10-15T19:08:28.346799Z","steps":["trace[939574189] 'process raft request' (duration: 242.619939ms)","trace[939574189] 'compare' (duration: 17.224694ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:08:28.347096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.306394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-10-15T19:08:28.347156Z","caller":"traceutil/trace.go:171","msg":"trace[1426350531] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2683; }","duration":"206.436398ms","start":"2026-10-15T19:08:28.140704Z","end":"2026-10-15T19:08:28.347141Z","steps":["trace[1426350531] 'agreement among raft nodes before linearized reading' (duration: 206.243083ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:08:28.347371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.669953ms","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-15T19:08:28.347434Z","caller":"traceutil/trace.go:171","msg":"trace[419092499] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2683; }","duration":"159.799866ms","start":"2026-10-15T19:08:28.187622Z","end":"2026-10-15T19:08:28.347422Z","steps":["trace[419092499] 'agreement among raft nodes before linearized reading' (duration: 159.323914ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:08:28.347438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.924472ms","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-15T19:08:28.347645Z","caller":"traceutil/trace.go:171","msg":"trace[1287624374] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2683; }","duration":"210.052255ms","start":"2026-10-15T19:08:28.137424Z","end":"2026-10-15T19:08:28.347476Z","steps":["trace[1287624374] 'agreement among raft nodes before linearized reading' (duration: 209.44461ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:09:01.843842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.852106ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T19:09:01.844307Z","caller":"traceutil/trace.go:171","msg":"trace[565594027] transaction","detail":"{read_only:false; response_revision:2884; number_of_response:1; }","duration":"252.297262ms","start":"2026-10-15T19:09:01.591956Z","end":"2026-10-15T19:09:01.844254Z","steps":["trace[565594027] 'process raft request' (duration: 106.904133ms)","trace[565594027] 'compare' (duration: 144.454026ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:11:42.918917Z","caller":"traceutil/trace.go:171","msg":"trace[1607718694] transaction","detail":"{read_only:false; response_revision:3893; number_of_response:1; }","duration":"112.916094ms","start":"2026-10-15T19:11:42.805959Z","end":"2026-10-15T19:11:42.918875Z","steps":["trace[1607718694] 'process raft request' (duration: 109.319415ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:11:42.919597Z","caller":"traceutil/trace.go:171","msg":"trace[1266779804] transaction","detail":"{read_only:false; response_revision:3894; number_of_response:1; }","duration":"111.283705ms","start":"2026-10-15T19:11:42.808281Z","end":"2026-10-15T19:11:42.919564Z","steps":["trace[1266779804] 'process raft request' (duration: 111.068319ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:12:18.954218Z","caller":"traceutil/trace.go:171","msg":"trace[1091938383] linearizableReadLoop","detail":"{readStateIndex:4281; appliedIndex:4280; }","duration":"195.729604ms","start":"2026-10-15T19:12:18.758457Z","end":"2026-10-15T19:12:18.954186Z","steps":["trace[1091938383] 'read index received' (duration: 195.5787ms)","trace[1091938383] 'applied index is now lower than readState.Index' (duration: 149.444µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:12:18.95439Z","caller":"traceutil/trace.go:171","msg":"trace[342565426] transaction","detail":"{read_only:false; response_revision:4124; number_of_response:1; }","duration":"219.499663ms","start":"2026-10-15T19:12:18.734831Z","end":"2026-10-15T19:12:18.954331Z","steps":["trace[342565426] 'process raft request' (duration: 219.156995ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:12:18.954433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.951189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T19:12:18.95451Z","caller":"traceutil/trace.go:171","msg":"trace[1301430340] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4124; }","duration":"196.060901ms","start":"2026-10-15T19:12:18.75843Z","end":"2026-10-15T19:12:18.954491Z","steps":["trace[1301430340] 'agreement among raft nodes before linearized reading' (duration: 195.932338ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:12:18.999287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.239722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"warn","ts":"2026-10-15T19:12:18.99935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.920683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-15T19:12:18.999441Z","caller":"traceutil/trace.go:171","msg":"trace[795379900] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4126; }","duration":"211.049177ms","start":"2026-10-15T19:12:18.788368Z","end":"2026-10-15T19:12:18.999417Z","steps":["trace[795379900] 'agreement among raft nodes before linearized reading' (duration: 210.903113ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:12:18.999376Z","caller":"traceutil/trace.go:171","msg":"trace[566359483] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4126; }","duration":"111.400746ms","start":"2026-10-15T19:12:18.887957Z","end":"2026-10-15T19:12:18.999357Z","steps":["trace[566359483] 'agreement among raft nodes before linearized reading' (duration: 111.15697ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:13:36.572008Z","caller":"traceutil/trace.go:171","msg":"trace[2054377670] transaction","detail":"{read_only:false; response_revision:5023; number_of_response:1; }","duration":"108.91049ms","start":"2026-10-15T19:13:36.463067Z","end":"2026-10-15T19:13:36.571978Z","steps":["trace[2054377670] 'process raft request' (duration: 82.940438ms)","trace[2054377670] 'compare' (duration: 25.835529ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:13:36.573394Z","caller":"traceutil/trace.go:171","msg":"trace[1123030973] transaction","detail":"{read_only:false; response_revision:5025; number_of_response:1; }","duration":"106.90037ms","start":"2026-10-15T19:13:36.466467Z","end":"2026-10-15T19:13:36.573367Z","steps":["trace[1123030973] 'process raft request' (duration: 106.780697ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:13:36.573416Z","caller":"traceutil/trace.go:171","msg":"trace[400686272] transaction","detail":"{read_only:false; response_revision:5024; number_of_response:1; }","duration":"107.015822ms","start":"2026-10-15T19:13:36.466371Z","end":"2026-10-15T19:13:36.573387Z","steps":["trace[400686272] 'process raft request' (duration: 106.764166ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:13:36.573388Z","caller":"traceutil/trace.go:171","msg":"trace[951692493] linearizableReadLoop","detail":"{readStateIndex:5217; appliedIndex:5214; }","duration":"104.571132ms","start":"2026-10-15T19:13:36.468767Z","end":"2026-10-15T19:13:36.573338Z","steps":["trace[951692493] 'read index received' (duration: 77.989416ms)","trace[951692493] 'applied index is now lower than readState.Index' (duration: 26.580666ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:13:36.573781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.990012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4736"} {"level":"info","ts":"2026-10-15T19:13:36.573841Z","caller":"traceutil/trace.go:171","msg":"trace[645725177] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:5026; }","duration":"105.089965ms","start":"2026-10-15T19:13:36.468732Z","end":"2026-10-15T19:13:36.573822Z","steps":["trace[645725177] 'agreement among raft nodes before linearized reading' (duration: 104.712846ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:13:36.581717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.566943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-10-15T19:13:36.581782Z","caller":"traceutil/trace.go:171","msg":"trace[2060133101] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:5027; }","duration":"100.677566ms","start":"2026-10-15T19:13:36.481085Z","end":"2026-10-15T19:13:36.581762Z","steps":["trace[2060133101] 'agreement among raft nodes before linearized reading' (duration: 100.46314ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:13:43.629589Z","caller":"traceutil/trace.go:171","msg":"trace[376293216] linearizableReadLoop","detail":"{readStateIndex:5379; appliedIndex:5378; }","duration":"114.337344ms","start":"2026-10-15T19:13:43.515229Z","end":"2026-10-15T19:13:43.629566Z","steps":["trace[376293216] 'read index received' (duration: 114.20199ms)","trace[376293216] 'applied index is now lower than readState.Index' (duration: 134.504µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:13:43.629633Z","caller":"traceutil/trace.go:171","msg":"trace[395970187] transaction","detail":"{read_only:false; response_revision:5181; number_of_response:1; }","duration":"114.785874ms","start":"2026-10-15T19:13:43.514806Z","end":"2026-10-15T19:13:43.629592Z","steps":["trace[395970187] 'process raft request' (duration: 114.647121ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:13:43.629791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.552829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-mm68p\" ","response":"range_response_count:1 size:9753"} {"level":"info","ts":"2026-10-15T19:13:43.629832Z","caller":"traceutil/trace.go:171","msg":"trace[1106466760] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-mm68p; range_end:; response_count:1; response_revision:5181; }","duration":"114.640351ms","start":"2026-10-15T19:13:43.515182Z","end":"2026-10-15T19:13:43.629822Z","steps":["trace[1106466760] 'agreement among raft nodes before linearized reading' (duration: 114.475857ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:13:47.157496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.456837ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T19:13:47.157605Z","caller":"traceutil/trace.go:171","msg":"trace[163893053] linearizableReadLoop","detail":"{readStateIndex:5520; appliedIndex:5519; }","duration":"107.963706ms","start":"2026-10-15T19:13:47.049624Z","end":"2026-10-15T19:13:47.157588Z","steps":["trace[163893053] 'read index received' (duration: 46.071µs)","trace[163893053] 'applied index is now lower than readState.Index' (duration: 107.916445ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:13:47.157681Z","caller":"traceutil/trace.go:171","msg":"trace[608219246] transaction","detail":"{read_only:false; response_revision:5318; number_of_response:1; }","duration":"187.633544ms","start":"2026-10-15T19:13:46.970036Z","end":"2026-10-15T19:13:47.15767Z","steps":["trace[608219246] 'process raft request' (duration: 57.914981ms)","trace[608219246] 'compare' (duration: 129.259162ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:13:47.157896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.204275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:16939"} {"level":"info","ts":"2026-10-15T19:13:47.157971Z","caller":"traceutil/trace.go:171","msg":"trace[1205403788] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:5318; }","duration":"102.304297ms","start":"2026-10-15T19:13:47.055647Z","end":"2026-10-15T19:13:47.157951Z","steps":["trace[1205403788] 'agreement among raft nodes before linearized reading' (duration: 102.123203ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:13:47.158014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.375417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-057db618-3ba7-49d5-955e-a287263eec2a\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-10-15T19:13:47.158076Z","caller":"traceutil/trace.go:171","msg":"trace[2024126615] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-057db618-3ba7-49d5-955e-a287263eec2a; range_end:; response_count:1; response_revision:5318; }","duration":"108.478979ms","start":"2026-10-15T19:13:47.049583Z","end":"2026-10-15T19:13:47.158062Z","steps":["trace[2024126615] 'agreement among raft nodes before linearized reading' (duration: 108.326075ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:13:47.354304Z","caller":"traceutil/trace.go:171","msg":"trace[161946571] transaction","detail":"{read_only:false; response_revision:5319; number_of_response:1; }","duration":"185.737796ms","start":"2026-10-15T19:13:47.168526Z","end":"2026-10-15T19:13:47.354264Z","steps":["trace[161946571] 'process raft request' (duration: 185.557213ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:13:47.678209Z","caller":"traceutil/trace.go:171","msg":"trace[1642237857] linearizableReadLoop","detail":"{readStateIndex:5525; appliedIndex:5524; }","duration":"122.537376ms","start":"2026-10-15T19:13:47.555638Z","end":"2026-10-15T19:13:47.678176Z","steps":["trace[1642237857] 'read index received' (duration: 122.28614ms)","trace[1642237857] 'applied index is now lower than readState.Index' (duration: 250.166µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:13:47.678593Z","caller":"traceutil/trace.go:171","msg":"trace[483453285] transaction","detail":"{read_only:false; response_revision:5323; number_of_response:1; }","duration":"140.032468ms","start":"2026-10-15T19:13:47.538542Z","end":"2026-10-15T19:13:47.678575Z","steps":["trace[483453285] 'process raft request' (duration: 139.449124ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:13:47.679029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.360436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-057db618-3ba7-49d5-955e-a287263eec2a\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-10-15T19:13:47.67911Z","caller":"traceutil/trace.go:171","msg":"trace[503911667] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-057db618-3ba7-49d5-955e-a287263eec2a; range_end:; response_count:1; response_revision:5323; }","duration":"123.488939ms","start":"2026-10-15T19:13:47.555596Z","end":"2026-10-15T19:13:47.679085Z","steps":["trace[503911667] 'agreement among raft nodes before linearized reading' (duration: 123.244974ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:14:00.833785Z","caller":"traceutil/trace.go:171","msg":"trace[1103053710] transaction","detail":"{read_only:false; response_revision:5566; number_of_response:1; }","duration":"121.03584ms","start":"2026-10-15T19:14:00.712725Z","end":"2026-10-15T19:14:00.833761Z","steps":["trace[1103053710] 'process raft request' (duration: 98.086703ms)","trace[1103053710] 'compare' (duration: 22.851374ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:14:00.848441Z","caller":"traceutil/trace.go:171","msg":"trace[622421853] transaction","detail":"{read_only:false; response_revision:5567; number_of_response:1; }","duration":"109.942114ms","start":"2026-10-15T19:14:00.738465Z","end":"2026-10-15T19:14:00.848407Z","steps":["trace[622421853] 'process raft request' (duration: 109.701588ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:14:00.84862Z","caller":"traceutil/trace.go:171","msg":"trace[2082708551] transaction","detail":"{read_only:false; response_revision:5568; number_of_response:1; }","duration":"104.753216ms","start":"2026-10-15T19:14:00.743847Z","end":"2026-10-15T19:14:00.8486Z","steps":["trace[2082708551] 'process raft request' (duration: 104.457619ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:14:00.848943Z","caller":"traceutil/trace.go:171","msg":"trace[1068041409] transaction","detail":"{read_only:false; response_revision:5569; number_of_response:1; }","duration":"104.251734ms","start":"2026-10-15T19:14:00.74467Z","end":"2026-10-15T19:14:00.848922Z","steps":["trace[1068041409] 'process raft request' (duration: 103.866495ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:14:00.848984Z","caller":"traceutil/trace.go:171","msg":"trace[817946360] transaction","detail":"{read_only:false; response_revision:5570; number_of_response:1; }","duration":"104.136251ms","start":"2026-10-15T19:14:00.744834Z","end":"2026-10-15T19:14:00.848971Z","steps":["trace[817946360] 'process raft request' (duration: 103.988158ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:14:00.849006Z","caller":"traceutil/trace.go:171","msg":"trace[1705502556] transaction","detail":"{read_only:false; response_revision:5571; number_of_response:1; }","duration":"104.124941ms","start":"2026-10-15T19:14:00.744866Z","end":"2026-10-15T19:14:00.848991Z","steps":["trace[1705502556] 'process raft request' (duration: 104.051269ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:14:01.320299Z","caller":"traceutil/trace.go:171","msg":"trace[1885573925] transaction","detail":"{read_only:false; response_revision:5572; number_of_response:1; }","duration":"121.673315ms","start":"2026-10-15T19:14:01.198586Z","end":"2026-10-15T19:14:01.32026Z","steps":["trace[1885573925] 'process raft request' (duration: 121.45676ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:14:08.935934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.420117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9b6bfeb4-ae3d-4553-a71c-9e20673cb07c\" ","response":"range_response_count:1 size:3645"} {"level":"warn","ts":"2026-10-15T19:14:08.935951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.786889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T19:14:08.936013Z","caller":"traceutil/trace.go:171","msg":"trace[731256118] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9b6bfeb4-ae3d-4553-a71c-9e20673cb07c; range_end:; response_count:1; response_revision:5731; }","duration":"144.546379ms","start":"2026-10-15T19:14:08.791448Z","end":"2026-10-15T19:14:08.935994Z","steps":["trace[731256118] 'range keys from in-memory index tree' (duration: 144.194672ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:14:08.936026Z","caller":"traceutil/trace.go:171","msg":"trace[2091480642] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5731; }","duration":"178.908751ms","start":"2026-10-15T19:14:08.757099Z","end":"2026-10-15T19:14:08.936008Z","steps":["trace[2091480642] 'range keys from in-memory index tree' (duration: 178.713648ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:14:08.936056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.326636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-15T19:14:08.936102Z","caller":"traceutil/trace.go:171","msg":"trace[1422667075] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:5731; }","duration":"230.414018ms","start":"2026-10-15T19:14:08.705675Z","end":"2026-10-15T19:14:08.936089Z","steps":["trace[1422667075] 'count revisions from in-memory index tree' (duration: 230.239623ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:14:08.936079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.658106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9b6bfeb4-ae3d-4553-a71c-9e20673cb07c\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-10-15T19:14:08.936167Z","caller":"traceutil/trace.go:171","msg":"trace[1408757084] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9b6bfeb4-ae3d-4553-a71c-9e20673cb07c; range_end:; response_count:1; response_revision:5731; }","duration":"114.799529ms","start":"2026-10-15T19:14:08.821344Z","end":"2026-10-15T19:14:08.936144Z","steps":["trace[1408757084] 'range keys from in-memory index tree' (duration: 114.423541ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:14:08.983829Z","caller":"traceutil/trace.go:171","msg":"trace[2018536900] transaction","detail":"{read_only:false; response_revision:5732; number_of_response:1; }","duration":"110.792132ms","start":"2026-10-15T19:14:08.873018Z","end":"2026-10-15T19:14:08.98381Z","steps":["trace[2018536900] 'process raft request' (duration: 110.677419ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:14:09.266279Z","caller":"traceutil/trace.go:171","msg":"trace[299266480] transaction","detail":"{read_only:false; response_revision:5733; number_of_response:1; }","duration":"357.459915ms","start":"2026-10-15T19:14:08.908777Z","end":"2026-10-15T19:14:09.266236Z","steps":["trace[299266480] 'process raft request' (duration: 357.189988ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:14:09.26645Z","caller":"traceutil/trace.go:171","msg":"trace[509303140] transaction","detail":"{read_only:false; response_revision:5734; number_of_response:1; }","duration":"322.556078ms","start":"2026-10-15T19:14:08.943862Z","end":"2026-10-15T19:14:09.266418Z","steps":["trace[509303140] 'process raft request' (duration: 322.326883ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:14:09.266503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.370773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-15T19:14:09.266495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:14:08.908749Z","time spent":"357.685829ms","remote":"127.0.0.1:58860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":753,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-15T19:14:09.26632Z","caller":"traceutil/trace.go:171","msg":"trace[701833984] linearizableReadLoop","detail":"{readStateIndex:5948; appliedIndex:5946; }","duration":"329.17604ms","start":"2026-10-15T19:14:08.937114Z","end":"2026-10-15T19:14:09.26629Z","steps":["trace[701833984] 'read index received' (duration: 46.664719ms)","trace[701833984] 'applied index is now lower than readState.Index' (duration: 282.510221ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:14:09.26656Z","caller":"traceutil/trace.go:171","msg":"trace[2012079561] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5734; }","duration":"329.445665ms","start":"2026-10-15T19:14:08.9371Z","end":"2026-10-15T19:14:09.266546Z","steps":["trace[2012079561] 'agreement among raft nodes before linearized reading' (duration: 329.275871ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:14:09.266611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:14:08.937068Z","time spent":"329.532707ms","remote":"127.0.0.1:58752","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-15T19:14:09.266629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:14:08.943839Z","time spent":"322.695632ms","remote":"127.0.0.1:58954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3747,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T19:14:09.266786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.938433ms","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-15T19:14:09.266843Z","caller":"traceutil/trace.go:171","msg":"trace[1110096434] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:5734; }","duration":"184.028725ms","start":"2026-10-15T19:14:09.082798Z","end":"2026-10-15T19:14:09.266826Z","steps":["trace[1110096434] 'agreement among raft nodes before linearized reading' (duration: 183.926922ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:14:09.454442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.698121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-15T19:14:09.454548Z","caller":"traceutil/trace.go:171","msg":"trace[16807787] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:5734; }","duration":"101.906956ms","start":"2026-10-15T19:14:09.352613Z","end":"2026-10-15T19:14:09.45452Z","steps":["trace[16807787] 'count revisions from in-memory index tree' (duration: 101.584699ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:14:16.793385Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2970} {"level":"info","ts":"2026-10-15T19:14:16.875389Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2970,"took":"80.561601ms","hash":2013609991,"current-db-size-bytes":56999936,"current-db-size":"57 MB","current-db-size-in-use-bytes":32210944,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-15T19:14:16.875479Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2013609991,"revision":2970,"compact-revision":-1} {"level":"info","ts":"2026-10-15T19:15:01.311775Z","caller":"traceutil/trace.go:171","msg":"trace[126543621] transaction","detail":"{read_only:false; response_revision:6239; number_of_response:1; }","duration":"103.487585ms","start":"2026-10-15T19:15:01.208261Z","end":"2026-10-15T19:15:01.311749Z","steps":["trace[126543621] 'process raft request' (duration: 103.338871ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:18:43.735868Z","caller":"traceutil/trace.go:171","msg":"trace[1471760726] transaction","detail":"{read_only:false; response_revision:7806; number_of_response:1; }","duration":"108.70612ms","start":"2026-10-15T19:18:43.627129Z","end":"2026-10-15T19:18:43.735836Z","steps":["trace[1471760726] 'process raft request' (duration: 108.514926ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:19:16.800016Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5862} {"level":"info","ts":"2026-10-15T19:19:16.901833Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5862,"took":"99.826035ms","hash":3626951453,"current-db-size-bytes":56999936,"current-db-size":"57 MB","current-db-size-in-use-bytes":26599424,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-15T19:19:16.902106Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3626951453,"revision":5862,"compact-revision":2970} {"level":"warn","ts":"2026-10-15T19:20:22.672837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.621218ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T19:20:22.672971Z","caller":"traceutil/trace.go:171","msg":"trace[2005826876] linearizableReadLoop","detail":"{readStateIndex:8916; appliedIndex:8915; }","duration":"428.061841ms","start":"2026-10-15T19:20:22.244891Z","end":"2026-10-15T19:20:22.672953Z","steps":["trace[2005826876] 'read index received' (duration: 53.157167ms)","trace[2005826876] 'applied index is now lower than readState.Index' (duration: 374.903174ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:20:22.673018Z","caller":"traceutil/trace.go:171","msg":"trace[2114607812] transaction","detail":"{read_only:false; response_revision:8607; number_of_response:1; }","duration":"434.554738ms","start":"2026-10-15T19:20:22.23843Z","end":"2026-10-15T19:20:22.672984Z","steps":["trace[2114607812] 'process raft request' (duration: 59.710275ms)","trace[2114607812] 'compare' (duration: 374.519765ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:20:22.673121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.222325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-15T19:20:22.673157Z","caller":"traceutil/trace.go:171","msg":"trace[1863796074] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:8607; }","duration":"428.285676ms","start":"2026-10-15T19:20:22.244862Z","end":"2026-10-15T19:20:22.673148Z","steps":["trace[1863796074] 'agreement among raft nodes before linearized reading' (duration: 428.159613ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:20:22.673166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:20:22.238403Z","time spent":"434.688592ms","remote":"127.0.0.1:59034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T19:20:22.673188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:20:22.244846Z","time spent":"428.332968ms","remote":"127.0.0.1:58882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":99,"response size":30,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"info","ts":"2026-10-15T19:20:22.835751Z","caller":"traceutil/trace.go:171","msg":"trace[796745927] transaction","detail":"{read_only:false; response_revision:8608; number_of_response:1; }","duration":"457.287577ms","start":"2026-10-15T19:20:22.378429Z","end":"2026-10-15T19:20:22.835717Z","steps":["trace[796745927] 'process raft request' (duration: 457.073353ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:20:22.835929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:20:22.378404Z","time spent":"457.431671ms","remote":"127.0.0.1:58946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20149,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T19:20:22.836531Z","caller":"traceutil/trace.go:171","msg":"trace[962695275] linearizableReadLoop","detail":"{readStateIndex:8918; appliedIndex:8916; }","duration":"163.502217ms","start":"2026-10-15T19:20:22.672995Z","end":"2026-10-15T19:20:22.836497Z","steps":["trace[962695275] 'read index received' (duration: 162.607135ms)","trace[962695275] 'applied index is now lower than readState.Index' (duration: 893.972µs)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:20:22.836728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.584613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-10-15T19:20:22.836727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.942752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-5fqwg\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-10-15T19:20:22.836717Z","caller":"traceutil/trace.go:171","msg":"trace[259215559] transaction","detail":"{read_only:false; response_revision:8609; number_of_response:1; }","duration":"292.447448ms","start":"2026-10-15T19:20:22.544219Z","end":"2026-10-15T19:20:22.836666Z","steps":["trace[259215559] 'process raft request' (duration: 292.141371ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:20:22.836751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.266333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-15T19:20:22.836789Z","caller":"traceutil/trace.go:171","msg":"trace[996187652] range","detail":"{range_begin:/registry/pods/openstack/keepalived-5fqwg; range_end:; response_count:1; response_revision:8609; }","duration":"379.026254ms","start":"2026-10-15T19:20:22.457748Z","end":"2026-10-15T19:20:22.836775Z","steps":["trace[996187652] 'agreement among raft nodes before linearized reading' (duration: 378.819059ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:20:22.836828Z","caller":"traceutil/trace.go:171","msg":"trace[928625733] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8609; }","duration":"406.401227ms","start":"2026-10-15T19:20:22.430408Z","end":"2026-10-15T19:20:22.836809Z","steps":["trace[928625733] 'agreement among raft nodes before linearized reading' (duration: 406.188412ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:20:22.836834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:20:22.457736Z","time spent":"379.087076ms","remote":"127.0.0.1:58954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6549,"request content":"key:\"/registry/pods/openstack/keepalived-5fqwg\" "} {"level":"warn","ts":"2026-10-15T19:20:22.836866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:20:22.430388Z","time spent":"406.466118ms","remote":"127.0.0.1:59094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-10-15T19:20:22.836781Z","caller":"traceutil/trace.go:171","msg":"trace[1084712507] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8609; }","duration":"585.677596ms","start":"2026-10-15T19:20:22.251087Z","end":"2026-10-15T19:20:22.836765Z","steps":["trace[1084712507] 'agreement among raft nodes before linearized reading' (duration: 585.491411ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:20:22.836938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:20:22.251067Z","time spent":"585.852931ms","remote":"127.0.0.1:59034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-15T19:20:33.172563Z","caller":"traceutil/trace.go:171","msg":"trace[332382429] transaction","detail":"{read_only:false; response_revision:8665; number_of_response:1; }","duration":"130.694653ms","start":"2026-10-15T19:20:33.041842Z","end":"2026-10-15T19:20:33.172536Z","steps":["trace[332382429] 'process raft request' (duration: 130.512158ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:20:33.200341Z","caller":"traceutil/trace.go:171","msg":"trace[601933878] linearizableReadLoop","detail":"{readStateIndex:8977; appliedIndex:8975; }","duration":"152.412008ms","start":"2026-10-15T19:20:33.047904Z","end":"2026-10-15T19:20:33.200316Z","steps":["trace[601933878] 'read index received' (duration: 124.575404ms)","trace[601933878] 'applied index is now lower than readState.Index' (duration: 27.835464ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:20:33.200472Z","caller":"traceutil/trace.go:171","msg":"trace[1507905080] transaction","detail":"{read_only:false; response_revision:8666; number_of_response:1; }","duration":"156.21434ms","start":"2026-10-15T19:20:33.044227Z","end":"2026-10-15T19:20:33.200441Z","steps":["trace[1507905080] 'process raft request' (duration: 139.095576ms)","trace[1507905080] 'compare' (duration: 16.885748ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:20:33.200996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.066765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-d65f449ff-77br9.18deca30e263f4c8\" ","response":"range_response_count:1 size:1131"} {"level":"info","ts":"2026-10-15T19:20:33.20104Z","caller":"traceutil/trace.go:171","msg":"trace[579908391] range","detail":"{range_begin:/registry/events/openstack/barbican-api-d65f449ff-77br9.18deca30e263f4c8; range_end:; response_count:1; response_revision:8666; }","duration":"153.166687ms","start":"2026-10-15T19:20:33.047862Z","end":"2026-10-15T19:20:33.201028Z","steps":["trace[579908391] 'agreement among raft nodes before linearized reading' (duration: 152.550841ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:20:34.730806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.855509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-15T19:20:34.730881Z","caller":"traceutil/trace.go:171","msg":"trace[1769895694] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8671; }","duration":"311.975541ms","start":"2026-10-15T19:20:34.418885Z","end":"2026-10-15T19:20:34.73086Z","steps":["trace[1769895694] 'count revisions from in-memory index tree' (duration: 311.752846ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:20:34.730919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:20:34.418826Z","time spent":"312.082743ms","remote":"127.0.0.1:59080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-10-15T19:20:35.251948Z","caller":"traceutil/trace.go:171","msg":"trace[654027369] transaction","detail":"{read_only:false; response_revision:8676; number_of_response:1; }","duration":"158.068986ms","start":"2026-10-15T19:20:35.093845Z","end":"2026-10-15T19:20:35.251914Z","steps":["trace[654027369] 'process raft request' (duration: 134.603048ms)","trace[654027369] 'compare' (duration: 23.246872ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:20:35.284527Z","caller":"traceutil/trace.go:171","msg":"trace[1288704987] transaction","detail":"{read_only:false; response_revision:8677; number_of_response:1; }","duration":"107.370349ms","start":"2026-10-15T19:20:35.177131Z","end":"2026-10-15T19:20:35.284501Z","steps":["trace[1288704987] 'process raft request' (duration: 107.196255ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:20:59.836015Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023ca.wal"} {"level":"info","ts":"2026-10-15T19:21:01.11242Z","caller":"traceutil/trace.go:171","msg":"trace[91238847] transaction","detail":"{read_only:false; response_revision:8865; number_of_response:1; }","duration":"127.889716ms","start":"2026-10-15T19:21:00.984504Z","end":"2026-10-15T19:21:01.112394Z","steps":["trace[91238847] 'process raft request' (duration: 127.67047ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:21:04.164959Z","caller":"traceutil/trace.go:171","msg":"trace[1712172412] linearizableReadLoop","detail":"{readStateIndex:9203; appliedIndex:9202; }","duration":"118.412506ms","start":"2026-10-15T19:21:04.046511Z","end":"2026-10-15T19:21:04.164923Z","steps":["trace[1712172412] 'read index received' (duration: 118.090569ms)","trace[1712172412] 'applied index is now lower than readState.Index' (duration: 320.697µs)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:21:04.165143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.603861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T19:21:04.165229Z","caller":"traceutil/trace.go:171","msg":"trace[1805101205] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:8884; }","duration":"118.756044ms","start":"2026-10-15T19:21:04.046452Z","end":"2026-10-15T19:21:04.165208Z","steps":["trace[1805101205] 'agreement among raft nodes before linearized reading' (duration: 118.58286ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:21:04.165701Z","caller":"traceutil/trace.go:171","msg":"trace[462336882] transaction","detail":"{read_only:false; response_revision:8884; number_of_response:1; }","duration":"125.62648ms","start":"2026-10-15T19:21:04.039373Z","end":"2026-10-15T19:21:04.165Z","steps":["trace[462336882] 'process raft request' (duration: 125.335733ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:21:07.775176Z","caller":"traceutil/trace.go:171","msg":"trace[2078887897] transaction","detail":"{read_only:false; response_revision:8902; number_of_response:1; }","duration":"365.792788ms","start":"2026-10-15T19:21:07.40936Z","end":"2026-10-15T19:21:07.775152Z","steps":["trace[2078887897] 'process raft request' (duration: 365.652354ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:21:07.775321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:21:07.40933Z","time spent":"365.91773ms","remote":"127.0.0.1:59034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T19:21:07.787244Z","caller":"traceutil/trace.go:171","msg":"trace[2061731807] transaction","detail":"{read_only:false; response_revision:8903; number_of_response:1; }","duration":"376.418056ms","start":"2026-10-15T19:21:07.410783Z","end":"2026-10-15T19:21:07.787201Z","steps":["trace[2061731807] 'process raft request' (duration: 376.019616ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:21:07.787463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:21:07.410741Z","time spent":"376.59846ms","remote":"127.0.0.1:59034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T19:21:15.928185Z","caller":"traceutil/trace.go:171","msg":"trace[476222826] transaction","detail":"{read_only:false; response_revision:8945; number_of_response:1; }","duration":"112.035087ms","start":"2026-10-15T19:21:15.816118Z","end":"2026-10-15T19:21:15.928153Z","steps":["trace[476222826] 'process raft request' (duration: 111.825332ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:21:15.995233Z","caller":"traceutil/trace.go:171","msg":"trace[2119338450] transaction","detail":"{read_only:false; response_revision:8946; number_of_response:1; }","duration":"170.862962ms","start":"2026-10-15T19:21:15.82434Z","end":"2026-10-15T19:21:15.995202Z","steps":["trace[2119338450] 'process raft request' (duration: 170.638507ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:21:16.717187Z","caller":"traceutil/trace.go:171","msg":"trace[1152652018] transaction","detail":"{read_only:false; response_revision:8949; number_of_response:1; }","duration":"187.986888ms","start":"2026-10-15T19:21:16.529166Z","end":"2026-10-15T19:21:16.717153Z","steps":["trace[1152652018] 'process raft request' (duration: 187.020634ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:22:23.229982Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"9d6ea9653fb28c79","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T19:22:23.236198Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-15T19:22:23.236298Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-15T19:22:47.818577Z","caller":"traceutil/trace.go:171","msg":"trace[966683221] transaction","detail":"{read_only:false; response_revision:9815; number_of_response:1; }","duration":"172.133064ms","start":"2026-10-15T19:22:47.646414Z","end":"2026-10-15T19:22:47.818547Z","steps":["trace[966683221] 'process raft request' (duration: 171.98091ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:22:47.834209Z","caller":"traceutil/trace.go:171","msg":"trace[403130413] linearizableReadLoop","detail":"{readStateIndex:10167; appliedIndex:10165; }","duration":"129.864979ms","start":"2026-10-15T19:22:47.704318Z","end":"2026-10-15T19:22:47.834183Z","steps":["trace[403130413] 'read index received' (duration: 114.143708ms)","trace[403130413] 'applied index is now lower than readState.Index' (duration: 15.719981ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:22:47.834333Z","caller":"traceutil/trace.go:171","msg":"trace[1374934712] transaction","detail":"{read_only:false; response_revision:9816; number_of_response:1; }","duration":"148.825108ms","start":"2026-10-15T19:22:47.685497Z","end":"2026-10-15T19:22:47.834322Z","steps":["trace[1374934712] 'process raft request' (duration: 148.530301ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:22:47.834452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.761717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-15T19:22:47.834507Z","caller":"traceutil/trace.go:171","msg":"trace[1087512407] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9816; }","duration":"129.866609ms","start":"2026-10-15T19:22:47.704627Z","end":"2026-10-15T19:22:47.834494Z","steps":["trace[1087512407] 'agreement among raft nodes before linearized reading' (duration: 129.710775ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:22:47.834641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.33364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-15T19:22:47.834723Z","caller":"traceutil/trace.go:171","msg":"trace[1616281789] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9816; }","duration":"130.446493ms","start":"2026-10-15T19:22:47.704264Z","end":"2026-10-15T19:22:47.834711Z","steps":["trace[1616281789] 'agreement among raft nodes before linearized reading' (duration: 130.32141ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:22:47.987897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.820716ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T19:22:47.988072Z","caller":"traceutil/trace.go:171","msg":"trace[1504697663] transaction","detail":"{read_only:false; response_revision:9818; number_of_response:1; }","duration":"148.07756ms","start":"2026-10-15T19:22:47.839977Z","end":"2026-10-15T19:22:47.988055Z","steps":["trace[1504697663] 'process raft request' (duration: 148.021879ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:22:47.988159Z","caller":"traceutil/trace.go:171","msg":"trace[981925841] transaction","detail":"{read_only:false; response_revision:9817; number_of_response:1; }","duration":"149.133946ms","start":"2026-10-15T19:22:47.838995Z","end":"2026-10-15T19:22:47.988129Z","steps":["trace[981925841] 'process raft request' (duration: 16.994102ms)","trace[981925841] 'compare' (duration: 131.710913ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:24:16.809165Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8032} {"level":"info","ts":"2026-10-15T19:24:16.888399Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8032,"took":"77.011877ms","hash":4164798842,"current-db-size-bytes":56999936,"current-db-size":"57 MB","current-db-size-in-use-bytes":28504064,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-15T19:24:16.888468Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4164798842,"revision":8032,"compact-revision":5862} {"level":"info","ts":"2026-10-15T19:28:06.263988Z","caller":"traceutil/trace.go:171","msg":"trace[1288256561] transaction","detail":"{read_only:false; response_revision:12140; number_of_response:1; }","duration":"145.148537ms","start":"2026-10-15T19:28:06.11881Z","end":"2026-10-15T19:28:06.263959Z","steps":["trace[1288256561] 'process raft request' (duration: 144.932401ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:28:29.212898Z","caller":"traceutil/trace.go:171","msg":"trace[1993789181] transaction","detail":"{read_only:false; response_revision:12264; number_of_response:1; }","duration":"110.942748ms","start":"2026-10-15T19:28:29.101925Z","end":"2026-10-15T19:28:29.212868Z","steps":["trace[1993789181] 'process raft request' (duration: 110.813106ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:29:16.825309Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10426} {"level":"info","ts":"2026-10-15T19:29:16.923387Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10426,"took":"95.821571ms","hash":1383476149,"current-db-size-bytes":56999936,"current-db-size":"57 MB","current-db-size-in-use-bytes":25804800,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-15T19:29:16.923469Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1383476149,"revision":10426,"compact-revision":8032} {"level":"info","ts":"2026-10-15T19:34:16.832805Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12595} {"level":"info","ts":"2026-10-15T19:34:16.911053Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12595,"took":"76.234777ms","hash":166442764,"current-db-size-bytes":56999936,"current-db-size":"57 MB","current-db-size-in-use-bytes":27111424,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-15T19:34:16.911232Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":166442764,"revision":12595,"compact-revision":10426} {"level":"warn","ts":"2026-10-15T19:35:03.51615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.937226ms","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-15T19:35:03.516242Z","caller":"traceutil/trace.go:171","msg":"trace[454551354] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14900; }","duration":"416.093761ms","start":"2026-10-15T19:35:03.10013Z","end":"2026-10-15T19:35:03.516224Z","steps":["trace[454551354] 'range keys from in-memory index tree' (duration: 415.734951ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:35:03.516284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:35:03.100108Z","time spent":"416.165152ms","remote":"127.0.0.1:59034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-15T19:35:03.523456Z","caller":"traceutil/trace.go:171","msg":"trace[29002713] transaction","detail":"{read_only:false; response_revision:14901; number_of_response:1; }","duration":"382.458766ms","start":"2026-10-15T19:35:03.140674Z","end":"2026-10-15T19:35:03.523132Z","steps":["trace[29002713] 'process raft request' (duration: 382.277562ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:35:03.523612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:35:03.14065Z","time spent":"382.876516ms","remote":"127.0.0.1:59034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T19:35:03.52424Z","caller":"traceutil/trace.go:171","msg":"trace[1180128089] transaction","detail":"{read_only:false; response_revision:14902; number_of_response:1; }","duration":"376.186893ms","start":"2026-10-15T19:35:03.148031Z","end":"2026-10-15T19:35:03.524218Z","steps":["trace[1180128089] 'process raft request' (duration: 375.962258ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:35:03.524349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:35:03.148012Z","time spent":"376.282035ms","remote":"127.0.0.1:59034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T19:35:26.939084Z","caller":"traceutil/trace.go:171","msg":"trace[1937366068] transaction","detail":"{read_only:false; response_revision:15217; number_of_response:1; }","duration":"174.731184ms","start":"2026-10-15T19:35:26.764322Z","end":"2026-10-15T19:35:26.939053Z","steps":["trace[1937366068] 'process raft request' (duration: 174.525839ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:35:27.017515Z","caller":"traceutil/trace.go:171","msg":"trace[1325343877] linearizableReadLoop","detail":"{readStateIndex:15756; appliedIndex:15754; }","duration":"188.65035ms","start":"2026-10-15T19:35:26.828841Z","end":"2026-10-15T19:35:27.017491Z","steps":["trace[1325343877] 'read index received' (duration: 110.010015ms)","trace[1325343877] 'applied index is now lower than readState.Index' (duration: 78.639255ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:35:27.017635Z","caller":"traceutil/trace.go:171","msg":"trace[128693529] transaction","detail":"{read_only:false; response_revision:15218; number_of_response:1; }","duration":"246.127894ms","start":"2026-10-15T19:35:26.771495Z","end":"2026-10-15T19:35:27.017623Z","steps":["trace[128693529] 'process raft request' (duration: 232.840121ms)","trace[128693529] 'compare' (duration: 13.052327ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:35:27.017839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.994109ms","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-15T19:35:27.017923Z","caller":"traceutil/trace.go:171","msg":"trace[1080622912] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15218; }","duration":"189.094571ms","start":"2026-10-15T19:35:26.828792Z","end":"2026-10-15T19:35:27.017886Z","steps":["trace[1080622912] 'agreement among raft nodes before linearized reading' (duration: 188.933798ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:36:53.470304Z","caller":"traceutil/trace.go:171","msg":"trace[1714586956] linearizableReadLoop","detail":"{readStateIndex:16589; appliedIndex:16588; }","duration":"116.722107ms","start":"2026-10-15T19:36:53.353562Z","end":"2026-10-15T19:36:53.470284Z","steps":["trace[1714586956] 'read index received' (duration: 116.556933ms)","trace[1714586956] 'applied index is now lower than readState.Index' (duration: 164.084µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:36:53.470407Z","caller":"traceutil/trace.go:171","msg":"trace[242902105] transaction","detail":"{read_only:false; response_revision:16029; number_of_response:1; }","duration":"136.525947ms","start":"2026-10-15T19:36:53.333871Z","end":"2026-10-15T19:36:53.470397Z","steps":["trace[242902105] 'process raft request' (duration: 136.26224ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:36:53.470603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.027424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-t2nj4\" ","response":"range_response_count:1 size:22128"} {"level":"info","ts":"2026-10-15T19:36:53.470649Z","caller":"traceutil/trace.go:171","msg":"trace[1477379262] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-t2nj4; range_end:; response_count:1; response_revision:16029; }","duration":"117.108507ms","start":"2026-10-15T19:36:53.353524Z","end":"2026-10-15T19:36:53.470632Z","steps":["trace[1477379262] 'agreement among raft nodes before linearized reading' (duration: 116.978203ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:36:53.802361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.902838ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T19:36:53.802443Z","caller":"traceutil/trace.go:171","msg":"trace[344228589] linearizableReadLoop","detail":"{readStateIndex:16590; appliedIndex:16589; }","duration":"332.056551ms","start":"2026-10-15T19:36:53.470373Z","end":"2026-10-15T19:36:53.80243Z","steps":["trace[344228589] 'read index received' (duration: 77.954438ms)","trace[344228589] 'applied index is now lower than readState.Index' (duration: 254.100823ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:36:53.802569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.432964ms","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-15T19:36:53.802602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.893564ms","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-15T19:36:53.802641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.514303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T19:36:53.802623Z","caller":"traceutil/trace.go:171","msg":"trace[702076475] transaction","detail":"{read_only:false; response_revision:16030; number_of_response:1; }","duration":"438.655332ms","start":"2026-10-15T19:36:53.363939Z","end":"2026-10-15T19:36:53.802594Z","steps":["trace[702076475] 'process raft request' (duration: 184.459977ms)","trace[702076475] 'compare' (duration: 253.461777ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:36:53.802639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.759311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-10-15T19:36:53.802703Z","caller":"traceutil/trace.go:171","msg":"trace[1834441650] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:16030; }","duration":"355.029597ms","start":"2026-10-15T19:36:53.447653Z","end":"2026-10-15T19:36:53.802683Z","steps":["trace[1834441650] 'agreement among raft nodes before linearized reading' (duration: 354.791261ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:36:53.802695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.206887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"warn","ts":"2026-10-15T19:36:53.802738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:36:53.363899Z","time spent":"438.793676ms","remote":"127.0.0.1:59034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T19:36:53.802751Z","caller":"traceutil/trace.go:171","msg":"trace[1091978160] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16030; }","duration":"425.911614ms","start":"2026-10-15T19:36:53.376812Z","end":"2026-10-15T19:36:53.802723Z","steps":["trace[1091978160] 'agreement among raft nodes before linearized reading' (duration: 425.680979ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:36:53.802757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:36:53.447636Z","time spent":"355.107209ms","remote":"127.0.0.1:59034","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-15T19:36:53.802709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.407611ms","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-15T19:36:53.802799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:36:53.376791Z","time spent":"425.997676ms","remote":"127.0.0.1:58938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":690,"request content":"key:\"/registry/services/endpoints/openstack/nova-api\" "} {"level":"warn","ts":"2026-10-15T19:36:53.802856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.152569ms","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-15T19:36:53.802889Z","caller":"traceutil/trace.go:171","msg":"trace[261330733] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:16030; }","duration":"357.18668ms","start":"2026-10-15T19:36:53.445695Z","end":"2026-10-15T19:36:53.802882Z","steps":["trace[261330733] 'agreement among raft nodes before linearized reading' (duration: 357.103588ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:36:53.802759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.706758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"warn","ts":"2026-10-15T19:36:53.802902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.21774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-15T19:36:53.802948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:36:53.44564Z","time spent":"357.300594ms","remote":"127.0.0.1:59034","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":"info","ts":"2026-10-15T19:36:53.802958Z","caller":"traceutil/trace.go:171","msg":"trace[721714219] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16030; }","duration":"254.932504ms","start":"2026-10-15T19:36:53.548014Z","end":"2026-10-15T19:36:53.802946Z","steps":["trace[721714219] 'agreement among raft nodes before linearized reading' (duration: 254.694098ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:36:53.802973Z","caller":"traceutil/trace.go:171","msg":"trace[1279028156] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16030; }","duration":"209.78151ms","start":"2026-10-15T19:36:53.593168Z","end":"2026-10-15T19:36:53.802949Z","steps":["trace[1279028156] 'agreement among raft nodes before linearized reading' (duration: 209.350259ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:36:53.802988Z","caller":"traceutil/trace.go:171","msg":"trace[899786508] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:16030; }","duration":"338.329993ms","start":"2026-10-15T19:36:53.464641Z","end":"2026-10-15T19:36:53.802971Z","steps":["trace[899786508] 'agreement among raft nodes before linearized reading' (duration: 338.218201ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:36:53.803014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.059607ms","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-15T19:36:53.803015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.328552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-10-15T19:36:53.803038Z","caller":"traceutil/trace.go:171","msg":"trace[278943797] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16030; }","duration":"426.13569ms","start":"2026-10-15T19:36:53.376896Z","end":"2026-10-15T19:36:53.803031Z","steps":["trace[278943797] 'agreement among raft nodes before linearized reading' (duration: 426.092799ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:36:53.80304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:36:53.464627Z","time spent":"338.397465ms","remote":"127.0.0.1:55510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" count_only:true "} {"level":"info","ts":"2026-10-15T19:36:53.80305Z","caller":"traceutil/trace.go:171","msg":"trace[545465273] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16030; }","duration":"110.389514ms","start":"2026-10-15T19:36:53.69265Z","end":"2026-10-15T19:36:53.80304Z","steps":["trace[545465273] 'agreement among raft nodes before linearized reading' (duration: 110.23737ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:36:53.803065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:36:53.376885Z","time spent":"426.174971ms","remote":"127.0.0.1:59012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "} {"level":"warn","ts":"2026-10-15T19:36:53.802609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.224818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-t2nj4\" ","response":"range_response_count:1 size:22128"} {"level":"info","ts":"2026-10-15T19:36:53.802688Z","caller":"traceutil/trace.go:171","msg":"trace[288561641] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:16030; }","duration":"209.602225ms","start":"2026-10-15T19:36:53.593078Z","end":"2026-10-15T19:36:53.80268Z","steps":["trace[288561641] 'agreement among raft nodes before linearized reading' (duration: 209.545724ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:36:53.803158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.138322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T19:36:53.803099Z","caller":"traceutil/trace.go:171","msg":"trace[1082945628] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-t2nj4; range_end:; response_count:1; response_revision:16030; }","duration":"328.748071ms","start":"2026-10-15T19:36:53.474339Z","end":"2026-10-15T19:36:53.803087Z","steps":["trace[1082945628] 'agreement among raft nodes before linearized reading' (duration: 328.161277ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:36:53.80319Z","caller":"traceutil/trace.go:171","msg":"trace[1942831416] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16030; }","duration":"123.169903ms","start":"2026-10-15T19:36:53.680013Z","end":"2026-10-15T19:36:53.803183Z","steps":["trace[1942831416] 'agreement among raft nodes before linearized reading' (duration: 123.124571ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:36:53.803203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.761347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-10-15T19:36:53.803237Z","caller":"traceutil/trace.go:171","msg":"trace[534052528] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16030; }","duration":"206.831309ms","start":"2026-10-15T19:36:53.596398Z","end":"2026-10-15T19:36:53.803229Z","steps":["trace[534052528] 'agreement among raft nodes before linearized reading' (duration: 206.727366ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:36:53.803301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.166101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-10-15T19:36:53.803331Z","caller":"traceutil/trace.go:171","msg":"trace[893387628] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16030; }","duration":"163.230623ms","start":"2026-10-15T19:36:53.640093Z","end":"2026-10-15T19:36:53.803323Z","steps":["trace[893387628] 'agreement among raft nodes before linearized reading' (duration: 163.153641ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:36:53.803333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.319425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-10-15T19:36:53.803364Z","caller":"traceutil/trace.go:171","msg":"trace[95922714] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16030; }","duration":"163.388547ms","start":"2026-10-15T19:36:53.639969Z","end":"2026-10-15T19:36:53.803357Z","steps":["trace[95922714] 'agreement among raft nodes before linearized reading' (duration: 163.315765ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:36:53.80338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.138541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:76 size:737454"} {"level":"warn","ts":"2026-10-15T19:36:53.803205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:36:53.474321Z","time spent":"328.877345ms","remote":"127.0.0.1:58954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22151,"request content":"key:\"/registry/pods/openstack/nova-compute-default-t2nj4\" "} {"level":"warn","ts":"2026-10-15T19:36:53.803404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.939907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-10-15T19:36:53.803415Z","caller":"traceutil/trace.go:171","msg":"trace[811562043] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:76; response_revision:16030; }","duration":"310.215263ms","start":"2026-10-15T19:36:53.493193Z","end":"2026-10-15T19:36:53.803408Z","steps":["trace[811562043] 'agreement among raft nodes before linearized reading' (duration: 309.577747ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:36:53.802762Z","caller":"traceutil/trace.go:171","msg":"trace[553263781] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16030; }","duration":"411.333221ms","start":"2026-10-15T19:36:53.391412Z","end":"2026-10-15T19:36:53.802745Z","steps":["trace[553263781] 'agreement among raft nodes before linearized reading' (duration: 411.075515ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:36:53.803486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:36:53.391402Z","time spent":"412.067409ms","remote":"127.0.0.1:59012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5546,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"warn","ts":"2026-10-15T19:36:53.803367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.676927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T19:36:53.803538Z","caller":"traceutil/trace.go:171","msg":"trace[373008665] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:16030; }","duration":"209.868542ms","start":"2026-10-15T19:36:53.593661Z","end":"2026-10-15T19:36:53.803529Z","steps":["trace[373008665] 'agreement among raft nodes before linearized reading' (duration: 209.679487ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:36:53.802686Z","caller":"traceutil/trace.go:171","msg":"trace[1168756754] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16030; }","duration":"162.563187ms","start":"2026-10-15T19:36:53.640077Z","end":"2026-10-15T19:36:53.802641Z","steps":["trace[1168756754] 'agreement among raft nodes before linearized reading' (duration: 162.427334ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:36:53.803429Z","caller":"traceutil/trace.go:171","msg":"trace[900394696] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16030; }","duration":"123.000938ms","start":"2026-10-15T19:36:53.680421Z","end":"2026-10-15T19:36:53.803422Z","steps":["trace[900394696] 'agreement among raft nodes before linearized reading' (duration: 122.705651ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:36:53.803437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:36:53.493174Z","time spent":"310.256184ms","remote":"127.0.0.1:58954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":76,"response size":737477,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-10-15T19:36:54.074522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.950541ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T19:36:54.074623Z","caller":"traceutil/trace.go:171","msg":"trace[982607849] transaction","detail":"{read_only:false; response_revision:16031; number_of_response:1; }","duration":"266.168936ms","start":"2026-10-15T19:36:53.808436Z","end":"2026-10-15T19:36:54.074605Z","steps":["trace[982607849] 'process raft request' (duration: 159.057891ms)","trace[982607849] 'compare' (duration: 106.840528ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:36:54.086561Z","caller":"traceutil/trace.go:171","msg":"trace[540024591] transaction","detail":"{read_only:false; response_revision:16032; number_of_response:1; }","duration":"277.886199ms","start":"2026-10-15T19:36:53.808648Z","end":"2026-10-15T19:36:54.086534Z","steps":["trace[540024591] 'process raft request' (duration: 277.725686ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:36:54.086739Z","caller":"traceutil/trace.go:171","msg":"trace[789169355] linearizableReadLoop","detail":"{readStateIndex:16592; appliedIndex:16590; }","duration":"274.435435ms","start":"2026-10-15T19:36:53.812257Z","end":"2026-10-15T19:36:54.086692Z","steps":["trace[789169355] 'read index received' (duration: 155.25227ms)","trace[789169355] 'applied index is now lower than readState.Index' (duration: 119.180395ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:36:54.086768Z","caller":"traceutil/trace.go:171","msg":"trace[1528029949] transaction","detail":"{read_only:false; response_revision:16033; number_of_response:1; }","duration":"151.721903ms","start":"2026-10-15T19:36:53.935011Z","end":"2026-10-15T19:36:54.086733Z","steps":["trace[1528029949] 'process raft request' (duration: 151.475968ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:36:54.087493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.214184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:76 size:737454"} {"level":"info","ts":"2026-10-15T19:36:54.087543Z","caller":"traceutil/trace.go:171","msg":"trace[2123687077] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:76; response_revision:16033; }","duration":"275.292626ms","start":"2026-10-15T19:36:53.812238Z","end":"2026-10-15T19:36:54.087531Z","steps":["trace[2123687077] 'agreement among raft nodes before linearized reading' (duration: 274.586949ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:36:54.090141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.31529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T19:36:54.090217Z","caller":"traceutil/trace.go:171","msg":"trace[131926346] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16034; }","duration":"219.421363ms","start":"2026-10-15T19:36:53.870779Z","end":"2026-10-15T19:36:54.090201Z","steps":["trace[131926346] 'agreement among raft nodes before linearized reading' (duration: 219.287149ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:36:54.090227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.245069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-15T19:36:54.090249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.05488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"warn","ts":"2026-10-15T19:36:54.090265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.383933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T19:36:54.090283Z","caller":"traceutil/trace.go:171","msg":"trace[518331666] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:16034; }","duration":"219.371392ms","start":"2026-10-15T19:36:53.870894Z","end":"2026-10-15T19:36:54.090266Z","steps":["trace[518331666] 'agreement among raft nodes before linearized reading' (duration: 219.27294ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:36:54.0903Z","caller":"traceutil/trace.go:171","msg":"trace[283097075] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16034; }","duration":"218.123742ms","start":"2026-10-15T19:36:53.872164Z","end":"2026-10-15T19:36:54.090287Z","steps":["trace[283097075] 'agreement among raft nodes before linearized reading' (duration: 217.958658ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:36:54.090312Z","caller":"traceutil/trace.go:171","msg":"trace[1560746892] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:16034; }","duration":"219.480565ms","start":"2026-10-15T19:36:53.870818Z","end":"2026-10-15T19:36:54.090298Z","steps":["trace[1560746892] 'agreement among raft nodes before linearized reading' (duration: 219.382653ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:36:54.09031Z","caller":"traceutil/trace.go:171","msg":"trace[1652317965] transaction","detail":"{read_only:false; response_revision:16034; number_of_response:1; }","duration":"120.066168ms","start":"2026-10-15T19:36:53.970217Z","end":"2026-10-15T19:36:54.090284Z","steps":["trace[1652317965] 'process raft request' (duration: 119.74518ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:39:16.839805Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14636} {"level":"info","ts":"2026-10-15T19:39:16.929701Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14636,"took":"87.164543ms","hash":395882508,"current-db-size-bytes":56999936,"current-db-size":"57 MB","current-db-size-in-use-bytes":31674368,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-15T19:39:16.92977Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":395882508,"revision":14636,"compact-revision":12595} {"level":"info","ts":"2026-10-15T19:39:57.550297Z","caller":"traceutil/trace.go:171","msg":"trace[1154787484] transaction","detail":"{read_only:false; response_revision:17499; number_of_response:1; }","duration":"150.528998ms","start":"2026-10-15T19:39:57.399731Z","end":"2026-10-15T19:39:57.55026Z","steps":["trace[1154787484] 'process raft request' (duration: 150.297563ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:43:01.049203Z","caller":"traceutil/trace.go:171","msg":"trace[794921917] transaction","detail":"{read_only:false; response_revision:18705; number_of_response:1; }","duration":"103.601444ms","start":"2026-10-15T19:43:00.945561Z","end":"2026-10-15T19:43:01.049163Z","steps":["trace[794921917] 'process raft request' (duration: 103.355449ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:43:07.871889Z","caller":"traceutil/trace.go:171","msg":"trace[320670015] linearizableReadLoop","detail":"{readStateIndex:19397; appliedIndex:19396; }","duration":"112.702293ms","start":"2026-10-15T19:43:07.759157Z","end":"2026-10-15T19:43:07.87186Z","steps":["trace[320670015] 'read index received' (duration: 112.531339ms)","trace[320670015] 'applied index is now lower than readState.Index' (duration: 169.764µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:43:07.871969Z","caller":"traceutil/trace.go:171","msg":"trace[1273617027] transaction","detail":"{read_only:false; response_revision:18746; number_of_response:1; }","duration":"289.608185ms","start":"2026-10-15T19:43:07.582317Z","end":"2026-10-15T19:43:07.871925Z","steps":["trace[1273617027] 'process raft request' (duration: 289.386139ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:43:07.872035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.868327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T19:43:07.87208Z","caller":"traceutil/trace.go:171","msg":"trace[1260665784] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18746; }","duration":"112.967929ms","start":"2026-10-15T19:43:07.759099Z","end":"2026-10-15T19:43:07.872067Z","steps":["trace[1260665784] 'agreement among raft nodes before linearized reading' (duration: 112.863627ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:43:08.561459Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10122298937825618234,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-15T19:43:08.636086Z","caller":"traceutil/trace.go:171","msg":"trace[1788059796] transaction","detail":"{read_only:false; response_revision:18747; number_of_response:1; }","duration":"638.425778ms","start":"2026-10-15T19:43:07.997633Z","end":"2026-10-15T19:43:08.636058Z","steps":["trace[1788059796] 'process raft request' (duration: 638.244914ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:43:08.636231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:43:07.997615Z","time spent":"638.53955ms","remote":"127.0.0.1:59034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T19:43:08.666461Z","caller":"traceutil/trace.go:171","msg":"trace[249477888] linearizableReadLoop","detail":"{readStateIndex:19399; appliedIndex:19397; }","duration":"605.901479ms","start":"2026-10-15T19:43:08.060523Z","end":"2026-10-15T19:43:08.666425Z","steps":["trace[249477888] 'read index received' (duration: 575.283606ms)","trace[249477888] 'applied index is now lower than readState.Index' (duration: 30.616703ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:43:08.666535Z","caller":"traceutil/trace.go:171","msg":"trace[374391103] transaction","detail":"{read_only:false; response_revision:18748; number_of_response:1; }","duration":"668.392425ms","start":"2026-10-15T19:43:07.998112Z","end":"2026-10-15T19:43:08.666504Z","steps":["trace[374391103] 'process raft request' (duration: 668.17701ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:43:08.666617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.073053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T19:43:08.666718Z","caller":"traceutil/trace.go:171","msg":"trace[1852273062] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:18748; }","duration":"606.230326ms","start":"2026-10-15T19:43:08.060469Z","end":"2026-10-15T19:43:08.6667Z","steps":["trace[1852273062] 'agreement among raft nodes before linearized reading' (duration: 606.066923ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:43:08.666733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.819489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-10-15T19:43:08.666759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:43:08.06045Z","time spent":"606.297317ms","remote":"127.0.0.1:36050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true "} {"level":"info","ts":"2026-10-15T19:43:08.666778Z","caller":"traceutil/trace.go:171","msg":"trace[2068118144] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18748; }","duration":"540.921572ms","start":"2026-10-15T19:43:08.125844Z","end":"2026-10-15T19:43:08.666766Z","steps":["trace[2068118144] 'agreement among raft nodes before linearized reading' (duration: 540.722368ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:43:08.666709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:43:07.99809Z","time spent":"668.514788ms","remote":"127.0.0.1:59034","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-15T19:43:08.666814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:43:08.125827Z","time spent":"540.978903ms","remote":"127.0.0.1:59034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-15T19:43:08.666788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.629472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-15T19:43:08.666868Z","caller":"traceutil/trace.go:171","msg":"trace[434043232] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18748; }","duration":"119.766795ms","start":"2026-10-15T19:43:08.547088Z","end":"2026-10-15T19:43:08.666854Z","steps":["trace[434043232] 'agreement among raft nodes before linearized reading' (duration: 119.50905ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:43:54.539032Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"9d6ea9653fb28c79","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T19:43:54.545156Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-15T19:43:54.545335Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-15T19:44:16.849737Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17008} {"level":"info","ts":"2026-10-15T19:44:16.96269Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17008,"took":"109.483086ms","hash":2130557536,"current-db-size-bytes":56999936,"current-db-size":"57 MB","current-db-size-in-use-bytes":32980992,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-15T19:44:16.962762Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2130557536,"revision":17008,"compact-revision":14636} {"level":"info","ts":"2026-10-15T19:48:04.549456Z","caller":"traceutil/trace.go:171","msg":"trace[1762647701] transaction","detail":"{read_only:false; response_revision:21175; number_of_response:1; }","duration":"125.119563ms","start":"2026-10-15T19:48:04.4243Z","end":"2026-10-15T19:48:04.549419Z","steps":["trace[1762647701] 'process raft request' (duration: 113.352068ms)","trace[1762647701] 'compare' (duration: 10.835672ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:48:55.795168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.207466ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-10-15T19:48:55.795518Z","caller":"traceutil/trace.go:171","msg":"trace[1848918685] linearizableReadLoop","detail":"{readStateIndex:22179; appliedIndex:22178; }","duration":"248.2678ms","start":"2026-10-15T19:48:55.547011Z","end":"2026-10-15T19:48:55.795279Z","steps":["trace[1848918685] 'read index received' (duration: 63.662µs)","trace[1848918685] 'applied index is now lower than readState.Index' (duration: 248.202138ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:48:55.795773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.733922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T19:48:55.795959Z","caller":"traceutil/trace.go:171","msg":"trace[1940480496] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:21440; }","duration":"248.911636ms","start":"2026-10-15T19:48:55.546961Z","end":"2026-10-15T19:48:55.795873Z","steps":["trace[1940480496] 'agreement among raft nodes before linearized reading' (duration: 248.600958ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:48:56.243513Z","caller":"traceutil/trace.go:171","msg":"trace[25034683] linearizableReadLoop","detail":"{readStateIndex:22181; appliedIndex:22180; }","duration":"234.764443ms","start":"2026-10-15T19:48:56.008716Z","end":"2026-10-15T19:48:56.243481Z","steps":["trace[25034683] 'read index received' (duration: 179.842791ms)","trace[25034683] 'applied index is now lower than readState.Index' (duration: 54.920242ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:48:56.243564Z","caller":"traceutil/trace.go:171","msg":"trace[1652879115] transaction","detail":"{read_only:false; response_revision:21441; number_of_response:1; }","duration":"345.671983ms","start":"2026-10-15T19:48:55.89787Z","end":"2026-10-15T19:48:56.243542Z","steps":["trace[1652879115] 'process raft request' (duration: 290.69376ms)","trace[1652879115] 'compare' (duration: 54.704486ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:48:56.243689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.958038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-15T19:48:56.243882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:48:55.897853Z","time spent":"345.856698ms","remote":"127.0.0.1:58860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":888,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T19:48:56.243887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.322934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T19:48:56.24374Z","caller":"traceutil/trace.go:171","msg":"trace[76568925] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:21441; }","duration":"235.059241ms","start":"2026-10-15T19:48:56.008666Z","end":"2026-10-15T19:48:56.243725Z","steps":["trace[76568925] 'agreement among raft nodes before linearized reading' (duration: 234.955198ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:48:56.244094Z","caller":"traceutil/trace.go:171","msg":"trace[1138064255] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:21441; }","duration":"192.56694ms","start":"2026-10-15T19:48:56.051496Z","end":"2026-10-15T19:48:56.244063Z","steps":["trace[1138064255] 'agreement among raft nodes before linearized reading' (duration: 192.268493ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:48:56.607564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.354212ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T19:48:56.607839Z","caller":"traceutil/trace.go:171","msg":"trace[1094703736] transaction","detail":"{read_only:false; response_revision:21443; number_of_response:1; }","duration":"190.863108ms","start":"2026-10-15T19:48:56.416933Z","end":"2026-10-15T19:48:56.607796Z","steps":["trace[1094703736] 'process raft request' (duration: 190.755726ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:48:56.607794Z","caller":"traceutil/trace.go:171","msg":"trace[178570704] transaction","detail":"{read_only:false; response_revision:21442; number_of_response:1; }","duration":"357.992761ms","start":"2026-10-15T19:48:56.249761Z","end":"2026-10-15T19:48:56.607754Z","steps":["trace[178570704] 'process raft request' (duration: 109.346752ms)","trace[178570704] 'compare' (duration: 247.798289ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:48:56.607895Z","caller":"traceutil/trace.go:171","msg":"trace[1857725214] linearizableReadLoop","detail":"{readStateIndex:22182; appliedIndex:22181; }","duration":"349.939286ms","start":"2026-10-15T19:48:56.257925Z","end":"2026-10-15T19:48:56.607864Z","steps":["trace[1857725214] 'read index received' (duration: 101.093362ms)","trace[1857725214] 'applied index is now lower than readState.Index' (duration: 248.843834ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:48:56.607998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:48:56.249743Z","time spent":"358.154165ms","remote":"127.0.0.1:58860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":888,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T19:48:56.608115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.160991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-15T19:48:56.608206Z","caller":"traceutil/trace.go:171","msg":"trace[715079607] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:21443; }","duration":"350.307175ms","start":"2026-10-15T19:48:56.257876Z","end":"2026-10-15T19:48:56.608183Z","steps":["trace[715079607] 'agreement among raft nodes before linearized reading' (duration: 350.080529ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:48:56.60822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.228293ms","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-15T19:48:56.608256Z","caller":"traceutil/trace.go:171","msg":"trace[473040242] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21443; }","duration":"103.298344ms","start":"2026-10-15T19:48:56.504945Z","end":"2026-10-15T19:48:56.608243Z","steps":["trace[473040242] 'agreement among raft nodes before linearized reading' (duration: 103.223073ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:48:56.608256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:48:56.257858Z","time spent":"350.388437ms","remote":"127.0.0.1:58896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":13,"response size":31,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-10-15T19:48:56.608147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.62756ms","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-15T19:48:56.608475Z","caller":"traceutil/trace.go:171","msg":"trace[1001991121] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21443; }","duration":"107.988199ms","start":"2026-10-15T19:48:56.500459Z","end":"2026-10-15T19:48:56.608448Z","steps":["trace[1001991121] 'agreement among raft nodes before linearized reading' (duration: 107.549439ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:48:56.954764Z","caller":"traceutil/trace.go:171","msg":"trace[1488288236] transaction","detail":"{read_only:false; response_revision:21445; number_of_response:1; }","duration":"341.507882ms","start":"2026-10-15T19:48:56.61323Z","end":"2026-10-15T19:48:56.954738Z","steps":["trace[1488288236] 'process raft request' (duration: 341.337578ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:48:56.955382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:48:56.613191Z","time spent":"341.718037ms","remote":"127.0.0.1:59034","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-15T19:48:57.337982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.099618ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T19:48:57.338346Z","caller":"traceutil/trace.go:171","msg":"trace[1957715254] transaction","detail":"{read_only:false; response_revision:21446; number_of_response:1; }","duration":"702.075366ms","start":"2026-10-15T19:48:56.636241Z","end":"2026-10-15T19:48:57.338317Z","steps":["trace[1957715254] 'process raft request' (duration: 483.561837ms)","trace[1957715254] 'compare' (duration: 217.919034ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:48:57.33846Z","caller":"traceutil/trace.go:171","msg":"trace[2092565564] transaction","detail":"{read_only:false; response_revision:21448; number_of_response:1; }","duration":"401.773083ms","start":"2026-10-15T19:48:56.93666Z","end":"2026-10-15T19:48:57.338433Z","steps":["trace[2092565564] 'process raft request' (duration: 401.556008ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:48:57.338506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:48:56.636219Z","time spent":"702.204998ms","remote":"127.0.0.1:59034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T19:48:57.338573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:48:56.936633Z","time spent":"401.885797ms","remote":"127.0.0.1:59034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T19:48:57.338341Z","caller":"traceutil/trace.go:171","msg":"trace[1539779044] transaction","detail":"{read_only:false; response_revision:21447; number_of_response:1; }","duration":"608.700461ms","start":"2026-10-15T19:48:56.729594Z","end":"2026-10-15T19:48:57.338295Z","steps":["trace[1539779044] 'process raft request' (duration: 608.492936ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:48:57.338572Z","caller":"traceutil/trace.go:171","msg":"trace[1500527079] linearizableReadLoop","detail":"{readStateIndex:22186; appliedIndex:22184; }","duration":"695.63501ms","start":"2026-10-15T19:48:56.642923Z","end":"2026-10-15T19:48:57.338558Z","steps":["trace[1500527079] 'read index received' (duration: 311.591246ms)","trace[1500527079] 'applied index is now lower than readState.Index' (duration: 384.042854ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:48:57.33876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.841675ms","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-15T19:48:57.338762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:48:56.729573Z","time spent":"609.106862ms","remote":"127.0.0.1:59034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T19:48:57.338803Z","caller":"traceutil/trace.go:171","msg":"trace[824700520] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21448; }","duration":"695.943657ms","start":"2026-10-15T19:48:56.642847Z","end":"2026-10-15T19:48:57.338791Z","steps":["trace[824700520] 'agreement among raft nodes before linearized reading' (duration: 695.755243ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:48:57.338861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:48:56.642776Z","time spent":"696.075631ms","remote":"127.0.0.1:59034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-15T19:48:57.339639Z","caller":"traceutil/trace.go:171","msg":"trace[1375970201] transaction","detail":"{read_only:false; response_revision:21449; number_of_response:1; }","duration":"371.95636ms","start":"2026-10-15T19:48:56.967667Z","end":"2026-10-15T19:48:57.339623Z","steps":["trace[1375970201] 'process raft request' (duration: 370.842933ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:48:57.33972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:48:56.967621Z","time spent":"372.058512ms","remote":"127.0.0.1:59034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T19:48:57.339997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.872092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-15T19:48:57.340005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.468603ms","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:6"} {"level":"info","ts":"2026-10-15T19:48:57.340053Z","caller":"traceutil/trace.go:171","msg":"trace[1732562459] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:21450; }","duration":"492.960774ms","start":"2026-10-15T19:48:56.847076Z","end":"2026-10-15T19:48:57.340037Z","steps":["trace[1732562459] 'agreement among raft nodes before linearized reading' (duration: 492.884672ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:48:57.340092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:48:56.847056Z","time spent":"493.025386ms","remote":"127.0.0.1:42948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":1,"response size":31,"request content":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true "} {"level":"info","ts":"2026-10-15T19:48:57.340106Z","caller":"traceutil/trace.go:171","msg":"trace[642969025] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:21450; }","duration":"577.605136ms","start":"2026-10-15T19:48:56.762442Z","end":"2026-10-15T19:48:57.340048Z","steps":["trace[642969025] 'agreement among raft nodes before linearized reading' (duration: 577.482193ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:48:57.340167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:48:56.762422Z","time spent":"577.727509ms","remote":"127.0.0.1:33720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"info","ts":"2026-10-15T19:48:57.34056Z","caller":"traceutil/trace.go:171","msg":"trace[1723858683] transaction","detail":"{read_only:false; response_revision:21450; number_of_response:1; }","duration":"232.273102ms","start":"2026-10-15T19:48:57.108261Z","end":"2026-10-15T19:48:57.340534Z","steps":["trace[1723858683] 'process raft request' (duration: 231.412841ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:48:57.340735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.319298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-15T19:48:57.340857Z","caller":"traceutil/trace.go:171","msg":"trace[1257351255] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:21450; }","duration":"348.442971ms","start":"2026-10-15T19:48:56.992394Z","end":"2026-10-15T19:48:57.340837Z","steps":["trace[1257351255] 'agreement among raft nodes before linearized reading' (duration: 347.389495ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:48:57.340916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:48:56.992344Z","time spent":"348.554852ms","remote":"127.0.0.1:59012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":62,"response size":31,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-10-15T19:48:57.341607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"693.801744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-15T19:48:57.341669Z","caller":"traceutil/trace.go:171","msg":"trace[1961041682] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21450; }","duration":"693.890737ms","start":"2026-10-15T19:48:56.647764Z","end":"2026-10-15T19:48:57.341654Z","steps":["trace[1961041682] 'agreement among raft nodes before linearized reading' (duration: 692.189985ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:48:57.341713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:48:56.647752Z","time spent":"693.951758ms","remote":"127.0.0.1:59034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-15T19:48:57.341985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"696.252705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-15T19:48:57.342151Z","caller":"traceutil/trace.go:171","msg":"trace[1762639461] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21450; }","duration":"696.444859ms","start":"2026-10-15T19:48:56.645677Z","end":"2026-10-15T19:48:57.342122Z","steps":["trace[1762639461] 'agreement among raft nodes before linearized reading' (duration: 694.091201ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:48:57.342197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:48:56.645658Z","time spent":"696.528372ms","remote":"127.0.0.1:59034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-15T19:48:57.447007Z","caller":"traceutil/trace.go:171","msg":"trace[1445169268] transaction","detail":"{read_only:false; response_revision:21451; number_of_response:1; }","duration":"102.920946ms","start":"2026-10-15T19:48:57.344055Z","end":"2026-10-15T19:48:57.446976Z","steps":["trace[1445169268] 'process raft request' (duration: 102.751731ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:48:57.449838Z","caller":"traceutil/trace.go:171","msg":"trace[1443521173] transaction","detail":"{read_only:false; response_revision:21452; number_of_response:1; }","duration":"103.685335ms","start":"2026-10-15T19:48:57.3461Z","end":"2026-10-15T19:48:57.449786Z","steps":["trace[1443521173] 'process raft request' (duration: 103.48129ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:48:57.450082Z","caller":"traceutil/trace.go:171","msg":"trace[1409074267] transaction","detail":"{read_only:false; response_revision:21453; number_of_response:1; }","duration":"103.597602ms","start":"2026-10-15T19:48:57.346329Z","end":"2026-10-15T19:48:57.449926Z","steps":["trace[1409074267] 'process raft request' (duration: 103.399817ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:49:16.857797Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19507} {"level":"info","ts":"2026-10-15T19:49:16.98617Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19507,"took":"124.909439ms","hash":4182714479,"current-db-size-bytes":56999936,"current-db-size":"57 MB","current-db-size-in-use-bytes":29065216,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-15T19:49:16.986243Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4182714479,"revision":19507,"compact-revision":17008} {"level":"info","ts":"2026-10-15T19:53:01.614552Z","caller":"traceutil/trace.go:171","msg":"trace[535879937] transaction","detail":"{read_only:false; response_revision:23948; number_of_response:1; }","duration":"212.87944ms","start":"2026-10-15T19:53:01.401644Z","end":"2026-10-15T19:53:01.614524Z","steps":["trace[535879937] 'process raft request' (duration: 212.709856ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:54:16.870588Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21546} {"level":"info","ts":"2026-10-15T19:54:16.984365Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21546,"took":"110.147554ms","hash":2556895023,"current-db-size-bytes":56999936,"current-db-size":"57 MB","current-db-size-in-use-bytes":43036672,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-10-15T19:54:16.984495Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2556895023,"revision":21546,"compact-revision":19507} {"level":"info","ts":"2026-10-15T19:56:09.16881Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006827.wal"} {"level":"warn","ts":"2026-10-15T19:56:45.832971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.934847ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-10-15T19:56:45.833088Z","caller":"traceutil/trace.go:171","msg":"trace[613950724] linearizableReadLoop","detail":"{readStateIndex:27014; appliedIndex:27013; }","duration":"292.141422ms","start":"2026-10-15T19:56:45.54093Z","end":"2026-10-15T19:56:45.833072Z","steps":["trace[613950724] 'read index received' (duration: 78.033601ms)","trace[613950724] 'applied index is now lower than readState.Index' (duration: 214.106431ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:56:45.833691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.757247ms","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-15T19:56:45.833791Z","caller":"traceutil/trace.go:171","msg":"trace[207908926] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26144; }","duration":"292.89209ms","start":"2026-10-15T19:56:45.540881Z","end":"2026-10-15T19:56:45.833773Z","steps":["trace[207908926] 'agreement among raft nodes before linearized reading' (duration: 292.666064ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:56:45.83403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.513622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:419209"} {"level":"info","ts":"2026-10-15T19:56:45.834164Z","caller":"traceutil/trace.go:171","msg":"trace[1264771658] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:26144; }","duration":"285.616895ms","start":"2026-10-15T19:56:45.548467Z","end":"2026-10-15T19:56:45.834084Z","steps":["trace[1264771658] 'agreement among raft nodes before linearized reading' (duration: 285.254256ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:56:46.007859Z","caller":"traceutil/trace.go:171","msg":"trace[570795561] transaction","detail":"{read_only:false; response_revision:26145; number_of_response:1; }","duration":"168.477637ms","start":"2026-10-15T19:56:45.839332Z","end":"2026-10-15T19:56:46.00781Z","steps":["trace[570795561] 'process raft request' (duration: 168.298913ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:56:46.164884Z","caller":"traceutil/trace.go:171","msg":"trace[1816077795] transaction","detail":"{read_only:false; response_revision:26146; number_of_response:1; }","duration":"155.112097ms","start":"2026-10-15T19:56:46.009745Z","end":"2026-10-15T19:56:46.164857Z","steps":["trace[1816077795] 'process raft request' (duration: 153.222239ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:56:46.430384Z","caller":"traceutil/trace.go:171","msg":"trace[1607455725] transaction","detail":"{read_only:false; response_revision:26148; number_of_response:1; }","duration":"183.11826ms","start":"2026-10-15T19:56:46.247246Z","end":"2026-10-15T19:56:46.430365Z","steps":["trace[1607455725] 'process raft request' (duration: 163.278434ms)","trace[1607455725] 'compare' (duration: 19.752374ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:56:46.618302Z","caller":"traceutil/trace.go:171","msg":"trace[1537987889] transaction","detail":"{read_only:false; response_revision:26149; number_of_response:1; }","duration":"368.56419ms","start":"2026-10-15T19:56:46.249669Z","end":"2026-10-15T19:56:46.618234Z","steps":["trace[1537987889] 'process raft request' (duration: 368.309604ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:56:46.618613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:56:46.249643Z","time spent":"368.846768ms","remote":"127.0.0.1:59034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T19:56:46.618619Z","caller":"traceutil/trace.go:171","msg":"trace[85495415] linearizableReadLoop","detail":"{readStateIndex:27019; appliedIndex:27017; }","duration":"365.369879ms","start":"2026-10-15T19:56:46.253217Z","end":"2026-10-15T19:56:46.618587Z","steps":["trace[85495415] 'read index received' (duration: 157.491956ms)","trace[85495415] 'applied index is now lower than readState.Index' (duration: 207.876693ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:56:46.618629Z","caller":"traceutil/trace.go:171","msg":"trace[943759906] transaction","detail":"{read_only:false; response_revision:26150; number_of_response:1; }","duration":"324.341623ms","start":"2026-10-15T19:56:46.294249Z","end":"2026-10-15T19:56:46.618591Z","steps":["trace[943759906] 'process raft request' (duration: 323.899762ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:56:46.618808Z","caller":"traceutil/trace.go:171","msg":"trace[1672263245] transaction","detail":"{read_only:false; response_revision:26151; number_of_response:1; }","duration":"313.087036ms","start":"2026-10-15T19:56:46.305707Z","end":"2026-10-15T19:56:46.618794Z","steps":["trace[1672263245] 'process raft request' (duration: 312.596313ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:56:46.618896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:56:46.294225Z","time spent":"324.556248ms","remote":"127.0.0.1:59034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T19:56:46.618948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:56:46.305684Z","time spent":"313.183349ms","remote":"127.0.0.1:44164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419192,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T19:56:46.618955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.731589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/neutron-dhcp-agent-default-ksvq2.18decb851413035b\" ","response":"range_response_count:1 size:957"} {"level":"info","ts":"2026-10-15T19:56:46.619Z","caller":"traceutil/trace.go:171","msg":"trace[107527739] range","detail":"{range_begin:/registry/events/openstack/neutron-dhcp-agent-default-ksvq2.18decb851413035b; range_end:; response_count:1; response_revision:26151; }","duration":"365.79541ms","start":"2026-10-15T19:56:46.253189Z","end":"2026-10-15T19:56:46.618985Z","steps":["trace[107527739] 'agreement among raft nodes before linearized reading' (duration: 365.607235ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:56:46.619034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:56:46.253173Z","time spent":"365.855092ms","remote":"127.0.0.1:58860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":980,"request content":"key:\"/registry/events/openstack/neutron-dhcp-agent-default-ksvq2.18decb851413035b\" "} {"level":"warn","ts":"2026-10-15T19:56:46.631812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.443698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T19:56:46.631909Z","caller":"traceutil/trace.go:171","msg":"trace[1857689536] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:26152; }","duration":"310.561601ms","start":"2026-10-15T19:56:46.32133Z","end":"2026-10-15T19:56:46.631891Z","steps":["trace[1857689536] 'agreement among raft nodes before linearized reading' (duration: 310.386376ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:56:46.631892Z","caller":"traceutil/trace.go:171","msg":"trace[1682973971] transaction","detail":"{read_only:false; response_revision:26152; number_of_response:1; }","duration":"150.836727ms","start":"2026-10-15T19:56:46.481017Z","end":"2026-10-15T19:56:46.631854Z","steps":["trace[1682973971] 'process raft request' (duration: 150.542859ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:56:46.631948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:56:46.321276Z","time spent":"310.662083ms","remote":"127.0.0.1:33698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-10-15T19:57:43.223137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.006145ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T19:57:43.223269Z","caller":"traceutil/trace.go:171","msg":"trace[716844029] linearizableReadLoop","detail":"{readStateIndex:27554; appliedIndex:27553; }","duration":"124.717071ms","start":"2026-10-15T19:57:43.098531Z","end":"2026-10-15T19:57:43.223248Z","steps":["trace[716844029] 'read index received' (duration: 54.881µs)","trace[716844029] 'applied index is now lower than readState.Index' (duration: 124.66043ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:57:43.223559Z","caller":"traceutil/trace.go:171","msg":"trace[1097347756] transaction","detail":"{read_only:false; response_revision:26672; number_of_response:1; }","duration":"188.55668ms","start":"2026-10-15T19:57:43.034984Z","end":"2026-10-15T19:57:43.22354Z","steps":["trace[1097347756] 'process raft request' (duration: 11.049682ms)","trace[1097347756] 'compare' (duration: 176.591124ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:57:43.223771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.210805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T19:57:43.223865Z","caller":"traceutil/trace.go:171","msg":"trace[1864596410] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0; response_count:0; response_revision:26672; }","duration":"125.363969ms","start":"2026-10-15T19:57:43.098477Z","end":"2026-10-15T19:57:43.223841Z","steps":["trace[1864596410] 'agreement among raft nodes before linearized reading' (duration: 125.159003ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:57:49.137008Z","caller":"traceutil/trace.go:171","msg":"trace[796112078] transaction","detail":"{read_only:false; response_revision:26707; number_of_response:1; }","duration":"484.403445ms","start":"2026-10-15T19:57:48.65258Z","end":"2026-10-15T19:57:49.136983Z","steps":["trace[796112078] 'process raft request' (duration: 484.227661ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:57:49.137155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:57:48.65256Z","time spent":"484.522398ms","remote":"127.0.0.1:59034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T19:57:49.355557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.049927ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T19:57:49.355766Z","caller":"traceutil/trace.go:171","msg":"trace[1746803596] transaction","detail":"{read_only:false; response_revision:26708; number_of_response:1; }","duration":"649.164747ms","start":"2026-10-15T19:57:48.70658Z","end":"2026-10-15T19:57:49.355744Z","steps":["trace[1746803596] 'process raft request' (duration: 527.836043ms)","trace[1746803596] 'compare' (duration: 120.790042ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:57:49.355839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:57:48.706558Z","time spent":"649.24494ms","remote":"127.0.0.1:59034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T19:57:49.356337Z","caller":"traceutil/trace.go:171","msg":"trace[2016332870] transaction","detail":"{read_only:false; response_revision:26709; number_of_response:1; }","duration":"493.713253ms","start":"2026-10-15T19:57:48.862588Z","end":"2026-10-15T19:57:49.356301Z","steps":["trace[2016332870] 'process raft request' (duration: 493.089387ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:57:49.356481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:57:48.862566Z","time spent":"493.838277ms","remote":"127.0.0.1:59034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T19:57:49.35651Z","caller":"traceutil/trace.go:171","msg":"trace[1532523772] transaction","detail":"{read_only:false; response_revision:26710; number_of_response:1; }","duration":"489.707511ms","start":"2026-10-15T19:57:48.866781Z","end":"2026-10-15T19:57:49.356489Z","steps":["trace[1532523772] 'process raft request' (duration: 489.272029ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:57:49.356526Z","caller":"traceutil/trace.go:171","msg":"trace[832561374] transaction","detail":"{read_only:false; response_revision:26712; number_of_response:1; }","duration":"109.169764ms","start":"2026-10-15T19:57:49.247337Z","end":"2026-10-15T19:57:49.356507Z","steps":["trace[832561374] 'process raft request' (duration: 109.108723ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:57:49.356573Z","caller":"traceutil/trace.go:171","msg":"trace[897351613] linearizableReadLoop","detail":"{readStateIndex:27591; appliedIndex:27589; }","duration":"613.329893ms","start":"2026-10-15T19:57:48.743219Z","end":"2026-10-15T19:57:49.356549Z","steps":["trace[897351613] 'read index received' (duration: 393.572209ms)","trace[897351613] 'applied index is now lower than readState.Index' (duration: 219.755744ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:57:49.356567Z","caller":"traceutil/trace.go:171","msg":"trace[1279288143] transaction","detail":"{read_only:false; response_revision:26711; number_of_response:1; }","duration":"316.242136ms","start":"2026-10-15T19:57:49.040291Z","end":"2026-10-15T19:57:49.356533Z","steps":["trace[1279288143] 'process raft request' (duration: 316.088872ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:57:49.356611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:57:48.866759Z","time spent":"489.798062ms","remote":"127.0.0.1:59034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T19:57:49.356739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:57:49.040264Z","time spent":"316.38991ms","remote":"127.0.0.1:59034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":605,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T19:57:49.356823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.969062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T19:57:49.356889Z","caller":"traceutil/trace.go:171","msg":"trace[1974750123] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:26712; }","duration":"382.055745ms","start":"2026-10-15T19:57:48.974815Z","end":"2026-10-15T19:57:49.356871Z","steps":["trace[1974750123] 'agreement among raft nodes before linearized reading' (duration: 381.929731ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:57:49.35686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.61198ms","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-15T19:57:49.356964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:57:48.974763Z","time spent":"382.188809ms","remote":"127.0.0.1:43970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":0,"response size":29,"request content":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true "} {"level":"info","ts":"2026-10-15T19:57:49.357Z","caller":"traceutil/trace.go:171","msg":"trace[418173187] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26712; }","duration":"613.790255ms","start":"2026-10-15T19:57:48.743177Z","end":"2026-10-15T19:57:49.356967Z","steps":["trace[418173187] 'agreement among raft nodes before linearized reading' (duration: 613.471667ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:57:49.357018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.374908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-10-15T19:57:49.357072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:57:48.743158Z","time spent":"613.900028ms","remote":"127.0.0.1:59034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-15T19:57:49.357106Z","caller":"traceutil/trace.go:171","msg":"trace[1683506979] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26712; }","duration":"412.493801ms","start":"2026-10-15T19:57:48.944587Z","end":"2026-10-15T19:57:49.357081Z","steps":["trace[1683506979] 'agreement among raft nodes before linearized reading' (duration: 412.187213ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:57:49.35716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:57:48.944538Z","time spent":"412.612915ms","remote":"127.0.0.1:59034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-15T19:57:49.357405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.317877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusteridentities/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusteridentities0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T19:57:49.357501Z","caller":"traceutil/trace.go:171","msg":"trace[1930810046] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusteridentities/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusteridentities0; response_count:0; response_revision:26712; }","duration":"501.422469ms","start":"2026-10-15T19:57:48.856058Z","end":"2026-10-15T19:57:49.357481Z","steps":["trace[1930810046] 'agreement among raft nodes before linearized reading' (duration: 500.637359ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:57:49.357566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:57:48.856034Z","time spent":"501.511972ms","remote":"127.0.0.1:44268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":144,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusteridentities/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusteridentities0\" count_only:true "} {"level":"warn","ts":"2026-10-15T19:57:49.781922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.493594ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T19:57:49.782071Z","caller":"traceutil/trace.go:171","msg":"trace[1906823240] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:26712; }","duration":"280.676889ms","start":"2026-10-15T19:57:49.501369Z","end":"2026-10-15T19:57:49.782046Z","steps":["trace[1906823240] 'range keys from in-memory index tree' (duration: 280.469894ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:57:49.782319Z","caller":"traceutil/trace.go:171","msg":"trace[1652046805] transaction","detail":"{read_only:false; response_revision:26713; number_of_response:1; }","duration":"421.07196ms","start":"2026-10-15T19:57:49.361221Z","end":"2026-10-15T19:57:49.782293Z","steps":["trace[1652046805] 'process raft request' (duration: 344.633791ms)","trace[1652046805] 'compare' (duration: 76.308006ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:57:49.782459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:57:49.361202Z","time spent":"421.188733ms","remote":"127.0.0.1:59034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T19:57:49.965101Z","caller":"traceutil/trace.go:171","msg":"trace[2091023839] transaction","detail":"{read_only:false; response_revision:26714; number_of_response:1; }","duration":"603.36506ms","start":"2026-10-15T19:57:49.361705Z","end":"2026-10-15T19:57:49.96507Z","steps":["trace[2091023839] 'process raft request' (duration: 603.120754ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:57:49.965259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:57:49.361674Z","time spent":"603.511804ms","remote":"127.0.0.1:59034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T19:57:49.965333Z","caller":"traceutil/trace.go:171","msg":"trace[1131597767] transaction","detail":"{read_only:false; response_revision:26715; number_of_response:1; }","duration":"603.071553ms","start":"2026-10-15T19:57:49.362227Z","end":"2026-10-15T19:57:49.965299Z","steps":["trace[1131597767] 'process raft request' (duration: 602.791496ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:57:49.965523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:57:49.36221Z","time spent":"603.206266ms","remote":"127.0.0.1:59034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T19:57:49.965828Z","caller":"traceutil/trace.go:171","msg":"trace[909594278] transaction","detail":"{read_only:false; response_revision:26716; number_of_response:1; }","duration":"352.731117ms","start":"2026-10-15T19:57:49.613083Z","end":"2026-10-15T19:57:49.965814Z","steps":["trace[909594278] 'process raft request' (duration: 352.141743ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:57:49.965976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:57:49.61306Z","time spent":"352.806939ms","remote":"127.0.0.1:59034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T19:57:49.966059Z","caller":"traceutil/trace.go:171","msg":"trace[1881476630] transaction","detail":"{read_only:false; response_revision:26717; number_of_response:1; }","duration":"351.446504ms","start":"2026-10-15T19:57:49.614584Z","end":"2026-10-15T19:57:49.96603Z","steps":["trace[1881476630] 'process raft request' (duration: 351.184897ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:57:49.96628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:57:49.614558Z","time spent":"351.609708ms","remote":"127.0.0.1:59034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T19:57:49.966477Z","caller":"traceutil/trace.go:171","msg":"trace[590185737] transaction","detail":"{read_only:false; response_revision:26719; number_of_response:1; }","duration":"244.784273ms","start":"2026-10-15T19:57:49.72164Z","end":"2026-10-15T19:57:49.966424Z","steps":["trace[590185737] 'process raft request' (duration: 244.687ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:57:49.966604Z","caller":"traceutil/trace.go:171","msg":"trace[804101174] linearizableReadLoop","detail":"{readStateIndex:27598; appliedIndex:27595; }","duration":"599.06454ms","start":"2026-10-15T19:57:49.367492Z","end":"2026-10-15T19:57:49.966557Z","steps":["trace[804101174] 'read index received' (duration: 338.379451ms)","trace[804101174] 'applied index is now lower than readState.Index' (duration: 260.683689ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:57:49.966856Z","caller":"traceutil/trace.go:171","msg":"trace[1323146015] transaction","detail":"{read_only:false; response_revision:26718; number_of_response:1; }","duration":"337.649961ms","start":"2026-10-15T19:57:49.629181Z","end":"2026-10-15T19:57:49.966831Z","steps":["trace[1323146015] 'process raft request' (duration: 336.910582ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:57:49.966854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.190001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T19:57:49.966997Z","caller":"traceutil/trace.go:171","msg":"trace[1222576017] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26719; }","duration":"208.356054ms","start":"2026-10-15T19:57:49.75859Z","end":"2026-10-15T19:57:49.966946Z","steps":["trace[1222576017] 'agreement among raft nodes before linearized reading' (duration: 208.140769ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:57:49.96697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.475581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11793"} {"level":"info","ts":"2026-10-15T19:57:49.967783Z","caller":"traceutil/trace.go:171","msg":"trace[1011446555] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:26719; }","duration":"600.314103ms","start":"2026-10-15T19:57:49.367453Z","end":"2026-10-15T19:57:49.967767Z","steps":["trace[1011446555] 'agreement among raft nodes before linearized reading' (duration: 599.259365ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:57:49.967831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:57:49.367439Z","time spent":"600.377264ms","remote":"127.0.0.1:59234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":11816,"request content":"key:\"/registry/deployments/openstack/glance-api\" "} {"level":"warn","ts":"2026-10-15T19:57:49.968286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:57:49.629155Z","time spent":"337.792816ms","remote":"127.0.0.1:59034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T19:59:16.877936Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24770} {"level":"info","ts":"2026-10-15T19:59:17.032525Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24770,"took":"149.637152ms","hash":21238353,"current-db-size-bytes":57843712,"current-db-size":"58 MB","current-db-size-in-use-bytes":35885056,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-15T19:59:17.03261Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":21238353,"revision":24770,"compact-revision":21546} {"level":"info","ts":"2026-10-15T20:01:17.363295Z","caller":"traceutil/trace.go:171","msg":"trace[1676234441] transaction","detail":"{read_only:false; response_revision:28847; number_of_response:1; }","duration":"115.371274ms","start":"2026-10-15T20:01:17.247898Z","end":"2026-10-15T20:01:17.363269Z","steps":["trace[1676234441] 'process raft request' (duration: 112.623856ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T20:01:37.452069Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"9d6ea9653fb28c79","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T20:01:37.45858Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-15T20:01:37.458784Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-15T20:04:16.893223Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27563} {"level":"info","ts":"2026-10-15T20:04:17.031504Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27563,"took":"133.740576ms","hash":839901369,"current-db-size-bytes":57843712,"current-db-size":"58 MB","current-db-size-in-use-bytes":37335040,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-10-15T20:04:17.031802Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":839901369,"revision":27563,"compact-revision":24770} {"level":"info","ts":"2026-10-15T20:09:16.899725Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30297} {"level":"info","ts":"2026-10-15T20:09:17.044837Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30297,"took":"140.721466ms","hash":265123455,"current-db-size-bytes":57843712,"current-db-size":"58 MB","current-db-size-in-use-bytes":30724096,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-15T20:09:17.044906Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":265123455,"revision":30297,"compact-revision":27563} {"level":"info","ts":"2026-10-15T20:14:16.907009Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32586} {"level":"info","ts":"2026-10-15T20:14:17.06858Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32586,"took":"155.879389ms","hash":2373548356,"current-db-size-bytes":57843712,"current-db-size":"58 MB","current-db-size-in-use-bytes":30240768,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-15T20:14:17.068645Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2373548356,"revision":32586,"compact-revision":30297} {"level":"info","ts":"2026-10-15T20:19:16.914894Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34814} {"level":"info","ts":"2026-10-15T20:19:17.062934Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34814,"took":"143.690773ms","hash":1880272896,"current-db-size-bytes":57843712,"current-db-size":"58 MB","current-db-size-in-use-bytes":31612928,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-15T20:19:17.063008Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1880272896,"revision":34814,"compact-revision":32586} {"level":"info","ts":"2026-10-15T20:22:55.124257Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"9d6ea9653fb28c79","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T20:22:55.133767Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-15T20:22:55.133878Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-15T20:24:16.922913Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37110} {"level":"info","ts":"2026-10-15T20:24:17.057373Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37110,"took":"130.06847ms","hash":1234904778,"current-db-size-bytes":59121664,"current-db-size":"59 MB","current-db-size-in-use-bytes":32985088,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-15T20:24:17.057445Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1234904778,"revision":37110,"compact-revision":34814} {"level":"info","ts":"2026-10-15T20:29:16.930166Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39389} {"level":"info","ts":"2026-10-15T20:29:17.067238Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39389,"took":"133.131035ms","hash":1101349008,"current-db-size-bytes":59121664,"current-db-size":"59 MB","current-db-size-in-use-bytes":31166464,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-15T20:29:17.067314Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1101349008,"revision":39389,"compact-revision":37110}