{"level":"warn","ts":"2026-10-17T10:45:12.707508Z","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-17T10:45:12.707642Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.84: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.84:2380","--initial-cluster=instance=https://162.253.55.84:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.84:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.84: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-17T10:45:12.707741Z","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-17T10:45:12.707759Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.84:2380"]} {"level":"info","ts":"2026-10-17T10:45:12.707783Z","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-17T10:45:12.70861Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.84:2379"]} {"level":"info","ts":"2026-10-17T10:45:12.708723Z","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.84:2380"],"listen-peer-urls":["https://162.253.55.84:2380"],"advertise-client-urls":["https://162.253.55.84:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.84:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.84: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-17T10:45:12.71168Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.639092ms"} {"level":"info","ts":"2026-10-17T10:45:12.718812Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ca4c710a8d5349d7","cluster-id":"8e28a51debf1c8ab"} {"level":"info","ts":"2026-10-17T10:45:12.718895Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 switched to configuration voters=()"} {"level":"info","ts":"2026-10-17T10:45:12.718916Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 became follower at term 0"} {"level":"info","ts":"2026-10-17T10:45:12.718932Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ca4c710a8d5349d7 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-17T10:45:12.718943Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 became follower at term 1"} {"level":"info","ts":"2026-10-17T10:45:12.718988Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 switched to configuration voters=(14577150384026110423)"} {"level":"warn","ts":"2026-10-17T10:45:12.725959Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-17T10:45:12.730102Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-17T10:45:12.73223Z","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-17T10:45:12.73542Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ca4c710a8d5349d7","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-17T10:45:12.737073Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ca4c710a8d5349d7","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-17T10:45:12.737136Z","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-17T10:45:12.737263Z","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-17T10:45:12.737286Z","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-17T10:45:12.738315Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 switched to configuration voters=(14577150384026110423)"} {"level":"info","ts":"2026-10-17T10:45:12.738739Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8e28a51debf1c8ab","local-member-id":"ca4c710a8d5349d7","added-peer-id":"ca4c710a8d5349d7","added-peer-peer-urls":["https://162.253.55.84:2380"]} {"level":"info","ts":"2026-10-17T10:45:12.740486Z","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-17T10:45:12.740606Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.84:2380"} {"level":"info","ts":"2026-10-17T10:45:12.74071Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.84:2380"} {"level":"info","ts":"2026-10-17T10:45:12.740855Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ca4c710a8d5349d7","initial-advertise-peer-urls":["https://162.253.55.84:2380"],"listen-peer-urls":["https://162.253.55.84:2380"],"advertise-client-urls":["https://162.253.55.84:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.84:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-17T10:45:12.74092Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-17T10:45:13.720816Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 is starting a new election at term 1"} {"level":"info","ts":"2026-10-17T10:45:13.720879Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-17T10:45:13.720916Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 received MsgPreVoteResp from ca4c710a8d5349d7 at term 1"} {"level":"info","ts":"2026-10-17T10:45:13.720937Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 became candidate at term 2"} {"level":"info","ts":"2026-10-17T10:45:13.720948Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 received MsgVoteResp from ca4c710a8d5349d7 at term 2"} {"level":"info","ts":"2026-10-17T10:45:13.720963Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 became leader at term 2"} {"level":"info","ts":"2026-10-17T10:45:13.720976Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ca4c710a8d5349d7 elected leader ca4c710a8d5349d7 at term 2"} {"level":"info","ts":"2026-10-17T10:45:13.722134Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ca4c710a8d5349d7","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.84:2379]}","request-path":"/0/members/ca4c710a8d5349d7/attributes","cluster-id":"8e28a51debf1c8ab","publish-timeout":"7s"} {"level":"info","ts":"2026-10-17T10:45:13.722143Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-17T10:45:13.722161Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-17T10:45:13.722355Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-17T10:45:13.722543Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-17T10:45:13.722646Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-17T10:45:13.723631Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8e28a51debf1c8ab","local-member-id":"ca4c710a8d5349d7","cluster-version":"3.5"} {"level":"info","ts":"2026-10-17T10:45:13.723765Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-17T10:45:13.723807Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-17T10:45:13.727016Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-17T10:45:13.727092Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.84:2379"} {"level":"info","ts":"2026-10-17T10:46:04.34062Z","caller":"traceutil/trace.go:171","msg":"trace[1637593809] linearizableReadLoop","detail":"{readStateIndex:630; appliedIndex:629; }","duration":"152.841622ms","start":"2026-10-17T10:46:04.187747Z","end":"2026-10-17T10:46:04.340589Z","steps":["trace[1637593809] 'read index received' (duration: 152.020662ms)","trace[1637593809] 'applied index is now lower than readState.Index' (duration: 819.6µs)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:46:04.340732Z","caller":"traceutil/trace.go:171","msg":"trace[1197814584] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"200.619327ms","start":"2026-10-17T10:46:04.140054Z","end":"2026-10-17T10:46:04.340673Z","steps":["trace[1197814584] 'process raft request' (duration: 199.970571ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:46:04.340901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.086189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-2mjq4\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-10-17T10:46:04.34101Z","caller":"traceutil/trace.go:171","msg":"trace[23081674] range","detail":"{range_begin:/registry/pods/kube-system/cilium-2mjq4; range_end:; response_count:1; response_revision:606; }","duration":"153.334765ms","start":"2026-10-17T10:46:04.18766Z","end":"2026-10-17T10:46:04.340995Z","steps":["trace[23081674] 'agreement among raft nodes before linearized reading' (duration: 153.064688ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:46:04.972485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.870499ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-17T10:46:04.972593Z","caller":"traceutil/trace.go:171","msg":"trace[212595244] linearizableReadLoop","detail":"{readStateIndex:632; appliedIndex:631; }","duration":"153.704423ms","start":"2026-10-17T10:46:04.818871Z","end":"2026-10-17T10:46:04.972575Z","steps":["trace[212595244] 'read index received' (duration: 34.041µs)","trace[212595244] 'applied index is now lower than readState.Index' (duration: 153.668832ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:46:04.972672Z","caller":"traceutil/trace.go:171","msg":"trace[1295358152] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"539.426231ms","start":"2026-10-17T10:46:04.433235Z","end":"2026-10-17T10:46:04.972661Z","steps":["trace[1295358152] 'process raft request' (duration: 280.786033ms)","trace[1295358152] 'compare' (duration: 257.709086ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T10:46:04.97275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:46:04.433203Z","time spent":"539.522574ms","remote":"127.0.0.1:49996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-17T10:46:04.973229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.328888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-17T10:46:04.973327Z","caller":"traceutil/trace.go:171","msg":"trace[466783372] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:608; }","duration":"154.461781ms","start":"2026-10-17T10:46:04.818832Z","end":"2026-10-17T10:46:04.973294Z","steps":["trace[466783372] 'agreement among raft nodes before linearized reading' (duration: 154.160994ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:46:20.094958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.453259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T10:46:20.095047Z","caller":"traceutil/trace.go:171","msg":"trace[1734619950] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:770; }","duration":"151.597472ms","start":"2026-10-17T10:46:19.94343Z","end":"2026-10-17T10:46:20.095028Z","steps":["trace[1734619950] 'range keys from in-memory index tree' (duration: 151.396728ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:46:20.095136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.517141ms","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-17T10:46:20.095216Z","caller":"traceutil/trace.go:171","msg":"trace[1480980260] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:770; }","duration":"156.644344ms","start":"2026-10-17T10:46:19.938555Z","end":"2026-10-17T10:46:20.095199Z","steps":["trace[1480980260] 'range keys from in-memory index tree' (duration: 156.216834ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:46:20.208526Z","caller":"traceutil/trace.go:171","msg":"trace[1053305859] linearizableReadLoop","detail":"{readStateIndex:800; appliedIndex:799; }","duration":"134.854918ms","start":"2026-10-17T10:46:20.07365Z","end":"2026-10-17T10:46:20.208505Z","steps":["trace[1053305859] 'read index received' (duration: 134.734165ms)","trace[1053305859] 'applied index is now lower than readState.Index' (duration: 119.403µs)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:46:20.208555Z","caller":"traceutil/trace.go:171","msg":"trace[797140198] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"137.808299ms","start":"2026-10-17T10:46:20.070723Z","end":"2026-10-17T10:46:20.208531Z","steps":["trace[797140198] 'process raft request' (duration: 137.653806ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:46:20.208768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.113023ms","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-17T10:46:20.208823Z","caller":"traceutil/trace.go:171","msg":"trace[1719011710] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:771; }","duration":"135.218036ms","start":"2026-10-17T10:46:20.07359Z","end":"2026-10-17T10:46:20.208808Z","steps":["trace[1719011710] 'agreement among raft nodes before linearized reading' (duration: 135.026461ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:46:20.262481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.281267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T10:46:20.262501Z","caller":"traceutil/trace.go:171","msg":"trace[1736000311] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"163.50066ms","start":"2026-10-17T10:46:20.098966Z","end":"2026-10-17T10:46:20.262466Z","steps":["trace[1736000311] 'process raft request' (duration: 163.311416ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:46:20.262553Z","caller":"traceutil/trace.go:171","msg":"trace[858960372] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:772; }","duration":"166.39508ms","start":"2026-10-17T10:46:20.09614Z","end":"2026-10-17T10:46:20.262535Z","steps":["trace[858960372] 'agreement among raft nodes before linearized reading' (duration: 166.247466ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:46:20.262776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.06024ms","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-17T10:46:20.262839Z","caller":"traceutil/trace.go:171","msg":"trace[442642404] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:772; }","duration":"103.158862ms","start":"2026-10-17T10:46:20.159665Z","end":"2026-10-17T10:46:20.262824Z","steps":["trace[442642404] 'agreement among raft nodes before linearized reading' (duration: 102.928286ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:46:48.590174Z","caller":"traceutil/trace.go:171","msg":"trace[1856755432] linearizableReadLoop","detail":"{readStateIndex:1204; appliedIndex:1203; }","duration":"186.68122ms","start":"2026-10-17T10:46:48.403468Z","end":"2026-10-17T10:46:48.59015Z","steps":["trace[1856755432] 'read index received' (duration: 186.465794ms)","trace[1856755432] 'applied index is now lower than readState.Index' (duration: 214.206µs)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:46:48.590233Z","caller":"traceutil/trace.go:171","msg":"trace[2009079352] transaction","detail":"{read_only:false; response_revision:1169; number_of_response:1; }","duration":"188.735949ms","start":"2026-10-17T10:46:48.401472Z","end":"2026-10-17T10:46:48.590208Z","steps":["trace[2009079352] 'process raft request' (duration: 188.541544ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:46:48.590387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.906885ms","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-17T10:46:48.590425Z","caller":"traceutil/trace.go:171","msg":"trace[1425292573] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1169; }","duration":"186.990687ms","start":"2026-10-17T10:46:48.403425Z","end":"2026-10-17T10:46:48.590415Z","steps":["trace[1425292573] 'agreement among raft nodes before linearized reading' (duration: 186.849244ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:46:48.590497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.664401ms","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-17T10:46:48.590564Z","caller":"traceutil/trace.go:171","msg":"trace[2117906794] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1169; }","duration":"184.760683ms","start":"2026-10-17T10:46:48.405788Z","end":"2026-10-17T10:46:48.590549Z","steps":["trace[2117906794] 'agreement among raft nodes before linearized reading' (duration: 184.587709ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:47:10.320299Z","caller":"traceutil/trace.go:171","msg":"trace[507767208] transaction","detail":"{read_only:false; response_revision:1422; number_of_response:1; }","duration":"100.628994ms","start":"2026-10-17T10:47:10.219625Z","end":"2026-10-17T10:47:10.320254Z","steps":["trace[507767208] 'process raft request' (duration: 100.433169ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:47:37.240725Z","caller":"traceutil/trace.go:171","msg":"trace[334207323] linearizableReadLoop","detail":"{readStateIndex:1911; appliedIndex:1910; }","duration":"427.515181ms","start":"2026-10-17T10:47:36.813168Z","end":"2026-10-17T10:47:37.240683Z","steps":["trace[334207323] 'read index received' (duration: 427.258684ms)","trace[334207323] 'applied index is now lower than readState.Index' (duration: 255.657µs)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:47:37.240811Z","caller":"traceutil/trace.go:171","msg":"trace[1131496502] transaction","detail":"{read_only:false; response_revision:1838; number_of_response:1; }","duration":"542.334378ms","start":"2026-10-17T10:47:36.698451Z","end":"2026-10-17T10:47:37.240785Z","steps":["trace[1131496502] 'process raft request' (duration: 542.065482ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:47:37.240993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.40334ms","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-17T10:47:37.240993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.817929ms","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-17T10:47:37.241042Z","caller":"traceutil/trace.go:171","msg":"trace[569634443] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1838; }","duration":"425.483632ms","start":"2026-10-17T10:47:36.815544Z","end":"2026-10-17T10:47:37.241028Z","steps":["trace[569634443] 'agreement among raft nodes before linearized reading' (duration: 425.353689ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:47:37.241055Z","caller":"traceutil/trace.go:171","msg":"trace[1399404994] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1838; }","duration":"427.90718ms","start":"2026-10-17T10:47:36.813131Z","end":"2026-10-17T10:47:37.241038Z","steps":["trace[1399404994] 'agreement among raft nodes before linearized reading' (duration: 427.722126ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:47:37.241093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:47:36.813109Z","time spent":"427.973812ms","remote":"127.0.0.1:49896","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-17T10:47:37.241105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.978175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5732"} {"level":"warn","ts":"2026-10-17T10:47:37.241118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:47:36.698416Z","time spent":"542.454541ms","remote":"127.0.0.1:49812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11434,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T10:47:37.241153Z","caller":"traceutil/trace.go:171","msg":"trace[833885281] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1838; }","duration":"259.058046ms","start":"2026-10-17T10:47:36.982078Z","end":"2026-10-17T10:47:37.241136Z","steps":["trace[833885281] 'agreement among raft nodes before linearized reading' (duration: 258.944603ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:47:37.241253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.5647ms","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-17T10:47:37.241291Z","caller":"traceutil/trace.go:171","msg":"trace[772464022] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1838; }","duration":"417.630082ms","start":"2026-10-17T10:47:36.823648Z","end":"2026-10-17T10:47:37.241278Z","steps":["trace[772464022] 'agreement among raft nodes before linearized reading' (duration: 417.52507ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:47:37.241277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.076411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-17T10:47:37.241326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:47:36.823633Z","time spent":"417.684533ms","remote":"127.0.0.1:49896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-17T10:47:37.241365Z","caller":"traceutil/trace.go:171","msg":"trace[880274872] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1838; }","duration":"377.198174ms","start":"2026-10-17T10:47:36.864147Z","end":"2026-10-17T10:47:37.241345Z","steps":["trace[880274872] 'agreement among raft nodes before linearized reading' (duration: 377.03195ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:47:37.241404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:47:36.864136Z","time spent":"377.260526ms","remote":"127.0.0.1:49594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-17T10:47:37.241153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.431259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T10:47:37.241478Z","caller":"traceutil/trace.go:171","msg":"trace[603989802] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1838; }","duration":"298.792308ms","start":"2026-10-17T10:47:36.94267Z","end":"2026-10-17T10:47:37.241463Z","steps":["trace[603989802] 'agreement among raft nodes before linearized reading' (duration: 298.448339ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:47:37.241093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:47:36.815534Z","time spent":"425.547133ms","remote":"127.0.0.1:49896","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-17T10:47:37.361602Z","caller":"traceutil/trace.go:171","msg":"trace[2128012493] transaction","detail":"{read_only:false; response_revision:1839; number_of_response:1; }","duration":"116.207891ms","start":"2026-10-17T10:47:37.245351Z","end":"2026-10-17T10:47:37.361559Z","steps":["trace[2128012493] 'process raft request' (duration: 96.599796ms)","trace[2128012493] 'compare' (duration: 19.185575ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:47:37.361936Z","caller":"traceutil/trace.go:171","msg":"trace[129298176] transaction","detail":"{read_only:false; response_revision:1840; number_of_response:1; }","duration":"115.35347ms","start":"2026-10-17T10:47:37.246546Z","end":"2026-10-17T10:47:37.3619Z","steps":["trace[129298176] 'process raft request' (duration: 114.95056ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:47:37.362113Z","caller":"traceutil/trace.go:171","msg":"trace[564280600] transaction","detail":"{read_only:false; response_revision:1841; number_of_response:1; }","duration":"114.832938ms","start":"2026-10-17T10:47:37.247262Z","end":"2026-10-17T10:47:37.362095Z","steps":["trace[564280600] 'process raft request' (duration: 114.51255ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:47:37.362391Z","caller":"traceutil/trace.go:171","msg":"trace[788359357] transaction","detail":"{read_only:false; response_revision:1842; number_of_response:1; }","duration":"114.723506ms","start":"2026-10-17T10:47:37.247651Z","end":"2026-10-17T10:47:37.362374Z","steps":["trace[788359357] 'process raft request' (duration: 114.341466ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:47:37.362529Z","caller":"traceutil/trace.go:171","msg":"trace[677144943] transaction","detail":"{read_only:false; response_revision:1843; number_of_response:1; }","duration":"114.49625ms","start":"2026-10-17T10:47:37.248018Z","end":"2026-10-17T10:47:37.362514Z","steps":["trace[677144943] 'process raft request' (duration: 114.278865ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:47:37.362788Z","caller":"traceutil/trace.go:171","msg":"trace[1401723173] transaction","detail":"{read_only:false; response_revision:1845; number_of_response:1; }","duration":"113.247739ms","start":"2026-10-17T10:47:37.24952Z","end":"2026-10-17T10:47:37.362768Z","steps":["trace[1401723173] 'process raft request' (duration: 113.137666ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:47:37.362819Z","caller":"traceutil/trace.go:171","msg":"trace[1196567153] transaction","detail":"{read_only:false; response_revision:1844; number_of_response:1; }","duration":"114.219263ms","start":"2026-10-17T10:47:37.248578Z","end":"2026-10-17T10:47:37.362797Z","steps":["trace[1196567153] 'process raft request' (duration: 113.873044ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:47:37.438588Z","caller":"traceutil/trace.go:171","msg":"trace[1573425472] transaction","detail":"{read_only:false; response_revision:1847; number_of_response:1; }","duration":"168.344422ms","start":"2026-10-17T10:47:37.270217Z","end":"2026-10-17T10:47:37.438561Z","steps":["trace[1573425472] 'process raft request' (duration: 168.215769ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:47:37.438711Z","caller":"traceutil/trace.go:171","msg":"trace[943969910] transaction","detail":"{read_only:false; response_revision:1846; number_of_response:1; }","duration":"174.805608ms","start":"2026-10-17T10:47:37.263867Z","end":"2026-10-17T10:47:37.438673Z","steps":["trace[943969910] 'process raft request' (duration: 174.400268ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:47:37.969964Z","caller":"traceutil/trace.go:171","msg":"trace[124864007] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"156.628259ms","start":"2026-10-17T10:47:37.813307Z","end":"2026-10-17T10:47:37.969935Z","steps":["trace[124864007] 'process raft request' (duration: 150.08127ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:47:37.974268Z","caller":"traceutil/trace.go:171","msg":"trace[683979213] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"139.067024ms","start":"2026-10-17T10:47:37.835182Z","end":"2026-10-17T10:47:37.974249Z","steps":["trace[683979213] 'process raft request' (duration: 138.91332ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:48:25.863501Z","caller":"traceutil/trace.go:171","msg":"trace[1182465896] linearizableReadLoop","detail":"{readStateIndex:2243; appliedIndex:2241; }","duration":"142.307107ms","start":"2026-10-17T10:48:25.721168Z","end":"2026-10-17T10:48:25.863475Z","steps":["trace[1182465896] 'read index received' (duration: 103.674332ms)","trace[1182465896] 'applied index is now lower than readState.Index' (duration: 38.631715ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:48:25.86356Z","caller":"traceutil/trace.go:171","msg":"trace[1572424966] transaction","detail":"{read_only:false; response_revision:2157; number_of_response:1; }","duration":"188.207646ms","start":"2026-10-17T10:48:25.675301Z","end":"2026-10-17T10:48:25.863509Z","steps":["trace[1572424966] 'process raft request' (duration: 149.439968ms)","trace[1572424966] 'compare' (duration: 38.567083ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:48:25.863669Z","caller":"traceutil/trace.go:171","msg":"trace[1028887638] transaction","detail":"{read_only:false; response_revision:2158; number_of_response:1; }","duration":"188.317739ms","start":"2026-10-17T10:48:25.675337Z","end":"2026-10-17T10:48:25.863655Z","steps":["trace[1028887638] 'process raft request' (duration: 188.099893ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:48:25.863944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.759988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-17T10:48:25.864011Z","caller":"traceutil/trace.go:171","msg":"trace[976170880] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2158; }","duration":"142.85367ms","start":"2026-10-17T10:48:25.721133Z","end":"2026-10-17T10:48:25.863986Z","steps":["trace[976170880] 'agreement among raft nodes before linearized reading' (duration: 142.668746ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:48:25.864122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.466859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-17T10:48:25.864198Z","caller":"traceutil/trace.go:171","msg":"trace[193659466] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2158; }","duration":"101.633723ms","start":"2026-10-17T10:48:25.762548Z","end":"2026-10-17T10:48:25.864182Z","steps":["trace[193659466] 'agreement among raft nodes before linearized reading' (duration: 101.115341ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:48:26.124081Z","caller":"traceutil/trace.go:171","msg":"trace[1776177425] transaction","detail":"{read_only:false; response_revision:2159; number_of_response:1; }","duration":"255.60069ms","start":"2026-10-17T10:48:25.868447Z","end":"2026-10-17T10:48:26.124048Z","steps":["trace[1776177425] 'process raft request' (duration: 180.871751ms)","trace[1776177425] 'compare' (duration: 74.607466ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:48:26.141328Z","caller":"traceutil/trace.go:171","msg":"trace[278280863] linearizableReadLoop","detail":"{readStateIndex:2245; appliedIndex:2243; }","duration":"198.78942ms","start":"2026-10-17T10:48:25.942513Z","end":"2026-10-17T10:48:26.141302Z","steps":["trace[278280863] 'read index received' (duration: 106.92484ms)","trace[278280863] 'applied index is now lower than readState.Index' (duration: 91.8634ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:48:26.14138Z","caller":"traceutil/trace.go:171","msg":"trace[105731323] transaction","detail":"{read_only:false; response_revision:2160; number_of_response:1; }","duration":"272.625297ms","start":"2026-10-17T10:48:25.868728Z","end":"2026-10-17T10:48:26.141354Z","steps":["trace[105731323] 'process raft request' (duration: 272.433373ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:48:26.141469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.931173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T10:48:26.141516Z","caller":"traceutil/trace.go:171","msg":"trace[703948313] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2160; }","duration":"199.024155ms","start":"2026-10-17T10:48:25.94248Z","end":"2026-10-17T10:48:26.141504Z","steps":["trace[703948313] 'agreement among raft nodes before linearized reading' (duration: 198.935113ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:48:26.142442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.343549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-10-17T10:48:26.142502Z","caller":"traceutil/trace.go:171","msg":"trace[1445293030] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2162; }","duration":"127.462271ms","start":"2026-10-17T10:48:26.015027Z","end":"2026-10-17T10:48:26.142489Z","steps":["trace[1445293030] 'agreement among raft nodes before linearized reading' (duration: 127.260837ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:54:25.128063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.552312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T10:54:25.128151Z","caller":"traceutil/trace.go:171","msg":"trace[866643752] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5196; }","duration":"181.690605ms","start":"2026-10-17T10:54:24.946443Z","end":"2026-10-17T10:54:25.128133Z","steps":["trace[866643752] 'range keys from in-memory index tree' (duration: 181.456799ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:54:25.128212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.953281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-17T10:54:25.12831Z","caller":"traceutil/trace.go:171","msg":"trace[25559096] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:5196; }","duration":"198.120696ms","start":"2026-10-17T10:54:24.930167Z","end":"2026-10-17T10:54:25.128288Z","steps":["trace[25559096] 'count revisions from in-memory index tree' (duration: 197.87963ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:54:40.149591Z","caller":"traceutil/trace.go:171","msg":"trace[530554213] transaction","detail":"{read_only:false; response_revision:5467; number_of_response:1; }","duration":"139.503433ms","start":"2026-10-17T10:54:40.010056Z","end":"2026-10-17T10:54:40.149559Z","steps":["trace[530554213] 'process raft request' (duration: 139.272137ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:54:40.149686Z","caller":"traceutil/trace.go:171","msg":"trace[756859484] transaction","detail":"{read_only:false; response_revision:5468; number_of_response:1; }","duration":"129.206678ms","start":"2026-10-17T10:54:40.020449Z","end":"2026-10-17T10:54:40.149656Z","steps":["trace[756859484] 'process raft request' (duration: 129.038964ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:54:40.264145Z","caller":"traceutil/trace.go:171","msg":"trace[574735200] transaction","detail":"{read_only:false; response_revision:5470; number_of_response:1; }","duration":"103.922497ms","start":"2026-10-17T10:54:40.160194Z","end":"2026-10-17T10:54:40.264117Z","steps":["trace[574735200] 'process raft request' (duration: 73.226228ms)","trace[574735200] 'compare' (duration: 30.509215ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:54:40.264173Z","caller":"traceutil/trace.go:171","msg":"trace[703077811] transaction","detail":"{read_only:false; response_revision:5473; number_of_response:1; }","duration":"102.32442ms","start":"2026-10-17T10:54:40.161824Z","end":"2026-10-17T10:54:40.264149Z","steps":["trace[703077811] 'process raft request' (duration: 102.277499ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:54:40.2645Z","caller":"traceutil/trace.go:171","msg":"trace[1409534008] transaction","detail":"{read_only:false; response_revision:5471; number_of_response:1; }","duration":"104.198284ms","start":"2026-10-17T10:54:40.16029Z","end":"2026-10-17T10:54:40.264489Z","steps":["trace[1409534008] 'process raft request' (duration: 103.727343ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:54:40.264608Z","caller":"traceutil/trace.go:171","msg":"trace[1276341811] transaction","detail":"{read_only:false; response_revision:5472; number_of_response:1; }","duration":"102.780231ms","start":"2026-10-17T10:54:40.161817Z","end":"2026-10-17T10:54:40.264597Z","steps":["trace[1276341811] 'process raft request' (duration: 102.255038ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:54:47.756649Z","caller":"traceutil/trace.go:171","msg":"trace[628326790] transaction","detail":"{read_only:false; response_revision:5627; number_of_response:1; }","duration":"232.44116ms","start":"2026-10-17T10:54:47.524185Z","end":"2026-10-17T10:54:47.756626Z","steps":["trace[628326790] 'process raft request' (duration: 232.301987ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:54:47.875532Z","caller":"traceutil/trace.go:171","msg":"trace[92581937] linearizableReadLoop","detail":"{readStateIndex:5843; appliedIndex:5840; }","duration":"251.610466ms","start":"2026-10-17T10:54:47.6239Z","end":"2026-10-17T10:54:47.87551Z","steps":["trace[92581937] 'read index received' (duration: 132.646551ms)","trace[92581937] 'applied index is now lower than readState.Index' (duration: 118.962855ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:54:47.875561Z","caller":"traceutil/trace.go:171","msg":"trace[399856134] transaction","detail":"{read_only:false; response_revision:5629; number_of_response:1; }","duration":"264.587393ms","start":"2026-10-17T10:54:47.610946Z","end":"2026-10-17T10:54:47.875533Z","steps":["trace[399856134] 'process raft request' (duration: 264.507191ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:54:47.875553Z","caller":"traceutil/trace.go:171","msg":"trace[1496999725] transaction","detail":"{read_only:false; response_revision:5628; number_of_response:1; }","duration":"349.687295ms","start":"2026-10-17T10:54:47.525829Z","end":"2026-10-17T10:54:47.875516Z","steps":["trace[1496999725] 'process raft request' (duration: 275.709228ms)","trace[1496999725] 'compare' (duration: 73.785682ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T10:54:47.875726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:54:47.525804Z","time spent":"349.833108ms","remote":"127.0.0.1:49896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T10:54:47.875801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.869031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-17T10:54:47.87588Z","caller":"traceutil/trace.go:171","msg":"trace[1193878327] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5629; }","duration":"251.978354ms","start":"2026-10-17T10:54:47.623884Z","end":"2026-10-17T10:54:47.875862Z","steps":["trace[1193878327] 'agreement among raft nodes before linearized reading' (duration: 251.739568ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:54:47.997337Z","caller":"traceutil/trace.go:171","msg":"trace[1239451756] transaction","detail":"{read_only:false; response_revision:5631; number_of_response:1; }","duration":"117.087301ms","start":"2026-10-17T10:54:47.880221Z","end":"2026-10-17T10:54:47.997308Z","steps":["trace[1239451756] 'process raft request' (duration: 116.584839ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:54:47.997537Z","caller":"traceutil/trace.go:171","msg":"trace[1710109684] transaction","detail":"{read_only:false; response_revision:5632; number_of_response:1; }","duration":"117.237814ms","start":"2026-10-17T10:54:47.880279Z","end":"2026-10-17T10:54:47.997517Z","steps":["trace[1710109684] 'process raft request' (duration: 116.943207ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:55:13.754486Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2943} {"level":"info","ts":"2026-10-17T10:55:13.836197Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2943,"took":"79.782555ms","hash":3465810989,"current-db-size-bytes":55615488,"current-db-size":"56 MB","current-db-size-in-use-bytes":32301056,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-17T10:55:13.836332Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3465810989,"revision":2943,"compact-revision":-1} {"level":"info","ts":"2026-10-17T10:55:39.50086Z","caller":"traceutil/trace.go:171","msg":"trace[1572291555] transaction","detail":"{read_only:false; response_revision:6127; number_of_response:1; }","duration":"111.605515ms","start":"2026-10-17T10:55:39.389226Z","end":"2026-10-17T10:55:39.500832Z","steps":["trace[1572291555] 'process raft request' (duration: 111.341689ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:00:13.762333Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5888} {"level":"info","ts":"2026-10-17T11:00:13.854893Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5888,"took":"90.202012ms","hash":333786199,"current-db-size-bytes":55615488,"current-db-size":"56 MB","current-db-size-in-use-bytes":26525696,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-17T11:00:13.854982Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":333786199,"revision":5888,"compact-revision":2943} {"level":"info","ts":"2026-10-17T11:00:52.122689Z","caller":"traceutil/trace.go:171","msg":"trace[2004560875] linearizableReadLoop","detail":"{readStateIndex:8744; appliedIndex:8743; }","duration":"189.761687ms","start":"2026-10-17T11:00:51.932898Z","end":"2026-10-17T11:00:52.122659Z","steps":["trace[2004560875] 'read index received' (duration: 189.530242ms)","trace[2004560875] 'applied index is now lower than readState.Index' (duration: 230.175µs)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:00:52.122861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.759079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T11:00:52.122892Z","caller":"traceutil/trace.go:171","msg":"trace[668338942] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8443; }","duration":"179.849481ms","start":"2026-10-17T11:00:51.943033Z","end":"2026-10-17T11:00:52.122883Z","steps":["trace[668338942] 'agreement among raft nodes before linearized reading' (duration: 179.78343ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:00:52.122941Z","caller":"traceutil/trace.go:171","msg":"trace[1457906101] transaction","detail":"{read_only:false; response_revision:8443; number_of_response:1; }","duration":"205.619804ms","start":"2026-10-17T11:00:51.917268Z","end":"2026-10-17T11:00:52.122888Z","steps":["trace[1457906101] 'process raft request' (duration: 205.217634ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:00:52.122994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.072254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-10-17T11:00:52.123062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.622168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-17T11:00:52.123071Z","caller":"traceutil/trace.go:171","msg":"trace[1876529650] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8443; }","duration":"190.177426ms","start":"2026-10-17T11:00:51.932873Z","end":"2026-10-17T11:00:52.123051Z","steps":["trace[1876529650] 'agreement among raft nodes before linearized reading' (duration: 189.949861ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:00:52.12308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.249169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-17T11:00:52.123115Z","caller":"traceutil/trace.go:171","msg":"trace[1188941652] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8443; }","duration":"137.67734ms","start":"2026-10-17T11:00:51.985423Z","end":"2026-10-17T11:00:52.1231Z","steps":["trace[1188941652] 'agreement among raft nodes before linearized reading' (duration: 137.514366ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:00:52.123133Z","caller":"traceutil/trace.go:171","msg":"trace[1494426613] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8443; }","duration":"134.30306ms","start":"2026-10-17T11:00:51.988815Z","end":"2026-10-17T11:00:52.123118Z","steps":["trace[1494426613] 'agreement among raft nodes before linearized reading' (duration: 134.129826ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:00:52.123178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.41341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T11:00:52.123546Z","caller":"traceutil/trace.go:171","msg":"trace[100206607] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:8443; }","duration":"120.855821ms","start":"2026-10-17T11:00:52.002664Z","end":"2026-10-17T11:00:52.12352Z","steps":["trace[100206607] 'agreement among raft nodes before linearized reading' (duration: 120.232876ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:00:52.261471Z","caller":"traceutil/trace.go:171","msg":"trace[534040176] transaction","detail":"{read_only:false; response_revision:8444; number_of_response:1; }","duration":"134.950525ms","start":"2026-10-17T11:00:52.12649Z","end":"2026-10-17T11:00:52.261441Z","steps":["trace[534040176] 'process raft request' (duration: 112.758838ms)","trace[534040176] 'compare' (duration: 22.003493ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:00:52.261742Z","caller":"traceutil/trace.go:171","msg":"trace[1558770007] transaction","detail":"{read_only:false; response_revision:8445; number_of_response:1; }","duration":"134.398452ms","start":"2026-10-17T11:00:52.127329Z","end":"2026-10-17T11:00:52.261727Z","steps":["trace[1558770007] 'process raft request' (duration: 134.044884ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:00:52.261843Z","caller":"traceutil/trace.go:171","msg":"trace[1813885931] transaction","detail":"{read_only:false; response_revision:8446; number_of_response:1; }","duration":"132.64805ms","start":"2026-10-17T11:00:52.129183Z","end":"2026-10-17T11:00:52.261831Z","steps":["trace[1813885931] 'process raft request' (duration: 132.22926ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:01:03.445718Z","caller":"traceutil/trace.go:171","msg":"trace[1521040900] linearizableReadLoop","detail":"{readStateIndex:8808; appliedIndex:8807; }","duration":"210.792496ms","start":"2026-10-17T11:01:03.234896Z","end":"2026-10-17T11:01:03.445689Z","steps":["trace[1521040900] 'read index received' (duration: 210.706784ms)","trace[1521040900] 'applied index is now lower than readState.Index' (duration: 84.232µs)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:01:03.445763Z","caller":"traceutil/trace.go:171","msg":"trace[96406290] transaction","detail":"{read_only:false; response_revision:8503; number_of_response:1; }","duration":"252.245721ms","start":"2026-10-17T11:01:03.193484Z","end":"2026-10-17T11:01:03.445729Z","steps":["trace[96406290] 'process raft request' (duration: 252.025325ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:01:03.445914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.979591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T11:01:03.445996Z","caller":"traceutil/trace.go:171","msg":"trace[1672783985] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:8503; }","duration":"211.121484ms","start":"2026-10-17T11:01:03.234844Z","end":"2026-10-17T11:01:03.445966Z","steps":["trace[1672783985] 'agreement among raft nodes before linearized reading' (duration: 210.964711ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:01:03.488829Z","caller":"traceutil/trace.go:171","msg":"trace[1402580693] transaction","detail":"{read_only:false; response_revision:8505; number_of_response:1; }","duration":"190.884154ms","start":"2026-10-17T11:01:03.297919Z","end":"2026-10-17T11:01:03.488804Z","steps":["trace[1402580693] 'process raft request' (duration: 190.817732ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:01:03.488854Z","caller":"traceutil/trace.go:171","msg":"trace[281853715] transaction","detail":"{read_only:false; response_revision:8504; number_of_response:1; }","duration":"193.516606ms","start":"2026-10-17T11:01:03.295305Z","end":"2026-10-17T11:01:03.488821Z","steps":["trace[281853715] 'process raft request' (duration: 188.171299ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:01:04.546046Z","caller":"traceutil/trace.go:171","msg":"trace[491087049] linearizableReadLoop","detail":"{readStateIndex:8814; appliedIndex:8813; }","duration":"142.350411ms","start":"2026-10-17T11:01:04.403671Z","end":"2026-10-17T11:01:04.546022Z","steps":["trace[491087049] 'read index received' (duration: 129.308711ms)","trace[491087049] 'applied index is now lower than readState.Index' (duration: 13.04057ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:01:04.546096Z","caller":"traceutil/trace.go:171","msg":"trace[698826919] transaction","detail":"{read_only:false; response_revision:8509; number_of_response:1; }","duration":"167.667172ms","start":"2026-10-17T11:01:04.378396Z","end":"2026-10-17T11:01:04.546063Z","steps":["trace[698826919] 'process raft request' (duration: 154.636242ms)","trace[698826919] 'compare' (duration: 12.880536ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:01:04.546284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.897762ms","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":"warn","ts":"2026-10-17T11:01:04.546234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.928433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-17T11:01:04.546322Z","caller":"traceutil/trace.go:171","msg":"trace[1379347268] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8509; }","duration":"163.985135ms","start":"2026-10-17T11:01:04.382326Z","end":"2026-10-17T11:01:04.546311Z","steps":["trace[1379347268] 'agreement among raft nodes before linearized reading' (duration: 163.823611ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:01:04.546363Z","caller":"traceutil/trace.go:171","msg":"trace[308993372] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:8509; }","duration":"156.100927ms","start":"2026-10-17T11:01:04.390236Z","end":"2026-10-17T11:01:04.546337Z","steps":["trace[308993372] 'agreement among raft nodes before linearized reading' (duration: 155.821981ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:01:04.547303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.221267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:225003"} {"level":"info","ts":"2026-10-17T11:01:04.547377Z","caller":"traceutil/trace.go:171","msg":"trace[41218416] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:8509; }","duration":"139.309219ms","start":"2026-10-17T11:01:04.408051Z","end":"2026-10-17T11:01:04.54736Z","steps":["trace[41218416] 'agreement among raft nodes before linearized reading' (duration: 138.568461ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:01:40.850196Z","caller":"traceutil/trace.go:171","msg":"trace[453643428] transaction","detail":"{read_only:false; response_revision:8759; number_of_response:1; }","duration":"109.973862ms","start":"2026-10-17T11:01:40.740198Z","end":"2026-10-17T11:01:40.850172Z","steps":["trace[453643428] 'process raft request' (duration: 109.837128ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:01:47.943525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.161914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-vl4n6\" ","response":"range_response_count:1 size:2800"} {"level":"info","ts":"2026-10-17T11:01:47.943654Z","caller":"traceutil/trace.go:171","msg":"trace[1427166780] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-vl4n6; range_end:; response_count:1; response_revision:8837; }","duration":"152.350488ms","start":"2026-10-17T11:01:47.791282Z","end":"2026-10-17T11:01:47.943632Z","steps":["trace[1427166780] 'agreement among raft nodes before linearized reading' (duration: 80.43099ms)","trace[1427166780] 'range keys from in-memory index tree' (duration: 71.605741ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:01:48.617191Z","caller":"traceutil/trace.go:171","msg":"trace[1513643147] transaction","detail":"{read_only:false; response_revision:8839; number_of_response:1; }","duration":"123.085334ms","start":"2026-10-17T11:01:48.494073Z","end":"2026-10-17T11:01:48.617159Z","steps":["trace[1513643147] 'process raft request' (duration: 122.92404ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:01:48.891836Z","caller":"traceutil/trace.go:171","msg":"trace[1952094542] transaction","detail":"{read_only:false; response_revision:8843; number_of_response:1; }","duration":"142.905284ms","start":"2026-10-17T11:01:48.748854Z","end":"2026-10-17T11:01:48.891759Z","steps":["trace[1952094542] 'process raft request' (duration: 142.669109ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:01:48.891953Z","caller":"traceutil/trace.go:171","msg":"trace[1381949257] transaction","detail":"{read_only:false; response_revision:8842; number_of_response:1; }","duration":"143.828256ms","start":"2026-10-17T11:01:48.748095Z","end":"2026-10-17T11:01:48.891923Z","steps":["trace[1381949257] 'process raft request' (duration: 100.880276ms)","trace[1381949257] 'compare' (duration: 42.397837ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:02:07.759912Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002481.wal"} {"level":"info","ts":"2026-10-17T11:03:13.423211Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ca4c710a8d5349d7","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-17T11:03:13.430086Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-17T11:03:13.430228Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-17T11:05:13.769765Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8064} {"level":"info","ts":"2026-10-17T11:05:13.841395Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8064,"took":"69.18796ms","hash":2822544165,"current-db-size-bytes":55615488,"current-db-size":"56 MB","current-db-size-in-use-bytes":28729344,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-17T11:05:13.841471Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2822544165,"revision":8064,"compact-revision":5888} {"level":"info","ts":"2026-10-17T11:06:28.220876Z","caller":"traceutil/trace.go:171","msg":"trace[183224938] transaction","detail":"{read_only:false; response_revision:11319; number_of_response:1; }","duration":"118.522569ms","start":"2026-10-17T11:06:28.10233Z","end":"2026-10-17T11:06:28.220853Z","steps":["trace[183224938] 'process raft request' (duration: 118.363225ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:06:28.220887Z","caller":"traceutil/trace.go:171","msg":"trace[527932639] linearizableReadLoop","detail":"{readStateIndex:11713; appliedIndex:11712; }","duration":"106.851093ms","start":"2026-10-17T11:06:28.113998Z","end":"2026-10-17T11:06:28.220849Z","steps":["trace[527932639] 'read index received' (duration: 106.830722ms)","trace[527932639] 'applied index is now lower than readState.Index' (duration: 17.17µs)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:06:28.221139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.026803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-17T11:06:28.221148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.155096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T11:06:28.221203Z","caller":"traceutil/trace.go:171","msg":"trace[1595625185] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:11319; }","duration":"106.139305ms","start":"2026-10-17T11:06:28.115037Z","end":"2026-10-17T11:06:28.221176Z","steps":["trace[1595625185] 'agreement among raft nodes before linearized reading' (duration: 106.019952ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:06:28.221218Z","caller":"traceutil/trace.go:171","msg":"trace[1924834788] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11319; }","duration":"106.240278ms","start":"2026-10-17T11:06:28.114961Z","end":"2026-10-17T11:06:28.221201Z","steps":["trace[1924834788] 'agreement among raft nodes before linearized reading' (duration: 106.077094ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:06:28.221261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.205487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-17T11:06:28.221279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.285562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T11:06:28.22131Z","caller":"traceutil/trace.go:171","msg":"trace[226208494] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11319; }","duration":"106.297619ms","start":"2026-10-17T11:06:28.114999Z","end":"2026-10-17T11:06:28.221297Z","steps":["trace[226208494] 'agreement among raft nodes before linearized reading' (duration: 106.221247ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:06:28.221324Z","caller":"traceutil/trace.go:171","msg":"trace[1125163728] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:11319; }","duration":"107.380144ms","start":"2026-10-17T11:06:28.11393Z","end":"2026-10-17T11:06:28.22131Z","steps":["trace[1125163728] 'agreement among raft nodes before linearized reading' (duration: 107.317973ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:06:44.588159Z","caller":"traceutil/trace.go:171","msg":"trace[707211607] transaction","detail":"{read_only:false; response_revision:11411; number_of_response:1; }","duration":"111.365849ms","start":"2026-10-17T11:06:44.476761Z","end":"2026-10-17T11:06:44.588127Z","steps":["trace[707211607] 'process raft request' (duration: 88.994699ms)","trace[707211607] 'compare' (duration: 21.96323ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:06:44.588247Z","caller":"traceutil/trace.go:171","msg":"trace[799888529] transaction","detail":"{read_only:false; response_revision:11412; number_of_response:1; }","duration":"111.030191ms","start":"2026-10-17T11:06:44.477191Z","end":"2026-10-17T11:06:44.588222Z","steps":["trace[799888529] 'process raft request' (duration: 110.875448ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:10:13.783965Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10500} {"level":"info","ts":"2026-10-17T11:10:13.868587Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10500,"took":"82.43452ms","hash":4272014934,"current-db-size-bytes":55615488,"current-db-size":"56 MB","current-db-size-in-use-bytes":27312128,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-17T11:10:13.868655Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4272014934,"revision":10500,"compact-revision":8064} {"level":"info","ts":"2026-10-17T11:12:36.217626Z","caller":"traceutil/trace.go:171","msg":"trace[968909647] transaction","detail":"{read_only:false; response_revision:13804; number_of_response:1; }","duration":"133.844247ms","start":"2026-10-17T11:12:36.083756Z","end":"2026-10-17T11:12:36.217601Z","steps":["trace[968909647] 'process raft request' (duration: 133.707004ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:12:36.218894Z","caller":"traceutil/trace.go:171","msg":"trace[1840684105] transaction","detail":"{read_only:false; response_revision:13805; number_of_response:1; }","duration":"125.610012ms","start":"2026-10-17T11:12:36.093259Z","end":"2026-10-17T11:12:36.218869Z","steps":["trace[1840684105] 'process raft request' (duration: 124.952167ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:13:15.163067Z","caller":"traceutil/trace.go:171","msg":"trace[1598182338] transaction","detail":"{read_only:false; response_revision:14032; number_of_response:1; }","duration":"440.333167ms","start":"2026-10-17T11:13:14.722704Z","end":"2026-10-17T11:13:15.163037Z","steps":["trace[1598182338] 'process raft request' (duration: 440.175223ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:13:15.163282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:13:14.722669Z","time spent":"440.491871ms","remote":"127.0.0.1:49896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T11:13:15.171413Z","caller":"traceutil/trace.go:171","msg":"trace[755921363] transaction","detail":"{read_only:false; response_revision:14033; number_of_response:1; }","duration":"447.976529ms","start":"2026-10-17T11:13:14.723416Z","end":"2026-10-17T11:13:15.171393Z","steps":["trace[755921363] 'process raft request' (duration: 447.825365ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:13:15.171514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:13:14.723393Z","time spent":"448.073871ms","remote":"127.0.0.1:49896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T11:13:15.171606Z","caller":"traceutil/trace.go:171","msg":"trace[1426698783] transaction","detail":"{read_only:false; response_revision:14034; number_of_response:1; }","duration":"130.087669ms","start":"2026-10-17T11:13:15.041482Z","end":"2026-10-17T11:13:15.17157Z","steps":["trace[1426698783] 'process raft request' (duration: 129.845723ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:13:15.171547Z","caller":"traceutil/trace.go:171","msg":"trace[413438898] linearizableReadLoop","detail":"{readStateIndex:14522; appliedIndex:14520; }","duration":"446.716619ms","start":"2026-10-17T11:13:14.724804Z","end":"2026-10-17T11:13:15.171521Z","steps":["trace[413438898] 'read index received' (duration: 438.172146ms)","trace[413438898] 'applied index is now lower than readState.Index' (duration: 8.542573ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:13:15.171869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.031146ms","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-17T11:13:15.171948Z","caller":"traceutil/trace.go:171","msg":"trace[351519470] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14034; }","duration":"447.148899ms","start":"2026-10-17T11:13:14.724779Z","end":"2026-10-17T11:13:15.171928Z","steps":["trace[351519470] 'agreement among raft nodes before linearized reading' (duration: 446.897693ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:13:15.172002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:13:14.724769Z","time spent":"447.22347ms","remote":"127.0.0.1:49896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-17T11:13:15.17299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.780015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-17T11:13:15.173075Z","caller":"traceutil/trace.go:171","msg":"trace[1428116540] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:14034; }","duration":"183.922179ms","start":"2026-10-17T11:13:14.989131Z","end":"2026-10-17T11:13:15.173054Z","steps":["trace[1428116540] 'agreement among raft nodes before linearized reading' (duration: 182.884144ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:13:15.173434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.434671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-sxmv2\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-10-17T11:13:15.173499Z","caller":"traceutil/trace.go:171","msg":"trace[1918735653] range","detail":"{range_begin:/registry/pods/openstack/keepalived-sxmv2; range_end:; response_count:1; response_revision:14034; }","duration":"355.538154ms","start":"2026-10-17T11:13:14.817946Z","end":"2026-10-17T11:13:15.173484Z","steps":["trace[1918735653] 'agreement among raft nodes before linearized reading' (duration: 354.030788ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:13:15.173541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:13:14.817926Z","time spent":"355.601535ms","remote":"127.0.0.1:49812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6551,"request content":"key:\"/registry/pods/openstack/keepalived-sxmv2\" "} {"level":"info","ts":"2026-10-17T11:13:15.31266Z","caller":"traceutil/trace.go:171","msg":"trace[888966601] linearizableReadLoop","detail":"{readStateIndex:14524; appliedIndex:14523; }","duration":"134.611837ms","start":"2026-10-17T11:13:15.177835Z","end":"2026-10-17T11:13:15.312447Z","steps":["trace[888966601] 'read index received' (duration: 126.170786ms)","trace[888966601] 'applied index is now lower than readState.Index' (duration: 8.439711ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:13:15.312733Z","caller":"traceutil/trace.go:171","msg":"trace[277882144] transaction","detail":"{read_only:false; response_revision:14035; number_of_response:1; }","duration":"135.769844ms","start":"2026-10-17T11:13:15.176939Z","end":"2026-10-17T11:13:15.312709Z","steps":["trace[277882144] 'process raft request' (duration: 127.052807ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:13:15.312918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.554685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-10-17T11:13:15.312959Z","caller":"traceutil/trace.go:171","msg":"trace[518376607] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:14035; }","duration":"110.638958ms","start":"2026-10-17T11:13:15.202309Z","end":"2026-10-17T11:13:15.312947Z","steps":["trace[518376607] 'agreement among raft nodes before linearized reading' (duration: 110.494704ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:13:15.313457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.59441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:63 size:597467"} {"level":"info","ts":"2026-10-17T11:13:15.313533Z","caller":"traceutil/trace.go:171","msg":"trace[802589090] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:63; response_revision:14035; }","duration":"135.723593ms","start":"2026-10-17T11:13:15.177791Z","end":"2026-10-17T11:13:15.313515Z","steps":["trace[802589090] 'agreement among raft nodes before linearized reading' (duration: 135.007926ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:15:13.795439Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12761} {"level":"info","ts":"2026-10-17T11:15:13.883977Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12761,"took":"85.957752ms","hash":1065208691,"current-db-size-bytes":55615488,"current-db-size":"56 MB","current-db-size-in-use-bytes":30072832,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-17T11:15:13.884059Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1065208691,"revision":12761,"compact-revision":10500} {"level":"info","ts":"2026-10-17T11:20:13.80511Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15201} {"level":"info","ts":"2026-10-17T11:20:13.913934Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15201,"took":"105.460487ms","hash":371527030,"current-db-size-bytes":55615488,"current-db-size":"56 MB","current-db-size-in-use-bytes":31289344,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-17T11:20:13.914023Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":371527030,"revision":15201,"compact-revision":12761} {"level":"info","ts":"2026-10-17T11:20:27.729813Z","caller":"traceutil/trace.go:171","msg":"trace[627712646] linearizableReadLoop","detail":"{readStateIndex:18211; appliedIndex:18210; }","duration":"131.231681ms","start":"2026-10-17T11:20:27.598557Z","end":"2026-10-17T11:20:27.729789Z","steps":["trace[627712646] 'read index received' (duration: 61.695492ms)","trace[627712646] 'applied index is now lower than readState.Index' (duration: 69.535189ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:20:27.730751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.145693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-k9n6p\" ","response":"range_response_count:1 size:22129"} {"level":"info","ts":"2026-10-17T11:20:27.730807Z","caller":"traceutil/trace.go:171","msg":"trace[655937808] transaction","detail":"{read_only:false; response_revision:17602; number_of_response:1; }","duration":"143.860502ms","start":"2026-10-17T11:20:27.58689Z","end":"2026-10-17T11:20:27.73075Z","steps":["trace[655937808] 'process raft request' (duration: 73.421841ms)","trace[655937808] 'compare' (duration: 69.376926ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:20:27.730882Z","caller":"traceutil/trace.go:171","msg":"trace[530374916] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-k9n6p; range_end:; response_count:1; response_revision:17602; }","duration":"132.324287ms","start":"2026-10-17T11:20:27.598503Z","end":"2026-10-17T11:20:27.730827Z","steps":["trace[530374916] 'agreement among raft nodes before linearized reading' (duration: 131.983029ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:20:32.743126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.180315ms","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-17T11:20:32.743223Z","caller":"traceutil/trace.go:171","msg":"trace[1243786059] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17631; }","duration":"140.340699ms","start":"2026-10-17T11:20:32.602855Z","end":"2026-10-17T11:20:32.743195Z","steps":["trace[1243786059] 'range keys from in-memory index tree' (duration: 139.968279ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:20:32.743286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.321037ms","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-17T11:20:32.743407Z","caller":"traceutil/trace.go:171","msg":"trace[1385377151] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17631; }","duration":"137.47979ms","start":"2026-10-17T11:20:32.605901Z","end":"2026-10-17T11:20:32.743381Z","steps":["trace[1385377151] 'range keys from in-memory index tree' (duration: 137.139222ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:20:33.561092Z","caller":"traceutil/trace.go:171","msg":"trace[961243510] linearizableReadLoop","detail":"{readStateIndex:18244; appliedIndex:18243; }","duration":"247.740581ms","start":"2026-10-17T11:20:33.313326Z","end":"2026-10-17T11:20:33.561067Z","steps":["trace[961243510] 'read index received' (duration: 247.490165ms)","trace[961243510] 'applied index is now lower than readState.Index' (duration: 249.326µs)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:20:33.561259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.907925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-17T11:20:33.561299Z","caller":"traceutil/trace.go:171","msg":"trace[1778273085] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:17634; }","duration":"248.010417ms","start":"2026-10-17T11:20:33.313277Z","end":"2026-10-17T11:20:33.561287Z","steps":["trace[1778273085] 'agreement among raft nodes before linearized reading' (duration: 247.882914ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:20:33.561495Z","caller":"traceutil/trace.go:171","msg":"trace[900681576] transaction","detail":"{read_only:false; response_revision:17634; number_of_response:1; }","duration":"395.69927ms","start":"2026-10-17T11:20:33.165754Z","end":"2026-10-17T11:20:33.561454Z","steps":["trace[900681576] 'process raft request' (duration: 395.160867ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:20:33.561639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:20:33.165727Z","time spent":"395.835504ms","remote":"127.0.0.1:49812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":21021,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T11:20:42.611528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.456637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2225402"} {"level":"info","ts":"2026-10-17T11:20:42.611616Z","caller":"traceutil/trace.go:171","msg":"trace[1842774772] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:17808; }","duration":"113.557379ms","start":"2026-10-17T11:20:42.498039Z","end":"2026-10-17T11:20:42.611597Z","steps":["trace[1842774772] 'range keys from in-memory index tree' (duration: 112.33987ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:23:56.777108Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ca4c710a8d5349d7","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-17T11:23:56.784163Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-17T11:23:56.78425Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-17T11:25:13.812468Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17497} {"level":"info","ts":"2026-10-17T11:25:13.912223Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17497,"took":"96.271297ms","hash":771072313,"current-db-size-bytes":55615488,"current-db-size":"56 MB","current-db-size-in-use-bytes":34340864,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-17T11:25:13.912296Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":771072313,"revision":17497,"compact-revision":15201} {"level":"info","ts":"2026-10-17T11:25:41.537966Z","caller":"traceutil/trace.go:171","msg":"trace[600185594] transaction","detail":"{read_only:false; response_revision:20116; number_of_response:1; }","duration":"142.91378ms","start":"2026-10-17T11:25:41.395024Z","end":"2026-10-17T11:25:41.537938Z","steps":["trace[600185594] 'process raft request' (duration: 142.779007ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:25:41.553379Z","caller":"traceutil/trace.go:171","msg":"trace[1661368708] transaction","detail":"{read_only:false; response_revision:20117; number_of_response:1; }","duration":"157.616571ms","start":"2026-10-17T11:25:41.395745Z","end":"2026-10-17T11:25:41.553362Z","steps":["trace[1661368708] 'process raft request' (duration: 157.484928ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:25:41.553561Z","caller":"traceutil/trace.go:171","msg":"trace[1210408435] transaction","detail":"{read_only:false; response_revision:20118; number_of_response:1; }","duration":"139.922099ms","start":"2026-10-17T11:25:41.413629Z","end":"2026-10-17T11:25:41.553551Z","steps":["trace[1210408435] 'process raft request' (duration: 139.694683ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:25:41.553855Z","caller":"traceutil/trace.go:171","msg":"trace[1536296734] transaction","detail":"{read_only:false; response_revision:20120; number_of_response:1; }","duration":"134.968651ms","start":"2026-10-17T11:25:41.418875Z","end":"2026-10-17T11:25:41.553844Z","steps":["trace[1536296734] 'process raft request' (duration: 134.889079ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:25:41.553878Z","caller":"traceutil/trace.go:171","msg":"trace[1743980975] transaction","detail":"{read_only:false; response_revision:20119; number_of_response:1; }","duration":"135.402971ms","start":"2026-10-17T11:25:41.418423Z","end":"2026-10-17T11:25:41.553826Z","steps":["trace[1743980975] 'process raft request' (duration: 135.091444ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:25:57.777646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.205282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-17T11:25:57.777766Z","caller":"traceutil/trace.go:171","msg":"trace[178440294] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20196; }","duration":"165.335334ms","start":"2026-10-17T11:25:57.612369Z","end":"2026-10-17T11:25:57.777705Z","steps":["trace[178440294] 'range keys from in-memory index tree' (duration: 164.911714ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:25:57.980798Z","caller":"traceutil/trace.go:171","msg":"trace[269569781] transaction","detail":"{read_only:false; response_revision:20197; number_of_response:1; }","duration":"363.187965ms","start":"2026-10-17T11:25:57.617582Z","end":"2026-10-17T11:25:57.98077Z","steps":["trace[269569781] 'process raft request' (duration: 362.851247ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:25:57.981085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:25:57.617544Z","time spent":"363.423191ms","remote":"127.0.0.1:49896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T11:25:57.981075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.858667ms","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-17T11:25:57.98125Z","caller":"traceutil/trace.go:171","msg":"trace[1733764158] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20197; }","duration":"363.078703ms","start":"2026-10-17T11:25:57.61815Z","end":"2026-10-17T11:25:57.981229Z","steps":["trace[1733764158] 'agreement among raft nodes before linearized reading' (duration: 362.733935ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:25:57.981298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:25:57.618135Z","time spent":"363.153685ms","remote":"127.0.0.1:49896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-17T11:25:57.981449Z","caller":"traceutil/trace.go:171","msg":"trace[1471296021] linearizableReadLoop","detail":"{readStateIndex:20891; appliedIndex:20890; }","duration":"362.504149ms","start":"2026-10-17T11:25:57.618177Z","end":"2026-10-17T11:25:57.980681Z","steps":["trace[1471296021] 'read index received' (duration: 362.310105ms)","trace[1471296021] 'applied index is now lower than readState.Index' (duration: 192.694µs)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:25:57.987119Z","caller":"traceutil/trace.go:171","msg":"trace[136460155] transaction","detail":"{read_only:false; response_revision:20198; number_of_response:1; }","duration":"346.254802ms","start":"2026-10-17T11:25:57.640845Z","end":"2026-10-17T11:25:57.9871Z","steps":["trace[136460155] 'process raft request' (duration: 346.091868ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:25:57.987239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:25:57.640812Z","time spent":"346.359144ms","remote":"127.0.0.1:49896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T11:25:57.987245Z","caller":"traceutil/trace.go:171","msg":"trace[422221230] transaction","detail":"{read_only:false; response_revision:20199; number_of_response:1; }","duration":"334.926171ms","start":"2026-10-17T11:25:57.652304Z","end":"2026-10-17T11:25:57.98723Z","steps":["trace[422221230] 'process raft request' (duration: 334.742736ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:25:57.987324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:25:57.652283Z","time spent":"334.995403ms","remote":"127.0.0.1:49896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T11:25:57.987606Z","caller":"traceutil/trace.go:171","msg":"trace[1826836354] transaction","detail":"{read_only:false; response_revision:20200; number_of_response:1; }","duration":"204.169311ms","start":"2026-10-17T11:25:57.783425Z","end":"2026-10-17T11:25:57.987594Z","steps":["trace[1826836354] 'process raft request' (duration: 203.754712ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:25:57.987772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.07924ms","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-17T11:25:57.987804Z","caller":"traceutil/trace.go:171","msg":"trace[1659495627] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20200; }","duration":"305.149011ms","start":"2026-10-17T11:25:57.682647Z","end":"2026-10-17T11:25:57.987796Z","steps":["trace[1659495627] 'agreement among raft nodes before linearized reading' (duration: 305.031718ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:25:57.987827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:25:57.682632Z","time spent":"305.189162ms","remote":"127.0.0.1:49896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":519,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-17T11:25:57.987856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.142996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-17T11:25:57.987963Z","caller":"traceutil/trace.go:171","msg":"trace[974566101] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20200; }","duration":"327.31736ms","start":"2026-10-17T11:25:57.660618Z","end":"2026-10-17T11:25:57.987935Z","steps":["trace[974566101] 'agreement among raft nodes before linearized reading' (duration: 326.92246ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:25:57.988018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:25:57.660585Z","time spent":"327.421062ms","remote":"127.0.0.1:49896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":582,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-10-17T11:25:58.181317Z","caller":"traceutil/trace.go:171","msg":"trace[217848623] transaction","detail":"{read_only:false; response_revision:20202; number_of_response:1; }","duration":"188.15811ms","start":"2026-10-17T11:25:57.993124Z","end":"2026-10-17T11:25:58.181282Z","steps":["trace[217848623] 'process raft request' (duration: 106.302007ms)","trace[217848623] 'compare' (duration: 81.403332ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:25:58.181512Z","caller":"traceutil/trace.go:171","msg":"trace[1491644938] transaction","detail":"{read_only:false; response_revision:20203; number_of_response:1; }","duration":"187.115465ms","start":"2026-10-17T11:25:57.994368Z","end":"2026-10-17T11:25:58.181483Z","steps":["trace[1491644938] 'process raft request' (duration: 186.795027ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:25:58.181325Z","caller":"traceutil/trace.go:171","msg":"trace[1800918888] linearizableReadLoop","detail":"{readStateIndex:20897; appliedIndex:20895; }","duration":"181.42823ms","start":"2026-10-17T11:25:57.999841Z","end":"2026-10-17T11:25:58.18127Z","steps":["trace[1800918888] 'read index received' (duration: 6.876714ms)","trace[1800918888] 'applied index is now lower than readState.Index' (duration: 174.550446ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:25:58.181771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.89349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-10-17T11:25:58.181843Z","caller":"traceutil/trace.go:171","msg":"trace[440592401] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:20203; }","duration":"182.037884ms","start":"2026-10-17T11:25:57.999787Z","end":"2026-10-17T11:25:58.181825Z","steps":["trace[440592401] 'agreement among raft nodes before linearized reading' (duration: 181.787808ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:25:58.334904Z","caller":"traceutil/trace.go:171","msg":"trace[1033151354] transaction","detail":"{read_only:false; response_revision:20204; number_of_response:1; }","duration":"148.651127ms","start":"2026-10-17T11:25:58.186223Z","end":"2026-10-17T11:25:58.334875Z","steps":["trace[1033151354] 'process raft request' (duration: 135.812721ms)","trace[1033151354] 'compare' (duration: 12.627231ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:30:13.820146Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19971} {"level":"info","ts":"2026-10-17T11:30:13.93741Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19971,"took":"113.404656ms","hash":3323389231,"current-db-size-bytes":55615488,"current-db-size":"56 MB","current-db-size-in-use-bytes":42086400,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-10-17T11:30:13.937531Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3323389231,"revision":19971,"compact-revision":17497} {"level":"info","ts":"2026-10-17T11:33:20.889267Z","caller":"traceutil/trace.go:171","msg":"trace[364733337] transaction","detail":"{read_only:false; response_revision:24701; number_of_response:1; }","duration":"116.561202ms","start":"2026-10-17T11:33:20.772677Z","end":"2026-10-17T11:33:20.889239Z","steps":["trace[364733337] 'process raft request' (duration: 116.389508ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:33:21.10859Z","caller":"traceutil/trace.go:171","msg":"trace[30634307] transaction","detail":"{read_only:false; response_revision:24702; number_of_response:1; }","duration":"335.815815ms","start":"2026-10-17T11:33:20.772721Z","end":"2026-10-17T11:33:21.108537Z","steps":["trace[30634307] 'process raft request' (duration: 324.25761ms)","trace[30634307] 'compare' (duration: 10.91395ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:33:21.108653Z","caller":"traceutil/trace.go:171","msg":"trace[94206789] transaction","detail":"{read_only:false; response_revision:24703; number_of_response:1; }","duration":"267.879249ms","start":"2026-10-17T11:33:20.840737Z","end":"2026-10-17T11:33:21.108617Z","steps":["trace[94206789] 'process raft request' (duration: 267.648004ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:33:21.108583Z","caller":"traceutil/trace.go:171","msg":"trace[311534870] linearizableReadLoop","detail":"{readStateIndex:25524; appliedIndex:25522; }","duration":"197.507196ms","start":"2026-10-17T11:33:20.911034Z","end":"2026-10-17T11:33:21.108541Z","steps":["trace[311534870] 'read index received' (duration: 185.792208ms)","trace[311534870] 'applied index is now lower than readState.Index' (duration: 11.713478ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:33:21.108858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:33:20.772688Z","time spent":"336.00007ms","remote":"127.0.0.1:49896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T11:33:21.108885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.817813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-10-17T11:33:21.108948Z","caller":"traceutil/trace.go:171","msg":"trace[1233605466] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:24703; }","duration":"197.945597ms","start":"2026-10-17T11:33:20.910987Z","end":"2026-10-17T11:33:21.108933Z","steps":["trace[1233605466] 'agreement among raft nodes before linearized reading' (duration: 197.67757ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:33:21.109159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.727923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-10-17T11:33:21.109212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.852258ms","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-17T11:33:21.109243Z","caller":"traceutil/trace.go:171","msg":"trace[2043950270] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24703; }","duration":"179.883367ms","start":"2026-10-17T11:33:20.929336Z","end":"2026-10-17T11:33:21.109219Z","steps":["trace[2043950270] 'agreement among raft nodes before linearized reading' (duration: 179.58098ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:33:21.109263Z","caller":"traceutil/trace.go:171","msg":"trace[583844027] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24703; }","duration":"169.960181ms","start":"2026-10-17T11:33:20.939286Z","end":"2026-10-17T11:33:21.109246Z","steps":["trace[583844027] 'agreement among raft nodes before linearized reading' (duration: 169.713095ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:33:21.395447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.727766ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-17T11:33:21.395699Z","caller":"traceutil/trace.go:171","msg":"trace[663152419] transaction","detail":"{read_only:false; response_revision:24705; number_of_response:1; }","duration":"280.882609ms","start":"2026-10-17T11:33:21.114793Z","end":"2026-10-17T11:33:21.395675Z","steps":["trace[663152419] 'process raft request' (duration: 280.792206ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:33:21.395865Z","caller":"traceutil/trace.go:171","msg":"trace[1235672308] transaction","detail":"{read_only:false; response_revision:24704; number_of_response:1; }","duration":"281.504734ms","start":"2026-10-17T11:33:21.114305Z","end":"2026-10-17T11:33:21.395809Z","steps":["trace[1235672308] 'process raft request' (duration: 147.937568ms)","trace[1235672308] 'compare' (duration: 132.566552ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:33:21.703718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.700171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-17T11:33:21.703844Z","caller":"traceutil/trace.go:171","msg":"trace[661354857] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24705; }","duration":"208.843804ms","start":"2026-10-17T11:33:21.494976Z","end":"2026-10-17T11:33:21.70382Z","steps":["trace[661354857] 'range keys from in-memory index tree' (duration: 208.575128ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:34:22.877993Z","caller":"traceutil/trace.go:171","msg":"trace[1673826230] transaction","detail":"{read_only:false; response_revision:25254; number_of_response:1; }","duration":"107.509566ms","start":"2026-10-17T11:34:22.770455Z","end":"2026-10-17T11:34:22.877965Z","steps":["trace[1673826230] 'process raft request' (duration: 107.056896ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:34:23.05155Z","caller":"traceutil/trace.go:171","msg":"trace[1794517509] transaction","detail":"{read_only:false; response_revision:25255; number_of_response:1; }","duration":"164.717536ms","start":"2026-10-17T11:34:22.886808Z","end":"2026-10-17T11:34:23.051525Z","steps":["trace[1794517509] 'process raft request' (duration: 161.49168ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:34:23.053665Z","caller":"traceutil/trace.go:171","msg":"trace[1606771097] linearizableReadLoop","detail":"{readStateIndex:26091; appliedIndex:26089; }","duration":"143.773839ms","start":"2026-10-17T11:34:22.909878Z","end":"2026-10-17T11:34:23.053652Z","steps":["trace[1606771097] 'read index received' (duration: 138.498583ms)","trace[1606771097] 'applied index is now lower than readState.Index' (duration: 5.274476ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:34:23.053861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.769553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-17T11:34:23.053817Z","caller":"traceutil/trace.go:171","msg":"trace[817859436] transaction","detail":"{read_only:false; response_revision:25256; number_of_response:1; }","duration":"164.238815ms","start":"2026-10-17T11:34:22.889541Z","end":"2026-10-17T11:34:23.053779Z","steps":["trace[817859436] 'process raft request' (duration: 164.055281ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:34:23.053906Z","caller":"traceutil/trace.go:171","msg":"trace[1522995082] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25256; }","duration":"107.860835ms","start":"2026-10-17T11:34:22.946034Z","end":"2026-10-17T11:34:23.053895Z","steps":["trace[1522995082] 'agreement among raft nodes before linearized reading' (duration: 107.798153ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:34:23.053866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.971633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-17T11:34:23.054031Z","caller":"traceutil/trace.go:171","msg":"trace[107653035] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackservers/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackservers0; response_count:0; response_revision:25256; }","duration":"144.195029ms","start":"2026-10-17T11:34:22.909823Z","end":"2026-10-17T11:34:23.054018Z","steps":["trace[107653035] 'agreement among raft nodes before linearized reading' (duration: 143.980374ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:34:23.450292Z","caller":"traceutil/trace.go:171","msg":"trace[1493539324] transaction","detail":"{read_only:false; response_revision:25259; number_of_response:1; }","duration":"107.212179ms","start":"2026-10-17T11:34:23.343047Z","end":"2026-10-17T11:34:23.450259Z","steps":["trace[1493539324] 'process raft request' (duration: 107.072356ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:34:23.685789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.596538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-17T11:34:23.685933Z","caller":"traceutil/trace.go:171","msg":"trace[1274567972] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25259; }","duration":"197.766732ms","start":"2026-10-17T11:34:23.48813Z","end":"2026-10-17T11:34:23.685897Z","steps":["trace[1274567972] 'range keys from in-memory index tree' (duration: 197.381373ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:34:23.845048Z","caller":"traceutil/trace.go:171","msg":"trace[1892656422] transaction","detail":"{read_only:false; response_revision:25260; number_of_response:1; }","duration":"154.078793ms","start":"2026-10-17T11:34:23.69093Z","end":"2026-10-17T11:34:23.845009Z","steps":["trace[1892656422] 'process raft request' (duration: 153.848738ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:34:23.997439Z","caller":"traceutil/trace.go:171","msg":"trace[273221570] transaction","detail":"{read_only:false; response_revision:25261; number_of_response:1; }","duration":"147.800134ms","start":"2026-10-17T11:34:23.849609Z","end":"2026-10-17T11:34:23.997409Z","steps":["trace[273221570] 'process raft request' (duration: 140.206933ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:34:24.005705Z","caller":"traceutil/trace.go:171","msg":"trace[31236789] transaction","detail":"{read_only:false; response_revision:25262; number_of_response:1; }","duration":"155.476307ms","start":"2026-10-17T11:34:23.850202Z","end":"2026-10-17T11:34:24.005678Z","steps":["trace[31236789] 'process raft request' (duration: 154.915383ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:35:13.827959Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22871} {"level":"info","ts":"2026-10-17T11:35:13.989818Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22871,"took":"157.849123ms","hash":1077803835,"current-db-size-bytes":55615488,"current-db-size":"56 MB","current-db-size-in-use-bytes":35659776,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-17T11:35:13.990567Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1077803835,"revision":22871,"compact-revision":19971} {"level":"info","ts":"2026-10-17T11:35:23.314049Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006881.wal"} {"level":"info","ts":"2026-10-17T11:37:53.292492Z","caller":"traceutil/trace.go:171","msg":"trace[1167163724] transaction","detail":"{read_only:false; response_revision:27334; number_of_response:1; }","duration":"118.711502ms","start":"2026-10-17T11:37:53.173753Z","end":"2026-10-17T11:37:53.292465Z","steps":["trace[1167163724] 'process raft request' (duration: 118.534828ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:37:53.292867Z","caller":"traceutil/trace.go:171","msg":"trace[800033826] linearizableReadLoop","detail":"{readStateIndex:28221; appliedIndex:28218; }","duration":"103.661774ms","start":"2026-10-17T11:37:53.189055Z","end":"2026-10-17T11:37:53.292716Z","steps":["trace[800033826] 'read index received' (duration: 81.704672ms)","trace[800033826] 'applied index is now lower than readState.Index' (duration: 21.955582ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:37:53.292938Z","caller":"traceutil/trace.go:171","msg":"trace[331622451] transaction","detail":"{read_only:false; response_revision:27335; number_of_response:1; }","duration":"113.5696ms","start":"2026-10-17T11:37:53.179316Z","end":"2026-10-17T11:37:53.292886Z","steps":["trace[331622451] 'process raft request' (duration: 113.100979ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:37:53.293003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.938391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-17T11:37:53.293074Z","caller":"traceutil/trace.go:171","msg":"trace[673516537] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:27336; }","duration":"104.020813ms","start":"2026-10-17T11:37:53.189036Z","end":"2026-10-17T11:37:53.293057Z","steps":["trace[673516537] 'agreement among raft nodes before linearized reading' (duration: 103.87523ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:40:13.836596Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25832} {"level":"info","ts":"2026-10-17T11:40:13.985129Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25832,"took":"144.965254ms","hash":2501730328,"current-db-size-bytes":56037376,"current-db-size":"56 MB","current-db-size-in-use-bytes":35045376,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-17T11:40:13.985214Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2501730328,"revision":25832,"compact-revision":22871} {"level":"info","ts":"2026-10-17T11:41:27.606166Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ca4c710a8d5349d7","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-17T11:41:27.61309Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-17T11:41:27.613234Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-17T11:45:13.84702Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28530} {"level":"info","ts":"2026-10-17T11:45:13.996425Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28530,"took":"142.507095ms","hash":2036491279,"current-db-size-bytes":56037376,"current-db-size":"56 MB","current-db-size-in-use-bytes":30445568,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-17T11:45:13.996488Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2036491279,"revision":28530,"compact-revision":25832} {"level":"info","ts":"2026-10-17T11:50:13.85446Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30748} {"level":"info","ts":"2026-10-17T11:50:13.988572Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30748,"took":"129.054511ms","hash":2105184859,"current-db-size-bytes":56037376,"current-db-size":"56 MB","current-db-size-in-use-bytes":31289344,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-17T11:50:13.988637Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2105184859,"revision":30748,"compact-revision":28530} {"level":"info","ts":"2026-10-17T11:55:13.862146Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33013} {"level":"info","ts":"2026-10-17T11:55:14.02187Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33013,"took":"155.334776ms","hash":4208651391,"current-db-size-bytes":57081856,"current-db-size":"57 MB","current-db-size-in-use-bytes":31559680,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-17T11:55:14.021915Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4208651391,"revision":33013,"compact-revision":30748} {"level":"info","ts":"2026-10-17T12:00:13.870765Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35305} {"level":"info","ts":"2026-10-17T12:00:14.020448Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35305,"took":"144.961859ms","hash":369332778,"current-db-size-bytes":57081856,"current-db-size":"57 MB","current-db-size-in-use-bytes":33099776,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-17T12:00:14.020522Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":369332778,"revision":35305,"compact-revision":33013} {"level":"info","ts":"2026-10-17T12:02:46.382445Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ca4c710a8d5349d7","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-17T12:02:46.389166Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-17T12:02:46.389285Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-17T12:05:13.887757Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37608} {"level":"info","ts":"2026-10-17T12:05:14.024767Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37608,"took":"131.98626ms","hash":1360627022,"current-db-size-bytes":57933824,"current-db-size":"58 MB","current-db-size-in-use-bytes":33026048,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-17T12:05:14.024838Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1360627022,"revision":37608,"compact-revision":35305}