{"level":"warn","ts":"2026-10-04T09:53:24.189679Z","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-04T09:53:24.189765Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.215: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.215:2380","--initial-cluster=instance=https://162.253.55.215:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.215:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.215: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-04T09:53:24.189828Z","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-04T09:53:24.189841Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.215:2380"]} {"level":"info","ts":"2026-10-04T09:53:24.18986Z","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-04T09:53:24.190499Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.215:2379"]} {"level":"info","ts":"2026-10-04T09:53:24.190581Z","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.215:2380"],"listen-peer-urls":["https://162.253.55.215:2380"],"advertise-client-urls":["https://162.253.55.215:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.215:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.215: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-04T09:53:24.193084Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.289941ms"} {"level":"info","ts":"2026-10-04T09:53:24.197547Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4b019b7871d5da9a","cluster-id":"608f860684155cc8"} {"level":"info","ts":"2026-10-04T09:53:24.197623Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4b019b7871d5da9a switched to configuration voters=()"} {"level":"info","ts":"2026-10-04T09:53:24.197642Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4b019b7871d5da9a became follower at term 0"} {"level":"info","ts":"2026-10-04T09:53:24.197656Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4b019b7871d5da9a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-04T09:53:24.197664Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4b019b7871d5da9a became follower at term 1"} {"level":"info","ts":"2026-10-04T09:53:24.1977Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4b019b7871d5da9a switched to configuration voters=(5404771969429527194)"} {"level":"warn","ts":"2026-10-04T09:53:24.211285Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-04T09:53:24.214063Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-04T09:53:24.215844Z","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-04T09:53:24.219406Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4b019b7871d5da9a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-04T09:53:24.21967Z","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-04T09:53:24.219859Z","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-04T09:53:24.219877Z","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-04T09:53:24.219901Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4b019b7871d5da9a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-04T09:53:24.223854Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4b019b7871d5da9a switched to configuration voters=(5404771969429527194)"} {"level":"info","ts":"2026-10-04T09:53:24.225114Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"608f860684155cc8","local-member-id":"4b019b7871d5da9a","added-peer-id":"4b019b7871d5da9a","added-peer-peer-urls":["https://162.253.55.215:2380"]} {"level":"info","ts":"2026-10-04T09:53:24.227802Z","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-04T09:53:24.228031Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.215:2380"} {"level":"info","ts":"2026-10-04T09:53:24.228126Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.215:2380"} {"level":"info","ts":"2026-10-04T09:53:24.228394Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4b019b7871d5da9a","initial-advertise-peer-urls":["https://162.253.55.215:2380"],"listen-peer-urls":["https://162.253.55.215:2380"],"advertise-client-urls":["https://162.253.55.215:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.215:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-04T09:53:24.228488Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-04T09:53:24.2987Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4b019b7871d5da9a is starting a new election at term 1"} {"level":"info","ts":"2026-10-04T09:53:24.298772Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4b019b7871d5da9a became pre-candidate at term 1"} {"level":"info","ts":"2026-10-04T09:53:24.29881Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4b019b7871d5da9a received MsgPreVoteResp from 4b019b7871d5da9a at term 1"} {"level":"info","ts":"2026-10-04T09:53:24.298833Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4b019b7871d5da9a became candidate at term 2"} {"level":"info","ts":"2026-10-04T09:53:24.298845Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4b019b7871d5da9a received MsgVoteResp from 4b019b7871d5da9a at term 2"} {"level":"info","ts":"2026-10-04T09:53:24.298869Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4b019b7871d5da9a became leader at term 2"} {"level":"info","ts":"2026-10-04T09:53:24.298888Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4b019b7871d5da9a elected leader 4b019b7871d5da9a at term 2"} {"level":"info","ts":"2026-10-04T09:53:24.300119Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-04T09:53:24.301345Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-04T09:53:24.301339Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4b019b7871d5da9a","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.215:2379]}","request-path":"/0/members/4b019b7871d5da9a/attributes","cluster-id":"608f860684155cc8","publish-timeout":"7s"} {"level":"info","ts":"2026-10-04T09:53:24.301399Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-04T09:53:24.301722Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-04T09:53:24.301829Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-04T09:53:24.301979Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"608f860684155cc8","local-member-id":"4b019b7871d5da9a","cluster-version":"3.5"} {"level":"info","ts":"2026-10-04T09:53:24.302129Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-04T09:53:24.302172Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-04T09:53:24.30506Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.215:2379"} {"level":"info","ts":"2026-10-04T09:53:24.305191Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-04T09:53:36.727749Z","caller":"traceutil/trace.go:171","msg":"trace[528957369] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"106.853494ms","start":"2026-10-04T09:53:36.620855Z","end":"2026-10-04T09:53:36.727709Z","steps":["trace[528957369] 'process raft request' (duration: 106.65926ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T09:54:07.901359Z","caller":"traceutil/trace.go:171","msg":"trace[1259242141] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"181.2226ms","start":"2026-10-04T09:54:07.720108Z","end":"2026-10-04T09:54:07.901331Z","steps":["trace[1259242141] 'process raft request' (duration: 181.066967ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T09:54:07.912686Z","caller":"traceutil/trace.go:171","msg":"trace[835456152] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"149.630343ms","start":"2026-10-04T09:54:07.76302Z","end":"2026-10-04T09:54:07.912651Z","steps":["trace[835456152] 'process raft request' (duration: 149.481069ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T09:54:08.385734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T09:54:07.932874Z","time spent":"452.851715ms","remote":"127.0.0.1:58596","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-10-04T09:54:08.385831Z","caller":"traceutil/trace.go:171","msg":"trace[654202621] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:656; }","duration":"164.024741ms","start":"2026-10-04T09:54:08.221777Z","end":"2026-10-04T09:54:08.385802Z","steps":["trace[654202621] 'read index received' (duration: 134.350375ms)","trace[654202621] 'applied index is now lower than readState.Index' (duration: 29.671816ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T09:54:08.386051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.262155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-10-04T09:54:08.38614Z","caller":"traceutil/trace.go:171","msg":"trace[1460954896] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:633; }","duration":"164.366748ms","start":"2026-10-04T09:54:08.221762Z","end":"2026-10-04T09:54:08.386129Z","steps":["trace[1460954896] 'agreement among raft nodes before linearized reading' (duration: 164.135233ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T09:55:39.657208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.494513ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-04T09:55:39.657405Z","caller":"traceutil/trace.go:171","msg":"trace[1949616132] transaction","detail":"{read_only:false; response_revision:1871; number_of_response:1; }","duration":"271.244492ms","start":"2026-10-04T09:55:39.38611Z","end":"2026-10-04T09:55:39.657355Z","steps":["trace[1949616132] 'process raft request' (duration: 56.789566ms)","trace[1949616132] 'compare' (duration: 213.31981ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T09:55:39.657778Z","caller":"traceutil/trace.go:171","msg":"trace[226540930] linearizableReadLoop","detail":"{readStateIndex:1942; appliedIndex:1941; }","duration":"240.734126ms","start":"2026-10-04T09:55:39.417013Z","end":"2026-10-04T09:55:39.657747Z","steps":["trace[226540930] 'read index received' (duration: 25.743749ms)","trace[226540930] 'applied index is now lower than readState.Index' (duration: 214.986807ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T09:55:39.658143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.108354ms","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-04T09:55:39.658256Z","caller":"traceutil/trace.go:171","msg":"trace[2147405646] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1872; }","duration":"241.250478ms","start":"2026-10-04T09:55:39.416927Z","end":"2026-10-04T09:55:39.658178Z","steps":["trace[2147405646] 'agreement among raft nodes before linearized reading' (duration: 240.927421ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T09:55:55.154919Z","caller":"traceutil/trace.go:171","msg":"trace[1095534229] transaction","detail":"{read_only:false; response_revision:2000; number_of_response:1; }","duration":"105.161798ms","start":"2026-10-04T09:55:55.049743Z","end":"2026-10-04T09:55:55.154904Z","steps":["trace[1095534229] 'process raft request' (duration: 104.976374ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T09:56:28.913732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.146472ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-04T09:56:28.913977Z","caller":"traceutil/trace.go:171","msg":"trace[583153488] transaction","detail":"{read_only:false; response_revision:2184; number_of_response:1; }","duration":"229.95684ms","start":"2026-10-04T09:56:28.683999Z","end":"2026-10-04T09:56:28.913956Z","steps":["trace[583153488] 'process raft request' (duration: 229.881539ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T09:56:28.914046Z","caller":"traceutil/trace.go:171","msg":"trace[162446416] linearizableReadLoop","detail":"{readStateIndex:2266; appliedIndex:2264; }","duration":"371.654077ms","start":"2026-10-04T09:56:28.542348Z","end":"2026-10-04T09:56:28.914002Z","steps":["trace[162446416] 'read index received' (duration: 9.312734ms)","trace[162446416] 'applied index is now lower than readState.Index' (duration: 362.334533ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T09:56:28.91417Z","caller":"traceutil/trace.go:171","msg":"trace[150271546] transaction","detail":"{read_only:false; response_revision:2183; number_of_response:1; }","duration":"396.686276ms","start":"2026-10-04T09:56:28.517461Z","end":"2026-10-04T09:56:28.914147Z","steps":["trace[150271546] 'process raft request' (duration: 173.048173ms)","trace[150271546] 'compare' (duration: 223.049329ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T09:56:28.914289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T09:56:28.517441Z","time spent":"396.787198ms","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T09:56:28.914268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.499804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-04T09:56:28.914347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.984414ms","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-04T09:56:28.914414Z","caller":"traceutil/trace.go:171","msg":"trace[2058651400] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2184; }","duration":"372.122077ms","start":"2026-10-04T09:56:28.54228Z","end":"2026-10-04T09:56:28.914402Z","steps":["trace[2058651400] 'agreement among raft nodes before linearized reading' (duration: 371.914313ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T09:56:28.91443Z","caller":"traceutil/trace.go:171","msg":"trace[271060430] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2184; }","duration":"303.641947ms","start":"2026-10-04T09:56:28.610715Z","end":"2026-10-04T09:56:28.914357Z","steps":["trace[271060430] 'agreement among raft nodes before linearized reading' (duration: 303.474784ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T09:56:28.914456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T09:56:28.542256Z","time spent":"372.191429ms","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-04T09:56:28.914507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T09:56:28.610701Z","time spent":"303.78951ms","remote":"127.0.0.1:58572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-04T09:57:53.186427Z","caller":"traceutil/trace.go:171","msg":"trace[1704474340] linearizableReadLoop","detail":"{readStateIndex:2941; appliedIndex:2940; }","duration":"133.605928ms","start":"2026-10-04T09:57:53.052761Z","end":"2026-10-04T09:57:53.186367Z","steps":["trace[1704474340] 'read index received' (duration: 75.507324ms)","trace[1704474340] 'applied index is now lower than readState.Index' (duration: 58.096774ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T09:57:53.186703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.905815ms","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-04T09:57:53.186802Z","caller":"traceutil/trace.go:171","msg":"trace[1933753704] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2832; }","duration":"134.056138ms","start":"2026-10-04T09:57:53.052721Z","end":"2026-10-04T09:57:53.186777Z","steps":["trace[1933753704] 'agreement among raft nodes before linearized reading' (duration: 133.767472ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T09:57:53.504592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.222816ms","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-04T09:57:53.504702Z","caller":"traceutil/trace.go:171","msg":"trace[1840591981] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2835; }","duration":"140.37591ms","start":"2026-10-04T09:57:53.364303Z","end":"2026-10-04T09:57:53.504679Z","steps":["trace[1840591981] 'range keys from in-memory index tree' (duration: 140.045993ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T09:57:53.504664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.319816ms","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-04T09:57:53.505255Z","caller":"traceutil/trace.go:171","msg":"trace[1840919321] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2835; }","duration":"143.96638ms","start":"2026-10-04T09:57:53.361263Z","end":"2026-10-04T09:57:53.505229Z","steps":["trace[1840919321] 'range keys from in-memory index tree' (duration: 143.103571ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T09:57:53.656794Z","caller":"traceutil/trace.go:171","msg":"trace[742866624] transaction","detail":"{read_only:false; response_revision:2837; number_of_response:1; }","duration":"146.18497ms","start":"2026-10-04T09:57:53.510581Z","end":"2026-10-04T09:57:53.656766Z","steps":["trace[742866624] 'process raft request' (duration: 118.944158ms)","trace[742866624] 'compare' (duration: 27.107129ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T10:01:00.754986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.110553ms","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-04T10:01:00.755081Z","caller":"traceutil/trace.go:171","msg":"trace[1416236539] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4024; }","duration":"113.273046ms","start":"2026-10-04T10:01:00.641791Z","end":"2026-10-04T10:01:00.755064Z","steps":["trace[1416236539] 'range keys from in-memory index tree' (duration: 112.942009ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:01:00.884195Z","caller":"traceutil/trace.go:171","msg":"trace[1670811558] transaction","detail":"{read_only:false; response_revision:4025; number_of_response:1; }","duration":"126.4921ms","start":"2026-10-04T10:01:00.757681Z","end":"2026-10-04T10:01:00.884173Z","steps":["trace[1670811558] 'process raft request' (duration: 126.348747ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:01:00.890609Z","caller":"traceutil/trace.go:171","msg":"trace[557389112] transaction","detail":"{read_only:false; response_revision:4026; number_of_response:1; }","duration":"130.832538ms","start":"2026-10-04T10:01:00.759756Z","end":"2026-10-04T10:01:00.890588Z","steps":["trace[557389112] 'process raft request' (duration: 130.600252ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:02:35.742756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.149081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-04T10:02:35.742866Z","caller":"traceutil/trace.go:171","msg":"trace[540907469] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5255; }","duration":"130.286124ms","start":"2026-10-04T10:02:35.612559Z","end":"2026-10-04T10:02:35.742845Z","steps":["trace[540907469] 'range keys from in-memory index tree' (duration: 130.031398ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:02:49.878566Z","caller":"traceutil/trace.go:171","msg":"trace[131728025] transaction","detail":"{read_only:false; response_revision:5513; number_of_response:1; }","duration":"128.828531ms","start":"2026-10-04T10:02:49.749707Z","end":"2026-10-04T10:02:49.878536Z","steps":["trace[131728025] 'process raft request' (duration: 128.579156ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:02:49.878793Z","caller":"traceutil/trace.go:171","msg":"trace[505782028] transaction","detail":"{read_only:false; response_revision:5514; number_of_response:1; }","duration":"128.674348ms","start":"2026-10-04T10:02:49.750095Z","end":"2026-10-04T10:02:49.878769Z","steps":["trace[505782028] 'process raft request' (duration: 128.391361ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:02:49.878946Z","caller":"traceutil/trace.go:171","msg":"trace[1181282184] transaction","detail":"{read_only:false; response_revision:5516; number_of_response:1; }","duration":"116.149987ms","start":"2026-10-04T10:02:49.762787Z","end":"2026-10-04T10:02:49.878937Z","steps":["trace[1181282184] 'process raft request' (duration: 115.998024ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:02:49.87902Z","caller":"traceutil/trace.go:171","msg":"trace[1816401851] transaction","detail":"{read_only:false; response_revision:5515; number_of_response:1; }","duration":"128.7869ms","start":"2026-10-04T10:02:49.7502Z","end":"2026-10-04T10:02:49.878987Z","steps":["trace[1816401851] 'process raft request' (duration: 128.461393ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:02:50.203214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.488318ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-04T10:02:50.203312Z","caller":"traceutil/trace.go:171","msg":"trace[1471223124] transaction","detail":"{read_only:false; response_revision:5517; number_of_response:1; }","duration":"319.943639ms","start":"2026-10-04T10:02:49.883351Z","end":"2026-10-04T10:02:50.203294Z","steps":["trace[1471223124] 'process raft request' (duration: 218.264447ms)","trace[1471223124] 'compare' (duration: 101.241442ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T10:02:50.20337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:02:49.883328Z","time spent":"320.010891ms","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T10:02:50.238762Z","caller":"traceutil/trace.go:171","msg":"trace[2099519960] transaction","detail":"{read_only:false; response_revision:5518; number_of_response:1; }","duration":"354.695219ms","start":"2026-10-04T10:02:49.884044Z","end":"2026-10-04T10:02:50.238739Z","steps":["trace[2099519960] 'process raft request' (duration: 354.533825ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:02:50.238893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:02:49.88403Z","time spent":"354.795291ms","remote":"127.0.0.1:58866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1434,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T10:02:50.239138Z","caller":"traceutil/trace.go:171","msg":"trace[434993505] transaction","detail":"{read_only:false; response_revision:5519; number_of_response:1; }","duration":"354.139037ms","start":"2026-10-04T10:02:49.884947Z","end":"2026-10-04T10:02:50.239086Z","steps":["trace[434993505] 'process raft request' (duration: 353.743568ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:02:50.239255Z","caller":"traceutil/trace.go:171","msg":"trace[1291378971] linearizableReadLoop","detail":"{readStateIndex:5730; appliedIndex:5726; }","duration":"351.702463ms","start":"2026-10-04T10:02:49.887526Z","end":"2026-10-04T10:02:50.239228Z","steps":["trace[1291378971] 'read index received' (duration: 214.174626ms)","trace[1291378971] 'applied index is now lower than readState.Index' (duration: 137.526267ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:02:50.239297Z","caller":"traceutil/trace.go:171","msg":"trace[1173434402] transaction","detail":"{read_only:false; response_revision:5520; number_of_response:1; }","duration":"352.561802ms","start":"2026-10-04T10:02:49.886708Z","end":"2026-10-04T10:02:50.239269Z","steps":["trace[1173434402] 'process raft request' (duration: 352.282965ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:02:50.239439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:02:49.886691Z","time spent":"352.672254ms","remote":"127.0.0.1:59090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3912,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T10:02:50.239478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.953398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-jrbkx\" ","response":"range_response_count:1 size:6524"} {"level":"warn","ts":"2026-10-04T10:02:50.239468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.810828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-04T10:02:50.239535Z","caller":"traceutil/trace.go:171","msg":"trace[714739943] range","detail":"{range_begin:/registry/pods/openstack/keepalived-jrbkx; range_end:; response_count:1; response_revision:5520; }","duration":"352.04739ms","start":"2026-10-04T10:02:49.887475Z","end":"2026-10-04T10:02:50.239522Z","steps":["trace[714739943] 'agreement among raft nodes before linearized reading' (duration: 351.875766ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:02:50.239548Z","caller":"traceutil/trace.go:171","msg":"trace[1482956725] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:5520; }","duration":"299.92028ms","start":"2026-10-04T10:02:49.93961Z","end":"2026-10-04T10:02:50.239531Z","steps":["trace[1482956725] 'agreement among raft nodes before linearized reading' (duration: 299.753827ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:02:50.23957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:02:49.887462Z","time spent":"352.101581ms","remote":"127.0.0.1:58786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6546,"request content":"key:\"/registry/pods/openstack/keepalived-jrbkx\" "} {"level":"warn","ts":"2026-10-04T10:02:50.239464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:02:49.884903Z","time spent":"354.374573ms","remote":"127.0.0.1:58768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":919,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T10:02:57.569714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.521692ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-04T10:02:57.569928Z","caller":"traceutil/trace.go:171","msg":"trace[1212713725] linearizableReadLoop","detail":"{readStateIndex:5905; appliedIndex:5903; }","duration":"235.105105ms","start":"2026-10-04T10:02:57.334804Z","end":"2026-10-04T10:02:57.569909Z","steps":["trace[1212713725] 'read index received' (duration: 50.650837ms)","trace[1212713725] 'applied index is now lower than readState.Index' (duration: 184.453058ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:02:57.570065Z","caller":"traceutil/trace.go:171","msg":"trace[1353149826] transaction","detail":"{read_only:false; response_revision:5691; number_of_response:1; }","duration":"241.596841ms","start":"2026-10-04T10:02:57.328436Z","end":"2026-10-04T10:02:57.570033Z","steps":["trace[1353149826] 'process raft request' (duration: 241.400157ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:02:57.570115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.312461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e9177618-0d93-4032-8b8e-9fb8e03eb5d2\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-10-04T10:02:57.570171Z","caller":"traceutil/trace.go:171","msg":"trace[645641619] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e9177618-0d93-4032-8b8e-9fb8e03eb5d2; range_end:; response_count:1; response_revision:5691; }","duration":"235.411773ms","start":"2026-10-04T10:02:57.334747Z","end":"2026-10-04T10:02:57.570159Z","steps":["trace[645641619] 'agreement among raft nodes before linearized reading' (duration: 235.212639ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:02:57.570191Z","caller":"traceutil/trace.go:171","msg":"trace[1347742240] transaction","detail":"{read_only:false; response_revision:5690; number_of_response:1; }","duration":"379.24964ms","start":"2026-10-04T10:02:57.190904Z","end":"2026-10-04T10:02:57.570154Z","steps":["trace[1347742240] 'process raft request' (duration: 21.198186ms)","trace[1347742240] 'compare' (duration: 357.365059ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T10:02:57.57033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:02:57.190876Z","time spent":"379.382673ms","remote":"127.0.0.1:58646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":774,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-04T10:02:57.74421Z","caller":"traceutil/trace.go:171","msg":"trace[1654958887] linearizableReadLoop","detail":"{readStateIndex:5908; appliedIndex:5907; }","duration":"134.025358ms","start":"2026-10-04T10:02:57.61016Z","end":"2026-10-04T10:02:57.744185Z","steps":["trace[1654958887] 'read index received' (duration: 125.694691ms)","trace[1654958887] 'applied index is now lower than readState.Index' (duration: 8.329617ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:02:57.744292Z","caller":"traceutil/trace.go:171","msg":"trace[652824252] transaction","detail":"{read_only:false; response_revision:5694; number_of_response:1; }","duration":"142.804804ms","start":"2026-10-04T10:02:57.601439Z","end":"2026-10-04T10:02:57.744244Z","steps":["trace[652824252] 'process raft request' (duration: 134.514328ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:02:57.744359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.178731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-04T10:02:57.744432Z","caller":"traceutil/trace.go:171","msg":"trace[1827964930] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5694; }","duration":"134.321234ms","start":"2026-10-04T10:02:57.610094Z","end":"2026-10-04T10:02:57.744415Z","steps":["trace[1827964930] 'agreement among raft nodes before linearized reading' (duration: 134.176601ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:03:24.653369Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3009} {"level":"info","ts":"2026-10-04T10:03:24.748606Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3009,"took":"93.520729ms","hash":137837317,"current-db-size-bytes":56733696,"current-db-size":"57 MB","current-db-size-in-use-bytes":30216192,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-04T10:03:24.748678Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":137837317,"revision":3009,"compact-revision":-1} {"level":"info","ts":"2026-10-04T10:03:49.322084Z","caller":"traceutil/trace.go:171","msg":"trace[946117706] transaction","detail":"{read_only:false; response_revision:6179; number_of_response:1; }","duration":"173.122485ms","start":"2026-10-04T10:03:49.14893Z","end":"2026-10-04T10:03:49.322052Z","steps":["trace[946117706] 'process raft request' (duration: 172.935221ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:08:24.66105Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5937} {"level":"info","ts":"2026-10-04T10:08:24.775246Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5937,"took":"111.809579ms","hash":2095678698,"current-db-size-bytes":56733696,"current-db-size":"57 MB","current-db-size-in-use-bytes":26193920,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-04T10:08:24.775319Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2095678698,"revision":5937,"compact-revision":3009} {"level":"info","ts":"2026-10-04T10:09:03.792613Z","caller":"traceutil/trace.go:171","msg":"trace[615738720] linearizableReadLoop","detail":"{readStateIndex:8803; appliedIndex:8802; }","duration":"215.998118ms","start":"2026-10-04T10:09:03.576584Z","end":"2026-10-04T10:09:03.792582Z","steps":["trace[615738720] 'read index received' (duration: 215.848185ms)","trace[615738720] 'applied index is now lower than readState.Index' (duration: 148.643µs)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:09:03.792677Z","caller":"traceutil/trace.go:171","msg":"trace[2094651192] transaction","detail":"{read_only:false; response_revision:8499; number_of_response:1; }","duration":"238.606784ms","start":"2026-10-04T10:09:03.554026Z","end":"2026-10-04T10:09:03.792633Z","steps":["trace[2094651192] 'process raft request' (duration: 238.334318ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:09:03.79285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.122711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-04T10:09:03.79289Z","caller":"traceutil/trace.go:171","msg":"trace[329082868] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8499; }","duration":"182.254294ms","start":"2026-10-04T10:09:03.610626Z","end":"2026-10-04T10:09:03.79288Z","steps":["trace[329082868] 'agreement among raft nodes before linearized reading' (duration: 182.136511ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:09:03.792971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.341336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-10-04T10:09:03.792999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.15113ms","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-04T10:09:03.793087Z","caller":"traceutil/trace.go:171","msg":"trace[704384028] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8499; }","duration":"143.250342ms","start":"2026-10-04T10:09:03.649819Z","end":"2026-10-04T10:09:03.793069Z","steps":["trace[704384028] 'agreement among raft nodes before linearized reading' (duration: 142.994677ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:09:03.793084Z","caller":"traceutil/trace.go:171","msg":"trace[1833590071] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8499; }","duration":"216.53448ms","start":"2026-10-04T10:09:03.576524Z","end":"2026-10-04T10:09:03.793059Z","steps":["trace[1833590071] 'agreement among raft nodes before linearized reading' (duration: 216.236433ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:09:03.793216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.854746ms","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-04T10:09:03.793338Z","caller":"traceutil/trace.go:171","msg":"trace[553298674] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8499; }","duration":"157.03663ms","start":"2026-10-04T10:09:03.636273Z","end":"2026-10-04T10:09:03.79331Z","steps":["trace[553298674] 'agreement among raft nodes before linearized reading' (duration: 156.627311ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:09:03.966536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.784873ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-04T10:09:03.9668Z","caller":"traceutil/trace.go:171","msg":"trace[1778238387] transaction","detail":"{read_only:false; response_revision:8500; number_of_response:1; }","duration":"165.415868ms","start":"2026-10-04T10:09:03.801357Z","end":"2026-10-04T10:09:03.966773Z","steps":["trace[1778238387] 'process raft request' (duration: 51.225386ms)","trace[1778238387] 'compare' (duration: 113.608209ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:09:03.966868Z","caller":"traceutil/trace.go:171","msg":"trace[265521236] transaction","detail":"{read_only:false; response_revision:8501; number_of_response:1; }","duration":"165.068419ms","start":"2026-10-04T10:09:03.801776Z","end":"2026-10-04T10:09:03.966844Z","steps":["trace[265521236] 'process raft request' (duration: 164.941827ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:09:03.967153Z","caller":"traceutil/trace.go:171","msg":"trace[335365068] transaction","detail":"{read_only:false; response_revision:8502; number_of_response:1; }","duration":"165.272454ms","start":"2026-10-04T10:09:03.801838Z","end":"2026-10-04T10:09:03.967111Z","steps":["trace[335365068] 'process raft request' (duration: 164.948637ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:09:03.983998Z","caller":"traceutil/trace.go:171","msg":"trace[762677163] transaction","detail":"{read_only:false; response_revision:8503; number_of_response:1; }","duration":"115.953872ms","start":"2026-10-04T10:09:03.868018Z","end":"2026-10-04T10:09:03.983972Z","steps":["trace[762677163] 'process raft request' (duration: 115.619944ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:09:16.374503Z","caller":"traceutil/trace.go:171","msg":"trace[615549734] transaction","detail":"{read_only:false; response_revision:8567; number_of_response:1; }","duration":"119.071491ms","start":"2026-10-04T10:09:16.255387Z","end":"2026-10-04T10:09:16.374459Z","steps":["trace[615549734] 'process raft request' (duration: 118.778835ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:09:16.492184Z","caller":"traceutil/trace.go:171","msg":"trace[124143172] transaction","detail":"{read_only:false; response_revision:8568; number_of_response:1; }","duration":"112.224979ms","start":"2026-10-04T10:09:16.379922Z","end":"2026-10-04T10:09:16.492147Z","steps":["trace[124143172] 'process raft request' (duration: 83.762743ms)","trace[124143172] 'compare' (duration: 28.227971ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:09:17.074628Z","caller":"traceutil/trace.go:171","msg":"trace[1087782849] linearizableReadLoop","detail":"{readStateIndex:8876; appliedIndex:8875; }","duration":"182.181733ms","start":"2026-10-04T10:09:16.892415Z","end":"2026-10-04T10:09:17.074597Z","steps":["trace[1087782849] 'read index received' (duration: 182.004369ms)","trace[1087782849] 'applied index is now lower than readState.Index' (duration: 175.824µs)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:09:17.074665Z","caller":"traceutil/trace.go:171","msg":"trace[57536669] transaction","detail":"{read_only:false; response_revision:8569; number_of_response:1; }","duration":"211.718792ms","start":"2026-10-04T10:09:16.862903Z","end":"2026-10-04T10:09:17.074621Z","steps":["trace[57536669] 'process raft request' (duration: 211.515958ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:09:17.074793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.349796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-04T10:09:17.074843Z","caller":"traceutil/trace.go:171","msg":"trace[2014561535] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8569; }","duration":"182.456538ms","start":"2026-10-04T10:09:16.892373Z","end":"2026-10-04T10:09:17.07483Z","steps":["trace[2014561535] 'agreement among raft nodes before linearized reading' (duration: 182.329285ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:09:53.735911Z","caller":"traceutil/trace.go:171","msg":"trace[1196420876] transaction","detail":"{read_only:false; response_revision:8822; number_of_response:1; }","duration":"113.024616ms","start":"2026-10-04T10:09:53.622856Z","end":"2026-10-04T10:09:53.73588Z","steps":["trace[1196420876] 'process raft request' (duration: 112.795571ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:10:01.182903Z","caller":"traceutil/trace.go:171","msg":"trace[2072839478] linearizableReadLoop","detail":"{readStateIndex:9222; appliedIndex:9220; }","duration":"248.420574ms","start":"2026-10-04T10:10:00.934448Z","end":"2026-10-04T10:10:01.182868Z","steps":["trace[2072839478] 'read index received' (duration: 31.947884ms)","trace[2072839478] 'applied index is now lower than readState.Index' (duration: 216.47121ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:10:01.182979Z","caller":"traceutil/trace.go:171","msg":"trace[600540833] transaction","detail":"{read_only:false; response_revision:8901; number_of_response:1; }","duration":"275.816426ms","start":"2026-10-04T10:10:00.907127Z","end":"2026-10-04T10:10:01.182943Z","steps":["trace[600540833] 'process raft request' (duration: 275.567911ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:10:01.183189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.841968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-04T10:10:01.183213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.74191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-jrbkx\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-10-04T10:10:01.183279Z","caller":"traceutil/trace.go:171","msg":"trace[2100724262] range","detail":"{range_begin:/registry/pods/openstack/keepalived-jrbkx; range_end:; response_count:1; response_revision:8901; }","duration":"248.865183ms","start":"2026-10-04T10:10:00.934402Z","end":"2026-10-04T10:10:01.183268Z","steps":["trace[2100724262] 'agreement among raft nodes before linearized reading' (duration: 248.650089ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:10:01.183286Z","caller":"traceutil/trace.go:171","msg":"trace[1028815495] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:8901; }","duration":"213.028072ms","start":"2026-10-04T10:10:00.970236Z","end":"2026-10-04T10:10:01.183264Z","steps":["trace[1028815495] 'agreement among raft nodes before linearized reading' (duration: 212.853048ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:10:01.758393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.378765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-04T10:10:01.75848Z","caller":"traceutil/trace.go:171","msg":"trace[1358916266] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8906; }","duration":"147.554689ms","start":"2026-10-04T10:10:01.610906Z","end":"2026-10-04T10:10:01.75846Z","steps":["trace[1358916266] 'range keys from in-memory index tree' (duration: 147.245262ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:10:01.758684Z","caller":"traceutil/trace.go:171","msg":"trace[1489419730] transaction","detail":"{read_only:false; response_revision:8907; number_of_response:1; }","duration":"110.96961ms","start":"2026-10-04T10:10:01.647684Z","end":"2026-10-04T10:10:01.758654Z","steps":["trace[1489419730] 'process raft request' (duration: 53.2517ms)","trace[1489419730] 'compare' (duration: 57.600477ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T10:10:01.759229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.850553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-wq7rd\" ","response":"range_response_count:1 size:4647"} {"level":"info","ts":"2026-10-04T10:10:01.759359Z","caller":"traceutil/trace.go:171","msg":"trace[1606195830] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-wq7rd; range_end:; response_count:1; response_revision:8906; }","duration":"117.800534ms","start":"2026-10-04T10:10:01.641527Z","end":"2026-10-04T10:10:01.759328Z","steps":["trace[1606195830] 'range keys from in-memory index tree' (duration: 116.590567ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:10:01.798496Z","caller":"traceutil/trace.go:171","msg":"trace[1121252151] linearizableReadLoop","detail":"{readStateIndex:9229; appliedIndex:9227; }","duration":"137.80506ms","start":"2026-10-04T10:10:01.660665Z","end":"2026-10-04T10:10:01.79847Z","steps":["trace[1121252151] 'read index received' (duration: 40.292931ms)","trace[1121252151] 'applied index is now lower than readState.Index' (duration: 97.506339ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T10:10:01.798768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.098417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1633"} {"level":"info","ts":"2026-10-04T10:10:01.79884Z","caller":"traceutil/trace.go:171","msg":"trace[3778472] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:8908; }","duration":"138.234049ms","start":"2026-10-04T10:10:01.660584Z","end":"2026-10-04T10:10:01.798818Z","steps":["trace[3778472] 'agreement among raft nodes before linearized reading' (duration: 138.039965ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:10:01.799404Z","caller":"traceutil/trace.go:171","msg":"trace[258777184] transaction","detail":"{read_only:false; response_revision:8908; number_of_response:1; }","duration":"149.582644ms","start":"2026-10-04T10:10:01.649639Z","end":"2026-10-04T10:10:01.799221Z","steps":["trace[258777184] 'process raft request' (duration: 148.611992ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:10:13.076682Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000246b.wal"} {"level":"info","ts":"2026-10-04T10:11:18.792659Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4b019b7871d5da9a","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-04T10:11:18.798898Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-04T10:11:18.799177Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-04T10:13:24.668994Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8113} {"level":"info","ts":"2026-10-04T10:13:24.739484Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8113,"took":"68.237342ms","hash":3079941963,"current-db-size-bytes":56733696,"current-db-size":"57 MB","current-db-size-in-use-bytes":28692480,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-04T10:13:24.73955Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3079941963,"revision":8113,"compact-revision":5937} {"level":"info","ts":"2026-10-04T10:18:24.677554Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10556} {"level":"info","ts":"2026-10-04T10:18:24.774597Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10556,"took":"93.963268ms","hash":4103635519,"current-db-size-bytes":56733696,"current-db-size":"57 MB","current-db-size-in-use-bytes":27447296,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-04T10:18:24.774685Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4103635519,"revision":10556,"compact-revision":8113} {"level":"info","ts":"2026-10-04T10:21:17.259471Z","caller":"traceutil/trace.go:171","msg":"trace[1344575515] transaction","detail":"{read_only:false; response_revision:14018; number_of_response:1; }","duration":"146.508944ms","start":"2026-10-04T10:21:17.112933Z","end":"2026-10-04T10:21:17.259442Z","steps":["trace[1344575515] 'process raft request' (duration: 146.3151ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:21:17.273365Z","caller":"traceutil/trace.go:171","msg":"trace[1852023893] linearizableReadLoop","detail":"{readStateIndex:14503; appliedIndex:14501; }","duration":"141.85066ms","start":"2026-10-04T10:21:17.131491Z","end":"2026-10-04T10:21:17.273341Z","steps":["trace[1852023893] 'read index received' (duration: 127.802517ms)","trace[1852023893] 'applied index is now lower than readState.Index' (duration: 14.046953ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:21:17.273453Z","caller":"traceutil/trace.go:171","msg":"trace[346489355] transaction","detail":"{read_only:false; response_revision:14019; number_of_response:1; }","duration":"158.313257ms","start":"2026-10-04T10:21:17.115097Z","end":"2026-10-04T10:21:17.27341Z","steps":["trace[346489355] 'process raft request' (duration: 158.157114ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:21:17.273598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.080735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-04T10:21:17.273649Z","caller":"traceutil/trace.go:171","msg":"trace[950167746] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:14019; }","duration":"142.178497ms","start":"2026-10-04T10:21:17.131453Z","end":"2026-10-04T10:21:17.273632Z","steps":["trace[950167746] 'agreement among raft nodes before linearized reading' (duration: 142.001543ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:21:17.273852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.067069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-jrbkx\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-10-04T10:21:17.2739Z","caller":"traceutil/trace.go:171","msg":"trace[1742107962] range","detail":"{range_begin:/registry/pods/openstack/keepalived-jrbkx; range_end:; response_count:1; response_revision:14019; }","duration":"126.154451ms","start":"2026-10-04T10:21:17.147732Z","end":"2026-10-04T10:21:17.273886Z","steps":["trace[1742107962] 'agreement among raft nodes before linearized reading' (duration: 125.934336ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:21:36.44291Z","caller":"traceutil/trace.go:171","msg":"trace[603698656] linearizableReadLoop","detail":"{readStateIndex:14629; appliedIndex:14628; }","duration":"237.245097ms","start":"2026-10-04T10:21:36.205639Z","end":"2026-10-04T10:21:36.442885Z","steps":["trace[603698656] 'read index received' (duration: 236.915219ms)","trace[603698656] 'applied index is now lower than readState.Index' (duration: 328.378µs)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:21:36.44306Z","caller":"traceutil/trace.go:171","msg":"trace[1144675417] transaction","detail":"{read_only:false; response_revision:14140; number_of_response:1; }","duration":"256.143166ms","start":"2026-10-04T10:21:36.186871Z","end":"2026-10-04T10:21:36.443014Z","steps":["trace[1144675417] 'process raft request' (duration: 255.806669ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:21:36.443397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.439846ms","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-04T10:21:36.443499Z","caller":"traceutil/trace.go:171","msg":"trace[75841638] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14140; }","duration":"235.629551ms","start":"2026-10-04T10:21:36.207855Z","end":"2026-10-04T10:21:36.443484Z","steps":["trace[75841638] 'agreement among raft nodes before linearized reading' (duration: 235.381285ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:21:36.443517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.801409ms","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-04T10:21:36.443777Z","caller":"traceutil/trace.go:171","msg":"trace[724613237] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14140; }","duration":"238.074165ms","start":"2026-10-04T10:21:36.205564Z","end":"2026-10-04T10:21:36.443639Z","steps":["trace[724613237] 'agreement among raft nodes before linearized reading' (duration: 237.571074ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:21:36.443734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.53681ms","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-04T10:21:36.443922Z","caller":"traceutil/trace.go:171","msg":"trace[1974362443] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:14140; }","duration":"227.781786ms","start":"2026-10-04T10:21:36.216069Z","end":"2026-10-04T10:21:36.443851Z","steps":["trace[1974362443] 'agreement among raft nodes before linearized reading' (duration: 227.224433ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:21:36.443547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.303469ms","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-04T10:21:36.444241Z","caller":"traceutil/trace.go:171","msg":"trace[360097357] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14140; }","duration":"160.062748ms","start":"2026-10-04T10:21:36.284138Z","end":"2026-10-04T10:21:36.444201Z","steps":["trace[360097357] 'agreement among raft nodes before linearized reading' (duration: 159.134896ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:21:36.646826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.4395ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-04T10:21:36.646963Z","caller":"traceutil/trace.go:171","msg":"trace[1251832712] transaction","detail":"{read_only:false; response_revision:14141; number_of_response:1; }","duration":"196.728403ms","start":"2026-10-04T10:21:36.450212Z","end":"2026-10-04T10:21:36.646941Z","steps":["trace[1251832712] 'process raft request' (duration: 69.062408ms)","trace[1251832712] 'compare' (duration: 127.250026ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:21:36.647553Z","caller":"traceutil/trace.go:171","msg":"trace[281605144] transaction","detail":"{read_only:false; response_revision:14142; number_of_response:1; }","duration":"196.993029ms","start":"2026-10-04T10:21:36.450535Z","end":"2026-10-04T10:21:36.647528Z","steps":["trace[281605144] 'process raft request' (duration: 196.819466ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:21:36.647731Z","caller":"traceutil/trace.go:171","msg":"trace[423175924] transaction","detail":"{read_only:false; response_revision:14143; number_of_response:1; }","duration":"196.871716ms","start":"2026-10-04T10:21:36.450841Z","end":"2026-10-04T10:21:36.647684Z","steps":["trace[423175924] 'process raft request' (duration: 196.640101ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:21:36.648017Z","caller":"traceutil/trace.go:171","msg":"trace[1892159298] linearizableReadLoop","detail":"{readStateIndex:14633; appliedIndex:14629; }","duration":"142.718571ms","start":"2026-10-04T10:21:36.505283Z","end":"2026-10-04T10:21:36.648001Z","steps":["trace[1892159298] 'read index received' (duration: 14.016633ms)","trace[1892159298] 'applied index is now lower than readState.Index' (duration: 128.700478ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:21:36.648152Z","caller":"traceutil/trace.go:171","msg":"trace[438028320] transaction","detail":"{read_only:false; response_revision:14144; number_of_response:1; }","duration":"196.688993ms","start":"2026-10-04T10:21:36.451392Z","end":"2026-10-04T10:21:36.648081Z","steps":["trace[438028320] 'process raft request' (duration: 196.238633ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:21:36.648275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.39904ms","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-04T10:21:36.648328Z","caller":"traceutil/trace.go:171","msg":"trace[866594004] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14144; }","duration":"141.523394ms","start":"2026-10-04T10:21:36.506792Z","end":"2026-10-04T10:21:36.648315Z","steps":["trace[866594004] 'agreement among raft nodes before linearized reading' (duration: 141.309999ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:21:36.648973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.683823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:86 size:930194"} {"level":"info","ts":"2026-10-04T10:21:36.64902Z","caller":"traceutil/trace.go:171","msg":"trace[1285861561] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:86; response_revision:14144; }","duration":"143.770155ms","start":"2026-10-04T10:21:36.505238Z","end":"2026-10-04T10:21:36.649009Z","steps":["trace[1285861561] 'agreement among raft nodes before linearized reading' (duration: 142.868924ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:23:23.122794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.882049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-fzhkl\" ","response":"range_response_count:1 size:22125"} {"level":"info","ts":"2026-10-04T10:23:23.122904Z","caller":"traceutil/trace.go:171","msg":"trace[416799633] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-fzhkl; range_end:; response_count:1; response_revision:15369; }","duration":"159.038883ms","start":"2026-10-04T10:23:22.963845Z","end":"2026-10-04T10:23:23.122884Z","steps":["trace[416799633] 'range keys from in-memory index tree' (duration: 158.686565ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:23:23.123215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.655285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"warn","ts":"2026-10-04T10:23:23.123173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.508251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-fzhkl\" ","response":"range_response_count:1 size:22125"} {"level":"info","ts":"2026-10-04T10:23:23.123263Z","caller":"traceutil/trace.go:171","msg":"trace[534030712] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15369; }","duration":"188.764987ms","start":"2026-10-04T10:23:22.934487Z","end":"2026-10-04T10:23:23.123252Z","steps":["trace[534030712] 'range keys from in-memory index tree' (duration: 188.439239ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:23:23.123293Z","caller":"traceutil/trace.go:171","msg":"trace[989866224] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-fzhkl; range_end:; response_count:1; response_revision:15369; }","duration":"153.695125ms","start":"2026-10-04T10:23:22.969572Z","end":"2026-10-04T10:23:23.123267Z","steps":["trace[989866224] 'range keys from in-memory index tree' (duration: 153.276026ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:23:23.123426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.741635ms","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-04T10:23:23.123487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.718986ms","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-04T10:23:23.123585Z","caller":"traceutil/trace.go:171","msg":"trace[757674533] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15369; }","duration":"179.929259ms","start":"2026-10-04T10:23:22.943615Z","end":"2026-10-04T10:23:23.123544Z","steps":["trace[757674533] 'range keys from in-memory index tree' (duration: 179.466799ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:23:23.123619Z","caller":"traceutil/trace.go:171","msg":"trace[1594403725] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15369; }","duration":"188.869059ms","start":"2026-10-04T10:23:22.934703Z","end":"2026-10-04T10:23:23.123572Z","steps":["trace[1594403725] 'range keys from in-memory index tree' (duration: 188.567342ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:23:23.367865Z","caller":"traceutil/trace.go:171","msg":"trace[367888883] transaction","detail":"{read_only:false; response_revision:15370; number_of_response:1; }","duration":"102.27341ms","start":"2026-10-04T10:23:23.265368Z","end":"2026-10-04T10:23:23.367642Z","steps":["trace[367888883] 'process raft request' (duration: 101.769719ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:23:23.368337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.777555ms","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-04T10:23:23.368437Z","caller":"traceutil/trace.go:171","msg":"trace[158358100] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15370; }","duration":"100.940419ms","start":"2026-10-04T10:23:23.267474Z","end":"2026-10-04T10:23:23.368414Z","steps":["trace[158358100] 'agreement among raft nodes before linearized reading' (duration: 100.698143ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:23:24.686498Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12809} {"level":"info","ts":"2026-10-04T10:23:24.772889Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12809,"took":"82.284574ms","hash":3026657309,"current-db-size-bytes":56733696,"current-db-size":"57 MB","current-db-size-in-use-bytes":30744576,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-04T10:23:24.772978Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3026657309,"revision":12809,"compact-revision":10556} {"level":"info","ts":"2026-10-04T10:28:24.695048Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15379} {"level":"info","ts":"2026-10-04T10:28:24.806225Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15379,"took":"106.565496ms","hash":3426936858,"current-db-size-bytes":56733696,"current-db-size":"57 MB","current-db-size-in-use-bytes":31748096,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-04T10:28:24.806312Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3426936858,"revision":15379,"compact-revision":12809} {"level":"info","ts":"2026-10-04T10:29:02.567049Z","caller":"traceutil/trace.go:171","msg":"trace[813570955] transaction","detail":"{read_only:false; response_revision:17921; number_of_response:1; }","duration":"129.085438ms","start":"2026-10-04T10:29:02.437933Z","end":"2026-10-04T10:29:02.567018Z","steps":["trace[813570955] 'process raft request' (duration: 122.374909ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:29:02.892193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.311021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-9txjp\" ","response":"range_response_count:1 size:20756"} {"level":"info","ts":"2026-10-04T10:29:02.892326Z","caller":"traceutil/trace.go:171","msg":"trace[1766003734] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-9txjp; range_end:; response_count:1; response_revision:17921; }","duration":"145.522296ms","start":"2026-10-04T10:29:02.746768Z","end":"2026-10-04T10:29:02.89229Z","steps":["trace[1766003734] 'range keys from in-memory index tree' (duration: 145.068545ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:29:02.892321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.594783ms","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-04T10:29:02.892422Z","caller":"traceutil/trace.go:171","msg":"trace[846726009] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:17921; }","duration":"213.741526ms","start":"2026-10-04T10:29:02.678651Z","end":"2026-10-04T10:29:02.892393Z","steps":["trace[846726009] 'range keys from in-memory index tree' (duration: 213.357807ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:29:03.17066Z","caller":"traceutil/trace.go:171","msg":"trace[984944254] transaction","detail":"{read_only:false; response_revision:17922; number_of_response:1; }","duration":"272.818115ms","start":"2026-10-04T10:29:02.897809Z","end":"2026-10-04T10:29:03.170628Z","steps":["trace[984944254] 'process raft request' (duration: 272.60958ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:29:03.185891Z","caller":"traceutil/trace.go:171","msg":"trace[2095961332] transaction","detail":"{read_only:false; response_revision:17923; number_of_response:1; }","duration":"270.909002ms","start":"2026-10-04T10:29:02.91495Z","end":"2026-10-04T10:29:03.185859Z","steps":["trace[2095961332] 'process raft request' (duration: 270.708697ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:31:50.065902Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4b019b7871d5da9a","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-04T10:31:50.072494Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-04T10:31:50.072599Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-04T10:33:24.702629Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17572} {"level":"info","ts":"2026-10-04T10:33:24.806802Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17572,"took":"99.727006ms","hash":4170133526,"current-db-size-bytes":56733696,"current-db-size":"57 MB","current-db-size-in-use-bytes":34095104,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-04T10:33:24.806887Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4170133526,"revision":17572,"compact-revision":15379} {"level":"warn","ts":"2026-10-04T10:34:12.303879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.683881ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-04T10:34:12.30407Z","caller":"traceutil/trace.go:171","msg":"trace[853798019] transaction","detail":"{read_only:false; response_revision:20352; number_of_response:1; }","duration":"246.509904ms","start":"2026-10-04T10:34:12.057505Z","end":"2026-10-04T10:34:12.304014Z","steps":["trace[853798019] 'process raft request' (duration: 135.561658ms)","trace[853798019] 'compare' (duration: 110.491836ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:34:27.758475Z","caller":"traceutil/trace.go:171","msg":"trace[1302715221] transaction","detail":"{read_only:false; response_revision:20430; number_of_response:1; }","duration":"104.598306ms","start":"2026-10-04T10:34:27.653846Z","end":"2026-10-04T10:34:27.758444Z","steps":["trace[1302715221] 'process raft request' (duration: 104.396011ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:34:32.995307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.036397ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-04T10:34:32.995444Z","caller":"traceutil/trace.go:171","msg":"trace[2019116514] transaction","detail":"{read_only:false; response_revision:20457; number_of_response:1; }","duration":"212.115307ms","start":"2026-10-04T10:34:32.783305Z","end":"2026-10-04T10:34:32.995421Z","steps":["trace[2019116514] 'process raft request' (duration: 54.842634ms)","trace[2019116514] 'compare' (duration: 156.684539ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T10:34:33.573757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.63821ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-04T10:34:33.573886Z","caller":"traceutil/trace.go:171","msg":"trace[1067687106] linearizableReadLoop","detail":"{readStateIndex:21154; appliedIndex:21153; }","duration":"277.542858ms","start":"2026-10-04T10:34:33.296318Z","end":"2026-10-04T10:34:33.573861Z","steps":["trace[1067687106] 'read index received' (duration: 145.800786ms)","trace[1067687106] 'applied index is now lower than readState.Index' (duration: 131.739922ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T10:34:33.574178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.841424ms","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-04T10:34:33.57422Z","caller":"traceutil/trace.go:171","msg":"trace[938274184] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20457; }","duration":"277.934726ms","start":"2026-10-04T10:34:33.296269Z","end":"2026-10-04T10:34:33.574203Z","steps":["trace[938274184] 'agreement among raft nodes before linearized reading' (duration: 277.713561ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:34:33.574187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.839704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T10:34:33.574288Z","caller":"traceutil/trace.go:171","msg":"trace[747711251] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:20457; }","duration":"184.01583ms","start":"2026-10-04T10:34:33.390248Z","end":"2026-10-04T10:34:33.574264Z","steps":["trace[747711251] 'agreement among raft nodes before linearized reading' (duration: 183.837916ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:34:33.574355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.882881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-04T10:34:33.57448Z","caller":"traceutil/trace.go:171","msg":"trace[1086423770] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20457; }","duration":"272.112626ms","start":"2026-10-04T10:34:33.30234Z","end":"2026-10-04T10:34:33.574452Z","steps":["trace[1086423770] 'agreement among raft nodes before linearized reading' (duration: 271.725897ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:34:33.574668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.986534ms","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":"warn","ts":"2026-10-04T10:34:33.574698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.96661ms","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-04T10:34:33.574707Z","caller":"traceutil/trace.go:171","msg":"trace[667498987] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:20457; }","duration":"152.075256ms","start":"2026-10-04T10:34:33.42262Z","end":"2026-10-04T10:34:33.574696Z","steps":["trace[667498987] 'agreement among raft nodes before linearized reading' (duration: 151.924263ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:34:33.57473Z","caller":"traceutil/trace.go:171","msg":"trace[363772421] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20457; }","duration":"271.042592ms","start":"2026-10-04T10:34:33.303677Z","end":"2026-10-04T10:34:33.574719Z","steps":["trace[363772421] 'agreement among raft nodes before linearized reading' (duration: 270.95251ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:34:33.57476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.433944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-04T10:34:33.574861Z","caller":"traceutil/trace.go:171","msg":"trace[197202272] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20457; }","duration":"264.600878ms","start":"2026-10-04T10:34:33.310235Z","end":"2026-10-04T10:34:33.574836Z","steps":["trace[197202272] 'agreement among raft nodes before linearized reading' (duration: 264.263311ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:34:33.751238Z","caller":"traceutil/trace.go:171","msg":"trace[1424367440] transaction","detail":"{read_only:false; response_revision:20459; number_of_response:1; }","duration":"169.880513ms","start":"2026-10-04T10:34:33.581318Z","end":"2026-10-04T10:34:33.751198Z","steps":["trace[1424367440] 'process raft request' (duration: 149.533269ms)","trace[1424367440] 'compare' (duration: 19.744371ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:34:33.75126Z","caller":"traceutil/trace.go:171","msg":"trace[1217341180] linearizableReadLoop","detail":"{readStateIndex:21159; appliedIndex:21155; }","duration":"151.463102ms","start":"2026-10-04T10:34:33.599763Z","end":"2026-10-04T10:34:33.751226Z","steps":["trace[1217341180] 'read index received' (duration: 130.967124ms)","trace[1217341180] 'applied index is now lower than readState.Index' (duration: 20.494638ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:34:33.751684Z","caller":"traceutil/trace.go:171","msg":"trace[836983451] transaction","detail":"{read_only:false; response_revision:20460; number_of_response:1; }","duration":"170.349315ms","start":"2026-10-04T10:34:33.581322Z","end":"2026-10-04T10:34:33.751671Z","steps":["trace[836983451] 'process raft request' (duration: 169.652298ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:34:33.751671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.637631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T10:34:33.75163Z","caller":"traceutil/trace.go:171","msg":"trace[1883643569] transaction","detail":"{read_only:false; response_revision:20462; number_of_response:1; }","duration":"167.904438ms","start":"2026-10-04T10:34:33.58366Z","end":"2026-10-04T10:34:33.751564Z","steps":["trace[1883643569] 'process raft request' (duration: 167.51971ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:34:33.751766Z","caller":"traceutil/trace.go:171","msg":"trace[1596484298] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20462; }","duration":"140.772664ms","start":"2026-10-04T10:34:33.610968Z","end":"2026-10-04T10:34:33.751741Z","steps":["trace[1596484298] 'agreement among raft nodes before linearized reading' (duration: 140.605599ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:34:33.751876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.082276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-04T10:34:33.751927Z","caller":"traceutil/trace.go:171","msg":"trace[2090216470] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:20462; }","duration":"152.21113ms","start":"2026-10-04T10:34:33.5997Z","end":"2026-10-04T10:34:33.751911Z","steps":["trace[2090216470] 'agreement among raft nodes before linearized reading' (duration: 152.070467ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:34:33.752107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.056098ms","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-04T10:34:33.752089Z","caller":"traceutil/trace.go:171","msg":"trace[1191287770] transaction","detail":"{read_only:false; response_revision:20461; number_of_response:1; }","duration":"170.104328ms","start":"2026-10-04T10:34:33.581924Z","end":"2026-10-04T10:34:33.752029Z","steps":["trace[1191287770] 'process raft request' (duration: 169.212138ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:34:33.752158Z","caller":"traceutil/trace.go:171","msg":"trace[1120117444] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20462; }","duration":"136.137151ms","start":"2026-10-04T10:34:33.616Z","end":"2026-10-04T10:34:33.752137Z","steps":["trace[1120117444] 'agreement among raft nodes before linearized reading' (duration: 135.945617ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:34:34.05255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.711976ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-04T10:34:34.052701Z","caller":"traceutil/trace.go:171","msg":"trace[807185165] transaction","detail":"{read_only:false; response_revision:20463; number_of_response:1; }","duration":"295.261052ms","start":"2026-10-04T10:34:33.757416Z","end":"2026-10-04T10:34:34.052677Z","steps":["trace[807185165] 'process raft request' (duration: 170.297432ms)","trace[807185165] 'compare' (duration: 124.347557ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:34:34.251466Z","caller":"traceutil/trace.go:171","msg":"trace[504438130] transaction","detail":"{read_only:false; response_revision:20464; number_of_response:1; }","duration":"129.413579ms","start":"2026-10-04T10:34:34.122018Z","end":"2026-10-04T10:34:34.251432Z","steps":["trace[504438130] 'process raft request' (duration: 76.633811ms)","trace[504438130] 'compare' (duration: 52.439181ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:38:24.713176Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20099} {"level":"info","ts":"2026-10-04T10:38:24.874733Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20099,"took":"155.277037ms","hash":3684565730,"current-db-size-bytes":56733696,"current-db-size":"57 MB","current-db-size-in-use-bytes":41279488,"current-db-size-in-use":"41 MB"} {"level":"info","ts":"2026-10-04T10:38:24.874881Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3684565730,"revision":20099,"compact-revision":17572} {"level":"info","ts":"2026-10-04T10:38:29.815191Z","caller":"traceutil/trace.go:171","msg":"trace[1287256907] linearizableReadLoop","detail":"{readStateIndex:23691; appliedIndex:23690; }","duration":"203.78942ms","start":"2026-10-04T10:38:29.61137Z","end":"2026-10-04T10:38:29.815159Z","steps":["trace[1287256907] 'read index received' (duration: 203.526695ms)","trace[1287256907] 'applied index is now lower than readState.Index' (duration: 261.865µs)"],"step_count":2} {"level":"warn","ts":"2026-10-04T10:38:29.815465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.067036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T10:38:29.815507Z","caller":"traceutil/trace.go:171","msg":"trace[1380162373] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22916; }","duration":"204.176109ms","start":"2026-10-04T10:38:29.61132Z","end":"2026-10-04T10:38:29.815496Z","steps":["trace[1380162373] 'agreement among raft nodes before linearized reading' (duration: 203.976324ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:38:29.815682Z","caller":"traceutil/trace.go:171","msg":"trace[516982602] transaction","detail":"{read_only:false; response_revision:22916; number_of_response:1; }","duration":"308.075349ms","start":"2026-10-04T10:38:29.507568Z","end":"2026-10-04T10:38:29.815643Z","steps":["trace[516982602] 'process raft request' (duration: 307.382554ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:38:29.81589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:38:29.507549Z","time spent":"308.228532ms","remote":"127.0.0.1:58858","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-04T10:42:08.223843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.403828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.215\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-10-04T10:42:08.224008Z","caller":"traceutil/trace.go:171","msg":"trace[1467666016] range","detail":"{range_begin:/registry/masterleases/162.253.55.215; range_end:; response_count:1; response_revision:25113; }","duration":"120.623813ms","start":"2026-10-04T10:42:08.103364Z","end":"2026-10-04T10:42:08.223988Z","steps":["trace[1467666016] 'range keys from in-memory index tree' (duration: 120.224253ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:42:08.247132Z","caller":"traceutil/trace.go:171","msg":"trace[1069084995] transaction","detail":"{read_only:false; response_revision:25114; number_of_response:1; }","duration":"128.163141ms","start":"2026-10-04T10:42:08.118911Z","end":"2026-10-04T10:42:08.247074Z","steps":["trace[1069084995] 'process raft request' (duration: 127.990438ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:42:08.530807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.397846ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-10-04T10:42:08.531034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:42:08.226299Z","time spent":"304.727374ms","remote":"127.0.0.1:58596","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-10-04T10:42:08.531113Z","caller":"traceutil/trace.go:171","msg":"trace[127227760] transaction","detail":"{read_only:false; response_revision:25115; number_of_response:1; }","duration":"277.979837ms","start":"2026-10-04T10:42:08.253113Z","end":"2026-10-04T10:42:08.531093Z","steps":["trace[127227760] 'process raft request' (duration: 277.818973ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:42:08.531179Z","caller":"traceutil/trace.go:171","msg":"trace[937553588] linearizableReadLoop","detail":"{readStateIndex:25946; appliedIndex:25945; }","duration":"284.034681ms","start":"2026-10-04T10:42:08.247097Z","end":"2026-10-04T10:42:08.531132Z","steps":["trace[937553588] 'read index received' (duration: 142.307228ms)","trace[937553588] 'applied index is now lower than readState.Index' (duration: 141.722823ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T10:42:08.53136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.878486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T10:42:08.531406Z","caller":"traceutil/trace.go:171","msg":"trace[126483881] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters0; response_count:0; response_revision:25115; }","duration":"342.958707ms","start":"2026-10-04T10:42:08.188434Z","end":"2026-10-04T10:42:08.531393Z","steps":["trace[126483881] 'agreement among raft nodes before linearized reading' (duration: 342.872735ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:42:08.531437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:42:08.188416Z","time spent":"343.011789ms","remote":"127.0.0.1:33266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":126,"response count":0,"response size":28,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters0\" count_only:true "} {"level":"warn","ts":"2026-10-04T10:42:08.543268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.733856ms","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-04T10:42:08.543267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.758794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-04T10:42:08.543288Z","caller":"traceutil/trace.go:171","msg":"trace[370460583] transaction","detail":"{read_only:false; response_revision:25116; number_of_response:1; }","duration":"120.099152ms","start":"2026-10-04T10:42:08.423135Z","end":"2026-10-04T10:42:08.543235Z","steps":["trace[370460583] 'process raft request' (duration: 119.659092ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:42:08.543331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.543533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-10-04T10:42:08.543343Z","caller":"traceutil/trace.go:171","msg":"trace[1514472313] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:25116; }","duration":"227.864508ms","start":"2026-10-04T10:42:08.315458Z","end":"2026-10-04T10:42:08.543323Z","steps":["trace[1514472313] 'agreement among raft nodes before linearized reading' (duration: 227.677423ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:42:08.543369Z","caller":"traceutil/trace.go:171","msg":"trace[1088589135] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25116; }","duration":"192.897907ms","start":"2026-10-04T10:42:08.350444Z","end":"2026-10-04T10:42:08.543342Z","steps":["trace[1088589135] 'agreement among raft nodes before linearized reading' (duration: 192.609959ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:42:08.543383Z","caller":"traceutil/trace.go:171","msg":"trace[1523595608] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25116; }","duration":"258.654736ms","start":"2026-10-04T10:42:08.284714Z","end":"2026-10-04T10:42:08.543369Z","steps":["trace[1523595608] 'agreement among raft nodes before linearized reading' (duration: 258.466502ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:42:08.543855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.149976ms","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-04T10:42:08.543916Z","caller":"traceutil/trace.go:171","msg":"trace[2013036357] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25116; }","duration":"207.230227ms","start":"2026-10-04T10:42:08.336674Z","end":"2026-10-04T10:42:08.543904Z","steps":["trace[2013036357] 'agreement among raft nodes before linearized reading' (duration: 207.074544ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:42:08.873119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.642372ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-04T10:42:08.873379Z","caller":"traceutil/trace.go:171","msg":"trace[1660969239] transaction","detail":"{read_only:false; response_revision:25118; number_of_response:1; }","duration":"324.757661ms","start":"2026-10-04T10:42:08.548579Z","end":"2026-10-04T10:42:08.873337Z","steps":["trace[1660969239] 'process raft request' (duration: 165.871974ms)","trace[1660969239] 'compare' (duration: 158.303354ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T10:42:08.873579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:42:08.548558Z","time spent":"324.904395ms","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T10:42:08.874505Z","caller":"traceutil/trace.go:171","msg":"trace[1633027510] linearizableReadLoop","detail":"{readStateIndex:25951; appliedIndex:25949; }","duration":"292.594423ms","start":"2026-10-04T10:42:08.581889Z","end":"2026-10-04T10:42:08.874484Z","steps":["trace[1633027510] 'read index received' (duration: 132.54794ms)","trace[1633027510] 'applied index is now lower than readState.Index' (duration: 160.044993ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:42:08.874596Z","caller":"traceutil/trace.go:171","msg":"trace[1478545508] transaction","detail":"{read_only:false; response_revision:25119; number_of_response:1; }","duration":"325.689511ms","start":"2026-10-04T10:42:08.548894Z","end":"2026-10-04T10:42:08.874583Z","steps":["trace[1478545508] 'process raft request' (duration: 324.33723ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:42:08.874687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:42:08.548859Z","time spent":"325.761303ms","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T10:42:08.874925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.302548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-10-04T10:42:08.87501Z","caller":"traceutil/trace.go:171","msg":"trace[996976611] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25119; }","duration":"327.446091ms","start":"2026-10-04T10:42:08.547541Z","end":"2026-10-04T10:42:08.874987Z","steps":["trace[996976611] 'agreement among raft nodes before linearized reading' (duration: 327.166865ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:42:08.875051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:42:08.547517Z","time spent":"327.526073ms","remote":"127.0.0.1:58786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12236,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-10-04T10:42:08.89194Z","caller":"traceutil/trace.go:171","msg":"trace[809774304] transaction","detail":"{read_only:false; response_revision:25120; number_of_response:1; }","duration":"305.052901ms","start":"2026-10-04T10:42:08.58686Z","end":"2026-10-04T10:42:08.891913Z","steps":["trace[809774304] 'process raft request' (duration: 304.875377ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:42:08.892125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:42:08.586844Z","time spent":"305.168804ms","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T10:42:08.893629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.879461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-04T10:42:08.893664Z","caller":"traceutil/trace.go:171","msg":"trace[666020009] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:25121; }","duration":"176.964462ms","start":"2026-10-04T10:42:08.716691Z","end":"2026-10-04T10:42:08.893655Z","steps":["trace[666020009] 'agreement among raft nodes before linearized reading' (duration: 176.83809ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:42:08.893789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.427549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"} {"level":"warn","ts":"2026-10-04T10:42:08.893606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.935066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T10:42:08.893842Z","caller":"traceutil/trace.go:171","msg":"trace[1178187356] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:25121; }","duration":"309.493981ms","start":"2026-10-04T10:42:08.58433Z","end":"2026-10-04T10:42:08.893824Z","steps":["trace[1178187356] 'agreement among raft nodes before linearized reading' (duration: 309.318037ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:42:08.893905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:42:08.584315Z","time spent":"309.579133ms","remote":"127.0.0.1:58768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-10-04T10:42:08.893887Z","caller":"traceutil/trace.go:171","msg":"trace[843550307] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25121; }","duration":"282.215752ms","start":"2026-10-04T10:42:08.611628Z","end":"2026-10-04T10:42:08.893843Z","steps":["trace[843550307] 'agreement among raft nodes before linearized reading' (duration: 281.898815ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:42:12.714703Z","caller":"traceutil/trace.go:171","msg":"trace[712866086] linearizableReadLoop","detail":"{readStateIndex:25977; appliedIndex:25976; }","duration":"103.755456ms","start":"2026-10-04T10:42:12.610913Z","end":"2026-10-04T10:42:12.714668Z","steps":["trace[712866086] 'read index received' (duration: 87.440622ms)","trace[712866086] 'applied index is now lower than readState.Index' (duration: 16.313504ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T10:42:12.714856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.922561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T10:42:12.714913Z","caller":"traceutil/trace.go:171","msg":"trace[1016859614] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25145; }","duration":"104.058523ms","start":"2026-10-04T10:42:12.610842Z","end":"2026-10-04T10:42:12.714901Z","steps":["trace[1016859614] 'agreement among raft nodes before linearized reading' (duration: 103.943331ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:42:12.714971Z","caller":"traceutil/trace.go:171","msg":"trace[359212532] transaction","detail":"{read_only:false; response_revision:25145; number_of_response:1; }","duration":"151.460362ms","start":"2026-10-04T10:42:12.563465Z","end":"2026-10-04T10:42:12.714926Z","steps":["trace[359212532] 'process raft request' (duration: 134.933983ms)","trace[359212532] 'compare' (duration: 15.957095ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:42:13.027153Z","caller":"traceutil/trace.go:171","msg":"trace[24074486] transaction","detail":"{read_only:false; response_revision:25146; number_of_response:1; }","duration":"133.396898ms","start":"2026-10-04T10:42:12.893719Z","end":"2026-10-04T10:42:13.027116Z","steps":["trace[24074486] 'process raft request' (duration: 133.178123ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:42:13.337629Z","caller":"traceutil/trace.go:171","msg":"trace[318024203] transaction","detail":"{read_only:false; response_revision:25147; number_of_response:1; }","duration":"443.803889ms","start":"2026-10-04T10:42:12.893771Z","end":"2026-10-04T10:42:13.337575Z","steps":["trace[318024203] 'process raft request' (duration: 368.234532ms)","trace[318024203] 'compare' (duration: 75.186929ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:42:13.337806Z","caller":"traceutil/trace.go:171","msg":"trace[1837952527] transaction","detail":"{read_only:false; response_revision:25148; number_of_response:1; }","duration":"429.791796ms","start":"2026-10-04T10:42:12.90799Z","end":"2026-10-04T10:42:13.337782Z","steps":["trace[1837952527] 'process raft request' (duration: 429.50612ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:42:13.337768Z","caller":"traceutil/trace.go:171","msg":"trace[506525142] linearizableReadLoop","detail":"{readStateIndex:25979; appliedIndex:25977; }","duration":"429.714095ms","start":"2026-10-04T10:42:12.90801Z","end":"2026-10-04T10:42:13.337724Z","steps":["trace[506525142] 'read index received' (duration: 118.952776ms)","trace[506525142] 'applied index is now lower than readState.Index' (duration: 310.759309ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T10:42:13.337933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:42:12.907966Z","time spent":"429.909489ms","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T10:42:13.33795Z","caller":"traceutil/trace.go:171","msg":"trace[1943907009] transaction","detail":"{read_only:false; response_revision:25149; number_of_response:1; }","duration":"359.166829ms","start":"2026-10-04T10:42:12.978769Z","end":"2026-10-04T10:42:13.337935Z","steps":["trace[1943907009] 'process raft request' (duration: 358.887403ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:42:13.337845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:42:12.893745Z","time spent":"443.989193ms","remote":"127.0.0.1:58858","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-04T10:42:13.338011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:42:12.978744Z","time spent":"359.235201ms","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T10:42:13.338171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.124814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-04T10:42:13.338246Z","caller":"traceutil/trace.go:171","msg":"trace[1018557203] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25149; }","duration":"430.256217ms","start":"2026-10-04T10:42:12.907966Z","end":"2026-10-04T10:42:13.338222Z","steps":["trace[1018557203] 'agreement among raft nodes before linearized reading' (duration: 430.009471ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:42:13.338329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:42:12.907952Z","time spent":"430.361259ms","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-04T10:42:13.345113Z","caller":"traceutil/trace.go:171","msg":"trace[1352618538] transaction","detail":"{read_only:false; response_revision:25150; number_of_response:1; }","duration":"286.096117ms","start":"2026-10-04T10:42:13.058995Z","end":"2026-10-04T10:42:13.345091Z","steps":["trace[1352618538] 'process raft request' (duration: 285.951124ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:42:13.504035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.350468ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-04T10:42:13.504309Z","caller":"traceutil/trace.go:171","msg":"trace[1219062184] linearizableReadLoop","detail":"{readStateIndex:25983; appliedIndex:25981; }","duration":"166.357824ms","start":"2026-10-04T10:42:13.337931Z","end":"2026-10-04T10:42:13.504289Z","steps":["trace[1219062184] 'read index received' (duration: 7.094609ms)","trace[1219062184] 'applied index is now lower than readState.Index' (duration: 159.261555ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T10:42:13.504465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.537848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T10:42:13.504505Z","caller":"traceutil/trace.go:171","msg":"trace[2110589007] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:25151; }","duration":"475.64353ms","start":"2026-10-04T10:42:13.028846Z","end":"2026-10-04T10:42:13.50449Z","steps":["trace[2110589007] 'agreement among raft nodes before linearized reading' (duration: 475.501956ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:42:13.504541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:42:13.02883Z","time spent":"475.698711ms","remote":"127.0.0.1:44048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-10-04T10:42:13.504684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.902678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/applicationcredentials/\" range_end:\"/registry/openstack.k-orc.cloud/applicationcredentials0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T10:42:13.504768Z","caller":"traceutil/trace.go:171","msg":"trace[3000664] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/applicationcredentials/; range_end:/registry/openstack.k-orc.cloud/applicationcredentials0; response_count:0; response_revision:25151; }","duration":"232.00596ms","start":"2026-10-04T10:42:13.272742Z","end":"2026-10-04T10:42:13.504748Z","steps":["trace[3000664] 'agreement among raft nodes before linearized reading' (duration: 231.832716ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:42:13.504793Z","caller":"traceutil/trace.go:171","msg":"trace[1853372943] transaction","detail":"{read_only:false; response_revision:25151; number_of_response:1; }","duration":"161.20675ms","start":"2026-10-04T10:42:13.343542Z","end":"2026-10-04T10:42:13.504748Z","steps":["trace[1853372943] 'process raft request' (duration: 160.592176ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:42:13.504901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.937307ms","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-04T10:42:13.504944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.371083ms","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-04T10:42:13.505Z","caller":"traceutil/trace.go:171","msg":"trace[748323994] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25151; }","duration":"427.478275ms","start":"2026-10-04T10:42:13.077506Z","end":"2026-10-04T10:42:13.504984Z","steps":["trace[748323994] 'agreement among raft nodes before linearized reading' (duration: 427.265341ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:42:13.505014Z","caller":"traceutil/trace.go:171","msg":"trace[545331540] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25151; }","duration":"300.157272ms","start":"2026-10-04T10:42:13.204821Z","end":"2026-10-04T10:42:13.504978Z","steps":["trace[545331540] 'agreement among raft nodes before linearized reading' (duration: 299.847005ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:42:13.50503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:42:13.07748Z","time spent":"427.541997ms","remote":"127.0.0.1:58858","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-04T10:42:13.505071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:42:13.204794Z","time spent":"300.267034ms","remote":"127.0.0.1:58858","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":"info","ts":"2026-10-04T10:42:13.663414Z","caller":"traceutil/trace.go:171","msg":"trace[755110978] transaction","detail":"{read_only:false; response_revision:25152; number_of_response:1; }","duration":"303.723771ms","start":"2026-10-04T10:42:13.359652Z","end":"2026-10-04T10:42:13.663376Z","steps":["trace[755110978] 'process raft request' (duration: 275.797368ms)","trace[755110978] 'compare' (duration: 27.715559ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T10:42:13.663615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:42:13.359629Z","time spent":"303.875504ms","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T10:42:13.664205Z","caller":"traceutil/trace.go:171","msg":"trace[680470235] transaction","detail":"{read_only:false; response_revision:25153; number_of_response:1; }","duration":"153.891356ms","start":"2026-10-04T10:42:13.510297Z","end":"2026-10-04T10:42:13.664188Z","steps":["trace[680470235] 'process raft request' (duration: 153.759403ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:42:13.66475Z","caller":"traceutil/trace.go:171","msg":"trace[2114756823] transaction","detail":"{read_only:false; response_revision:25154; number_of_response:1; }","duration":"153.777093ms","start":"2026-10-04T10:42:13.510959Z","end":"2026-10-04T10:42:13.664736Z","steps":["trace[2114756823] 'process raft request' (duration: 153.18296ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:42:13.665409Z","caller":"traceutil/trace.go:171","msg":"trace[1630284092] transaction","detail":"{read_only:false; response_revision:25155; number_of_response:1; }","duration":"105.615309ms","start":"2026-10-04T10:42:13.559777Z","end":"2026-10-04T10:42:13.665393Z","steps":["trace[1630284092] 'process raft request' (duration: 104.912463ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:42:14.241635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.230238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T10:42:14.241742Z","caller":"traceutil/trace.go:171","msg":"trace[357477708] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25156; }","duration":"134.414731ms","start":"2026-10-04T10:42:14.107302Z","end":"2026-10-04T10:42:14.241717Z","steps":["trace[357477708] 'range keys from in-memory index tree' (duration: 134.134205ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:42:14.912503Z","caller":"traceutil/trace.go:171","msg":"trace[153261017] linearizableReadLoop","detail":"{readStateIndex:25991; appliedIndex:25990; }","duration":"301.127053ms","start":"2026-10-04T10:42:14.611328Z","end":"2026-10-04T10:42:14.912455Z","steps":["trace[153261017] 'read index received' (duration: 300.942009ms)","trace[153261017] 'applied index is now lower than readState.Index' (duration: 183.734µs)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:42:14.912546Z","caller":"traceutil/trace.go:171","msg":"trace[943781121] transaction","detail":"{read_only:false; response_revision:25158; number_of_response:1; }","duration":"327.315788ms","start":"2026-10-04T10:42:14.585193Z","end":"2026-10-04T10:42:14.912509Z","steps":["trace[943781121] 'process raft request' (duration: 327.035232ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:42:14.912739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:42:14.585155Z","time spent":"327.476862ms","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T10:42:14.912892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.48367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T10:42:14.913079Z","caller":"traceutil/trace.go:171","msg":"trace[2080487014] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25158; }","duration":"301.768146ms","start":"2026-10-04T10:42:14.611255Z","end":"2026-10-04T10:42:14.913023Z","steps":["trace[2080487014] 'agreement among raft nodes before linearized reading' (duration: 301.356267ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:42:14.913168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:42:14.611231Z","time spent":"301.92459ms","remote":"127.0.0.1:58572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-04T10:42:15.032457Z","caller":"traceutil/trace.go:171","msg":"trace[161927974] linearizableReadLoop","detail":"{readStateIndex:25992; appliedIndex:25991; }","duration":"117.961884ms","start":"2026-10-04T10:42:14.914463Z","end":"2026-10-04T10:42:15.032425Z","steps":["trace[161927974] 'read index received' (duration: 116.087952ms)","trace[161927974] 'applied index is now lower than readState.Index' (duration: 1.869542ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:42:15.032541Z","caller":"traceutil/trace.go:171","msg":"trace[1171117180] transaction","detail":"{read_only:false; response_revision:25159; number_of_response:1; }","duration":"311.046305ms","start":"2026-10-04T10:42:14.721467Z","end":"2026-10-04T10:42:15.032513Z","steps":["trace[1171117180] 'process raft request' (duration: 309.172433ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:42:15.032686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:42:14.721434Z","time spent":"311.162217ms","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T10:42:15.032793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.293031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T10:42:15.032846Z","caller":"traceutil/trace.go:171","msg":"trace[934259495] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25159; }","duration":"118.418794ms","start":"2026-10-04T10:42:14.914412Z","end":"2026-10-04T10:42:15.032831Z","steps":["trace[934259495] 'agreement among raft nodes before linearized reading' (duration: 118.299941ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:42:15.032839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.728182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-04T10:42:15.032957Z","caller":"traceutil/trace.go:171","msg":"trace[260661108] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:25159; }","duration":"114.889935ms","start":"2026-10-04T10:42:14.918042Z","end":"2026-10-04T10:42:15.032932Z","steps":["trace[260661108] 'agreement among raft nodes before linearized reading' (duration: 114.66202ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:42:15.614159Z","caller":"traceutil/trace.go:171","msg":"trace[513437472] linearizableReadLoop","detail":"{readStateIndex:25997; appliedIndex:25995; }","duration":"104.139647ms","start":"2026-10-04T10:42:15.509988Z","end":"2026-10-04T10:42:15.614128Z","steps":["trace[513437472] 'read index received' (duration: 18.802561ms)","trace[513437472] 'applied index is now lower than readState.Index' (duration: 85.335456ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:42:15.614294Z","caller":"traceutil/trace.go:171","msg":"trace[1968570575] transaction","detail":"{read_only:false; response_revision:25163; number_of_response:1; }","duration":"265.355005ms","start":"2026-10-04T10:42:15.348892Z","end":"2026-10-04T10:42:15.614247Z","steps":["trace[1968570575] 'process raft request' (duration: 179.865276ms)","trace[1968570575] 'compare' (duration: 84.962267ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T10:42:15.614533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.514445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-04T10:42:15.614582Z","caller":"traceutil/trace.go:171","msg":"trace[1890865945] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25164; }","duration":"104.645197ms","start":"2026-10-04T10:42:15.509923Z","end":"2026-10-04T10:42:15.614569Z","steps":["trace[1890865945] 'agreement among raft nodes before linearized reading' (duration: 104.366321ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:42:15.614299Z","caller":"traceutil/trace.go:171","msg":"trace[505490899] transaction","detail":"{read_only:false; response_revision:25164; number_of_response:1; }","duration":"263.923023ms","start":"2026-10-04T10:42:15.35033Z","end":"2026-10-04T10:42:15.614253Z","steps":["trace[505490899] 'process raft request' (duration: 263.725489ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:42:15.849032Z","caller":"traceutil/trace.go:171","msg":"trace[1002116530] linearizableReadLoop","detail":"{readStateIndex:25999; appliedIndex:25998; }","duration":"148.70387ms","start":"2026-10-04T10:42:15.700296Z","end":"2026-10-04T10:42:15.849Z","steps":["trace[1002116530] 'read index received' (duration: 100.952584ms)","trace[1002116530] 'applied index is now lower than readState.Index' (duration: 47.749716ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:42:15.849217Z","caller":"traceutil/trace.go:171","msg":"trace[1001097673] transaction","detail":"{read_only:false; response_revision:25166; number_of_response:1; }","duration":"178.385324ms","start":"2026-10-04T10:42:15.670786Z","end":"2026-10-04T10:42:15.849172Z","steps":["trace[1001097673] 'process raft request' (duration: 130.446322ms)","trace[1001097673] 'compare' (duration: 47.595433ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T10:42:15.849365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.098252ms","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-04T10:42:15.849415Z","caller":"traceutil/trace.go:171","msg":"trace[1265228743] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25166; }","duration":"180.198664ms","start":"2026-10-04T10:42:15.669201Z","end":"2026-10-04T10:42:15.849399Z","steps":["trace[1265228743] 'agreement among raft nodes before linearized reading' (duration: 179.932028ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:42:15.849639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.423194ms","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-04T10:42:15.849818Z","caller":"traceutil/trace.go:171","msg":"trace[2036537135] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25166; }","duration":"178.656719ms","start":"2026-10-04T10:42:15.671135Z","end":"2026-10-04T10:42:15.849792Z","steps":["trace[2036537135] 'agreement among raft nodes before linearized reading' (duration: 178.2261ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:42:16.228828Z","caller":"traceutil/trace.go:171","msg":"trace[1034616894] transaction","detail":"{read_only:false; response_revision:25169; number_of_response:1; }","duration":"255.737839ms","start":"2026-10-04T10:42:15.973062Z","end":"2026-10-04T10:42:16.2288Z","steps":["trace[1034616894] 'process raft request' (duration: 255.537555ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:42:16.570765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.350386ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-04T10:42:16.571004Z","caller":"traceutil/trace.go:171","msg":"trace[933762918] transaction","detail":"{read_only:false; response_revision:25171; number_of_response:1; }","duration":"266.346837ms","start":"2026-10-04T10:42:16.304637Z","end":"2026-10-04T10:42:16.570984Z","steps":["trace[933762918] 'process raft request' (duration: 266.279625ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:42:16.571044Z","caller":"traceutil/trace.go:171","msg":"trace[1298203555] transaction","detail":"{read_only:false; response_revision:25170; number_of_response:1; }","duration":"419.311882ms","start":"2026-10-04T10:42:16.151693Z","end":"2026-10-04T10:42:16.571005Z","steps":["trace[1298203555] 'process raft request' (duration: 243.599999ms)","trace[1298203555] 'compare' (duration: 175.212602ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T10:42:16.57122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:42:16.151662Z","time spent":"419.455256ms","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T10:42:16.865005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.292107ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-04T10:42:16.865125Z","caller":"traceutil/trace.go:171","msg":"trace[863083451] linearizableReadLoop","detail":"{readStateIndex:26005; appliedIndex:26004; }","duration":"253.260525ms","start":"2026-10-04T10:42:16.611843Z","end":"2026-10-04T10:42:16.865103Z","steps":["trace[863083451] 'read index received' (duration: 93.675691ms)","trace[863083451] 'applied index is now lower than readState.Index' (duration: 159.583304ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:42:16.865224Z","caller":"traceutil/trace.go:171","msg":"trace[1378019563] transaction","detail":"{read_only:false; response_revision:25172; number_of_response:1; }","duration":"282.062657ms","start":"2026-10-04T10:42:16.583148Z","end":"2026-10-04T10:42:16.865211Z","steps":["trace[1378019563] 'process raft request' (duration: 122.461213ms)","trace[1378019563] 'compare' (duration: 159.031972ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T10:42:16.865608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.743675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T10:42:16.865697Z","caller":"traceutil/trace.go:171","msg":"trace[1508692588] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25172; }","duration":"253.906578ms","start":"2026-10-04T10:42:16.611768Z","end":"2026-10-04T10:42:16.865675Z","steps":["trace[1508692588] 'agreement among raft nodes before linearized reading' (duration: 253.731605ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:42:17.043038Z","caller":"traceutil/trace.go:171","msg":"trace[2031663093] transaction","detail":"{read_only:false; response_revision:25173; number_of_response:1; }","duration":"121.379211ms","start":"2026-10-04T10:42:16.921614Z","end":"2026-10-04T10:42:17.042993Z","steps":["trace[2031663093] 'process raft request' (duration: 120.667415ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:42:17.379709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.365676ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-04T10:42:17.380294Z","caller":"traceutil/trace.go:171","msg":"trace[1897641723] transaction","detail":"{read_only:false; response_revision:25174; number_of_response:1; }","duration":"341.566257ms","start":"2026-10-04T10:42:17.038706Z","end":"2026-10-04T10:42:17.380273Z","steps":["trace[1897641723] 'process raft request' (duration: 195.545647ms)","trace[1897641723] 'compare' (duration: 145.239793ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T10:42:17.380354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:42:17.038682Z","time spent":"341.643649ms","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T10:42:17.380444Z","caller":"traceutil/trace.go:171","msg":"trace[853352785] linearizableReadLoop","detail":"{readStateIndex:26007; appliedIndex:26006; }","duration":"328.577346ms","start":"2026-10-04T10:42:17.051833Z","end":"2026-10-04T10:42:17.38041Z","steps":["trace[853352785] 'read index received' (duration: 182.365281ms)","trace[853352785] 'applied index is now lower than readState.Index' (duration: 146.209425ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T10:42:17.380607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.777401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-04T10:42:17.380638Z","caller":"traceutil/trace.go:171","msg":"trace[1425348800] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25175; }","duration":"328.872213ms","start":"2026-10-04T10:42:17.051757Z","end":"2026-10-04T10:42:17.380629Z","steps":["trace[1425348800] 'agreement among raft nodes before linearized reading' (duration: 328.75602ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:42:17.380658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:42:17.051732Z","time spent":"328.922274ms","remote":"127.0.0.1:58858","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-04T10:42:17.691398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.559613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1989"} {"level":"info","ts":"2026-10-04T10:42:17.69152Z","caller":"traceutil/trace.go:171","msg":"trace[310185467] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:25178; }","duration":"198.753737ms","start":"2026-10-04T10:42:17.492738Z","end":"2026-10-04T10:42:17.691492Z","steps":["trace[310185467] 'range keys from in-memory index tree' (duration: 198.271638ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:42:17.849277Z","caller":"traceutil/trace.go:171","msg":"trace[1103440968] linearizableReadLoop","detail":"{readStateIndex:26012; appliedIndex:26011; }","duration":"153.774622ms","start":"2026-10-04T10:42:17.695468Z","end":"2026-10-04T10:42:17.849243Z","steps":["trace[1103440968] 'read index received' (duration: 153.598218ms)","trace[1103440968] 'applied index is now lower than readState.Index' (duration: 174.724µs)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:42:17.849373Z","caller":"traceutil/trace.go:171","msg":"trace[687690821] transaction","detail":"{read_only:false; response_revision:25179; number_of_response:1; }","duration":"228.548333ms","start":"2026-10-04T10:42:17.620789Z","end":"2026-10-04T10:42:17.849337Z","steps":["trace[687690821] 'process raft request' (duration: 228.281077ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:42:17.849584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.05473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/openstack/container-infra-registry\" ","response":"range_response_count:1 size:784"} {"level":"info","ts":"2026-10-04T10:42:17.849711Z","caller":"traceutil/trace.go:171","msg":"trace[1734835751] range","detail":"{range_begin:/registry/ingress/openstack/container-infra-registry; range_end:; response_count:1; response_revision:25179; }","duration":"154.275734ms","start":"2026-10-04T10:42:17.695412Z","end":"2026-10-04T10:42:17.849688Z","steps":["trace[1734835751] 'agreement among raft nodes before linearized reading' (duration: 153.950337ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:42:17.86417Z","caller":"traceutil/trace.go:171","msg":"trace[620891832] transaction","detail":"{read_only:false; response_revision:25180; number_of_response:1; }","duration":"167.393958ms","start":"2026-10-04T10:42:17.69675Z","end":"2026-10-04T10:42:17.864144Z","steps":["trace[620891832] 'process raft request' (duration: 167.128762ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:42:17.864301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.340858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/openstack/container-infra\" ","response":"range_response_count:1 size:729"} {"level":"warn","ts":"2026-10-04T10:42:17.864349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.848667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-04T10:42:17.864393Z","caller":"traceutil/trace.go:171","msg":"trace[1238515350] range","detail":"{range_begin:/registry/ingress/openstack/container-infra; range_end:; response_count:1; response_revision:25180; }","duration":"168.468422ms","start":"2026-10-04T10:42:17.695903Z","end":"2026-10-04T10:42:17.864372Z","steps":["trace[1238515350] 'agreement among raft nodes before linearized reading' (duration: 168.158435ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:42:17.864413Z","caller":"traceutil/trace.go:171","msg":"trace[223110999] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25180; }","duration":"158.933419ms","start":"2026-10-04T10:42:17.705458Z","end":"2026-10-04T10:42:17.864391Z","steps":["trace[223110999] 'agreement among raft nodes before linearized reading' (duration: 158.783496ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:42:18.029595Z","caller":"traceutil/trace.go:171","msg":"trace[789228237] transaction","detail":"{read_only:false; response_revision:25182; number_of_response:1; }","duration":"173.488634ms","start":"2026-10-04T10:42:17.856064Z","end":"2026-10-04T10:42:18.029553Z","steps":["trace[789228237] 'process raft request' (duration: 173.166697ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:42:18.029629Z","caller":"traceutil/trace.go:171","msg":"trace[798609195] transaction","detail":"{read_only:false; response_revision:25181; number_of_response:1; }","duration":"174.27622ms","start":"2026-10-04T10:42:17.855293Z","end":"2026-10-04T10:42:18.02957Z","steps":["trace[798609195] 'process raft request' (duration: 86.668835ms)","trace[798609195] 'compare' (duration: 86.767567ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:42:18.120315Z","caller":"traceutil/trace.go:171","msg":"trace[994624438] transaction","detail":"{read_only:false; response_revision:25183; number_of_response:1; }","duration":"252.179221ms","start":"2026-10-04T10:42:17.868106Z","end":"2026-10-04T10:42:18.120285Z","steps":["trace[994624438] 'process raft request' (duration: 251.988556ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:42:18.120523Z","caller":"traceutil/trace.go:171","msg":"trace[294835991] linearizableReadLoop","detail":"{readStateIndex:26017; appliedIndex:26013; }","duration":"244.270924ms","start":"2026-10-04T10:42:17.876205Z","end":"2026-10-04T10:42:18.120476Z","steps":["trace[294835991] 'read index received' (duration: 65.777959ms)","trace[294835991] 'applied index is now lower than readState.Index' (duration: 178.489825ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:42:18.120548Z","caller":"traceutil/trace.go:171","msg":"trace[946129466] transaction","detail":"{read_only:false; response_revision:25184; number_of_response:1; }","duration":"251.344692ms","start":"2026-10-04T10:42:17.869166Z","end":"2026-10-04T10:42:18.120511Z","steps":["trace[946129466] 'process raft request' (duration: 251.062965ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:42:18.12097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.374394ms","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-04T10:42:18.12102Z","caller":"traceutil/trace.go:171","msg":"trace[1727810067] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25184; }","duration":"230.505566ms","start":"2026-10-04T10:42:17.8905Z","end":"2026-10-04T10:42:18.121005Z","steps":["trace[1727810067] 'agreement among raft nodes before linearized reading' (duration: 230.21249ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:42:18.121302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.998211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-04T10:42:18.121483Z","caller":"traceutil/trace.go:171","msg":"trace[894875813] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25184; }","duration":"245.258016ms","start":"2026-10-04T10:42:17.876198Z","end":"2026-10-04T10:42:18.121456Z","steps":["trace[894875813] 'agreement among raft nodes before linearized reading' (duration: 244.463388ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:42:18.271087Z","caller":"traceutil/trace.go:171","msg":"trace[1630786135] transaction","detail":"{read_only:false; response_revision:25185; number_of_response:1; }","duration":"146.703215ms","start":"2026-10-04T10:42:18.124342Z","end":"2026-10-04T10:42:18.271045Z","steps":["trace[1630786135] 'process raft request' (duration: 146.300626ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:42:18.271115Z","caller":"traceutil/trace.go:171","msg":"trace[1528581395] transaction","detail":"{read_only:false; response_revision:25186; number_of_response:1; }","duration":"144.63583ms","start":"2026-10-04T10:42:18.126437Z","end":"2026-10-04T10:42:18.271073Z","steps":["trace[1528581395] 'process raft request' (duration: 144.333913ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:42:18.271131Z","caller":"traceutil/trace.go:171","msg":"trace[1505427072] transaction","detail":"{read_only:false; response_revision:25187; number_of_response:1; }","duration":"144.435164ms","start":"2026-10-04T10:42:18.126662Z","end":"2026-10-04T10:42:18.271097Z","steps":["trace[1505427072] 'process raft request' (duration: 144.168238ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:42:30.522308Z","caller":"traceutil/trace.go:171","msg":"trace[630903570] linearizableReadLoop","detail":"{readStateIndex:26112; appliedIndex:26111; }","duration":"190.3362ms","start":"2026-10-04T10:42:30.331943Z","end":"2026-10-04T10:42:30.522279Z","steps":["trace[630903570] 'read index received' (duration: 190.166436ms)","trace[630903570] 'applied index is now lower than readState.Index' (duration: 167.954µs)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:42:30.52235Z","caller":"traceutil/trace.go:171","msg":"trace[2146065229] transaction","detail":"{read_only:false; response_revision:25276; number_of_response:1; }","duration":"207.719998ms","start":"2026-10-04T10:42:30.314584Z","end":"2026-10-04T10:42:30.522304Z","steps":["trace[2146065229] 'process raft request' (duration: 207.507103ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:42:30.522614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.825085ms","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-04T10:42:30.52266Z","caller":"traceutil/trace.go:171","msg":"trace[515481714] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25276; }","duration":"170.940807ms","start":"2026-10-04T10:42:30.351705Z","end":"2026-10-04T10:42:30.522646Z","steps":["trace[515481714] 'agreement among raft nodes before linearized reading' (duration: 170.753473ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:42:30.522666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.140561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/ports/\" range_end:\"/registry/openstack.k-orc.cloud/ports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-04T10:42:30.522662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.678147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-04T10:42:30.522725Z","caller":"traceutil/trace.go:171","msg":"trace[920931900] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/ports/; range_end:/registry/openstack.k-orc.cloud/ports0; response_count:0; response_revision:25276; }","duration":"141.205792ms","start":"2026-10-04T10:42:30.381487Z","end":"2026-10-04T10:42:30.522692Z","steps":["trace[920931900] 'agreement among raft nodes before linearized reading' (duration: 141.0924ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:42:30.522764Z","caller":"traceutil/trace.go:171","msg":"trace[1515069786] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25276; }","duration":"190.893953ms","start":"2026-10-04T10:42:30.331847Z","end":"2026-10-04T10:42:30.522741Z","steps":["trace[1515069786] 'agreement among raft nodes before linearized reading' (duration: 190.565655ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:42:30.823525Z","caller":"traceutil/trace.go:171","msg":"trace[1377443205] linearizableReadLoop","detail":"{readStateIndex:26114; appliedIndex:26113; }","duration":"211.699767ms","start":"2026-10-04T10:42:30.611787Z","end":"2026-10-04T10:42:30.823487Z","steps":["trace[1377443205] 'read index received' (duration: 190.992854ms)","trace[1377443205] 'applied index is now lower than readState.Index' (duration: 20.704693ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:42:30.82368Z","caller":"traceutil/trace.go:171","msg":"trace[835526323] transaction","detail":"{read_only:false; response_revision:25278; number_of_response:1; }","duration":"295.500578ms","start":"2026-10-04T10:42:30.528139Z","end":"2026-10-04T10:42:30.823639Z","steps":["trace[835526323] 'process raft request' (duration: 274.781796ms)","trace[835526323] 'compare' (duration: 19.604028ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T10:42:30.823753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.949343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T10:42:30.824001Z","caller":"traceutil/trace.go:171","msg":"trace[317455039] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25278; }","duration":"212.069065ms","start":"2026-10-04T10:42:30.611718Z","end":"2026-10-04T10:42:30.823787Z","steps":["trace[317455039] 'agreement among raft nodes before linearized reading' (duration: 211.920342ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:42:31.175441Z","caller":"traceutil/trace.go:171","msg":"trace[3708627] linearizableReadLoop","detail":"{readStateIndex:26115; appliedIndex:26114; }","duration":"351.800085ms","start":"2026-10-04T10:42:30.823605Z","end":"2026-10-04T10:42:31.175405Z","steps":["trace[3708627] 'read index received' (duration: 269.331584ms)","trace[3708627] 'applied index is now lower than readState.Index' (duration: 82.467361ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:42:31.175462Z","caller":"traceutil/trace.go:171","msg":"trace[124796033] transaction","detail":"{read_only:false; response_revision:25279; number_of_response:1; }","duration":"493.257153ms","start":"2026-10-04T10:42:30.682152Z","end":"2026-10-04T10:42:31.175409Z","steps":["trace[124796033] 'process raft request' (duration: 410.767842ms)","trace[124796033] 'compare' (duration: 82.310627ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T10:42:31.175649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:42:30.682108Z","time spent":"493.440748ms","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T10:42:31.175703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.081457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-04T10:42:31.175768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.27835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T10:42:31.175788Z","caller":"traceutil/trace.go:171","msg":"trace[31328060] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25279; }","duration":"350.22619ms","start":"2026-10-04T10:42:30.825539Z","end":"2026-10-04T10:42:31.175766Z","steps":["trace[31328060] 'agreement among raft nodes before linearized reading' (duration: 350.095947ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:42:31.175849Z","caller":"traceutil/trace.go:171","msg":"trace[544372009] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagerconfigs/; range_end:/registry/monitoring.coreos.com/alertmanagerconfigs0; response_count:0; response_revision:25279; }","duration":"560.523156ms","start":"2026-10-04T10:42:30.615285Z","end":"2026-10-04T10:42:31.175808Z","steps":["trace[544372009] 'agreement among raft nodes before linearized reading' (duration: 560.296611ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:42:31.175859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:42:30.825521Z","time spent":"350.329973ms","remote":"127.0.0.1:58562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-04T10:42:31.175894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:42:30.615256Z","time spent":"560.625248ms","remote":"127.0.0.1:46978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true "} {"level":"warn","ts":"2026-10-04T10:42:31.176103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.239291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T10:42:31.176142Z","caller":"traceutil/trace.go:171","msg":"trace[790947228] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:25279; }","duration":"269.352394ms","start":"2026-10-04T10:42:30.906777Z","end":"2026-10-04T10:42:31.176129Z","steps":["trace[790947228] 'agreement among raft nodes before linearized reading' (duration: 269.283323ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:42:31.176118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.802903ms","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:1936"} {"level":"info","ts":"2026-10-04T10:42:31.176253Z","caller":"traceutil/trace.go:171","msg":"trace[848663616] 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:25279; }","duration":"552.987527ms","start":"2026-10-04T10:42:30.623247Z","end":"2026-10-04T10:42:31.176234Z","steps":["trace[848663616] 'agreement among raft nodes before linearized reading' (duration: 552.237421ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:42:31.176333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:42:30.623219Z","time spent":"553.09696ms","remote":"127.0.0.1:58754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1958,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"warn","ts":"2026-10-04T10:42:31.176252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.308385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:419049"} {"level":"info","ts":"2026-10-04T10:42:31.176438Z","caller":"traceutil/trace.go:171","msg":"trace[1316388726] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:25279; }","duration":"158.533901ms","start":"2026-10-04T10:42:31.017891Z","end":"2026-10-04T10:42:31.176425Z","steps":["trace[1316388726] 'agreement among raft nodes before linearized reading' (duration: 158.126112ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:42:31.424943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.060337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T10:42:31.425058Z","caller":"traceutil/trace.go:171","msg":"trace[167628966] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:25280; }","duration":"118.268302ms","start":"2026-10-04T10:42:31.306765Z","end":"2026-10-04T10:42:31.425033Z","steps":["trace[167628966] 'count revisions from in-memory index tree' (duration: 117.932784ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:42:32.063957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.795381ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-04T10:42:32.06407Z","caller":"traceutil/trace.go:171","msg":"trace[2022830331] linearizableReadLoop","detail":"{readStateIndex:26120; appliedIndex:26119; }","duration":"468.549193ms","start":"2026-10-04T10:42:31.595502Z","end":"2026-10-04T10:42:32.064052Z","steps":["trace[2022830331] 'read index received' (duration: 53.121µs)","trace[2022830331] 'applied index is now lower than readState.Index' (duration: 468.494472ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:42:32.064163Z","caller":"traceutil/trace.go:171","msg":"trace[1475813863] transaction","detail":"{read_only:false; response_revision:25284; number_of_response:1; }","duration":"573.950846ms","start":"2026-10-04T10:42:31.49019Z","end":"2026-10-04T10:42:32.064141Z","steps":["trace[1475813863] 'process raft request' (duration: 38.823257ms)","trace[1475813863] 'compare' (duration: 534.212358ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T10:42:32.064221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:42:31.490172Z","time spent":"574.016627ms","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T10:42:32.06446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.956591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" ","response":"range_response_count:1 size:1852"} {"level":"info","ts":"2026-10-04T10:42:32.06449Z","caller":"traceutil/trace.go:171","msg":"trace[577813816] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0; range_end:; response_count:1; response_revision:25284; }","duration":"469.045314ms","start":"2026-10-04T10:42:31.595432Z","end":"2026-10-04T10:42:32.064477Z","steps":["trace[577813816] 'agreement among raft nodes before linearized reading' (duration: 468.908021ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:42:32.064517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:42:31.595403Z","time spent":"469.105875ms","remote":"127.0.0.1:58754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":79,"response count":1,"response size":1874,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" "} {"level":"info","ts":"2026-10-04T10:42:32.065276Z","caller":"traceutil/trace.go:171","msg":"trace[5668184] transaction","detail":"{read_only:false; response_revision:25285; number_of_response:1; }","duration":"466.997468ms","start":"2026-10-04T10:42:31.598246Z","end":"2026-10-04T10:42:32.065243Z","steps":["trace[5668184] 'process raft request' (duration: 466.812364ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:42:32.065497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:42:31.598227Z","time spent":"467.13464ms","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T10:42:32.405393Z","caller":"traceutil/trace.go:171","msg":"trace[1824323632] transaction","detail":"{read_only:false; response_revision:25286; number_of_response:1; }","duration":"769.323078ms","start":"2026-10-04T10:42:31.636013Z","end":"2026-10-04T10:42:32.405336Z","steps":["trace[1824323632] 'process raft request' (duration: 764.52046ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:42:32.406872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:42:31.635985Z","time spent":"770.717499ms","remote":"127.0.0.1:51874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419107,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T10:42:32.40549Z","caller":"traceutil/trace.go:171","msg":"trace[660966497] transaction","detail":"{read_only:false; response_revision:25287; number_of_response:1; }","duration":"494.674955ms","start":"2026-10-04T10:42:31.910761Z","end":"2026-10-04T10:42:32.405436Z","steps":["trace[660966497] 'process raft request' (duration: 490.784978ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:42:32.407618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:42:31.910731Z","time spent":"496.837233ms","remote":"127.0.0.1:58858","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-04T10:42:32.405806Z","caller":"traceutil/trace.go:171","msg":"trace[486695357] linearizableReadLoop","detail":"{readStateIndex:26123; appliedIndex:26121; }","duration":"341.625117ms","start":"2026-10-04T10:42:32.064097Z","end":"2026-10-04T10:42:32.405722Z","steps":["trace[486695357] 'read index received' (duration: 336.244377ms)","trace[486695357] 'applied index is now lower than readState.Index' (duration: 5.37735ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T10:42:32.406453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.84557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-10-04T10:42:32.410545Z","caller":"traceutil/trace.go:171","msg":"trace[1908452531] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25287; }","duration":"537.978522ms","start":"2026-10-04T10:42:31.872514Z","end":"2026-10-04T10:42:32.410493Z","steps":["trace[1908452531] 'agreement among raft nodes before linearized reading' (duration: 533.3681ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:42:32.410692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:42:31.872487Z","time spent":"538.150296ms","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":554,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-10-04T10:42:32.406572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.932903ms","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-04T10:42:32.411179Z","caller":"traceutil/trace.go:171","msg":"trace[974994968] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25287; }","duration":"470.529156ms","start":"2026-10-04T10:42:31.940632Z","end":"2026-10-04T10:42:32.411161Z","steps":["trace[974994968] 'agreement among raft nodes before linearized reading' (duration: 465.873131ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:42:32.411242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:42:31.940588Z","time spent":"470.638608ms","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-04T10:42:32.420771Z","caller":"traceutil/trace.go:171","msg":"trace[859074981] transaction","detail":"{read_only:false; response_revision:25288; number_of_response:1; }","duration":"313.637573ms","start":"2026-10-04T10:42:32.107093Z","end":"2026-10-04T10:42:32.420731Z","steps":["trace[859074981] 'process raft request' (duration: 313.267045ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:42:32.420861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.776393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-c8fc383c-7ec1-4f21-8184-b2802379a872\" ","response":"range_response_count:1 size:1318"} {"level":"info","ts":"2026-10-04T10:42:32.420953Z","caller":"traceutil/trace.go:171","msg":"trace[1734102342] range","detail":"{range_begin:/registry/persistentvolumes/pvc-c8fc383c-7ec1-4f21-8184-b2802379a872; range_end:; response_count:1; response_revision:25288; }","duration":"350.900135ms","start":"2026-10-04T10:42:32.07001Z","end":"2026-10-04T10:42:32.42091Z","steps":["trace[1734102342] 'agreement among raft nodes before linearized reading' (duration: 350.557278ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:42:32.420994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:42:32.06999Z","time spent":"350.994307ms","remote":"127.0.0.1:58750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1340,"request content":"key:\"/registry/persistentvolumes/pvc-c8fc383c-7ec1-4f21-8184-b2802379a872\" "} {"level":"warn","ts":"2026-10-04T10:42:32.421005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:42:32.107061Z","time spent":"313.832308ms","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T10:42:32.421286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.00062ms","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-04T10:42:32.421348Z","caller":"traceutil/trace.go:171","msg":"trace[748707861] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25288; }","duration":"228.103332ms","start":"2026-10-04T10:42:32.193218Z","end":"2026-10-04T10:42:32.421322Z","steps":["trace[748707861] 'agreement among raft nodes before linearized reading' (duration: 227.980369ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:42:32.967417Z","caller":"traceutil/trace.go:171","msg":"trace[1190715745] transaction","detail":"{read_only:false; response_revision:25289; number_of_response:1; }","duration":"546.705797ms","start":"2026-10-04T10:42:32.420648Z","end":"2026-10-04T10:42:32.967354Z","steps":["trace[1190715745] 'process raft request' (duration: 544.13811ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:42:32.96761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:42:32.42062Z","time spent":"546.88826ms","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T10:42:32.974916Z","caller":"traceutil/trace.go:171","msg":"trace[1744280528] transaction","detail":"{read_only:false; response_revision:25291; number_of_response:1; }","duration":"547.645718ms","start":"2026-10-04T10:42:32.427246Z","end":"2026-10-04T10:42:32.974891Z","steps":["trace[1744280528] 'process raft request' (duration: 547.534456ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:42:32.97492Z","caller":"traceutil/trace.go:171","msg":"trace[1804697261] transaction","detail":"{read_only:false; response_revision:25290; number_of_response:1; }","duration":"553.845076ms","start":"2026-10-04T10:42:32.421028Z","end":"2026-10-04T10:42:32.974873Z","steps":["trace[1804697261] 'process raft request' (duration: 553.610841ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:42:32.97502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:42:32.427224Z","time spent":"547.74773ms","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T10:42:32.975109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:42:32.420991Z","time spent":"554.01473ms","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T10:42:32.975154Z","caller":"traceutil/trace.go:171","msg":"trace[1223571896] linearizableReadLoop","detail":"{readStateIndex:26128; appliedIndex:26124; }","duration":"418.770891ms","start":"2026-10-04T10:42:32.556371Z","end":"2026-10-04T10:42:32.975142Z","steps":["trace[1223571896] 'read index received' (duration: 408.43113ms)","trace[1223571896] 'applied index is now lower than readState.Index' (duration: 10.338471ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T10:42:32.975427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.946943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-04T10:42:32.975481Z","caller":"traceutil/trace.go:171","msg":"trace[243502988] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25292; }","duration":"414.043865ms","start":"2026-10-04T10:42:32.561425Z","end":"2026-10-04T10:42:32.975468Z","steps":["trace[243502988] 'agreement among raft nodes before linearized reading' (duration: 413.884412ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:42:32.975509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:42:32.561404Z","time spent":"414.098686ms","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-04T10:42:32.975498Z","caller":"traceutil/trace.go:171","msg":"trace[564703167] transaction","detail":"{read_only:false; response_revision:25292; number_of_response:1; }","duration":"440.587597ms","start":"2026-10-04T10:42:32.534883Z","end":"2026-10-04T10:42:32.975471Z","steps":["trace[564703167] 'process raft request' (duration: 439.959643ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:42:32.975628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.263075ms","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":"warn","ts":"2026-10-04T10:42:32.975647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:42:32.534851Z","time spent":"440.7187ms","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T10:42:32.97569Z","caller":"traceutil/trace.go:171","msg":"trace[1240191525] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25292; }","duration":"146.347058ms","start":"2026-10-04T10:42:32.829323Z","end":"2026-10-04T10:42:32.97567Z","steps":["trace[1240191525] 'agreement among raft nodes before linearized reading' (duration: 146.163554ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:42:32.975663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.826237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T10:42:32.975751Z","caller":"traceutil/trace.go:171","msg":"trace[2037779546] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25292; }","duration":"365.966131ms","start":"2026-10-04T10:42:32.60976Z","end":"2026-10-04T10:42:32.975726Z","steps":["trace[2037779546] 'agreement among raft nodes before linearized reading' (duration: 365.786817ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:42:32.975792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:42:32.609733Z","time spent":"366.053572ms","remote":"127.0.0.1:58572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-04T10:42:32.975389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.953255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T10:42:32.975873Z","caller":"traceutil/trace.go:171","msg":"trace[744453976] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:25292; }","duration":"419.498707ms","start":"2026-10-04T10:42:32.556362Z","end":"2026-10-04T10:42:32.975861Z","steps":["trace[744453976] 'agreement among raft nodes before linearized reading' (duration: 418.891273ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:42:32.975916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:42:32.556131Z","time spent":"419.770033ms","remote":"127.0.0.1:51606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true "} {"level":"warn","ts":"2026-10-04T10:42:32.975993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.293903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T10:42:32.976017Z","caller":"traceutil/trace.go:171","msg":"trace[1644258660] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:0; response_revision:25292; }","duration":"252.355174ms","start":"2026-10-04T10:42:32.723653Z","end":"2026-10-04T10:42:32.976008Z","steps":["trace[1644258660] 'agreement among raft nodes before linearized reading' (duration: 252.315213ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:42:33.275567Z","caller":"traceutil/trace.go:171","msg":"trace[1130680846] transaction","detail":"{read_only:false; response_revision:25294; number_of_response:1; }","duration":"293.338241ms","start":"2026-10-04T10:42:32.982169Z","end":"2026-10-04T10:42:33.275507Z","steps":["trace[1130680846] 'process raft request' (duration: 211.622406ms)","trace[1130680846] 'compare' (duration: 81.330616ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:42:33.275612Z","caller":"traceutil/trace.go:171","msg":"trace[174810104] linearizableReadLoop","detail":"{readStateIndex:26130; appliedIndex:26128; }","duration":"290.183309ms","start":"2026-10-04T10:42:32.985392Z","end":"2026-10-04T10:42:33.275576Z","steps":["trace[174810104] 'read index received' (duration: 50.59427ms)","trace[174810104] 'applied index is now lower than readState.Index' (duration: 239.586829ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T10:42:33.27575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.332242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/openstack/\" range_end:\"/registry/resourcequotas/openstack0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T10:42:33.275871Z","caller":"traceutil/trace.go:171","msg":"trace[1402765500] range","detail":"{range_begin:/registry/resourcequotas/openstack/; range_end:/registry/resourcequotas/openstack0; response_count:0; response_revision:25295; }","duration":"290.488296ms","start":"2026-10-04T10:42:32.985355Z","end":"2026-10-04T10:42:33.275843Z","steps":["trace[1402765500] 'agreement among raft nodes before linearized reading' (duration: 290.300222ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:42:36.361273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.339176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs/\" range_end:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T10:42:36.361388Z","caller":"traceutil/trace.go:171","msg":"trace[1578340720] range","detail":"{range_begin:/registry/runtime.cluster.x-k8s.io/extensionconfigs/; range_end:/registry/runtime.cluster.x-k8s.io/extensionconfigs0; response_count:0; response_revision:25374; }","duration":"184.560951ms","start":"2026-10-04T10:42:36.176797Z","end":"2026-10-04T10:42:36.361358Z","steps":["trace[1578340720] 'count revisions from in-memory index tree' (duration: 184.237064ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:42:36.361377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.620945ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-04T10:42:36.361801Z","caller":"traceutil/trace.go:171","msg":"trace[1888178493] transaction","detail":"{read_only:false; response_revision:25375; number_of_response:1; }","duration":"167.896019ms","start":"2026-10-04T10:42:36.193847Z","end":"2026-10-04T10:42:36.361743Z","steps":["trace[1888178493] 'process raft request' (duration: 58.806103ms)","trace[1888178493] 'compare' (duration: 108.404051ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:42:40.227652Z","caller":"traceutil/trace.go:171","msg":"trace[603096231] transaction","detail":"{read_only:false; response_revision:25422; number_of_response:1; }","duration":"109.397232ms","start":"2026-10-04T10:42:40.118224Z","end":"2026-10-04T10:42:40.227621Z","steps":["trace[603096231] 'process raft request' (duration: 109.220958ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:42:40.42302Z","caller":"traceutil/trace.go:171","msg":"trace[1518167229] linearizableReadLoop","detail":"{readStateIndex:26262; appliedIndex:26261; }","duration":"231.909138ms","start":"2026-10-04T10:42:40.191088Z","end":"2026-10-04T10:42:40.422998Z","steps":["trace[1518167229] 'read index received' (duration: 36.979405ms)","trace[1518167229] 'applied index is now lower than readState.Index' (duration: 194.928663ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:42:40.423344Z","caller":"traceutil/trace.go:171","msg":"trace[906690678] transaction","detail":"{read_only:false; response_revision:25423; number_of_response:1; }","duration":"298.773171ms","start":"2026-10-04T10:42:40.124557Z","end":"2026-10-04T10:42:40.42333Z","steps":["trace[906690678] 'process raft request' (duration: 298.23866ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:42:40.423638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.545823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:7698"} {"level":"info","ts":"2026-10-04T10:42:40.42367Z","caller":"traceutil/trace.go:171","msg":"trace[865607114] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:25423; }","duration":"232.640085ms","start":"2026-10-04T10:42:40.19102Z","end":"2026-10-04T10:42:40.42366Z","steps":["trace[865607114] 'agreement among raft nodes before linearized reading' (duration: 232.497251ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:42:40.4239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.024301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-10-04T10:42:40.423996Z","caller":"traceutil/trace.go:171","msg":"trace[969418769] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25423; }","duration":"232.136693ms","start":"2026-10-04T10:42:40.191838Z","end":"2026-10-04T10:42:40.423975Z","steps":["trace[969418769] 'agreement among raft nodes before linearized reading' (duration: 231.864397ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:43:11.296987Z","caller":"traceutil/trace.go:171","msg":"trace[579661758] transaction","detail":"{read_only:false; response_revision:25674; number_of_response:1; }","duration":"108.915072ms","start":"2026-10-04T10:43:11.18804Z","end":"2026-10-04T10:43:11.296955Z","steps":["trace[579661758] 'process raft request' (duration: 108.234416ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:43:12.670029Z","caller":"traceutil/trace.go:171","msg":"trace[1484042077] transaction","detail":"{read_only:false; response_revision:25683; number_of_response:1; }","duration":"250.660617ms","start":"2026-10-04T10:43:12.419346Z","end":"2026-10-04T10:43:12.670006Z","steps":["trace[1484042077] 'process raft request' (duration: 250.498904ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:43:12.670032Z","caller":"traceutil/trace.go:171","msg":"trace[1796597975] linearizableReadLoop","detail":"{readStateIndex:26528; appliedIndex:26528; }","duration":"145.872167ms","start":"2026-10-04T10:43:12.524128Z","end":"2026-10-04T10:43:12.67Z","steps":["trace[1796597975] 'read index received' (duration: 145.859957ms)","trace[1796597975] 'applied index is now lower than readState.Index' (duration: 9.84µs)"],"step_count":2} {"level":"warn","ts":"2026-10-04T10:43:12.670485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.333307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/floatingips/\" range_end:\"/registry/openstack.k-orc.cloud/floatingips0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T10:43:12.670513Z","caller":"traceutil/trace.go:171","msg":"trace[348477399] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/floatingips/; range_end:/registry/openstack.k-orc.cloud/floatingips0; response_count:0; response_revision:25683; }","duration":"146.427599ms","start":"2026-10-04T10:43:12.524076Z","end":"2026-10-04T10:43:12.670503Z","steps":["trace[348477399] 'agreement among raft nodes before linearized reading' (duration: 146.356558ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:43:13.103444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.572437ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-04T10:43:13.103573Z","caller":"traceutil/trace.go:171","msg":"trace[757101815] linearizableReadLoop","detail":"{readStateIndex:26529; appliedIndex:26528; }","duration":"399.50392ms","start":"2026-10-04T10:43:12.704045Z","end":"2026-10-04T10:43:13.103549Z","steps":["trace[757101815] 'read index received' (duration: 246.732079ms)","trace[757101815] 'applied index is now lower than readState.Index' (duration: 152.770251ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:43:13.103628Z","caller":"traceutil/trace.go:171","msg":"trace[2105831874] transaction","detail":"{read_only:false; response_revision:25684; number_of_response:1; }","duration":"401.933204ms","start":"2026-10-04T10:43:12.701647Z","end":"2026-10-04T10:43:13.10358Z","steps":["trace[2105831874] 'process raft request' (duration: 249.114442ms)","trace[2105831874] 'compare' (duration: 151.924512ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T10:43:13.103731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.678384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-04T10:43:13.103788Z","caller":"traceutil/trace.go:171","msg":"trace[1675372211] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:25684; }","duration":"399.761906ms","start":"2026-10-04T10:43:12.704014Z","end":"2026-10-04T10:43:13.103776Z","steps":["trace[1675372211] 'agreement among raft nodes before linearized reading' (duration: 399.623223ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:43:13.103825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:43:12.704006Z","time spent":"399.808958ms","remote":"127.0.0.1:59104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":59,"response size":30,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-10-04T10:43:13.103878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:43:12.701623Z","time spent":"402.108279ms","remote":"127.0.0.1:58858","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-04T10:43:13.158528Z","caller":"traceutil/trace.go:171","msg":"trace[656922404] transaction","detail":"{read_only:false; response_revision:25685; number_of_response:1; }","duration":"454.330034ms","start":"2026-10-04T10:43:12.704176Z","end":"2026-10-04T10:43:13.158506Z","steps":["trace[656922404] 'process raft request' (duration: 454.238702ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:43:13.158637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:43:12.70417Z","time spent":"454.417416ms","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T10:43:13.158732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.127817ms","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-04T10:43:13.158814Z","caller":"traceutil/trace.go:171","msg":"trace[477947224] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:25685; }","duration":"367.26753ms","start":"2026-10-04T10:43:12.791532Z","end":"2026-10-04T10:43:13.158799Z","steps":["trace[477947224] 'agreement among raft nodes before linearized reading' (duration: 367.042415ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:43:13.158842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:43:12.791509Z","time spent":"367.326091ms","remote":"127.0.0.1:58754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":1804,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" "} {"level":"warn","ts":"2026-10-04T10:43:13.596497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.442522ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-04T10:43:13.596677Z","caller":"traceutil/trace.go:171","msg":"trace[958748876] linearizableReadLoop","detail":"{readStateIndex:26531; appliedIndex:26530; }","duration":"433.063709ms","start":"2026-10-04T10:43:13.163571Z","end":"2026-10-04T10:43:13.596635Z","steps":["trace[958748876] 'read index received' (duration: 135.330521ms)","trace[958748876] 'applied index is now lower than readState.Index' (duration: 297.730588ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:43:13.597057Z","caller":"traceutil/trace.go:171","msg":"trace[1097514215] transaction","detail":"{read_only:false; response_revision:25686; number_of_response:1; }","duration":"453.923785ms","start":"2026-10-04T10:43:13.143116Z","end":"2026-10-04T10:43:13.59704Z","steps":["trace[1097514215] 'process raft request' (duration: 155.776228ms)","trace[1097514215] 'compare' (duration: 297.072723ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T10:43:13.597407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:43:13.143097Z","time spent":"454.257502ms","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T10:43:13.597472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.858887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-a51729e4-1e27-47af-8b17-886a15df958a\" ","response":"range_response_count:1 size:1276"} {"level":"info","ts":"2026-10-04T10:43:13.597598Z","caller":"traceutil/trace.go:171","msg":"trace[302985534] range","detail":"{range_begin:/registry/persistentvolumes/pvc-a51729e4-1e27-47af-8b17-886a15df958a; range_end:; response_count:1; response_revision:25686; }","duration":"434.008501ms","start":"2026-10-04T10:43:13.163556Z","end":"2026-10-04T10:43:13.597565Z","steps":["trace[302985534] 'agreement among raft nodes before linearized reading' (duration: 433.671323ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:43:13.59765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:43:13.163548Z","time spent":"434.092332ms","remote":"127.0.0.1:58750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1298,"request content":"key:\"/registry/persistentvolumes/pvc-a51729e4-1e27-47af-8b17-886a15df958a\" "} {"level":"warn","ts":"2026-10-04T10:43:13.961208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.595056ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-04T10:43:13.966621Z","caller":"traceutil/trace.go:171","msg":"trace[339619004] linearizableReadLoop","detail":"{readStateIndex:26533; appliedIndex:26531; }","duration":"369.857859ms","start":"2026-10-04T10:43:13.596731Z","end":"2026-10-04T10:43:13.966589Z","steps":["trace[339619004] 'read index received' (duration: 15.038436ms)","trace[339619004] 'applied index is now lower than readState.Index' (duration: 354.817753ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T10:43:13.966891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"689.325231ms","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-04T10:43:13.966876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.528808ms","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-04T10:43:13.966941Z","caller":"traceutil/trace.go:171","msg":"trace[1405624041] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25687; }","duration":"689.401213ms","start":"2026-10-04T10:43:13.277528Z","end":"2026-10-04T10:43:13.966929Z","steps":["trace[1405624041] 'agreement among raft nodes before linearized reading' (duration: 689.174338ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:43:13.966976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:43:13.277441Z","time spent":"689.527326ms","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-04T10:43:13.96698Z","caller":"traceutil/trace.go:171","msg":"trace[926489007] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25687; }","duration":"445.667331ms","start":"2026-10-04T10:43:13.521284Z","end":"2026-10-04T10:43:13.966951Z","steps":["trace[926489007] 'agreement among raft nodes before linearized reading' (duration: 445.315953ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:43:13.967027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:43:13.521274Z","time spent":"445.743762ms","remote":"127.0.0.1:58858","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-04T10:43:13.967241Z","caller":"traceutil/trace.go:171","msg":"trace[599896012] transaction","detail":"{read_only:false; response_revision:25687; number_of_response:1; }","duration":"518.813934ms","start":"2026-10-04T10:43:13.448415Z","end":"2026-10-04T10:43:13.967229Z","steps":["trace[599896012] 'process raft request' (duration: 517.929353ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:43:13.967347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.353858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-10-04T10:43:13.967367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:43:13.448392Z","time spent":"518.881316ms","remote":"127.0.0.1:58858","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-04T10:43:13.967385Z","caller":"traceutil/trace.go:171","msg":"trace[911702729] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25687; }","duration":"451.46228ms","start":"2026-10-04T10:43:13.515913Z","end":"2026-10-04T10:43:13.967375Z","steps":["trace[911702729] 'agreement among raft nodes before linearized reading' (duration: 451.357048ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:43:13.967416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:43:13.515882Z","time spent":"451.527672ms","remote":"127.0.0.1:58858","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-04T10:43:13.967567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.251403ms","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-04T10:43:13.967604Z","caller":"traceutil/trace.go:171","msg":"trace[1439652556] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25687; }","duration":"643.285444ms","start":"2026-10-04T10:43:13.324308Z","end":"2026-10-04T10:43:13.967593Z","steps":["trace[1439652556] 'agreement among raft nodes before linearized reading' (duration: 643.188562ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:43:13.967632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:43:13.324252Z","time spent":"643.373945ms","remote":"127.0.0.1:58858","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-04T10:43:13.967867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.825311ms","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-04T10:43:13.967911Z","caller":"traceutil/trace.go:171","msg":"trace[1487085273] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25687; }","duration":"650.888992ms","start":"2026-10-04T10:43:13.317007Z","end":"2026-10-04T10:43:13.967896Z","steps":["trace[1487085273] 'agreement among raft nodes before linearized reading' (duration: 650.7952ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:43:13.967937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:43:13.316995Z","time spent":"650.934774ms","remote":"127.0.0.1:58858","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-04T10:43:14.254599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.467325ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-04T10:43:14.254869Z","caller":"traceutil/trace.go:171","msg":"trace[634749090] transaction","detail":"{read_only:false; response_revision:25691; number_of_response:1; }","duration":"350.861404ms","start":"2026-10-04T10:43:13.903988Z","end":"2026-10-04T10:43:14.254849Z","steps":["trace[634749090] 'process raft request' (duration: 350.817973ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:43:14.254923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:43:13.903957Z","time spent":"350.941265ms","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T10:43:14.255146Z","caller":"traceutil/trace.go:171","msg":"trace[1888051106] transaction","detail":"{read_only:false; response_revision:25688; number_of_response:1; }","duration":"591.572219ms","start":"2026-10-04T10:43:13.66356Z","end":"2026-10-04T10:43:14.255133Z","steps":["trace[1888051106] 'process raft request' (duration: 446.421048ms)","trace[1888051106] 'compare' (duration: 144.338492ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T10:43:14.255193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:43:13.663535Z","time spent":"591.633231ms","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T10:43:14.255213Z","caller":"traceutil/trace.go:171","msg":"trace[2136927234] transaction","detail":"{read_only:false; response_revision:25689; number_of_response:1; }","duration":"588.013659ms","start":"2026-10-04T10:43:13.66714Z","end":"2026-10-04T10:43:14.255154Z","steps":["trace[2136927234] 'process raft request' (duration: 587.569938ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:43:14.255431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:43:13.6671Z","time spent":"588.201293ms","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T10:43:14.255276Z","caller":"traceutil/trace.go:171","msg":"trace[1023463830] transaction","detail":"{read_only:false; response_revision:25690; number_of_response:1; }","duration":"585.397371ms","start":"2026-10-04T10:43:13.669869Z","end":"2026-10-04T10:43:14.255267Z","steps":["trace[1023463830] 'process raft request' (duration: 584.897039ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:43:14.256644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:43:13.669846Z","time spent":"586.72845ms","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T10:43:14.332076Z","caller":"traceutil/trace.go:171","msg":"trace[1948640122] transaction","detail":"{read_only:false; response_revision:25694; number_of_response:1; }","duration":"357.487452ms","start":"2026-10-04T10:43:13.974572Z","end":"2026-10-04T10:43:14.332059Z","steps":["trace[1948640122] 'process raft request' (duration: 357.415821ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:43:14.332095Z","caller":"traceutil/trace.go:171","msg":"trace[1096924778] transaction","detail":"{read_only:false; response_revision:25692; number_of_response:1; }","duration":"359.237391ms","start":"2026-10-04T10:43:13.972835Z","end":"2026-10-04T10:43:14.332073Z","steps":["trace[1096924778] 'process raft request' (duration: 359.031916ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:43:14.33214Z","caller":"traceutil/trace.go:171","msg":"trace[2062741646] transaction","detail":"{read_only:false; response_revision:25693; number_of_response:1; }","duration":"358.881973ms","start":"2026-10-04T10:43:13.973217Z","end":"2026-10-04T10:43:14.332099Z","steps":["trace[2062741646] 'process raft request' (duration: 358.7285ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:43:14.332195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:43:13.974543Z","time spent":"357.583774ms","remote":"127.0.0.1:58858","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-04T10:43:14.332183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:43:13.972808Z","time spent":"359.329443ms","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T10:43:14.332338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:43:13.973205Z","time spent":"359.024206ms","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T10:43:14.332577Z","caller":"traceutil/trace.go:171","msg":"trace[559430676] transaction","detail":"{read_only:false; response_revision:25695; number_of_response:1; }","duration":"357.968153ms","start":"2026-10-04T10:43:13.974598Z","end":"2026-10-04T10:43:14.332567Z","steps":["trace[559430676] 'process raft request' (duration: 357.423641ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:43:14.332629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:43:13.974576Z","time spent":"358.023044ms","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T10:43:14.332728Z","caller":"traceutil/trace.go:171","msg":"trace[571478709] transaction","detail":"{read_only:false; response_revision:25696; number_of_response:1; }","duration":"357.734517ms","start":"2026-10-04T10:43:13.974986Z","end":"2026-10-04T10:43:14.33272Z","steps":["trace[571478709] 'process raft request' (duration: 357.42212ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:43:14.332788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:43:13.974972Z","time spent":"357.791178ms","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T10:43:15.290998Z","caller":"traceutil/trace.go:171","msg":"trace[1855252611] transaction","detail":"{read_only:false; response_revision:25698; number_of_response:1; }","duration":"180.867858ms","start":"2026-10-04T10:43:15.110097Z","end":"2026-10-04T10:43:15.290965Z","steps":["trace[1855252611] 'process raft request' (duration: 180.667223ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:43:15.587254Z","caller":"traceutil/trace.go:171","msg":"trace[1646553075] linearizableReadLoop","detail":"{readStateIndex:26545; appliedIndex:26543; }","duration":"375.962274ms","start":"2026-10-04T10:43:15.211265Z","end":"2026-10-04T10:43:15.587227Z","steps":["trace[1646553075] 'read index received' (duration: 79.660078ms)","trace[1646553075] 'applied index is now lower than readState.Index' (duration: 296.300546ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T10:43:15.587406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.118638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T10:43:15.58745Z","caller":"traceutil/trace.go:171","msg":"trace[1911791985] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:25699; }","duration":"376.2268ms","start":"2026-10-04T10:43:15.211209Z","end":"2026-10-04T10:43:15.587436Z","steps":["trace[1911791985] 'agreement among raft nodes before linearized reading' (duration: 376.123378ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:43:15.587493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:43:15.211195Z","time spent":"376.287801ms","remote":"127.0.0.1:51572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true "} {"level":"info","ts":"2026-10-04T10:43:15.587481Z","caller":"traceutil/trace.go:171","msg":"trace[1449190586] transaction","detail":"{read_only:false; response_revision:25699; number_of_response:1; }","duration":"420.422618ms","start":"2026-10-04T10:43:15.167021Z","end":"2026-10-04T10:43:15.587444Z","steps":["trace[1449190586] 'process raft request' (duration: 380.66884ms)","trace[1449190586] 'compare' (duration: 39.076573ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T10:43:15.58768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:43:15.166988Z","time spent":"420.57173ms","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T10:43:16.079312Z","caller":"traceutil/trace.go:171","msg":"trace[691017295] transaction","detail":"{read_only:false; response_revision:25701; number_of_response:1; }","duration":"104.993244ms","start":"2026-10-04T10:43:15.974272Z","end":"2026-10-04T10:43:16.079266Z","steps":["trace[691017295] 'process raft request' (duration: 104.747299ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:43:16.4921Z","caller":"traceutil/trace.go:171","msg":"trace[344479314] transaction","detail":"{read_only:false; response_revision:25703; number_of_response:1; }","duration":"229.863522ms","start":"2026-10-04T10:43:16.262195Z","end":"2026-10-04T10:43:16.492059Z","steps":["trace[344479314] 'process raft request' (duration: 152.057134ms)","trace[344479314] 'compare' (duration: 77.287057ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:43:16.492252Z","caller":"traceutil/trace.go:171","msg":"trace[897214806] linearizableReadLoop","detail":"{readStateIndex:26550; appliedIndex:26548; }","duration":"178.009234ms","start":"2026-10-04T10:43:16.314201Z","end":"2026-10-04T10:43:16.49221Z","steps":["trace[897214806] 'read index received' (duration: 6.013414ms)","trace[897214806] 'applied index is now lower than readState.Index' (duration: 171.99332ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T10:43:16.492448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.205839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T10:43:16.49255Z","caller":"traceutil/trace.go:171","msg":"trace[1225347780] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:25704; }","duration":"178.367821ms","start":"2026-10-04T10:43:16.314158Z","end":"2026-10-04T10:43:16.492526Z","steps":["trace[1225347780] 'agreement among raft nodes before linearized reading' (duration: 178.178707ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:43:16.492625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.626405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-10-04T10:43:16.492608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.773435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-10-04T10:43:16.492643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.133924ms","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-04T10:43:16.492669Z","caller":"traceutil/trace.go:171","msg":"trace[1825307313] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25704; }","duration":"155.751627ms","start":"2026-10-04T10:43:16.336906Z","end":"2026-10-04T10:43:16.492657Z","steps":["trace[1825307313] 'agreement among raft nodes before linearized reading' (duration: 155.494742ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:43:16.492653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.366608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-04T10:43:16.492707Z","caller":"traceutil/trace.go:171","msg":"trace[1623005305] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25704; }","duration":"155.269087ms","start":"2026-10-04T10:43:16.337417Z","end":"2026-10-04T10:43:16.492686Z","steps":["trace[1623005305] 'agreement among raft nodes before linearized reading' (duration: 155.058172ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:43:16.492708Z","caller":"traceutil/trace.go:171","msg":"trace[1360454892] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25704; }","duration":"154.905969ms","start":"2026-10-04T10:43:16.337774Z","end":"2026-10-04T10:43:16.49268Z","steps":["trace[1360454892] 'agreement among raft nodes before linearized reading' (duration: 154.632333ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:43:16.492678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.631715ms","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-04T10:43:16.492801Z","caller":"traceutil/trace.go:171","msg":"trace[1940669446] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25704; }","duration":"155.785488ms","start":"2026-10-04T10:43:16.336994Z","end":"2026-10-04T10:43:16.49278Z","steps":["trace[1940669446] 'agreement among raft nodes before linearized reading' (duration: 155.473261ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:43:16.492733Z","caller":"traceutil/trace.go:171","msg":"trace[534934182] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25704; }","duration":"155.470591ms","start":"2026-10-04T10:43:16.337243Z","end":"2026-10-04T10:43:16.492713Z","steps":["trace[534934182] 'agreement among raft nodes before linearized reading' (duration: 155.188705ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:43:16.493134Z","caller":"traceutil/trace.go:171","msg":"trace[511179377] transaction","detail":"{read_only:false; response_revision:25704; number_of_response:1; }","duration":"228.976002ms","start":"2026-10-04T10:43:16.26411Z","end":"2026-10-04T10:43:16.493086Z","steps":["trace[511179377] 'process raft request' (duration: 227.843287ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:43:16.750144Z","caller":"traceutil/trace.go:171","msg":"trace[263228072] transaction","detail":"{read_only:false; response_revision:25706; number_of_response:1; }","duration":"251.338272ms","start":"2026-10-04T10:43:16.498777Z","end":"2026-10-04T10:43:16.750115Z","steps":["trace[263228072] 'process raft request' (duration: 251.090137ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:43:16.750495Z","caller":"traceutil/trace.go:171","msg":"trace[942451221] transaction","detail":"{read_only:false; response_revision:25707; number_of_response:1; }","duration":"251.342132ms","start":"2026-10-04T10:43:16.499084Z","end":"2026-10-04T10:43:16.750427Z","steps":["trace[942451221] 'process raft request' (duration: 250.980744ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:43:16.750638Z","caller":"traceutil/trace.go:171","msg":"trace[1015150136] transaction","detail":"{read_only:false; response_revision:25708; number_of_response:1; }","duration":"251.408913ms","start":"2026-10-04T10:43:16.499196Z","end":"2026-10-04T10:43:16.750605Z","steps":["trace[1015150136] 'process raft request' (duration: 251.109376ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:43:16.750864Z","caller":"traceutil/trace.go:171","msg":"trace[174195934] transaction","detail":"{read_only:false; response_revision:25709; number_of_response:1; }","duration":"249.084451ms","start":"2026-10-04T10:43:16.501762Z","end":"2026-10-04T10:43:16.750846Z","steps":["trace[174195934] 'process raft request' (duration: 248.774104ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:43:16.984956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.326219ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-04T10:43:16.985095Z","caller":"traceutil/trace.go:171","msg":"trace[358036930] transaction","detail":"{read_only:false; response_revision:25710; number_of_response:1; }","duration":"229.500604ms","start":"2026-10-04T10:43:16.755568Z","end":"2026-10-04T10:43:16.985069Z","steps":["trace[358036930] 'process raft request' (duration: 107.94849ms)","trace[358036930] 'compare' (duration: 121.139105ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:43:17.412281Z","caller":"traceutil/trace.go:171","msg":"trace[1423197524] transaction","detail":"{read_only:false; response_revision:25712; number_of_response:1; }","duration":"110.681222ms","start":"2026-10-04T10:43:17.301558Z","end":"2026-10-04T10:43:17.412239Z","steps":["trace[1423197524] 'process raft request' (duration: 39.523713ms)","trace[1423197524] 'compare' (duration: 70.974054ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T10:43:17.975525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.45707ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-04T10:43:17.975663Z","caller":"traceutil/trace.go:171","msg":"trace[1483581517] linearizableReadLoop","detail":"{readStateIndex:26560; appliedIndex:26559; }","duration":"255.879274ms","start":"2026-10-04T10:43:17.719761Z","end":"2026-10-04T10:43:17.97564Z","steps":["trace[1483581517] 'read index received' (duration: 62.139268ms)","trace[1483581517] 'applied index is now lower than readState.Index' (duration: 193.738176ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T10:43:17.975813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.052497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-04T10:43:17.975874Z","caller":"traceutil/trace.go:171","msg":"trace[211953428] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25714; }","duration":"256.11879ms","start":"2026-10-04T10:43:17.719731Z","end":"2026-10-04T10:43:17.97585Z","steps":["trace[211953428] 'agreement among raft nodes before linearized reading' (duration: 255.963366ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:43:17.975856Z","caller":"traceutil/trace.go:171","msg":"trace[1342483886] transaction","detail":"{read_only:false; response_revision:25714; number_of_response:1; }","duration":"312.510238ms","start":"2026-10-04T10:43:17.663306Z","end":"2026-10-04T10:43:17.975816Z","steps":["trace[1342483886] 'process raft request' (duration: 118.641929ms)","trace[1342483886] 'compare' (duration: 192.962558ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T10:43:17.976419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:43:17.663287Z","time spent":"313.069001ms","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T10:43:18.187023Z","caller":"traceutil/trace.go:171","msg":"trace[1837114808] linearizableReadLoop","detail":"{readStateIndex:26561; appliedIndex:26560; }","duration":"130.992705ms","start":"2026-10-04T10:43:18.055996Z","end":"2026-10-04T10:43:18.186989Z","steps":["trace[1837114808] 'read index received' (duration: 130.831592ms)","trace[1837114808] 'applied index is now lower than readState.Index' (duration: 159.473µs)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:43:18.187091Z","caller":"traceutil/trace.go:171","msg":"trace[2051729894] transaction","detail":"{read_only:false; response_revision:25715; number_of_response:1; }","duration":"176.796968ms","start":"2026-10-04T10:43:18.010251Z","end":"2026-10-04T10:43:18.187048Z","steps":["trace[2051729894] 'process raft request' (duration: 176.510982ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:43:18.187257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.21665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/users/\" range_end:\"/registry/openstack.k-orc.cloud/users0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T10:43:18.187304Z","caller":"traceutil/trace.go:171","msg":"trace[2125089288] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/users/; range_end:/registry/openstack.k-orc.cloud/users0; response_count:0; response_revision:25715; }","duration":"131.337282ms","start":"2026-10-04T10:43:18.055951Z","end":"2026-10-04T10:43:18.187289Z","steps":["trace[2125089288] 'agreement among raft nodes before linearized reading' (duration: 131.215929ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:43:18.24041Z","caller":"traceutil/trace.go:171","msg":"trace[1616829072] transaction","detail":"{read_only:false; response_revision:25716; number_of_response:1; }","duration":"154.274725ms","start":"2026-10-04T10:43:18.086099Z","end":"2026-10-04T10:43:18.240373Z","steps":["trace[1616829072] 'process raft request' (duration: 153.952587ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:43:18.240484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.377932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.215\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-10-04T10:43:18.240574Z","caller":"traceutil/trace.go:171","msg":"trace[316006760] range","detail":"{range_begin:/registry/masterleases/162.253.55.215; range_end:; response_count:1; response_revision:25716; }","duration":"135.549507ms","start":"2026-10-04T10:43:18.105003Z","end":"2026-10-04T10:43:18.240553Z","steps":["trace[316006760] 'agreement among raft nodes before linearized reading' (duration: 135.25163ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:43:18.42947Z","caller":"traceutil/trace.go:171","msg":"trace[542393309] transaction","detail":"{read_only:false; response_revision:25717; number_of_response:1; }","duration":"103.0022ms","start":"2026-10-04T10:43:18.326434Z","end":"2026-10-04T10:43:18.429437Z","steps":["trace[542393309] 'process raft request' (duration: 102.826207ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:43:18.605384Z","caller":"traceutil/trace.go:171","msg":"trace[1441220843] transaction","detail":"{read_only:false; response_revision:25719; number_of_response:1; }","duration":"103.883259ms","start":"2026-10-04T10:43:18.501464Z","end":"2026-10-04T10:43:18.605347Z","steps":["trace[1441220843] 'process raft request' (duration: 55.476739ms)","trace[1441220843] 'compare' (duration: 48.228777ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T10:43:18.813978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.699995ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-04T10:43:18.814074Z","caller":"traceutil/trace.go:171","msg":"trace[1251762475] linearizableReadLoop","detail":"{readStateIndex:26567; appliedIndex:26565; }","duration":"280.695127ms","start":"2026-10-04T10:43:18.533364Z","end":"2026-10-04T10:43:18.814059Z","steps":["trace[1251762475] 'read index received' (duration: 23.654148ms)","trace[1251762475] 'applied index is now lower than readState.Index' (duration: 257.039889ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T10:43:18.81415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.784829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T10:43:18.814172Z","caller":"traceutil/trace.go:171","msg":"trace[507137128] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:25720; }","duration":"280.85202ms","start":"2026-10-04T10:43:18.53331Z","end":"2026-10-04T10:43:18.814162Z","steps":["trace[507137128] 'agreement among raft nodes before linearized reading' (duration: 280.803339ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:43:18.814149Z","caller":"traceutil/trace.go:171","msg":"trace[179778159] transaction","detail":"{read_only:false; response_revision:25720; number_of_response:1; }","duration":"311.26023ms","start":"2026-10-04T10:43:18.502827Z","end":"2026-10-04T10:43:18.814088Z","steps":["trace[179778159] 'process raft request' (duration: 207.271738ms)","trace[179778159] 'compare' (duration: 103.585663ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T10:43:18.81434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:43:18.502792Z","time spent":"311.437684ms","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T10:43:18.828391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.645017ms","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-04T10:43:18.828455Z","caller":"traceutil/trace.go:171","msg":"trace[676007643] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25721; }","duration":"255.73963ms","start":"2026-10-04T10:43:18.572699Z","end":"2026-10-04T10:43:18.828438Z","steps":["trace[676007643] 'agreement among raft nodes before linearized reading' (duration: 255.515945ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:43:18.828405Z","caller":"traceutil/trace.go:171","msg":"trace[109935475] transaction","detail":"{read_only:false; response_revision:25721; number_of_response:1; }","duration":"110.109598ms","start":"2026-10-04T10:43:18.718279Z","end":"2026-10-04T10:43:18.828389Z","steps":["trace[109935475] 'process raft request' (duration: 109.75097ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:43:19.069138Z","caller":"traceutil/trace.go:171","msg":"trace[1145711958] transaction","detail":"{read_only:false; response_revision:25724; number_of_response:1; }","duration":"235.724563ms","start":"2026-10-04T10:43:18.833379Z","end":"2026-10-04T10:43:19.069103Z","steps":["trace[1145711958] 'process raft request' (duration: 235.637591ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:43:19.069195Z","caller":"traceutil/trace.go:171","msg":"trace[223438238] transaction","detail":"{read_only:false; response_revision:25723; number_of_response:1; }","duration":"235.887997ms","start":"2026-10-04T10:43:18.833253Z","end":"2026-10-04T10:43:19.069141Z","steps":["trace[223438238] 'process raft request' (duration: 235.57709ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:43:19.069565Z","caller":"traceutil/trace.go:171","msg":"trace[987066965] transaction","detail":"{read_only:false; response_revision:25725; number_of_response:1; }","duration":"236.0397ms","start":"2026-10-04T10:43:18.833496Z","end":"2026-10-04T10:43:19.069536Z","steps":["trace[987066965] 'process raft request' (duration: 235.56564ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:43:19.069916Z","caller":"traceutil/trace.go:171","msg":"trace[1357085218] transaction","detail":"{read_only:false; response_revision:25726; number_of_response:1; }","duration":"236.162202ms","start":"2026-10-04T10:43:18.833702Z","end":"2026-10-04T10:43:19.069864Z","steps":["trace[1357085218] 'process raft request' (duration: 235.744793ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:43:24.726173Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22812} {"level":"info","ts":"2026-10-04T10:43:24.88697Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22812,"took":"155.321809ms","hash":2070420796,"current-db-size-bytes":57155584,"current-db-size":"57 MB","current-db-size-in-use-bytes":37138432,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-10-04T10:43:24.887051Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2070420796,"revision":22812,"compact-revision":20099} {"level":"warn","ts":"2026-10-04T10:43:27.835039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.684603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T10:43:27.835125Z","caller":"traceutil/trace.go:171","msg":"trace[471770990] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:25786; }","duration":"192.795295ms","start":"2026-10-04T10:43:27.642311Z","end":"2026-10-04T10:43:27.835106Z","steps":["trace[471770990] 'count revisions from in-memory index tree' (duration: 192.468797ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:43:27.835308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.3869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T10:43:27.835437Z","caller":"traceutil/trace.go:171","msg":"trace[505377832] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25786; }","duration":"224.534713ms","start":"2026-10-04T10:43:27.610872Z","end":"2026-10-04T10:43:27.835407Z","steps":["trace[505377832] 'range keys from in-memory index tree' (duration: 224.271147ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:43:27.835615Z","caller":"traceutil/trace.go:171","msg":"trace[1757592362] transaction","detail":"{read_only:false; response_revision:25787; number_of_response:1; }","duration":"146.122963ms","start":"2026-10-04T10:43:27.689455Z","end":"2026-10-04T10:43:27.835578Z","steps":["trace[1757592362] 'process raft request' (duration: 136.898177ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:43:28.141664Z","caller":"traceutil/trace.go:171","msg":"trace[174126453] transaction","detail":"{read_only:false; response_revision:25788; number_of_response:1; }","duration":"126.683409ms","start":"2026-10-04T10:43:28.014953Z","end":"2026-10-04T10:43:28.141637Z","steps":["trace[174126453] 'process raft request' (duration: 126.484565ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:43:28.487616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.294231ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-04T10:43:28.488119Z","caller":"traceutil/trace.go:171","msg":"trace[639133176] transaction","detail":"{read_only:false; response_revision:25789; number_of_response:1; }","duration":"297.39213ms","start":"2026-10-04T10:43:28.190704Z","end":"2026-10-04T10:43:28.488096Z","steps":["trace[639133176] 'process raft request' (duration: 139.476835ms)","trace[639133176] 'compare' (duration: 157.143568ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:43:28.488364Z","caller":"traceutil/trace.go:171","msg":"trace[1692080776] transaction","detail":"{read_only:false; response_revision:25790; number_of_response:1; }","duration":"210.538421ms","start":"2026-10-04T10:43:28.277815Z","end":"2026-10-04T10:43:28.488354Z","steps":["trace[1692080776] 'process raft request' (duration: 209.937948ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:43:31.468279Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000682b.wal"} {"level":"info","ts":"2026-10-04T10:43:54.138913Z","caller":"traceutil/trace.go:171","msg":"trace[1494488302] transaction","detail":"{read_only:false; response_revision:26134; number_of_response:1; }","duration":"181.918773ms","start":"2026-10-04T10:43:53.956962Z","end":"2026-10-04T10:43:54.13888Z","steps":["trace[1494488302] 'process raft request' (duration: 181.711718ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:43:54.369099Z","caller":"traceutil/trace.go:171","msg":"trace[582185783] transaction","detail":"{read_only:false; response_revision:26135; number_of_response:1; }","duration":"120.173683ms","start":"2026-10-04T10:43:54.248888Z","end":"2026-10-04T10:43:54.369062Z","steps":["trace[582185783] 'process raft request' (duration: 119.975699ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:43:54.851712Z","caller":"traceutil/trace.go:171","msg":"trace[628405730] transaction","detail":"{read_only:false; response_revision:26137; number_of_response:1; }","duration":"186.708388ms","start":"2026-10-04T10:43:54.664922Z","end":"2026-10-04T10:43:54.851631Z","steps":["trace[628405730] 'process raft request' (duration: 115.823896ms)","trace[628405730] 'compare' (duration: 70.288229ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:43:54.867254Z","caller":"traceutil/trace.go:171","msg":"trace[2123330708] linearizableReadLoop","detail":"{readStateIndex:26995; appliedIndex:26993; }","duration":"102.216273ms","start":"2026-10-04T10:43:54.764994Z","end":"2026-10-04T10:43:54.86721Z","steps":["trace[2123330708] 'read index received' (duration: 15.768132ms)","trace[2123330708] 'applied index is now lower than readState.Index' (duration: 86.446871ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:43:54.867406Z","caller":"traceutil/trace.go:171","msg":"trace[1446830886] transaction","detail":"{read_only:false; response_revision:26138; number_of_response:1; }","duration":"121.358009ms","start":"2026-10-04T10:43:54.746018Z","end":"2026-10-04T10:43:54.867376Z","steps":["trace[1446830886] 'process raft request' (duration: 120.997321ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:43:54.867433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.404116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T10:43:54.867477Z","caller":"traceutil/trace.go:171","msg":"trace[922575198] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:26138; }","duration":"102.516739ms","start":"2026-10-04T10:43:54.764943Z","end":"2026-10-04T10:43:54.86746Z","steps":["trace[922575198] 'agreement among raft nodes before linearized reading' (duration: 102.405817ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:43:55.289847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.221979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusteridentities/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusteridentities0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T10:43:55.289957Z","caller":"traceutil/trace.go:171","msg":"trace[1176724094] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusteridentities/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusteridentities0; response_count:0; response_revision:26138; }","duration":"324.365202ms","start":"2026-10-04T10:43:54.965567Z","end":"2026-10-04T10:43:55.289932Z","steps":["trace[1176724094] 'count revisions from in-memory index tree' (duration: 324.133268ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:43:55.290003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:43:54.965518Z","time spent":"324.472964ms","remote":"127.0.0.1:33394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":144,"response count":0,"response size":28,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusteridentities/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusteridentities0\" count_only:true "} {"level":"info","ts":"2026-10-04T10:43:55.315713Z","caller":"traceutil/trace.go:171","msg":"trace[1779071686] linearizableReadLoop","detail":"{readStateIndex:26996; appliedIndex:26995; }","duration":"301.298177ms","start":"2026-10-04T10:43:55.014397Z","end":"2026-10-04T10:43:55.315696Z","steps":["trace[1779071686] 'read index received' (duration: 301.050222ms)","trace[1779071686] 'applied index is now lower than readState.Index' (duration: 246.965µs)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:43:55.31582Z","caller":"traceutil/trace.go:171","msg":"trace[1988432126] transaction","detail":"{read_only:false; response_revision:26139; number_of_response:1; }","duration":"346.107619ms","start":"2026-10-04T10:43:54.969664Z","end":"2026-10-04T10:43:55.315771Z","steps":["trace[1988432126] 'process raft request' (duration: 345.845563ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:43:55.315922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.436475ms","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-04T10:43:55.315963Z","caller":"traceutil/trace.go:171","msg":"trace[1622863159] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26139; }","duration":"234.539437ms","start":"2026-10-04T10:43:55.081405Z","end":"2026-10-04T10:43:55.315944Z","steps":["trace[1622863159] 'agreement among raft nodes before linearized reading' (duration: 234.399414ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:43:55.315944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.534273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-04T10:43:55.316043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:43:54.969639Z","time spent":"346.278872ms","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T10:43:55.316048Z","caller":"traceutil/trace.go:171","msg":"trace[857301289] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:26139; }","duration":"301.712947ms","start":"2026-10-04T10:43:55.014307Z","end":"2026-10-04T10:43:55.31602Z","steps":["trace[857301289] 'agreement among raft nodes before linearized reading' (duration: 301.471841ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:43:55.31606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.749041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-04T10:43:55.316105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:43:55.014276Z","time spent":"301.815589ms","remote":"127.0.0.1:33218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true "} {"level":"warn","ts":"2026-10-04T10:43:55.316156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.48442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-04T10:43:55.316172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.030958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T10:43:55.316185Z","caller":"traceutil/trace.go:171","msg":"trace[2110376590] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26139; }","duration":"150.542412ms","start":"2026-10-04T10:43:55.165633Z","end":"2026-10-04T10:43:55.316175Z","steps":["trace[2110376590] 'agreement among raft nodes before linearized reading' (duration: 150.503401ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:43:55.316202Z","caller":"traceutil/trace.go:171","msg":"trace[1519874249] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:26139; }","duration":"179.082668ms","start":"2026-10-04T10:43:55.13711Z","end":"2026-10-04T10:43:55.316193Z","steps":["trace[1519874249] 'agreement among raft nodes before linearized reading' (duration: 179.032347ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:43:55.316119Z","caller":"traceutil/trace.go:171","msg":"trace[3466560] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:0; response_revision:26139; }","duration":"178.831333ms","start":"2026-10-04T10:43:55.137272Z","end":"2026-10-04T10:43:55.316103Z","steps":["trace[3466560] 'agreement among raft nodes before linearized reading' (duration: 178.705801ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:43:55.316387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.543346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T10:43:55.316422Z","caller":"traceutil/trace.go:171","msg":"trace[865966658] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:26139; }","duration":"178.604718ms","start":"2026-10-04T10:43:55.137809Z","end":"2026-10-04T10:43:55.316413Z","steps":["trace[865966658] 'agreement among raft nodes before linearized reading' (duration: 178.551647ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:43:55.316509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.738403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T10:43:55.316571Z","caller":"traceutil/trace.go:171","msg":"trace[854261289] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:26139; }","duration":"179.852565ms","start":"2026-10-04T10:43:55.136702Z","end":"2026-10-04T10:43:55.316555Z","steps":["trace[854261289] 'agreement among raft nodes before linearized reading' (duration: 179.730853ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:46:48.538919Z","caller":"traceutil/trace.go:171","msg":"trace[1801799523] transaction","detail":"{read_only:false; response_revision:27809; number_of_response:1; }","duration":"137.552281ms","start":"2026-10-04T10:46:48.401332Z","end":"2026-10-04T10:46:48.538884Z","steps":["trace[1801799523] 'process raft request' (duration: 125.179275ms)","trace[1801799523] 'compare' (duration: 12.043349ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:46:48.545467Z","caller":"traceutil/trace.go:171","msg":"trace[1276049758] transaction","detail":"{read_only:false; response_revision:27811; number_of_response:1; }","duration":"142.772068ms","start":"2026-10-04T10:46:48.402646Z","end":"2026-10-04T10:46:48.545419Z","steps":["trace[1276049758] 'process raft request' (duration: 142.689586ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:46:48.545514Z","caller":"traceutil/trace.go:171","msg":"trace[778797349] transaction","detail":"{read_only:false; response_revision:27810; number_of_response:1; }","duration":"143.115526ms","start":"2026-10-04T10:46:48.40235Z","end":"2026-10-04T10:46:48.545466Z","steps":["trace[778797349] 'process raft request' (duration: 142.888131ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:46:48.546458Z","caller":"traceutil/trace.go:171","msg":"trace[580399032] transaction","detail":"{read_only:false; response_revision:27812; number_of_response:1; }","duration":"142.702756ms","start":"2026-10-04T10:46:48.403722Z","end":"2026-10-04T10:46:48.546425Z","steps":["trace[580399032] 'process raft request' (duration: 141.655032ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:46:48.546899Z","caller":"traceutil/trace.go:171","msg":"trace[1200152998] linearizableReadLoop","detail":"{readStateIndex:28711; appliedIndex:28707; }","duration":"138.81319ms","start":"2026-10-04T10:46:48.408068Z","end":"2026-10-04T10:46:48.546882Z","steps":["trace[1200152998] 'read index received' (duration: 118.571528ms)","trace[1200152998] 'applied index is now lower than readState.Index' (duration: 20.240372ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T10:46:48.547113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.008984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-04T10:46:48.547166Z","caller":"traceutil/trace.go:171","msg":"trace[2042130086] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:27813; }","duration":"139.117757ms","start":"2026-10-04T10:46:48.408032Z","end":"2026-10-04T10:46:48.547149Z","steps":["trace[2042130086] 'agreement among raft nodes before linearized reading' (duration: 138.979624ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:46:48.547416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.265959ms","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-04T10:46:48.547516Z","caller":"traceutil/trace.go:171","msg":"trace[1327908427] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:27813; }","duration":"109.515835ms","start":"2026-10-04T10:46:48.437966Z","end":"2026-10-04T10:46:48.547482Z","steps":["trace[1327908427] 'agreement among raft nodes before linearized reading' (duration: 109.176567ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:48:24.735124Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25764} {"level":"info","ts":"2026-10-04T10:48:24.903166Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25764,"took":"163.033642ms","hash":1732184615,"current-db-size-bytes":57155584,"current-db-size":"57 MB","current-db-size-in-use-bytes":35749888,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-04T10:48:24.903255Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1732184615,"revision":25764,"compact-revision":22812} {"level":"info","ts":"2026-10-04T10:49:21.818178Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4b019b7871d5da9a","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-04T10:49:21.827846Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-04T10:49:21.827992Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-04T10:53:24.743Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28645} {"level":"info","ts":"2026-10-04T10:53:24.899071Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28645,"took":"150.85655ms","hash":2276682623,"current-db-size-bytes":57155584,"current-db-size":"57 MB","current-db-size-in-use-bytes":30916608,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-04T10:53:24.899285Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2276682623,"revision":28645,"compact-revision":25764} {"level":"info","ts":"2026-10-04T10:58:24.75007Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30868} {"level":"info","ts":"2026-10-04T10:58:24.880124Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30868,"took":"124.593013ms","hash":2551658035,"current-db-size-bytes":57155584,"current-db-size":"57 MB","current-db-size-in-use-bytes":31236096,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-04T10:58:24.880208Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2551658035,"revision":30868,"compact-revision":28645} {"level":"info","ts":"2026-10-04T11:03:24.757768Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33124} {"level":"info","ts":"2026-10-04T11:03:24.934463Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33124,"took":"170.383976ms","hash":3944715075,"current-db-size-bytes":58195968,"current-db-size":"58 MB","current-db-size-in-use-bytes":30511104,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-04T11:03:24.934538Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3944715075,"revision":33124,"compact-revision":30868} {"level":"info","ts":"2026-10-04T11:04:18.066506Z","caller":"traceutil/trace.go:171","msg":"trace[993926647] transaction","detail":"{read_only:false; response_revision:35862; number_of_response:1; }","duration":"107.620843ms","start":"2026-10-04T11:04:17.95883Z","end":"2026-10-04T11:04:18.066451Z","steps":["trace[993926647] 'process raft request' (duration: 107.248365ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T11:08:24.765348Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35419} {"level":"info","ts":"2026-10-04T11:08:24.916247Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35419,"took":"144.456125ms","hash":4242317474,"current-db-size-bytes":58195968,"current-db-size":"58 MB","current-db-size-in-use-bytes":32530432,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-04T11:08:24.916326Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4242317474,"revision":35419,"compact-revision":33124} {"level":"info","ts":"2026-10-04T11:10:37.238756Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4b019b7871d5da9a","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-04T11:10:37.245854Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-04T11:10:37.246324Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-04T11:13:24.774229Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37743} {"level":"info","ts":"2026-10-04T11:13:24.919852Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37743,"took":"140.24432ms","hash":3823538255,"current-db-size-bytes":59043840,"current-db-size":"59 MB","current-db-size-in-use-bytes":32608256,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-04T11:13:24.919941Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3823538255,"revision":37743,"compact-revision":35419} {"level":"info","ts":"2026-10-04T11:18:24.782883Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":40015} {"level":"info","ts":"2026-10-04T11:18:24.93134Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":40015,"took":"144.558286ms","hash":2039684171,"current-db-size-bytes":59043840,"current-db-size":"59 MB","current-db-size-in-use-bytes":30703616,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-04T11:18:24.931431Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2039684171,"revision":40015,"compact-revision":37743}