{"level":"warn","ts":"2026-07-08T09:02:52.094831Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-08T09:02:52.094925Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.229:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.229:2380","--initial-cluster=instance=https://199.204.45.229:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.229:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.229: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-07-08T09:02:52.094993Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-08T09:02:52.095007Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.229:2380"]} {"level":"info","ts":"2026-07-08T09:02:52.095024Z","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-07-08T09:02:52.095665Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"]} {"level":"info","ts":"2026-07-08T09:02:52.095736Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.229:2380"],"listen-peer-urls":["https://199.204.45.229:2380"],"advertise-client-urls":["https://199.204.45.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.229: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-07-08T09:02:52.09957Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.604987ms"} {"level":"info","ts":"2026-07-08T09:02:52.105908Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3ba9ae53cc4335db","cluster-id":"4ad2674607ff1d60"} {"level":"info","ts":"2026-07-08T09:02:52.106011Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=()"} {"level":"info","ts":"2026-07-08T09:02:52.106038Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became follower at term 0"} {"level":"info","ts":"2026-07-08T09:02:52.106065Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3ba9ae53cc4335db [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-08T09:02:52.106093Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became follower at term 1"} {"level":"info","ts":"2026-07-08T09:02:52.106149Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=(4299158994234324443)"} {"level":"warn","ts":"2026-07-08T09:02:52.119973Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-08T09:02:52.123045Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-08T09:02:52.124793Z","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-07-08T09:02:52.131595Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3ba9ae53cc4335db","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-08T09:02:52.131976Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3ba9ae53cc4335db","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-08T09:02:52.132021Z","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-07-08T09:02:52.132179Z","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-07-08T09:02:52.132191Z","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-07-08T09:02:52.135339Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=(4299158994234324443)"} {"level":"info","ts":"2026-07-08T09:02:52.135525Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4ad2674607ff1d60","local-member-id":"3ba9ae53cc4335db","added-peer-id":"3ba9ae53cc4335db","added-peer-peer-urls":["https://199.204.45.229:2380"]} {"level":"info","ts":"2026-07-08T09:02:52.136644Z","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-07-08T09:02:52.136711Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.229:2380"} {"level":"info","ts":"2026-07-08T09:02:52.136735Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.229:2380"} {"level":"info","ts":"2026-07-08T09:02:52.136978Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3ba9ae53cc4335db","initial-advertise-peer-urls":["https://199.204.45.229:2380"],"listen-peer-urls":["https://199.204.45.229:2380"],"advertise-client-urls":["https://199.204.45.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-08T09:02:52.137003Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-08T09:02:52.507204Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db is starting a new election at term 1"} {"level":"info","ts":"2026-07-08T09:02:52.507331Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became pre-candidate at term 1"} {"level":"info","ts":"2026-07-08T09:02:52.507357Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db received MsgPreVoteResp from 3ba9ae53cc4335db at term 1"} {"level":"info","ts":"2026-07-08T09:02:52.507379Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became candidate at term 2"} {"level":"info","ts":"2026-07-08T09:02:52.507398Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db received MsgVoteResp from 3ba9ae53cc4335db at term 2"} {"level":"info","ts":"2026-07-08T09:02:52.507416Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became leader at term 2"} {"level":"info","ts":"2026-07-08T09:02:52.507436Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3ba9ae53cc4335db elected leader 3ba9ae53cc4335db at term 2"} {"level":"info","ts":"2026-07-08T09:02:52.508476Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3ba9ae53cc4335db","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.229:2379]}","request-path":"/0/members/3ba9ae53cc4335db/attributes","cluster-id":"4ad2674607ff1d60","publish-timeout":"7s"} {"level":"info","ts":"2026-07-08T09:02:52.508484Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-08T09:02:52.508539Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-08T09:02:52.508504Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T09:02:52.508686Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-08T09:02:52.50872Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-08T09:02:52.509602Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4ad2674607ff1d60","local-member-id":"3ba9ae53cc4335db","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T09:02:52.509731Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T09:02:52.509779Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T09:02:52.510722Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-08T09:02:52.51201Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.229:2379"} {"level":"info","ts":"2026-07-08T09:02:57.631237Z","caller":"traceutil/trace.go:171","msg":"trace[1687960193] transaction","detail":"{read_only:false; response_revision:238; number_of_response:1; }","duration":"162.251843ms","start":"2026-07-08T09:02:57.468957Z","end":"2026-07-08T09:02:57.631209Z","steps":["trace[1687960193] 'process raft request' (duration: 162.074178ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:03:05.574926Z","caller":"traceutil/trace.go:171","msg":"trace[1160654591] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"230.370644ms","start":"2026-07-08T09:03:05.344522Z","end":"2026-07-08T09:03:05.574893Z","steps":["trace[1160654591] 'process raft request' (duration: 230.117827ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:03:36.274398Z","caller":"traceutil/trace.go:171","msg":"trace[1471920952] linearizableReadLoop","detail":"{readStateIndex:620; appliedIndex:619; }","duration":"151.655051ms","start":"2026-07-08T09:03:36.122716Z","end":"2026-07-08T09:03:36.274371Z","steps":["trace[1471920952] 'read index received' (duration: 150.752693ms)","trace[1471920952] 'applied index is now lower than readState.Index' (duration: 901.518µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:03:36.274488Z","caller":"traceutil/trace.go:171","msg":"trace[1666296949] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"186.884622ms","start":"2026-07-08T09:03:36.087568Z","end":"2026-07-08T09:03:36.274452Z","steps":["trace[1666296949] 'process raft request' (duration: 186.161538ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:03:36.274674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.660667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-08T09:03:36.274756Z","caller":"traceutil/trace.go:171","msg":"trace[1587461858] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:595; }","duration":"146.805019ms","start":"2026-07-08T09:03:36.127938Z","end":"2026-07-08T09:03:36.274743Z","steps":["trace[1587461858] 'agreement among raft nodes before linearized reading' (duration: 146.573715ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:03:36.274729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.986057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-08T09:03:36.274818Z","caller":"traceutil/trace.go:171","msg":"trace[581805064] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:595; }","duration":"152.116269ms","start":"2026-07-08T09:03:36.122678Z","end":"2026-07-08T09:03:36.274795Z","steps":["trace[581805064] 'agreement among raft nodes before linearized reading' (duration: 151.832944ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:03:50.977432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.531605ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-08T09:03:50.977591Z","caller":"traceutil/trace.go:171","msg":"trace[301037011] linearizableReadLoop","detail":"{readStateIndex:790; appliedIndex:789; }","duration":"219.354528ms","start":"2026-07-08T09:03:50.758217Z","end":"2026-07-08T09:03:50.977572Z","steps":["trace[301037011] 'read index received' (duration: 64.574868ms)","trace[301037011] 'applied index is now lower than readState.Index' (duration: 154.77794ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T09:03:50.977842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.628413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T09:03:50.977883Z","caller":"traceutil/trace.go:171","msg":"trace[1227905821] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:761; }","duration":"219.715925ms","start":"2026-07-08T09:03:50.758156Z","end":"2026-07-08T09:03:50.977872Z","steps":["trace[1227905821] 'agreement among raft nodes before linearized reading' (duration: 219.4633ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:05:00.200392Z","caller":"traceutil/trace.go:171","msg":"trace[168035109] transaction","detail":"{read_only:false; response_revision:1796; number_of_response:1; }","duration":"172.159753ms","start":"2026-07-08T09:05:00.028203Z","end":"2026-07-08T09:05:00.200363Z","steps":["trace[168035109] 'process raft request' (duration: 171.987839ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:05:00.401149Z","caller":"traceutil/trace.go:171","msg":"trace[551078332] transaction","detail":"{read_only:false; response_revision:1797; number_of_response:1; }","duration":"110.099518ms","start":"2026-07-08T09:05:00.291018Z","end":"2026-07-08T09:05:00.401118Z","steps":["trace[551078332] 'process raft request' (duration: 109.923135ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:05:00.602845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.428098ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T09:05:00.602962Z","caller":"traceutil/trace.go:171","msg":"trace[1405942697] linearizableReadLoop","detail":"{readStateIndex:1868; appliedIndex:1866; }","duration":"270.546139ms","start":"2026-07-08T09:05:00.332387Z","end":"2026-07-08T09:05:00.602933Z","steps":["trace[1405942697] 'read index received' (duration: 68.568829ms)","trace[1405942697] 'applied index is now lower than readState.Index' (duration: 201.97593ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:05:00.602967Z","caller":"traceutil/trace.go:171","msg":"trace[1911053480] transaction","detail":"{read_only:false; response_revision:1798; number_of_response:1; }","duration":"274.206185ms","start":"2026-07-08T09:05:00.328737Z","end":"2026-07-08T09:05:00.602944Z","steps":["trace[1911053480] 'process raft request' (duration: 145.165245ms)","trace[1911053480] 'compare' (duration: 128.232704ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T09:05:00.60316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.750746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3f7c0166-0b54-40d1-a51c-c63adeddc3c6\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-07-08T09:05:00.603213Z","caller":"traceutil/trace.go:171","msg":"trace[1641255789] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3f7c0166-0b54-40d1-a51c-c63adeddc3c6; range_end:; response_count:1; response_revision:1798; }","duration":"173.849528ms","start":"2026-07-08T09:05:00.42935Z","end":"2026-07-08T09:05:00.6032Z","steps":["trace[1641255789] 'agreement among raft nodes before linearized reading' (duration: 173.688005ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:05:00.603194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.828125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-07-08T09:05:00.603275Z","caller":"traceutil/trace.go:171","msg":"trace[1454064939] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1798; }","duration":"270.967247ms","start":"2026-07-08T09:05:00.332286Z","end":"2026-07-08T09:05:00.603253Z","steps":["trace[1454064939] 'agreement among raft nodes before linearized reading' (duration: 270.740413ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:09:58.085574Z","caller":"traceutil/trace.go:171","msg":"trace[1286093547] transaction","detail":"{read_only:false; response_revision:3675; number_of_response:1; }","duration":"131.223997ms","start":"2026-07-08T09:09:57.954263Z","end":"2026-07-08T09:09:58.085487Z","steps":["trace[1286093547] 'process raft request' (duration: 131.026233ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:09:58.093057Z","caller":"traceutil/trace.go:171","msg":"trace[2122749991] transaction","detail":"{read_only:false; response_revision:3676; number_of_response:1; }","duration":"136.413919ms","start":"2026-07-08T09:09:57.9566Z","end":"2026-07-08T09:09:58.093014Z","steps":["trace[2122749991] 'process raft request' (duration: 136.208225ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:11:33.955234Z","caller":"traceutil/trace.go:171","msg":"trace[1239360116] transaction","detail":"{read_only:false; response_revision:5073; number_of_response:1; }","duration":"153.326963ms","start":"2026-07-08T09:11:33.801875Z","end":"2026-07-08T09:11:33.955202Z","steps":["trace[1239360116] 'process raft request' (duration: 114.542916ms)","trace[1239360116] 'compare' (duration: 38.502591ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:11:33.955251Z","caller":"traceutil/trace.go:171","msg":"trace[332085948] transaction","detail":"{read_only:false; response_revision:5074; number_of_response:1; }","duration":"144.442922ms","start":"2026-07-08T09:11:33.810776Z","end":"2026-07-08T09:11:33.955218Z","steps":["trace[332085948] 'process raft request' (duration: 144.3494ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:11:34.211798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.127275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-08T09:11:34.211911Z","caller":"traceutil/trace.go:171","msg":"trace[1367103224] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5078; }","duration":"144.282737ms","start":"2026-07-08T09:11:34.067607Z","end":"2026-07-08T09:11:34.211889Z","steps":["trace[1367103224] 'count revisions from in-memory index tree' (duration: 144.065104ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:11:34.212111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.644003ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T09:11:34.212366Z","caller":"traceutil/trace.go:171","msg":"trace[1524356757] transaction","detail":"{read_only:false; response_revision:5079; number_of_response:1; }","duration":"141.986434ms","start":"2026-07-08T09:11:34.070352Z","end":"2026-07-08T09:11:34.212338Z","steps":["trace[1524356757] 'compare' (duration: 134.289505ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:11:52.895978Z","caller":"traceutil/trace.go:171","msg":"trace[1815701027] transaction","detail":"{read_only:false; response_revision:5406; number_of_response:1; }","duration":"112.050237ms","start":"2026-07-08T09:11:52.783904Z","end":"2026-07-08T09:11:52.895954Z","steps":["trace[1815701027] 'process raft request' (duration: 111.862684ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:11:52.896155Z","caller":"traceutil/trace.go:171","msg":"trace[1297836263] transaction","detail":"{read_only:false; response_revision:5407; number_of_response:1; }","duration":"111.796913ms","start":"2026-07-08T09:11:52.784331Z","end":"2026-07-08T09:11:52.896128Z","steps":["trace[1297836263] 'process raft request' (duration: 111.580708ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:12:52.566968Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2826} {"level":"info","ts":"2026-07-08T09:12:52.635043Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2826,"took":"66.316713ms","hash":3627494873,"current-db-size-bytes":56946688,"current-db-size":"57 MB","current-db-size-in-use-bytes":33239040,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-07-08T09:12:52.635111Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3627494873,"revision":2826,"compact-revision":-1} {"level":"info","ts":"2026-07-08T09:17:38.822992Z","caller":"traceutil/trace.go:171","msg":"trace[922679121] transaction","detail":"{read_only:false; response_revision:8030; number_of_response:1; }","duration":"376.34651ms","start":"2026-07-08T09:17:38.446619Z","end":"2026-07-08T09:17:38.822966Z","steps":["trace[922679121] 'process raft request' (duration: 376.179517ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:17:38.823153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:17:38.446594Z","time spent":"376.482904ms","remote":"127.0.0.1:34196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T09:17:38.824866Z","caller":"traceutil/trace.go:171","msg":"trace[1976071245] linearizableReadLoop","detail":"{readStateIndex:8313; appliedIndex:8311; }","duration":"203.828664ms","start":"2026-07-08T09:17:38.621011Z","end":"2026-07-08T09:17:38.824839Z","steps":["trace[1976071245] 'read index received' (duration: 201.831685ms)","trace[1976071245] 'applied index is now lower than readState.Index' (duration: 1.995339ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T09:17:38.825103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.086249ms","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-07-08T09:17:38.825168Z","caller":"traceutil/trace.go:171","msg":"trace[1669361261] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8031; }","duration":"204.17556ms","start":"2026-07-08T09:17:38.620968Z","end":"2026-07-08T09:17:38.825144Z","steps":["trace[1669361261] 'agreement among raft nodes before linearized reading' (duration: 204.028727ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:17:38.825189Z","caller":"traceutil/trace.go:171","msg":"trace[2004164600] transaction","detail":"{read_only:false; response_revision:8031; number_of_response:1; }","duration":"257.376136ms","start":"2026-07-08T09:17:38.567791Z","end":"2026-07-08T09:17:38.825167Z","steps":["trace[2004164600] 'process raft request' (duration: 256.944437ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:17:38.825238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.55402ms","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-07-08T09:17:38.825286Z","caller":"traceutil/trace.go:171","msg":"trace[408155099] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8031; }","duration":"200.635101ms","start":"2026-07-08T09:17:38.624638Z","end":"2026-07-08T09:17:38.825273Z","steps":["trace[408155099] 'agreement among raft nodes before linearized reading' (duration: 200.479098ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:17:38.825351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.233305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-07-08T09:17:38.825379Z","caller":"traceutil/trace.go:171","msg":"trace[1136350147] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8031; }","duration":"198.276346ms","start":"2026-07-08T09:17:38.627093Z","end":"2026-07-08T09:17:38.82537Z","steps":["trace[1136350147] 'agreement among raft nodes before linearized reading' (duration: 198.199974ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:17:38.825387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.171333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-08T09:17:38.825444Z","caller":"traceutil/trace.go:171","msg":"trace[1245451322] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8031; }","duration":"199.249535ms","start":"2026-07-08T09:17:38.62618Z","end":"2026-07-08T09:17:38.82543Z","steps":["trace[1245451322] 'agreement among raft nodes before linearized reading' (duration: 199.137953ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:17:51.132495Z","caller":"traceutil/trace.go:171","msg":"trace[1840071758] linearizableReadLoop","detail":"{readStateIndex:8381; appliedIndex:8380; }","duration":"245.156449ms","start":"2026-07-08T09:17:50.887316Z","end":"2026-07-08T09:17:51.132472Z","steps":["trace[1840071758] 'read index received' (duration: 245.044987ms)","trace[1840071758] 'applied index is now lower than readState.Index' (duration: 109.392µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:17:51.132589Z","caller":"traceutil/trace.go:171","msg":"trace[1098419909] transaction","detail":"{read_only:false; response_revision:8095; number_of_response:1; }","duration":"263.909495ms","start":"2026-07-08T09:17:50.868625Z","end":"2026-07-08T09:17:51.132535Z","steps":["trace[1098419909] 'process raft request' (duration: 263.679271ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:17:51.132795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.460835ms","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-07-08T09:17:51.132918Z","caller":"traceutil/trace.go:171","msg":"trace[430575095] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8095; }","duration":"245.572278ms","start":"2026-07-08T09:17:50.887283Z","end":"2026-07-08T09:17:51.132855Z","steps":["trace[430575095] 'agreement among raft nodes before linearized reading' (duration: 245.299892ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:17:51.194736Z","caller":"traceutil/trace.go:171","msg":"trace[224344506] transaction","detail":"{read_only:false; response_revision:8096; number_of_response:1; }","duration":"298.761655ms","start":"2026-07-08T09:17:50.895914Z","end":"2026-07-08T09:17:51.194675Z","steps":["trace[224344506] 'process raft request' (duration: 288.134378ms)","trace[224344506] 'compare' (duration: 10.159777ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T09:17:51.194874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.417335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-07-08T09:17:51.194881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.834235ms","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-07-08T09:17:51.194961Z","caller":"traceutil/trace.go:171","msg":"trace[187175915] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8096; }","duration":"304.515317ms","start":"2026-07-08T09:17:50.890425Z","end":"2026-07-08T09:17:51.19494Z","steps":["trace[187175915] 'agreement among raft nodes before linearized reading' (duration: 304.289023ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:17:51.194987Z","caller":"traceutil/trace.go:171","msg":"trace[307205851] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8096; }","duration":"300.998119ms","start":"2026-07-08T09:17:50.893966Z","end":"2026-07-08T09:17:51.194964Z","steps":["trace[307205851] 'agreement among raft nodes before linearized reading' (duration: 300.707813ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:17:51.194987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.502016ms","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-07-08T09:17:51.195017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:17:50.890419Z","time spent":"304.587138ms","remote":"127.0.0.1:34196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-08T09:17:51.195044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:17:50.893953Z","time spent":"301.08094ms","remote":"127.0.0.1:34196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-08T09:17:51.195047Z","caller":"traceutil/trace.go:171","msg":"trace[1864599839] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8096; }","duration":"305.655389ms","start":"2026-07-08T09:17:50.889379Z","end":"2026-07-08T09:17:51.195034Z","steps":["trace[1864599839] 'agreement among raft nodes before linearized reading' (duration: 305.461295ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:17:51.195166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:17:50.889369Z","time spent":"305.759901ms","remote":"127.0.0.1:34196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-07-08T09:17:51.194933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.918958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-07-08T09:17:51.195352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.587523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-07-08T09:17:51.195357Z","caller":"traceutil/trace.go:171","msg":"trace[230252407] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8096; }","duration":"301.357556ms","start":"2026-07-08T09:17:50.893973Z","end":"2026-07-08T09:17:51.19533Z","steps":["trace[230252407] 'agreement among raft nodes before linearized reading' (duration: 300.822295ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:17:51.195422Z","caller":"traceutil/trace.go:171","msg":"trace[1317822313] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8096; }","duration":"295.653194ms","start":"2026-07-08T09:17:50.899752Z","end":"2026-07-08T09:17:51.195405Z","steps":["trace[1317822313] 'agreement among raft nodes before linearized reading' (duration: 295.207845ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:17:51.195538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.870529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-07-08T09:17:51.195565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.129957ms","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-07-08T09:17:51.195663Z","caller":"traceutil/trace.go:171","msg":"trace[1094548700] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8096; }","duration":"155.035112ms","start":"2026-07-08T09:17:51.040594Z","end":"2026-07-08T09:17:51.195629Z","steps":["trace[1094548700] 'agreement among raft nodes before linearized reading' (duration: 154.357159ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:17:51.195696Z","caller":"traceutil/trace.go:171","msg":"trace[128485024] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8096; }","duration":"308.297911ms","start":"2026-07-08T09:17:50.887356Z","end":"2026-07-08T09:17:51.195654Z","steps":["trace[128485024] 'agreement among raft nodes before linearized reading' (duration: 307.292011ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:17:51.195749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:17:50.887326Z","time spent":"308.411462ms","remote":"127.0.0.1:34196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-08T09:17:51.195584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:17:50.893961Z","time spent":"301.60379ms","remote":"127.0.0.1:34196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-08T09:17:52.573759Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5916} {"level":"info","ts":"2026-07-08T09:17:52.654128Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5916,"took":"78.429419ms","hash":1352899522,"current-db-size-bytes":56946688,"current-db-size":"57 MB","current-db-size-in-use-bytes":26431488,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-07-08T09:17:52.65419Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1352899522,"revision":5916,"compact-revision":2826} {"level":"info","ts":"2026-07-08T09:18:33.322743Z","caller":"traceutil/trace.go:171","msg":"trace[1793465829] transaction","detail":"{read_only:false; response_revision:8416; number_of_response:1; }","duration":"240.175292ms","start":"2026-07-08T09:18:33.082537Z","end":"2026-07-08T09:18:33.322712Z","steps":["trace[1793465829] 'process raft request' (duration: 239.921238ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:18:33.397906Z","caller":"traceutil/trace.go:171","msg":"trace[1427406320] transaction","detail":"{read_only:false; response_revision:8417; number_of_response:1; }","duration":"109.148217ms","start":"2026-07-08T09:18:33.288714Z","end":"2026-07-08T09:18:33.397863Z","steps":["trace[1427406320] 'process raft request' (duration: 108.954944ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:19:24.984967Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002440.wal"} {"level":"info","ts":"2026-07-08T09:20:51.916801Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3ba9ae53cc4335db","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-08T09:20:51.923823Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-08T09:20:51.923933Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-08T09:22:52.580425Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8104} {"level":"info","ts":"2026-07-08T09:22:52.644521Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8104,"took":"61.463143ms","hash":1389051984,"current-db-size-bytes":56946688,"current-db-size":"57 MB","current-db-size-in-use-bytes":30556160,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-08T09:22:52.644582Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1389051984,"revision":8104,"compact-revision":5916} {"level":"info","ts":"2026-07-08T09:23:15.018139Z","caller":"traceutil/trace.go:171","msg":"trace[2112731499] linearizableReadLoop","detail":"{readStateIndex:11325; appliedIndex:11324; }","duration":"153.86414ms","start":"2026-07-08T09:23:14.864247Z","end":"2026-07-08T09:23:15.018111Z","steps":["trace[2112731499] 'read index received' (duration: 153.642206ms)","trace[2112731499] 'applied index is now lower than readState.Index' (duration: 220.814µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:23:15.018299Z","caller":"traceutil/trace.go:171","msg":"trace[944759234] transaction","detail":"{read_only:false; response_revision:10952; number_of_response:1; }","duration":"155.614923ms","start":"2026-07-08T09:23:14.862622Z","end":"2026-07-08T09:23:15.018237Z","steps":["trace[944759234] 'process raft request' (duration: 155.339378ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:23:15.018393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.131385ms","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-07-08T09:23:15.018434Z","caller":"traceutil/trace.go:171","msg":"trace[173244985] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:10952; }","duration":"154.215567ms","start":"2026-07-08T09:23:14.864205Z","end":"2026-07-08T09:23:15.01842Z","steps":["trace[173244985] 'agreement among raft nodes before linearized reading' (duration: 154.065904ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:23:15.20836Z","caller":"traceutil/trace.go:171","msg":"trace[1151022841] transaction","detail":"{read_only:false; response_revision:10956; number_of_response:1; }","duration":"185.174093ms","start":"2026-07-08T09:23:15.02316Z","end":"2026-07-08T09:23:15.208334Z","steps":["trace[1151022841] 'process raft request' (duration: 185.109301ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:23:15.208361Z","caller":"traceutil/trace.go:171","msg":"trace[285901571] transaction","detail":"{read_only:false; response_revision:10954; number_of_response:1; }","duration":"186.04675ms","start":"2026-07-08T09:23:15.022278Z","end":"2026-07-08T09:23:15.208325Z","steps":["trace[285901571] 'process raft request' (duration: 185.798694ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:23:15.208432Z","caller":"traceutil/trace.go:171","msg":"trace[1862480207] transaction","detail":"{read_only:false; response_revision:10955; number_of_response:1; }","duration":"185.957278ms","start":"2026-07-08T09:23:15.02243Z","end":"2026-07-08T09:23:15.208387Z","steps":["trace[1862480207] 'process raft request' (duration: 185.783234ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:23:15.208655Z","caller":"traceutil/trace.go:171","msg":"trace[1923121614] transaction","detail":"{read_only:false; response_revision:10957; number_of_response:1; }","duration":"185.183391ms","start":"2026-07-08T09:23:15.02345Z","end":"2026-07-08T09:23:15.208634Z","steps":["trace[1923121614] 'process raft request' (duration: 184.852694ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:23:15.208767Z","caller":"traceutil/trace.go:171","msg":"trace[906622328] linearizableReadLoop","detail":"{readStateIndex:11331; appliedIndex:11326; }","duration":"110.901974ms","start":"2026-07-08T09:23:15.097812Z","end":"2026-07-08T09:23:15.208714Z","steps":["trace[906622328] 'read index received' (duration: 110.21413ms)","trace[906622328] 'applied index is now lower than readState.Index' (duration: 686.874µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:23:15.208824Z","caller":"traceutil/trace.go:171","msg":"trace[679916998] transaction","detail":"{read_only:false; response_revision:10958; number_of_response:1; }","duration":"185.150502ms","start":"2026-07-08T09:23:15.023659Z","end":"2026-07-08T09:23:15.208809Z","steps":["trace[679916998] 'process raft request' (duration: 184.916517ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:23:15.208936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.089777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T09:23:15.208997Z","caller":"traceutil/trace.go:171","msg":"trace[612962708] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:10958; }","duration":"111.22136ms","start":"2026-07-08T09:23:15.097761Z","end":"2026-07-08T09:23:15.208983Z","steps":["trace[612962708] 'agreement among raft nodes before linearized reading' (duration: 111.092448ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:27:52.591066Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10806} {"level":"info","ts":"2026-07-08T09:27:52.678467Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10806,"took":"85.228805ms","hash":2585404169,"current-db-size-bytes":56946688,"current-db-size":"57 MB","current-db-size-in-use-bytes":27365376,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-07-08T09:27:52.678533Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2585404169,"revision":10806,"compact-revision":8104} {"level":"warn","ts":"2026-07-08T09:29:35.407105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.817ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T09:29:35.407231Z","caller":"traceutil/trace.go:171","msg":"trace[1779778051] linearizableReadLoop","detail":"{readStateIndex:13981; appliedIndex:13980; }","duration":"259.418961ms","start":"2026-07-08T09:29:35.147794Z","end":"2026-07-08T09:29:35.407213Z","steps":["trace[1779778051] 'read index received' (duration: 63.287975ms)","trace[1779778051] 'applied index is now lower than readState.Index' (duration: 196.129416ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:29:35.407263Z","caller":"traceutil/trace.go:171","msg":"trace[1760509113] transaction","detail":"{read_only:false; response_revision:13518; number_of_response:1; }","duration":"261.512992ms","start":"2026-07-08T09:29:35.145711Z","end":"2026-07-08T09:29:35.407224Z","steps":["trace[1760509113] 'process raft request' (duration: 65.485058ms)","trace[1760509113] 'compare' (duration: 195.497484ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T09:29:35.407438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.27901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-07-08T09:29:35.407432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.625116ms","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-07-08T09:29:35.407483Z","caller":"traceutil/trace.go:171","msg":"trace[906093171] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:13518; }","duration":"257.336751ms","start":"2026-07-08T09:29:35.150132Z","end":"2026-07-08T09:29:35.407469Z","steps":["trace[906093171] 'agreement among raft nodes before linearized reading' (duration: 257.175288ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:29:35.407502Z","caller":"traceutil/trace.go:171","msg":"trace[905078319] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:13518; }","duration":"259.723917ms","start":"2026-07-08T09:29:35.147758Z","end":"2026-07-08T09:29:35.407482Z","steps":["trace[905078319] 'agreement among raft nodes before linearized reading' (duration: 259.530264ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:29:35.566381Z","caller":"traceutil/trace.go:171","msg":"trace[621194710] linearizableReadLoop","detail":"{readStateIndex:13982; appliedIndex:13981; }","duration":"153.823234ms","start":"2026-07-08T09:29:35.412526Z","end":"2026-07-08T09:29:35.566349Z","steps":["trace[621194710] 'read index received' (duration: 133.663µs)","trace[621194710] 'applied index is now lower than readState.Index' (duration: 153.688321ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T09:29:35.567013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.467476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:62 size:578119"} {"level":"info","ts":"2026-07-08T09:29:35.567082Z","caller":"traceutil/trace.go:171","msg":"trace[2091491150] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:62; response_revision:13519; }","duration":"154.549898ms","start":"2026-07-08T09:29:35.412517Z","end":"2026-07-08T09:29:35.567067Z","steps":["trace[2091491150] 'agreement among raft nodes before linearized reading' (duration: 153.944606ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:29:35.56723Z","caller":"traceutil/trace.go:171","msg":"trace[807716987] transaction","detail":"{read_only:false; response_revision:13519; number_of_response:1; }","duration":"153.940436ms","start":"2026-07-08T09:29:35.412496Z","end":"2026-07-08T09:29:35.566437Z","steps":["trace[807716987] 'process raft request' (duration: 153.389455ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:29:35.576114Z","caller":"traceutil/trace.go:171","msg":"trace[576664879] transaction","detail":"{read_only:false; response_revision:13520; number_of_response:1; }","duration":"163.299436ms","start":"2026-07-08T09:29:35.412778Z","end":"2026-07-08T09:29:35.576077Z","steps":["trace[576664879] 'process raft request' (duration: 162.280556ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:29:35.576231Z","caller":"traceutil/trace.go:171","msg":"trace[1085659940] transaction","detail":"{read_only:false; response_revision:13521; number_of_response:1; }","duration":"154.978215ms","start":"2026-07-08T09:29:35.421198Z","end":"2026-07-08T09:29:35.576176Z","steps":["trace[1085659940] 'process raft request' (duration: 154.760221ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:29:43.807484Z","caller":"traceutil/trace.go:171","msg":"trace[289359907] linearizableReadLoop","detail":"{readStateIndex:14144; appliedIndex:14143; }","duration":"139.701431ms","start":"2026-07-08T09:29:43.667754Z","end":"2026-07-08T09:29:43.807456Z","steps":["trace[289359907] 'read index received' (duration: 130.821851ms)","trace[289359907] 'applied index is now lower than readState.Index' (duration: 8.87818ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:29:43.807772Z","caller":"traceutil/trace.go:171","msg":"trace[1628066951] transaction","detail":"{read_only:false; response_revision:13677; number_of_response:1; }","duration":"140.411036ms","start":"2026-07-08T09:29:43.667347Z","end":"2026-07-08T09:29:43.807758Z","steps":["trace[1628066951] 'process raft request' (duration: 131.194299ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:29:43.808251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.484999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:66 size:612521"} {"level":"info","ts":"2026-07-08T09:29:43.808328Z","caller":"traceutil/trace.go:171","msg":"trace[344294932] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:66; response_revision:13677; }","duration":"140.59448ms","start":"2026-07-08T09:29:43.667716Z","end":"2026-07-08T09:29:43.80831Z","steps":["trace[344294932] 'agreement among raft nodes before linearized reading' (duration: 140.009558ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:29:43.830075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.347395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c5e078c5-214d-435c-9661-95dae917ebe7\" ","response":"range_response_count:1 size:3607"} {"level":"info","ts":"2026-07-08T09:29:43.830165Z","caller":"traceutil/trace.go:171","msg":"trace[1478817242] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c5e078c5-214d-435c-9661-95dae917ebe7; range_end:; response_count:1; response_revision:13679; }","duration":"115.480317ms","start":"2026-07-08T09:29:43.714665Z","end":"2026-07-08T09:29:43.830146Z","steps":["trace[1478817242] 'agreement among raft nodes before linearized reading' (duration: 115.243852ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:29:43.830725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.914696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4a5d5287-ded5-40e5-be27-37523186440f\" ","response":"range_response_count:1 size:3607"} {"level":"info","ts":"2026-07-08T09:29:43.830801Z","caller":"traceutil/trace.go:171","msg":"trace[1304096877] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4a5d5287-ded5-40e5-be27-37523186440f; range_end:; response_count:1; response_revision:13679; }","duration":"116.042119ms","start":"2026-07-08T09:29:43.714742Z","end":"2026-07-08T09:29:43.830784Z","steps":["trace[1304096877] 'agreement among raft nodes before linearized reading' (duration: 115.695512ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:31:16.246411Z","caller":"traceutil/trace.go:171","msg":"trace[943484379] transaction","detail":"{read_only:false; response_revision:14724; number_of_response:1; }","duration":"100.848356ms","start":"2026-07-08T09:31:16.145532Z","end":"2026-07-08T09:31:16.24638Z","steps":["trace[943484379] 'process raft request' (duration: 100.667673ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:31:16.393544Z","caller":"traceutil/trace.go:171","msg":"trace[2131298082] linearizableReadLoop","detail":"{readStateIndex:15225; appliedIndex:15223; }","duration":"232.741639ms","start":"2026-07-08T09:31:16.160769Z","end":"2026-07-08T09:31:16.39351Z","steps":["trace[2131298082] 'read index received' (duration: 85.41078ms)","trace[2131298082] 'applied index is now lower than readState.Index' (duration: 147.329219ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:31:16.393563Z","caller":"traceutil/trace.go:171","msg":"trace[662793313] transaction","detail":"{read_only:false; response_revision:14725; number_of_response:1; }","duration":"244.837922ms","start":"2026-07-08T09:31:16.148688Z","end":"2026-07-08T09:31:16.393526Z","steps":["trace[662793313] 'process raft request' (duration: 224.122333ms)","trace[662793313] 'compare' (duration: 20.506635ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T09:31:16.393786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.998324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-07-08T09:31:16.393784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.247347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T09:31:16.393852Z","caller":"traceutil/trace.go:171","msg":"trace[1968944065] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:14725; }","duration":"233.077696ms","start":"2026-07-08T09:31:16.160754Z","end":"2026-07-08T09:31:16.393832Z","steps":["trace[1968944065] 'agreement among raft nodes before linearized reading' (duration: 232.856271ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:31:16.393882Z","caller":"traceutil/trace.go:171","msg":"trace[881699615] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:14725; }","duration":"173.38497ms","start":"2026-07-08T09:31:16.220474Z","end":"2026-07-08T09:31:16.393859Z","steps":["trace[881699615] 'agreement among raft nodes before linearized reading' (duration: 173.241307ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:31:16.393858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.008286ms","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-07-08T09:31:16.393959Z","caller":"traceutil/trace.go:171","msg":"trace[870181310] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14725; }","duration":"169.128948ms","start":"2026-07-08T09:31:16.224813Z","end":"2026-07-08T09:31:16.393942Z","steps":["trace[870181310] 'agreement among raft nodes before linearized reading' (duration: 168.955544ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:31:16.3941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.27994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T09:31:16.394159Z","caller":"traceutil/trace.go:171","msg":"trace[770827668] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:14725; }","duration":"169.355591ms","start":"2026-07-08T09:31:16.224786Z","end":"2026-07-08T09:31:16.394142Z","steps":["trace[770827668] 'agreement among raft nodes before linearized reading' (duration: 169.25678ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:31:16.394212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.34765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-08T09:31:16.39421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.402645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-frdv5\" ","response":"range_response_count:1 size:20653"} {"level":"info","ts":"2026-07-08T09:31:16.394253Z","caller":"traceutil/trace.go:171","msg":"trace[54864076] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14725; }","duration":"171.407761ms","start":"2026-07-08T09:31:16.222835Z","end":"2026-07-08T09:31:16.394243Z","steps":["trace[54864076] 'agreement among raft nodes before linearized reading' (duration: 171.255888ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:31:16.39427Z","caller":"traceutil/trace.go:171","msg":"trace[869691459] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-frdv5; range_end:; response_count:1; response_revision:14725; }","duration":"153.490778ms","start":"2026-07-08T09:31:16.240765Z","end":"2026-07-08T09:31:16.394255Z","steps":["trace[869691459] 'agreement among raft nodes before linearized reading' (duration: 153.296734ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:31:16.394594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.616115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-07-08T09:31:16.394633Z","caller":"traceutil/trace.go:171","msg":"trace[1488287543] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14725; }","duration":"159.734967ms","start":"2026-07-08T09:31:16.234888Z","end":"2026-07-08T09:31:16.394623Z","steps":["trace[1488287543] 'agreement among raft nodes before linearized reading' (duration: 159.231148ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:32:52.597115Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12854} {"level":"info","ts":"2026-07-08T09:32:52.667921Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12854,"took":"68.351983ms","hash":1222705672,"current-db-size-bytes":56946688,"current-db-size":"57 MB","current-db-size-in-use-bytes":30908416,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-08T09:32:52.667988Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1222705672,"revision":12854,"compact-revision":10806} {"level":"info","ts":"2026-07-08T09:33:59.371152Z","caller":"traceutil/trace.go:171","msg":"trace[1870317823] linearizableReadLoop","detail":"{readStateIndex:16565; appliedIndex:16564; }","duration":"110.392939ms","start":"2026-07-08T09:33:59.260736Z","end":"2026-07-08T09:33:59.371129Z","steps":["trace[1870317823] 'read index received' (duration: 78.446326ms)","trace[1870317823] 'applied index is now lower than readState.Index' (duration: 31.945773ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:33:59.371221Z","caller":"traceutil/trace.go:171","msg":"trace[514628595] transaction","detail":"{read_only:false; response_revision:16022; number_of_response:1; }","duration":"133.792669ms","start":"2026-07-08T09:33:59.237398Z","end":"2026-07-08T09:33:59.37119Z","steps":["trace[514628595] 'process raft request' (duration: 101.898466ms)","trace[514628595] 'compare' (duration: 31.630178ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T09:33:59.37138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.028669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-qgzd2\" ","response":"range_response_count:1 size:22125"} {"level":"warn","ts":"2026-07-08T09:33:59.371366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.590393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T09:33:59.371404Z","caller":"traceutil/trace.go:171","msg":"trace[377232225] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-qgzd2; range_end:; response_count:1; response_revision:16022; }","duration":"101.09306ms","start":"2026-07-08T09:33:59.270304Z","end":"2026-07-08T09:33:59.371397Z","steps":["trace[377232225] 'agreement among raft nodes before linearized reading' (duration: 100.966588ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:33:59.37143Z","caller":"traceutil/trace.go:171","msg":"trace[979868898] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:16022; }","duration":"110.712856ms","start":"2026-07-08T09:33:59.2607Z","end":"2026-07-08T09:33:59.371412Z","steps":["trace[979868898] 'agreement among raft nodes before linearized reading' (duration: 110.562343ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:33:59.371632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.242873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-qgzd2\" ","response":"range_response_count:1 size:22125"} {"level":"info","ts":"2026-07-08T09:33:59.371733Z","caller":"traceutil/trace.go:171","msg":"trace[1157896957] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-qgzd2; range_end:; response_count:1; response_revision:16022; }","duration":"101.401566ms","start":"2026-07-08T09:33:59.270307Z","end":"2026-07-08T09:33:59.371708Z","steps":["trace[1157896957] 'agreement among raft nodes before linearized reading' (duration: 101.049ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:37:01.694379Z","caller":"traceutil/trace.go:171","msg":"trace[1434113606] transaction","detail":"{read_only:false; response_revision:17489; number_of_response:1; }","duration":"131.506903ms","start":"2026-07-08T09:37:01.562839Z","end":"2026-07-08T09:37:01.694346Z","steps":["trace[1434113606] 'process raft request' (duration: 130.987944ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:37:07.233898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.176435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-08T09:37:07.233979Z","caller":"traceutil/trace.go:171","msg":"trace[394805154] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:17525; }","duration":"135.452189ms","start":"2026-07-08T09:37:07.098501Z","end":"2026-07-08T09:37:07.233953Z","steps":["trace[394805154] 'count revisions from in-memory index tree' (duration: 135.069302ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:37:07.234224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.818021ms","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-07-08T09:37:07.234289Z","caller":"traceutil/trace.go:171","msg":"trace[917432619] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17525; }","duration":"329.932502ms","start":"2026-07-08T09:37:06.904343Z","end":"2026-07-08T09:37:07.234276Z","steps":["trace[917432619] 'range keys from in-memory index tree' (duration: 329.685598ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:37:07.234348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:37:06.904324Z","time spent":"330.015795ms","remote":"127.0.0.1:34196","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-07-08T09:37:07.234459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.130175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/senlin-db-sync\" ","response":"range_response_count:1 size:5082"} {"level":"warn","ts":"2026-07-08T09:37:07.234524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.296137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/senlin-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T09:37:07.234594Z","caller":"traceutil/trace.go:171","msg":"trace[1395890984] range","detail":"{range_begin:/registry/jobs/openstack/senlin-db-sync; range_end:; response_count:1; response_revision:17525; }","duration":"282.310289ms","start":"2026-07-08T09:37:06.952269Z","end":"2026-07-08T09:37:07.234579Z","steps":["trace[1395890984] 'range keys from in-memory index tree' (duration: 281.706187ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:37:07.234579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.858539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/senlin-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T09:37:07.234633Z","caller":"traceutil/trace.go:171","msg":"trace[725220270] range","detail":"{range_begin:/registry/jobs/openstack/senlin-ks-endpoints; range_end:; response_count:0; response_revision:17525; }","duration":"282.937081ms","start":"2026-07-08T09:37:06.951684Z","end":"2026-07-08T09:37:07.234621Z","steps":["trace[725220270] 'range keys from in-memory index tree' (duration: 282.784997ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:37:07.234634Z","caller":"traceutil/trace.go:171","msg":"trace[2132534456] range","detail":"{range_begin:/registry/jobs/openstack/senlin-rabbit-init; range_end:; response_count:0; response_revision:17525; }","duration":"283.472781ms","start":"2026-07-08T09:37:06.951141Z","end":"2026-07-08T09:37:07.234613Z","steps":["trace[2132534456] 'range keys from in-memory index tree' (duration: 283.123334ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:37:07.234797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.020273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/senlin-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T09:37:07.234863Z","caller":"traceutil/trace.go:171","msg":"trace[709559604] range","detail":"{range_begin:/registry/jobs/openstack/senlin-ks-user; range_end:; response_count:0; response_revision:17525; }","duration":"283.743405ms","start":"2026-07-08T09:37:06.951108Z","end":"2026-07-08T09:37:07.234852Z","steps":["trace[709559604] 'range keys from in-memory index tree' (duration: 282.92704ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:37:16.067783Z","caller":"traceutil/trace.go:171","msg":"trace[611319441] transaction","detail":"{read_only:false; response_revision:17600; number_of_response:1; }","duration":"102.518147ms","start":"2026-07-08T09:37:15.965245Z","end":"2026-07-08T09:37:16.067763Z","steps":["trace[611319441] 'process raft request' (duration: 101.957406ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:37:52.609395Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15356} {"level":"info","ts":"2026-07-08T09:37:52.708117Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15356,"took":"93.519376ms","hash":1074107137,"current-db-size-bytes":56946688,"current-db-size":"57 MB","current-db-size-in-use-bytes":32595968,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-07-08T09:37:52.708197Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1074107137,"revision":15356,"compact-revision":12854} {"level":"info","ts":"2026-07-08T09:40:28.719572Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3ba9ae53cc4335db","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-08T09:40:28.723902Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-08T09:40:28.724004Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-07-08T09:42:52.616795Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17914} {"level":"info","ts":"2026-07-08T09:42:52.732876Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17914,"took":"112.296374ms","hash":2169263173,"current-db-size-bytes":56946688,"current-db-size":"57 MB","current-db-size-in-use-bytes":31662080,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-08T09:42:52.732931Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2169263173,"revision":17914,"compact-revision":15356} {"level":"info","ts":"2026-07-08T09:43:46.766499Z","caller":"traceutil/trace.go:171","msg":"trace[1399858510] transaction","detail":"{read_only:false; response_revision:20713; number_of_response:1; }","duration":"279.080245ms","start":"2026-07-08T09:43:46.487397Z","end":"2026-07-08T09:43:46.766477Z","steps":["trace[1399858510] 'process raft request' (duration: 278.952282ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:43:47.026343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.06029ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-08T09:43:47.026456Z","caller":"traceutil/trace.go:171","msg":"trace[325010604] transaction","detail":"{read_only:false; response_revision:20714; number_of_response:1; }","duration":"147.996518ms","start":"2026-07-08T09:43:46.878437Z","end":"2026-07-08T09:43:47.026434Z","steps":["trace[325010604] 'process raft request' (duration: 23.740755ms)","trace[325010604] 'compare' (duration: 123.649152ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T09:43:47.345274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.477345ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-08T09:43:47.345375Z","caller":"traceutil/trace.go:171","msg":"trace[1571577395] linearizableReadLoop","detail":"{readStateIndex:21410; appliedIndex:21409; }","duration":"207.016872ms","start":"2026-07-08T09:43:47.138344Z","end":"2026-07-08T09:43:47.345361Z","steps":["trace[1571577395] 'read index received' (duration: 41.297713ms)","trace[1571577395] 'applied index is now lower than readState.Index' (duration: 165.718109ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:43:47.345541Z","caller":"traceutil/trace.go:171","msg":"trace[1153388311] transaction","detail":"{read_only:false; response_revision:20715; number_of_response:1; }","duration":"223.014229ms","start":"2026-07-08T09:43:47.122491Z","end":"2026-07-08T09:43:47.345506Z","steps":["trace[1153388311] 'process raft request' (duration: 57.224208ms)","trace[1153388311] 'compare' (duration: 165.150449ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T09:43:47.34564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.291917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-07-08T09:43:47.345674Z","caller":"traceutil/trace.go:171","msg":"trace[1965078038] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:20715; }","duration":"207.353669ms","start":"2026-07-08T09:43:47.138311Z","end":"2026-07-08T09:43:47.345664Z","steps":["trace[1965078038] 'agreement among raft nodes before linearized reading' (duration: 207.226646ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:43:47.34586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.665763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T09:43:47.345912Z","caller":"traceutil/trace.go:171","msg":"trace[1175654054] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:20715; }","duration":"184.745675ms","start":"2026-07-08T09:43:47.161151Z","end":"2026-07-08T09:43:47.345897Z","steps":["trace[1175654054] 'agreement among raft nodes before linearized reading' (duration: 184.439978ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:43:47.346907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.01488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-08T09:43:47.347214Z","caller":"traceutil/trace.go:171","msg":"trace[986215948] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20715; }","duration":"111.181754ms","start":"2026-07-08T09:43:47.23583Z","end":"2026-07-08T09:43:47.347012Z","steps":["trace[986215948] 'agreement among raft nodes before linearized reading' (duration: 109.94638ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:43:47.604372Z","caller":"traceutil/trace.go:171","msg":"trace[1352218296] transaction","detail":"{read_only:false; response_revision:20717; number_of_response:1; }","duration":"254.249439ms","start":"2026-07-08T09:43:47.350082Z","end":"2026-07-08T09:43:47.604332Z","steps":["trace[1352218296] 'process raft request' (duration: 171.959339ms)","trace[1352218296] 'compare' (duration: 81.957153ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:43:47.604368Z","caller":"traceutil/trace.go:171","msg":"trace[173401937] linearizableReadLoop","detail":"{readStateIndex:21412; appliedIndex:21411; }","duration":"254.197288ms","start":"2026-07-08T09:43:47.350146Z","end":"2026-07-08T09:43:47.604343Z","steps":["trace[173401937] 'read index received' (duration: 71.209916ms)","trace[173401937] 'applied index is now lower than readState.Index' (duration: 182.985642ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:43:47.604539Z","caller":"traceutil/trace.go:171","msg":"trace[373647824] transaction","detail":"{read_only:false; response_revision:20718; number_of_response:1; }","duration":"253.79162ms","start":"2026-07-08T09:43:47.350714Z","end":"2026-07-08T09:43:47.604505Z","steps":["trace[373647824] 'process raft request' (duration: 253.554145ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:43:47.604528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.352691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18422"} {"level":"warn","ts":"2026-07-08T09:43:47.604832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.957642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:573"} {"level":"info","ts":"2026-07-08T09:43:47.604887Z","caller":"traceutil/trace.go:171","msg":"trace[37012853] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:20718; }","duration":"182.030663ms","start":"2026-07-08T09:43:47.42284Z","end":"2026-07-08T09:43:47.604871Z","steps":["trace[37012853] 'agreement among raft nodes before linearized reading' (duration: 181.86119ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:43:47.604662Z","caller":"traceutil/trace.go:171","msg":"trace[317182215] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:20718; }","duration":"254.511013ms","start":"2026-07-08T09:43:47.350128Z","end":"2026-07-08T09:43:47.604639Z","steps":["trace[317182215] 'agreement among raft nodes before linearized reading' (duration: 254.31698ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:43:47.802369Z","caller":"traceutil/trace.go:171","msg":"trace[575947886] linearizableReadLoop","detail":"{readStateIndex:21414; appliedIndex:21413; }","duration":"138.018828ms","start":"2026-07-08T09:43:47.664313Z","end":"2026-07-08T09:43:47.802332Z","steps":["trace[575947886] 'read index received' (duration: 80.871922ms)","trace[575947886] 'applied index is now lower than readState.Index' (duration: 57.145736ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:43:47.80245Z","caller":"traceutil/trace.go:171","msg":"trace[1083228445] transaction","detail":"{read_only:false; response_revision:20719; number_of_response:1; }","duration":"192.418602ms","start":"2026-07-08T09:43:47.610003Z","end":"2026-07-08T09:43:47.802422Z","steps":["trace[1083228445] 'process raft request' (duration: 135.175173ms)","trace[1083228445] 'compare' (duration: 57.000164ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T09:43:47.802588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.240473ms","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-07-08T09:43:47.802671Z","caller":"traceutil/trace.go:171","msg":"trace[298689397] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:20719; }","duration":"138.389026ms","start":"2026-07-08T09:43:47.664263Z","end":"2026-07-08T09:43:47.802652Z","steps":["trace[298689397] 'agreement among raft nodes before linearized reading' (duration: 138.192411ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:43:48.004951Z","caller":"traceutil/trace.go:171","msg":"trace[126746038] linearizableReadLoop","detail":"{readStateIndex:21415; appliedIndex:21414; }","duration":"195.488482ms","start":"2026-07-08T09:43:47.809438Z","end":"2026-07-08T09:43:48.004927Z","steps":["trace[126746038] 'read index received' (duration: 97.169425ms)","trace[126746038] 'applied index is now lower than readState.Index' (duration: 98.318167ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:43:48.005045Z","caller":"traceutil/trace.go:171","msg":"trace[317868607] transaction","detail":"{read_only:false; response_revision:20720; number_of_response:1; }","duration":"197.932628ms","start":"2026-07-08T09:43:47.807105Z","end":"2026-07-08T09:43:48.005037Z","steps":["trace[317868607] 'process raft request' (duration: 99.594031ms)","trace[317868607] 'compare' (duration: 98.007501ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T09:43:48.005168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.485103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T09:43:48.005242Z","caller":"traceutil/trace.go:171","msg":"trace[647542757] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:20720; }","duration":"194.599094ms","start":"2026-07-08T09:43:47.810624Z","end":"2026-07-08T09:43:48.005223Z","steps":["trace[647542757] 'agreement among raft nodes before linearized reading' (duration: 194.487262ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:43:48.005328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.873449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-07-08T09:43:48.005354Z","caller":"traceutil/trace.go:171","msg":"trace[1661104306] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:20720; }","duration":"195.92072ms","start":"2026-07-08T09:43:47.809426Z","end":"2026-07-08T09:43:48.005347Z","steps":["trace[1661104306] 'agreement among raft nodes before linearized reading' (duration: 195.796797ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:47:28.490143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.640282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-07-08T09:47:28.490259Z","caller":"traceutil/trace.go:171","msg":"trace[2037092559] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0; range_end:; response_count:1; response_revision:23116; }","duration":"185.814475ms","start":"2026-07-08T09:47:28.304422Z","end":"2026-07-08T09:47:28.490236Z","steps":["trace[2037092559] 'range keys from in-memory index tree' (duration: 185.433378ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:47:52.623025Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20429} {"level":"info","ts":"2026-07-08T09:47:52.732155Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20429,"took":"105.107978ms","hash":2143202197,"current-db-size-bytes":58527744,"current-db-size":"58 MB","current-db-size-in-use-bytes":43143168,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-07-08T09:47:52.732234Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2143202197,"revision":20429,"compact-revision":17914} {"level":"info","ts":"2026-07-08T09:50:49.338506Z","caller":"traceutil/trace.go:171","msg":"trace[1865883707] transaction","detail":"{read_only:false; response_revision:25214; number_of_response:1; }","duration":"299.039355ms","start":"2026-07-08T09:50:49.039446Z","end":"2026-07-08T09:50:49.338486Z","steps":["trace[1865883707] 'process raft request' (duration: 298.486374ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:50:49.832786Z","caller":"traceutil/trace.go:171","msg":"trace[214568225] transaction","detail":"{read_only:false; response_revision:25217; number_of_response:1; }","duration":"148.814914ms","start":"2026-07-08T09:50:49.683944Z","end":"2026-07-08T09:50:49.832759Z","steps":["trace[214568225] 'process raft request' (duration: 148.757513ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:50:49.832859Z","caller":"traceutil/trace.go:171","msg":"trace[2034402944] transaction","detail":"{read_only:false; response_revision:25216; number_of_response:1; }","duration":"150.631879ms","start":"2026-07-08T09:50:49.682198Z","end":"2026-07-08T09:50:49.832829Z","steps":["trace[2034402944] 'process raft request' (duration: 95.182442ms)","trace[2034402944] 'compare' (duration: 55.09598ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T09:50:54.791312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.728851ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-08T09:50:54.791594Z","caller":"traceutil/trace.go:171","msg":"trace[1298361062] transaction","detail":"{read_only:false; response_revision:25260; number_of_response:1; }","duration":"145.892288ms","start":"2026-07-08T09:50:54.645687Z","end":"2026-07-08T09:50:54.791579Z","steps":["trace[1298361062] 'process raft request' (duration: 23.83777ms)","trace[1298361062] 'compare' (duration: 121.575299ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:50:55.837085Z","caller":"traceutil/trace.go:171","msg":"trace[1370367868] linearizableReadLoop","detail":"{readStateIndex:26082; appliedIndex:26081; }","duration":"152.308571ms","start":"2026-07-08T09:50:55.684748Z","end":"2026-07-08T09:50:55.837056Z","steps":["trace[1370367868] 'read index received' (duration: 151.75687ms)","trace[1370367868] 'applied index is now lower than readState.Index' (duration: 550.551µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:50:55.837181Z","caller":"traceutil/trace.go:171","msg":"trace[2008047769] transaction","detail":"{read_only:false; response_revision:25264; number_of_response:1; }","duration":"153.229519ms","start":"2026-07-08T09:50:55.683915Z","end":"2026-07-08T09:50:55.837144Z","steps":["trace[2008047769] 'process raft request' (duration: 152.699568ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:50:55.83734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.584756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"} {"level":"info","ts":"2026-07-08T09:50:55.837384Z","caller":"traceutil/trace.go:171","msg":"trace[582297406] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:25264; }","duration":"152.668688ms","start":"2026-07-08T09:50:55.684705Z","end":"2026-07-08T09:50:55.837373Z","steps":["trace[582297406] 'agreement among raft nodes before linearized reading' (duration: 152.489565ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:50:56.141693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.3743ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-08T09:50:56.141875Z","caller":"traceutil/trace.go:171","msg":"trace[1335658509] transaction","detail":"{read_only:false; response_revision:25266; number_of_response:1; }","duration":"278.201213ms","start":"2026-07-08T09:50:55.863648Z","end":"2026-07-08T09:50:56.141849Z","steps":["trace[1335658509] 'process raft request' (duration: 278.138421ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:50:56.141918Z","caller":"traceutil/trace.go:171","msg":"trace[418795912] transaction","detail":"{read_only:false; response_revision:25265; number_of_response:1; }","duration":"281.399424ms","start":"2026-07-08T09:50:55.860479Z","end":"2026-07-08T09:50:56.141879Z","steps":["trace[418795912] 'process raft request' (duration: 139.749339ms)","trace[418795912] 'compare' (duration: 141.234917ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:50:56.151513Z","caller":"traceutil/trace.go:171","msg":"trace[689828821] linearizableReadLoop","detail":"{readStateIndex:26085; appliedIndex:26082; }","duration":"114.726598ms","start":"2026-07-08T09:50:56.036767Z","end":"2026-07-08T09:50:56.151494Z","steps":["trace[689828821] 'read index received' (duration: 102.313198ms)","trace[689828821] 'applied index is now lower than readState.Index' (duration: 12.41242ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:50:56.151726Z","caller":"traceutil/trace.go:171","msg":"trace[1818360405] transaction","detail":"{read_only:false; response_revision:25267; number_of_response:1; }","duration":"128.458552ms","start":"2026-07-08T09:50:56.023256Z","end":"2026-07-08T09:50:56.151714Z","steps":["trace[1818360405] 'process raft request' (duration: 128.163716ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:50:56.151879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.082864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-08T09:50:56.151959Z","caller":"traceutil/trace.go:171","msg":"trace[525819073] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:25267; }","duration":"115.209167ms","start":"2026-07-08T09:50:56.036728Z","end":"2026-07-08T09:50:56.151937Z","steps":["trace[525819073] 'agreement among raft nodes before linearized reading' (duration: 115.015413ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:50:56.317066Z","caller":"traceutil/trace.go:171","msg":"trace[445568986] transaction","detail":"{read_only:false; response_revision:25268; number_of_response:1; }","duration":"161.239313ms","start":"2026-07-08T09:50:56.155806Z","end":"2026-07-08T09:50:56.317045Z","steps":["trace[445568986] 'process raft request' (duration: 132.588142ms)","trace[445568986] 'compare' (duration: 28.542539ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T09:50:56.65078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.497597ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-08T09:50:56.651022Z","caller":"traceutil/trace.go:171","msg":"trace[18087068] linearizableReadLoop","detail":"{readStateIndex:26087; appliedIndex:26085; }","duration":"442.950604ms","start":"2026-07-08T09:50:56.207997Z","end":"2026-07-08T09:50:56.650948Z","steps":["trace[18087068] 'read index received' (duration: 80.415058ms)","trace[18087068] 'applied index is now lower than readState.Index' (duration: 362.534636ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:50:56.651092Z","caller":"traceutil/trace.go:171","msg":"trace[1632094255] transaction","detail":"{read_only:false; response_revision:25270; number_of_response:1; }","duration":"370.123822ms","start":"2026-07-08T09:50:56.280958Z","end":"2026-07-08T09:50:56.651082Z","steps":["trace[1632094255] 'process raft request' (duration: 369.930058ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:50:56.651187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:50:56.280934Z","time spent":"370.181254ms","remote":"127.0.0.1:34196","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-07-08T09:50:56.651219Z","caller":"traceutil/trace.go:171","msg":"trace[1624138290] transaction","detail":"{read_only:false; response_revision:25269; number_of_response:1; }","duration":"458.664276ms","start":"2026-07-08T09:50:56.192518Z","end":"2026-07-08T09:50:56.651182Z","steps":["trace[1624138290] 'process raft request' (duration: 268.69077ms)","trace[1624138290] 'compare' (duration: 189.208592ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T09:50:56.651389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:50:56.19249Z","time spent":"458.807239ms","remote":"127.0.0.1:34196","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-07-08T09:50:56.651576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.563215ms","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-07-08T09:50:56.651656Z","caller":"traceutil/trace.go:171","msg":"trace[2116610898] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25270; }","duration":"443.682958ms","start":"2026-07-08T09:50:56.207958Z","end":"2026-07-08T09:50:56.651641Z","steps":["trace[2116610898] 'agreement among raft nodes before linearized reading' (duration: 443.426213ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:50:56.651685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:50:56.207943Z","time spent":"443.736048ms","remote":"127.0.0.1:34196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-08T09:50:56.662383Z","caller":"traceutil/trace.go:171","msg":"trace[1901914102] transaction","detail":"{read_only:false; response_revision:25271; number_of_response:1; }","duration":"150.270011ms","start":"2026-07-08T09:50:56.512051Z","end":"2026-07-08T09:50:56.662321Z","steps":["trace[1901914102] 'process raft request' (duration: 150.007996ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:50:56.662429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.333593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-07-08T09:50:56.662473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.900721ms","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-07-08T09:50:56.662631Z","caller":"traceutil/trace.go:171","msg":"trace[207739322] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25271; }","duration":"454.481415ms","start":"2026-07-08T09:50:56.208044Z","end":"2026-07-08T09:50:56.662525Z","steps":["trace[207739322] 'agreement among raft nodes before linearized reading' (duration: 454.173649ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:50:56.662667Z","caller":"traceutil/trace.go:171","msg":"trace[318568932] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:25271; }","duration":"312.081645ms","start":"2026-07-08T09:50:56.350501Z","end":"2026-07-08T09:50:56.662583Z","steps":["trace[318568932] 'agreement among raft nodes before linearized reading' (duration: 311.763869ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:50:56.662777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:50:56.350483Z","time spent":"312.276649ms","remote":"127.0.0.1:34196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":584,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-07-08T09:50:56.662808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:50:56.208014Z","time spent":"454.70473ms","remote":"127.0.0.1:34196","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-07-08T09:50:56.663085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.87216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-07-08T09:50:56.663184Z","caller":"traceutil/trace.go:171","msg":"trace[1342154993] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25271; }","duration":"127.985082ms","start":"2026-07-08T09:50:56.535157Z","end":"2026-07-08T09:50:56.663142Z","steps":["trace[1342154993] 'agreement among raft nodes before linearized reading' (duration: 127.656586ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:50:56.932939Z","caller":"traceutil/trace.go:171","msg":"trace[1622267061] linearizableReadLoop","detail":"{readStateIndex:26093; appliedIndex:26092; }","duration":"127.130577ms","start":"2026-07-08T09:50:56.805789Z","end":"2026-07-08T09:50:56.932919Z","steps":["trace[1622267061] 'read index received' (duration: 126.913883ms)","trace[1622267061] 'applied index is now lower than readState.Index' (duration: 215.484µs)"],"step_count":2} {"level":"warn","ts":"2026-07-08T09:50:56.933072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.2717ms","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-07-08T09:50:56.9332Z","caller":"traceutil/trace.go:171","msg":"trace[1361074464] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25275; }","duration":"127.410782ms","start":"2026-07-08T09:50:56.805758Z","end":"2026-07-08T09:50:56.933168Z","steps":["trace[1361074464] 'agreement among raft nodes before linearized reading' (duration: 127.27111ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:50:56.933057Z","caller":"traceutil/trace.go:171","msg":"trace[1105156259] transaction","detail":"{read_only:false; response_revision:25275; number_of_response:1; }","duration":"127.619687ms","start":"2026-07-08T09:50:56.8054Z","end":"2026-07-08T09:50:56.93302Z","steps":["trace[1105156259] 'process raft request' (duration: 127.375142ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:50:57.138871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.595029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5684"} {"level":"info","ts":"2026-07-08T09:50:57.138937Z","caller":"traceutil/trace.go:171","msg":"trace[585847515] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:25275; }","duration":"188.69221ms","start":"2026-07-08T09:50:56.950228Z","end":"2026-07-08T09:50:57.138921Z","steps":["trace[585847515] 'range keys from in-memory index tree' (duration: 188.470547ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:50:57.443493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.33102ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-08T09:50:57.443908Z","caller":"traceutil/trace.go:171","msg":"trace[1614996396] transaction","detail":"{read_only:false; response_revision:25278; number_of_response:1; }","duration":"255.306182ms","start":"2026-07-08T09:50:57.188571Z","end":"2026-07-08T09:50:57.443877Z","steps":["trace[1614996396] 'process raft request' (duration: 126.426693ms)","trace[1614996396] 'compare' (duration: 128.203877ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:50:58.396521Z","caller":"traceutil/trace.go:171","msg":"trace[529591142] transaction","detail":"{read_only:false; response_revision:25280; number_of_response:1; }","duration":"247.890019ms","start":"2026-07-08T09:50:58.148611Z","end":"2026-07-08T09:50:58.396501Z","steps":["trace[529591142] 'process raft request' (duration: 247.719556ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:50:58.475609Z","caller":"traceutil/trace.go:171","msg":"trace[2099781321] linearizableReadLoop","detail":"{readStateIndex:26100; appliedIndex:26097; }","duration":"154.162716ms","start":"2026-07-08T09:50:58.321422Z","end":"2026-07-08T09:50:58.475585Z","steps":["trace[2099781321] 'read index received' (duration: 74.962532ms)","trace[2099781321] 'applied index is now lower than readState.Index' (duration: 79.199134ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:50:58.475653Z","caller":"traceutil/trace.go:171","msg":"trace[684915488] transaction","detail":"{read_only:false; response_revision:25281; number_of_response:1; }","duration":"326.734057ms","start":"2026-07-08T09:50:58.148876Z","end":"2026-07-08T09:50:58.47561Z","steps":["trace[684915488] 'process raft request' (duration: 269.559757ms)","trace[684915488] 'compare' (duration: 56.976416ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:50:58.475688Z","caller":"traceutil/trace.go:171","msg":"trace[194066211] transaction","detail":"{read_only:false; response_revision:25282; number_of_response:1; }","duration":"318.340975ms","start":"2026-07-08T09:50:58.157309Z","end":"2026-07-08T09:50:58.47565Z","steps":["trace[194066211] 'process raft request' (duration: 318.221483ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:50:58.475815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.373371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"warn","ts":"2026-07-08T09:50:58.475816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:50:58.148856Z","time spent":"326.873689ms","remote":"127.0.0.1:34196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T09:50:58.475852Z","caller":"traceutil/trace.go:171","msg":"trace[1357760564] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:25282; }","duration":"154.460953ms","start":"2026-07-08T09:50:58.321378Z","end":"2026-07-08T09:50:58.475839Z","steps":["trace[1357760564] 'agreement among raft nodes before linearized reading' (duration: 154.325369ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:50:58.475861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:50:58.157292Z","time spent":"318.477389ms","remote":"127.0.0.1:34196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":605,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T09:50:58.797088Z","caller":"traceutil/trace.go:171","msg":"trace[1113962818] transaction","detail":"{read_only:false; response_revision:25284; number_of_response:1; }","duration":"212.457188ms","start":"2026-07-08T09:50:58.584599Z","end":"2026-07-08T09:50:58.797056Z","steps":["trace[1113962818] 'process raft request' (duration: 130.556783ms)","trace[1113962818] 'compare' (duration: 81.63739ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T09:50:59.167089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.664634ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-08T09:50:59.167381Z","caller":"traceutil/trace.go:171","msg":"trace[1750373157] transaction","detail":"{read_only:false; response_revision:25285; number_of_response:1; }","duration":"510.749048ms","start":"2026-07-08T09:50:58.656497Z","end":"2026-07-08T09:50:59.167246Z","steps":["trace[1750373157] 'process raft request' (duration: 287.839419ms)","trace[1750373157] 'compare' (duration: 222.43557ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:50:59.167569Z","caller":"traceutil/trace.go:171","msg":"trace[1121963978] transaction","detail":"{read_only:false; response_revision:25288; number_of_response:1; }","duration":"229.676809ms","start":"2026-07-08T09:50:58.937869Z","end":"2026-07-08T09:50:59.167546Z","steps":["trace[1121963978] 'process raft request' (duration: 229.519156ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:50:59.167613Z","caller":"traceutil/trace.go:171","msg":"trace[551710539] transaction","detail":"{read_only:false; response_revision:25287; number_of_response:1; }","duration":"500.761705ms","start":"2026-07-08T09:50:58.66684Z","end":"2026-07-08T09:50:59.167601Z","steps":["trace[551710539] 'process raft request' (duration: 500.48734ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:50:59.167604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:50:58.656474Z","time spent":"511.034083ms","remote":"127.0.0.1:34196","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-07-08T09:50:59.167649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.160793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-07-08T09:50:59.167682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:50:58.666821Z","time spent":"500.818637ms","remote":"127.0.0.1:34196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T09:50:59.167706Z","caller":"traceutil/trace.go:171","msg":"trace[1077804309] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25288; }","duration":"501.260485ms","start":"2026-07-08T09:50:58.666432Z","end":"2026-07-08T09:50:59.167692Z","steps":["trace[1077804309] 'agreement among raft nodes before linearized reading' (duration: 501.053071ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:50:59.167437Z","caller":"traceutil/trace.go:171","msg":"trace[1989389954] transaction","detail":"{read_only:false; response_revision:25286; number_of_response:1; }","duration":"510.87745ms","start":"2026-07-08T09:50:58.656537Z","end":"2026-07-08T09:50:59.167415Z","steps":["trace[1989389954] 'process raft request' (duration: 510.665376ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:50:59.167757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:50:58.666408Z","time spent":"501.336317ms","remote":"127.0.0.1:34196","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-07-08T09:50:59.167795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:50:58.656512Z","time spent":"511.242218ms","remote":"127.0.0.1:34196","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-07-08T09:50:59.16745Z","caller":"traceutil/trace.go:171","msg":"trace[1209001423] linearizableReadLoop","detail":"{readStateIndex:26104; appliedIndex:26101; }","duration":"500.933259ms","start":"2026-07-08T09:50:58.666492Z","end":"2026-07-08T09:50:59.167426Z","steps":["trace[1209001423] 'read index received' (duration: 48.626886ms)","trace[1209001423] 'applied index is now lower than readState.Index' (duration: 452.305013ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T09:50:59.168053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.428002ms","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-07-08T09:50:59.168086Z","caller":"traceutil/trace.go:171","msg":"trace[1707669027] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25289; }","duration":"457.580356ms","start":"2026-07-08T09:50:58.710496Z","end":"2026-07-08T09:50:59.168077Z","steps":["trace[1707669027] 'agreement among raft nodes before linearized reading' (duration: 457.464314ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:50:59.168108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:50:58.71048Z","time spent":"457.622647ms","remote":"127.0.0.1:34196","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-07-08T09:50:59.168145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.829314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T09:50:59.168178Z","caller":"traceutil/trace.go:171","msg":"trace[1096818088] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25289; }","duration":"411.880756ms","start":"2026-07-08T09:50:58.756287Z","end":"2026-07-08T09:50:59.168168Z","steps":["trace[1096818088] 'agreement among raft nodes before linearized reading' (duration: 411.829205ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:50:59.168199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:50:58.756275Z","time spent":"411.919426ms","remote":"127.0.0.1:33904","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-07-08T09:50:59.455033Z","caller":"traceutil/trace.go:171","msg":"trace[1220092720] linearizableReadLoop","detail":"{readStateIndex:26110; appliedIndex:26108; }","duration":"132.682642ms","start":"2026-07-08T09:50:59.322333Z","end":"2026-07-08T09:50:59.455016Z","steps":["trace[1220092720] 'read index received' (duration: 132.401927ms)","trace[1220092720] 'applied index is now lower than readState.Index' (duration: 280.095µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:50:59.455079Z","caller":"traceutil/trace.go:171","msg":"trace[1793781604] transaction","detail":"{read_only:false; response_revision:25292; number_of_response:1; }","duration":"282.643109ms","start":"2026-07-08T09:50:59.172416Z","end":"2026-07-08T09:50:59.455059Z","steps":["trace[1793781604] 'process raft request' (duration: 282.564538ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:50:59.455151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.795264ms","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-07-08T09:50:59.455181Z","caller":"traceutil/trace.go:171","msg":"trace[337437882] 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:25292; }","duration":"132.878826ms","start":"2026-07-08T09:50:59.322289Z","end":"2026-07-08T09:50:59.455168Z","steps":["trace[337437882] 'agreement among raft nodes before linearized reading' (duration: 132.793515ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:50:59.455078Z","caller":"traceutil/trace.go:171","msg":"trace[901465306] transaction","detail":"{read_only:false; response_revision:25291; number_of_response:1; }","duration":"283.457455ms","start":"2026-07-08T09:50:59.171584Z","end":"2026-07-08T09:50:59.455042Z","steps":["trace[901465306] 'process raft request' (duration: 283.20387ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:50:59.570523Z","caller":"traceutil/trace.go:171","msg":"trace[1853376637] transaction","detail":"{read_only:false; response_revision:25293; number_of_response:1; }","duration":"245.681287ms","start":"2026-07-08T09:50:59.324811Z","end":"2026-07-08T09:50:59.570492Z","steps":["trace[1853376637] 'process raft request' (duration: 201.925856ms)","trace[1853376637] 'compare' (duration: 43.234191ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:50:59.570529Z","caller":"traceutil/trace.go:171","msg":"trace[1897777073] transaction","detail":"{read_only:false; response_revision:25294; number_of_response:1; }","duration":"217.148568ms","start":"2026-07-08T09:50:59.353355Z","end":"2026-07-08T09:50:59.570504Z","steps":["trace[1897777073] 'process raft request' (duration: 216.880923ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:51:12.10416Z","caller":"traceutil/trace.go:171","msg":"trace[1312523441] transaction","detail":"{read_only:false; response_revision:25437; number_of_response:1; }","duration":"591.89037ms","start":"2026-07-08T09:51:11.512235Z","end":"2026-07-08T09:51:12.104125Z","steps":["trace[1312523441] 'process raft request' (duration: 591.597994ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:51:12.104249Z","caller":"traceutil/trace.go:171","msg":"trace[914204918] transaction","detail":"{read_only:false; response_revision:25438; number_of_response:1; }","duration":"586.367063ms","start":"2026-07-08T09:51:11.517858Z","end":"2026-07-08T09:51:12.104225Z","steps":["trace[914204918] 'process raft request' (duration: 586.126849ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:51:12.104312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:51:11.512222Z","time spent":"592.010192ms","remote":"127.0.0.1:34196","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-07-08T09:51:12.104351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:51:11.517848Z","time spent":"586.458735ms","remote":"127.0.0.1:34196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T09:51:12.104505Z","caller":"traceutil/trace.go:171","msg":"trace[737860502] linearizableReadLoop","detail":"{readStateIndex:26259; appliedIndex:26259; }","duration":"415.790682ms","start":"2026-07-08T09:51:11.688705Z","end":"2026-07-08T09:51:12.104496Z","steps":["trace[737860502] 'read index received' (duration: 415.786931ms)","trace[737860502] 'applied index is now lower than readState.Index' (duration: 3.001µs)"],"step_count":2} {"level":"warn","ts":"2026-07-08T09:51:12.10464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.62698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-08T09:51:12.104661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.966466ms","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-07-08T09:51:12.104675Z","caller":"traceutil/trace.go:171","msg":"trace[1613289870] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25438; }","duration":"346.701232ms","start":"2026-07-08T09:51:11.757964Z","end":"2026-07-08T09:51:12.104665Z","steps":["trace[1613289870] 'agreement among raft nodes before linearized reading' (duration: 346.640911ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:51:12.104683Z","caller":"traceutil/trace.go:171","msg":"trace[1685527328] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:25438; }","duration":"415.988806ms","start":"2026-07-08T09:51:11.688686Z","end":"2026-07-08T09:51:12.104675Z","steps":["trace[1685527328] 'agreement among raft nodes before linearized reading' (duration: 415.876744ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:51:12.104699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:51:11.757941Z","time spent":"346.750452ms","remote":"127.0.0.1:33904","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-07-08T09:51:12.104706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:51:11.688626Z","time spent":"416.072057ms","remote":"127.0.0.1:34196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":584,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-07-08T09:51:12.104812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.798426ms","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-07-08T09:51:12.104838Z","caller":"traceutil/trace.go:171","msg":"trace[13895792] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25438; }","duration":"144.866568ms","start":"2026-07-08T09:51:11.959964Z","end":"2026-07-08T09:51:12.104831Z","steps":["trace[13895792] 'agreement among raft nodes before linearized reading' (duration: 144.803577ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:51:12.227553Z","caller":"traceutil/trace.go:171","msg":"trace[384086002] transaction","detail":"{read_only:false; response_revision:25440; number_of_response:1; }","duration":"116.890259ms","start":"2026-07-08T09:51:12.110639Z","end":"2026-07-08T09:51:12.227529Z","steps":["trace[384086002] 'process raft request' (duration: 116.777987ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:51:12.227636Z","caller":"traceutil/trace.go:171","msg":"trace[885549365] transaction","detail":"{read_only:false; response_revision:25439; number_of_response:1; }","duration":"119.569141ms","start":"2026-07-08T09:51:12.108043Z","end":"2026-07-08T09:51:12.227612Z","steps":["trace[885549365] 'process raft request' (duration: 117.999181ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:51:12.603352Z","caller":"traceutil/trace.go:171","msg":"trace[422638501] transaction","detail":"{read_only:false; response_revision:25441; number_of_response:1; }","duration":"364.954273ms","start":"2026-07-08T09:51:12.238366Z","end":"2026-07-08T09:51:12.60332Z","steps":["trace[422638501] 'process raft request' (duration: 322.255451ms)","trace[422638501] 'compare' (duration: 39.628593ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:51:12.603405Z","caller":"traceutil/trace.go:171","msg":"trace[87145080] linearizableReadLoop","detail":"{readStateIndex:26262; appliedIndex:26261; }","duration":"306.689412ms","start":"2026-07-08T09:51:12.296683Z","end":"2026-07-08T09:51:12.603372Z","steps":["trace[87145080] 'read index received' (duration: 263.968059ms)","trace[87145080] 'applied index is now lower than readState.Index' (duration: 42.718303ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T09:51:12.60352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:51:12.238346Z","time spent":"365.087586ms","remote":"127.0.0.1:34118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3743,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T09:51:12.603733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.031627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"warn","ts":"2026-07-08T09:51:12.603751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.978197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-08T09:51:12.603819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.302559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-08T09:51:12.603844Z","caller":"traceutil/trace.go:171","msg":"trace[32048215] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:25442; }","duration":"307.148291ms","start":"2026-07-08T09:51:12.296673Z","end":"2026-07-08T09:51:12.603822Z","steps":["trace[32048215] 'agreement among raft nodes before linearized reading' (duration: 306.826865ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:51:12.603873Z","caller":"traceutil/trace.go:171","msg":"trace[367207114] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25442; }","duration":"114.35409ms","start":"2026-07-08T09:51:12.489504Z","end":"2026-07-08T09:51:12.603858Z","steps":["trace[367207114] 'agreement among raft nodes before linearized reading' (duration: 114.205627ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:51:12.603905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.987493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-07-08T09:51:12.60399Z","caller":"traceutil/trace.go:171","msg":"trace[1615593231] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25442; }","duration":"155.110205ms","start":"2026-07-08T09:51:12.448859Z","end":"2026-07-08T09:51:12.603969Z","steps":["trace[1615593231] 'agreement among raft nodes before linearized reading' (duration: 154.943322ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:51:12.603927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:51:12.296608Z","time spent":"307.298223ms","remote":"127.0.0.1:36676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true "} {"level":"info","ts":"2026-07-08T09:51:12.603781Z","caller":"traceutil/trace.go:171","msg":"trace[1840596746] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25442; }","duration":"282.13408ms","start":"2026-07-08T09:51:12.321636Z","end":"2026-07-08T09:51:12.60377Z","steps":["trace[1840596746] 'agreement among raft nodes before linearized reading' (duration: 281.897795ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:51:12.967962Z","caller":"traceutil/trace.go:171","msg":"trace[364970440] transaction","detail":"{read_only:false; response_revision:25443; number_of_response:1; }","duration":"359.712042ms","start":"2026-07-08T09:51:12.608231Z","end":"2026-07-08T09:51:12.967943Z","steps":["trace[364970440] 'process raft request' (duration: 359.557589ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:51:12.968078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:51:12.608215Z","time spent":"359.807164ms","remote":"127.0.0.1:34196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T09:51:13.248892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.976297ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-08T09:51:13.249104Z","caller":"traceutil/trace.go:171","msg":"trace[1279634591] linearizableReadLoop","detail":"{readStateIndex:26265; appliedIndex:26263; }","duration":"640.074146ms","start":"2026-07-08T09:51:12.609009Z","end":"2026-07-08T09:51:13.249083Z","steps":["trace[1279634591] 'read index received' (duration: 358.817064ms)","trace[1279634591] 'applied index is now lower than readState.Index' (duration: 281.255632ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:51:13.249131Z","caller":"traceutil/trace.go:171","msg":"trace[670678816] transaction","detail":"{read_only:false; response_revision:25444; number_of_response:1; }","duration":"640.224789ms","start":"2026-07-08T09:51:12.608869Z","end":"2026-07-08T09:51:13.249094Z","steps":["trace[670678816] 'process raft request' (duration: 491.953276ms)","trace[670678816] 'compare' (duration: 147.845865ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:51:13.249168Z","caller":"traceutil/trace.go:171","msg":"trace[95098939] transaction","detail":"{read_only:false; response_revision:25445; number_of_response:1; }","duration":"640.182878ms","start":"2026-07-08T09:51:12.608955Z","end":"2026-07-08T09:51:13.249138Z","steps":["trace[95098939] 'process raft request' (duration: 640.045105ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:51:13.24928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:51:12.608837Z","time spent":"640.362752ms","remote":"127.0.0.1:34196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T09:51:13.249305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:51:12.608917Z","time spent":"640.319331ms","remote":"127.0.0.1:34196","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-07-08T09:51:13.24932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.30267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18144"} {"level":"info","ts":"2026-07-08T09:51:13.249368Z","caller":"traceutil/trace.go:171","msg":"trace[232310655] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25445; }","duration":"640.364352ms","start":"2026-07-08T09:51:12.608993Z","end":"2026-07-08T09:51:13.249358Z","steps":["trace[232310655] 'agreement among raft nodes before linearized reading' (duration: 640.216689ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:51:13.2494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:51:12.608987Z","time spent":"640.402402ms","remote":"127.0.0.1:34102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18166,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-07-08T09:51:13.591254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.798271ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-08T09:51:13.591474Z","caller":"traceutil/trace.go:171","msg":"trace[1964185790] transaction","detail":"{read_only:false; response_revision:25447; number_of_response:1; }","duration":"365.812939ms","start":"2026-07-08T09:51:13.225635Z","end":"2026-07-08T09:51:13.591448Z","steps":["trace[1964185790] 'process raft request' (duration: 241.743972ms)","trace[1964185790] 'compare' (duration: 123.553747ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:51:13.591531Z","caller":"traceutil/trace.go:171","msg":"trace[760329588] transaction","detail":"{read_only:false; response_revision:25449; number_of_response:1; }","duration":"361.751141ms","start":"2026-07-08T09:51:13.229762Z","end":"2026-07-08T09:51:13.591514Z","steps":["trace[760329588] 'process raft request' (duration: 361.671349ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:51:13.591592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:51:13.225626Z","time spent":"365.89336ms","remote":"127.0.0.1:34196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T09:51:13.591643Z","caller":"traceutil/trace.go:171","msg":"trace[23905616] transaction","detail":"{read_only:false; response_revision:25448; number_of_response:1; }","duration":"361.918944ms","start":"2026-07-08T09:51:13.229702Z","end":"2026-07-08T09:51:13.59162Z","steps":["trace[23905616] 'process raft request' (duration: 361.646509ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:51:13.591672Z","caller":"traceutil/trace.go:171","msg":"trace[1903306514] linearizableReadLoop","detail":"{readStateIndex:26271; appliedIndex:26266; }","duration":"342.47114ms","start":"2026-07-08T09:51:13.249179Z","end":"2026-07-08T09:51:13.59165Z","steps":["trace[1903306514] 'read index received' (duration: 14.579171ms)","trace[1903306514] 'applied index is now lower than readState.Index' (duration: 327.890219ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T09:51:13.591734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:51:13.229691Z","time spent":"361.995586ms","remote":"127.0.0.1:34196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T09:51:13.591724Z","caller":"traceutil/trace.go:171","msg":"trace[387355230] transaction","detail":"{read_only:false; response_revision:25450; number_of_response:1; }","duration":"361.840363ms","start":"2026-07-08T09:51:13.229848Z","end":"2026-07-08T09:51:13.591688Z","steps":["trace[387355230] 'process raft request' (duration: 361.624239ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:51:13.591808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"813.119036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-08T09:51:13.591833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.380515ms","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-07-08T09:51:13.591858Z","caller":"traceutil/trace.go:171","msg":"trace[447128232] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25450; }","duration":"333.420676ms","start":"2026-07-08T09:51:13.258429Z","end":"2026-07-08T09:51:13.59185Z","steps":["trace[447128232] 'agreement among raft nodes before linearized reading' (duration: 333.339595ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:51:13.591874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:51:13.258417Z","time spent":"333.453657ms","remote":"127.0.0.1:34196","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-07-08T09:51:13.591862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:51:13.229819Z","time spent":"361.970225ms","remote":"127.0.0.1:34196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T09:51:13.591888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.625385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-07-08T09:51:13.591871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.62681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-08T09:51:13.591908Z","caller":"traceutil/trace.go:171","msg":"trace[537060923] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:25450; }","duration":"220.683716ms","start":"2026-07-08T09:51:13.371219Z","end":"2026-07-08T09:51:13.591903Z","steps":["trace[537060923] 'agreement among raft nodes before linearized reading' (duration: 220.638165ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:51:13.591914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.760448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:186"} {"level":"warn","ts":"2026-07-08T09:51:13.591941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.393206ms","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-07-08T09:51:13.591954Z","caller":"traceutil/trace.go:171","msg":"trace[943071407] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25450; }","duration":"267.445917ms","start":"2026-07-08T09:51:13.324505Z","end":"2026-07-08T09:51:13.591951Z","steps":["trace[943071407] 'agreement among raft nodes before linearized reading' (duration: 267.420047ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:51:13.59195Z","caller":"traceutil/trace.go:171","msg":"trace[2037703070] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:25450; }","duration":"193.846221ms","start":"2026-07-08T09:51:13.398093Z","end":"2026-07-08T09:51:13.591939Z","steps":["trace[2037703070] 'agreement among raft nodes before linearized reading' (duration: 193.740188ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:51:13.591944Z","caller":"traceutil/trace.go:171","msg":"trace[91850212] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:25450; }","duration":"609.746634ms","start":"2026-07-08T09:51:12.982178Z","end":"2026-07-08T09:51:13.591924Z","steps":["trace[91850212] 'agreement among raft nodes before linearized reading' (duration: 609.534919ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:51:13.591836Z","caller":"traceutil/trace.go:171","msg":"trace[1741018967] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:25450; }","duration":"813.190728ms","start":"2026-07-08T09:51:12.778637Z","end":"2026-07-08T09:51:13.591828Z","steps":["trace[1741018967] 'agreement among raft nodes before linearized reading' (duration: 813.117056ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:51:13.592005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:51:12.982158Z","time spent":"609.829725ms","remote":"127.0.0.1:34168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":87,"response size":30,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-07-08T09:51:13.59204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:51:12.778621Z","time spent":"813.412673ms","remote":"127.0.0.1:45402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true "} {"level":"warn","ts":"2026-07-08T09:51:13.592169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"950.375409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:573"} {"level":"info","ts":"2026-07-08T09:51:13.592221Z","caller":"traceutil/trace.go:171","msg":"trace[774257234] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:25450; }","duration":"950.45652ms","start":"2026-07-08T09:51:12.641751Z","end":"2026-07-08T09:51:13.592208Z","steps":["trace[774257234] 'agreement among raft nodes before linearized reading' (duration: 950.356008ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:51:13.592249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:51:12.641734Z","time spent":"950.507492ms","remote":"127.0.0.1:34196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":595,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-07-08T09:51:13.595096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:51:13.229749Z","time spent":"361.823592ms","remote":"127.0.0.1:34196","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-07-08T09:51:13.934453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.838415ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-08T09:51:13.934743Z","caller":"traceutil/trace.go:171","msg":"trace[771698860] transaction","detail":"{read_only:false; response_revision:25451; number_of_response:1; }","duration":"338.231078ms","start":"2026-07-08T09:51:13.596476Z","end":"2026-07-08T09:51:13.934707Z","steps":["trace[771698860] 'process raft request' (duration: 149.034697ms)","trace[771698860] 'compare' (duration: 188.498698ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:51:13.934847Z","caller":"traceutil/trace.go:171","msg":"trace[1919585897] transaction","detail":"{read_only:false; response_revision:25453; number_of_response:1; }","duration":"336.570667ms","start":"2026-07-08T09:51:13.598256Z","end":"2026-07-08T09:51:13.934827Z","steps":["trace[1919585897] 'process raft request' (duration: 336.436254ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:51:13.934934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:51:13.598245Z","time spent":"336.651128ms","remote":"127.0.0.1:34196","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-07-08T09:51:13.934946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:51:13.596461Z","time spent":"338.385642ms","remote":"127.0.0.1:34196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T09:51:13.935191Z","caller":"traceutil/trace.go:171","msg":"trace[1056148138] transaction","detail":"{read_only:false; response_revision:25452; number_of_response:1; }","duration":"337.934323ms","start":"2026-07-08T09:51:13.597245Z","end":"2026-07-08T09:51:13.935179Z","steps":["trace[1056148138] 'process raft request' (duration: 337.336382ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:51:13.93523Z","caller":"traceutil/trace.go:171","msg":"trace[1470989487] linearizableReadLoop","detail":"{readStateIndex:26272; appliedIndex:26271; }","duration":"338.447053ms","start":"2026-07-08T09:51:13.596763Z","end":"2026-07-08T09:51:13.93521Z","steps":["trace[1470989487] 'read index received' (duration: 148.769443ms)","trace[1470989487] 'applied index is now lower than readState.Index' (duration: 189.67574ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T09:51:13.935247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:51:13.597213Z","time spent":"338.004374ms","remote":"127.0.0.1:34196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T09:51:13.935467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.753069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-74125823-3e23-44d9-b81f-323338f2447f\" ","response":"range_response_count:1 size:3761"} {"level":"info","ts":"2026-07-08T09:51:13.935525Z","caller":"traceutil/trace.go:171","msg":"trace[809469001] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-74125823-3e23-44d9-b81f-323338f2447f; range_end:; response_count:1; response_revision:25453; }","duration":"325.82458ms","start":"2026-07-08T09:51:13.609684Z","end":"2026-07-08T09:51:13.935509Z","steps":["trace[809469001] 'agreement among raft nodes before linearized reading' (duration: 325.659776ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:51:13.935567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:51:13.609674Z","time spent":"325.884351ms","remote":"127.0.0.1:34118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3783,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-74125823-3e23-44d9-b81f-323338f2447f\" "} {"level":"warn","ts":"2026-07-08T09:51:13.935489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.987445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T09:51:13.93563Z","caller":"traceutil/trace.go:171","msg":"trace[529091873] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25453; }","duration":"179.181029ms","start":"2026-07-08T09:51:13.756431Z","end":"2026-07-08T09:51:13.935612Z","steps":["trace[529091873] 'agreement among raft nodes before linearized reading' (duration: 178.916414ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:51:13.935867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.078295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-07-08T09:51:13.935913Z","caller":"traceutil/trace.go:171","msg":"trace[809442046] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25453; }","duration":"339.151856ms","start":"2026-07-08T09:51:13.596747Z","end":"2026-07-08T09:51:13.935899Z","steps":["trace[809442046] 'agreement among raft nodes before linearized reading' (duration: 338.578035ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:51:13.935954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:51:13.59674Z","time spent":"339.206007ms","remote":"127.0.0.1:34118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-07-08T09:51:14.240598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.389428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-07-08T09:51:14.240646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.599752ms","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-07-08T09:51:14.240686Z","caller":"traceutil/trace.go:171","msg":"trace[1243732289] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:25453; }","duration":"157.520171ms","start":"2026-07-08T09:51:14.083143Z","end":"2026-07-08T09:51:14.240663Z","steps":["trace[1243732289] 'count revisions from in-memory index tree' (duration: 157.310987ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:51:14.240708Z","caller":"traceutil/trace.go:171","msg":"trace[1175084720] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25453; }","duration":"131.727285ms","start":"2026-07-08T09:51:14.10896Z","end":"2026-07-08T09:51:14.240687Z","steps":["trace[1175084720] 'range keys from in-memory index tree' (duration: 131.4376ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:51:37.835135Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000677b.wal"} {"level":"warn","ts":"2026-07-08T09:51:48.467586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.617207ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-08T09:51:48.467892Z","caller":"traceutil/trace.go:171","msg":"trace[619665852] linearizableReadLoop","detail":"{readStateIndex:26578; appliedIndex:26576; }","duration":"189.397634ms","start":"2026-07-08T09:51:48.278472Z","end":"2026-07-08T09:51:48.46787Z","steps":["trace[619665852] 'read index received' (duration: 53.594332ms)","trace[619665852] 'applied index is now lower than readState.Index' (duration: 135.802022ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:51:48.467945Z","caller":"traceutil/trace.go:171","msg":"trace[1489938758] transaction","detail":"{read_only:false; response_revision:25748; number_of_response:1; }","duration":"284.075586ms","start":"2026-07-08T09:51:48.183843Z","end":"2026-07-08T09:51:48.467918Z","steps":["trace[1489938758] 'process raft request' (duration: 283.869232ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:51:48.468054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.556387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-08T09:51:48.46814Z","caller":"traceutil/trace.go:171","msg":"trace[2009054328] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:25748; }","duration":"189.687509ms","start":"2026-07-08T09:51:48.278433Z","end":"2026-07-08T09:51:48.468121Z","steps":["trace[2009054328] 'agreement among raft nodes before linearized reading' (duration: 189.530226ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:51:48.468133Z","caller":"traceutil/trace.go:171","msg":"trace[741637179] transaction","detail":"{read_only:false; response_revision:25747; number_of_response:1; }","duration":"285.204708ms","start":"2026-07-08T09:51:48.182873Z","end":"2026-07-08T09:51:48.468078Z","steps":["trace[741637179] 'process raft request' (duration: 40.977759ms)","trace[741637179] 'compare' (duration: 243.254291ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T09:51:49.990906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.945863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T09:51:49.990994Z","caller":"traceutil/trace.go:171","msg":"trace[1480393354] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:25756; }","duration":"285.089126ms","start":"2026-07-08T09:51:49.705883Z","end":"2026-07-08T09:51:49.990972Z","steps":["trace[1480393354] 'count revisions from in-memory index tree' (duration: 284.872932ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:51:49.99148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.675874ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-08T09:51:49.991686Z","caller":"traceutil/trace.go:171","msg":"trace[515562871] linearizableReadLoop","detail":"{readStateIndex:26590; appliedIndex:26586; }","duration":"234.998062ms","start":"2026-07-08T09:51:49.756675Z","end":"2026-07-08T09:51:49.991674Z","steps":["trace[515562871] 'read index received' (duration: 63.084115ms)","trace[515562871] 'applied index is now lower than readState.Index' (duration: 171.912897ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:51:49.991674Z","caller":"traceutil/trace.go:171","msg":"trace[854708237] transaction","detail":"{read_only:false; response_revision:25757; number_of_response:1; }","duration":"267.401865ms","start":"2026-07-08T09:51:49.72424Z","end":"2026-07-08T09:51:49.991642Z","steps":["trace[854708237] 'process raft request' (duration: 95.508718ms)","trace[854708237] 'compare' (duration: 170.694884ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:51:49.991689Z","caller":"traceutil/trace.go:171","msg":"trace[1929804838] transaction","detail":"{read_only:false; response_revision:25758; number_of_response:1; }","duration":"265.835126ms","start":"2026-07-08T09:51:49.725816Z","end":"2026-07-08T09:51:49.991651Z","steps":["trace[1929804838] 'process raft request' (duration: 265.722194ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:51:49.991749Z","caller":"traceutil/trace.go:171","msg":"trace[465324650] transaction","detail":"{read_only:false; response_revision:25759; number_of_response:1; }","duration":"263.863297ms","start":"2026-07-08T09:51:49.727851Z","end":"2026-07-08T09:51:49.991714Z","steps":["trace[465324650] 'process raft request' (duration: 263.748735ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:51:49.991762Z","caller":"traceutil/trace.go:171","msg":"trace[746865813] transaction","detail":"{read_only:false; response_revision:25760; number_of_response:1; }","duration":"260.36748ms","start":"2026-07-08T09:51:49.731364Z","end":"2026-07-08T09:51:49.991732Z","steps":["trace[746865813] 'process raft request' (duration: 260.270429ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:51:49.991779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.098974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T09:51:49.991935Z","caller":"traceutil/trace.go:171","msg":"trace[899592395] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25760; }","duration":"235.271797ms","start":"2026-07-08T09:51:49.756645Z","end":"2026-07-08T09:51:49.991917Z","steps":["trace[899592395] 'agreement among raft nodes before linearized reading' (duration: 235.070723ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:51:51.735319Z","caller":"traceutil/trace.go:171","msg":"trace[1028270655] linearizableReadLoop","detail":"{readStateIndex:26598; appliedIndex:26597; }","duration":"256.682959ms","start":"2026-07-08T09:51:51.478611Z","end":"2026-07-08T09:51:51.735294Z","steps":["trace[1028270655] 'read index received' (duration: 256.460664ms)","trace[1028270655] 'applied index is now lower than readState.Index' (duration: 221.345µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:51:51.735458Z","caller":"traceutil/trace.go:171","msg":"trace[1055311000] transaction","detail":"{read_only:false; response_revision:25767; number_of_response:1; }","duration":"259.780559ms","start":"2026-07-08T09:51:51.475644Z","end":"2026-07-08T09:51:51.735425Z","steps":["trace[1055311000] 'process raft request' (duration: 259.478363ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:51:51.735571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.942955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-08T09:51:51.735615Z","caller":"traceutil/trace.go:171","msg":"trace[1458965925] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25767; }","duration":"257.006266ms","start":"2026-07-08T09:51:51.478598Z","end":"2026-07-08T09:51:51.735604Z","steps":["trace[1458965925] 'agreement among raft nodes before linearized reading' (duration: 256.820372ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:51:51.735765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.096388ms","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-07-08T09:51:51.735851Z","caller":"traceutil/trace.go:171","msg":"trace[1358993425] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25767; }","duration":"256.221451ms","start":"2026-07-08T09:51:51.479611Z","end":"2026-07-08T09:51:51.735833Z","steps":["trace[1358993425] 'agreement among raft nodes before linearized reading' (duration: 255.972657ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:51:52.03863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.996583ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-08T09:51:52.038793Z","caller":"traceutil/trace.go:171","msg":"trace[616877037] transaction","detail":"{read_only:false; response_revision:25768; number_of_response:1; }","duration":"299.540284ms","start":"2026-07-08T09:51:51.739234Z","end":"2026-07-08T09:51:52.038774Z","steps":["trace[616877037] 'process raft request' (duration: 145.299336ms)","trace[616877037] 'compare' (duration: 153.277008ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:51:52.039026Z","caller":"traceutil/trace.go:171","msg":"trace[1831163715] linearizableReadLoop","detail":"{readStateIndex:26600; appliedIndex:26598; }","duration":"283.775671ms","start":"2026-07-08T09:51:51.75523Z","end":"2026-07-08T09:51:52.039006Z","steps":["trace[1831163715] 'read index received' (duration: 129.242208ms)","trace[1831163715] 'applied index is now lower than readState.Index' (duration: 154.532183ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:51:52.039098Z","caller":"traceutil/trace.go:171","msg":"trace[937121195] transaction","detail":"{read_only:false; response_revision:25769; number_of_response:1; }","duration":"298.643637ms","start":"2026-07-08T09:51:51.74042Z","end":"2026-07-08T09:51:52.039064Z","steps":["trace[937121195] 'process raft request' (duration: 298.302551ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:51:52.03916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.927314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T09:51:52.039193Z","caller":"traceutil/trace.go:171","msg":"trace[887256667] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25769; }","duration":"283.987964ms","start":"2026-07-08T09:51:51.755196Z","end":"2026-07-08T09:51:52.039183Z","steps":["trace[887256667] 'agreement among raft nodes before linearized reading' (duration: 283.904863ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:51:52.200715Z","caller":"traceutil/trace.go:171","msg":"trace[1373802343] linearizableReadLoop","detail":"{readStateIndex:26604; appliedIndex:26600; }","duration":"161.61999ms","start":"2026-07-08T09:51:52.039071Z","end":"2026-07-08T09:51:52.200691Z","steps":["trace[1373802343] 'read index received' (duration: 161.281394ms)","trace[1373802343] 'applied index is now lower than readState.Index' (duration: 337.836µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:51:52.201072Z","caller":"traceutil/trace.go:171","msg":"trace[391020916] transaction","detail":"{read_only:false; response_revision:25770; number_of_response:1; }","duration":"204.934833ms","start":"2026-07-08T09:51:51.996124Z","end":"2026-07-08T09:51:52.201059Z","steps":["trace[391020916] 'process raft request' (duration: 204.301941ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:51:52.20123Z","caller":"traceutil/trace.go:171","msg":"trace[706456679] transaction","detail":"{read_only:false; response_revision:25771; number_of_response:1; }","duration":"205.069675ms","start":"2026-07-08T09:51:51.996152Z","end":"2026-07-08T09:51:52.201222Z","steps":["trace[706456679] 'process raft request' (duration: 204.424983ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:51:52.201339Z","caller":"traceutil/trace.go:171","msg":"trace[692766891] transaction","detail":"{read_only:false; response_revision:25772; number_of_response:1; }","duration":"204.962753ms","start":"2026-07-08T09:51:51.996368Z","end":"2026-07-08T09:51:52.201331Z","steps":["trace[692766891] 'process raft request' (duration: 204.2522ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:51:52.201439Z","caller":"traceutil/trace.go:171","msg":"trace[672935023] transaction","detail":"{read_only:false; response_revision:25773; number_of_response:1; }","duration":"205.025945ms","start":"2026-07-08T09:51:51.996406Z","end":"2026-07-08T09:51:52.201431Z","steps":["trace[672935023] 'process raft request' (duration: 204.252001ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:51:52.201587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.695386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T09:51:52.201621Z","caller":"traceutil/trace.go:171","msg":"trace[515207840] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:25773; }","duration":"407.777987ms","start":"2026-07-08T09:51:51.793831Z","end":"2026-07-08T09:51:52.201609Z","steps":["trace[515207840] 'agreement among raft nodes before linearized reading' (duration: 407.696375ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:51:52.201649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:51:51.793812Z","time spent":"407.828238ms","remote":"127.0.0.1:55394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-07-08T09:51:52.23927Z","caller":"traceutil/trace.go:171","msg":"trace[1355995614] transaction","detail":"{read_only:false; response_revision:25774; number_of_response:1; }","duration":"123.971745ms","start":"2026-07-08T09:51:52.115257Z","end":"2026-07-08T09:51:52.239229Z","steps":["trace[1355995614] 'process raft request' (duration: 123.16203ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:51:52.586323Z","caller":"traceutil/trace.go:171","msg":"trace[1375930105] linearizableReadLoop","detail":"{readStateIndex:26606; appliedIndex:26605; }","duration":"264.934018ms","start":"2026-07-08T09:51:52.321362Z","end":"2026-07-08T09:51:52.586296Z","steps":["trace[1375930105] 'read index received' (duration: 182.847289ms)","trace[1375930105] 'applied index is now lower than readState.Index' (duration: 82.085219ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:51:52.58636Z","caller":"traceutil/trace.go:171","msg":"trace[859872379] transaction","detail":"{read_only:false; response_revision:25775; number_of_response:1; }","duration":"342.344408ms","start":"2026-07-08T09:51:52.24398Z","end":"2026-07-08T09:51:52.586325Z","steps":["trace[859872379] 'process raft request' (duration: 260.33851ms)","trace[859872379] 'compare' (duration: 81.835524ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T09:51:52.586532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:51:52.243955Z","time spent":"342.47873ms","remote":"127.0.0.1:34196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T09:51:52.586636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.959748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"warn","ts":"2026-07-08T09:51:52.586621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.237714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-07-08T09:51:52.58668Z","caller":"traceutil/trace.go:171","msg":"trace[1743968127] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:25775; }","duration":"265.344735ms","start":"2026-07-08T09:51:52.321319Z","end":"2026-07-08T09:51:52.586664Z","steps":["trace[1743968127] 'agreement among raft nodes before linearized reading' (duration: 265.110031ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:51:52.58679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.699127ms","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-07-08T09:51:52.586824Z","caller":"traceutil/trace.go:171","msg":"trace[599093876] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25775; }","duration":"101.793609ms","start":"2026-07-08T09:51:52.485023Z","end":"2026-07-08T09:51:52.586817Z","steps":["trace[599093876] 'agreement among raft nodes before linearized reading' (duration: 101.685327ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:51:52.586672Z","caller":"traceutil/trace.go:171","msg":"trace[409695464] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25775; }","duration":"106.01877ms","start":"2026-07-08T09:51:52.480637Z","end":"2026-07-08T09:51:52.586656Z","steps":["trace[409695464] 'agreement among raft nodes before linearized reading' (duration: 105.900458ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:51:52.587047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.45319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:573"} {"level":"info","ts":"2026-07-08T09:51:52.587081Z","caller":"traceutil/trace.go:171","msg":"trace[70860217] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:25775; }","duration":"105.499411ms","start":"2026-07-08T09:51:52.481571Z","end":"2026-07-08T09:51:52.587071Z","steps":["trace[70860217] 'agreement among raft nodes before linearized reading' (duration: 105.365038ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:51:53.039943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.726336ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-08T09:51:53.040031Z","caller":"traceutil/trace.go:171","msg":"trace[1967297801] transaction","detail":"{read_only:false; response_revision:25776; number_of_response:1; }","duration":"448.47019ms","start":"2026-07-08T09:51:52.591545Z","end":"2026-07-08T09:51:53.040015Z","steps":["trace[1967297801] 'process raft request' (duration: 292.601761ms)","trace[1967297801] 'compare' (duration: 155.40925ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T09:51:53.040074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:51:52.591516Z","time spent":"448.536081ms","remote":"127.0.0.1:34196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T09:51:53.106492Z","caller":"traceutil/trace.go:171","msg":"trace[574112699] transaction","detail":"{read_only:false; response_revision:25777; number_of_response:1; }","duration":"513.236436ms","start":"2026-07-08T09:51:52.593229Z","end":"2026-07-08T09:51:53.106465Z","steps":["trace[574112699] 'process raft request' (duration: 513.000171ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:51:53.106527Z","caller":"traceutil/trace.go:171","msg":"trace[965437222] transaction","detail":"{read_only:false; response_revision:25778; number_of_response:1; }","duration":"513.195975ms","start":"2026-07-08T09:51:52.593311Z","end":"2026-07-08T09:51:53.106507Z","steps":["trace[965437222] 'process raft request' (duration: 513.057373ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:51:53.106638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:51:52.593212Z","time spent":"513.364559ms","remote":"127.0.0.1:34196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T09:51:53.106641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:51:52.593305Z","time spent":"513.278827ms","remote":"127.0.0.1:34196","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-07-08T09:51:53.106849Z","caller":"traceutil/trace.go:171","msg":"trace[914104247] transaction","detail":"{read_only:false; response_revision:25779; number_of_response:1; }","duration":"512.845368ms","start":"2026-07-08T09:51:52.593979Z","end":"2026-07-08T09:51:53.106825Z","steps":["trace[914104247] 'process raft request' (duration: 512.42559ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:51:53.106974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:51:52.593971Z","time spent":"512.93754ms","remote":"127.0.0.1:34196","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-07-08T09:51:53.10705Z","caller":"traceutil/trace.go:171","msg":"trace[2058500210] transaction","detail":"{read_only:false; response_revision:25780; number_of_response:1; }","duration":"314.428581ms","start":"2026-07-08T09:51:52.792611Z","end":"2026-07-08T09:51:53.10704Z","steps":["trace[2058500210] 'process raft request' (duration: 313.835539ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:51:53.107118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:51:52.792592Z","time spent":"314.494641ms","remote":"127.0.0.1:34196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T09:51:53.107179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.372921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T09:51:53.107226Z","caller":"traceutil/trace.go:171","msg":"trace[1012469357] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25780; }","duration":"351.454812ms","start":"2026-07-08T09:51:52.755764Z","end":"2026-07-08T09:51:53.107218Z","steps":["trace[1012469357] 'agreement among raft nodes before linearized reading' (duration: 351.384521ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:51:53.107263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:51:52.755561Z","time spent":"351.695987ms","remote":"127.0.0.1:33904","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-07-08T09:51:53.107411Z","caller":"traceutil/trace.go:171","msg":"trace[502134701] linearizableReadLoop","detail":"{readStateIndex:26610; appliedIndex:26606; }","duration":"351.191677ms","start":"2026-07-08T09:51:52.755814Z","end":"2026-07-08T09:51:53.107006Z","steps":["trace[502134701] 'read index received' (duration: 128.3797ms)","trace[502134701] 'applied index is now lower than readState.Index' (duration: 222.811027ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:51:53.234759Z","caller":"traceutil/trace.go:171","msg":"trace[309885908] linearizableReadLoop","detail":"{readStateIndex:26612; appliedIndex:26611; }","duration":"127.29224ms","start":"2026-07-08T09:51:53.10744Z","end":"2026-07-08T09:51:53.234732Z","steps":["trace[309885908] 'read index received' (duration: 93.148973ms)","trace[309885908] 'applied index is now lower than readState.Index' (duration: 34.142337ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T09:51:53.234847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.959992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/servers/\" range_end:\"/registry/openstack.k-orc.cloud/servers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T09:51:53.234927Z","caller":"traceutil/trace.go:171","msg":"trace[276382178] transaction","detail":"{read_only:false; response_revision:25781; number_of_response:1; }","duration":"296.18322ms","start":"2026-07-08T09:51:52.938725Z","end":"2026-07-08T09:51:53.234908Z","steps":["trace[276382178] 'process raft request' (duration: 261.88438ms)","trace[276382178] 'compare' (duration: 33.81087ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:51:53.234935Z","caller":"traceutil/trace.go:171","msg":"trace[1707095791] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/servers/; range_end:/registry/openstack.k-orc.cloud/servers0; response_count:0; response_revision:25781; }","duration":"185.50098ms","start":"2026-07-08T09:51:53.04941Z","end":"2026-07-08T09:51:53.234911Z","steps":["trace[1707095791] 'agreement among raft nodes before linearized reading' (duration: 185.345327ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:51:53.235019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.802682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-07-08T09:51:53.235047Z","caller":"traceutil/trace.go:171","msg":"trace[911853751] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:25781; }","duration":"256.878153ms","start":"2026-07-08T09:51:52.978161Z","end":"2026-07-08T09:51:53.235039Z","steps":["trace[911853751] 'agreement among raft nodes before linearized reading' (duration: 256.679839ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:51:53.23503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.418994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T09:51:53.235118Z","caller":"traceutil/trace.go:171","msg":"trace[849812510] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25781; }","duration":"124.554337ms","start":"2026-07-08T09:51:53.11054Z","end":"2026-07-08T09:51:53.235094Z","steps":["trace[849812510] 'agreement among raft nodes before linearized reading' (duration: 124.415154ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:51:53.23518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.247609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T09:51:53.235212Z","caller":"traceutil/trace.go:171","msg":"trace[214535449] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:25781; }","duration":"311.28636ms","start":"2026-07-08T09:51:52.923917Z","end":"2026-07-08T09:51:53.235203Z","steps":["trace[214535449] 'agreement among raft nodes before linearized reading' (duration: 311.222409ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:51:53.235239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:51:52.923873Z","time spent":"311.359682ms","remote":"127.0.0.1:33016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true "} {"level":"info","ts":"2026-07-08T09:51:53.872775Z","caller":"traceutil/trace.go:171","msg":"trace[37978260] linearizableReadLoop","detail":"{readStateIndex:26613; appliedIndex:26612; }","duration":"116.574244ms","start":"2026-07-08T09:51:53.756176Z","end":"2026-07-08T09:51:53.87275Z","steps":["trace[37978260] 'read index received' (duration: 116.376159ms)","trace[37978260] 'applied index is now lower than readState.Index' (duration: 196.444µs)"],"step_count":2} {"level":"warn","ts":"2026-07-08T09:51:53.872983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.795737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T09:51:53.87306Z","caller":"traceutil/trace.go:171","msg":"trace[1374502907] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25782; }","duration":"116.92582ms","start":"2026-07-08T09:51:53.756121Z","end":"2026-07-08T09:51:53.873047Z","steps":["trace[1374502907] 'agreement among raft nodes before linearized reading' (duration: 116.801558ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:51:53.873048Z","caller":"traceutil/trace.go:171","msg":"trace[559136196] transaction","detail":"{read_only:false; response_revision:25782; number_of_response:1; }","duration":"131.979201ms","start":"2026-07-08T09:51:53.741044Z","end":"2026-07-08T09:51:53.873023Z","steps":["trace[559136196] 'process raft request' (duration: 131.539441ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:51:54.224478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.767009ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-08T09:51:54.22473Z","caller":"traceutil/trace.go:171","msg":"trace[1455841068] transaction","detail":"{read_only:false; response_revision:25784; number_of_response:1; }","duration":"158.275226ms","start":"2026-07-08T09:51:54.06643Z","end":"2026-07-08T09:51:54.224706Z","steps":["trace[1455841068] 'process raft request' (duration: 30.17392ms)","trace[1455841068] 'compare' (duration: 127.631077ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:51:54.757039Z","caller":"traceutil/trace.go:171","msg":"trace[1860219418] transaction","detail":"{read_only:false; response_revision:25790; number_of_response:1; }","duration":"114.401372ms","start":"2026-07-08T09:51:54.642613Z","end":"2026-07-08T09:51:54.757014Z","steps":["trace[1860219418] 'process raft request' (duration: 106.212274ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:51:54.980156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.37563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-08T09:51:54.980238Z","caller":"traceutil/trace.go:171","msg":"trace[91758940] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:25790; }","duration":"116.513632ms","start":"2026-07-08T09:51:54.863706Z","end":"2026-07-08T09:51:54.980219Z","steps":["trace[91758940] 'count revisions from in-memory index tree' (duration: 116.298929ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:51:55.273667Z","caller":"traceutil/trace.go:171","msg":"trace[180973965] transaction","detail":"{read_only:false; response_revision:25793; number_of_response:1; }","duration":"144.629914ms","start":"2026-07-08T09:51:55.129021Z","end":"2026-07-08T09:51:55.273651Z","steps":["trace[180973965] 'process raft request' (duration: 144.514231ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:51:55.288075Z","caller":"traceutil/trace.go:171","msg":"trace[1842490477] transaction","detail":"{read_only:false; response_revision:25795; number_of_response:1; }","duration":"158.917319ms","start":"2026-07-08T09:51:55.129131Z","end":"2026-07-08T09:51:55.288048Z","steps":["trace[1842490477] 'process raft request' (duration: 158.867248ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:51:55.288536Z","caller":"traceutil/trace.go:171","msg":"trace[1664671378] transaction","detail":"{read_only:false; response_revision:25794; number_of_response:1; }","duration":"159.436468ms","start":"2026-07-08T09:51:55.129084Z","end":"2026-07-08T09:51:55.288521Z","steps":["trace[1664671378] 'process raft request' (duration: 158.769335ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:51:56.403994Z","caller":"traceutil/trace.go:171","msg":"trace[9208538] transaction","detail":"{read_only:false; response_revision:25799; number_of_response:1; }","duration":"174.022949ms","start":"2026-07-08T09:51:56.229944Z","end":"2026-07-08T09:51:56.403967Z","steps":["trace[9208538] 'process raft request' (duration: 173.869686ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:51:56.497536Z","caller":"traceutil/trace.go:171","msg":"trace[861350544] linearizableReadLoop","detail":"{readStateIndex:26634; appliedIndex:26630; }","duration":"190.179408ms","start":"2026-07-08T09:51:56.307335Z","end":"2026-07-08T09:51:56.497514Z","steps":["trace[861350544] 'read index received' (duration: 96.554417ms)","trace[861350544] 'applied index is now lower than readState.Index' (duration: 93.624161ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:51:56.497742Z","caller":"traceutil/trace.go:171","msg":"trace[1046340967] transaction","detail":"{read_only:false; response_revision:25801; number_of_response:1; }","duration":"191.462645ms","start":"2026-07-08T09:51:56.306264Z","end":"2026-07-08T09:51:56.497727Z","steps":["trace[1046340967] 'process raft request' (duration: 191.116258ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:51:56.497744Z","caller":"traceutil/trace.go:171","msg":"trace[512978014] transaction","detail":"{read_only:false; response_revision:25803; number_of_response:1; }","duration":"184.893178ms","start":"2026-07-08T09:51:56.312833Z","end":"2026-07-08T09:51:56.497726Z","steps":["trace[512978014] 'process raft request' (duration: 184.630743ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:51:56.497865Z","caller":"traceutil/trace.go:171","msg":"trace[2125655964] transaction","detail":"{read_only:false; response_revision:25802; number_of_response:1; }","duration":"191.494796ms","start":"2026-07-08T09:51:56.30636Z","end":"2026-07-08T09:51:56.497855Z","steps":["trace[2125655964] 'process raft request' (duration: 191.068297ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:51:56.497862Z","caller":"traceutil/trace.go:171","msg":"trace[1096283389] transaction","detail":"{read_only:false; response_revision:25800; number_of_response:1; }","duration":"191.73243ms","start":"2026-07-08T09:51:56.306082Z","end":"2026-07-08T09:51:56.497814Z","steps":["trace[1096283389] 'process raft request' (duration: 114.021324ms)","trace[1096283389] 'compare' (duration: 77.126424ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T09:51:56.497923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.604378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T09:51:56.498018Z","caller":"traceutil/trace.go:171","msg":"trace[11367364] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:25803; }","duration":"190.70499ms","start":"2026-07-08T09:51:56.307299Z","end":"2026-07-08T09:51:56.498004Z","steps":["trace[11367364] 'agreement among raft nodes before linearized reading' (duration: 190.577927ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:51:56.497956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.257974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0\" ","response":"range_response_count:1 size:1862"} {"level":"info","ts":"2026-07-08T09:51:56.498124Z","caller":"traceutil/trace.go:171","msg":"trace[28375448] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0; range_end:; response_count:1; response_revision:25803; }","duration":"174.485789ms","start":"2026-07-08T09:51:56.323624Z","end":"2026-07-08T09:51:56.49811Z","steps":["trace[28375448] 'agreement among raft nodes before linearized reading' (duration: 174.118352ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:51:56.737296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.879906ms","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-07-08T09:51:56.737435Z","caller":"traceutil/trace.go:171","msg":"trace[390376446] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25803; }","duration":"134.07845ms","start":"2026-07-08T09:51:56.603336Z","end":"2026-07-08T09:51:56.737415Z","steps":["trace[390376446] 'range keys from in-memory index tree' (duration: 133.587281ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:51:56.964085Z","caller":"traceutil/trace.go:171","msg":"trace[887865773] transaction","detail":"{read_only:false; response_revision:25804; number_of_response:1; }","duration":"222.46418ms","start":"2026-07-08T09:51:56.741589Z","end":"2026-07-08T09:51:56.964053Z","steps":["trace[887865773] 'process raft request' (duration: 221.93501ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:51:56.964531Z","caller":"traceutil/trace.go:171","msg":"trace[1915183635] linearizableReadLoop","detail":"{readStateIndex:26636; appliedIndex:26636; }","duration":"210.857187ms","start":"2026-07-08T09:51:56.75365Z","end":"2026-07-08T09:51:56.964507Z","steps":["trace[1915183635] 'read index received' (duration: 210.846157ms)","trace[1915183635] 'applied index is now lower than readState.Index' (duration: 8.95µs)"],"step_count":2} {"level":"warn","ts":"2026-07-08T09:51:56.964663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.99704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T09:51:56.964711Z","caller":"traceutil/trace.go:171","msg":"trace[1130867500] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25804; }","duration":"211.088212ms","start":"2026-07-08T09:51:56.753611Z","end":"2026-07-08T09:51:56.964699Z","steps":["trace[1130867500] 'agreement among raft nodes before linearized reading' (duration: 210.98261ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:51:57.560836Z","caller":"traceutil/trace.go:171","msg":"trace[1304631213] transaction","detail":"{read_only:false; response_revision:25810; number_of_response:1; }","duration":"247.281318ms","start":"2026-07-08T09:51:57.313533Z","end":"2026-07-08T09:51:57.560815Z","steps":["trace[1304631213] 'process raft request' (duration: 247.208957ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:51:57.560876Z","caller":"traceutil/trace.go:171","msg":"trace[99548535] transaction","detail":"{read_only:false; response_revision:25808; number_of_response:1; }","duration":"249.161565ms","start":"2026-07-08T09:51:57.31167Z","end":"2026-07-08T09:51:57.560832Z","steps":["trace[99548535] 'process raft request' (duration: 151.592867ms)","trace[99548535] 'compare' (duration: 97.115149ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:51:57.560792Z","caller":"traceutil/trace.go:171","msg":"trace[811929075] transaction","detail":"{read_only:false; response_revision:25809; number_of_response:1; }","duration":"248.958861ms","start":"2026-07-08T09:51:57.311815Z","end":"2026-07-08T09:51:57.560774Z","steps":["trace[811929075] 'process raft request' (duration: 248.869689ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:51:58.132Z","caller":"traceutil/trace.go:171","msg":"trace[1127731820] linearizableReadLoop","detail":"{readStateIndex:26643; appliedIndex:26642; }","duration":"137.803821ms","start":"2026-07-08T09:51:57.994178Z","end":"2026-07-08T09:51:58.131982Z","steps":["trace[1127731820] 'read index received' (duration: 137.639788ms)","trace[1127731820] 'applied index is now lower than readState.Index' (duration: 163.203µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:51:58.132228Z","caller":"traceutil/trace.go:171","msg":"trace[939441664] transaction","detail":"{read_only:false; response_revision:25811; number_of_response:1; }","duration":"167.284019ms","start":"2026-07-08T09:51:57.964935Z","end":"2026-07-08T09:51:58.132219Z","steps":["trace[939441664] 'process raft request' (duration: 166.916572ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:51:58.132464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.28393ms","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-07-08T09:51:58.132502Z","caller":"traceutil/trace.go:171","msg":"trace[1452222324] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25811; }","duration":"138.377602ms","start":"2026-07-08T09:51:57.994115Z","end":"2026-07-08T09:51:58.132493Z","steps":["trace[1452222324] 'agreement among raft nodes before linearized reading' (duration: 138.2505ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:51:58.338105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.568727ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-08T09:51:58.33833Z","caller":"traceutil/trace.go:171","msg":"trace[671742815] transaction","detail":"{read_only:false; response_revision:25813; number_of_response:1; }","duration":"201.811653ms","start":"2026-07-08T09:51:58.136505Z","end":"2026-07-08T09:51:58.338317Z","steps":["trace[671742815] 'process raft request' (duration: 201.680211ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:51:58.338412Z","caller":"traceutil/trace.go:171","msg":"trace[695723879] transaction","detail":"{read_only:false; response_revision:25812; number_of_response:1; }","duration":"202.164151ms","start":"2026-07-08T09:51:58.136218Z","end":"2026-07-08T09:51:58.338382Z","steps":["trace[695723879] 'process raft request' (duration: 65.251436ms)","trace[695723879] 'compare' (duration: 136.241671ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:51:58.550726Z","caller":"traceutil/trace.go:171","msg":"trace[411125632] transaction","detail":"{read_only:false; response_revision:25814; number_of_response:1; }","duration":"141.054724ms","start":"2026-07-08T09:51:58.409639Z","end":"2026-07-08T09:51:58.550693Z","steps":["trace[411125632] 'process raft request' (duration: 46.579717ms)","trace[411125632] 'compare' (duration: 94.165851ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:51:58.621512Z","caller":"traceutil/trace.go:171","msg":"trace[2026759558] transaction","detail":"{read_only:false; response_revision:25815; number_of_response:1; }","duration":"118.638983ms","start":"2026-07-08T09:51:58.502848Z","end":"2026-07-08T09:51:58.621487Z","steps":["trace[2026759558] 'process raft request' (duration: 118.438829ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:51:58.725961Z","caller":"traceutil/trace.go:171","msg":"trace[829273617] transaction","detail":"{read_only:false; response_revision:25818; number_of_response:1; }","duration":"170.926409ms","start":"2026-07-08T09:51:58.554987Z","end":"2026-07-08T09:51:58.725913Z","steps":["trace[829273617] 'process raft request' (duration: 170.884999ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:51:58.72611Z","caller":"traceutil/trace.go:171","msg":"trace[2139277514] transaction","detail":"{read_only:false; response_revision:25816; number_of_response:1; }","duration":"223.235756ms","start":"2026-07-08T09:51:58.502863Z","end":"2026-07-08T09:51:58.726099Z","steps":["trace[2139277514] 'process raft request' (duration: 178.492665ms)","trace[2139277514] 'compare' (duration: 44.154ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:51:58.72621Z","caller":"traceutil/trace.go:171","msg":"trace[946215787] transaction","detail":"{read_only:false; response_revision:25817; number_of_response:1; }","duration":"223.259746ms","start":"2026-07-08T09:51:58.502926Z","end":"2026-07-08T09:51:58.726185Z","steps":["trace[946215787] 'process raft request' (duration: 222.89814ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:51:59.294608Z","caller":"traceutil/trace.go:171","msg":"trace[1327493444] linearizableReadLoop","detail":"{readStateIndex:26652; appliedIndex:26651; }","duration":"150.529187ms","start":"2026-07-08T09:51:59.14406Z","end":"2026-07-08T09:51:59.294589Z","steps":["trace[1327493444] 'read index received' (duration: 94.890406ms)","trace[1327493444] 'applied index is now lower than readState.Index' (duration: 55.637831ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:51:59.294636Z","caller":"traceutil/trace.go:171","msg":"trace[949929267] transaction","detail":"{read_only:false; response_revision:25820; number_of_response:1; }","duration":"151.064907ms","start":"2026-07-08T09:51:59.143543Z","end":"2026-07-08T09:51:59.294608Z","steps":["trace[949929267] 'process raft request' (duration: 95.458886ms)","trace[949929267] 'compare' (duration: 55.477688ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T09:51:59.294819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.741281ms","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-07-08T09:51:59.294912Z","caller":"traceutil/trace.go:171","msg":"trace[821764405] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25820; }","duration":"150.867943ms","start":"2026-07-08T09:51:59.144025Z","end":"2026-07-08T09:51:59.294893Z","steps":["trace[821764405] 'agreement among raft nodes before linearized reading' (duration: 150.626968ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:52:14.946095Z","caller":"traceutil/trace.go:171","msg":"trace[1246801008] transaction","detail":"{read_only:false; response_revision:25944; number_of_response:1; }","duration":"182.211836ms","start":"2026-07-08T09:52:14.763848Z","end":"2026-07-08T09:52:14.94606Z","steps":["trace[1246801008] 'process raft request' (duration: 150.912195ms)","trace[1246801008] 'compare' (duration: 30.933005ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:52:14.963493Z","caller":"traceutil/trace.go:171","msg":"trace[1423267966] transaction","detail":"{read_only:false; response_revision:25946; number_of_response:1; }","duration":"164.435273ms","start":"2026-07-08T09:52:14.799023Z","end":"2026-07-08T09:52:14.963458Z","steps":["trace[1423267966] 'process raft request' (duration: 164.360492ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:52:14.96361Z","caller":"traceutil/trace.go:171","msg":"trace[1886690210] transaction","detail":"{read_only:false; response_revision:25945; number_of_response:1; }","duration":"182.246927ms","start":"2026-07-08T09:52:14.781306Z","end":"2026-07-08T09:52:14.963553Z","steps":["trace[1886690210] 'process raft request' (duration: 181.923281ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:52:14.963735Z","caller":"traceutil/trace.go:171","msg":"trace[2084540362] linearizableReadLoop","detail":"{readStateIndex:26782; appliedIndex:26778; }","duration":"156.795207ms","start":"2026-07-08T09:52:14.806914Z","end":"2026-07-08T09:52:14.963709Z","steps":["trace[2084540362] 'read index received' (duration: 107.844736ms)","trace[2084540362] 'applied index is now lower than readState.Index' (duration: 48.949541ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:52:14.963815Z","caller":"traceutil/trace.go:171","msg":"trace[1739530727] transaction","detail":"{read_only:false; response_revision:25947; number_of_response:1; }","duration":"164.696348ms","start":"2026-07-08T09:52:14.799092Z","end":"2026-07-08T09:52:14.963788Z","steps":["trace[1739530727] 'process raft request' (duration: 164.344602ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:52:14.963976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.061353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-08T09:52:14.964044Z","caller":"traceutil/trace.go:171","msg":"trace[578544198] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25947; }","duration":"157.188465ms","start":"2026-07-08T09:52:14.806839Z","end":"2026-07-08T09:52:14.964027Z","steps":["trace[578544198] 'agreement among raft nodes before linearized reading' (duration: 156.970311ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:52:20.487037Z","caller":"traceutil/trace.go:171","msg":"trace[48546253] transaction","detail":"{read_only:false; response_revision:25984; number_of_response:1; }","duration":"173.197803ms","start":"2026-07-08T09:52:20.313813Z","end":"2026-07-08T09:52:20.487011Z","steps":["trace[48546253] 'process raft request' (duration: 155.8739ms)","trace[48546253] 'compare' (duration: 16.899835ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:52:20.487007Z","caller":"traceutil/trace.go:171","msg":"trace[1212445065] linearizableReadLoop","detail":"{readStateIndex:26820; appliedIndex:26819; }","duration":"159.510949ms","start":"2026-07-08T09:52:20.327463Z","end":"2026-07-08T09:52:20.486974Z","steps":["trace[1212445065] 'read index received' (duration: 142.103475ms)","trace[1212445065] 'applied index is now lower than readState.Index' (duration: 17.405784ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T09:52:20.487186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.731994ms","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-07-08T09:52:20.48735Z","caller":"traceutil/trace.go:171","msg":"trace[1842505649] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25984; }","duration":"159.902946ms","start":"2026-07-08T09:52:20.327388Z","end":"2026-07-08T09:52:20.487291Z","steps":["trace[1842505649] 'agreement among raft nodes before linearized reading' (duration: 159.718413ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:52:20.709915Z","caller":"traceutil/trace.go:171","msg":"trace[1008030841] linearizableReadLoop","detail":"{readStateIndex:26821; appliedIndex:26820; }","duration":"216.830333ms","start":"2026-07-08T09:52:20.493062Z","end":"2026-07-08T09:52:20.709892Z","steps":["trace[1008030841] 'read index received' (duration: 129.692496ms)","trace[1008030841] 'applied index is now lower than readState.Index' (duration: 87.136347ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T09:52:20.710095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.016347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-07-08T09:52:20.710134Z","caller":"traceutil/trace.go:171","msg":"trace[1929202483] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25987; }","duration":"217.096068ms","start":"2026-07-08T09:52:20.493027Z","end":"2026-07-08T09:52:20.710123Z","steps":["trace[1929202483] 'agreement among raft nodes before linearized reading' (duration: 216.952575ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:52:20.710843Z","caller":"traceutil/trace.go:171","msg":"trace[332310613] transaction","detail":"{read_only:false; response_revision:25985; number_of_response:1; }","duration":"218.235368ms","start":"2026-07-08T09:52:20.491442Z","end":"2026-07-08T09:52:20.709677Z","steps":["trace[332310613] 'process raft request' (duration: 131.368727ms)","trace[332310613] 'compare' (duration: 85.534236ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T09:52:20.864773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.007546ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-08T09:52:20.864929Z","caller":"traceutil/trace.go:171","msg":"trace[1084987192] linearizableReadLoop","detail":"{readStateIndex:26824; appliedIndex:26823; }","duration":"141.385661ms","start":"2026-07-08T09:52:20.723518Z","end":"2026-07-08T09:52:20.864904Z","steps":["trace[1084987192] 'read index received' (duration: 2.936576ms)","trace[1084987192] 'applied index is now lower than readState.Index' (duration: 138.447045ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T09:52:20.865187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.651156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5684"} {"level":"info","ts":"2026-07-08T09:52:20.865235Z","caller":"traceutil/trace.go:171","msg":"trace[686441509] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:25987; }","duration":"141.727638ms","start":"2026-07-08T09:52:20.723491Z","end":"2026-07-08T09:52:20.865219Z","steps":["trace[686441509] 'agreement among raft nodes before linearized reading' (duration: 141.483133ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:52:21.135029Z","caller":"traceutil/trace.go:171","msg":"trace[1434504968] transaction","detail":"{read_only:false; response_revision:25988; number_of_response:1; }","duration":"169.019212ms","start":"2026-07-08T09:52:20.96598Z","end":"2026-07-08T09:52:21.134999Z","steps":["trace[1434504968] 'process raft request' (duration: 168.787847ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:52:21.255785Z","caller":"traceutil/trace.go:171","msg":"trace[327598008] linearizableReadLoop","detail":"{readStateIndex:26827; appliedIndex:26824; }","duration":"264.860956ms","start":"2026-07-08T09:52:20.990902Z","end":"2026-07-08T09:52:21.255763Z","steps":["trace[327598008] 'read index received' (duration: 143.837547ms)","trace[327598008] 'applied index is now lower than readState.Index' (duration: 121.022559ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:52:21.256133Z","caller":"traceutil/trace.go:171","msg":"trace[1811120004] transaction","detail":"{read_only:false; response_revision:25989; number_of_response:1; }","duration":"273.280809ms","start":"2026-07-08T09:52:20.982839Z","end":"2026-07-08T09:52:21.25612Z","steps":["trace[1811120004] 'process raft request' (duration: 272.758518ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:52:21.25633Z","caller":"traceutil/trace.go:171","msg":"trace[1729198620] transaction","detail":"{read_only:false; response_revision:25990; number_of_response:1; }","duration":"270.160419ms","start":"2026-07-08T09:52:20.986159Z","end":"2026-07-08T09:52:21.25632Z","steps":["trace[1729198620] 'process raft request' (duration: 269.563357ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:52:21.256602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.702483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-08T09:52:21.25665Z","caller":"traceutil/trace.go:171","msg":"trace[683497774] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25990; }","duration":"265.809305ms","start":"2026-07-08T09:52:20.990829Z","end":"2026-07-08T09:52:21.256638Z","steps":["trace[683497774] 'agreement among raft nodes before linearized reading' (duration: 265.668182ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:52:21.256818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.545333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T09:52:21.256854Z","caller":"traceutil/trace.go:171","msg":"trace[923503986] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:25990; }","duration":"221.623475ms","start":"2026-07-08T09:52:21.035222Z","end":"2026-07-08T09:52:21.256845Z","steps":["trace[923503986] 'agreement among raft nodes before linearized reading' (duration: 221.558544ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:52:21.257465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.762668ms","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-07-08T09:52:21.25752Z","caller":"traceutil/trace.go:171","msg":"trace[1026774507] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25990; }","duration":"102.88596ms","start":"2026-07-08T09:52:21.154619Z","end":"2026-07-08T09:52:21.257505Z","steps":["trace[1026774507] 'agreement among raft nodes before linearized reading' (duration: 102.702117ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:52:21.257726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.142001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1125"} {"level":"info","ts":"2026-07-08T09:52:21.257756Z","caller":"traceutil/trace.go:171","msg":"trace[21396078] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:25990; }","duration":"119.172622ms","start":"2026-07-08T09:52:21.138577Z","end":"2026-07-08T09:52:21.257749Z","steps":["trace[21396078] 'agreement among raft nodes before linearized reading' (duration: 119.09222ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:52:21.257881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.412267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1125"} {"level":"info","ts":"2026-07-08T09:52:21.257943Z","caller":"traceutil/trace.go:171","msg":"trace[491297306] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:25990; }","duration":"119.474838ms","start":"2026-07-08T09:52:21.138455Z","end":"2026-07-08T09:52:21.25793Z","steps":["trace[491297306] 'agreement among raft nodes before linearized reading' (duration: 119.328956ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:52:21.459856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.513189ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T09:52:21.459985Z","caller":"traceutil/trace.go:171","msg":"trace[1951897194] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:25991; }","duration":"107.651801ms","start":"2026-07-08T09:52:21.352314Z","end":"2026-07-08T09:52:21.459966Z","steps":["trace[1951897194] 'range keys from in-memory index tree' (duration: 107.498758ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:52:21.460498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.688103ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-08T09:52:21.460673Z","caller":"traceutil/trace.go:171","msg":"trace[137709177] transaction","detail":"{read_only:false; response_revision:25992; number_of_response:1; }","duration":"197.155354ms","start":"2026-07-08T09:52:21.263494Z","end":"2026-07-08T09:52:21.46065Z","steps":["trace[137709177] 'process raft request' (duration: 91.195675ms)","trace[137709177] 'compare' (duration: 105.52098ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:52:21.460851Z","caller":"traceutil/trace.go:171","msg":"trace[194504927] linearizableReadLoop","detail":"{readStateIndex:26829; appliedIndex:26828; }","duration":"183.196816ms","start":"2026-07-08T09:52:21.277608Z","end":"2026-07-08T09:52:21.460804Z","steps":["trace[194504927] 'read index received' (duration: 77.109035ms)","trace[194504927] 'applied index is now lower than readState.Index' (duration: 106.084481ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T09:52:21.461054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.42244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1344"} {"level":"info","ts":"2026-07-08T09:52:21.46113Z","caller":"traceutil/trace.go:171","msg":"trace[264887455] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:25993; }","duration":"183.534262ms","start":"2026-07-08T09:52:21.277574Z","end":"2026-07-08T09:52:21.461108Z","steps":["trace[264887455] 'agreement among raft nodes before linearized reading' (duration: 183.303477ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:52:52.637514Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23348} {"level":"info","ts":"2026-07-08T09:52:52.768528Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23348,"took":"124.629617ms","hash":3798177672,"current-db-size-bytes":61067264,"current-db-size":"61 MB","current-db-size-in-use-bytes":39952384,"current-db-size-in-use":"40 MB"} {"level":"info","ts":"2026-07-08T09:52:52.768586Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3798177672,"revision":23348,"compact-revision":20429} {"level":"warn","ts":"2026-07-08T09:55:24.837179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.737528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"warn","ts":"2026-07-08T09:55:24.837244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.040528ms","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-07-08T09:55:24.837261Z","caller":"traceutil/trace.go:171","msg":"trace[1520617148] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:27917; }","duration":"222.830479ms","start":"2026-07-08T09:55:24.614415Z","end":"2026-07-08T09:55:24.837245Z","steps":["trace[1520617148] 'range keys from in-memory index tree' (duration: 222.602375ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:55:24.837273Z","caller":"traceutil/trace.go:171","msg":"trace[1728752562] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27917; }","duration":"217.102629ms","start":"2026-07-08T09:55:24.620161Z","end":"2026-07-08T09:55:24.837263Z","steps":["trace[1728752562] 'range keys from in-memory index tree' (duration: 216.940325ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:55:34.17506Z","caller":"traceutil/trace.go:171","msg":"trace[1791797627] transaction","detail":"{read_only:false; response_revision:28059; number_of_response:1; }","duration":"167.394913ms","start":"2026-07-08T09:55:34.007634Z","end":"2026-07-08T09:55:34.175029Z","steps":["trace[1791797627] 'process raft request' (duration: 131.993331ms)","trace[1791797627] 'compare' (duration: 35.261699ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:55:34.348311Z","caller":"traceutil/trace.go:171","msg":"trace[1142528807] linearizableReadLoop","detail":"{readStateIndex:28951; appliedIndex:28949; }","duration":"119.739705ms","start":"2026-07-08T09:55:34.228554Z","end":"2026-07-08T09:55:34.348293Z","steps":["trace[1142528807] 'read index received' (duration: 100.219649ms)","trace[1142528807] 'applied index is now lower than readState.Index' (duration: 19.518956ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T09:55:34.3485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.925579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-08T09:55:34.348529Z","caller":"traceutil/trace.go:171","msg":"trace[1651535579] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:28061; }","duration":"120.016001ms","start":"2026-07-08T09:55:34.228505Z","end":"2026-07-08T09:55:34.348521Z","steps":["trace[1651535579] 'agreement among raft nodes before linearized reading' (duration: 119.869087ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:55:34.348615Z","caller":"traceutil/trace.go:171","msg":"trace[1280741423] transaction","detail":"{read_only:false; response_revision:28060; number_of_response:1; }","duration":"177.840134ms","start":"2026-07-08T09:55:34.170751Z","end":"2026-07-08T09:55:34.348591Z","steps":["trace[1280741423] 'process raft request' (duration: 158.041153ms)","trace[1280741423] 'compare' (duration: 19.368342ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:55:34.34867Z","caller":"traceutil/trace.go:171","msg":"trace[937740894] transaction","detail":"{read_only:false; response_revision:28061; number_of_response:1; }","duration":"161.408838ms","start":"2026-07-08T09:55:34.187253Z","end":"2026-07-08T09:55:34.348661Z","steps":["trace[937740894] 'process raft request' (duration: 161.006219ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:57:45.923025Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3ba9ae53cc4335db","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-08T09:57:45.928166Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-07-08T09:57:45.928258Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-07-08T09:57:52.644204Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26396} {"level":"info","ts":"2026-07-08T09:57:52.802981Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26396,"took":"152.277124ms","hash":1318486139,"current-db-size-bytes":61489152,"current-db-size":"62 MB","current-db-size-in-use-bytes":36151296,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-07-08T09:57:52.803046Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1318486139,"revision":26396,"compact-revision":23348} {"level":"info","ts":"2026-07-08T10:02:52.650587Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":29130} {"level":"info","ts":"2026-07-08T10:02:52.791674Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":29130,"took":"136.613498ms","hash":2017686198,"current-db-size-bytes":61489152,"current-db-size":"62 MB","current-db-size-in-use-bytes":32739328,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-07-08T10:02:52.791728Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2017686198,"revision":29130,"compact-revision":26396} {"level":"info","ts":"2026-07-08T10:07:52.657996Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31457} {"level":"info","ts":"2026-07-08T10:07:52.800853Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31457,"took":"138.626637ms","hash":1300361123,"current-db-size-bytes":61489152,"current-db-size":"62 MB","current-db-size-in-use-bytes":31440896,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-08T10:07:52.800921Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1300361123,"revision":31457,"compact-revision":29130} {"level":"info","ts":"2026-07-08T10:08:21.19947Z","caller":"traceutil/trace.go:171","msg":"trace[71369422] transaction","detail":"{read_only:false; response_revision:34001; number_of_response:1; }","duration":"329.045006ms","start":"2026-07-08T10:08:20.870393Z","end":"2026-07-08T10:08:21.199438Z","steps":["trace[71369422] 'process raft request' (duration: 328.911823ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T10:08:21.199667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T10:08:20.870375Z","time spent":"329.189749ms","remote":"127.0.0.1:34196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T10:08:21.200914Z","caller":"traceutil/trace.go:171","msg":"trace[1095143639] transaction","detail":"{read_only:false; response_revision:34002; number_of_response:1; }","duration":"287.071164ms","start":"2026-07-08T10:08:20.913825Z","end":"2026-07-08T10:08:21.200896Z","steps":["trace[1095143639] 'process raft request' (duration: 286.614805ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T10:08:21.203173Z","caller":"traceutil/trace.go:171","msg":"trace[1887398189] linearizableReadLoop","detail":"{readStateIndex:35061; appliedIndex:35059; }","duration":"157.692166ms","start":"2026-07-08T10:08:21.045457Z","end":"2026-07-08T10:08:21.203149Z","steps":["trace[1887398189] 'read index received' (duration: 153.888393ms)","trace[1887398189] 'applied index is now lower than readState.Index' (duration: 3.802893ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T10:08:21.203291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.816078ms","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-07-08T10:08:21.203325Z","caller":"traceutil/trace.go:171","msg":"trace[1224673694] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:34003; }","duration":"157.868659ms","start":"2026-07-08T10:08:21.045445Z","end":"2026-07-08T10:08:21.203314Z","steps":["trace[1224673694] 'agreement among raft nodes before linearized reading' (duration: 157.790797ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T10:12:52.668686Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33795} {"level":"info","ts":"2026-07-08T10:12:52.841638Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33795,"took":"168.603677ms","hash":2014348836,"current-db-size-bytes":62533632,"current-db-size":"62 MB","current-db-size-in-use-bytes":33193984,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-07-08T10:12:52.841747Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2014348836,"revision":33795,"compact-revision":31457} {"level":"info","ts":"2026-07-08T10:17:52.674885Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":36126} {"level":"info","ts":"2026-07-08T10:17:52.831249Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":36126,"took":"148.114438ms","hash":1124093977,"current-db-size-bytes":65150976,"current-db-size":"65 MB","current-db-size-in-use-bytes":32952320,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-07-08T10:17:52.831329Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1124093977,"revision":36126,"compact-revision":33795} {"level":"info","ts":"2026-07-08T10:18:47.560576Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3ba9ae53cc4335db","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-08T10:18:47.565318Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-07-08T10:18:47.565437Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004}