{"level":"warn","ts":"2026-04-19T13:32:39.040542Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-19T13:32:39.040678Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.12: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://199.19.213.12:2380","--initial-cluster=instance=https://199.19.213.12:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.12:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.12: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-04-19T13:32:39.040768Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-19T13:32:39.040784Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.12:2380"]} {"level":"info","ts":"2026-04-19T13:32:39.040807Z","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-04-19T13:32:39.041515Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.12:2379"]} {"level":"info","ts":"2026-04-19T13:32:39.0416Z","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://199.19.213.12:2380"],"listen-peer-urls":["https://199.19.213.12:2380"],"advertise-client-urls":["https://199.19.213.12:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.12:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.12: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-04-19T13:32:39.045653Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.802565ms"} {"level":"info","ts":"2026-04-19T13:32:39.05151Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"790561176553eafe","cluster-id":"e32c72c573d8545f"} {"level":"info","ts":"2026-04-19T13:32:39.051596Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"790561176553eafe switched to configuration voters=()"} {"level":"info","ts":"2026-04-19T13:32:39.051626Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"790561176553eafe became follower at term 0"} {"level":"info","ts":"2026-04-19T13:32:39.051641Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 790561176553eafe [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-19T13:32:39.051648Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"790561176553eafe became follower at term 1"} {"level":"info","ts":"2026-04-19T13:32:39.051689Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"790561176553eafe switched to configuration voters=(8720483006584974078)"} {"level":"warn","ts":"2026-04-19T13:32:39.058308Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-19T13:32:39.060158Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-19T13:32:39.061711Z","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-04-19T13:32:39.065086Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"790561176553eafe","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-19T13:32:39.065515Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"790561176553eafe","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-19T13:32:39.065717Z","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-04-19T13:32:39.065848Z","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-04-19T13:32:39.065866Z","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-04-19T13:32:39.066962Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"790561176553eafe switched to configuration voters=(8720483006584974078)"} {"level":"info","ts":"2026-04-19T13:32:39.067172Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e32c72c573d8545f","local-member-id":"790561176553eafe","added-peer-id":"790561176553eafe","added-peer-peer-urls":["https://199.19.213.12:2380"]} {"level":"info","ts":"2026-04-19T13:32:39.071511Z","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-04-19T13:32:39.07166Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.12:2380"} {"level":"info","ts":"2026-04-19T13:32:39.07173Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.12:2380"} {"level":"info","ts":"2026-04-19T13:32:39.071838Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"790561176553eafe","initial-advertise-peer-urls":["https://199.19.213.12:2380"],"listen-peer-urls":["https://199.19.213.12:2380"],"advertise-client-urls":["https://199.19.213.12:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.12:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-19T13:32:39.071881Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-19T13:32:39.652789Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"790561176553eafe is starting a new election at term 1"} {"level":"info","ts":"2026-04-19T13:32:39.652881Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"790561176553eafe became pre-candidate at term 1"} {"level":"info","ts":"2026-04-19T13:32:39.652917Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"790561176553eafe received MsgPreVoteResp from 790561176553eafe at term 1"} {"level":"info","ts":"2026-04-19T13:32:39.652936Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"790561176553eafe became candidate at term 2"} {"level":"info","ts":"2026-04-19T13:32:39.652945Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"790561176553eafe received MsgVoteResp from 790561176553eafe at term 2"} {"level":"info","ts":"2026-04-19T13:32:39.652959Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"790561176553eafe became leader at term 2"} {"level":"info","ts":"2026-04-19T13:32:39.65297Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 790561176553eafe elected leader 790561176553eafe at term 2"} {"level":"info","ts":"2026-04-19T13:32:39.654261Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-19T13:32:39.655284Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"790561176553eafe","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.12:2379]}","request-path":"/0/members/790561176553eafe/attributes","cluster-id":"e32c72c573d8545f","publish-timeout":"7s"} {"level":"info","ts":"2026-04-19T13:32:39.655493Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-19T13:32:39.655583Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-19T13:32:39.656105Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e32c72c573d8545f","local-member-id":"790561176553eafe","cluster-version":"3.5"} {"level":"info","ts":"2026-04-19T13:32:39.656255Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-19T13:32:39.656298Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-19T13:32:39.657546Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-19T13:32:39.657639Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-19T13:32:39.658979Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.12:2379"} {"level":"info","ts":"2026-04-19T13:32:39.659235Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-04-19T13:33:07.061793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.597888ms","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-04-19T13:33:07.061883Z","caller":"traceutil/trace.go:171","msg":"trace[215558289] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:425; }","duration":"319.758433ms","start":"2026-04-19T13:33:06.742107Z","end":"2026-04-19T13:33:07.061865Z","steps":["trace[215558289] 'range keys from in-memory index tree' (duration: 319.465895ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:33:07.061928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:33:06.742087Z","time spent":"319.833124ms","remote":"127.0.0.1:36286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-19T13:33:07.061634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.124676ms","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-04-19T13:33:07.06201Z","caller":"traceutil/trace.go:171","msg":"trace[109026429] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:425; }","duration":"261.584729ms","start":"2026-04-19T13:33:06.800401Z","end":"2026-04-19T13:33:07.061986Z","steps":["trace[109026429] 'range keys from in-memory index tree' (duration: 261.015474ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:33:11.966115Z","caller":"traceutil/trace.go:171","msg":"trace[2139734497] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"230.444192ms","start":"2026-04-19T13:33:11.735644Z","end":"2026-04-19T13:33:11.966088Z","steps":["trace[2139734497] 'process raft request' (duration: 216.166823ms)","trace[2139734497] 'compare' (duration: 14.064383ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:33:11.967289Z","caller":"traceutil/trace.go:171","msg":"trace[1833569291] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"215.258629ms","start":"2026-04-19T13:33:11.752014Z","end":"2026-04-19T13:33:11.967273Z","steps":["trace[1833569291] 'process raft request' (duration: 214.581381ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:33:11.967429Z","caller":"traceutil/trace.go:171","msg":"trace[510130595] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"213.701756ms","start":"2026-04-19T13:33:11.753693Z","end":"2026-04-19T13:33:11.967395Z","steps":["trace[510130595] 'process raft request' (duration: 213.11207ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:33:11.967939Z","caller":"traceutil/trace.go:171","msg":"trace[601842637] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"185.664654ms","start":"2026-04-19T13:33:11.782248Z","end":"2026-04-19T13:33:11.967913Z","steps":["trace[601842637] 'process raft request' (duration: 184.820711ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:33:12.344877Z","caller":"traceutil/trace.go:171","msg":"trace[937846740] linearizableReadLoop","detail":"{readStateIndex:550; appliedIndex:549; }","duration":"108.019329ms","start":"2026-04-19T13:33:12.236832Z","end":"2026-04-19T13:33:12.344851Z","steps":["trace[937846740] 'read index received' (duration: 107.752152ms)","trace[937846740] 'applied index is now lower than readState.Index' (duration: 266.407µs)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:33:12.345061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.214055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:33:12.345112Z","caller":"traceutil/trace.go:171","msg":"trace[1243675768] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:528; }","duration":"108.319518ms","start":"2026-04-19T13:33:12.23678Z","end":"2026-04-19T13:33:12.345099Z","steps":["trace[1243675768] 'agreement among raft nodes before linearized reading' (duration: 108.205665ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:33:12.345538Z","caller":"traceutil/trace.go:171","msg":"trace[1625170379] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"129.510755ms","start":"2026-04-19T13:33:12.216015Z","end":"2026-04-19T13:33:12.345526Z","steps":["trace[1625170379] 'process raft request' (duration: 128.649511ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:33:12.362942Z","caller":"traceutil/trace.go:171","msg":"trace[1480325860] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"104.517734ms","start":"2026-04-19T13:33:12.258405Z","end":"2026-04-19T13:33:12.362923Z","steps":["trace[1480325860] 'process raft request' (duration: 104.223706ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:33:12.363181Z","caller":"traceutil/trace.go:171","msg":"trace[1071085485] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"122.780871ms","start":"2026-04-19T13:33:12.240369Z","end":"2026-04-19T13:33:12.36315Z","steps":["trace[1071085485] 'process raft request' (duration: 121.99761ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:33:12.498354Z","caller":"traceutil/trace.go:171","msg":"trace[1235825720] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"107.926597ms","start":"2026-04-19T13:33:12.390396Z","end":"2026-04-19T13:33:12.498322Z","steps":["trace[1235825720] 'process raft request' (duration: 65.113352ms)","trace[1235825720] 'compare' (duration: 42.663251ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:33:18.309097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.389765ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-19T13:33:18.309191Z","caller":"traceutil/trace.go:171","msg":"trace[1662332648] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:602; }","duration":"132.094885ms","start":"2026-04-19T13:33:18.177082Z","end":"2026-04-19T13:33:18.309177Z","steps":["trace[1662332648] 'read index received' (duration: 14.464774ms)","trace[1662332648] 'applied index is now lower than readState.Index' (duration: 117.629181ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:33:18.309259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.176918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:33:18.309273Z","caller":"traceutil/trace.go:171","msg":"trace[221328323] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:579; }","duration":"132.264319ms","start":"2026-04-19T13:33:18.177004Z","end":"2026-04-19T13:33:18.309268Z","steps":["trace[221328323] 'agreement among raft nodes before linearized reading' (duration: 132.216218ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:33:18.65882Z","caller":"traceutil/trace.go:171","msg":"trace[1970991509] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"299.259395ms","start":"2026-04-19T13:33:18.359528Z","end":"2026-04-19T13:33:18.658787Z","steps":["trace[1970991509] 'process raft request' (duration: 298.34914ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:33:19.309154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.552689ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-19T13:33:19.309524Z","caller":"traceutil/trace.go:171","msg":"trace[1159841872] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"562.970491ms","start":"2026-04-19T13:33:18.746449Z","end":"2026-04-19T13:33:19.309419Z","steps":["trace[1159841872] 'process raft request' (duration: 304.418584ms)","trace[1159841872] 'compare' (duration: 257.306873ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:33:19.309672Z","caller":"traceutil/trace.go:171","msg":"trace[711767635] linearizableReadLoop","detail":"{readStateIndex:606; appliedIndex:605; }","duration":"216.371939ms","start":"2026-04-19T13:33:19.09322Z","end":"2026-04-19T13:33:19.309591Z","steps":["trace[711767635] 'read index received' (duration: 74.492µs)","trace[711767635] 'applied index is now lower than readState.Index' (duration: 216.290747ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:33:19.309842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:33:18.746438Z","time spent":"563.216578ms","remote":"127.0.0.1:36286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-19T13:33:19.309896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.636146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:33:19.310132Z","caller":"traceutil/trace.go:171","msg":"trace[1448416727] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:582; }","duration":"216.922434ms","start":"2026-04-19T13:33:19.093184Z","end":"2026-04-19T13:33:19.310106Z","steps":["trace[1448416727] 'agreement among raft nodes before linearized reading' (duration: 216.575225ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:33:19.311188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.88044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:33:19.311259Z","caller":"traceutil/trace.go:171","msg":"trace[1660303431] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:583; }","duration":"217.961052ms","start":"2026-04-19T13:33:19.093281Z","end":"2026-04-19T13:33:19.311242Z","steps":["trace[1660303431] 'agreement among raft nodes before linearized reading' (duration: 217.835239ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:33:19.311461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.788538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:33:19.311507Z","caller":"traceutil/trace.go:171","msg":"trace[343740127] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:583; }","duration":"188.8685ms","start":"2026-04-19T13:33:19.122624Z","end":"2026-04-19T13:33:19.311493Z","steps":["trace[343740127] 'agreement among raft nodes before linearized reading' (duration: 188.768797ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:33:19.311614Z","caller":"traceutil/trace.go:171","msg":"trace[1757085024] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"164.639301ms","start":"2026-04-19T13:33:19.14693Z","end":"2026-04-19T13:33:19.311569Z","steps":["trace[1757085024] 'process raft request' (duration: 162.860583ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:33:19.311693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.313022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-19T13:33:19.311681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.029615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-19T13:33:19.311743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.383244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:33:19.311791Z","caller":"traceutil/trace.go:171","msg":"trace[1606010311] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:583; }","duration":"189.495287ms","start":"2026-04-19T13:33:19.122281Z","end":"2026-04-19T13:33:19.311776Z","steps":["trace[1606010311] 'agreement among raft nodes before linearized reading' (duration: 189.387614ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:33:19.31181Z","caller":"traceutil/trace.go:171","msg":"trace[1515760969] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:583; }","duration":"178.212541ms","start":"2026-04-19T13:33:19.133565Z","end":"2026-04-19T13:33:19.311777Z","steps":["trace[1515760969] 'agreement among raft nodes before linearized reading' (duration: 177.855471ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:33:19.311738Z","caller":"traceutil/trace.go:171","msg":"trace[1475400247] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:583; }","duration":"189.365313ms","start":"2026-04-19T13:33:19.122357Z","end":"2026-04-19T13:33:19.311722Z","steps":["trace[1475400247] 'agreement among raft nodes before linearized reading' (duration: 189.303371ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:33:19.312121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.359968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-19T13:33:19.312308Z","caller":"traceutil/trace.go:171","msg":"trace[1410568301] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:583; }","duration":"173.571814ms","start":"2026-04-19T13:33:19.138717Z","end":"2026-04-19T13:33:19.312289Z","steps":["trace[1410568301] 'agreement among raft nodes before linearized reading' (duration: 172.643378ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:33:35.522661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.472612ms","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-04-19T13:33:35.522785Z","caller":"traceutil/trace.go:171","msg":"trace[1532613852] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:745; }","duration":"126.662647ms","start":"2026-04-19T13:33:35.396103Z","end":"2026-04-19T13:33:35.522765Z","steps":["trace[1532613852] 'range keys from in-memory index tree' (duration: 126.269637ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:33:35.523065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.521773ms","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-04-19T13:33:35.523153Z","caller":"traceutil/trace.go:171","msg":"trace[404844731] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:745; }","duration":"126.626326ms","start":"2026-04-19T13:33:35.396507Z","end":"2026-04-19T13:33:35.523133Z","steps":["trace[404844731] 'range keys from in-memory index tree' (duration: 126.300687ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:33:35.523061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.744799ms","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-04-19T13:33:35.523231Z","caller":"traceutil/trace.go:171","msg":"trace[1456217440] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:745; }","duration":"126.918374ms","start":"2026-04-19T13:33:35.396279Z","end":"2026-04-19T13:33:35.523198Z","steps":["trace[1456217440] 'range keys from in-memory index tree' (duration: 126.354888ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:34:04.603483Z","caller":"traceutil/trace.go:171","msg":"trace[1822374530] transaction","detail":"{read_only:false; response_revision:1143; number_of_response:1; }","duration":"177.246275ms","start":"2026-04-19T13:34:04.426213Z","end":"2026-04-19T13:34:04.60346Z","steps":["trace[1822374530] 'process raft request' (duration: 177.110961ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:34:46.945995Z","caller":"traceutil/trace.go:171","msg":"trace[1389240791] transaction","detail":"{read_only:false; response_revision:1783; number_of_response:1; }","duration":"351.426627ms","start":"2026-04-19T13:34:46.594546Z","end":"2026-04-19T13:34:46.945973Z","steps":["trace[1389240791] 'process raft request' (duration: 351.278503ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:34:46.946153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:34:46.59452Z","time spent":"351.54814ms","remote":"127.0.0.1:36230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3731,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-19T13:34:46.999359Z","caller":"traceutil/trace.go:171","msg":"trace[784813887] transaction","detail":"{read_only:false; response_revision:1784; number_of_response:1; }","duration":"210.216178ms","start":"2026-04-19T13:34:46.789115Z","end":"2026-04-19T13:34:46.999331Z","steps":["trace[784813887] 'process raft request' (duration: 210.004222ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:34:46.999474Z","caller":"traceutil/trace.go:171","msg":"trace[1460705349] linearizableReadLoop","detail":"{readStateIndex:1839; appliedIndex:1837; }","duration":"189.370244ms","start":"2026-04-19T13:34:46.81008Z","end":"2026-04-19T13:34:46.99945Z","steps":["trace[1460705349] 'read index received' (duration: 135.84243ms)","trace[1460705349] 'applied index is now lower than readState.Index' (duration: 53.525784ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:34:46.999531Z","caller":"traceutil/trace.go:171","msg":"trace[1752492640] transaction","detail":"{read_only:false; response_revision:1785; number_of_response:1; }","duration":"129.26802ms","start":"2026-04-19T13:34:46.870226Z","end":"2026-04-19T13:34:46.999494Z","steps":["trace[1752492640] 'process raft request' (duration: 129.045913ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:34:46.999631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.53959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-19T13:34:46.999676Z","caller":"traceutil/trace.go:171","msg":"trace[2102127711] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1785; }","duration":"189.626681ms","start":"2026-04-19T13:34:46.810037Z","end":"2026-04-19T13:34:46.999664Z","steps":["trace[2102127711] 'agreement among raft nodes before linearized reading' (duration: 189.501738ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:35:28.794449Z","caller":"traceutil/trace.go:171","msg":"trace[513059420] transaction","detail":"{read_only:false; response_revision:2123; number_of_response:1; }","duration":"116.380371ms","start":"2026-04-19T13:35:28.678046Z","end":"2026-04-19T13:35:28.794426Z","steps":["trace[513059420] 'process raft request' (duration: 116.229356ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:35:29.368616Z","caller":"traceutil/trace.go:171","msg":"trace[2022768028] linearizableReadLoop","detail":"{readStateIndex:2199; appliedIndex:2198; }","duration":"191.733981ms","start":"2026-04-19T13:35:29.176841Z","end":"2026-04-19T13:35:29.368575Z","steps":["trace[2022768028] 'read index received' (duration: 98.64041ms)","trace[2022768028] 'applied index is now lower than readState.Index' (duration: 93.091841ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:35:29.36868Z","caller":"traceutil/trace.go:171","msg":"trace[457410732] transaction","detail":"{read_only:false; response_revision:2125; number_of_response:1; }","duration":"226.138002ms","start":"2026-04-19T13:35:29.142487Z","end":"2026-04-19T13:35:29.368625Z","steps":["trace[457410732] 'process raft request' (duration: 133.103143ms)","trace[457410732] 'compare' (duration: 92.799253ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:35:29.368899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.025918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:35:29.368956Z","caller":"traceutil/trace.go:171","msg":"trace[143678531] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2125; }","duration":"192.151522ms","start":"2026-04-19T13:35:29.176789Z","end":"2026-04-19T13:35:29.368941Z","steps":["trace[143678531] 'agreement among raft nodes before linearized reading' (duration: 192.023078ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:35:29.589853Z","caller":"traceutil/trace.go:171","msg":"trace[172073709] transaction","detail":"{read_only:false; response_revision:2126; number_of_response:1; }","duration":"216.298355ms","start":"2026-04-19T13:35:29.373499Z","end":"2026-04-19T13:35:29.589797Z","steps":["trace[172073709] 'process raft request' (duration: 214.902227ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:37:43.603685Z","caller":"traceutil/trace.go:171","msg":"trace[1617732996] linearizableReadLoop","detail":"{readStateIndex:3399; appliedIndex:3398; }","duration":"114.12202ms","start":"2026-04-19T13:37:43.489527Z","end":"2026-04-19T13:37:43.603649Z","steps":["trace[1617732996] 'read index received' (duration: 113.879293ms)","trace[1617732996] 'applied index is now lower than readState.Index' (duration: 241.537µs)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:37:43.603771Z","caller":"traceutil/trace.go:171","msg":"trace[42947059] transaction","detail":"{read_only:false; response_revision:3265; number_of_response:1; }","duration":"124.01969ms","start":"2026-04-19T13:37:43.479719Z","end":"2026-04-19T13:37:43.603739Z","steps":["trace[42947059] 'process raft request' (duration: 123.721142ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:37:43.604048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.487869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-04-19T13:37:43.604154Z","caller":"traceutil/trace.go:171","msg":"trace[1304556463] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:3265; }","duration":"114.672035ms","start":"2026-04-19T13:37:43.489461Z","end":"2026-04-19T13:37:43.604133Z","steps":["trace[1304556463] 'agreement among raft nodes before linearized reading' (duration: 114.338846ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:37:43.711967Z","caller":"traceutil/trace.go:171","msg":"trace[670667296] transaction","detail":"{read_only:false; response_revision:3267; number_of_response:1; }","duration":"100.982899ms","start":"2026-04-19T13:37:43.610934Z","end":"2026-04-19T13:37:43.711917Z","steps":["trace[670667296] 'process raft request' (duration: 59.27788ms)","trace[670667296] 'compare' (duration: 41.391961ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:39:01.395423Z","caller":"traceutil/trace.go:171","msg":"trace[1288196441] transaction","detail":"{read_only:false; response_revision:4399; number_of_response:1; }","duration":"105.852179ms","start":"2026-04-19T13:39:01.289545Z","end":"2026-04-19T13:39:01.395397Z","steps":["trace[1288196441] 'process raft request' (duration: 105.450948ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:39:02.277256Z","caller":"traceutil/trace.go:171","msg":"trace[1427589976] linearizableReadLoop","detail":"{readStateIndex:4651; appliedIndex:4650; }","duration":"125.963653ms","start":"2026-04-19T13:39:02.151267Z","end":"2026-04-19T13:39:02.27723Z","steps":["trace[1427589976] 'read index received' (duration: 125.790578ms)","trace[1427589976] 'applied index is now lower than readState.Index' (duration: 171.535µs)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:39:02.277334Z","caller":"traceutil/trace.go:171","msg":"trace[1742651988] transaction","detail":"{read_only:false; response_revision:4460; number_of_response:1; }","duration":"143.733383ms","start":"2026-04-19T13:39:02.13358Z","end":"2026-04-19T13:39:02.277313Z","steps":["trace[1742651988] 'process raft request' (duration: 143.400924ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:39:02.277569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.286733ms","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-04-19T13:39:02.277621Z","caller":"traceutil/trace.go:171","msg":"trace[1830681441] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4460; }","duration":"126.385715ms","start":"2026-04-19T13:39:02.151223Z","end":"2026-04-19T13:39:02.277609Z","steps":["trace[1830681441] 'agreement among raft nodes before linearized reading' (duration: 126.142609ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:39:02.278527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.188903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:39:02.278638Z","caller":"traceutil/trace.go:171","msg":"trace[748502951] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4462; }","duration":"100.357297ms","start":"2026-04-19T13:39:02.178254Z","end":"2026-04-19T13:39:02.278611Z","steps":["trace[748502951] 'agreement among raft nodes before linearized reading' (duration: 100.166132ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:39:04.215178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.582468ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-19T13:39:04.215361Z","caller":"traceutil/trace.go:171","msg":"trace[204420984] linearizableReadLoop","detail":"{readStateIndex:4681; appliedIndex:4680; }","duration":"324.314553ms","start":"2026-04-19T13:39:03.891011Z","end":"2026-04-19T13:39:04.215325Z","steps":["trace[204420984] 'read index received' (duration: 167.385396ms)","trace[204420984] 'applied index is now lower than readState.Index' (duration: 156.926807ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:39:04.215422Z","caller":"traceutil/trace.go:171","msg":"trace[1557899056] transaction","detail":"{read_only:false; response_revision:4488; number_of_response:1; }","duration":"329.228408ms","start":"2026-04-19T13:39:03.886096Z","end":"2026-04-19T13:39:04.215324Z","steps":["trace[1557899056] 'process raft request' (duration: 172.383453ms)","trace[1557899056] 'compare' (duration: 156.308031ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:39:04.215586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:39:03.886078Z","time spent":"329.426004ms","remote":"127.0.0.1:36160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1956,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-19T13:39:04.21572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.696294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-18cb0654-b22c-43d1-92d0-1a9225536dac\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-04-19T13:39:04.215774Z","caller":"traceutil/trace.go:171","msg":"trace[2080397781] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-18cb0654-b22c-43d1-92d0-1a9225536dac; range_end:; response_count:1; response_revision:4488; }","duration":"324.765565ms","start":"2026-04-19T13:39:03.890993Z","end":"2026-04-19T13:39:04.215759Z","steps":["trace[2080397781] 'agreement among raft nodes before linearized reading' (duration: 324.487688ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:39:04.215821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:39:03.890936Z","time spent":"324.872969ms","remote":"127.0.0.1:36230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3623,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-18cb0654-b22c-43d1-92d0-1a9225536dac\" "} {"level":"warn","ts":"2026-04-19T13:39:04.21584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.841995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"warn","ts":"2026-04-19T13:39:04.215889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.588671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/storage-loki-0.18a7c5b13b22779f\" ","response":"range_response_count:1 size:943"} {"level":"info","ts":"2026-04-19T13:39:04.215973Z","caller":"traceutil/trace.go:171","msg":"trace[2020881209] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:4488; }","duration":"136.018061ms","start":"2026-04-19T13:39:04.079907Z","end":"2026-04-19T13:39:04.215925Z","steps":["trace[2020881209] 'agreement among raft nodes before linearized reading' (duration: 135.714522ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:39:04.216014Z","caller":"traceutil/trace.go:171","msg":"trace[1203274944] range","detail":"{range_begin:/registry/events/monitoring/storage-loki-0.18a7c5b13b22779f; range_end:; response_count:1; response_revision:4488; }","duration":"266.755346ms","start":"2026-04-19T13:39:03.949221Z","end":"2026-04-19T13:39:04.215976Z","steps":["trace[1203274944] 'agreement among raft nodes before linearized reading' (duration: 266.394176ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:39:04.216454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.546814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-18cb0654-b22c-43d1-92d0-1a9225536dac\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-04-19T13:39:04.216555Z","caller":"traceutil/trace.go:171","msg":"trace[1330925433] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-18cb0654-b22c-43d1-92d0-1a9225536dac; range_end:; response_count:1; response_revision:4488; }","duration":"186.725449ms","start":"2026-04-19T13:39:04.029807Z","end":"2026-04-19T13:39:04.216533Z","steps":["trace[1330925433] 'agreement among raft nodes before linearized reading' (duration: 186.4178ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:39:18.443799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.778527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-c47d949f5-6fnzb\" ","response":"range_response_count:1 size:7812"} {"level":"info","ts":"2026-04-19T13:39:18.443992Z","caller":"traceutil/trace.go:171","msg":"trace[529283269] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-c47d949f5-6fnzb; range_end:; response_count:1; response_revision:4754; }","duration":"415.033764ms","start":"2026-04-19T13:39:18.02894Z","end":"2026-04-19T13:39:18.443974Z","steps":["trace[529283269] 'agreement among raft nodes before linearized reading' (duration: 414.646553ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:39:18.444071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:39:18.028918Z","time spent":"415.139587ms","remote":"127.0.0.1:36230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":7835,"request content":"key:\"/registry/pods/openstack/memcached-memcached-c47d949f5-6fnzb\" "} {"level":"info","ts":"2026-04-19T13:39:18.443565Z","caller":"traceutil/trace.go:171","msg":"trace[945979216] linearizableReadLoop","detail":"{readStateIndex:4957; appliedIndex:4956; }","duration":"414.47965ms","start":"2026-04-19T13:39:18.029002Z","end":"2026-04-19T13:39:18.443482Z","steps":["trace[945979216] 'read index received' (duration: 341.786385ms)","trace[945979216] 'applied index is now lower than readState.Index' (duration: 72.691285ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:39:18.447642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.079103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:39:18.448005Z","caller":"traceutil/trace.go:171","msg":"trace[1104483858] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4755; }","duration":"272.483684ms","start":"2026-04-19T13:39:18.175505Z","end":"2026-04-19T13:39:18.447989Z","steps":["trace[1104483858] 'agreement among raft nodes before linearized reading' (duration: 271.773034ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:39:18.449941Z","caller":"traceutil/trace.go:171","msg":"trace[790840093] transaction","detail":"{read_only:false; response_revision:4755; number_of_response:1; }","duration":"107.452864ms","start":"2026-04-19T13:39:18.342475Z","end":"2026-04-19T13:39:18.449928Z","steps":["trace[790840093] 'process raft request' (duration: 103.600757ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:39:18.450287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.248194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-04-19T13:39:18.450344Z","caller":"traceutil/trace.go:171","msg":"trace[1246458725] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:4755; }","duration":"129.361878ms","start":"2026-04-19T13:39:18.320972Z","end":"2026-04-19T13:39:18.450334Z","steps":["trace[1246458725] 'agreement among raft nodes before linearized reading' (duration: 129.236094ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:39:25.024047Z","caller":"traceutil/trace.go:171","msg":"trace[1639675930] transaction","detail":"{read_only:false; response_revision:4914; number_of_response:1; }","duration":"118.391184ms","start":"2026-04-19T13:39:24.905627Z","end":"2026-04-19T13:39:25.024018Z","steps":["trace[1639675930] 'process raft request' (duration: 95.449892ms)","trace[1639675930] 'compare' (duration: 22.789088ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:39:25.251123Z","caller":"traceutil/trace.go:171","msg":"trace[160988734] linearizableReadLoop","detail":"{readStateIndex:5123; appliedIndex:5122; }","duration":"156.67877ms","start":"2026-04-19T13:39:25.094414Z","end":"2026-04-19T13:39:25.251093Z","steps":["trace[160988734] 'read index received' (duration: 156.549487ms)","trace[160988734] 'applied index is now lower than readState.Index' (duration: 127.813µs)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:39:25.251216Z","caller":"traceutil/trace.go:171","msg":"trace[1479130078] transaction","detail":"{read_only:false; response_revision:4916; number_of_response:1; }","duration":"158.501211ms","start":"2026-04-19T13:39:25.092691Z","end":"2026-04-19T13:39:25.251192Z","steps":["trace[1479130078] 'process raft request' (duration: 158.208243ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:39:25.251581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.430294ms","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-04-19T13:39:25.251648Z","caller":"traceutil/trace.go:171","msg":"trace[4846655] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:4916; }","duration":"119.539186ms","start":"2026-04-19T13:39:25.13209Z","end":"2026-04-19T13:39:25.251629Z","steps":["trace[4846655] 'agreement among raft nodes before linearized reading' (duration: 119.31096ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:39:25.251629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.35588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/loki-0\" ","response":"range_response_count:1 size:5906"} {"level":"info","ts":"2026-04-19T13:39:25.251749Z","caller":"traceutil/trace.go:171","msg":"trace[1101636172] range","detail":"{range_begin:/registry/pods/monitoring/loki-0; range_end:; response_count:1; response_revision:4916; }","duration":"144.527546ms","start":"2026-04-19T13:39:25.107195Z","end":"2026-04-19T13:39:25.251723Z","steps":["trace[1101636172] 'agreement among raft nodes before linearized reading' (duration: 144.169086ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:39:25.25209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.666777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:8 size:59697"} {"level":"info","ts":"2026-04-19T13:39:25.252138Z","caller":"traceutil/trace.go:171","msg":"trace[1118741662] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:8; response_revision:4916; }","duration":"157.71277ms","start":"2026-04-19T13:39:25.09441Z","end":"2026-04-19T13:39:25.252123Z","steps":["trace[1118741662] 'agreement among raft nodes before linearized reading' (duration: 156.859676ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:39:25.387927Z","caller":"traceutil/trace.go:171","msg":"trace[827644499] transaction","detail":"{read_only:false; response_revision:4918; number_of_response:1; }","duration":"122.979042ms","start":"2026-04-19T13:39:25.264915Z","end":"2026-04-19T13:39:25.387894Z","steps":["trace[827644499] 'process raft request' (duration: 122.790776ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:42:39.693154Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3238} {"level":"info","ts":"2026-04-19T13:42:39.787079Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3238,"took":"91.595791ms","hash":2318966000,"current-db-size-bytes":60510208,"current-db-size":"60 MB","current-db-size-in-use-bytes":33873920,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-19T13:42:39.78716Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2318966000,"revision":3238,"compact-revision":-1} {"level":"info","ts":"2026-04-19T13:45:16.221994Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002121.wal"} {"level":"info","ts":"2026-04-19T13:45:18.655826Z","caller":"traceutil/trace.go:171","msg":"trace[1071167036] transaction","detail":"{read_only:false; response_revision:8142; number_of_response:1; }","duration":"123.794644ms","start":"2026-04-19T13:45:18.531983Z","end":"2026-04-19T13:45:18.655777Z","steps":["trace[1071167036] 'process raft request' (duration: 120.389483ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:45:31.458184Z","caller":"traceutil/trace.go:171","msg":"trace[1039456884] transaction","detail":"{read_only:false; response_revision:8227; number_of_response:1; }","duration":"147.853755ms","start":"2026-04-19T13:45:31.310296Z","end":"2026-04-19T13:45:31.45815Z","steps":["trace[1039456884] 'process raft request' (duration: 118.631125ms)","trace[1039456884] 'compare' (duration: 29.078336ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:45:31.490149Z","caller":"traceutil/trace.go:171","msg":"trace[1671599238] linearizableReadLoop","detail":"{readStateIndex:8594; appliedIndex:8592; }","duration":"158.578844ms","start":"2026-04-19T13:45:31.331537Z","end":"2026-04-19T13:45:31.490115Z","steps":["trace[1671599238] 'read index received' (duration: 97.407731ms)","trace[1671599238] 'applied index is now lower than readState.Index' (duration: 61.170063ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:45:31.490271Z","caller":"traceutil/trace.go:171","msg":"trace[2032114732] transaction","detail":"{read_only:false; response_revision:8228; number_of_response:1; }","duration":"175.798689ms","start":"2026-04-19T13:45:31.314431Z","end":"2026-04-19T13:45:31.49023Z","steps":["trace[2032114732] 'process raft request' (duration: 175.507341ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:45:31.490975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.424167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-04-19T13:45:31.49104Z","caller":"traceutil/trace.go:171","msg":"trace[1493776572] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8228; }","duration":"159.573541ms","start":"2026-04-19T13:45:31.331446Z","end":"2026-04-19T13:45:31.49102Z","steps":["trace[1493776572] 'agreement among raft nodes before linearized reading' (duration: 158.843732ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:45:31.953669Z","caller":"traceutil/trace.go:171","msg":"trace[125293288] transaction","detail":"{read_only:false; response_revision:8234; number_of_response:1; }","duration":"142.090679ms","start":"2026-04-19T13:45:31.811549Z","end":"2026-04-19T13:45:31.953639Z","steps":["trace[125293288] 'process raft request' (duration: 67.575355ms)","trace[125293288] 'compare' (duration: 74.329408ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:45:32.653292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.173178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:45:32.653512Z","caller":"traceutil/trace.go:171","msg":"trace[1208789584] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:8236; }","duration":"334.463695ms","start":"2026-04-19T13:45:32.319017Z","end":"2026-04-19T13:45:32.653481Z","steps":["trace[1208789584] 'count revisions from in-memory index tree' (duration: 334.057034ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:45:32.653566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:45:32.318997Z","time spent":"334.554458ms","remote":"127.0.0.1:34572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true "} {"level":"warn","ts":"2026-04-19T13:45:33.530044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.802936ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-19T13:45:33.530181Z","caller":"traceutil/trace.go:171","msg":"trace[2103429260] linearizableReadLoop","detail":"{readStateIndex:8607; appliedIndex:8606; }","duration":"498.754175ms","start":"2026-04-19T13:45:33.031407Z","end":"2026-04-19T13:45:33.530162Z","steps":["trace[2103429260] 'read index received' (duration: 70.502µs)","trace[2103429260] 'applied index is now lower than readState.Index' (duration: 498.682423ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:45:33.530229Z","caller":"traceutil/trace.go:171","msg":"trace[1670377421] transaction","detail":"{read_only:false; response_revision:8239; number_of_response:1; }","duration":"565.798396ms","start":"2026-04-19T13:45:32.964389Z","end":"2026-04-19T13:45:33.530188Z","steps":["trace[1670377421] 'process raft request' (duration: 26.732423ms)","trace[1670377421] 'compare' (duration: 538.627551ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:45:33.530459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:45:32.964361Z","time spent":"565.95723ms","remote":"127.0.0.1:36112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-19T13:45:33.530748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.31764ms","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-04-19T13:45:33.530851Z","caller":"traceutil/trace.go:171","msg":"trace[185150530] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8239; }","duration":"499.500904ms","start":"2026-04-19T13:45:33.031325Z","end":"2026-04-19T13:45:33.530826Z","steps":["trace[185150530] 'agreement among raft nodes before linearized reading' (duration: 499.145375ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:45:33.530925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:45:33.031295Z","time spent":"499.620608ms","remote":"127.0.0.1:36286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-19T13:46:01.155351Z","caller":"traceutil/trace.go:171","msg":"trace[1291668215] linearizableReadLoop","detail":"{readStateIndex:8856; appliedIndex:8855; }","duration":"144.825366ms","start":"2026-04-19T13:46:01.010493Z","end":"2026-04-19T13:46:01.155318Z","steps":["trace[1291668215] 'read index received' (duration: 127.534159ms)","trace[1291668215] 'applied index is now lower than readState.Index' (duration: 17.289977ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:46:01.155444Z","caller":"traceutil/trace.go:171","msg":"trace[1889889710] transaction","detail":"{read_only:false; response_revision:8477; number_of_response:1; }","duration":"206.449147ms","start":"2026-04-19T13:46:00.948934Z","end":"2026-04-19T13:46:01.155383Z","steps":["trace[1889889710] 'process raft request' (duration: 189.066579ms)","trace[1889889710] 'compare' (duration: 16.605617ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:46:01.155654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.129384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-04-19T13:46:01.15571Z","caller":"traceutil/trace.go:171","msg":"trace[1932585106] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:8477; }","duration":"145.207966ms","start":"2026-04-19T13:46:01.010485Z","end":"2026-04-19T13:46:01.155693Z","steps":["trace[1932585106] 'agreement among raft nodes before linearized reading' (duration: 144.971349ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:46:15.186066Z","caller":"traceutil/trace.go:171","msg":"trace[1232703870] transaction","detail":"{read_only:false; response_revision:8614; number_of_response:1; }","duration":"157.816646ms","start":"2026-04-19T13:46:15.028207Z","end":"2026-04-19T13:46:15.186023Z","steps":["trace[1232703870] 'process raft request' (duration: 157.567679ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:46:15.33092Z","caller":"traceutil/trace.go:171","msg":"trace[1581713911] linearizableReadLoop","detail":"{readStateIndex:9002; appliedIndex:9001; }","duration":"144.932469ms","start":"2026-04-19T13:46:15.185957Z","end":"2026-04-19T13:46:15.330889Z","steps":["trace[1581713911] 'read index received' (duration: 115.091034ms)","trace[1581713911] 'applied index is now lower than readState.Index' (duration: 29.839985ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:46:15.331092Z","caller":"traceutil/trace.go:171","msg":"trace[13978156] transaction","detail":"{read_only:false; response_revision:8615; number_of_response:1; }","duration":"162.830651ms","start":"2026-04-19T13:46:15.168245Z","end":"2026-04-19T13:46:15.331075Z","steps":["trace[13978156] 'process raft request' (duration: 132.787731ms)","trace[13978156] 'compare' (duration: 29.713341ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:46:15.331377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.208892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:46:15.331427Z","caller":"traceutil/trace.go:171","msg":"trace[1152172984] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8615; }","duration":"156.322426ms","start":"2026-04-19T13:46:15.175094Z","end":"2026-04-19T13:46:15.331416Z","steps":["trace[1152172984] 'agreement among raft nodes before linearized reading' (duration: 156.217773ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:46:15.331673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.771234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-04-19T13:46:15.331807Z","caller":"traceutil/trace.go:171","msg":"trace[1714976921] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:8615; }","duration":"108.946758ms","start":"2026-04-19T13:46:15.222833Z","end":"2026-04-19T13:46:15.33178Z","steps":["trace[1714976921] 'agreement among raft nodes before linearized reading' (duration: 108.521586ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:46:16.274705Z","caller":"traceutil/trace.go:171","msg":"trace[179094529] linearizableReadLoop","detail":"{readStateIndex:9008; appliedIndex:9007; }","duration":"127.794426ms","start":"2026-04-19T13:46:16.146879Z","end":"2026-04-19T13:46:16.274674Z","steps":["trace[179094529] 'read index received' (duration: 127.719404ms)","trace[179094529] 'applied index is now lower than readState.Index' (duration: 73.462µs)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:46:16.274826Z","caller":"traceutil/trace.go:171","msg":"trace[1170481948] transaction","detail":"{read_only:false; response_revision:8621; number_of_response:1; }","duration":"209.084139ms","start":"2026-04-19T13:46:16.065694Z","end":"2026-04-19T13:46:16.274779Z","steps":["trace[1170481948] 'process raft request' (duration: 208.803931ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:46:16.274973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.075864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-04-19T13:46:16.275029Z","caller":"traceutil/trace.go:171","msg":"trace[1219148284] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8621; }","duration":"128.194437ms","start":"2026-04-19T13:46:16.146817Z","end":"2026-04-19T13:46:16.275012Z","steps":["trace[1219148284] 'agreement among raft nodes before linearized reading' (duration: 128.015152ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:46:16.40209Z","caller":"traceutil/trace.go:171","msg":"trace[1849891553] linearizableReadLoop","detail":"{readStateIndex:9009; appliedIndex:9008; }","duration":"127.258912ms","start":"2026-04-19T13:46:16.2748Z","end":"2026-04-19T13:46:16.402059Z","steps":["trace[1849891553] 'read index received' (duration: 42.395304ms)","trace[1849891553] 'applied index is now lower than readState.Index' (duration: 84.862768ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:46:16.40217Z","caller":"traceutil/trace.go:171","msg":"trace[871716887] transaction","detail":"{read_only:false; response_revision:8622; number_of_response:1; }","duration":"237.942727ms","start":"2026-04-19T13:46:16.164176Z","end":"2026-04-19T13:46:16.402118Z","steps":["trace[871716887] 'process raft request' (duration: 153.000466ms)","trace[871716887] 'compare' (duration: 84.795007ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:46:16.402465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.906035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:567"} {"level":"info","ts":"2026-04-19T13:46:16.40258Z","caller":"traceutil/trace.go:171","msg":"trace[914537573] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8622; }","duration":"123.031138ms","start":"2026-04-19T13:46:16.279516Z","end":"2026-04-19T13:46:16.402547Z","steps":["trace[914537573] 'agreement among raft nodes before linearized reading' (duration: 122.71208ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:46:16.402792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.198793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:552"} {"level":"info","ts":"2026-04-19T13:46:16.402905Z","caller":"traceutil/trace.go:171","msg":"trace[40795647] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8622; }","duration":"123.421738ms","start":"2026-04-19T13:46:16.279459Z","end":"2026-04-19T13:46:16.402881Z","steps":["trace[40795647] 'agreement among raft nodes before linearized reading' (duration: 122.72493ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:46:16.402861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.09328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:46:16.403161Z","caller":"traceutil/trace.go:171","msg":"trace[2036953161] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8622; }","duration":"222.470149ms","start":"2026-04-19T13:46:16.180669Z","end":"2026-04-19T13:46:16.403139Z","steps":["trace[2036953161] 'agreement among raft nodes before linearized reading' (duration: 221.510653ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:46:16.539302Z","caller":"traceutil/trace.go:171","msg":"trace[1217033866] transaction","detail":"{read_only:false; response_revision:8624; number_of_response:1; }","duration":"130.778117ms","start":"2026-04-19T13:46:16.408476Z","end":"2026-04-19T13:46:16.539255Z","steps":["trace[1217033866] 'process raft request' (duration: 122.766021ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:47:39.700829Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6622} {"level":"info","ts":"2026-04-19T13:47:39.811041Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6622,"took":"107.629943ms","hash":481345329,"current-db-size-bytes":60510208,"current-db-size":"60 MB","current-db-size-in-use-bytes":27017216,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-04-19T13:47:39.811133Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":481345329,"revision":6622,"compact-revision":3238} {"level":"info","ts":"2026-04-19T13:47:48.590892Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"790561176553eafe","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-19T13:47:48.597127Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-19T13:47:48.597218Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-19T13:52:39.707585Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9509} {"level":"info","ts":"2026-04-19T13:52:39.801713Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9509,"took":"91.54129ms","hash":1466002684,"current-db-size-bytes":60510208,"current-db-size":"60 MB","current-db-size-in-use-bytes":25194496,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-19T13:52:39.801881Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1466002684,"revision":9509,"compact-revision":6622}