{"level":"warn","ts":"2026-10-16T00:06:47.709063Z","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-16T00:06:47.709166Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.19: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.19:2380","--initial-cluster=instance=https://162.253.55.19:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.19:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.19: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-16T00:06:47.709239Z","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-16T00:06:47.709249Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.19:2380"]} {"level":"info","ts":"2026-10-16T00:06:47.709273Z","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-16T00:06:47.710072Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.19:2379"]} {"level":"info","ts":"2026-10-16T00:06:47.710164Z","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.19:2380"],"listen-peer-urls":["https://162.253.55.19:2380"],"advertise-client-urls":["https://162.253.55.19:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.19:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.19: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-16T00:06:47.714025Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.606586ms"} {"level":"info","ts":"2026-10-16T00:06:47.719696Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ded76e421897a43d","cluster-id":"8853d1107d87769b"} {"level":"info","ts":"2026-10-16T00:06:47.719814Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ded76e421897a43d switched to configuration voters=()"} {"level":"info","ts":"2026-10-16T00:06:47.719847Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ded76e421897a43d became follower at term 0"} {"level":"info","ts":"2026-10-16T00:06:47.719862Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ded76e421897a43d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-16T00:06:47.719878Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ded76e421897a43d became follower at term 1"} {"level":"info","ts":"2026-10-16T00:06:47.719932Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ded76e421897a43d switched to configuration voters=(16057424226572280893)"} {"level":"warn","ts":"2026-10-16T00:06:47.726594Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-16T00:06:47.728759Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-16T00:06:47.73103Z","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-16T00:06:47.734686Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ded76e421897a43d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-16T00:06:47.735246Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ded76e421897a43d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-16T00:06:47.735442Z","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-16T00:06:47.736127Z","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-16T00:06:47.736235Z","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-16T00:06:47.73691Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ded76e421897a43d switched to configuration voters=(16057424226572280893)"} {"level":"info","ts":"2026-10-16T00:06:47.737439Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8853d1107d87769b","local-member-id":"ded76e421897a43d","added-peer-id":"ded76e421897a43d","added-peer-peer-urls":["https://162.253.55.19:2380"]} {"level":"info","ts":"2026-10-16T00:06:47.739098Z","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-16T00:06:47.739271Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.19:2380"} {"level":"info","ts":"2026-10-16T00:06:47.739314Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.19:2380"} {"level":"info","ts":"2026-10-16T00:06:47.739515Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ded76e421897a43d","initial-advertise-peer-urls":["https://162.253.55.19:2380"],"listen-peer-urls":["https://162.253.55.19:2380"],"advertise-client-urls":["https://162.253.55.19:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.19:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-16T00:06:47.739576Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-16T00:06:48.620571Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ded76e421897a43d is starting a new election at term 1"} {"level":"info","ts":"2026-10-16T00:06:48.620671Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ded76e421897a43d became pre-candidate at term 1"} {"level":"info","ts":"2026-10-16T00:06:48.620701Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ded76e421897a43d received MsgPreVoteResp from ded76e421897a43d at term 1"} {"level":"info","ts":"2026-10-16T00:06:48.620741Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ded76e421897a43d became candidate at term 2"} {"level":"info","ts":"2026-10-16T00:06:48.620764Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ded76e421897a43d received MsgVoteResp from ded76e421897a43d at term 2"} {"level":"info","ts":"2026-10-16T00:06:48.620788Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ded76e421897a43d became leader at term 2"} {"level":"info","ts":"2026-10-16T00:06:48.620807Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ded76e421897a43d elected leader ded76e421897a43d at term 2"} {"level":"info","ts":"2026-10-16T00:06:48.622283Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ded76e421897a43d","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.19:2379]}","request-path":"/0/members/ded76e421897a43d/attributes","cluster-id":"8853d1107d87769b","publish-timeout":"7s"} {"level":"info","ts":"2026-10-16T00:06:48.622301Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-16T00:06:48.622349Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-16T00:06:48.622405Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-16T00:06:48.622544Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-16T00:06:48.622596Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-16T00:06:48.623549Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8853d1107d87769b","local-member-id":"ded76e421897a43d","cluster-version":"3.5"} {"level":"info","ts":"2026-10-16T00:06:48.623698Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-16T00:06:48.623742Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-16T00:06:48.625504Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.19:2379"} {"level":"info","ts":"2026-10-16T00:06:48.625901Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-16T00:06:50.275331Z","caller":"traceutil/trace.go:171","msg":"trace[1585227641] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"118.420535ms","start":"2026-10-16T00:06:50.15689Z","end":"2026-10-16T00:06:50.275311Z","steps":["trace[1585227641] 'process raft request' (duration: 111.59088ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:06:50.27543Z","caller":"traceutil/trace.go:171","msg":"trace[262852063] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"116.629362ms","start":"2026-10-16T00:06:50.158774Z","end":"2026-10-16T00:06:50.275404Z","steps":["trace[262852063] 'process raft request' (duration: 116.49738ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:06:50.275357Z","caller":"traceutil/trace.go:171","msg":"trace[488166100] linearizableReadLoop","detail":"{readStateIndex:22; appliedIndex:21; }","duration":"118.314813ms","start":"2026-10-16T00:06:50.157015Z","end":"2026-10-16T00:06:50.27533Z","steps":["trace[488166100] 'read index received' (duration: 724.304µs)","trace[488166100] 'applied index is now lower than readState.Index' (duration: 117.588249ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:06:50.2755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.461286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-16T00:06:50.275574Z","caller":"traceutil/trace.go:171","msg":"trace[1320963500] range","detail":"{range_begin:/registry/resourcequotas/kube-public/; range_end:/registry/resourcequotas/kube-public0; response_count:0; response_revision:21; }","duration":"118.569967ms","start":"2026-10-16T00:06:50.156992Z","end":"2026-10-16T00:06:50.275562Z","steps":["trace[1320963500] 'agreement among raft nodes before linearized reading' (duration: 118.412474ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:06:50.275617Z","caller":"traceutil/trace.go:171","msg":"trace[184942937] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"116.145283ms","start":"2026-10-16T00:06:50.159458Z","end":"2026-10-16T00:06:50.275603Z","steps":["trace[184942937] 'process raft request' (duration: 115.906969ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:06:50.275719Z","caller":"traceutil/trace.go:171","msg":"trace[221147435] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"116.119283ms","start":"2026-10-16T00:06:50.159364Z","end":"2026-10-16T00:06:50.275483Z","steps":["trace[221147435] 'process raft request' (duration: 115.95812ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:06:50.275979Z","caller":"traceutil/trace.go:171","msg":"trace[2082195938] transaction","detail":"{read_only:false; response_revision:22; number_of_response:1; }","duration":"116.240645ms","start":"2026-10-16T00:06:50.159724Z","end":"2026-10-16T00:06:50.275965Z","steps":["trace[2082195938] 'process raft request' (duration: 115.680914ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:06:50.276026Z","caller":"traceutil/trace.go:171","msg":"trace[1021029974] transaction","detail":"{read_only:false; response_revision:23; number_of_response:1; }","duration":"116.186934ms","start":"2026-10-16T00:06:50.159824Z","end":"2026-10-16T00:06:50.276011Z","steps":["trace[1021029974] 'process raft request' (duration: 115.957619ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:06:50.276114Z","caller":"traceutil/trace.go:171","msg":"trace[181507467] transaction","detail":"{read_only:false; response_revision:25; number_of_response:1; }","duration":"116.165083ms","start":"2026-10-16T00:06:50.159933Z","end":"2026-10-16T00:06:50.276098Z","steps":["trace[181507467] 'process raft request' (duration: 116.056681ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:06:50.276103Z","caller":"traceutil/trace.go:171","msg":"trace[247532712] transaction","detail":"{read_only:false; response_revision:24; number_of_response:1; }","duration":"116.167943ms","start":"2026-10-16T00:06:50.159875Z","end":"2026-10-16T00:06:50.276043Z","steps":["trace[247532712] 'process raft request' (duration: 116.01698ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:06:50.27631Z","caller":"traceutil/trace.go:171","msg":"trace[646070086] transaction","detail":"{read_only:false; response_revision:27; number_of_response:1; }","duration":"115.600493ms","start":"2026-10-16T00:06:50.160699Z","end":"2026-10-16T00:06:50.276299Z","steps":["trace[646070086] 'process raft request' (duration: 115.350938ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:06:50.276318Z","caller":"traceutil/trace.go:171","msg":"trace[99902154] transaction","detail":"{read_only:false; response_revision:26; number_of_response:1; }","duration":"115.771635ms","start":"2026-10-16T00:06:50.160534Z","end":"2026-10-16T00:06:50.276306Z","steps":["trace[99902154] 'process raft request' (duration: 115.488071ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:06:50.276369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.241852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-16T00:06:50.276534Z","caller":"traceutil/trace.go:171","msg":"trace[210465929] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:0; response_revision:28; }","duration":"117.452507ms","start":"2026-10-16T00:06:50.159052Z","end":"2026-10-16T00:06:50.276505Z","steps":["trace[210465929] 'agreement among raft nodes before linearized reading' (duration: 117.135602ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:07:08.823791Z","caller":"traceutil/trace.go:171","msg":"trace[2050609726] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"167.345984ms","start":"2026-10-16T00:07:08.656428Z","end":"2026-10-16T00:07:08.823774Z","steps":["trace[2050609726] 'process raft request' (duration: 167.238462ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:07:14.333885Z","caller":"traceutil/trace.go:171","msg":"trace[1948111943] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"239.789512ms","start":"2026-10-16T00:07:14.094063Z","end":"2026-10-16T00:07:14.333852Z","steps":["trace[1948111943] 'process raft request' (duration: 239.614988ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:07:38.538724Z","caller":"traceutil/trace.go:171","msg":"trace[1831040737] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"288.667912ms","start":"2026-10-16T00:07:38.250027Z","end":"2026-10-16T00:07:38.538695Z","steps":["trace[1831040737] 'process raft request' (duration: 288.06352ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:07:38.807426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.946935ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-16T00:07:38.80783Z","caller":"traceutil/trace.go:171","msg":"trace[1765022949] linearizableReadLoop","detail":"{readStateIndex:634; appliedIndex:632; }","duration":"233.669173ms","start":"2026-10-16T00:07:38.574145Z","end":"2026-10-16T00:07:38.807814Z","steps":["trace[1765022949] 'read index received' (duration: 72.790832ms)","trace[1765022949] 'applied index is now lower than readState.Index' (duration: 160.877291ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:07:38.807913Z","caller":"traceutil/trace.go:171","msg":"trace[533509551] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"264.693906ms","start":"2026-10-16T00:07:38.543209Z","end":"2026-10-16T00:07:38.807903Z","steps":["trace[533509551] 'process raft request' (duration: 103.730034ms)","trace[533509551] 'compare' (duration: 159.842012ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:07:38.808001Z","caller":"traceutil/trace.go:171","msg":"trace[656087687] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"200.891017ms","start":"2026-10-16T00:07:38.607075Z","end":"2026-10-16T00:07:38.807966Z","steps":["trace[656087687] 'process raft request' (duration: 200.51033ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:07:38.808298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.454045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-10-16T00:07:38.808309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.148002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-qnbrs\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-10-16T00:07:38.808328Z","caller":"traceutil/trace.go:171","msg":"trace[980010234] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"264.735477ms","start":"2026-10-16T00:07:38.543537Z","end":"2026-10-16T00:07:38.808273Z","steps":["trace[980010234] 'process raft request' (duration: 263.997614ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:07:38.808386Z","caller":"traceutil/trace.go:171","msg":"trace[1285143491] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:609; }","duration":"219.560707ms","start":"2026-10-16T00:07:38.588795Z","end":"2026-10-16T00:07:38.808355Z","steps":["trace[1285143491] 'agreement among raft nodes before linearized reading' (duration: 219.327113ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:07:38.808386Z","caller":"traceutil/trace.go:171","msg":"trace[664515733] range","detail":"{range_begin:/registry/pods/kube-system/cilium-qnbrs; range_end:; response_count:1; response_revision:609; }","duration":"234.256514ms","start":"2026-10-16T00:07:38.574109Z","end":"2026-10-16T00:07:38.808365Z","steps":["trace[664515733] 'agreement among raft nodes before linearized reading' (duration: 234.03448ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:07:39.092178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.634274ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-16T00:07:39.092275Z","caller":"traceutil/trace.go:171","msg":"trace[85658872] linearizableReadLoop","detail":"{readStateIndex:637; appliedIndex:636; }","duration":"153.887074ms","start":"2026-10-16T00:07:38.938372Z","end":"2026-10-16T00:07:39.092259Z","steps":["trace[85658872] 'read index received' (duration: 42.461µs)","trace[85658872] 'applied index is now lower than readState.Index' (duration: 153.843143ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:07:39.092313Z","caller":"traceutil/trace.go:171","msg":"trace[73853156] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"263.028935ms","start":"2026-10-16T00:07:38.82925Z","end":"2026-10-16T00:07:39.092279Z","steps":["trace[73853156] 'process raft request' (duration: 101.219768ms)","trace[73853156] 'compare' (duration: 161.502042ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:07:39.092402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.003456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:07:39.092478Z","caller":"traceutil/trace.go:171","msg":"trace[136525134] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:611; }","duration":"154.125259ms","start":"2026-10-16T00:07:38.938332Z","end":"2026-10-16T00:07:39.092457Z","steps":["trace[136525134] 'agreement among raft nodes before linearized reading' (duration: 153.975456ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:07:39.092526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.811197ms","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-16T00:07:39.092597Z","caller":"traceutil/trace.go:171","msg":"trace[476534560] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:611; }","duration":"107.916269ms","start":"2026-10-16T00:07:38.984669Z","end":"2026-10-16T00:07:39.092586Z","steps":["trace[476534560] 'agreement among raft nodes before linearized reading' (duration: 107.678865ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:07:39.581393Z","caller":"traceutil/trace.go:171","msg":"trace[685353337] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"338.144791ms","start":"2026-10-16T00:07:39.243226Z","end":"2026-10-16T00:07:39.581371Z","steps":["trace[685353337] 'process raft request' (duration: 337.619871ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:07:39.581551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:07:39.243199Z","time spent":"338.266762ms","remote":"127.0.0.1:55052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T00:07:39.889342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.536207ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-16T00:07:39.889457Z","caller":"traceutil/trace.go:171","msg":"trace[1039514736] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"237.589075ms","start":"2026-10-16T00:07:39.651845Z","end":"2026-10-16T00:07:39.889434Z","steps":["trace[1039514736] 'process raft request' (duration: 88.885835ms)","trace[1039514736] 'compare' (duration: 148.392134ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:07:40.610566Z","caller":"traceutil/trace.go:171","msg":"trace[97022805] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"259.950969ms","start":"2026-10-16T00:07:40.350599Z","end":"2026-10-16T00:07:40.61055Z","steps":["trace[97022805] 'process raft request' (duration: 259.582203ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:07:56.88519Z","caller":"traceutil/trace.go:171","msg":"trace[342520363] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"203.702929ms","start":"2026-10-16T00:07:56.681466Z","end":"2026-10-16T00:07:56.885169Z","steps":["trace[342520363] 'process raft request' (duration: 203.539496ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:08:26.422188Z","caller":"traceutil/trace.go:171","msg":"trace[793381043] transaction","detail":"{read_only:false; response_revision:1179; number_of_response:1; }","duration":"151.026072ms","start":"2026-10-16T00:08:26.271137Z","end":"2026-10-16T00:08:26.422163Z","steps":["trace[793381043] 'process raft request' (duration: 150.882929ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:08:51.36242Z","caller":"traceutil/trace.go:171","msg":"trace[333595952] linearizableReadLoop","detail":"{readStateIndex:1494; appliedIndex:1493; }","duration":"104.904009ms","start":"2026-10-16T00:08:51.257496Z","end":"2026-10-16T00:08:51.3624Z","steps":["trace[333595952] 'read index received' (duration: 104.739296ms)","trace[333595952] 'applied index is now lower than readState.Index' (duration: 163.903µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:08:51.362485Z","caller":"traceutil/trace.go:171","msg":"trace[899268490] transaction","detail":"{read_only:false; response_revision:1446; number_of_response:1; }","duration":"123.939471ms","start":"2026-10-16T00:08:51.238525Z","end":"2026-10-16T00:08:51.362465Z","steps":["trace[899268490] 'process raft request' (duration: 123.753537ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:08:51.362657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.123153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-10-16T00:08:51.362695Z","caller":"traceutil/trace.go:171","msg":"trace[635912597] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1446; }","duration":"105.232705ms","start":"2026-10-16T00:08:51.257452Z","end":"2026-10-16T00:08:51.362685Z","steps":["trace[635912597] 'agreement among raft nodes before linearized reading' (duration: 105.062121ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:08:51.36283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.173834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-16T00:08:51.362933Z","caller":"traceutil/trace.go:171","msg":"trace[1984199525] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1446; }","duration":"105.309317ms","start":"2026-10-16T00:08:51.257601Z","end":"2026-10-16T00:08:51.362911Z","steps":["trace[1984199525] 'agreement among raft nodes before linearized reading' (duration: 105.006921ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:09:15.144706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.261912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:09:15.144803Z","caller":"traceutil/trace.go:171","msg":"trace[826539326] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1848; }","duration":"206.394785ms","start":"2026-10-16T00:09:14.938381Z","end":"2026-10-16T00:09:15.144776Z","steps":["trace[826539326] 'range keys from in-memory index tree' (duration: 206.14596ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:09:15.144694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.754331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-10-16T00:09:15.144976Z","caller":"traceutil/trace.go:171","msg":"trace[933257472] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1848; }","duration":"181.039987ms","start":"2026-10-16T00:09:14.963885Z","end":"2026-10-16T00:09:15.144925Z","steps":["trace[933257472] 'range keys from in-memory index tree' (duration: 180.532287ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:09:15.68342Z","caller":"traceutil/trace.go:171","msg":"trace[1221546414] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"197.572933ms","start":"2026-10-16T00:09:15.485824Z","end":"2026-10-16T00:09:15.683397Z","steps":["trace[1221546414] 'process raft request' (duration: 196.288349ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:09:15.683536Z","caller":"traceutil/trace.go:171","msg":"trace[1394360125] linearizableReadLoop","detail":"{readStateIndex:1927; appliedIndex:1924; }","duration":"162.270749ms","start":"2026-10-16T00:09:15.521242Z","end":"2026-10-16T00:09:15.683513Z","steps":["trace[1394360125] 'read index received' (duration: 56.221139ms)","trace[1394360125] 'applied index is now lower than readState.Index' (duration: 106.04858ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:09:15.683644Z","caller":"traceutil/trace.go:171","msg":"trace[669886510] transaction","detail":"{read_only:false; response_revision:1852; number_of_response:1; }","duration":"197.334338ms","start":"2026-10-16T00:09:15.486266Z","end":"2026-10-16T00:09:15.683601Z","steps":["trace[669886510] 'process raft request' (duration: 197.069413ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:09:15.683951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.669067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-16T00:09:15.684123Z","caller":"traceutil/trace.go:171","msg":"trace[1450740121] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1852; }","duration":"162.888471ms","start":"2026-10-16T00:09:15.521215Z","end":"2026-10-16T00:09:15.684103Z","steps":["trace[1450740121] 'agreement among raft nodes before linearized reading' (duration: 162.403172ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:11:29.318084Z","caller":"traceutil/trace.go:171","msg":"trace[2004316235] transaction","detail":"{read_only:false; response_revision:2826; number_of_response:1; }","duration":"162.362509ms","start":"2026-10-16T00:11:29.155695Z","end":"2026-10-16T00:11:29.318058Z","steps":["trace[2004316235] 'process raft request' (duration: 162.217957ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:11:29.378196Z","caller":"traceutil/trace.go:171","msg":"trace[1864258322] transaction","detail":"{read_only:false; response_revision:2827; number_of_response:1; }","duration":"196.905162ms","start":"2026-10-16T00:11:29.181273Z","end":"2026-10-16T00:11:29.378179Z","steps":["trace[1864258322] 'process raft request' (duration: 196.730608ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:11:29.708159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.097617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-10-16T00:11:29.70826Z","caller":"traceutil/trace.go:171","msg":"trace[107528448] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2827; }","duration":"266.254361ms","start":"2026-10-16T00:11:29.441982Z","end":"2026-10-16T00:11:29.708236Z","steps":["trace[107528448] 'range keys from in-memory index tree' (duration: 265.837502ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:11:29.880332Z","caller":"traceutil/trace.go:171","msg":"trace[640703294] transaction","detail":"{read_only:false; response_revision:2828; number_of_response:1; }","duration":"167.479004ms","start":"2026-10-16T00:11:29.71283Z","end":"2026-10-16T00:11:29.880309Z","steps":["trace[640703294] 'process raft request' (duration: 167.324761ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:15:59.782422Z","caller":"traceutil/trace.go:171","msg":"trace[941539491] transaction","detail":"{read_only:false; response_revision:5129; number_of_response:1; }","duration":"200.686135ms","start":"2026-10-16T00:15:59.581707Z","end":"2026-10-16T00:15:59.782393Z","steps":["trace[941539491] 'process raft request' (duration: 197.12269ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:15:59.785078Z","caller":"traceutil/trace.go:171","msg":"trace[1237694304] linearizableReadLoop","detail":"{readStateIndex:5335; appliedIndex:5327; }","duration":"150.709094ms","start":"2026-10-16T00:15:59.634341Z","end":"2026-10-16T00:15:59.78505Z","steps":["trace[1237694304] 'read index received' (duration: 144.42684ms)","trace[1237694304] 'applied index is now lower than readState.Index' (duration: 6.281214ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:15:59.785575Z","caller":"traceutil/trace.go:171","msg":"trace[1797945982] transaction","detail":"{read_only:false; response_revision:5135; number_of_response:1; }","duration":"151.437508ms","start":"2026-10-16T00:15:59.634119Z","end":"2026-10-16T00:15:59.785557Z","steps":["trace[1797945982] 'process raft request' (duration: 150.757825ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:15:59.785591Z","caller":"traceutil/trace.go:171","msg":"trace[810265927] transaction","detail":"{read_only:false; response_revision:5132; number_of_response:1; }","duration":"155.287518ms","start":"2026-10-16T00:15:59.630284Z","end":"2026-10-16T00:15:59.785572Z","steps":["trace[810265927] 'process raft request' (duration: 154.442792ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:15:59.785607Z","caller":"traceutil/trace.go:171","msg":"trace[597275047] transaction","detail":"{read_only:false; response_revision:5130; number_of_response:1; }","duration":"161.095454ms","start":"2026-10-16T00:15:59.624483Z","end":"2026-10-16T00:15:59.785578Z","steps":["trace[597275047] 'process raft request' (duration: 159.910822ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:15:59.785797Z","caller":"traceutil/trace.go:171","msg":"trace[333119153] transaction","detail":"{read_only:false; response_revision:5133; number_of_response:1; }","duration":"154.950981ms","start":"2026-10-16T00:15:59.630835Z","end":"2026-10-16T00:15:59.785786Z","steps":["trace[333119153] 'process raft request' (duration: 153.940283ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:15:59.785866Z","caller":"traceutil/trace.go:171","msg":"trace[1981648480] transaction","detail":"{read_only:false; number_of_response:1; response_revision:5135; }","duration":"151.578899ms","start":"2026-10-16T00:15:59.634271Z","end":"2026-10-16T00:15:59.78585Z","steps":["trace[1981648480] 'process raft request' (duration: 150.669163ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:15:59.785923Z","caller":"traceutil/trace.go:171","msg":"trace[1417759743] transaction","detail":"{read_only:false; response_revision:5134; number_of_response:1; }","duration":"152.910033ms","start":"2026-10-16T00:15:59.633004Z","end":"2026-10-16T00:15:59.785914Z","steps":["trace[1417759743] 'process raft request' (duration: 151.827944ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:15:59.786235Z","caller":"traceutil/trace.go:171","msg":"trace[1025504402] transaction","detail":"{read_only:false; response_revision:5131; number_of_response:1; }","duration":"156.962848ms","start":"2026-10-16T00:15:59.629252Z","end":"2026-10-16T00:15:59.786215Z","steps":["trace[1025504402] 'process raft request' (duration: 155.41167ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:15:59.786445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.503719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/monitoring/loki-chunks-cache\" ","response":"range_response_count:1 size:3778"} {"level":"warn","ts":"2026-10-16T00:15:59.786447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.100999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/loki-results-cache-0\" ","response":"range_response_count:1 size:3431"} {"level":"info","ts":"2026-10-16T00:15:59.786481Z","caller":"traceutil/trace.go:171","msg":"trace[1848593819] range","detail":"{range_begin:/registry/statefulsets/monitoring/loki-chunks-cache; range_end:; response_count:1; response_revision:5136; }","duration":"151.58668ms","start":"2026-10-16T00:15:59.634885Z","end":"2026-10-16T00:15:59.786472Z","steps":["trace[1848593819] 'agreement among raft nodes before linearized reading' (duration: 151.467917ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:15:59.786501Z","caller":"traceutil/trace.go:171","msg":"trace[1742525404] range","detail":"{range_begin:/registry/pods/monitoring/loki-results-cache-0; range_end:; response_count:1; response_revision:5136; }","duration":"152.18744ms","start":"2026-10-16T00:15:59.634296Z","end":"2026-10-16T00:15:59.786484Z","steps":["trace[1742525404] 'agreement among raft nodes before linearized reading' (duration: 152.008837ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:16:01.945652Z","caller":"traceutil/trace.go:171","msg":"trace[1019462900] transaction","detail":"{read_only:false; response_revision:5180; number_of_response:1; }","duration":"158.298962ms","start":"2026-10-16T00:16:01.787305Z","end":"2026-10-16T00:16:01.945604Z","steps":["trace[1019462900] 'process raft request' (duration: 77.610923ms)","trace[1019462900] 'compare' (duration: 80.442974ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:16:01.945661Z","caller":"traceutil/trace.go:171","msg":"trace[1283359963] linearizableReadLoop","detail":"{readStateIndex:5383; appliedIndex:5380; }","duration":"140.715883ms","start":"2026-10-16T00:16:01.804903Z","end":"2026-10-16T00:16:01.945618Z","steps":["trace[1283359963] 'read index received' (duration: 59.992843ms)","trace[1283359963] 'applied index is now lower than readState.Index' (duration: 80.72195ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:16:01.94595Z","caller":"traceutil/trace.go:171","msg":"trace[212955446] transaction","detail":"{read_only:false; response_revision:5182; number_of_response:1; }","duration":"149.416791ms","start":"2026-10-16T00:16:01.79652Z","end":"2026-10-16T00:16:01.945936Z","steps":["trace[212955446] 'process raft request' (duration: 149.055314ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:16:01.945961Z","caller":"traceutil/trace.go:171","msg":"trace[405378781] transaction","detail":"{read_only:false; response_revision:5181; number_of_response:1; }","duration":"154.035176ms","start":"2026-10-16T00:16:01.791886Z","end":"2026-10-16T00:16:01.945921Z","steps":["trace[405378781] 'process raft request' (duration: 153.618628ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:16:01.946129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.407433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-10-16T00:16:01.945902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.972168ms","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-16T00:16:01.946201Z","caller":"traceutil/trace.go:171","msg":"trace[1977364517] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5182; }","duration":"125.516705ms","start":"2026-10-16T00:16:01.820668Z","end":"2026-10-16T00:16:01.946185Z","steps":["trace[1977364517] 'agreement among raft nodes before linearized reading' (duration: 125.314882ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:16:01.946295Z","caller":"traceutil/trace.go:171","msg":"trace[1585966256] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5182; }","duration":"141.332944ms","start":"2026-10-16T00:16:01.804896Z","end":"2026-10-16T00:16:01.946229Z","steps":["trace[1585966256] 'agreement among raft nodes before linearized reading' (duration: 140.816145ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:16:02.402197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.934278ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-16T00:16:02.402446Z","caller":"traceutil/trace.go:171","msg":"trace[2071555202] linearizableReadLoop","detail":"{readStateIndex:5385; appliedIndex:5383; }","duration":"450.924531ms","start":"2026-10-16T00:16:01.951504Z","end":"2026-10-16T00:16:02.402428Z","steps":["trace[2071555202] 'read index received' (duration: 182.688017ms)","trace[2071555202] 'applied index is now lower than readState.Index' (duration: 268.235324ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:16:02.402547Z","caller":"traceutil/trace.go:171","msg":"trace[1572602045] transaction","detail":"{read_only:false; response_revision:5185; number_of_response:1; }","duration":"385.599181ms","start":"2026-10-16T00:16:02.016937Z","end":"2026-10-16T00:16:02.402537Z","steps":["trace[1572602045] 'process raft request' (duration: 385.449019ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:16:02.40255Z","caller":"traceutil/trace.go:171","msg":"trace[1487824512] transaction","detail":"{read_only:false; response_revision:5184; number_of_response:1; }","duration":"451.428411ms","start":"2026-10-16T00:16:01.951093Z","end":"2026-10-16T00:16:02.402521Z","steps":["trace[1487824512] 'process raft request' (duration: 451.209947ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:16:02.402614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:16:02.016917Z","time spent":"385.660553ms","remote":"127.0.0.1:55220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T00:16:02.402726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:16:01.951076Z","time spent":"451.543503ms","remote":"127.0.0.1:55220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T00:16:02.402897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.051808ms","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-16T00:16:02.402972Z","caller":"traceutil/trace.go:171","msg":"trace[1846006039] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5185; }","duration":"437.158961ms","start":"2026-10-16T00:16:01.965796Z","end":"2026-10-16T00:16:02.402955Z","steps":["trace[1846006039] 'agreement among raft nodes before linearized reading' (duration: 436.928217ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:16:02.403022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.951559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-10-16T00:16:02.403033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:16:01.965779Z","time spent":"437.220822ms","remote":"127.0.0.1:55220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-16T00:16:02.403044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.850475ms","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-16T00:16:02.40306Z","caller":"traceutil/trace.go:171","msg":"trace[1407353858] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5185; }","duration":"436.010221ms","start":"2026-10-16T00:16:01.967039Z","end":"2026-10-16T00:16:02.403049Z","steps":["trace[1407353858] 'agreement among raft nodes before linearized reading' (duration: 435.885948ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:16:02.403092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:16:01.967027Z","time spent":"436.058571ms","remote":"127.0.0.1:55220","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-16T00:16:02.403027Z","caller":"traceutil/trace.go:171","msg":"trace[792978009] transaction","detail":"{read_only:false; response_revision:5183; number_of_response:1; }","duration":"452.565291ms","start":"2026-10-16T00:16:01.950357Z","end":"2026-10-16T00:16:02.402922Z","steps":["trace[792978009] 'process raft request' (duration: 183.821758ms)","trace[792978009] 'compare' (duration: 267.593992ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:16:02.403095Z","caller":"traceutil/trace.go:171","msg":"trace[166591096] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5185; }","duration":"380.929986ms","start":"2026-10-16T00:16:02.022149Z","end":"2026-10-16T00:16:02.403079Z","steps":["trace[166591096] 'agreement among raft nodes before linearized reading' (duration: 380.772623ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:16:02.403112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.381141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9568d730-dfe6-48a8-9a3b-69c734232ce6\" ","response":"range_response_count:1 size:3600"} {"level":"warn","ts":"2026-10-16T00:16:02.403136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:16:02.022139Z","time spent":"380.989197ms","remote":"127.0.0.1:55220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-16T00:16:02.403091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.637622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:414015"} {"level":"warn","ts":"2026-10-16T00:16:02.403109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.588373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-10-16T00:16:02.403169Z","caller":"traceutil/trace.go:171","msg":"trace[518643864] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9568d730-dfe6-48a8-9a3b-69c734232ce6; range_end:; response_count:1; response_revision:5185; }","duration":"305.495743ms","start":"2026-10-16T00:16:02.097656Z","end":"2026-10-16T00:16:02.403152Z","steps":["trace[518643864] 'agreement among raft nodes before linearized reading' (duration: 305.260578ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:16:02.403205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:16:02.097612Z","time spent":"305.584264ms","remote":"127.0.0.1:55134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3624,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9568d730-dfe6-48a8-9a3b-69c734232ce6\" "} {"level":"info","ts":"2026-10-16T00:16:02.403203Z","caller":"traceutil/trace.go:171","msg":"trace[872426381] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:5185; }","duration":"451.695855ms","start":"2026-10-16T00:16:01.95149Z","end":"2026-10-16T00:16:02.403186Z","steps":["trace[872426381] 'agreement among raft nodes before linearized reading' (duration: 451.444291ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:16:02.403248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:16:01.951441Z","time spent":"451.798187ms","remote":"127.0.0.1:55000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"info","ts":"2026-10-16T00:16:02.403276Z","caller":"traceutil/trace.go:171","msg":"trace[204839607] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:5185; }","duration":"145.837956ms","start":"2026-10-16T00:16:02.257378Z","end":"2026-10-16T00:16:02.403216Z","steps":["trace[204839607] 'agreement among raft nodes before linearized reading' (duration: 145.557121ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:16:02.407994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:16:01.950343Z","time spent":"457.540202ms","remote":"127.0.0.1:55220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:16:04.514542Z","caller":"traceutil/trace.go:171","msg":"trace[575962290] linearizableReadLoop","detail":"{readStateIndex:5419; appliedIndex:5418; }","duration":"104.114496ms","start":"2026-10-16T00:16:04.410395Z","end":"2026-10-16T00:16:04.51451Z","steps":["trace[575962290] 'read index received' (duration: 104.076595ms)","trace[575962290] 'applied index is now lower than readState.Index' (duration: 34.75µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:16:04.514553Z","caller":"traceutil/trace.go:171","msg":"trace[316970806] transaction","detail":"{read_only:false; response_revision:5217; number_of_response:1; }","duration":"106.508769ms","start":"2026-10-16T00:16:04.407984Z","end":"2026-10-16T00:16:04.514493Z","steps":["trace[316970806] 'process raft request' (duration: 106.385967ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:16:04.515162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.748507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:786905"} {"level":"info","ts":"2026-10-16T00:16:04.515227Z","caller":"traceutil/trace.go:171","msg":"trace[1351272159] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:5217; }","duration":"104.838598ms","start":"2026-10-16T00:16:04.410364Z","end":"2026-10-16T00:16:04.515203Z","steps":["trace[1351272159] 'agreement among raft nodes before linearized reading' (duration: 104.244427ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:16:04.574302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.385279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-16T00:16:04.574336Z","caller":"traceutil/trace.go:171","msg":"trace[311640993] transaction","detail":"{read_only:false; response_revision:5218; number_of_response:1; }","duration":"162.929717ms","start":"2026-10-16T00:16:04.411388Z","end":"2026-10-16T00:16:04.574318Z","steps":["trace[311640993] 'process raft request' (duration: 162.519959ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:16:04.574405Z","caller":"traceutil/trace.go:171","msg":"trace[975363791] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5218; }","duration":"161.512731ms","start":"2026-10-16T00:16:04.412868Z","end":"2026-10-16T00:16:04.574381Z","steps":["trace[975363791] 'agreement among raft nodes before linearized reading' (duration: 161.223646ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:16:16.673933Z","caller":"traceutil/trace.go:171","msg":"trace[1783727420] transaction","detail":"{read_only:false; response_revision:5440; number_of_response:1; }","duration":"107.729682ms","start":"2026-10-16T00:16:16.566178Z","end":"2026-10-16T00:16:16.673907Z","steps":["trace[1783727420] 'process raft request' (duration: 40.706702ms)","trace[1783727420] 'compare' (duration: 66.656254ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:16:25.073436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.752539ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-16T00:16:25.073581Z","caller":"traceutil/trace.go:171","msg":"trace[525907309] transaction","detail":"{read_only:false; response_revision:5607; number_of_response:1; }","duration":"212.097163ms","start":"2026-10-16T00:16:24.861458Z","end":"2026-10-16T00:16:25.073555Z","steps":["trace[525907309] 'process raft request' (duration: 80.119559ms)","trace[525907309] 'compare' (duration: 131.612407ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:16:25.319254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.015475ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-16T00:16:25.319462Z","caller":"traceutil/trace.go:171","msg":"trace[1184444649] transaction","detail":"{read_only:false; response_revision:5609; number_of_response:1; }","duration":"457.621333ms","start":"2026-10-16T00:16:24.861823Z","end":"2026-10-16T00:16:25.319444Z","steps":["trace[1184444649] 'process raft request' (duration: 457.540352ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:16:25.319554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:16:24.861794Z","time spent":"457.705735ms","remote":"127.0.0.1:55220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:16:25.319709Z","caller":"traceutil/trace.go:171","msg":"trace[1464167559] transaction","detail":"{read_only:false; response_revision:5611; number_of_response:1; }","duration":"243.704947ms","start":"2026-10-16T00:16:25.075973Z","end":"2026-10-16T00:16:25.319678Z","steps":["trace[1464167559] 'process raft request' (duration: 243.602225ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:16:25.319491Z","caller":"traceutil/trace.go:171","msg":"trace[1900622119] transaction","detail":"{read_only:false; response_revision:5608; number_of_response:1; }","duration":"457.645444ms","start":"2026-10-16T00:16:24.861775Z","end":"2026-10-16T00:16:25.319421Z","steps":["trace[1900622119] 'process raft request' (duration: 342.372524ms)","trace[1900622119] 'compare' (duration: 114.874892ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:16:25.320109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:16:24.861754Z","time spent":"458.135663ms","remote":"127.0.0.1:55220","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-16T00:16:25.320177Z","caller":"traceutil/trace.go:171","msg":"trace[1353075655] transaction","detail":"{read_only:false; response_revision:5610; number_of_response:1; }","duration":"457.521292ms","start":"2026-10-16T00:16:24.862618Z","end":"2026-10-16T00:16:25.320139Z","steps":["trace[1353075655] 'process raft request' (duration: 456.795628ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:16:25.320197Z","caller":"traceutil/trace.go:171","msg":"trace[2074547470] linearizableReadLoop","detail":"{readStateIndex:5824; appliedIndex:5820; }","duration":"435.49793ms","start":"2026-10-16T00:16:24.884667Z","end":"2026-10-16T00:16:25.320165Z","steps":["trace[2074547470] 'read index received' (duration: 56.900806ms)","trace[2074547470] 'applied index is now lower than readState.Index' (duration: 378.593684ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:16:25.320358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:16:24.862605Z","time spent":"457.659023ms","remote":"127.0.0.1:55220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T00:16:25.320465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.602578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-16T00:16:25.320507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.524526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-16T00:16:25.32052Z","caller":"traceutil/trace.go:171","msg":"trace[1119844562] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:5611; }","duration":"231.682529ms","start":"2026-10-16T00:16:25.088821Z","end":"2026-10-16T00:16:25.320503Z","steps":["trace[1119844562] 'agreement among raft nodes before linearized reading' (duration: 231.592037ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:16:25.320556Z","caller":"traceutil/trace.go:171","msg":"trace[934371957] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:5611; }","duration":"164.601487ms","start":"2026-10-16T00:16:25.15594Z","end":"2026-10-16T00:16:25.320542Z","steps":["trace[934371957] 'agreement among raft nodes before linearized reading' (duration: 164.462465ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:16:25.320723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.00982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3684"} {"level":"info","ts":"2026-10-16T00:16:25.320862Z","caller":"traceutil/trace.go:171","msg":"trace[605229441] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:5611; }","duration":"436.239534ms","start":"2026-10-16T00:16:24.884591Z","end":"2026-10-16T00:16:25.320831Z","steps":["trace[605229441] 'agreement among raft nodes before linearized reading' (duration: 435.669384ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:16:25.320901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.683318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-16T00:16:25.32093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:16:24.884572Z","time spent":"436.340825ms","remote":"127.0.0.1:55052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":3708,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" "} {"level":"info","ts":"2026-10-16T00:16:25.320938Z","caller":"traceutil/trace.go:171","msg":"trace[2102730709] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5611; }","duration":"382.75714ms","start":"2026-10-16T00:16:24.93817Z","end":"2026-10-16T00:16:25.320927Z","steps":["trace[2102730709] 'agreement among raft nodes before linearized reading' (duration: 382.162139ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:16:25.320975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:16:24.938141Z","time spent":"382.82686ms","remote":"127.0.0.1:54960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-16T00:16:25.585853Z","caller":"traceutil/trace.go:171","msg":"trace[796597904] linearizableReadLoop","detail":"{readStateIndex:5826; appliedIndex:5825; }","duration":"245.010342ms","start":"2026-10-16T00:16:25.340815Z","end":"2026-10-16T00:16:25.585825Z","steps":["trace[796597904] 'read index received' (duration: 176.326761ms)","trace[796597904] 'applied index is now lower than readState.Index' (duration: 68.682321ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:16:25.586066Z","caller":"traceutil/trace.go:171","msg":"trace[1853566209] transaction","detail":"{read_only:false; response_revision:5612; number_of_response:1; }","duration":"247.219132ms","start":"2026-10-16T00:16:25.338809Z","end":"2026-10-16T00:16:25.586028Z","steps":["trace[1853566209] 'process raft request' (duration: 178.266486ms)","trace[1853566209] 'compare' (duration: 68.62912ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:16:25.586301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.463339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config\" ","response":"range_response_count:1 size:696"} {"level":"info","ts":"2026-10-16T00:16:25.586386Z","caller":"traceutil/trace.go:171","msg":"trace[1633468346] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config; range_end:; response_count:1; response_revision:5612; }","duration":"245.583852ms","start":"2026-10-16T00:16:25.340783Z","end":"2026-10-16T00:16:25.586367Z","steps":["trace[1633468346] 'agreement among raft nodes before linearized reading' (duration: 245.339568ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:16:25.588603Z","caller":"traceutil/trace.go:171","msg":"trace[1409197603] transaction","detail":"{read_only:false; response_revision:5613; number_of_response:1; }","duration":"238.722147ms","start":"2026-10-16T00:16:25.349852Z","end":"2026-10-16T00:16:25.588574Z","steps":["trace[1409197603] 'process raft request' (duration: 238.515383ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:16:25.588724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.997287ms","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-16T00:16:25.58879Z","caller":"traceutil/trace.go:171","msg":"trace[699174695] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5613; }","duration":"231.095048ms","start":"2026-10-16T00:16:25.357678Z","end":"2026-10-16T00:16:25.588773Z","steps":["trace[699174695] 'agreement among raft nodes before linearized reading' (duration: 230.858734ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:16:25.588794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.781249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-m9vpq\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-10-16T00:16:25.58888Z","caller":"traceutil/trace.go:171","msg":"trace[171981150] range","detail":"{range_begin:/registry/pods/openstack/keepalived-m9vpq; range_end:; response_count:1; response_revision:5613; }","duration":"176.905242ms","start":"2026-10-16T00:16:25.411929Z","end":"2026-10-16T00:16:25.588834Z","steps":["trace[171981150] 'agreement among raft nodes before linearized reading' (duration: 176.677597ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:16:40.536829Z","caller":"traceutil/trace.go:171","msg":"trace[1492273173] transaction","detail":"{read_only:false; response_revision:5789; number_of_response:1; }","duration":"205.699915ms","start":"2026-10-16T00:16:40.331106Z","end":"2026-10-16T00:16:40.536806Z","steps":["trace[1492273173] 'process raft request' (duration: 205.370539ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:16:40.663089Z","caller":"traceutil/trace.go:171","msg":"trace[517683709] transaction","detail":"{read_only:false; response_revision:5790; number_of_response:1; }","duration":"113.593318ms","start":"2026-10-16T00:16:40.549465Z","end":"2026-10-16T00:16:40.663058Z","steps":["trace[517683709] 'process raft request' (duration: 70.763208ms)","trace[517683709] 'compare' (duration: 42.658207ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:16:40.802324Z","caller":"traceutil/trace.go:171","msg":"trace[741026699] transaction","detail":"{read_only:false; response_revision:5794; number_of_response:1; }","duration":"128.798035ms","start":"2026-10-16T00:16:40.673501Z","end":"2026-10-16T00:16:40.802299Z","steps":["trace[741026699] 'process raft request' (duration: 128.740404ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:16:40.802596Z","caller":"traceutil/trace.go:171","msg":"trace[851568221] transaction","detail":"{read_only:false; response_revision:5792; number_of_response:1; }","duration":"130.069628ms","start":"2026-10-16T00:16:40.672455Z","end":"2026-10-16T00:16:40.802524Z","steps":["trace[851568221] 'process raft request' (duration: 108.642339ms)","trace[851568221] 'compare' (duration: 20.91253ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:16:40.802854Z","caller":"traceutil/trace.go:171","msg":"trace[825012090] transaction","detail":"{read_only:false; response_revision:5793; number_of_response:1; }","duration":"129.514509ms","start":"2026-10-16T00:16:40.673323Z","end":"2026-10-16T00:16:40.802837Z","steps":["trace[825012090] 'process raft request' (duration: 128.821126ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:16:40.955286Z","caller":"traceutil/trace.go:171","msg":"trace[1065534597] transaction","detail":"{read_only:false; response_revision:5795; number_of_response:1; }","duration":"108.064718ms","start":"2026-10-16T00:16:40.847198Z","end":"2026-10-16T00:16:40.955263Z","steps":["trace[1065534597] 'process raft request' (duration: 87.85163ms)","trace[1065534597] 'compare' (duration: 20.061296ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:16:48.656508Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2935} {"level":"info","ts":"2026-10-16T00:16:48.732296Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2935,"took":"74.325234ms","hash":3070022990,"current-db-size-bytes":55361536,"current-db-size":"55 MB","current-db-size-in-use-bytes":32186368,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-16T00:16:48.732356Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3070022990,"revision":2935,"compact-revision":-1} {"level":"info","ts":"2026-10-16T00:21:48.663598Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5850} {"level":"info","ts":"2026-10-16T00:21:48.753713Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5850,"took":"88.390208ms","hash":1424117507,"current-db-size-bytes":55361536,"current-db-size":"55 MB","current-db-size-in-use-bytes":25481216,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-16T00:21:48.753792Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1424117507,"revision":5850,"compact-revision":2935} {"level":"info","ts":"2026-10-16T00:22:29.623339Z","caller":"traceutil/trace.go:171","msg":"trace[1933998713] linearizableReadLoop","detail":"{readStateIndex:8609; appliedIndex:8608; }","duration":"112.512731ms","start":"2026-10-16T00:22:29.510804Z","end":"2026-10-16T00:22:29.623317Z","steps":["trace[1933998713] 'read index received' (duration: 112.270647ms)","trace[1933998713] 'applied index is now lower than readState.Index' (duration: 241.184µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:22:29.62338Z","caller":"traceutil/trace.go:171","msg":"trace[1544197158] transaction","detail":"{read_only:false; response_revision:8306; number_of_response:1; }","duration":"131.235275ms","start":"2026-10-16T00:22:29.492122Z","end":"2026-10-16T00:22:29.623357Z","steps":["trace[1544197158] 'process raft request' (duration: 131.059202ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:22:29.62355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.726035ms","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-16T00:22:29.623614Z","caller":"traceutil/trace.go:171","msg":"trace[1660723583] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8306; }","duration":"112.822947ms","start":"2026-10-16T00:22:29.510761Z","end":"2026-10-16T00:22:29.623584Z","steps":["trace[1660723583] 'agreement among raft nodes before linearized reading' (duration: 112.683875ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:22:38.059301Z","caller":"traceutil/trace.go:171","msg":"trace[98104467] linearizableReadLoop","detail":"{readStateIndex:8786; appliedIndex:8785; }","duration":"292.922865ms","start":"2026-10-16T00:22:37.766356Z","end":"2026-10-16T00:22:38.059279Z","steps":["trace[98104467] 'read index received' (duration: 292.786933ms)","trace[98104467] 'applied index is now lower than readState.Index' (duration: 134.712µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:22:38.059388Z","caller":"traceutil/trace.go:171","msg":"trace[121786034] transaction","detail":"{read_only:false; response_revision:8479; number_of_response:1; }","duration":"397.190305ms","start":"2026-10-16T00:22:37.662171Z","end":"2026-10-16T00:22:38.059361Z","steps":["trace[121786034] 'process raft request' (duration: 396.96724ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:22:38.059466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.718266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:22:38.059502Z","caller":"traceutil/trace.go:171","msg":"trace[2106779741] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:8479; }","duration":"289.790177ms","start":"2026-10-16T00:22:37.769702Z","end":"2026-10-16T00:22:38.059492Z","steps":["trace[2106779741] 'agreement among raft nodes before linearized reading' (duration: 289.711686ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:22:38.059566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.18196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-10-16T00:22:38.059587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.709551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:22:38.059665Z","caller":"traceutil/trace.go:171","msg":"trace[2059719651] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8479; }","duration":"293.334042ms","start":"2026-10-16T00:22:37.766312Z","end":"2026-10-16T00:22:38.059646Z","steps":["trace[2059719651] 'agreement among raft nodes before linearized reading' (duration: 293.078897ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:22:38.059688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.645915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-m9vpq\" ","response":"range_response_count:1 size:6526"} {"level":"warn","ts":"2026-10-16T00:22:38.059676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.318244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:22:38.05972Z","caller":"traceutil/trace.go:171","msg":"trace[55543736] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8479; }","duration":"120.841683ms","start":"2026-10-16T00:22:37.938821Z","end":"2026-10-16T00:22:38.059663Z","steps":["trace[55543736] 'agreement among raft nodes before linearized reading' (duration: 120.66534ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:22:38.059783Z","caller":"traceutil/trace.go:171","msg":"trace[1925156133] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:8479; }","duration":"161.489297ms","start":"2026-10-16T00:22:37.898272Z","end":"2026-10-16T00:22:38.059761Z","steps":["trace[1925156133] 'agreement among raft nodes before linearized reading' (duration: 161.300233ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:22:38.059724Z","caller":"traceutil/trace.go:171","msg":"trace[2049516859] range","detail":"{range_begin:/registry/pods/openstack/keepalived-m9vpq; range_end:; response_count:1; response_revision:8479; }","duration":"271.697686ms","start":"2026-10-16T00:22:37.788015Z","end":"2026-10-16T00:22:38.059712Z","steps":["trace[2049516859] 'agreement among raft nodes before linearized reading' (duration: 271.560784ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:22:38.059521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:22:37.662147Z","time spent":"397.302166ms","remote":"127.0.0.1:55220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:22:38.257468Z","caller":"traceutil/trace.go:171","msg":"trace[1109277339] transaction","detail":"{read_only:false; response_revision:8480; number_of_response:1; }","duration":"191.191502ms","start":"2026-10-16T00:22:38.066251Z","end":"2026-10-16T00:22:38.257442Z","steps":["trace[1109277339] 'process raft request' (duration: 189.588663ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:22:49.135724Z","caller":"traceutil/trace.go:171","msg":"trace[2136299145] transaction","detail":"{read_only:false; response_revision:8536; number_of_response:1; }","duration":"112.216745ms","start":"2026-10-16T00:22:49.023483Z","end":"2026-10-16T00:22:49.1357Z","steps":["trace[2136299145] 'process raft request' (duration: 112.067922ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:22:49.582564Z","caller":"traceutil/trace.go:171","msg":"trace[1552363357] transaction","detail":"{read_only:false; response_revision:8538; number_of_response:1; }","duration":"223.050465ms","start":"2026-10-16T00:22:49.359476Z","end":"2026-10-16T00:22:49.582527Z","steps":["trace[1552363357] 'process raft request' (duration: 222.866151ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:22:49.917764Z","caller":"traceutil/trace.go:171","msg":"trace[1085200425] linearizableReadLoop","detail":"{readStateIndex:8849; appliedIndex:8847; }","duration":"496.423232ms","start":"2026-10-16T00:22:49.421307Z","end":"2026-10-16T00:22:49.91773Z","steps":["trace[1085200425] 'read index received' (duration: 161.168702ms)","trace[1085200425] 'applied index is now lower than readState.Index' (duration: 335.25316ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:22:49.917982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.656836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-10-16T00:22:49.918017Z","caller":"traceutil/trace.go:171","msg":"trace[1035959321] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8539; }","duration":"496.732328ms","start":"2026-10-16T00:22:49.421271Z","end":"2026-10-16T00:22:49.918003Z","steps":["trace[1035959321] 'agreement among raft nodes before linearized reading' (duration: 496.561255ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:22:49.918046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:22:49.421259Z","time spent":"496.780189ms","remote":"127.0.0.1:55220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-10-16T00:22:49.918256Z","caller":"traceutil/trace.go:171","msg":"trace[1300475413] transaction","detail":"{read_only:false; response_revision:8539; number_of_response:1; }","duration":"551.180315ms","start":"2026-10-16T00:22:49.367039Z","end":"2026-10-16T00:22:49.918219Z","steps":["trace[1300475413] 'process raft request' (duration: 519.384213ms)","trace[1300475413] 'compare' (duration: 30.857846ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:22:49.91843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:22:49.367022Z","time spent":"551.307727ms","remote":"127.0.0.1:55220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:22:50.452922Z","caller":"traceutil/trace.go:171","msg":"trace[697795117] transaction","detail":"{read_only:false; response_revision:8543; number_of_response:1; }","duration":"103.527566ms","start":"2026-10-16T00:22:50.349363Z","end":"2026-10-16T00:22:50.452891Z","steps":["trace[697795117] 'process raft request' (duration: 103.381143ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:22:50.76526Z","caller":"traceutil/trace.go:171","msg":"trace[1729562084] transaction","detail":"{read_only:false; response_revision:8544; number_of_response:1; }","duration":"380.016741ms","start":"2026-10-16T00:22:50.385206Z","end":"2026-10-16T00:22:50.765223Z","steps":["trace[1729562084] 'process raft request' (duration: 281.124779ms)","trace[1729562084] 'compare' (duration: 98.627627ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:22:50.765422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:22:50.385176Z","time spent":"380.153224ms","remote":"127.0.0.1:55220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:22:51.293929Z","caller":"traceutil/trace.go:171","msg":"trace[1860785848] transaction","detail":"{read_only:false; response_revision:8547; number_of_response:1; }","duration":"145.631777ms","start":"2026-10-16T00:22:51.148268Z","end":"2026-10-16T00:22:51.293899Z","steps":["trace[1860785848] 'process raft request' (duration: 123.486752ms)","trace[1860785848] 'compare' (duration: 22.019023ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:23:17.044067Z","caller":"traceutil/trace.go:171","msg":"trace[635811828] linearizableReadLoop","detail":"{readStateIndex:9056; appliedIndex:9055; }","duration":"106.156475ms","start":"2026-10-16T00:23:16.937878Z","end":"2026-10-16T00:23:17.044035Z","steps":["trace[635811828] 'read index received' (duration: 102.132691ms)","trace[635811828] 'applied index is now lower than readState.Index' (duration: 4.021874ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:23:17.044343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.228036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-16T00:23:17.044431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.487941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:20181"} {"level":"info","ts":"2026-10-16T00:23:17.044415Z","caller":"traceutil/trace.go:171","msg":"trace[171200754] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8738; }","duration":"106.345388ms","start":"2026-10-16T00:23:16.93805Z","end":"2026-10-16T00:23:17.044396Z","steps":["trace[171200754] 'agreement among raft nodes before linearized reading' (duration: 106.223595ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:23:17.044559Z","caller":"traceutil/trace.go:171","msg":"trace[1384224411] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:8738; }","duration":"106.704714ms","start":"2026-10-16T00:23:16.937833Z","end":"2026-10-16T00:23:17.044475Z","steps":["trace[1384224411] 'agreement among raft nodes before linearized reading' (duration: 106.403119ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:23:20.05448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.011707ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-16T00:23:20.054575Z","caller":"traceutil/trace.go:171","msg":"trace[1478523048] linearizableReadLoop","detail":"{readStateIndex:9073; appliedIndex:9072; }","duration":"281.625058ms","start":"2026-10-16T00:23:19.772933Z","end":"2026-10-16T00:23:20.054558Z","steps":["trace[1478523048] 'read index received' (duration: 76.601µs)","trace[1478523048] 'applied index is now lower than readState.Index' (duration: 281.547147ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:23:20.05501Z","caller":"traceutil/trace.go:171","msg":"trace[217064805] transaction","detail":"{read_only:false; response_revision:8755; number_of_response:1; }","duration":"651.077485ms","start":"2026-10-16T00:23:19.403917Z","end":"2026-10-16T00:23:20.054995Z","steps":["trace[217064805] 'process raft request' (duration: 357.459517ms)","trace[217064805] 'compare' (duration: 292.551329ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:23:20.055087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:23:19.403895Z","time spent":"651.155006ms","remote":"127.0.0.1:55220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T00:23:20.055125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.349721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-16T00:23:20.055144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.441508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-16T00:23:20.055209Z","caller":"traceutil/trace.go:171","msg":"trace[1637775001] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8755; }","duration":"116.469532ms","start":"2026-10-16T00:23:19.938723Z","end":"2026-10-16T00:23:20.055192Z","steps":["trace[1637775001] 'agreement among raft nodes before linearized reading' (duration: 116.34324ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:23:20.05523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.26882ms","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-16T00:23:20.055312Z","caller":"traceutil/trace.go:171","msg":"trace[185800984] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8755; }","duration":"282.399612ms","start":"2026-10-16T00:23:19.772893Z","end":"2026-10-16T00:23:20.055292Z","steps":["trace[185800984] 'agreement among raft nodes before linearized reading' (duration: 282.157398ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:23:20.055252Z","caller":"traceutil/trace.go:171","msg":"trace[71760496] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:8755; }","duration":"172.598561ms","start":"2026-10-16T00:23:19.882634Z","end":"2026-10-16T00:23:20.055233Z","steps":["trace[71760496] 'agreement among raft nodes before linearized reading' (duration: 172.406088ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:23:26.582193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.878012ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-16T00:23:26.582321Z","caller":"traceutil/trace.go:171","msg":"trace[13067046] transaction","detail":"{read_only:false; response_revision:8789; number_of_response:1; }","duration":"433.483349ms","start":"2026-10-16T00:23:26.148817Z","end":"2026-10-16T00:23:26.5823Z","steps":["trace[13067046] 'process raft request' (duration: 299.377093ms)","trace[13067046] 'compare' (duration: 133.554796ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:23:26.582406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:23:26.1488Z","time spent":"433.565321ms","remote":"127.0.0.1:55220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:23:26.586104Z","caller":"traceutil/trace.go:171","msg":"trace[1276083549] linearizableReadLoop","detail":"{readStateIndex:9110; appliedIndex:9107; }","duration":"362.396596ms","start":"2026-10-16T00:23:26.223687Z","end":"2026-10-16T00:23:26.586083Z","steps":["trace[1276083549] 'read index received' (duration: 224.644755ms)","trace[1276083549] 'applied index is now lower than readState.Index' (duration: 137.750851ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:23:26.586136Z","caller":"traceutil/trace.go:171","msg":"trace[999878781] transaction","detail":"{read_only:false; response_revision:8791; number_of_response:1; }","duration":"434.741612ms","start":"2026-10-16T00:23:26.151372Z","end":"2026-10-16T00:23:26.586114Z","steps":["trace[999878781] 'process raft request' (duration: 434.61738ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:23:26.586242Z","caller":"traceutil/trace.go:171","msg":"trace[1485303429] transaction","detail":"{read_only:false; response_revision:8792; number_of_response:1; }","duration":"317.659047ms","start":"2026-10-16T00:23:26.268573Z","end":"2026-10-16T00:23:26.586232Z","steps":["trace[1485303429] 'process raft request' (duration: 317.454813ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:23:26.586248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:23:26.151359Z","time spent":"434.844044ms","remote":"127.0.0.1:55220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T00:23:26.586294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.479845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-16T00:23:26.58634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:23:26.268548Z","time spent":"317.722809ms","remote":"127.0.0.1:55220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:23:26.586348Z","caller":"traceutil/trace.go:171","msg":"trace[913213874] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:8792; }","duration":"299.596437ms","start":"2026-10-16T00:23:26.286738Z","end":"2026-10-16T00:23:26.586334Z","steps":["trace[913213874] 'agreement among raft nodes before linearized reading' (duration: 299.502085ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:23:26.586376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.424611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-m9vpq\" ","response":"range_response_count:1 size:6526"} {"level":"warn","ts":"2026-10-16T00:23:26.586409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.9594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-10-16T00:23:26.586426Z","caller":"traceutil/trace.go:171","msg":"trace[717344843] range","detail":"{range_begin:/registry/pods/openstack/keepalived-m9vpq; range_end:; response_count:1; response_revision:8792; }","duration":"188.515022ms","start":"2026-10-16T00:23:26.397898Z","end":"2026-10-16T00:23:26.586413Z","steps":["trace[717344843] 'agreement among raft nodes before linearized reading' (duration: 188.380289ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:23:26.586463Z","caller":"traceutil/trace.go:171","msg":"trace[2001724395] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8792; }","duration":"112.054682ms","start":"2026-10-16T00:23:26.474394Z","end":"2026-10-16T00:23:26.586448Z","steps":["trace[2001724395] 'agreement among raft nodes before linearized reading' (duration: 111.879949ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:23:26.586581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.900596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-54cc6f6d5-25vmq.18dedaaedfc2ea47\" ","response":"range_response_count:1 size:1131"} {"level":"info","ts":"2026-10-16T00:23:26.586623Z","caller":"traceutil/trace.go:171","msg":"trace[777491304] range","detail":"{range_begin:/registry/events/openstack/barbican-api-54cc6f6d5-25vmq.18dedaaedfc2ea47; range_end:; response_count:1; response_revision:8792; }","duration":"362.966617ms","start":"2026-10-16T00:23:26.223644Z","end":"2026-10-16T00:23:26.58661Z","steps":["trace[777491304] 'agreement among raft nodes before linearized reading' (duration: 362.843605ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:23:26.586669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:23:26.223622Z","time spent":"363.036108ms","remote":"127.0.0.1:55038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":1155,"request content":"key:\"/registry/events/openstack/barbican-api-54cc6f6d5-25vmq.18dedaaedfc2ea47\" "} {"level":"info","ts":"2026-10-16T00:23:26.586673Z","caller":"traceutil/trace.go:171","msg":"trace[651017548] transaction","detail":"{read_only:false; response_revision:8790; number_of_response:1; }","duration":"437.450392ms","start":"2026-10-16T00:23:26.149207Z","end":"2026-10-16T00:23:26.586657Z","steps":["trace[651017548] 'process raft request' (duration: 436.574897ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:23:26.586746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:23:26.149124Z","time spent":"437.583585ms","remote":"127.0.0.1:55220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:23:26.774902Z","caller":"traceutil/trace.go:171","msg":"trace[1107078931] linearizableReadLoop","detail":"{readStateIndex:9112; appliedIndex:9111; }","duration":"183.763575ms","start":"2026-10-16T00:23:26.591114Z","end":"2026-10-16T00:23:26.774877Z","steps":["trace[1107078931] 'read index received' (duration: 93.994861ms)","trace[1107078931] 'applied index is now lower than readState.Index' (duration: 89.767704ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:23:26.775221Z","caller":"traceutil/trace.go:171","msg":"trace[290672856] transaction","detail":"{read_only:false; response_revision:8793; number_of_response:1; }","duration":"185.508348ms","start":"2026-10-16T00:23:26.589688Z","end":"2026-10-16T00:23:26.775196Z","steps":["trace[290672856] 'process raft request' (duration: 95.407468ms)","trace[290672856] 'compare' (duration: 89.59718ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:23:26.775737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.60489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228490"} {"level":"info","ts":"2026-10-16T00:23:26.775785Z","caller":"traceutil/trace.go:171","msg":"trace[2057942442] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8793; }","duration":"184.684933ms","start":"2026-10-16T00:23:26.591088Z","end":"2026-10-16T00:23:26.775773Z","steps":["trace[2057942442] 'agreement among raft nodes before linearized reading' (duration: 184.309656ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:23:26.784885Z","caller":"traceutil/trace.go:171","msg":"trace[566210132] transaction","detail":"{read_only:false; response_revision:8794; number_of_response:1; }","duration":"121.871532ms","start":"2026-10-16T00:23:26.662992Z","end":"2026-10-16T00:23:26.784863Z","steps":["trace[566210132] 'process raft request' (duration: 121.660288ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:23:26.784968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.788581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-10-16T00:23:26.785045Z","caller":"traceutil/trace.go:171","msg":"trace[1020023251] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:8794; }","duration":"192.901313ms","start":"2026-10-16T00:23:26.592126Z","end":"2026-10-16T00:23:26.785027Z","steps":["trace[1020023251] 'agreement among raft nodes before linearized reading' (duration: 192.663998ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:23:34.107592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.84057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-jt5wx\" ","response":"range_response_count:1 size:4644"} {"level":"info","ts":"2026-10-16T00:23:34.10769Z","caller":"traceutil/trace.go:171","msg":"trace[369590230] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-jt5wx; range_end:; response_count:1; response_revision:8869; }","duration":"134.951362ms","start":"2026-10-16T00:23:33.972713Z","end":"2026-10-16T00:23:34.107665Z","steps":["trace[369590230] 'range keys from in-memory index tree' (duration: 134.694267ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:23:34.10768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.097828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:23:34.107754Z","caller":"traceutil/trace.go:171","msg":"trace[1292041726] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8869; }","duration":"169.21476ms","start":"2026-10-16T00:23:33.938521Z","end":"2026-10-16T00:23:34.107736Z","steps":["trace[1292041726] 'range keys from in-memory index tree' (duration: 169.001646ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:23:34.117687Z","caller":"traceutil/trace.go:171","msg":"trace[1424688673] transaction","detail":"{read_only:false; response_revision:8870; number_of_response:1; }","duration":"144.299533ms","start":"2026-10-16T00:23:33.973318Z","end":"2026-10-16T00:23:34.117617Z","steps":["trace[1424688673] 'process raft request' (duration: 144.068168ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:23:34.933066Z","caller":"traceutil/trace.go:171","msg":"trace[447426540] transaction","detail":"{read_only:false; response_revision:8875; number_of_response:1; }","duration":"229.246139ms","start":"2026-10-16T00:23:34.70379Z","end":"2026-10-16T00:23:34.933036Z","steps":["trace[447426540] 'process raft request' (duration: 154.412278ms)","trace[447426540] 'compare' (duration: 74.713148ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:23:34.942708Z","caller":"traceutil/trace.go:171","msg":"trace[76291864] linearizableReadLoop","detail":"{readStateIndex:9203; appliedIndex:9198; }","duration":"109.353912ms","start":"2026-10-16T00:23:34.833314Z","end":"2026-10-16T00:23:34.942667Z","steps":["trace[76291864] 'read index received' (duration: 24.903497ms)","trace[76291864] 'applied index is now lower than readState.Index' (duration: 84.448235ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:23:34.942801Z","caller":"traceutil/trace.go:171","msg":"trace[913903770] transaction","detail":"{read_only:false; response_revision:8878; number_of_response:1; }","duration":"185.726251ms","start":"2026-10-16T00:23:34.757055Z","end":"2026-10-16T00:23:34.942781Z","steps":["trace[913903770] 'process raft request' (duration: 185.465496ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:23:34.942887Z","caller":"traceutil/trace.go:171","msg":"trace[2026372905] transaction","detail":"{read_only:false; response_revision:8879; number_of_response:1; }","duration":"123.98137ms","start":"2026-10-16T00:23:34.81887Z","end":"2026-10-16T00:23:34.942852Z","steps":["trace[2026372905] 'process raft request' (duration: 123.736185ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:23:34.942947Z","caller":"traceutil/trace.go:171","msg":"trace[1256402902] transaction","detail":"{read_only:false; response_revision:8876; number_of_response:1; }","duration":"216.072318ms","start":"2026-10-16T00:23:34.726865Z","end":"2026-10-16T00:23:34.942937Z","steps":["trace[1256402902] 'process raft request' (duration: 215.337245ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:23:34.942882Z","caller":"traceutil/trace.go:171","msg":"trace[921719136] transaction","detail":"{read_only:false; response_revision:8877; number_of_response:1; }","duration":"215.63617ms","start":"2026-10-16T00:23:34.727215Z","end":"2026-10-16T00:23:34.942851Z","steps":["trace[921719136] 'process raft request' (duration: 215.222812ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:23:34.943083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.73077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-m9vpq\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-10-16T00:23:34.943136Z","caller":"traceutil/trace.go:171","msg":"trace[319112317] range","detail":"{range_begin:/registry/pods/openstack/keepalived-m9vpq; range_end:; response_count:1; response_revision:8879; }","duration":"109.817152ms","start":"2026-10-16T00:23:34.833305Z","end":"2026-10-16T00:23:34.943122Z","steps":["trace[319112317] 'agreement among raft nodes before linearized reading' (duration: 109.584168ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:23:35.157611Z","caller":"traceutil/trace.go:171","msg":"trace[1850430192] transaction","detail":"{read_only:false; response_revision:8880; number_of_response:1; }","duration":"178.733065ms","start":"2026-10-16T00:23:34.978848Z","end":"2026-10-16T00:23:35.157581Z","steps":["trace[1850430192] 'process raft request' (duration: 100.440701ms)","trace[1850430192] 'compare' (duration: 78.170111ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:23:35.385051Z","caller":"traceutil/trace.go:171","msg":"trace[301630787] transaction","detail":"{read_only:false; response_revision:8883; number_of_response:1; }","duration":"120.07741ms","start":"2026-10-16T00:23:35.26494Z","end":"2026-10-16T00:23:35.385018Z","steps":["trace[301630787] 'process raft request' (duration: 119.891196ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:23:51.820468Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000248d.wal"} {"level":"info","ts":"2026-10-16T00:24:41.211894Z","caller":"traceutil/trace.go:171","msg":"trace[2012180527] transaction","detail":"{read_only:false; response_revision:9563; number_of_response:1; }","duration":"100.021337ms","start":"2026-10-16T00:24:41.111845Z","end":"2026-10-16T00:24:41.211867Z","steps":["trace[2012180527] 'process raft request' (duration: 98.535379ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:24:56.229898Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ded76e421897a43d","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-16T00:24:56.236722Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-16T00:24:56.236827Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-16T00:26:48.672275Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8016} {"level":"info","ts":"2026-10-16T00:26:48.74158Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8016,"took":"66.690059ms","hash":1318479843,"current-db-size-bytes":55361536,"current-db-size":"55 MB","current-db-size-in-use-bytes":28639232,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-16T00:26:48.741627Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1318479843,"revision":8016,"compact-revision":5850} {"level":"info","ts":"2026-10-16T00:31:48.680328Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10458} {"level":"info","ts":"2026-10-16T00:31:48.766566Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10458,"took":"83.76929ms","hash":1488165209,"current-db-size-bytes":55361536,"current-db-size":"55 MB","current-db-size-in-use-bytes":27394048,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-16T00:31:48.766754Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1488165209,"revision":10458,"compact-revision":8016} {"level":"info","ts":"2026-10-16T00:34:26.786101Z","caller":"traceutil/trace.go:171","msg":"trace[949232884] transaction","detail":"{read_only:false; response_revision:13861; number_of_response:1; }","duration":"134.491726ms","start":"2026-10-16T00:34:26.651585Z","end":"2026-10-16T00:34:26.786076Z","steps":["trace[949232884] 'process raft request' (duration: 134.324564ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:34:26.786856Z","caller":"traceutil/trace.go:171","msg":"trace[1054532800] linearizableReadLoop","detail":"{readStateIndex:14343; appliedIndex:14341; }","duration":"109.858617ms","start":"2026-10-16T00:34:26.676974Z","end":"2026-10-16T00:34:26.786833Z","steps":["trace[1054532800] 'read index received' (duration: 108.926801ms)","trace[1054532800] 'applied index is now lower than readState.Index' (duration: 930.716µs)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:34:26.787085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.095201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-10-16T00:34:26.787125Z","caller":"traceutil/trace.go:171","msg":"trace[2111709184] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:13862; }","duration":"110.188073ms","start":"2026-10-16T00:34:26.676926Z","end":"2026-10-16T00:34:26.787114Z","steps":["trace[2111709184] 'agreement among raft nodes before linearized reading' (duration: 109.99997ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:34:26.78733Z","caller":"traceutil/trace.go:171","msg":"trace[1672139457] transaction","detail":"{read_only:false; response_revision:13862; number_of_response:1; }","duration":"135.173549ms","start":"2026-10-16T00:34:26.652139Z","end":"2026-10-16T00:34:26.787312Z","steps":["trace[1672139457] 'process raft request' (duration: 134.579938ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:34:50.054581Z","caller":"traceutil/trace.go:171","msg":"trace[906337801] transaction","detail":"{read_only:false; response_revision:13989; number_of_response:1; }","duration":"317.472891ms","start":"2026-10-16T00:34:49.737062Z","end":"2026-10-16T00:34:50.054535Z","steps":["trace[906337801] 'process raft request' (duration: 301.01811ms)","trace[906337801] 'compare' (duration: 15.425562ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:34:50.054595Z","caller":"traceutil/trace.go:171","msg":"trace[1466296743] linearizableReadLoop","detail":"{readStateIndex:14475; appliedIndex:14474; }","duration":"169.149416ms","start":"2026-10-16T00:34:49.885377Z","end":"2026-10-16T00:34:50.054526Z","steps":["trace[1466296743] 'read index received' (duration: 152.648244ms)","trace[1466296743] 'applied index is now lower than readState.Index' (duration: 16.499182ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:34:50.054756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.36438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-16T00:34:50.054803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:34:49.737035Z","time spent":"317.683315ms","remote":"127.0.0.1:55220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:34:50.054846Z","caller":"traceutil/trace.go:171","msg":"trace[1555403538] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:13989; }","duration":"169.503203ms","start":"2026-10-16T00:34:49.885326Z","end":"2026-10-16T00:34:50.054829Z","steps":["trace[1555403538] 'agreement among raft nodes before linearized reading' (duration: 169.275058ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:34:50.054918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.114286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:34:50.054959Z","caller":"traceutil/trace.go:171","msg":"trace[1697132562] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13989; }","duration":"116.207238ms","start":"2026-10-16T00:34:49.938735Z","end":"2026-10-16T00:34:50.054942Z","steps":["trace[1697132562] 'agreement among raft nodes before linearized reading' (duration: 116.102416ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:35:05.288023Z","caller":"traceutil/trace.go:171","msg":"trace[180457031] transaction","detail":"{read_only:false; response_revision:14085; number_of_response:1; }","duration":"189.368757ms","start":"2026-10-16T00:35:05.098626Z","end":"2026-10-16T00:35:05.287994Z","steps":["trace[180457031] 'process raft request' (duration: 178.184342ms)","trace[180457031] 'compare' (duration: 10.775317ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:35:05.288137Z","caller":"traceutil/trace.go:171","msg":"trace[1011336988] linearizableReadLoop","detail":"{readStateIndex:14578; appliedIndex:14575; }","duration":"187.871697ms","start":"2026-10-16T00:35:05.100244Z","end":"2026-10-16T00:35:05.288115Z","steps":["trace[1011336988] 'read index received' (duration: 176.577871ms)","trace[1011336988] 'applied index is now lower than readState.Index' (duration: 11.292936ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:35:05.288042Z","caller":"traceutil/trace.go:171","msg":"trace[2096673342] transaction","detail":"{read_only:false; response_revision:14086; number_of_response:1; }","duration":"188.642253ms","start":"2026-10-16T00:35:05.099366Z","end":"2026-10-16T00:35:05.288009Z","steps":["trace[2096673342] 'process raft request' (duration: 188.558132ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:35:05.288325Z","caller":"traceutil/trace.go:171","msg":"trace[565670363] transaction","detail":"{read_only:false; response_revision:14087; number_of_response:1; }","duration":"188.867936ms","start":"2026-10-16T00:35:05.099421Z","end":"2026-10-16T00:35:05.288289Z","steps":["trace[565670363] 'process raft request' (duration: 188.560912ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:35:05.288389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.131362ms","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-16T00:35:05.288501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.991723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-10-16T00:35:05.288531Z","caller":"traceutil/trace.go:171","msg":"trace[865953836] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14087; }","duration":"188.301846ms","start":"2026-10-16T00:35:05.100218Z","end":"2026-10-16T00:35:05.28852Z","steps":["trace[865953836] 'agreement among raft nodes before linearized reading' (duration: 188.076702ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:35:05.28856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.16129ms","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-16T00:35:05.288537Z","caller":"traceutil/trace.go:171","msg":"trace[1503470710] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14087; }","duration":"157.057575ms","start":"2026-10-16T00:35:05.131466Z","end":"2026-10-16T00:35:05.288524Z","steps":["trace[1503470710] 'agreement among raft nodes before linearized reading' (duration: 156.930142ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:35:05.288642Z","caller":"traceutil/trace.go:171","msg":"trace[1665817353] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14087; }","duration":"160.287553ms","start":"2026-10-16T00:35:05.128335Z","end":"2026-10-16T00:35:05.288622Z","steps":["trace[1665817353] 'agreement among raft nodes before linearized reading' (duration: 160.061058ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:35:08.524621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.146486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:35:08.524674Z","caller":"traceutil/trace.go:171","msg":"trace[650607894] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:14105; }","duration":"210.237627ms","start":"2026-10-16T00:35:08.314423Z","end":"2026-10-16T00:35:08.524661Z","steps":["trace[650607894] 'count revisions from in-memory index tree' (duration: 210.053564ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:36:48.686276Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12732} {"level":"info","ts":"2026-10-16T00:36:48.773947Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12732,"took":"85.004306ms","hash":120963793,"current-db-size-bytes":55361536,"current-db-size":"55 MB","current-db-size-in-use-bytes":30068736,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-16T00:36:48.774105Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":120963793,"revision":12732,"compact-revision":10458} {"level":"info","ts":"2026-10-16T00:36:51.998818Z","caller":"traceutil/trace.go:171","msg":"trace[1759102134] linearizableReadLoop","detail":"{readStateIndex:15724; appliedIndex:15723; }","duration":"129.786784ms","start":"2026-10-16T00:36:51.869011Z","end":"2026-10-16T00:36:51.998798Z","steps":["trace[1759102134] 'read index received' (duration: 129.713623ms)","trace[1759102134] 'applied index is now lower than readState.Index' (duration: 71.831µs)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:36:51.999217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.191392ms","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-16T00:36:51.999297Z","caller":"traceutil/trace.go:171","msg":"trace[363083142] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15197; }","duration":"130.298174ms","start":"2026-10-16T00:36:51.86898Z","end":"2026-10-16T00:36:51.999278Z","steps":["trace[363083142] 'agreement among raft nodes before linearized reading' (duration: 129.915257ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:36:52.287715Z","caller":"traceutil/trace.go:171","msg":"trace[1571783430] linearizableReadLoop","detail":"{readStateIndex:15725; appliedIndex:15724; }","duration":"288.806445ms","start":"2026-10-16T00:36:51.998883Z","end":"2026-10-16T00:36:52.28769Z","steps":["trace[1571783430] 'read index received' (duration: 288.763974ms)","trace[1571783430] 'applied index is now lower than readState.Index' (duration: 41.261µs)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:36:52.287908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.455408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-16T00:36:52.288002Z","caller":"traceutil/trace.go:171","msg":"trace[99353278] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:15198; }","duration":"382.582131ms","start":"2026-10-16T00:36:51.905393Z","end":"2026-10-16T00:36:52.287976Z","steps":["trace[99353278] 'agreement among raft nodes before linearized reading' (duration: 382.375608ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:36:52.288052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:36:51.905382Z","time spent":"382.659693ms","remote":"127.0.0.1:55460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":4,"response size":31,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-10-16T00:36:52.288165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.702179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-16T00:36:52.288252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.970037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"warn","ts":"2026-10-16T00:36:52.288219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.881294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-10-16T00:36:52.288313Z","caller":"traceutil/trace.go:171","msg":"trace[284625895] transaction","detail":"{read_only:false; response_revision:15198; number_of_response:1; }","duration":"324.855085ms","start":"2026-10-16T00:36:51.963438Z","end":"2026-10-16T00:36:52.288293Z","steps":["trace[284625895] 'process raft request' (duration: 324.080721ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:36:52.288321Z","caller":"traceutil/trace.go:171","msg":"trace[1026474220] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15198; }","duration":"325.06693ms","start":"2026-10-16T00:36:51.963235Z","end":"2026-10-16T00:36:52.288302Z","steps":["trace[1026474220] 'agreement among raft nodes before linearized reading' (duration: 324.789594ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:36:52.288305Z","caller":"traceutil/trace.go:171","msg":"trace[590309442] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15198; }","duration":"349.868373ms","start":"2026-10-16T00:36:51.938412Z","end":"2026-10-16T00:36:52.288281Z","steps":["trace[590309442] 'agreement among raft nodes before linearized reading' (duration: 349.561117ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:36:52.288365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:36:51.963219Z","time spent":"325.138631ms","remote":"127.0.0.1:55196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5547,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"warn","ts":"2026-10-16T00:36:52.288379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:36:51.9384Z","time spent":"349.968035ms","remote":"127.0.0.1:54960","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-16T00:36:52.288367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.698211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-10-16T00:36:52.288413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:36:51.963418Z","time spent":"324.934426ms","remote":"127.0.0.1:55220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:36:52.288375Z","caller":"traceutil/trace.go:171","msg":"trace[1418813829] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:15198; }","duration":"314.069497ms","start":"2026-10-16T00:36:51.974276Z","end":"2026-10-16T00:36:52.288346Z","steps":["trace[1418813829] 'agreement among raft nodes before linearized reading' (duration: 313.623989ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:36:52.288032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.392254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-10-16T00:36:52.288465Z","caller":"traceutil/trace.go:171","msg":"trace[1603687891] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:15198; }","duration":"290.875293ms","start":"2026-10-16T00:36:51.997576Z","end":"2026-10-16T00:36:52.288452Z","steps":["trace[1603687891] 'agreement among raft nodes before linearized reading' (duration: 290.258232ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:36:52.288467Z","caller":"traceutil/trace.go:171","msg":"trace[1487414532] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15198; }","duration":"413.825453ms","start":"2026-10-16T00:36:51.874611Z","end":"2026-10-16T00:36:52.288437Z","steps":["trace[1487414532] 'agreement among raft nodes before linearized reading' (duration: 413.506107ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:36:52.288466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:36:51.974262Z","time spent":"314.19185ms","remote":"127.0.0.1:55220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-16T00:36:52.28846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.471665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-10-16T00:36:52.288509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.008191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-10-16T00:36:52.288663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.964446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:36:52.288738Z","caller":"traceutil/trace.go:171","msg":"trace[2031791706] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15198; }","duration":"332.905752ms","start":"2026-10-16T00:36:51.955814Z","end":"2026-10-16T00:36:52.288719Z","steps":["trace[2031791706] 'agreement among raft nodes before linearized reading' (duration: 331.947614ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:36:52.288655Z","caller":"traceutil/trace.go:171","msg":"trace[514266403] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15198; }","duration":"400.230995ms","start":"2026-10-16T00:36:51.888395Z","end":"2026-10-16T00:36:52.288626Z","steps":["trace[514266403] 'agreement among raft nodes before linearized reading' (duration: 399.771496ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:36:52.288823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:36:51.955802Z","time spent":"333.005264ms","remote":"127.0.0.1:55196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "} {"level":"warn","ts":"2026-10-16T00:36:52.28853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:36:51.874601Z","time spent":"413.916655ms","remote":"127.0.0.1:55220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-16T00:36:52.288576Z","caller":"traceutil/trace.go:171","msg":"trace[547691281] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15198; }","duration":"414.621108ms","start":"2026-10-16T00:36:51.873911Z","end":"2026-10-16T00:36:52.288532Z","steps":["trace[547691281] 'agreement among raft nodes before linearized reading' (duration: 414.314592ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:36:52.288891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.900216ms","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-16T00:36:52.288944Z","caller":"traceutil/trace.go:171","msg":"trace[1708286498] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15198; }","duration":"418.953398ms","start":"2026-10-16T00:36:51.869978Z","end":"2026-10-16T00:36:52.288931Z","steps":["trace[1708286498] 'agreement among raft nodes before linearized reading' (duration: 417.894909ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:36:52.288937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:36:51.873891Z","time spent":"415.033056ms","remote":"127.0.0.1:55220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-16T00:36:52.288981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:36:51.869888Z","time spent":"419.08315ms","remote":"127.0.0.1:55220","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":"warn","ts":"2026-10-16T00:36:52.289025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:36:51.888381Z","time spent":"400.466529ms","remote":"127.0.0.1:55220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-16T00:36:52.289244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.412553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-10-16T00:36:52.289304Z","caller":"traceutil/trace.go:171","msg":"trace[40762572] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15198; }","duration":"338.506065ms","start":"2026-10-16T00:36:51.950784Z","end":"2026-10-16T00:36:52.28929Z","steps":["trace[40762572] 'agreement among raft nodes before linearized reading' (duration: 337.13814ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:36:52.289335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:36:51.950768Z","time spent":"338.559526ms","remote":"127.0.0.1:55122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":692,"request content":"key:\"/registry/services/endpoints/openstack/nova-api\" "} {"level":"info","ts":"2026-10-16T00:36:52.313612Z","caller":"traceutil/trace.go:171","msg":"trace[1974169410] transaction","detail":"{read_only:false; response_revision:15200; number_of_response:1; }","duration":"310.589784ms","start":"2026-10-16T00:36:52.002993Z","end":"2026-10-16T00:36:52.313583Z","steps":["trace[1974169410] 'process raft request' (duration: 310.463122ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:36:52.313593Z","caller":"traceutil/trace.go:171","msg":"trace[467831224] transaction","detail":"{read_only:false; response_revision:15199; number_of_response:1; }","duration":"313.65813ms","start":"2026-10-16T00:36:51.999905Z","end":"2026-10-16T00:36:52.313563Z","steps":["trace[467831224] 'process raft request' (duration: 313.289813ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:36:52.313767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:36:52.002974Z","time spent":"310.702246ms","remote":"127.0.0.1:55220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T00:36:52.313837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:36:51.999889Z","time spent":"313.802113ms","remote":"127.0.0.1:55000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":119,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:36:52.459818Z","caller":"traceutil/trace.go:171","msg":"trace[275075413] transaction","detail":"{read_only:false; response_revision:15201; number_of_response:1; }","duration":"166.184481ms","start":"2026-10-16T00:36:52.293603Z","end":"2026-10-16T00:36:52.459788Z","steps":["trace[275075413] 'process raft request' (duration: 165.954037ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:36:52.460112Z","caller":"traceutil/trace.go:171","msg":"trace[1469182244] transaction","detail":"{read_only:false; response_revision:15202; number_of_response:1; }","duration":"166.317493ms","start":"2026-10-16T00:36:52.29377Z","end":"2026-10-16T00:36:52.460088Z","steps":["trace[1469182244] 'process raft request' (duration: 165.952427ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:36:52.460349Z","caller":"traceutil/trace.go:171","msg":"trace[2062399070] transaction","detail":"{read_only:false; response_revision:15203; number_of_response:1; }","duration":"166.15727ms","start":"2026-10-16T00:36:52.294168Z","end":"2026-10-16T00:36:52.460325Z","steps":["trace[2062399070] 'process raft request' (duration: 165.828904ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:36:52.460475Z","caller":"traceutil/trace.go:171","msg":"trace[1997676061] linearizableReadLoop","detail":"{readStateIndex:15732; appliedIndex:15727; }","duration":"146.934309ms","start":"2026-10-16T00:36:52.313526Z","end":"2026-10-16T00:36:52.46046Z","steps":["trace[1997676061] 'read index received' (duration: 145.944301ms)","trace[1997676061] 'applied index is now lower than readState.Index' (duration: 988.888µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:36:52.460561Z","caller":"traceutil/trace.go:171","msg":"trace[1954041679] transaction","detail":"{read_only:false; response_revision:15205; number_of_response:1; }","duration":"166.213032ms","start":"2026-10-16T00:36:52.294332Z","end":"2026-10-16T00:36:52.460545Z","steps":["trace[1954041679] 'process raft request' (duration: 166.072469ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:36:52.460557Z","caller":"traceutil/trace.go:171","msg":"trace[1627929422] transaction","detail":"{read_only:false; response_revision:15204; number_of_response:1; }","duration":"166.336134ms","start":"2026-10-16T00:36:52.294183Z","end":"2026-10-16T00:36:52.460519Z","steps":["trace[1627929422] 'process raft request' (duration: 166.10111ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:36:52.46069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.060227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-10-16T00:36:52.460737Z","caller":"traceutil/trace.go:171","msg":"trace[1911501725] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:15205; }","duration":"167.147489ms","start":"2026-10-16T00:36:52.293578Z","end":"2026-10-16T00:36:52.460725Z","steps":["trace[1911501725] 'agreement among raft nodes before linearized reading' (duration: 166.978256ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:36:52.460806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.306489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-10-16T00:36:52.460852Z","caller":"traceutil/trace.go:171","msg":"trace[984284633] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:15205; }","duration":"145.375541ms","start":"2026-10-16T00:36:52.315464Z","end":"2026-10-16T00:36:52.46084Z","steps":["trace[984284633] 'agreement among raft nodes before linearized reading' (duration: 145.181957ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:36:52.460982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.608257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-10-16T00:36:52.461056Z","caller":"traceutil/trace.go:171","msg":"trace[314313368] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15205; }","duration":"114.73919ms","start":"2026-10-16T00:36:52.346301Z","end":"2026-10-16T00:36:52.46104Z","steps":["trace[314313368] 'agreement among raft nodes before linearized reading' (duration: 114.509095ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:36:52.700582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.872369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5683"} {"level":"info","ts":"2026-10-16T00:36:52.701218Z","caller":"traceutil/trace.go:171","msg":"trace[1546117099] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:15205; }","duration":"147.53162ms","start":"2026-10-16T00:36:52.553663Z","end":"2026-10-16T00:36:52.701195Z","steps":["trace[1546117099] 'range keys from in-memory index tree' (duration: 146.673845ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:36:52.700587Z","caller":"traceutil/trace.go:171","msg":"trace[1129723907] transaction","detail":"{read_only:false; response_revision:15206; number_of_response:1; }","duration":"111.040753ms","start":"2026-10-16T00:36:52.589522Z","end":"2026-10-16T00:36:52.700563Z","steps":["trace[1129723907] 'process raft request' (duration: 100.335606ms)","trace[1129723907] 'compare' (duration: 10.379131ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:41:48.69258Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15183} {"level":"info","ts":"2026-10-16T00:41:48.792697Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15183,"took":"96.942705ms","hash":4268176452,"current-db-size-bytes":55361536,"current-db-size":"55 MB","current-db-size-in-use-bytes":32092160,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-16T00:41:48.792757Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4268176452,"revision":15183,"compact-revision":12732} {"level":"info","ts":"2026-10-16T00:42:21.632718Z","caller":"traceutil/trace.go:171","msg":"trace[876028855] transaction","detail":"{read_only:false; response_revision:17689; number_of_response:1; }","duration":"124.555629ms","start":"2026-10-16T00:42:21.508141Z","end":"2026-10-16T00:42:21.632696Z","steps":["trace[876028855] 'process raft request' (duration: 124.410487ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:42:27.983741Z","caller":"traceutil/trace.go:171","msg":"trace[2003098599] linearizableReadLoop","detail":"{readStateIndex:18334; appliedIndex:18333; }","duration":"497.076518ms","start":"2026-10-16T00:42:27.486642Z","end":"2026-10-16T00:42:27.983719Z","steps":["trace[2003098599] 'read index received' (duration: 496.900115ms)","trace[2003098599] 'applied index is now lower than readState.Index' (duration: 175.613µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:42:27.983787Z","caller":"traceutil/trace.go:171","msg":"trace[1261144714] transaction","detail":"{read_only:false; response_revision:17724; number_of_response:1; }","duration":"529.215225ms","start":"2026-10-16T00:42:27.454553Z","end":"2026-10-16T00:42:27.983768Z","steps":["trace[1261144714] 'process raft request' (duration: 529.008972ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:42:27.98395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.308602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1634"} {"level":"info","ts":"2026-10-16T00:42:27.983996Z","caller":"traceutil/trace.go:171","msg":"trace[474869481] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:17724; }","duration":"497.403414ms","start":"2026-10-16T00:42:27.486578Z","end":"2026-10-16T00:42:27.983981Z","steps":["trace[474869481] 'agreement among raft nodes before linearized reading' (duration: 497.235971ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:42:27.984025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:42:27.486549Z","time spent":"497.468365ms","remote":"127.0.0.1:55110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1658,"request content":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" "} {"level":"warn","ts":"2026-10-16T00:42:27.984178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:42:27.454527Z","time spent":"529.309218ms","remote":"127.0.0.1:55134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20874,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T00:42:28.19264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.499262ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-16T00:42:28.192838Z","caller":"traceutil/trace.go:171","msg":"trace[945756708] linearizableReadLoop","detail":"{readStateIndex:18336; appliedIndex:18334; }","duration":"209.029395ms","start":"2026-10-16T00:42:27.983793Z","end":"2026-10-16T00:42:28.192823Z","steps":["trace[945756708] 'read index received' (duration: 86.214268ms)","trace[945756708] 'applied index is now lower than readState.Index' (duration: 122.813777ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:42:28.193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.162771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T00:42:28.193033Z","caller":"traceutil/trace.go:171","msg":"trace[1281896769] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17726; }","duration":"254.196392ms","start":"2026-10-16T00:42:27.938827Z","end":"2026-10-16T00:42:28.193023Z","steps":["trace[1281896769] 'agreement among raft nodes before linearized reading' (duration: 254.1117ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:42:28.193022Z","caller":"traceutil/trace.go:171","msg":"trace[555405501] transaction","detail":"{read_only:false; response_revision:17725; number_of_response:1; }","duration":"537.423876ms","start":"2026-10-16T00:42:27.655561Z","end":"2026-10-16T00:42:28.192985Z","steps":["trace[555405501] 'process raft request' (duration: 414.495577ms)","trace[555405501] 'compare' (duration: 122.336888ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:42:28.193062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.490043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-16T00:42:28.193092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.574633ms","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-16T00:42:28.193146Z","caller":"traceutil/trace.go:171","msg":"trace[1965469082] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:17726; }","duration":"522.611765ms","start":"2026-10-16T00:42:27.67049Z","end":"2026-10-16T00:42:28.193102Z","steps":["trace[1965469082] 'agreement among raft nodes before linearized reading' (duration: 522.397151ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:42:28.193149Z","caller":"traceutil/trace.go:171","msg":"trace[993097788] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17726; }","duration":"234.690475ms","start":"2026-10-16T00:42:27.958444Z","end":"2026-10-16T00:42:28.193134Z","steps":["trace[993097788] 'agreement among raft nodes before linearized reading' (duration: 234.469171ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:42:28.193187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:42:27.670473Z","time spent":"522.701097ms","remote":"127.0.0.1:60040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":30,"request content":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true "} {"level":"warn","ts":"2026-10-16T00:42:28.193217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:42:27.655532Z","time spent":"537.568298ms","remote":"127.0.0.1:55220","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-16T00:42:28.193243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"681.488293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-q2lcc\" ","response":"range_response_count:1 size:22128"} {"level":"info","ts":"2026-10-16T00:42:28.193259Z","caller":"traceutil/trace.go:171","msg":"trace[652111794] transaction","detail":"{read_only:false; response_revision:17726; number_of_response:1; }","duration":"295.496579ms","start":"2026-10-16T00:42:27.897752Z","end":"2026-10-16T00:42:28.193249Z","steps":["trace[652111794] 'process raft request' (duration: 294.990119ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:42:28.193135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.861388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-dfce9427-a52a-4dfa-a8d8-cb47b4a4bdb7\" ","response":"range_response_count:1 size:1294"} {"level":"info","ts":"2026-10-16T00:42:28.193283Z","caller":"traceutil/trace.go:171","msg":"trace[1806586702] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-q2lcc; range_end:; response_count:1; response_revision:17726; }","duration":"681.554714ms","start":"2026-10-16T00:42:27.511715Z","end":"2026-10-16T00:42:28.19327Z","steps":["trace[1806586702] 'agreement among raft nodes before linearized reading' (duration: 681.457053ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:42:28.193313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:42:27.511696Z","time spent":"681.611115ms","remote":"127.0.0.1:55134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22152,"request content":"key:\"/registry/pods/openstack/nova-compute-default-q2lcc\" "} {"level":"info","ts":"2026-10-16T00:42:28.193314Z","caller":"traceutil/trace.go:171","msg":"trace[112982270] range","detail":"{range_begin:/registry/persistentvolumes/pvc-dfce9427-a52a-4dfa-a8d8-cb47b4a4bdb7; range_end:; response_count:1; response_revision:17726; }","duration":"206.075582ms","start":"2026-10-16T00:42:27.987212Z","end":"2026-10-16T00:42:28.193288Z","steps":["trace[112982270] 'agreement among raft nodes before linearized reading' (duration: 205.735465ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:42:28.334951Z","caller":"traceutil/trace.go:171","msg":"trace[1500936325] linearizableReadLoop","detail":"{readStateIndex:18338; appliedIndex:18336; }","duration":"133.224358ms","start":"2026-10-16T00:42:28.201701Z","end":"2026-10-16T00:42:28.334925Z","steps":["trace[1500936325] 'read index received' (duration: 85.780149ms)","trace[1500936325] 'applied index is now lower than readState.Index' (duration: 47.443089ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:42:28.335017Z","caller":"traceutil/trace.go:171","msg":"trace[1948801627] transaction","detail":"{read_only:false; response_revision:17728; number_of_response:1; }","duration":"136.704792ms","start":"2026-10-16T00:42:28.198287Z","end":"2026-10-16T00:42:28.334992Z","steps":["trace[1948801627] 'process raft request' (duration: 129.414339ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:42:28.335817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.089664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:94 size:945967"} {"level":"info","ts":"2026-10-16T00:42:28.335913Z","caller":"traceutil/trace.go:171","msg":"trace[286870923] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:94; response_revision:17728; }","duration":"134.203895ms","start":"2026-10-16T00:42:28.201677Z","end":"2026-10-16T00:42:28.335881Z","steps":["trace[286870923] 'agreement among raft nodes before linearized reading' (duration: 133.337329ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:42:28.446325Z","caller":"traceutil/trace.go:171","msg":"trace[2106203463] transaction","detail":"{read_only:false; response_revision:17730; number_of_response:1; }","duration":"105.647823ms","start":"2026-10-16T00:42:28.340656Z","end":"2026-10-16T00:42:28.446304Z","steps":["trace[2106203463] 'process raft request' (duration: 104.707056ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:42:28.446428Z","caller":"traceutil/trace.go:171","msg":"trace[28184203] transaction","detail":"{read_only:false; response_revision:17731; number_of_response:1; }","duration":"104.547263ms","start":"2026-10-16T00:42:28.341854Z","end":"2026-10-16T00:42:28.446401Z","steps":["trace[28184203] 'process raft request' (duration: 104.353439ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:45:39.474642Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ded76e421897a43d","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-16T00:45:39.483817Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-16T00:45:39.484058Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-16T00:46:48.700929Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17460} {"level":"info","ts":"2026-10-16T00:46:48.806274Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17460,"took":"101.410296ms","hash":2058651595,"current-db-size-bytes":55361536,"current-db-size":"55 MB","current-db-size-in-use-bytes":34443264,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-16T00:46:48.80634Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2058651595,"revision":17460,"compact-revision":15183} {"level":"info","ts":"2026-10-16T00:47:37.996472Z","caller":"traceutil/trace.go:171","msg":"trace[31846342] transaction","detail":"{read_only:false; response_revision:20213; number_of_response:1; }","duration":"125.825013ms","start":"2026-10-16T00:47:37.870616Z","end":"2026-10-16T00:47:37.996441Z","steps":["trace[31846342] 'process raft request' (duration: 125.628189ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:47:38.413291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.823179ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-16T00:47:38.413386Z","caller":"traceutil/trace.go:171","msg":"trace[834830341] linearizableReadLoop","detail":"{readStateIndex:20904; appliedIndex:20903; }","duration":"266.68551ms","start":"2026-10-16T00:47:38.146685Z","end":"2026-10-16T00:47:38.413371Z","steps":["trace[834830341] 'read index received' (duration: 61.670958ms)","trace[834830341] 'applied index is now lower than readState.Index' (duration: 205.013382ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:47:38.413504Z","caller":"traceutil/trace.go:171","msg":"trace[843902997] transaction","detail":"{read_only:false; response_revision:20214; number_of_response:1; }","duration":"412.903766ms","start":"2026-10-16T00:47:38.000539Z","end":"2026-10-16T00:47:38.413442Z","steps":["trace[843902997] 'process raft request' (duration: 207.847274ms)","trace[843902997] 'compare' (duration: 204.493193ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:47:38.41355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.862193ms","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-16T00:47:38.413592Z","caller":"traceutil/trace.go:171","msg":"trace[932395677] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20214; }","duration":"266.937245ms","start":"2026-10-16T00:47:38.146642Z","end":"2026-10-16T00:47:38.413579Z","steps":["trace[932395677] 'agreement among raft nodes before linearized reading' (duration: 266.792482ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:47:38.413684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:47:38.000522Z","time spent":"413.065099ms","remote":"127.0.0.1:55220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T00:47:38.427582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.097457ms","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-16T00:47:38.427582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.805993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T00:47:38.427611Z","caller":"traceutil/trace.go:171","msg":"trace[1285318853] transaction","detail":"{read_only:false; response_revision:20215; number_of_response:1; }","duration":"242.640371ms","start":"2026-10-16T00:47:38.18492Z","end":"2026-10-16T00:47:38.427561Z","steps":["trace[1285318853] 'process raft request' (duration: 242.396156ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:47:38.427647Z","caller":"traceutil/trace.go:171","msg":"trace[1417803532] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20215; }","duration":"268.191548ms","start":"2026-10-16T00:47:38.159439Z","end":"2026-10-16T00:47:38.427631Z","steps":["trace[1417803532] 'agreement among raft nodes before linearized reading' (duration: 268.017565ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:47:38.427663Z","caller":"traceutil/trace.go:171","msg":"trace[1814201945] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:20215; }","duration":"224.941036ms","start":"2026-10-16T00:47:38.2027Z","end":"2026-10-16T00:47:38.427641Z","steps":["trace[1814201945] 'agreement among raft nodes before linearized reading' (duration: 224.794553ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:47:38.427737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.570657ms","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-16T00:47:38.427801Z","caller":"traceutil/trace.go:171","msg":"trace[1677878363] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20215; }","duration":"249.68398ms","start":"2026-10-16T00:47:38.178102Z","end":"2026-10-16T00:47:38.427786Z","steps":["trace[1677878363] 'agreement among raft nodes before linearized reading' (duration: 249.488946ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:47:38.428238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.967447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-10-16T00:47:38.428302Z","caller":"traceutil/trace.go:171","msg":"trace[23191782] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20215; }","duration":"249.064148ms","start":"2026-10-16T00:47:38.179218Z","end":"2026-10-16T00:47:38.428282Z","steps":["trace[23191782] 'agreement among raft nodes before linearized reading' (duration: 248.696741ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:47:54.12998Z","caller":"traceutil/trace.go:171","msg":"trace[1525323207] transaction","detail":"{read_only:false; response_revision:20294; number_of_response:1; }","duration":"119.988836ms","start":"2026-10-16T00:47:54.009951Z","end":"2026-10-16T00:47:54.12994Z","steps":["trace[1525323207] 'process raft request' (duration: 118.937768ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:47:54.379196Z","caller":"traceutil/trace.go:171","msg":"trace[1437917458] transaction","detail":"{read_only:false; response_revision:20295; number_of_response:1; }","duration":"241.956298ms","start":"2026-10-16T00:47:54.137188Z","end":"2026-10-16T00:47:54.379144Z","steps":["trace[1437917458] 'process raft request' (duration: 190.964565ms)","trace[1437917458] 'compare' (duration: 50.76542ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:47:54.757379Z","caller":"traceutil/trace.go:171","msg":"trace[512092762] transaction","detail":"{read_only:false; response_revision:20296; number_of_response:1; }","duration":"258.025681ms","start":"2026-10-16T00:47:54.499329Z","end":"2026-10-16T00:47:54.757355Z","steps":["trace[512092762] 'process raft request' (duration: 201.933695ms)","trace[512092762] 'compare' (duration: 55.937104ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:47:54.757367Z","caller":"traceutil/trace.go:171","msg":"trace[2118474712] linearizableReadLoop","detail":"{readStateIndex:20989; appliedIndex:20988; }","duration":"246.338328ms","start":"2026-10-16T00:47:54.511003Z","end":"2026-10-16T00:47:54.757341Z","steps":["trace[2118474712] 'read index received' (duration: 190.249932ms)","trace[2118474712] 'applied index is now lower than readState.Index' (duration: 56.086886ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:47:54.757546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.532742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1782"} {"level":"info","ts":"2026-10-16T00:47:54.757601Z","caller":"traceutil/trace.go:171","msg":"trace[264066325] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:20296; }","duration":"246.635284ms","start":"2026-10-16T00:47:54.510954Z","end":"2026-10-16T00:47:54.757589Z","steps":["trace[264066325] 'agreement among raft nodes before linearized reading' (duration: 246.462411ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:47:54.757717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.9607ms","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-16T00:47:54.757792Z","caller":"traceutil/trace.go:171","msg":"trace[671902043] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20296; }","duration":"241.063862ms","start":"2026-10-16T00:47:54.516708Z","end":"2026-10-16T00:47:54.757772Z","steps":["trace[671902043] 'agreement among raft nodes before linearized reading' (duration: 240.864879ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:47:54.75797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.39962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-10-16T00:47:54.757998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.349701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-16T00:47:54.758055Z","caller":"traceutil/trace.go:171","msg":"trace[739094768] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20296; }","duration":"228.486661ms","start":"2026-10-16T00:47:54.529533Z","end":"2026-10-16T00:47:54.75802Z","steps":["trace[739094768] 'agreement among raft nodes before linearized reading' (duration: 228.275767ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:47:54.758055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.518491ms","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-16T00:47:54.758101Z","caller":"traceutil/trace.go:171","msg":"trace[501859247] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20296; }","duration":"233.495034ms","start":"2026-10-16T00:47:54.524584Z","end":"2026-10-16T00:47:54.758079Z","steps":["trace[501859247] 'agreement among raft nodes before linearized reading' (duration: 233.25901ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:47:54.758091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.214715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-16T00:47:54.758125Z","caller":"traceutil/trace.go:171","msg":"trace[1655326943] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20296; }","duration":"228.647815ms","start":"2026-10-16T00:47:54.529457Z","end":"2026-10-16T00:47:54.758105Z","steps":["trace[1655326943] 'agreement among raft nodes before linearized reading' (duration: 228.370369ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:47:54.758063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.840045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1985"} {"level":"info","ts":"2026-10-16T00:47:54.758177Z","caller":"traceutil/trace.go:171","msg":"trace[1651502082] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20296; }","duration":"229.355937ms","start":"2026-10-16T00:47:54.528798Z","end":"2026-10-16T00:47:54.758154Z","steps":["trace[1651502082] 'agreement among raft nodes before linearized reading' (duration: 229.027941ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:47:54.758218Z","caller":"traceutil/trace.go:171","msg":"trace[512825884] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:20296; }","duration":"160.08457ms","start":"2026-10-16T00:47:54.598102Z","end":"2026-10-16T00:47:54.758187Z","steps":["trace[512825884] 'agreement among raft nodes before linearized reading' (duration: 159.718703ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:47:54.949057Z","caller":"traceutil/trace.go:171","msg":"trace[626418964] linearizableReadLoop","detail":"{readStateIndex:20990; appliedIndex:20989; }","duration":"185.854881ms","start":"2026-10-16T00:47:54.763149Z","end":"2026-10-16T00:47:54.949004Z","steps":["trace[626418964] 'read index received' (duration: 98.300759ms)","trace[626418964] 'applied index is now lower than readState.Index' (duration: 87.552362ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:47:54.949093Z","caller":"traceutil/trace.go:171","msg":"trace[821490393] transaction","detail":"{read_only:false; response_revision:20297; number_of_response:1; }","duration":"186.416582ms","start":"2026-10-16T00:47:54.762627Z","end":"2026-10-16T00:47:54.949044Z","steps":["trace[821490393] 'process raft request' (duration: 98.805688ms)","trace[821490393] 'compare' (duration: 87.40153ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:47:54.949686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.514185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-9568d730-dfe6-48a8-9a3b-69c734232ce6\" ","response":"range_response_count:1 size:1276"} {"level":"info","ts":"2026-10-16T00:47:54.949793Z","caller":"traceutil/trace.go:171","msg":"trace[791770097] range","detail":"{range_begin:/registry/persistentvolumes/pvc-9568d730-dfe6-48a8-9a3b-69c734232ce6; range_end:; response_count:1; response_revision:20297; }","duration":"186.627506ms","start":"2026-10-16T00:47:54.763119Z","end":"2026-10-16T00:47:54.949747Z","steps":["trace[791770097] 'agreement among raft nodes before linearized reading' (duration: 186.009415ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:47:55.170861Z","caller":"traceutil/trace.go:171","msg":"trace[1121371593] transaction","detail":"{read_only:false; response_revision:20299; number_of_response:1; }","duration":"407.415307ms","start":"2026-10-16T00:47:54.763408Z","end":"2026-10-16T00:47:55.170823Z","steps":["trace[1121371593] 'process raft request' (duration: 407.270964ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:47:55.171147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:47:54.763397Z","time spent":"407.5899ms","remote":"127.0.0.1:55220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:47:55.171216Z","caller":"traceutil/trace.go:171","msg":"trace[587161447] transaction","detail":"{read_only:false; response_revision:20300; number_of_response:1; }","duration":"407.778154ms","start":"2026-10-16T00:47:54.76341Z","end":"2026-10-16T00:47:55.171188Z","steps":["trace[587161447] 'process raft request' (duration: 407.347546ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:47:55.171379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:47:54.763379Z","time spent":"407.905055ms","remote":"127.0.0.1:55220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:47:55.171588Z","caller":"traceutil/trace.go:171","msg":"trace[1324918962] linearizableReadLoop","detail":"{readStateIndex:20994; appliedIndex:20990; }","duration":"222.42553ms","start":"2026-10-16T00:47:54.949152Z","end":"2026-10-16T00:47:55.171577Z","steps":["trace[1324918962] 'read index received' (duration: 221.150167ms)","trace[1324918962] 'applied index is now lower than readState.Index' (duration: 1.274043ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:47:55.171688Z","caller":"traceutil/trace.go:171","msg":"trace[1708585302] transaction","detail":"{read_only:false; response_revision:20298; number_of_response:1; }","duration":"408.343903ms","start":"2026-10-16T00:47:54.763326Z","end":"2026-10-16T00:47:55.17167Z","steps":["trace[1708585302] 'process raft request' (duration: 407.07043ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:47:55.171721Z","caller":"traceutil/trace.go:171","msg":"trace[1187235809] transaction","detail":"{read_only:false; response_revision:20301; number_of_response:1; }","duration":"408.226051ms","start":"2026-10-16T00:47:54.763485Z","end":"2026-10-16T00:47:55.171711Z","steps":["trace[1187235809] 'process raft request' (duration: 407.60677ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:47:55.171789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:47:54.763476Z","time spent":"408.267501ms","remote":"127.0.0.1:55220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T00:47:55.171786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:47:54.763312Z","time spent":"408.418094ms","remote":"127.0.0.1:55220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T00:47:55.171952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.260547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T00:47:55.172053Z","caller":"traceutil/trace.go:171","msg":"trace[1032045763] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20301; }","duration":"234.388409ms","start":"2026-10-16T00:47:54.937625Z","end":"2026-10-16T00:47:55.172014Z","steps":["trace[1032045763] 'agreement among raft nodes before linearized reading' (duration: 234.239016ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:47:55.172515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.237712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0\" ","response":"range_response_count:1 size:1862"} {"level":"info","ts":"2026-10-16T00:47:55.172563Z","caller":"traceutil/trace.go:171","msg":"trace[1841579205] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0; range_end:; response_count:1; response_revision:20301; }","duration":"219.324114ms","start":"2026-10-16T00:47:54.953226Z","end":"2026-10-16T00:47:55.17255Z","steps":["trace[1841579205] 'agreement among raft nodes before linearized reading' (duration: 218.600231ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:47:55.480168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.23612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T00:47:55.480262Z","caller":"traceutil/trace.go:171","msg":"trace[2001670733] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:20301; }","duration":"108.401733ms","start":"2026-10-16T00:47:55.371839Z","end":"2026-10-16T00:47:55.48024Z","steps":["trace[2001670733] 'count revisions from in-memory index tree' (duration: 108.122029ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:47:55.884512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.931455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-16T00:47:55.884613Z","caller":"traceutil/trace.go:171","msg":"trace[1660185681] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:20303; }","duration":"108.099179ms","start":"2026-10-16T00:47:55.776492Z","end":"2026-10-16T00:47:55.884591Z","steps":["trace[1660185681] 'count revisions from in-memory index tree' (duration: 107.826664ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:51:32.24169Z","caller":"traceutil/trace.go:171","msg":"trace[223585243] transaction","detail":"{read_only:false; response_revision:22134; number_of_response:1; }","duration":"103.341461ms","start":"2026-10-16T00:51:32.138323Z","end":"2026-10-16T00:51:32.241664Z","steps":["trace[223585243] 'process raft request' (duration: 102.994236ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:51:32.599481Z","caller":"traceutil/trace.go:171","msg":"trace[72154360] transaction","detail":"{read_only:false; response_revision:22139; number_of_response:1; }","duration":"146.629424ms","start":"2026-10-16T00:51:32.452815Z","end":"2026-10-16T00:51:32.599445Z","steps":["trace[72154360] 'process raft request' (duration: 60.513957ms)","trace[72154360] 'compare' (duration: 85.863112ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:51:32.599964Z","caller":"traceutil/trace.go:171","msg":"trace[203316695] transaction","detail":"{read_only:false; response_revision:22140; number_of_response:1; }","duration":"138.915322ms","start":"2026-10-16T00:51:32.46102Z","end":"2026-10-16T00:51:32.599935Z","steps":["trace[203316695] 'process raft request' (duration: 138.79277ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:51:32.60002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.858689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/flavors/\" range_end:\"/registry/openstack.k-orc.cloud/flavors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T00:51:32.599947Z","caller":"traceutil/trace.go:171","msg":"trace[85933305] linearizableReadLoop","detail":"{readStateIndex:22893; appliedIndex:22891; }","duration":"133.778307ms","start":"2026-10-16T00:51:32.46614Z","end":"2026-10-16T00:51:32.599918Z","steps":["trace[85933305] 'read index received' (duration: 47.202943ms)","trace[85933305] 'applied index is now lower than readState.Index' (duration: 86.574034ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:51:32.600091Z","caller":"traceutil/trace.go:171","msg":"trace[2106891232] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/flavors/; range_end:/registry/openstack.k-orc.cloud/flavors0; response_count:0; response_revision:22140; }","duration":"133.92685ms","start":"2026-10-16T00:51:32.466137Z","end":"2026-10-16T00:51:32.600064Z","steps":["trace[2106891232] 'agreement among raft nodes before linearized reading' (duration: 133.791558ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:51:32.600066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.899971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/flavors/\" range_end:\"/registry/openstack.k-orc.cloud/flavors0\" limit:10000 ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-16T00:51:32.600221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.805672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/flavors/\" range_end:\"/registry/openstack.k-orc.cloud/flavors0\" limit:500 ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T00:51:32.600303Z","caller":"traceutil/trace.go:171","msg":"trace[857959429] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/flavors/; range_end:/registry/openstack.k-orc.cloud/flavors0; response_count:0; response_revision:22140; }","duration":"131.907254ms","start":"2026-10-16T00:51:32.468359Z","end":"2026-10-16T00:51:32.600267Z","steps":["trace[857959429] 'agreement among raft nodes before linearized reading' (duration: 131.822022ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:51:32.600316Z","caller":"traceutil/trace.go:171","msg":"trace[1278656389] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/flavors/; range_end:/registry/openstack.k-orc.cloud/flavors0; response_count:0; response_revision:22140; }","duration":"134.088334ms","start":"2026-10-16T00:51:32.466078Z","end":"2026-10-16T00:51:32.600167Z","steps":["trace[1278656389] 'agreement among raft nodes before linearized reading' (duration: 133.924451ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:51:44.607771Z","caller":"traceutil/trace.go:171","msg":"trace[2009187636] transaction","detail":"{read_only:false; response_revision:22724; number_of_response:1; }","duration":"150.189358ms","start":"2026-10-16T00:51:44.457543Z","end":"2026-10-16T00:51:44.607732Z","steps":["trace[2009187636] 'process raft request' (duration: 97.396162ms)","trace[2009187636] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/plndr-cp-lock; req_size:415; } (duration: 52.637113ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:51:44.618667Z","caller":"traceutil/trace.go:171","msg":"trace[952145755] transaction","detail":"{read_only:false; response_revision:22725; number_of_response:1; }","duration":"147.627722ms","start":"2026-10-16T00:51:44.471011Z","end":"2026-10-16T00:51:44.618638Z","steps":["trace[952145755] 'process raft request' (duration: 147.261616ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:51:45.042304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.498398ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-16T00:51:45.042652Z","caller":"traceutil/trace.go:171","msg":"trace[1417640271] transaction","detail":"{read_only:false; response_revision:22726; number_of_response:1; }","duration":"399.863118ms","start":"2026-10-16T00:51:44.642753Z","end":"2026-10-16T00:51:45.042616Z","steps":["trace[1417640271] 'process raft request' (duration: 221.82527ms)","trace[1417640271] 'compare' (duration: 176.904686ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:51:45.042769Z","caller":"traceutil/trace.go:171","msg":"trace[2039214918] linearizableReadLoop","detail":"{readStateIndex:23488; appliedIndex:23486; }","duration":"158.490541ms","start":"2026-10-16T00:51:44.884259Z","end":"2026-10-16T00:51:45.04275Z","steps":["trace[2039214918] 'read index received' (duration: 138.456984ms)","trace[2039214918] 'applied index is now lower than readState.Index' (duration: 20.031847ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:51:45.042925Z","caller":"traceutil/trace.go:171","msg":"trace[246953485] transaction","detail":"{read_only:false; response_revision:22727; number_of_response:1; }","duration":"398.730827ms","start":"2026-10-16T00:51:44.644142Z","end":"2026-10-16T00:51:45.042873Z","steps":["trace[246953485] 'process raft request' (duration: 398.34135ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:51:45.042972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.982202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T00:51:45.043037Z","caller":"traceutil/trace.go:171","msg":"trace[458119978] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22727; }","duration":"105.083203ms","start":"2026-10-16T00:51:44.937943Z","end":"2026-10-16T00:51:45.043026Z","steps":["trace[458119978] 'agreement among raft nodes before linearized reading' (duration: 104.987451ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:51:45.042967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:51:44.642736Z","time spent":"400.069211ms","remote":"127.0.0.1:55220","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-16T00:51:45.043163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:51:44.644106Z","time spent":"398.930531ms","remote":"127.0.0.1:55220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T00:51:45.042949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.676264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-16T00:51:45.043377Z","caller":"traceutil/trace.go:171","msg":"trace[383532037] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:22727; }","duration":"159.098911ms","start":"2026-10-16T00:51:44.884204Z","end":"2026-10-16T00:51:45.043303Z","steps":["trace[383532037] 'agreement among raft nodes before linearized reading' (duration: 158.660603ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:51:48.708536Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19935} {"level":"info","ts":"2026-10-16T00:51:48.83518Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19935,"took":"122.606884ms","hash":3139553619,"current-db-size-bytes":56467456,"current-db-size":"56 MB","current-db-size-in-use-bytes":41738240,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-10-16T00:51:48.835247Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3139553619,"revision":19935,"compact-revision":17460} {"level":"info","ts":"2026-10-16T00:53:09.46282Z","caller":"traceutil/trace.go:171","msg":"trace[627563057] transaction","detail":"{read_only:false; response_revision:23625; number_of_response:1; }","duration":"196.208942ms","start":"2026-10-16T00:53:09.266587Z","end":"2026-10-16T00:53:09.462796Z","steps":["trace[627563057] 'process raft request' (duration: 196.048249ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:53:09.480534Z","caller":"traceutil/trace.go:171","msg":"trace[94183952] transaction","detail":"{read_only:false; response_revision:23627; number_of_response:1; }","duration":"122.900631ms","start":"2026-10-16T00:53:09.357616Z","end":"2026-10-16T00:53:09.480517Z","steps":["trace[94183952] 'process raft request' (duration: 122.82964ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:53:09.480609Z","caller":"traceutil/trace.go:171","msg":"trace[696470694] transaction","detail":"{read_only:false; response_revision:23626; number_of_response:1; }","duration":"165.612671ms","start":"2026-10-16T00:53:09.314961Z","end":"2026-10-16T00:53:09.480573Z","steps":["trace[696470694] 'process raft request' (duration: 165.382798ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:53:09.480869Z","caller":"traceutil/trace.go:171","msg":"trace[1515658607] transaction","detail":"{read_only:false; response_revision:23628; number_of_response:1; }","duration":"122.91426ms","start":"2026-10-16T00:53:09.357913Z","end":"2026-10-16T00:53:09.480828Z","steps":["trace[1515658607] 'process raft request' (duration: 122.570335ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:54:58.083914Z","caller":"traceutil/trace.go:171","msg":"trace[1919684337] transaction","detail":"{read_only:false; response_revision:24630; number_of_response:1; }","duration":"173.65994ms","start":"2026-10-16T00:54:57.910226Z","end":"2026-10-16T00:54:58.083886Z","steps":["trace[1919684337] 'process raft request' (duration: 173.523547ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:54:58.083969Z","caller":"traceutil/trace.go:171","msg":"trace[1012398207] linearizableReadLoop","detail":"{readStateIndex:25441; appliedIndex:25441; }","duration":"145.195899ms","start":"2026-10-16T00:54:57.938746Z","end":"2026-10-16T00:54:58.083941Z","steps":["trace[1012398207] 'read index received' (duration: 145.187419ms)","trace[1012398207] 'applied index is now lower than readState.Index' (duration: 6.78µs)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:54:58.084184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.414433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T00:54:58.084235Z","caller":"traceutil/trace.go:171","msg":"trace[2113791993] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24630; }","duration":"145.525405ms","start":"2026-10-16T00:54:57.9387Z","end":"2026-10-16T00:54:58.084226Z","steps":["trace[2113791993] 'agreement among raft nodes before linearized reading' (duration: 145.366792ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:54:58.228492Z","caller":"traceutil/trace.go:171","msg":"trace[1345055726] transaction","detail":"{read_only:false; response_revision:24632; number_of_response:1; }","duration":"250.469807ms","start":"2026-10-16T00:54:57.977998Z","end":"2026-10-16T00:54:58.228468Z","steps":["trace[1345055726] 'process raft request' (duration: 250.387496ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:54:58.228512Z","caller":"traceutil/trace.go:171","msg":"trace[572883827] transaction","detail":"{read_only:false; response_revision:24631; number_of_response:1; }","duration":"257.687599ms","start":"2026-10-16T00:54:57.970775Z","end":"2026-10-16T00:54:58.228463Z","steps":["trace[572883827] 'process raft request' (duration: 257.460615ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:54:58.22876Z","caller":"traceutil/trace.go:171","msg":"trace[1791170028] transaction","detail":"{read_only:false; response_revision:24633; number_of_response:1; }","duration":"207.969487ms","start":"2026-10-16T00:54:58.020775Z","end":"2026-10-16T00:54:58.228745Z","steps":["trace[1791170028] 'process raft request' (duration: 207.653872ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:54:58.228836Z","caller":"traceutil/trace.go:171","msg":"trace[1269935606] linearizableReadLoop","detail":"{readStateIndex:25445; appliedIndex:25441; }","duration":"144.76355ms","start":"2026-10-16T00:54:58.084041Z","end":"2026-10-16T00:54:58.228805Z","steps":["trace[1269935606] 'read index received' (duration: 144.266982ms)","trace[1269935606] 'applied index is now lower than readState.Index' (duration: 495.268µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:54:58.228891Z","caller":"traceutil/trace.go:171","msg":"trace[543205454] transaction","detail":"{read_only:false; response_revision:24634; number_of_response:1; }","duration":"181.144777ms","start":"2026-10-16T00:54:58.047718Z","end":"2026-10-16T00:54:58.228862Z","steps":["trace[543205454] 'process raft request' (duration: 180.952634ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:54:58.228971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.255597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T00:54:58.229021Z","caller":"traceutil/trace.go:171","msg":"trace[5259715] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:24634; }","duration":"164.319518ms","start":"2026-10-16T00:54:58.064689Z","end":"2026-10-16T00:54:58.229009Z","steps":["trace[5259715] 'agreement among raft nodes before linearized reading' (duration: 164.227116ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:54:58.236557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.949057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T00:54:58.236622Z","caller":"traceutil/trace.go:171","msg":"trace[2124623341] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24635; }","duration":"150.061189ms","start":"2026-10-16T00:54:58.086546Z","end":"2026-10-16T00:54:58.236607Z","steps":["trace[2124623341] 'agreement among raft nodes before linearized reading' (duration: 149.925486ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:55:04.322175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.363794ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T00:55:04.322366Z","caller":"traceutil/trace.go:171","msg":"trace[1645767913] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:24690; }","duration":"547.596998ms","start":"2026-10-16T00:55:03.774747Z","end":"2026-10-16T00:55:04.322344Z","steps":["trace[1645767913] 'range keys from in-memory index tree' (duration: 547.306203ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:55:04.322488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"632.631934ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-16T00:55:04.322698Z","caller":"traceutil/trace.go:171","msg":"trace[1183641574] transaction","detail":"{read_only:false; response_revision:24692; number_of_response:1; }","duration":"340.361783ms","start":"2026-10-16T00:55:03.982319Z","end":"2026-10-16T00:55:04.32268Z","steps":["trace[1183641574] 'process raft request' (duration: 340.270892ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:55:04.322819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:55:03.982292Z","time spent":"340.443064ms","remote":"127.0.0.1:55220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:55:04.322785Z","caller":"traceutil/trace.go:171","msg":"trace[224727938] transaction","detail":"{read_only:false; response_revision:24691; number_of_response:1; }","duration":"752.408959ms","start":"2026-10-16T00:55:03.570347Z","end":"2026-10-16T00:55:04.322756Z","steps":["trace[224727938] 'process raft request' (duration: 119.432288ms)","trace[224727938] 'compare' (duration: 631.520674ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:55:04.322795Z","caller":"traceutil/trace.go:171","msg":"trace[2024045596] linearizableReadLoop","detail":"{readStateIndex:25503; appliedIndex:25502; }","duration":"736.783522ms","start":"2026-10-16T00:55:03.585979Z","end":"2026-10-16T00:55:04.322763Z","steps":["trace[2024045596] 'read index received' (duration: 103.695089ms)","trace[2024045596] 'applied index is now lower than readState.Index' (duration: 633.085743ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:55:04.322944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:55:03.570323Z","time spent":"752.551761ms","remote":"127.0.0.1:55220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T00:55:04.323013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"737.040496ms","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-16T00:55:04.323048Z","caller":"traceutil/trace.go:171","msg":"trace[812773542] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24692; }","duration":"737.111418ms","start":"2026-10-16T00:55:03.585927Z","end":"2026-10-16T00:55:04.323038Z","steps":["trace[812773542] 'agreement among raft nodes before linearized reading' (duration: 736.998086ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:55:04.323081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:55:03.585906Z","time spent":"737.167179ms","remote":"127.0.0.1:55220","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-16T00:55:04.366633Z","caller":"traceutil/trace.go:171","msg":"trace[230751402] transaction","detail":"{read_only:false; response_revision:24693; number_of_response:1; }","duration":"264.229619ms","start":"2026-10-16T00:55:04.102377Z","end":"2026-10-16T00:55:04.366606Z","steps":["trace[230751402] 'process raft request' (duration: 264.106407ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:55:04.798551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.609007ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-16T00:55:04.798911Z","caller":"traceutil/trace.go:171","msg":"trace[1923056385] linearizableReadLoop","detail":"{readStateIndex:25508; appliedIndex:25504; }","duration":"476.000557ms","start":"2026-10-16T00:55:04.322894Z","end":"2026-10-16T00:55:04.798894Z","steps":["trace[1923056385] 'read index received' (duration: 43.654699ms)","trace[1923056385] 'applied index is now lower than readState.Index' (duration: 432.344868ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:55:04.799025Z","caller":"traceutil/trace.go:171","msg":"trace[924845100] transaction","detail":"{read_only:false; response_revision:24694; number_of_response:1; }","duration":"539.850746ms","start":"2026-10-16T00:55:04.259161Z","end":"2026-10-16T00:55:04.799012Z","steps":["trace[924845100] 'process raft request' (duration: 322.70069ms)","trace[924845100] 'compare' (duration: 216.203249ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:55:04.799125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:55:04.259126Z","time spent":"539.959887ms","remote":"127.0.0.1:55220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T00:55:04.79939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.133448785s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19027"} {"level":"info","ts":"2026-10-16T00:55:04.79946Z","caller":"traceutil/trace.go:171","msg":"trace[81293142] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24697; }","duration":"1.133561397s","start":"2026-10-16T00:55:03.665882Z","end":"2026-10-16T00:55:04.799443Z","steps":["trace[81293142] 'agreement among raft nodes before linearized reading' (duration: 1.133388684s)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:55:04.799497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:55:03.665861Z","time spent":"1.133628019s","remote":"127.0.0.1:55128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":19051,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-10-16T00:55:04.7997Z","caller":"traceutil/trace.go:171","msg":"trace[1945345501] transaction","detail":"{read_only:false; response_revision:24696; number_of_response:1; }","duration":"537.518643ms","start":"2026-10-16T00:55:04.26214Z","end":"2026-10-16T00:55:04.799658Z","steps":["trace[1945345501] 'process raft request' (duration: 536.663078ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:55:04.799753Z","caller":"traceutil/trace.go:171","msg":"trace[1722114068] transaction","detail":"{read_only:false; response_revision:24698; number_of_response:1; }","duration":"285.61186ms","start":"2026-10-16T00:55:04.514115Z","end":"2026-10-16T00:55:04.799727Z","steps":["trace[1722114068] 'process raft request' (duration: 285.506448ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:55:04.799772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.09007339s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-10-16T00:55:04.799802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.090655831s","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-16T00:55:04.799844Z","caller":"traceutil/trace.go:171","msg":"trace[2096223057] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24697; }","duration":"1.090177752s","start":"2026-10-16T00:55:03.70965Z","end":"2026-10-16T00:55:04.799827Z","steps":["trace[2096223057] 'agreement among raft nodes before linearized reading' (duration: 1.089920197s)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:55:04.799876Z","caller":"traceutil/trace.go:171","msg":"trace[114073282] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24697; }","duration":"1.090765334s","start":"2026-10-16T00:55:03.709093Z","end":"2026-10-16T00:55:04.799859Z","steps":["trace[114073282] 'agreement among raft nodes before linearized reading' (duration: 1.090479298s)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:55:04.799894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:55:03.709642Z","time spent":"1.090239393s","remote":"127.0.0.1:55220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-16T00:55:04.799897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:55:04.262121Z","time spent":"537.676355ms","remote":"127.0.0.1:55220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":605,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T00:55:04.799914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:55:03.709073Z","time spent":"1.090833554s","remote":"127.0.0.1:55220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-16T00:55:04.799561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.150996676s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-10-16T00:55:04.799946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.191562869s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-10-16T00:55:04.79997Z","caller":"traceutil/trace.go:171","msg":"trace[1601999068] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24697; }","duration":"1.151462245s","start":"2026-10-16T00:55:03.648494Z","end":"2026-10-16T00:55:04.799956Z","steps":["trace[1601999068] 'agreement among raft nodes before linearized reading' (duration: 1.150869164s)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:55:04.799992Z","caller":"traceutil/trace.go:171","msg":"trace[1537562094] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24697; }","duration":"1.191657391s","start":"2026-10-16T00:55:03.608324Z","end":"2026-10-16T00:55:04.799981Z","steps":["trace[1537562094] 'agreement among raft nodes before linearized reading' (duration: 1.191230883s)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:55:04.80002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:55:03.648484Z","time spent":"1.151520366s","remote":"127.0.0.1:55220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-16T00:55:04.800028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:55:03.608306Z","time spent":"1.191711092s","remote":"127.0.0.1:55134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-10-16T00:55:04.79963Z","caller":"traceutil/trace.go:171","msg":"trace[461951064] transaction","detail":"{read_only:false; response_revision:24695; number_of_response:1; }","duration":"539.57895ms","start":"2026-10-16T00:55:04.260027Z","end":"2026-10-16T00:55:04.799606Z","steps":["trace[461951064] 'process raft request' (duration: 538.694404ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:55:04.80023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:55:04.260002Z","time spent":"540.135811ms","remote":"127.0.0.1:55220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:55:04.79968Z","caller":"traceutil/trace.go:171","msg":"trace[471120629] transaction","detail":"{read_only:false; response_revision:24697; number_of_response:1; }","duration":"471.891751ms","start":"2026-10-16T00:55:04.327768Z","end":"2026-10-16T00:55:04.79966Z","steps":["trace[471120629] 'process raft request' (duration: 471.081867ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:55:04.800423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:55:04.327732Z","time spent":"472.649155ms","remote":"127.0.0.1:55220","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-16T00:55:04.800914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.514227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T00:55:04.800957Z","caller":"traceutil/trace.go:171","msg":"trace[936112498] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:24700; }","duration":"126.577528ms","start":"2026-10-16T00:55:04.674368Z","end":"2026-10-16T00:55:04.800945Z","steps":["trace[936112498] 'agreement among raft nodes before linearized reading' (duration: 126.506557ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:55:04.801068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.436952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-10-16T00:55:04.801116Z","caller":"traceutil/trace.go:171","msg":"trace[1590696275] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:24700; }","duration":"199.511974ms","start":"2026-10-16T00:55:04.60159Z","end":"2026-10-16T00:55:04.801102Z","steps":["trace[1590696275] 'agreement among raft nodes before linearized reading' (duration: 199.401862ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:55:04.801243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.107694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:31 size:271396"} {"level":"info","ts":"2026-10-16T00:55:04.801295Z","caller":"traceutil/trace.go:171","msg":"trace[1825549044] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:31; response_revision:24700; }","duration":"248.194665ms","start":"2026-10-16T00:55:04.553086Z","end":"2026-10-16T00:55:04.801281Z","steps":["trace[1825549044] 'agreement among raft nodes before linearized reading' (duration: 247.734427ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:55:04.799321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"860.372124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T00:55:04.803825Z","caller":"traceutil/trace.go:171","msg":"trace[115979498] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24697; }","duration":"865.036171ms","start":"2026-10-16T00:55:03.938773Z","end":"2026-10-16T00:55:04.80381Z","steps":["trace[115979498] 'agreement among raft nodes before linearized reading' (duration: 860.350434ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:55:04.803869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:55:03.938754Z","time spent":"865.101381ms","remote":"127.0.0.1:54960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-16T00:56:05.395465Z","caller":"traceutil/trace.go:171","msg":"trace[1870326085] transaction","detail":"{read_only:false; response_revision:25257; number_of_response:1; }","duration":"283.720505ms","start":"2026-10-16T00:56:05.111722Z","end":"2026-10-16T00:56:05.395442Z","steps":["trace[1870326085] 'process raft request' (duration: 278.999279ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:56:05.395695Z","caller":"traceutil/trace.go:171","msg":"trace[252500803] transaction","detail":"{read_only:false; response_revision:25258; number_of_response:1; }","duration":"282.183327ms","start":"2026-10-16T00:56:05.11349Z","end":"2026-10-16T00:56:05.395673Z","steps":["trace[252500803] 'process raft request' (duration: 281.913742ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:56:05.395749Z","caller":"traceutil/trace.go:171","msg":"trace[407023906] linearizableReadLoop","detail":"{readStateIndex:26085; appliedIndex:26081; }","duration":"232.754623ms","start":"2026-10-16T00:56:05.162985Z","end":"2026-10-16T00:56:05.39574Z","steps":["trace[407023906] 'read index received' (duration: 227.66146ms)","trace[407023906] 'applied index is now lower than readState.Index' (duration: 5.092273ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:56:05.395723Z","caller":"traceutil/trace.go:171","msg":"trace[2112361735] transaction","detail":"{read_only:false; response_revision:25259; number_of_response:1; }","duration":"282.004434ms","start":"2026-10-16T00:56:05.113703Z","end":"2026-10-16T00:56:05.395707Z","steps":["trace[2112361735] 'process raft request' (duration: 281.863601ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:56:05.395767Z","caller":"traceutil/trace.go:171","msg":"trace[925168578] transaction","detail":"{read_only:false; response_revision:25260; number_of_response:1; }","duration":"281.971023ms","start":"2026-10-16T00:56:05.11378Z","end":"2026-10-16T00:56:05.395751Z","steps":["trace[925168578] 'process raft request' (duration: 281.888371ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:56:05.395848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.850094ms","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:6"} {"level":"info","ts":"2026-10-16T00:56:05.395874Z","caller":"traceutil/trace.go:171","msg":"trace[1399522815] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:25260; }","duration":"232.928415ms","start":"2026-10-16T00:56:05.162936Z","end":"2026-10-16T00:56:05.395864Z","steps":["trace[1399522815] 'agreement among raft nodes before linearized reading' (duration: 232.853113ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:56:05.395948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.677928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-10-16T00:56:05.395962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.96852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-16T00:56:05.395983Z","caller":"traceutil/trace.go:171","msg":"trace[1326435058] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25260; }","duration":"180.78727ms","start":"2026-10-16T00:56:05.215184Z","end":"2026-10-16T00:56:05.395971Z","steps":["trace[1326435058] 'agreement among raft nodes before linearized reading' (duration: 180.676578ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:56:05.395992Z","caller":"traceutil/trace.go:171","msg":"trace[1318206582] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25260; }","duration":"165.019062ms","start":"2026-10-16T00:56:05.230964Z","end":"2026-10-16T00:56:05.395983Z","steps":["trace[1318206582] 'agreement among raft nodes before linearized reading' (duration: 164.92164ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:56:05.396164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.799196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-10-16T00:56:05.396163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.725867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-16T00:56:05.396189Z","caller":"traceutil/trace.go:171","msg":"trace[120301141] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25260; }","duration":"171.857988ms","start":"2026-10-16T00:56:05.224322Z","end":"2026-10-16T00:56:05.396181Z","steps":["trace[120301141] 'agreement among raft nodes before linearized reading' (duration: 171.737906ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:56:05.396193Z","caller":"traceutil/trace.go:171","msg":"trace[559731965] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25260; }","duration":"169.774188ms","start":"2026-10-16T00:56:05.226411Z","end":"2026-10-16T00:56:05.396185Z","steps":["trace[559731965] 'agreement among raft nodes before linearized reading' (duration: 169.646125ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:56:05.396275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.640517ms","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-16T00:56:05.396413Z","caller":"traceutil/trace.go:171","msg":"trace[663408844] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25260; }","duration":"163.82623ms","start":"2026-10-16T00:56:05.232552Z","end":"2026-10-16T00:56:05.396378Z","steps":["trace[663408844] 'agreement among raft nodes before linearized reading' (duration: 163.379142ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:56:08.075872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.460072ms","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":"warn","ts":"2026-10-16T00:56:08.075876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.140961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T00:56:08.075965Z","caller":"traceutil/trace.go:171","msg":"trace[1194086183] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:25280; }","duration":"507.609485ms","start":"2026-10-16T00:56:07.568333Z","end":"2026-10-16T00:56:08.075942Z","steps":["trace[1194086183] 'count revisions from in-memory index tree' (duration: 507.33587ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:56:08.076032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:56:07.56831Z","time spent":"507.710857ms","remote":"127.0.0.1:55172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":30,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"info","ts":"2026-10-16T00:56:08.07605Z","caller":"traceutil/trace.go:171","msg":"trace[1303819030] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25280; }","duration":"131.368766ms","start":"2026-10-16T00:56:07.944638Z","end":"2026-10-16T00:56:08.076007Z","steps":["trace[1303819030] 'range keys from in-memory index tree' (duration: 131.0672ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:56:08.076168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.529664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-16T00:56:08.076272Z","caller":"traceutil/trace.go:171","msg":"trace[1833620165] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25280; }","duration":"264.670107ms","start":"2026-10-16T00:56:07.811579Z","end":"2026-10-16T00:56:08.076249Z","steps":["trace[1833620165] 'range keys from in-memory index tree' (duration: 264.338921ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:56:08.873523Z","caller":"traceutil/trace.go:171","msg":"trace[1029882376] transaction","detail":"{read_only:false; response_revision:25282; number_of_response:1; }","duration":"186.574056ms","start":"2026-10-16T00:56:08.686916Z","end":"2026-10-16T00:56:08.87349Z","steps":["trace[1029882376] 'process raft request' (duration: 186.402533ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:56:09.235316Z","caller":"traceutil/trace.go:171","msg":"trace[1543203267] transaction","detail":"{read_only:false; response_revision:25283; number_of_response:1; }","duration":"164.601723ms","start":"2026-10-16T00:56:09.070688Z","end":"2026-10-16T00:56:09.23529Z","steps":["trace[1543203267] 'process raft request' (duration: 164.42715ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:56:09.450002Z","caller":"traceutil/trace.go:171","msg":"trace[809509568] transaction","detail":"{read_only:false; response_revision:25284; number_of_response:1; }","duration":"283.928999ms","start":"2026-10-16T00:56:09.166034Z","end":"2026-10-16T00:56:09.449963Z","steps":["trace[809509568] 'process raft request' (duration: 283.677025ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:56:42.949011Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006789.wal"} {"level":"info","ts":"2026-10-16T00:56:48.715467Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22763} {"level":"info","ts":"2026-10-16T00:56:48.850321Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22763,"took":"131.048909ms","hash":1692393857,"current-db-size-bytes":57741312,"current-db-size":"58 MB","current-db-size-in-use-bytes":36425728,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-16T00:56:48.850391Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1692393857,"revision":22763,"compact-revision":19935} {"level":"info","ts":"2026-10-16T01:00:03.576679Z","caller":"traceutil/trace.go:171","msg":"trace[1807158858] transaction","detail":"{read_only:false; response_revision:27659; number_of_response:1; }","duration":"113.164941ms","start":"2026-10-16T01:00:03.463489Z","end":"2026-10-16T01:00:03.576654Z","steps":["trace[1807158858] 'process raft request' (duration: 112.641492ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T01:01:48.737053Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25722} {"level":"info","ts":"2026-10-16T01:01:48.880725Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25722,"took":"139.105665ms","hash":1752586863,"current-db-size-bytes":57741312,"current-db-size":"58 MB","current-db-size-in-use-bytes":37691392,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-10-16T01:01:48.880797Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1752586863,"revision":25722,"compact-revision":22763} {"level":"info","ts":"2026-10-16T01:03:07.08799Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ded76e421897a43d","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-16T01:03:07.094616Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-16T01:03:07.094891Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-16T01:06:48.743318Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28508} {"level":"info","ts":"2026-10-16T01:06:48.882185Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28508,"took":"134.945686ms","hash":467757215,"current-db-size-bytes":57741312,"current-db-size":"58 MB","current-db-size-in-use-bytes":31481856,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-16T01:06:48.882247Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":467757215,"revision":28508,"compact-revision":25722} {"level":"info","ts":"2026-10-16T01:11:48.749904Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30762} {"level":"info","ts":"2026-10-16T01:11:48.885291Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30762,"took":"132.042281ms","hash":199402909,"current-db-size-bytes":57741312,"current-db-size":"58 MB","current-db-size-in-use-bytes":33341440,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-16T01:11:48.885385Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":199402909,"revision":30762,"compact-revision":28508} {"level":"info","ts":"2026-10-16T01:16:48.76054Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33026} {"level":"info","ts":"2026-10-16T01:16:48.923866Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33026,"took":"157.492279ms","hash":976570901,"current-db-size-bytes":58785792,"current-db-size":"59 MB","current-db-size-in-use-bytes":31961088,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-16T01:16:48.923931Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":976570901,"revision":33026,"compact-revision":30762} {"level":"info","ts":"2026-10-16T01:21:48.768781Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35324} {"level":"info","ts":"2026-10-16T01:21:48.913812Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35324,"took":"133.048559ms","hash":1599118822,"current-db-size-bytes":60542976,"current-db-size":"60 MB","current-db-size-in-use-bytes":33411072,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-16T01:21:48.913914Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1599118822,"revision":35324,"compact-revision":33026} {"level":"info","ts":"2026-10-16T01:24:32.833527Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ded76e421897a43d","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-16T01:24:32.840725Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-16T01:24:32.841124Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-16T01:26:48.775289Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37550} {"level":"info","ts":"2026-10-16T01:26:48.90844Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37550,"took":"128.884683ms","hash":1725787237,"current-db-size-bytes":62234624,"current-db-size":"62 MB","current-db-size-in-use-bytes":32768000,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-16T01:26:48.908502Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1725787237,"revision":37550,"compact-revision":35324}