{"level":"warn","ts":"2026-08-12T00:18:20.636007Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-12T00:18:20.636119Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.109: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.109:2380","--initial-cluster=instance=https://199.204.45.109:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.109:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.109: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-08-12T00:18:20.636205Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-12T00:18:20.636225Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-08-12T00:18:20.63625Z","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-08-12T00:18:20.637085Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"]} {"level":"info","ts":"2026-08-12T00:18:20.637188Z","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.109:2380"],"listen-peer-urls":["https://199.204.45.109:2380"],"advertise-client-urls":["https://199.204.45.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.109: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-08-12T00:18:20.640029Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.600542ms"} {"level":"info","ts":"2026-08-12T00:18:20.648318Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d71a1778b31d4ce","cluster-id":"aaca35652fabf9b2"} {"level":"info","ts":"2026-08-12T00:18:20.648421Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=()"} {"level":"info","ts":"2026-08-12T00:18:20.648447Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became follower at term 0"} {"level":"info","ts":"2026-08-12T00:18:20.648459Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d71a1778b31d4ce [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-12T00:18:20.648471Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became follower at term 1"} {"level":"info","ts":"2026-08-12T00:18:20.648504Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=(968732929669846222)"} {"level":"warn","ts":"2026-08-12T00:18:20.652903Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-12T00:18:20.655622Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-12T00:18:20.657111Z","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-08-12T00:18:20.659363Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d71a1778b31d4ce","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-12T00:18:20.659582Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d71a1778b31d4ce","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-12T00:18:20.659712Z","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-08-12T00:18:20.659821Z","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-08-12T00:18:20.659838Z","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-08-12T00:18:20.660612Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=(968732929669846222)"} {"level":"info","ts":"2026-08-12T00:18:20.660778Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"aaca35652fabf9b2","local-member-id":"d71a1778b31d4ce","added-peer-id":"d71a1778b31d4ce","added-peer-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-08-12T00:18:20.662163Z","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-08-12T00:18:20.662335Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.109:2380"} {"level":"info","ts":"2026-08-12T00:18:20.662379Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.109:2380"} {"level":"info","ts":"2026-08-12T00:18:20.662454Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d71a1778b31d4ce","initial-advertise-peer-urls":["https://199.204.45.109:2380"],"listen-peer-urls":["https://199.204.45.109:2380"],"advertise-client-urls":["https://199.204.45.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-12T00:18:20.662493Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-12T00:18:20.949673Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce is starting a new election at term 1"} {"level":"info","ts":"2026-08-12T00:18:20.94978Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became pre-candidate at term 1"} {"level":"info","ts":"2026-08-12T00:18:20.949808Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce received MsgPreVoteResp from d71a1778b31d4ce at term 1"} {"level":"info","ts":"2026-08-12T00:18:20.94983Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became candidate at term 2"} {"level":"info","ts":"2026-08-12T00:18:20.949845Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce received MsgVoteResp from d71a1778b31d4ce at term 2"} {"level":"info","ts":"2026-08-12T00:18:20.949863Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became leader at term 2"} {"level":"info","ts":"2026-08-12T00:18:20.949883Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d71a1778b31d4ce elected leader d71a1778b31d4ce at term 2"} {"level":"info","ts":"2026-08-12T00:18:20.951973Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d71a1778b31d4ce","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.109:2379]}","request-path":"/0/members/d71a1778b31d4ce/attributes","cluster-id":"aaca35652fabf9b2","publish-timeout":"7s"} {"level":"info","ts":"2026-08-12T00:18:20.952015Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-12T00:18:20.95203Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-12T00:18:20.952146Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-12T00:18:20.952356Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-12T00:18:20.952494Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-12T00:18:20.953314Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"aaca35652fabf9b2","local-member-id":"d71a1778b31d4ce","cluster-version":"3.5"} {"level":"info","ts":"2026-08-12T00:18:20.953529Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-12T00:18:20.953575Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-12T00:18:20.955558Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-12T00:18:20.956106Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.109:2379"} {"level":"info","ts":"2026-08-12T00:18:56.839861Z","caller":"traceutil/trace.go:171","msg":"trace[1058796101] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"102.568008ms","start":"2026-08-12T00:18:56.73727Z","end":"2026-08-12T00:18:56.839838Z","steps":["trace[1058796101] 'process raft request' (duration: 102.525627ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:18:56.839933Z","caller":"traceutil/trace.go:171","msg":"trace[585666397] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"103.020509ms","start":"2026-08-12T00:18:56.736861Z","end":"2026-08-12T00:18:56.839882Z","steps":["trace[585666397] 'process raft request' (duration: 88.760083ms)","trace[585666397] 'compare' (duration: 13.984629ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T00:18:56.840363Z","caller":"traceutil/trace.go:171","msg":"trace[1032618557] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"101.054361ms","start":"2026-08-12T00:18:56.739296Z","end":"2026-08-12T00:18:56.840351Z","steps":["trace[1032618557] 'process raft request' (duration: 100.790745ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:19:02.843101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.013729ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T00:19:02.843293Z","caller":"traceutil/trace.go:171","msg":"trace[934896062] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:604; }","duration":"177.989778ms","start":"2026-08-12T00:19:02.665237Z","end":"2026-08-12T00:19:02.843227Z","steps":["trace[934896062] 'read index received' (duration: 66.874862ms)","trace[934896062] 'applied index is now lower than readState.Index' (duration: 111.113686ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T00:19:02.843306Z","caller":"traceutil/trace.go:171","msg":"trace[198784168] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"233.961376ms","start":"2026-08-12T00:19:02.609312Z","end":"2026-08-12T00:19:02.843273Z","steps":["trace[198784168] 'process raft request' (duration: 123.110227ms)","trace[198784168] 'compare' (duration: 109.688981ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T00:19:02.843535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.245835ms","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-08-12T00:19:02.84361Z","caller":"traceutil/trace.go:171","msg":"trace[1320403298] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:585; }","duration":"178.405678ms","start":"2026-08-12T00:19:02.665185Z","end":"2026-08-12T00:19:02.843591Z","steps":["trace[1320403298] 'agreement among raft nodes before linearized reading' (duration: 178.160582ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:19:03.245234Z","caller":"traceutil/trace.go:171","msg":"trace[1131416836] linearizableReadLoop","detail":"{readStateIndex:609; appliedIndex:608; }","duration":"295.308894ms","start":"2026-08-12T00:19:02.949885Z","end":"2026-08-12T00:19:03.245194Z","steps":["trace[1131416836] 'read index received' (duration: 274.49212ms)","trace[1131416836] 'applied index is now lower than readState.Index' (duration: 20.809154ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T00:19:03.245404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.499068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T00:19:03.245352Z","caller":"traceutil/trace.go:171","msg":"trace[627561200] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"304.337074ms","start":"2026-08-12T00:19:02.940977Z","end":"2026-08-12T00:19:03.245314Z","steps":["trace[627561200] 'process raft request' (duration: 283.465738ms)","trace[627561200] 'compare' (duration: 20.429965ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T00:19:03.24551Z","caller":"traceutil/trace.go:171","msg":"trace[1291608289] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:589; }","duration":"295.640812ms","start":"2026-08-12T00:19:02.949836Z","end":"2026-08-12T00:19:03.245477Z","steps":["trace[1291608289] 'agreement among raft nodes before linearized reading' (duration: 295.486768ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:19:03.245762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:19:02.94096Z","time spent":"304.695452ms","remote":"127.0.0.1:35408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T00:19:03.245811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.467814ms","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-08-12T00:19:03.246021Z","caller":"traceutil/trace.go:171","msg":"trace[412542798] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:589; }","duration":"179.74588ms","start":"2026-08-12T00:19:03.066255Z","end":"2026-08-12T00:19:03.246001Z","steps":["trace[412542798] 'agreement among raft nodes before linearized reading' (duration: 179.151146ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:19:03.385384Z","caller":"traceutil/trace.go:171","msg":"trace[1063765363] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"135.23536ms","start":"2026-08-12T00:19:03.24994Z","end":"2026-08-12T00:19:03.385176Z","steps":["trace[1063765363] 'process raft request' (duration: 108.156043ms)","trace[1063765363] 'compare' (duration: 26.533294ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T00:20:29.507159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.806993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-12T00:20:29.507281Z","caller":"traceutil/trace.go:171","msg":"trace[1075465451] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1795; }","duration":"307.044029ms","start":"2026-08-12T00:20:29.20021Z","end":"2026-08-12T00:20:29.507254Z","steps":["trace[1075465451] 'count revisions from in-memory index tree' (duration: 306.67796ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:20:29.507341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:20:29.200187Z","time spent":"307.137702ms","remote":"127.0.0.1:52968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-08-12T00:20:29.51043Z","caller":"traceutil/trace.go:171","msg":"trace[835907980] linearizableReadLoop","detail":"{readStateIndex:1862; appliedIndex:1861; }","duration":"210.266067ms","start":"2026-08-12T00:20:29.300119Z","end":"2026-08-12T00:20:29.510385Z","steps":["trace[835907980] 'read index received' (duration: 210.024552ms)","trace[835907980] 'applied index is now lower than readState.Index' (duration: 239.435µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T00:20:29.510675Z","caller":"traceutil/trace.go:171","msg":"trace[7031328] transaction","detail":"{read_only:false; response_revision:1796; number_of_response:1; }","duration":"239.228046ms","start":"2026-08-12T00:20:29.271431Z","end":"2026-08-12T00:20:29.510659Z","steps":["trace[7031328] 'process raft request' (duration: 238.695993ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:20:29.510817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.710879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:10668"} {"level":"info","ts":"2026-08-12T00:20:29.510879Z","caller":"traceutil/trace.go:171","msg":"trace[1674725001] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:1796; }","duration":"210.838571ms","start":"2026-08-12T00:20:29.300025Z","end":"2026-08-12T00:20:29.510863Z","steps":["trace[1674725001] 'agreement among raft nodes before linearized reading' (duration: 210.623056ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:20:29.569928Z","caller":"traceutil/trace.go:171","msg":"trace[113489532] transaction","detail":"{read_only:false; response_revision:1797; number_of_response:1; }","duration":"254.374452ms","start":"2026-08-12T00:20:29.315528Z","end":"2026-08-12T00:20:29.569902Z","steps":["trace[113489532] 'process raft request' (duration: 254.111746ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:20:29.570029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.584382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-08-12T00:20:29.569961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.955943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-12T00:20:29.570231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.176565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4607986b-9c43-411b-be29-edfe85cdf5eb\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-08-12T00:20:29.570277Z","caller":"traceutil/trace.go:171","msg":"trace[558765320] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4607986b-9c43-411b-be29-edfe85cdf5eb; range_end:; response_count:1; response_revision:1797; }","duration":"160.235626ms","start":"2026-08-12T00:20:29.41003Z","end":"2026-08-12T00:20:29.570265Z","steps":["trace[558765320] 'agreement among raft nodes before linearized reading' (duration: 160.051251ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:20:29.570391Z","caller":"traceutil/trace.go:171","msg":"trace[220768869] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1797; }","duration":"138.360653ms","start":"2026-08-12T00:20:29.431866Z","end":"2026-08-12T00:20:29.570226Z","steps":["trace[220768869] 'agreement among raft nodes before linearized reading' (duration: 137.999684ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:20:29.570116Z","caller":"traceutil/trace.go:171","msg":"trace[980483835] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1797; }","duration":"251.734287ms","start":"2026-08-12T00:20:29.318361Z","end":"2026-08-12T00:20:29.570096Z","steps":["trace[980483835] 'agreement among raft nodes before linearized reading' (duration: 251.47175ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:20:29.648783Z","caller":"traceutil/trace.go:171","msg":"trace[1671479872] transaction","detail":"{read_only:false; response_revision:1798; number_of_response:1; }","duration":"128.722604ms","start":"2026-08-12T00:20:29.52002Z","end":"2026-08-12T00:20:29.648742Z","steps":["trace[1671479872] 'process raft request' (duration: 101.400196ms)","trace[1671479872] 'compare' (duration: 27.050551ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T00:20:29.955312Z","caller":"traceutil/trace.go:171","msg":"trace[1472570663] transaction","detail":"{read_only:false; response_revision:1806; number_of_response:1; }","duration":"173.186157ms","start":"2026-08-12T00:20:29.782095Z","end":"2026-08-12T00:20:29.955281Z","steps":["trace[1472570663] 'process raft request' (duration: 172.968142ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:20:29.955515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.60154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-12T00:20:29.955583Z","caller":"traceutil/trace.go:171","msg":"trace[1817964847] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1806; }","duration":"136.729003ms","start":"2026-08-12T00:20:29.818834Z","end":"2026-08-12T00:20:29.955563Z","steps":["trace[1817964847] 'agreement among raft nodes before linearized reading' (duration: 136.526438ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:20:29.955296Z","caller":"traceutil/trace.go:171","msg":"trace[618341671] linearizableReadLoop","detail":"{readStateIndex:1873; appliedIndex:1872; }","duration":"136.340323ms","start":"2026-08-12T00:20:29.818896Z","end":"2026-08-12T00:20:29.955236Z","steps":["trace[618341671] 'read index received' (duration: 136.096047ms)","trace[618341671] 'applied index is now lower than readState.Index' (duration: 243.366µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T00:20:41.221847Z","caller":"traceutil/trace.go:171","msg":"trace[781888842] linearizableReadLoop","detail":"{readStateIndex:1970; appliedIndex:1969; }","duration":"189.504634ms","start":"2026-08-12T00:20:41.032316Z","end":"2026-08-12T00:20:41.221821Z","steps":["trace[781888842] 'read index received' (duration: 189.32012ms)","trace[781888842] 'applied index is now lower than readState.Index' (duration: 183.444µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T00:20:41.221891Z","caller":"traceutil/trace.go:171","msg":"trace[173615252] transaction","detail":"{read_only:false; response_revision:1900; number_of_response:1; }","duration":"197.205456ms","start":"2026-08-12T00:20:41.024653Z","end":"2026-08-12T00:20:41.221858Z","steps":["trace[173615252] 'process raft request' (duration: 196.985241ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:20:41.222065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.73316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-08-12T00:20:41.222089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.197366ms","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-08-12T00:20:41.222125Z","caller":"traceutil/trace.go:171","msg":"trace[1664177519] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1900; }","duration":"189.834412ms","start":"2026-08-12T00:20:41.032273Z","end":"2026-08-12T00:20:41.222108Z","steps":["trace[1664177519] 'agreement among raft nodes before linearized reading' (duration: 189.646508ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:20:41.222148Z","caller":"traceutil/trace.go:171","msg":"trace[640996816] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1900; }","duration":"143.277718ms","start":"2026-08-12T00:20:41.078849Z","end":"2026-08-12T00:20:41.222127Z","steps":["trace[640996816] 'agreement among raft nodes before linearized reading' (duration: 143.144244ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:21:13.877492Z","caller":"traceutil/trace.go:171","msg":"trace[192061084] transaction","detail":"{read_only:false; response_revision:2090; number_of_response:1; }","duration":"145.466222ms","start":"2026-08-12T00:21:13.731982Z","end":"2026-08-12T00:21:13.877449Z","steps":["trace[192061084] 'process raft request' (duration: 145.126003ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:21:14.35485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.709065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-08-12T00:21:14.354948Z","caller":"traceutil/trace.go:171","msg":"trace[1523783560] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2094; }","duration":"103.864149ms","start":"2026-08-12T00:21:14.251061Z","end":"2026-08-12T00:21:14.354925Z","steps":["trace[1523783560] 'range keys from in-memory index tree' (duration: 103.458349ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:21:19.543227Z","caller":"traceutil/trace.go:171","msg":"trace[1371624558] linearizableReadLoop","detail":"{readStateIndex:2198; appliedIndex:2197; }","duration":"111.416617ms","start":"2026-08-12T00:21:19.431769Z","end":"2026-08-12T00:21:19.543185Z","steps":["trace[1371624558] 'read index received' (duration: 90.321283ms)","trace[1371624558] 'applied index is now lower than readState.Index' (duration: 21.094004ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T00:21:19.543399Z","caller":"traceutil/trace.go:171","msg":"trace[1047573311] transaction","detail":"{read_only:false; response_revision:2120; number_of_response:1; }","duration":"120.338998ms","start":"2026-08-12T00:21:19.423018Z","end":"2026-08-12T00:21:19.543357Z","steps":["trace[1047573311] 'process raft request' (duration: 98.957677ms)","trace[1047573311] 'compare' (duration: 21.048143ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T00:21:19.543568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.786175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T00:21:19.543617Z","caller":"traceutil/trace.go:171","msg":"trace[1321156509] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2120; }","duration":"111.877638ms","start":"2026-08-12T00:21:19.431729Z","end":"2026-08-12T00:21:19.543607Z","steps":["trace[1321156509] 'agreement among raft nodes before linearized reading' (duration: 111.650802ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:21:20.117938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.267492ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T00:21:20.118089Z","caller":"traceutil/trace.go:171","msg":"trace[380674108] linearizableReadLoop","detail":"{readStateIndex:2200; appliedIndex:2199; }","duration":"193.871164ms","start":"2026-08-12T00:21:19.924196Z","end":"2026-08-12T00:21:20.118068Z","steps":["trace[380674108] 'read index received' (duration: 74.276624ms)","trace[380674108] 'applied index is now lower than readState.Index' (duration: 119.592829ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T00:21:20.118254Z","caller":"traceutil/trace.go:171","msg":"trace[1079432839] transaction","detail":"{read_only:false; response_revision:2122; number_of_response:1; }","duration":"216.347821ms","start":"2026-08-12T00:21:19.901836Z","end":"2026-08-12T00:21:20.118184Z","steps":["trace[1079432839] 'process raft request' (duration: 96.6962ms)","trace[1079432839] 'compare' (duration: 118.937564ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T00:21:20.118413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.716466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-08-12T00:21:20.118455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.243152ms","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-08-12T00:21:20.118502Z","caller":"traceutil/trace.go:171","msg":"trace[2102309360] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2122; }","duration":"124.847669ms","start":"2026-08-12T00:21:19.993631Z","end":"2026-08-12T00:21:20.118479Z","steps":["trace[2102309360] 'agreement among raft nodes before linearized reading' (duration: 124.568862ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:21:20.118529Z","caller":"traceutil/trace.go:171","msg":"trace[10054] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2122; }","duration":"194.366165ms","start":"2026-08-12T00:21:19.924144Z","end":"2026-08-12T00:21:20.11851Z","steps":["trace[10054] 'agreement among raft nodes before linearized reading' (duration: 194.002906ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:21:20.259222Z","caller":"traceutil/trace.go:171","msg":"trace[606419945] transaction","detail":"{read_only:false; response_revision:2123; number_of_response:1; }","duration":"135.839372ms","start":"2026-08-12T00:21:20.123344Z","end":"2026-08-12T00:21:20.259183Z","steps":["trace[606419945] 'process raft request' (duration: 135.621587ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:21:20.269734Z","caller":"traceutil/trace.go:171","msg":"trace[2156095] transaction","detail":"{read_only:false; response_revision:2124; number_of_response:1; }","duration":"146.360453ms","start":"2026-08-12T00:21:20.123356Z","end":"2026-08-12T00:21:20.269716Z","steps":["trace[2156095] 'process raft request' (duration: 145.976014ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:26:39.498512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.485481ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-12T00:26:39.498812Z","caller":"traceutil/trace.go:171","msg":"trace[1989460123] linearizableReadLoop","detail":"{readStateIndex:4976; appliedIndex:4974; }","duration":"195.466856ms","start":"2026-08-12T00:26:39.303324Z","end":"2026-08-12T00:26:39.498791Z","steps":["trace[1989460123] 'read index received' (duration: 64.782159ms)","trace[1989460123] 'applied index is now lower than readState.Index' (duration: 130.683346ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T00:26:39.498925Z","caller":"traceutil/trace.go:171","msg":"trace[1317211914] transaction","detail":"{read_only:false; response_revision:4794; number_of_response:1; }","duration":"201.970404ms","start":"2026-08-12T00:26:39.296915Z","end":"2026-08-12T00:26:39.498885Z","steps":["trace[1317211914] 'process raft request' (duration: 201.729767ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:26:39.499029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.694112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-08-12T00:26:39.499075Z","caller":"traceutil/trace.go:171","msg":"trace[900129241] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4794; }","duration":"195.800944ms","start":"2026-08-12T00:26:39.303258Z","end":"2026-08-12T00:26:39.499059Z","steps":["trace[900129241] 'agreement among raft nodes before linearized reading' (duration: 195.64215ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:26:39.499204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.913854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d86c654a-79a7-4830-97cb-c751eb31b225\" ","response":"range_response_count:1 size:4064"} {"level":"info","ts":"2026-08-12T00:26:39.499301Z","caller":"traceutil/trace.go:171","msg":"trace[709677635] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d86c654a-79a7-4830-97cb-c751eb31b225; range_end:; response_count:1; response_revision:4794; }","duration":"108.071028ms","start":"2026-08-12T00:26:39.39121Z","end":"2026-08-12T00:26:39.499281Z","steps":["trace[709677635] 'agreement among raft nodes before linearized reading' (duration: 107.783801ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:26:43.057167Z","caller":"traceutil/trace.go:171","msg":"trace[1738866882] transaction","detail":"{read_only:false; response_revision:4950; number_of_response:1; }","duration":"252.440956ms","start":"2026-08-12T00:26:42.804696Z","end":"2026-08-12T00:26:43.057137Z","steps":["trace[1738866882] 'process raft request' (duration: 167.950299ms)","trace[1738866882] 'compare' (duration: 84.340123ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T00:26:43.087372Z","caller":"traceutil/trace.go:171","msg":"trace[57140560] transaction","detail":"{read_only:false; response_revision:4951; number_of_response:1; }","duration":"141.399705ms","start":"2026-08-12T00:26:42.94595Z","end":"2026-08-12T00:26:43.087349Z","steps":["trace[57140560] 'process raft request' (duration: 140.212957ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:26:43.347043Z","caller":"traceutil/trace.go:171","msg":"trace[505582466] linearizableReadLoop","detail":"{readStateIndex:5139; appliedIndex:5138; }","duration":"104.752268ms","start":"2026-08-12T00:26:43.242271Z","end":"2026-08-12T00:26:43.347023Z","steps":["trace[505582466] 'read index received' (duration: 104.599374ms)","trace[505582466] 'applied index is now lower than readState.Index' (duration: 151.774µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T00:26:43.347121Z","caller":"traceutil/trace.go:171","msg":"trace[380882939] transaction","detail":"{read_only:false; response_revision:4954; number_of_response:1; }","duration":"142.174115ms","start":"2026-08-12T00:26:43.204893Z","end":"2026-08-12T00:26:43.347067Z","steps":["trace[380882939] 'process raft request' (duration: 141.98168ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:26:43.347378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.082055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c87c6944-6ff7-4aa9-86d7-9a9ee39904eb\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-08-12T00:26:43.347464Z","caller":"traceutil/trace.go:171","msg":"trace[2049642056] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c87c6944-6ff7-4aa9-86d7-9a9ee39904eb; range_end:; response_count:1; response_revision:4954; }","duration":"105.230089ms","start":"2026-08-12T00:26:43.242213Z","end":"2026-08-12T00:26:43.347443Z","steps":["trace[2049642056] 'agreement among raft nodes before linearized reading' (duration: 104.909361ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:26:56.382189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.516359ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T00:26:56.382312Z","caller":"traceutil/trace.go:171","msg":"trace[1983337890] linearizableReadLoop","detail":"{readStateIndex:5417; appliedIndex:5416; }","duration":"132.157212ms","start":"2026-08-12T00:26:56.250135Z","end":"2026-08-12T00:26:56.382292Z","steps":["trace[1983337890] 'read index received' (duration: 58.152µs)","trace[1983337890] 'applied index is now lower than readState.Index' (duration: 132.09785ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T00:26:56.382387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.245734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/memcached-metrics\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T00:26:56.382382Z","caller":"traceutil/trace.go:171","msg":"trace[472705911] transaction","detail":"{read_only:false; response_revision:5222; number_of_response:1; }","duration":"166.565885ms","start":"2026-08-12T00:26:56.215792Z","end":"2026-08-12T00:26:56.382358Z","steps":["trace[472705911] 'process raft request' (duration: 17.772391ms)","trace[472705911] 'compare' (duration: 148.337733ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T00:26:56.382418Z","caller":"traceutil/trace.go:171","msg":"trace[466094393] range","detail":"{range_begin:/registry/services/specs/openstack/memcached-metrics; range_end:; response_count:0; response_revision:5222; }","duration":"132.319765ms","start":"2026-08-12T00:26:56.250086Z","end":"2026-08-12T00:26:56.382406Z","steps":["trace[466094393] 'agreement among raft nodes before linearized reading' (duration: 132.257504ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:26:56.628137Z","caller":"traceutil/trace.go:171","msg":"trace[551450534] linearizableReadLoop","detail":"{readStateIndex:5418; appliedIndex:5417; }","duration":"232.086603ms","start":"2026-08-12T00:26:56.396023Z","end":"2026-08-12T00:26:56.628109Z","steps":["trace[551450534] 'read index received' (duration: 139.036088ms)","trace[551450534] 'applied index is now lower than readState.Index' (duration: 93.049624ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T00:26:56.628166Z","caller":"traceutil/trace.go:171","msg":"trace[808969892] transaction","detail":"{read_only:false; response_revision:5223; number_of_response:1; }","duration":"236.620423ms","start":"2026-08-12T00:26:56.391499Z","end":"2026-08-12T00:26:56.628119Z","steps":["trace[808969892] 'process raft request' (duration: 143.64817ms)","trace[808969892] 'compare' (duration: 92.816539ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T00:26:56.628456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.404721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-ljmcv\" ","response":"range_response_count:1 size:7818"} {"level":"warn","ts":"2026-08-12T00:26:56.628456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.638344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T00:26:56.628529Z","caller":"traceutil/trace.go:171","msg":"trace[1917648040] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-ljmcv; range_end:; response_count:1; response_revision:5223; }","duration":"232.543294ms","start":"2026-08-12T00:26:56.395967Z","end":"2026-08-12T00:26:56.62851Z","steps":["trace[1917648040] 'agreement among raft nodes before linearized reading' (duration: 232.311579ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:26:56.628551Z","caller":"traceutil/trace.go:171","msg":"trace[1974663940] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5223; }","duration":"196.776497ms","start":"2026-08-12T00:26:56.431749Z","end":"2026-08-12T00:26:56.628525Z","steps":["trace[1974663940] 'agreement among raft nodes before linearized reading' (duration: 196.580213ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:27:32.555163Z","caller":"traceutil/trace.go:171","msg":"trace[1012931608] transaction","detail":"{read_only:false; response_revision:5555; number_of_response:1; }","duration":"109.368927ms","start":"2026-08-12T00:27:32.445767Z","end":"2026-08-12T00:27:32.555136Z","steps":["trace[1012931608] 'process raft request' (duration: 109.186093ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:28:21.244748Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2975} {"level":"info","ts":"2026-08-12T00:28:21.318906Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2975,"took":"72.526176ms","hash":1646393041,"current-db-size-bytes":58155008,"current-db-size":"58 MB","current-db-size-in-use-bytes":33550336,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-12T00:28:21.318956Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1646393041,"revision":2975,"compact-revision":-1} {"level":"info","ts":"2026-08-12T00:33:21.251642Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6012} {"level":"info","ts":"2026-08-12T00:33:21.346012Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6012,"took":"92.349449ms","hash":2840694194,"current-db-size-bytes":58155008,"current-db-size":"58 MB","current-db-size-in-use-bytes":21770240,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-08-12T00:33:21.346077Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2840694194,"revision":6012,"compact-revision":2975} {"level":"warn","ts":"2026-08-12T00:33:38.874017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.387681ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T00:33:38.87425Z","caller":"traceutil/trace.go:171","msg":"trace[2050899174] transaction","detail":"{read_only:false; response_revision:8360; number_of_response:1; }","duration":"242.880965ms","start":"2026-08-12T00:33:38.631296Z","end":"2026-08-12T00:33:38.874177Z","steps":["trace[2050899174] 'process raft request' (duration: 60.216057ms)","trace[2050899174] 'compare' (duration: 182.166646ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T00:33:51.175806Z","caller":"traceutil/trace.go:171","msg":"trace[1819771367] transaction","detail":"{read_only:false; response_revision:8423; number_of_response:1; }","duration":"262.462342ms","start":"2026-08-12T00:33:50.913316Z","end":"2026-08-12T00:33:51.175779Z","steps":["trace[1819771367] 'process raft request' (duration: 262.296268ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:33:51.181849Z","caller":"traceutil/trace.go:171","msg":"trace[1856480157] transaction","detail":"{read_only:false; response_revision:8424; number_of_response:1; }","duration":"162.825785ms","start":"2026-08-12T00:33:51.019003Z","end":"2026-08-12T00:33:51.181829Z","steps":["trace[1856480157] 'process raft request' (duration: 162.699482ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:33:51.18217Z","caller":"traceutil/trace.go:171","msg":"trace[1682638925] linearizableReadLoop","detail":"{readStateIndex:8722; appliedIndex:8720; }","duration":"113.776741ms","start":"2026-08-12T00:33:51.068367Z","end":"2026-08-12T00:33:51.182144Z","steps":["trace[1682638925] 'read index received' (duration: 107.295883ms)","trace[1682638925] 'applied index is now lower than readState.Index' (duration: 6.478177ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T00:33:51.182306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.936914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-r7c5z\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-08-12T00:33:51.18234Z","caller":"traceutil/trace.go:171","msg":"trace[752959491] range","detail":"{range_begin:/registry/pods/openstack/keepalived-r7c5z; range_end:; response_count:1; response_revision:8425; }","duration":"114.024526ms","start":"2026-08-12T00:33:51.068306Z","end":"2026-08-12T00:33:51.182331Z","steps":["trace[752959491] 'agreement among raft nodes before linearized reading' (duration: 113.906183ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:34:20.876862Z","caller":"traceutil/trace.go:171","msg":"trace[1184837683] transaction","detail":"{read_only:false; response_revision:8639; number_of_response:1; }","duration":"182.624848ms","start":"2026-08-12T00:34:20.694207Z","end":"2026-08-12T00:34:20.876832Z","steps":["trace[1184837683] 'process raft request' (duration: 182.414013ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:34:23.456909Z","caller":"traceutil/trace.go:171","msg":"trace[890909997] transaction","detail":"{read_only:false; response_revision:8652; number_of_response:1; }","duration":"162.023315ms","start":"2026-08-12T00:34:23.294864Z","end":"2026-08-12T00:34:23.456887Z","steps":["trace[890909997] 'process raft request' (duration: 153.092188ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:34:33.673706Z","caller":"traceutil/trace.go:171","msg":"trace[1385386810] transaction","detail":"{read_only:false; response_revision:8748; number_of_response:1; }","duration":"130.754423ms","start":"2026-08-12T00:34:33.54292Z","end":"2026-08-12T00:34:33.673674Z","steps":["trace[1385386810] 'process raft request' (duration: 127.237288ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:34:34.45406Z","caller":"traceutil/trace.go:171","msg":"trace[867835521] linearizableReadLoop","detail":"{readStateIndex:9063; appliedIndex:9063; }","duration":"189.369633ms","start":"2026-08-12T00:34:34.264666Z","end":"2026-08-12T00:34:34.454036Z","steps":["trace[867835521] 'read index received' (duration: 189.357882ms)","trace[867835521] 'applied index is now lower than readState.Index' (duration: 9.871µs)"],"step_count":2} {"level":"warn","ts":"2026-08-12T00:34:34.454281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.597238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-2xbnt\" ","response":"range_response_count:1 size:4650"} {"level":"info","ts":"2026-08-12T00:34:34.454329Z","caller":"traceutil/trace.go:171","msg":"trace[294794921] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-2xbnt; range_end:; response_count:1; response_revision:8751; }","duration":"189.69611ms","start":"2026-08-12T00:34:34.264617Z","end":"2026-08-12T00:34:34.454313Z","steps":["trace[294794921] 'agreement among raft nodes before linearized reading' (duration: 189.502476ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:34:34.584219Z","caller":"traceutil/trace.go:171","msg":"trace[1931284140] transaction","detail":"{read_only:false; response_revision:8752; number_of_response:1; }","duration":"319.051469ms","start":"2026-08-12T00:34:34.265112Z","end":"2026-08-12T00:34:34.584164Z","steps":["trace[1931284140] 'process raft request' (duration: 275.352146ms)","trace[1931284140] 'compare' (duration: 43.14974ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T00:34:34.584582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.581701ms","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-08-12T00:34:34.584587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.639478ms","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-08-12T00:34:34.58422Z","caller":"traceutil/trace.go:171","msg":"trace[1169918314] linearizableReadLoop","detail":"{readStateIndex:9065; appliedIndex:9063; }","duration":"130.082828ms","start":"2026-08-12T00:34:34.454114Z","end":"2026-08-12T00:34:34.584197Z","steps":["trace[1169918314] 'read index received' (duration: 86.495747ms)","trace[1169918314] 'applied index is now lower than readState.Index' (duration: 43.586001ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T00:34:34.58464Z","caller":"traceutil/trace.go:171","msg":"trace[281690917] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8754; }","duration":"222.713054ms","start":"2026-08-12T00:34:34.361915Z","end":"2026-08-12T00:34:34.584628Z","steps":["trace[281690917] 'agreement among raft nodes before linearized reading' (duration: 222.302183ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:34:34.584665Z","caller":"traceutil/trace.go:171","msg":"trace[1435225688] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8754; }","duration":"299.766741ms","start":"2026-08-12T00:34:34.284878Z","end":"2026-08-12T00:34:34.584645Z","steps":["trace[1435225688] 'agreement among raft nodes before linearized reading' (duration: 299.394601ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:34:34.584318Z","caller":"traceutil/trace.go:171","msg":"trace[749260056] transaction","detail":"{read_only:false; response_revision:8754; number_of_response:1; }","duration":"128.328044ms","start":"2026-08-12T00:34:34.455979Z","end":"2026-08-12T00:34:34.584307Z","steps":["trace[749260056] 'process raft request' (duration: 128.164081ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:34:34.584767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.27116ms","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-08-12T00:34:34.584301Z","caller":"traceutil/trace.go:171","msg":"trace[752924557] transaction","detail":"{read_only:false; response_revision:8753; number_of_response:1; }","duration":"309.088757ms","start":"2026-08-12T00:34:34.275179Z","end":"2026-08-12T00:34:34.584268Z","steps":["trace[752924557] 'process raft request' (duration: 308.80612ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:34:34.584867Z","caller":"traceutil/trace.go:171","msg":"trace[1522605249] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8754; }","duration":"235.412153ms","start":"2026-08-12T00:34:34.349419Z","end":"2026-08-12T00:34:34.584831Z","steps":["trace[1522605249] 'agreement among raft nodes before linearized reading' (duration: 234.859249ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:34:34.584607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:34:34.265099Z","time spent":"319.420899ms","remote":"127.0.0.1:59926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":746,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-12T00:34:34.585074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:34:34.275162Z","time spent":"309.736524ms","remote":"127.0.0.1:60060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3037,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T00:34:35.343127Z","caller":"traceutil/trace.go:171","msg":"trace[379735372] transaction","detail":"{read_only:false; response_revision:8760; number_of_response:1; }","duration":"196.117016ms","start":"2026-08-12T00:34:35.146978Z","end":"2026-08-12T00:34:35.343095Z","steps":["trace[379735372] 'process raft request' (duration: 195.942492ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:34:35.816037Z","caller":"traceutil/trace.go:171","msg":"trace[1900284221] linearizableReadLoop","detail":"{readStateIndex:9076; appliedIndex:9074; }","duration":"119.821019ms","start":"2026-08-12T00:34:35.696192Z","end":"2026-08-12T00:34:35.816013Z","steps":["trace[1900284221] 'read index received' (duration: 12.358742ms)","trace[1900284221] 'applied index is now lower than readState.Index' (duration: 107.461327ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T00:34:35.816211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.014903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3684"} {"level":"info","ts":"2026-08-12T00:34:35.816238Z","caller":"traceutil/trace.go:171","msg":"trace[1428752381] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:8765; }","duration":"120.061684ms","start":"2026-08-12T00:34:35.696165Z","end":"2026-08-12T00:34:35.816227Z","steps":["trace[1428752381] 'agreement among raft nodes before linearized reading' (duration: 119.934941ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:34:35.816235Z","caller":"traceutil/trace.go:171","msg":"trace[335663709] transaction","detail":"{read_only:false; response_revision:8764; number_of_response:1; }","duration":"121.728194ms","start":"2026-08-12T00:34:35.694461Z","end":"2026-08-12T00:34:35.816189Z","steps":["trace[335663709] 'process raft request' (duration: 74.840521ms)","trace[335663709] 'compare' (duration: 46.510234ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T00:34:35.81628Z","caller":"traceutil/trace.go:171","msg":"trace[708603279] transaction","detail":"{read_only:false; response_revision:8765; number_of_response:1; }","duration":"119.234983ms","start":"2026-08-12T00:34:35.69701Z","end":"2026-08-12T00:34:35.816245Z","steps":["trace[708603279] 'process raft request' (duration: 118.947396ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:34:50.80143Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002411.wal"} {"level":"warn","ts":"2026-08-12T00:35:49.831517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.771942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T00:35:49.831773Z","caller":"traceutil/trace.go:171","msg":"trace[916828404] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:9493; }","duration":"112.161721ms","start":"2026-08-12T00:35:49.719561Z","end":"2026-08-12T00:35:49.831723Z","steps":["trace[916828404] 'count revisions from in-memory index tree' (duration: 111.615268ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:35:54.605739Z","caller":"traceutil/trace.go:171","msg":"trace[674807108] transaction","detail":"{read_only:false; response_revision:9519; number_of_response:1; }","duration":"308.504853ms","start":"2026-08-12T00:35:54.297205Z","end":"2026-08-12T00:35:54.605709Z","steps":["trace[674807108] 'process raft request' (duration: 297.509445ms)","trace[674807108] 'compare' (duration: 10.618068ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T00:35:54.605893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:35:54.297185Z","time spent":"308.623616ms","remote":"127.0.0.1:35408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T00:35:54.645447Z","caller":"traceutil/trace.go:171","msg":"trace[1441623230] linearizableReadLoop","detail":"{readStateIndex:9853; appliedIndex:9851; }","duration":"213.336245ms","start":"2026-08-12T00:35:54.432081Z","end":"2026-08-12T00:35:54.645417Z","steps":["trace[1441623230] 'read index received' (duration: 162.648831ms)","trace[1441623230] 'applied index is now lower than readState.Index' (duration: 50.686294ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T00:35:54.645592Z","caller":"traceutil/trace.go:171","msg":"trace[1028651942] transaction","detail":"{read_only:false; response_revision:9520; number_of_response:1; }","duration":"289.603292ms","start":"2026-08-12T00:35:54.355943Z","end":"2026-08-12T00:35:54.645546Z","steps":["trace[1028651942] 'process raft request' (duration: 289.302315ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:35:54.645642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.536679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T00:35:54.645685Z","caller":"traceutil/trace.go:171","msg":"trace[1005570035] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9520; }","duration":"213.656542ms","start":"2026-08-12T00:35:54.432016Z","end":"2026-08-12T00:35:54.645673Z","steps":["trace[1005570035] 'agreement among raft nodes before linearized reading' (duration: 213.55923ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:35:54.645805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.015599ms","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-08-12T00:35:54.645866Z","caller":"traceutil/trace.go:171","msg":"trace[1465794648] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9520; }","duration":"163.141032ms","start":"2026-08-12T00:35:54.482712Z","end":"2026-08-12T00:35:54.645853Z","steps":["trace[1465794648] 'agreement among raft nodes before linearized reading' (duration: 162.929657ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:35:54.812638Z","caller":"traceutil/trace.go:171","msg":"trace[990293891] transaction","detail":"{read_only:false; response_revision:9521; number_of_response:1; }","duration":"162.314672ms","start":"2026-08-12T00:35:54.65029Z","end":"2026-08-12T00:35:54.812605Z","steps":["trace[990293891] 'process raft request' (duration: 101.533143ms)","trace[990293891] 'compare' (duration: 60.485102ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T00:35:54.813381Z","caller":"traceutil/trace.go:171","msg":"trace[2009223146] transaction","detail":"{read_only:false; response_revision:9522; number_of_response:1; }","duration":"162.295132ms","start":"2026-08-12T00:35:54.651042Z","end":"2026-08-12T00:35:54.813337Z","steps":["trace[2009223146] 'process raft request' (duration: 161.459521ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:36:12.158599Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d71a1778b31d4ce","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-12T00:36:12.164272Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-12T00:36:12.16445Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-12T00:38:21.257478Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8097} {"level":"info","ts":"2026-08-12T00:38:21.316495Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8097,"took":"57.055707ms","hash":4128623016,"current-db-size-bytes":58155008,"current-db-size":"58 MB","current-db-size-in-use-bytes":27353088,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-08-12T00:38:21.316557Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4128623016,"revision":8097,"compact-revision":6012} {"level":"warn","ts":"2026-08-12T00:41:31.506201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.013235ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T00:41:31.506467Z","caller":"traceutil/trace.go:171","msg":"trace[1140027829] transaction","detail":"{read_only:false; response_revision:11940; number_of_response:1; }","duration":"247.7632ms","start":"2026-08-12T00:41:31.258679Z","end":"2026-08-12T00:41:31.506442Z","steps":["trace[1140027829] 'process raft request' (duration: 16.410756ms)","trace[1140027829] 'compare' (duration: 230.83224ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T00:41:31.506916Z","caller":"traceutil/trace.go:171","msg":"trace[641316869] transaction","detail":"{read_only:false; response_revision:11941; number_of_response:1; }","duration":"164.875766ms","start":"2026-08-12T00:41:31.341998Z","end":"2026-08-12T00:41:31.506874Z","steps":["trace[641316869] 'process raft request' (duration: 164.348142ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:43:21.263069Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10491} {"level":"info","ts":"2026-08-12T00:43:21.347781Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10491,"took":"81.508643ms","hash":4065443025,"current-db-size-bytes":58155008,"current-db-size":"58 MB","current-db-size-in-use-bytes":25583616,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-12T00:43:21.347854Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4065443025,"revision":10491,"compact-revision":8097} {"level":"warn","ts":"2026-08-12T00:47:53.570272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.400655ms","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-08-12T00:47:53.57041Z","caller":"traceutil/trace.go:171","msg":"trace[833479422] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14502; }","duration":"111.593279ms","start":"2026-08-12T00:47:53.45878Z","end":"2026-08-12T00:47:53.570373Z","steps":["trace[833479422] 'range keys from in-memory index tree' (duration: 111.173889ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:48:02.079158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.021799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-12T00:48:02.079289Z","caller":"traceutil/trace.go:171","msg":"trace[1788485155] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:14561; }","duration":"162.207253ms","start":"2026-08-12T00:48:01.917055Z","end":"2026-08-12T00:48:02.079262Z","steps":["trace[1788485155] 'range keys from in-memory index tree' (duration: 161.774503ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:48:21.269592Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12683} {"level":"info","ts":"2026-08-12T00:48:21.349025Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12683,"took":"77.290049ms","hash":649227263,"current-db-size-bytes":58155008,"current-db-size":"58 MB","current-db-size-in-use-bytes":28127232,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-12T00:48:21.349118Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":649227263,"revision":12683,"compact-revision":10491} {"level":"warn","ts":"2026-08-12T00:48:24.652955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.432156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-43d7efca-01ea-41a5-8627-250561a9485d\" ","response":"range_response_count:1 size:3637"} {"level":"info","ts":"2026-08-12T00:48:24.653098Z","caller":"traceutil/trace.go:171","msg":"trace[669873262] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-43d7efca-01ea-41a5-8627-250561a9485d; range_end:; response_count:1; response_revision:14874; }","duration":"238.717433ms","start":"2026-08-12T00:48:24.414347Z","end":"2026-08-12T00:48:24.653064Z","steps":["trace[669873262] 'range keys from in-memory index tree' (duration: 238.187411ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:48:24.653151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.77817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T00:48:24.653246Z","caller":"traceutil/trace.go:171","msg":"trace[2143943120] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14874; }","duration":"220.915513ms","start":"2026-08-12T00:48:24.43231Z","end":"2026-08-12T00:48:24.653225Z","steps":["trace[2143943120] 'range keys from in-memory index tree' (duration: 220.675017ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:49:17.561692Z","caller":"traceutil/trace.go:171","msg":"trace[1369460237] linearizableReadLoop","detail":"{readStateIndex:16043; appliedIndex:16041; }","duration":"129.291497ms","start":"2026-08-12T00:49:17.43236Z","end":"2026-08-12T00:49:17.561651Z","steps":["trace[1369460237] 'read index received' (duration: 82.276911ms)","trace[1369460237] 'applied index is now lower than readState.Index' (duration: 47.011916ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T00:49:17.561717Z","caller":"traceutil/trace.go:171","msg":"trace[2113074583] transaction","detail":"{read_only:false; response_revision:15509; number_of_response:1; }","duration":"137.555767ms","start":"2026-08-12T00:49:17.424132Z","end":"2026-08-12T00:49:17.561688Z","steps":["trace[2113074583] 'process raft request' (duration: 135.322024ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:49:17.562129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.690587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T00:49:17.562214Z","caller":"traceutil/trace.go:171","msg":"trace[1403481078] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15509; }","duration":"129.897442ms","start":"2026-08-12T00:49:17.432296Z","end":"2026-08-12T00:49:17.562194Z","steps":["trace[1403481078] 'agreement among raft nodes before linearized reading' (duration: 129.477991ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:49:51.452189Z","caller":"traceutil/trace.go:171","msg":"trace[265463655] transaction","detail":"{read_only:false; response_revision:15685; number_of_response:1; }","duration":"117.393079ms","start":"2026-08-12T00:49:51.334762Z","end":"2026-08-12T00:49:51.452155Z","steps":["trace[265463655] 'process raft request' (duration: 117.115882ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:49:57.857553Z","caller":"traceutil/trace.go:171","msg":"trace[1120066159] linearizableReadLoop","detail":"{readStateIndex:16262; appliedIndex:16261; }","duration":"100.15994ms","start":"2026-08-12T00:49:57.757357Z","end":"2026-08-12T00:49:57.857517Z","steps":["trace[1120066159] 'read index received' (duration: 92.884557ms)","trace[1120066159] 'applied index is now lower than readState.Index' (duration: 7.274053ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T00:49:57.858078Z","caller":"traceutil/trace.go:171","msg":"trace[249365288] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15720; }","duration":"100.001426ms","start":"2026-08-12T00:49:57.758027Z","end":"2026-08-12T00:49:57.858028Z","steps":["trace[249365288] 'agreement among raft nodes before linearized reading' (duration: 99.857232ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:49:57.85845Z","caller":"traceutil/trace.go:171","msg":"trace[1318653309] transaction","detail":"{read_only:false; response_revision:15720; number_of_response:1; }","duration":"129.572918ms","start":"2026-08-12T00:49:57.728854Z","end":"2026-08-12T00:49:57.858427Z","steps":["trace[1318653309] 'process raft request' (duration: 121.487646ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:49:57.858547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.157873ms","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-08-12T00:49:57.858629Z","caller":"traceutil/trace.go:171","msg":"trace[585114177] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15720; }","duration":"101.285056ms","start":"2026-08-12T00:49:57.757324Z","end":"2026-08-12T00:49:57.858609Z","steps":["trace[585114177] 'agreement among raft nodes before linearized reading' (duration: 101.141963ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:49:57.858765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.066058ms","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-08-12T00:49:57.858849Z","caller":"traceutil/trace.go:171","msg":"trace[297585909] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15720; }","duration":"100.18838ms","start":"2026-08-12T00:49:57.758642Z","end":"2026-08-12T00:49:57.85883Z","steps":["trace[297585909] 'agreement among raft nodes before linearized reading' (duration: 99.904404ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:53:21.275041Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14793} {"level":"info","ts":"2026-08-12T00:53:21.369425Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14793,"took":"91.596144ms","hash":2217661719,"current-db-size-bytes":58155008,"current-db-size":"58 MB","current-db-size-in-use-bytes":30453760,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-12T00:53:21.369503Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2217661719,"revision":14793,"compact-revision":12683} {"level":"info","ts":"2026-08-12T00:55:55.240716Z","caller":"traceutil/trace.go:171","msg":"trace[1800228570] transaction","detail":"{read_only:false; response_revision:18350; number_of_response:1; }","duration":"110.640613ms","start":"2026-08-12T00:55:55.130042Z","end":"2026-08-12T00:55:55.240683Z","steps":["trace[1800228570] 'process raft request' (duration: 76.394786ms)","trace[1800228570] 'compare' (duration: 33.852728ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T00:56:03.461286Z","caller":"traceutil/trace.go:171","msg":"trace[1649817827] transaction","detail":"{read_only:false; response_revision:18396; number_of_response:1; }","duration":"116.961045ms","start":"2026-08-12T00:56:03.344292Z","end":"2026-08-12T00:56:03.461253Z","steps":["trace[1649817827] 'process raft request' (duration: 48.633964ms)","trace[1649817827] 'compare' (duration: 67.772308ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T00:56:03.461464Z","caller":"traceutil/trace.go:171","msg":"trace[1397628458] transaction","detail":"{read_only:false; response_revision:18397; number_of_response:1; }","duration":"107.858706ms","start":"2026-08-12T00:56:03.353579Z","end":"2026-08-12T00:56:03.461437Z","steps":["trace[1397628458] 'process raft request' (duration: 107.313143ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:56:04.210331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.150402ms","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-08-12T00:56:04.210428Z","caller":"traceutil/trace.go:171","msg":"trace[1958346006] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18399; }","duration":"391.302945ms","start":"2026-08-12T00:56:03.819103Z","end":"2026-08-12T00:56:04.210406Z","steps":["trace[1958346006] 'range keys from in-memory index tree' (duration: 390.967237ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:56:04.210475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:56:03.819075Z","time spent":"391.388047ms","remote":"127.0.0.1:35408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-12T00:56:04.654287Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334369648705025430,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-12T00:56:04.791171Z","caller":"traceutil/trace.go:171","msg":"trace[2021032553] transaction","detail":"{read_only:false; response_revision:18400; number_of_response:1; }","duration":"964.961916ms","start":"2026-08-12T00:56:03.826113Z","end":"2026-08-12T00:56:04.791075Z","steps":["trace[2021032553] 'process raft request' (duration: 964.671419ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:56:04.791389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:56:03.826076Z","time spent":"965.207172ms","remote":"127.0.0.1:60002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T00:56:05.000197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.807951ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-12T00:56:05.000372Z","caller":"traceutil/trace.go:171","msg":"trace[1004810300] linearizableReadLoop","detail":"{readStateIndex:19033; appliedIndex:19030; }","duration":"846.513244ms","start":"2026-08-12T00:56:04.153846Z","end":"2026-08-12T00:56:05.000359Z","steps":["trace[1004810300] 'read index received' (duration: 636.882518ms)","trace[1004810300] 'applied index is now lower than readState.Index' (duration: 209.630036ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T00:56:05.000474Z","caller":"traceutil/trace.go:171","msg":"trace[18761799] transaction","detail":"{read_only:false; response_revision:18402; number_of_response:1; }","duration":"541.229887ms","start":"2026-08-12T00:56:04.459202Z","end":"2026-08-12T00:56:05.000432Z","steps":["trace[18761799] 'process raft request' (duration: 541.104404ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:56:05.000477Z","caller":"traceutil/trace.go:171","msg":"trace[122149690] transaction","detail":"{read_only:false; response_revision:18401; number_of_response:1; }","duration":"783.077721ms","start":"2026-08-12T00:56:04.217339Z","end":"2026-08-12T00:56:05.000417Z","steps":["trace[122149690] 'process raft request' (duration: 622.952062ms)","trace[122149690] 'compare' (duration: 159.655558ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T00:56:05.000605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.364509ms","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:6"} {"level":"warn","ts":"2026-08-12T00:56:05.000659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:56:04.45918Z","time spent":"541.37798ms","remote":"127.0.0.1:35408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T00:56:05.000631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"846.761329ms","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-08-12T00:56:05.000697Z","caller":"traceutil/trace.go:171","msg":"trace[966192458] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:18402; }","duration":"257.536883ms","start":"2026-08-12T00:56:04.743138Z","end":"2026-08-12T00:56:05.000675Z","steps":["trace[966192458] 'agreement among raft nodes before linearized reading' (duration: 257.328458ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:56:05.000699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:56:04.217311Z","time spent":"783.268296ms","remote":"127.0.0.1:35408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T00:56:05.000733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.149637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T00:56:05.000763Z","caller":"traceutil/trace.go:171","msg":"trace[1970425179] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18402; }","duration":"568.22887ms","start":"2026-08-12T00:56:04.432522Z","end":"2026-08-12T00:56:05.000751Z","steps":["trace[1970425179] 'agreement among raft nodes before linearized reading' (duration: 568.191329ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:56:05.00075Z","caller":"traceutil/trace.go:171","msg":"trace[736888928] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18402; }","duration":"846.970324ms","start":"2026-08-12T00:56:04.153758Z","end":"2026-08-12T00:56:05.000728Z","steps":["trace[736888928] 'agreement among raft nodes before linearized reading' (duration: 846.676177ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:56:05.000789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:56:04.432498Z","time spent":"568.285221ms","remote":"127.0.0.1:59856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-12T00:56:05.000798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:56:04.153729Z","time spent":"847.059367ms","remote":"127.0.0.1:35408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-12T00:56:05.000695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.168643ms","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-08-12T00:56:05.000874Z","caller":"traceutil/trace.go:171","msg":"trace[1882897764] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18402; }","duration":"639.386329ms","start":"2026-08-12T00:56:04.361473Z","end":"2026-08-12T00:56:05.00086Z","steps":["trace[1882897764] 'agreement among raft nodes before linearized reading' (duration: 639.074511ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:56:05.000919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:56:04.361455Z","time spent":"639.44377ms","remote":"127.0.0.1:35408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-12T00:56:05.000984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.965529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-heat\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T00:56:05.00104Z","caller":"traceutil/trace.go:171","msg":"trace[733276019] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-heat; range_end:; response_count:0; response_revision:18402; }","duration":"257.038961ms","start":"2026-08-12T00:56:04.743985Z","end":"2026-08-12T00:56:05.001024Z","steps":["trace[733276019] 'agreement among raft nodes before linearized reading' (duration: 256.944269ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:56:05.001268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"803.584426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-5qp42\" ","response":"range_response_count:1 size:9797"} {"level":"info","ts":"2026-08-12T00:56:05.001303Z","caller":"traceutil/trace.go:171","msg":"trace[1153080141] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-5qp42; range_end:; response_count:1; response_revision:18402; }","duration":"803.670509ms","start":"2026-08-12T00:56:04.197625Z","end":"2026-08-12T00:56:05.001296Z","steps":["trace[1153080141] 'agreement among raft nodes before linearized reading' (duration: 802.825529ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:56:05.001324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:56:04.197599Z","time spent":"803.721019ms","remote":"127.0.0.1:60006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9820,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-5qp42\" "} {"level":"warn","ts":"2026-08-12T00:56:05.00133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.861669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.109\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-08-12T00:56:05.001733Z","caller":"traceutil/trace.go:171","msg":"trace[2010075908] range","detail":"{range_begin:/registry/masterleases/199.204.45.109; range_end:; response_count:1; response_revision:18402; }","duration":"731.471864ms","start":"2026-08-12T00:56:04.269998Z","end":"2026-08-12T00:56:05.00147Z","steps":["trace[2010075908] 'agreement among raft nodes before linearized reading' (duration: 730.712096ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:56:05.0018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T00:56:04.269972Z","time spent":"731.811541ms","remote":"127.0.0.1:59874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":163,"request content":"key:\"/registry/masterleases/199.204.45.109\" "} {"level":"info","ts":"2026-08-12T00:56:05.135875Z","caller":"traceutil/trace.go:171","msg":"trace[1593946523] transaction","detail":"{read_only:false; response_revision:18403; number_of_response:1; }","duration":"128.600248ms","start":"2026-08-12T00:56:05.007232Z","end":"2026-08-12T00:56:05.135832Z","steps":["trace[1593946523] 'process raft request' (duration: 102.555929ms)","trace[1593946523] 'compare' (duration: 25.632189ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T00:56:05.13625Z","caller":"traceutil/trace.go:171","msg":"trace[1809142430] linearizableReadLoop","detail":"{readStateIndex:19035; appliedIndex:19033; }","duration":"127.040099ms","start":"2026-08-12T00:56:05.009185Z","end":"2026-08-12T00:56:05.136225Z","steps":["trace[1809142430] 'read index received' (duration: 73.184838ms)","trace[1809142430] 'applied index is now lower than readState.Index' (duration: 53.853931ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T00:56:05.136972Z","caller":"traceutil/trace.go:171","msg":"trace[1585433745] transaction","detail":"{read_only:false; response_revision:18404; number_of_response:1; }","duration":"127.531101ms","start":"2026-08-12T00:56:05.009419Z","end":"2026-08-12T00:56:05.13695Z","steps":["trace[1585433745] 'process raft request' (duration: 126.445396ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T00:56:05.137638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.427984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:94 size:946917"} {"level":"info","ts":"2026-08-12T00:56:05.137705Z","caller":"traceutil/trace.go:171","msg":"trace[1621926651] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:94; response_revision:18405; }","duration":"128.561857ms","start":"2026-08-12T00:56:05.00913Z","end":"2026-08-12T00:56:05.137692Z","steps":["trace[1621926651] 'agreement among raft nodes before linearized reading' (duration: 127.241785ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T00:57:35.645693Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d71a1778b31d4ce","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-12T00:57:35.652049Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-12T00:57:35.652197Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-12T00:58:21.282701Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17333} {"level":"info","ts":"2026-08-12T00:58:21.389136Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17333,"took":"102.561785ms","hash":3677174390,"current-db-size-bytes":58155008,"current-db-size":"58 MB","current-db-size-in-use-bytes":34025472,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-12T00:58:21.389227Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3677174390,"revision":17333,"compact-revision":14793} {"level":"warn","ts":"2026-08-12T01:01:41.496056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.994437ms","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-08-12T01:01:41.496157Z","caller":"traceutil/trace.go:171","msg":"trace[154593039] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21094; }","duration":"104.14857ms","start":"2026-08-12T01:01:41.391988Z","end":"2026-08-12T01:01:41.496136Z","steps":["trace[154593039] 'range keys from in-memory index tree' (duration: 103.811752ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:01:57.529154Z","caller":"traceutil/trace.go:171","msg":"trace[1742189601] transaction","detail":"{read_only:false; response_revision:21177; number_of_response:1; }","duration":"179.058971ms","start":"2026-08-12T01:01:57.350053Z","end":"2026-08-12T01:01:57.529112Z","steps":["trace[1742189601] 'process raft request' (duration: 178.746703ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:01:57.882614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.115203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T01:01:57.88276Z","caller":"traceutil/trace.go:171","msg":"trace[1719276336] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:21177; }","duration":"142.289547ms","start":"2026-08-12T01:01:57.740437Z","end":"2026-08-12T01:01:57.882727Z","steps":["trace[1719276336] 'count revisions from in-memory index tree' (duration: 141.94245ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:01:57.882837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.206439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-12T01:01:57.882851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.807811ms","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-08-12T01:01:57.882958Z","caller":"traceutil/trace.go:171","msg":"trace[765131934] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:21177; }","duration":"132.364483ms","start":"2026-08-12T01:01:57.750551Z","end":"2026-08-12T01:01:57.882916Z","steps":["trace[765131934] 'count revisions from in-memory index tree' (duration: 132.069646ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:01:57.88297Z","caller":"traceutil/trace.go:171","msg":"trace[41444360] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21177; }","duration":"197.968785ms","start":"2026-08-12T01:01:57.684967Z","end":"2026-08-12T01:01:57.882935Z","steps":["trace[41444360] 'range keys from in-memory index tree' (duration: 197.538925ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:01:57.88349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.820874ms","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-08-12T01:01:57.883579Z","caller":"traceutil/trace.go:171","msg":"trace[411973354] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21177; }","duration":"228.982917ms","start":"2026-08-12T01:01:57.654576Z","end":"2026-08-12T01:01:57.883559Z","steps":["trace[411973354] 'range keys from in-memory index tree' (duration: 227.952713ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:01:58.184393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.040423ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-12T01:01:58.184765Z","caller":"traceutil/trace.go:171","msg":"trace[1444893907] transaction","detail":"{read_only:false; response_revision:21180; number_of_response:1; }","duration":"296.1923ms","start":"2026-08-12T01:01:57.888548Z","end":"2026-08-12T01:01:58.18474Z","steps":["trace[1444893907] 'process raft request' (duration: 296.034506ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:01:58.184963Z","caller":"traceutil/trace.go:171","msg":"trace[834055903] transaction","detail":"{read_only:false; response_revision:21179; number_of_response:1; }","duration":"296.410775ms","start":"2026-08-12T01:01:57.888489Z","end":"2026-08-12T01:01:58.1849Z","steps":["trace[834055903] 'process raft request' (duration: 159.706495ms)","trace[834055903] 'compare' (duration: 135.642154ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T01:03:21.291609Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19632} {"level":"info","ts":"2026-08-12T01:03:21.415789Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19632,"took":"119.228951ms","hash":781041209,"current-db-size-bytes":58155008,"current-db-size":"58 MB","current-db-size-in-use-bytes":29446144,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-08-12T01:03:21.415939Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":781041209,"revision":19632,"compact-revision":17333} {"level":"warn","ts":"2026-08-12T01:06:04.620711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.434774ms","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-08-12T01:06:04.6208Z","caller":"traceutil/trace.go:171","msg":"trace[1249379943] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23732; }","duration":"232.586777ms","start":"2026-08-12T01:06:04.388195Z","end":"2026-08-12T01:06:04.620782Z","steps":["trace[1249379943] 'range keys from in-memory index tree' (duration: 232.169067ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:06:04.621137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.388938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T01:06:04.621169Z","caller":"traceutil/trace.go:171","msg":"trace[271682445] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23732; }","duration":"187.455689ms","start":"2026-08-12T01:06:04.433707Z","end":"2026-08-12T01:06:04.621163Z","steps":["trace[271682445] 'range keys from in-memory index tree' (duration: 187.225304ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:06:04.621584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.77254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-12T01:06:04.621699Z","caller":"traceutil/trace.go:171","msg":"trace[2122583296] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:23732; }","duration":"100.940753ms","start":"2026-08-12T01:06:04.520731Z","end":"2026-08-12T01:06:04.621672Z","steps":["trace[2122583296] 'count revisions from in-memory index tree' (duration: 100.663978ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:08:21.299042Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21903} {"level":"info","ts":"2026-08-12T01:08:21.415785Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21903,"took":"111.351402ms","hash":1873482283,"current-db-size-bytes":58155008,"current-db-size":"58 MB","current-db-size-in-use-bytes":45023232,"current-db-size-in-use":"45 MB"} {"level":"info","ts":"2026-08-12T01:08:21.415852Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1873482283,"revision":21903,"compact-revision":19632} {"level":"info","ts":"2026-08-12T01:09:24.938844Z","caller":"traceutil/trace.go:171","msg":"trace[172283158] linearizableReadLoop","detail":"{readStateIndex:26610; appliedIndex:26609; }","duration":"112.362687ms","start":"2026-08-12T01:09:24.82645Z","end":"2026-08-12T01:09:24.938813Z","steps":["trace[172283158] 'read index received' (duration: 112.070429ms)","trace[172283158] 'applied index is now lower than readState.Index' (duration: 291.068µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T01:09:24.938988Z","caller":"traceutil/trace.go:171","msg":"trace[1964978421] transaction","detail":"{read_only:false; response_revision:25754; number_of_response:1; }","duration":"126.038995ms","start":"2026-08-12T01:09:24.812914Z","end":"2026-08-12T01:09:24.938953Z","steps":["trace[1964978421] 'process raft request' (duration: 125.700355ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:09:24.939103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.686216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T01:09:24.939171Z","caller":"traceutil/trace.go:171","msg":"trace[92932437] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:25754; }","duration":"112.776608ms","start":"2026-08-12T01:09:24.826376Z","end":"2026-08-12T01:09:24.939152Z","steps":["trace[92932437] 'agreement among raft nodes before linearized reading' (duration: 112.573093ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:09:30.420126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.847682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-08-12T01:09:30.420255Z","caller":"traceutil/trace.go:171","msg":"trace[569923647] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25789; }","duration":"148.035527ms","start":"2026-08-12T01:09:30.272188Z","end":"2026-08-12T01:09:30.420223Z","steps":["trace[569923647] 'range keys from in-memory index tree' (duration: 147.561834ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:09:30.420299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.765289ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-12T01:09:30.42044Z","caller":"traceutil/trace.go:171","msg":"trace[1648983258] transaction","detail":"{read_only:false; response_revision:25790; number_of_response:1; }","duration":"145.492681ms","start":"2026-08-12T01:09:30.274922Z","end":"2026-08-12T01:09:30.420415Z","steps":["trace[1648983258] 'process raft request' (duration: 17.476316ms)","trace[1648983258] 'compare' (duration: 127.322658ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T01:09:30.701928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.632193ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-12T01:09:30.702123Z","caller":"traceutil/trace.go:171","msg":"trace[1013197265] transaction","detail":"{read_only:false; response_revision:25792; number_of_response:1; }","duration":"276.214097ms","start":"2026-08-12T01:09:30.425893Z","end":"2026-08-12T01:09:30.702108Z","steps":["trace[1013197265] 'process raft request' (duration: 276.119335ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:09:30.702211Z","caller":"traceutil/trace.go:171","msg":"trace[925919988] transaction","detail":"{read_only:false; response_revision:25791; number_of_response:1; }","duration":"383.072603ms","start":"2026-08-12T01:09:30.319131Z","end":"2026-08-12T01:09:30.702204Z","steps":["trace[925919988] 'process raft request' (duration: 261.08875ms)","trace[925919988] 'compare' (duration: 121.309536ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T01:09:30.702244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:09:30.319082Z","time spent":"383.144584ms","remote":"127.0.0.1:35408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T01:09:30.702293Z","caller":"traceutil/trace.go:171","msg":"trace[636858126] linearizableReadLoop","detail":"{readStateIndex:26648; appliedIndex:26647; }","duration":"376.820304ms","start":"2026-08-12T01:09:30.325465Z","end":"2026-08-12T01:09:30.702286Z","steps":["trace[636858126] 'read index received' (duration: 254.78911ms)","trace[636858126] 'applied index is now lower than readState.Index' (duration: 122.030464ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T01:09:30.70281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.344666ms","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-08-12T01:09:30.702877Z","caller":"traceutil/trace.go:171","msg":"trace[1486047052] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25792; }","duration":"377.458349ms","start":"2026-08-12T01:09:30.3254Z","end":"2026-08-12T01:09:30.702859Z","steps":["trace[1486047052] 'agreement among raft nodes before linearized reading' (duration: 376.928326ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:09:30.702929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:09:30.325372Z","time spent":"377.546052ms","remote":"127.0.0.1:60006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-08-12T01:09:30.703786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.041918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-08-12T01:09:30.703823Z","caller":"traceutil/trace.go:171","msg":"trace[1435750996] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:25793; }","duration":"317.12924ms","start":"2026-08-12T01:09:30.386684Z","end":"2026-08-12T01:09:30.703813Z","steps":["trace[1435750996] 'agreement among raft nodes before linearized reading' (duration: 315.671853ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:09:30.703845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:09:30.386654Z","time spent":"317.185662ms","remote":"127.0.0.1:35670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":2634,"response size":32,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-08-12T01:09:30.924633Z","caller":"traceutil/trace.go:171","msg":"trace[597715681] linearizableReadLoop","detail":"{readStateIndex:26652; appliedIndex:26651; }","duration":"133.666639ms","start":"2026-08-12T01:09:30.790942Z","end":"2026-08-12T01:09:30.924609Z","steps":["trace[597715681] 'read index received' (duration: 86.684351ms)","trace[597715681] 'applied index is now lower than readState.Index' (duration: 46.981178ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T01:09:30.924849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.905745ms","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-08-12T01:09:30.92482Z","caller":"traceutil/trace.go:171","msg":"trace[1992615525] transaction","detail":"{read_only:false; response_revision:25795; number_of_response:1; }","duration":"177.22359ms","start":"2026-08-12T01:09:30.747537Z","end":"2026-08-12T01:09:30.924761Z","steps":["trace[1992615525] 'process raft request' (duration: 130.067227ms)","trace[1992615525] 'compare' (duration: 46.791714ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T01:09:30.924888Z","caller":"traceutil/trace.go:171","msg":"trace[1697617562] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25795; }","duration":"133.977747ms","start":"2026-08-12T01:09:30.790897Z","end":"2026-08-12T01:09:30.924875Z","steps":["trace[1697617562] 'agreement among raft nodes before linearized reading' (duration: 133.831534ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:09:30.924841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.434598ms","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-08-12T01:09:30.925032Z","caller":"traceutil/trace.go:171","msg":"trace[1675509761] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25795; }","duration":"101.686513ms","start":"2026-08-12T01:09:30.823306Z","end":"2026-08-12T01:09:30.924993Z","steps":["trace[1675509761] 'agreement among raft nodes before linearized reading' (duration: 101.466838ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:09:36.129828Z","caller":"traceutil/trace.go:171","msg":"trace[318600966] transaction","detail":"{read_only:false; response_revision:25829; number_of_response:1; }","duration":"248.435466ms","start":"2026-08-12T01:09:35.881357Z","end":"2026-08-12T01:09:36.129792Z","steps":["trace[318600966] 'process raft request' (duration: 248.16552ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:09:36.147752Z","caller":"traceutil/trace.go:171","msg":"trace[575736930] linearizableReadLoop","detail":"{readStateIndex:26688; appliedIndex:26686; }","duration":"260.229249ms","start":"2026-08-12T01:09:35.887497Z","end":"2026-08-12T01:09:36.147726Z","steps":["trace[575736930] 'read index received' (duration: 242.047904ms)","trace[575736930] 'applied index is now lower than readState.Index' (duration: 18.179394ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T01:09:36.148141Z","caller":"traceutil/trace.go:171","msg":"trace[1778556622] transaction","detail":"{read_only:false; response_revision:25830; number_of_response:1; }","duration":"265.36373ms","start":"2026-08-12T01:09:35.882762Z","end":"2026-08-12T01:09:36.148125Z","steps":["trace[1778556622] 'process raft request' (duration: 264.714543ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:09:36.148399Z","caller":"traceutil/trace.go:171","msg":"trace[1671318229] transaction","detail":"{read_only:false; response_revision:25831; number_of_response:1; }","duration":"209.616847ms","start":"2026-08-12T01:09:35.938771Z","end":"2026-08-12T01:09:36.148388Z","steps":["trace[1671318229] 'process raft request' (duration: 208.876409ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:09:36.14867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.135491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T01:09:36.14874Z","caller":"traceutil/trace.go:171","msg":"trace[1153198768] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:25831; }","duration":"261.282035ms","start":"2026-08-12T01:09:35.887441Z","end":"2026-08-12T01:09:36.148723Z","steps":["trace[1153198768] 'agreement among raft nodes before linearized reading' (duration: 261.130131ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:09:36.149179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.520193ms","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-08-12T01:09:36.149251Z","caller":"traceutil/trace.go:171","msg":"trace[626650984] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25832; }","duration":"254.664267ms","start":"2026-08-12T01:09:35.894572Z","end":"2026-08-12T01:09:36.149236Z","steps":["trace[626650984] 'agreement among raft nodes before linearized reading' (duration: 254.41741ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:09:36.149383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.878857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-08-12T01:09:36.149414Z","caller":"traceutil/trace.go:171","msg":"trace[1546374867] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:25832; }","duration":"137.951399ms","start":"2026-08-12T01:09:36.011455Z","end":"2026-08-12T01:09:36.149406Z","steps":["trace[1546374867] 'agreement among raft nodes before linearized reading' (duration: 137.680412ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:09:36.149471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.788913ms","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-08-12T01:09:36.149528Z","caller":"traceutil/trace.go:171","msg":"trace[175619992] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25832; }","duration":"164.898246ms","start":"2026-08-12T01:09:35.984617Z","end":"2026-08-12T01:09:36.149515Z","steps":["trace[175619992] 'agreement among raft nodes before linearized reading' (duration: 164.743182ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:09:36.34449Z","caller":"traceutil/trace.go:171","msg":"trace[1341026778] transaction","detail":"{read_only:false; response_revision:25833; number_of_response:1; }","duration":"179.654373ms","start":"2026-08-12T01:09:36.164799Z","end":"2026-08-12T01:09:36.344453Z","steps":["trace[1341026778] 'process raft request' (duration: 130.745026ms)","trace[1341026778] 'compare' (duration: 48.694462ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T01:09:36.56411Z","caller":"traceutil/trace.go:171","msg":"trace[935906502] transaction","detail":"{read_only:false; response_revision:25834; number_of_response:1; }","duration":"123.125031ms","start":"2026-08-12T01:09:36.440937Z","end":"2026-08-12T01:09:36.564062Z","steps":["trace[935906502] 'process raft request' (duration: 122.828223ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:09:36.854204Z","caller":"traceutil/trace.go:171","msg":"trace[1127545605] linearizableReadLoop","detail":"{readStateIndex:26693; appliedIndex:26692; }","duration":"129.472762ms","start":"2026-08-12T01:09:36.724698Z","end":"2026-08-12T01:09:36.854171Z","steps":["trace[1127545605] 'read index received' (duration: 129.272267ms)","trace[1127545605] 'applied index is now lower than readState.Index' (duration: 199.215µs)"],"step_count":2} {"level":"warn","ts":"2026-08-12T01:09:36.854714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.009896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedrainrules/\" range_end:\"/registry/cluster.x-k8s.io/machinedrainrules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T01:09:36.854779Z","caller":"traceutil/trace.go:171","msg":"trace[445814941] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedrainrules/; range_end:/registry/cluster.x-k8s.io/machinedrainrules0; response_count:0; response_revision:25835; }","duration":"130.133559ms","start":"2026-08-12T01:09:36.724627Z","end":"2026-08-12T01:09:36.854761Z","steps":["trace[445814941] 'agreement among raft nodes before linearized reading' (duration: 130.014296ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:09:36.854776Z","caller":"traceutil/trace.go:171","msg":"trace[339335562] transaction","detail":"{read_only:false; response_revision:25835; number_of_response:1; }","duration":"131.418212ms","start":"2026-08-12T01:09:36.723264Z","end":"2026-08-12T01:09:36.854682Z","steps":["trace[339335562] 'process raft request' (duration: 130.334835ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:09:36.898445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.080219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-08-12T01:09:36.898534Z","caller":"traceutil/trace.go:171","msg":"trace[498122967] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25836; }","duration":"161.211553ms","start":"2026-08-12T01:09:36.737302Z","end":"2026-08-12T01:09:36.898514Z","steps":["trace[498122967] 'agreement among raft nodes before linearized reading' (duration: 160.974577ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:09:36.898519Z","caller":"traceutil/trace.go:171","msg":"trace[1377650941] transaction","detail":"{read_only:false; response_revision:25836; number_of_response:1; }","duration":"131.955616ms","start":"2026-08-12T01:09:36.766512Z","end":"2026-08-12T01:09:36.898467Z","steps":["trace[1377650941] 'process raft request' (duration: 131.610777ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:09:37.055113Z","caller":"traceutil/trace.go:171","msg":"trace[2048780484] transaction","detail":"{read_only:false; response_revision:25837; number_of_response:1; }","duration":"151.275619ms","start":"2026-08-12T01:09:36.90379Z","end":"2026-08-12T01:09:37.055066Z","steps":["trace[2048780484] 'process raft request' (duration: 142.503526ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:09:37.055108Z","caller":"traceutil/trace.go:171","msg":"trace[1548358317] linearizableReadLoop","detail":"{readStateIndex:26695; appliedIndex:26694; }","duration":"101.374047ms","start":"2026-08-12T01:09:36.953665Z","end":"2026-08-12T01:09:37.055039Z","steps":["trace[1548358317] 'read index received' (duration: 92.649004ms)","trace[1548358317] 'applied index is now lower than readState.Index' (duration: 8.722973ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T01:09:37.055422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.732266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-12T01:09:37.055477Z","caller":"traceutil/trace.go:171","msg":"trace[1237686065] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:25837; }","duration":"101.848608ms","start":"2026-08-12T01:09:36.953615Z","end":"2026-08-12T01:09:37.055463Z","steps":["trace[1237686065] 'agreement among raft nodes before linearized reading' (duration: 101.682914ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:09:37.212014Z","caller":"traceutil/trace.go:171","msg":"trace[1262854035] transaction","detail":"{read_only:false; response_revision:25838; number_of_response:1; }","duration":"225.558865ms","start":"2026-08-12T01:09:36.98638Z","end":"2026-08-12T01:09:37.211939Z","steps":["trace[1262854035] 'process raft request' (duration: 224.305442ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:09:37.212209Z","caller":"traceutil/trace.go:171","msg":"trace[1069231663] linearizableReadLoop","detail":"{readStateIndex:26697; appliedIndex:26695; }","duration":"156.934244ms","start":"2026-08-12T01:09:37.055248Z","end":"2026-08-12T01:09:37.212182Z","steps":["trace[1069231663] 'read index received' (duration: 155.436565ms)","trace[1069231663] 'applied index is now lower than readState.Index' (duration: 1.496789ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T01:09:37.212364Z","caller":"traceutil/trace.go:171","msg":"trace[1619907381] transaction","detail":"{read_only:false; response_revision:25839; number_of_response:1; }","duration":"194.436361ms","start":"2026-08-12T01:09:37.017822Z","end":"2026-08-12T01:09:37.212258Z","steps":["trace[1619907381] 'process raft request' (duration: 194.238746ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:09:37.212546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.036591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-08-12T01:09:37.212583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.30448ms","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-08-12T01:09:37.212679Z","caller":"traceutil/trace.go:171","msg":"trace[1466339445] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25839; }","duration":"225.188794ms","start":"2026-08-12T01:09:36.987458Z","end":"2026-08-12T01:09:37.212647Z","steps":["trace[1466339445] 'agreement among raft nodes before linearized reading' (duration: 224.867046ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:09:37.212701Z","caller":"traceutil/trace.go:171","msg":"trace[552343036] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25839; }","duration":"258.497194ms","start":"2026-08-12T01:09:36.954181Z","end":"2026-08-12T01:09:37.212679Z","steps":["trace[552343036] 'agreement among raft nodes before linearized reading' (duration: 258.136875ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:09:38.269382Z","caller":"traceutil/trace.go:171","msg":"trace[2081023058] transaction","detail":"{read_only:false; response_revision:25844; number_of_response:1; }","duration":"129.016291ms","start":"2026-08-12T01:09:38.140298Z","end":"2026-08-12T01:09:38.269315Z","steps":["trace[2081023058] 'process raft request' (duration: 128.851947ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:09:38.269429Z","caller":"traceutil/trace.go:171","msg":"trace[860126426] linearizableReadLoop","detail":"{readStateIndex:26702; appliedIndex:26702; }","duration":"115.117866ms","start":"2026-08-12T01:09:38.154267Z","end":"2026-08-12T01:09:38.269329Z","steps":["trace[860126426] 'read index received' (duration: 115.034234ms)","trace[860126426] 'applied index is now lower than readState.Index' (duration: 25.371µs)"],"step_count":2} {"level":"warn","ts":"2026-08-12T01:09:38.269766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.471656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-12T01:09:38.269814Z","caller":"traceutil/trace.go:171","msg":"trace[661294408] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25844; }","duration":"115.582468ms","start":"2026-08-12T01:09:38.154217Z","end":"2026-08-12T01:09:38.269799Z","steps":["trace[661294408] 'agreement among raft nodes before linearized reading' (duration: 115.319302ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:09:38.387425Z","caller":"traceutil/trace.go:171","msg":"trace[950332699] transaction","detail":"{read_only:false; response_revision:25845; number_of_response:1; }","duration":"231.762581ms","start":"2026-08-12T01:09:38.155636Z","end":"2026-08-12T01:09:38.387398Z","steps":["trace[950332699] 'process raft request' (duration: 230.958671ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:09:38.387877Z","caller":"traceutil/trace.go:171","msg":"trace[1793220424] transaction","detail":"{read_only:false; response_revision:25846; number_of_response:1; }","duration":"113.472855ms","start":"2026-08-12T01:09:38.274369Z","end":"2026-08-12T01:09:38.387842Z","steps":["trace[1793220424] 'process raft request' (duration: 113.080185ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:09:38.742658Z","caller":"traceutil/trace.go:171","msg":"trace[1057704107] linearizableReadLoop","detail":"{readStateIndex:26706; appliedIndex:26705; }","duration":"155.25346ms","start":"2026-08-12T01:09:38.587384Z","end":"2026-08-12T01:09:38.742638Z","steps":["trace[1057704107] 'read index received' (duration: 154.84789ms)","trace[1057704107] 'applied index is now lower than readState.Index' (duration: 404.86µs)"],"step_count":2} {"level":"warn","ts":"2026-08-12T01:09:38.743041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.639849ms","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":"info","ts":"2026-08-12T01:09:38.743069Z","caller":"traceutil/trace.go:171","msg":"trace[1789048756] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:25848; }","duration":"155.722412ms","start":"2026-08-12T01:09:38.587339Z","end":"2026-08-12T01:09:38.743061Z","steps":["trace[1789048756] 'agreement among raft nodes before linearized reading' (duration: 155.410544ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:09:38.743351Z","caller":"traceutil/trace.go:171","msg":"trace[954843842] transaction","detail":"{read_only:false; response_revision:25848; number_of_response:1; }","duration":"172.680115ms","start":"2026-08-12T01:09:38.570662Z","end":"2026-08-12T01:09:38.743343Z","steps":["trace[954843842] 'process raft request' (duration: 171.621418ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:09:39.009145Z","caller":"traceutil/trace.go:171","msg":"trace[1986852272] transaction","detail":"{read_only:false; response_revision:25849; number_of_response:1; }","duration":"148.039657ms","start":"2026-08-12T01:09:38.861072Z","end":"2026-08-12T01:09:39.009112Z","steps":["trace[1986852272] 'process raft request' (duration: 147.790251ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:09:39.315498Z","caller":"traceutil/trace.go:171","msg":"trace[408891495] transaction","detail":"{read_only:false; response_revision:25850; number_of_response:1; }","duration":"410.949114ms","start":"2026-08-12T01:09:38.90452Z","end":"2026-08-12T01:09:39.315469Z","steps":["trace[408891495] 'process raft request' (duration: 410.785149ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:09:39.315567Z","caller":"traceutil/trace.go:171","msg":"trace[1144210595] linearizableReadLoop","detail":"{readStateIndex:26708; appliedIndex:26708; }","duration":"254.193985ms","start":"2026-08-12T01:09:39.061349Z","end":"2026-08-12T01:09:39.315543Z","steps":["trace[1144210595] 'read index received' (duration: 254.182584ms)","trace[1144210595] 'applied index is now lower than readState.Index' (duration: 9.84µs)"],"step_count":2} {"level":"warn","ts":"2026-08-12T01:09:39.315698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:09:38.904498Z","time spent":"411.092198ms","remote":"127.0.0.1:35408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T01:09:39.315835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.474981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-08-12T01:09:39.315887Z","caller":"traceutil/trace.go:171","msg":"trace[1813624375] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25850; }","duration":"254.560094ms","start":"2026-08-12T01:09:39.06131Z","end":"2026-08-12T01:09:39.31587Z","steps":["trace[1813624375] 'agreement among raft nodes before linearized reading' (duration: 254.325638ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:09:39.337478Z","caller":"traceutil/trace.go:171","msg":"trace[439078418] transaction","detail":"{read_only:false; response_revision:25851; number_of_response:1; }","duration":"119.059476ms","start":"2026-08-12T01:09:39.218375Z","end":"2026-08-12T01:09:39.337435Z","steps":["trace[439078418] 'process raft request' (duration: 118.556634ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:09:39.337583Z","caller":"traceutil/trace.go:171","msg":"trace[157549997] transaction","detail":"{read_only:false; response_revision:25852; number_of_response:1; }","duration":"118.644176ms","start":"2026-08-12T01:09:39.218912Z","end":"2026-08-12T01:09:39.337556Z","steps":["trace[157549997] 'process raft request' (duration: 118.200995ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:09:39.337597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.447007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-08-12T01:09:39.337493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.099463ms","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-08-12T01:09:39.337678Z","caller":"traceutil/trace.go:171","msg":"trace[836139822] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25852; }","duration":"110.57676ms","start":"2026-08-12T01:09:39.227083Z","end":"2026-08-12T01:09:39.33766Z","steps":["trace[836139822] 'agreement among raft nodes before linearized reading' (duration: 110.20657ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:09:39.337669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.305248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T01:09:39.337771Z","caller":"traceutil/trace.go:171","msg":"trace[835345324] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:25852; }","duration":"207.470062ms","start":"2026-08-12T01:09:39.130277Z","end":"2026-08-12T01:09:39.337747Z","steps":["trace[835345324] 'agreement among raft nodes before linearized reading' (duration: 207.258327ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:09:39.337702Z","caller":"traceutil/trace.go:171","msg":"trace[1204969287] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25852; }","duration":"111.351949ms","start":"2026-08-12T01:09:39.22633Z","end":"2026-08-12T01:09:39.337682Z","steps":["trace[1204969287] 'agreement among raft nodes before linearized reading' (duration: 111.011871ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:09:39.337487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.814423ms","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-08-12T01:09:39.338054Z","caller":"traceutil/trace.go:171","msg":"trace[477514729] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25852; }","duration":"243.45218ms","start":"2026-08-12T01:09:39.09459Z","end":"2026-08-12T01:09:39.338042Z","steps":["trace[477514729] 'agreement among raft nodes before linearized reading' (duration: 242.640729ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:09:39.533388Z","caller":"traceutil/trace.go:171","msg":"trace[330448241] transaction","detail":"{read_only:false; response_revision:25855; number_of_response:1; }","duration":"189.908934ms","start":"2026-08-12T01:09:39.343454Z","end":"2026-08-12T01:09:39.533363Z","steps":["trace[330448241] 'process raft request' (duration: 189.655627ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:09:39.533523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.392745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/volumetypes/\" range_end:\"/registry/openstack.k-orc.cloud/volumetypes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T01:09:39.533566Z","caller":"traceutil/trace.go:171","msg":"trace[1324201103] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/volumetypes/; range_end:/registry/openstack.k-orc.cloud/volumetypes0; response_count:0; response_revision:25856; }","duration":"146.509128ms","start":"2026-08-12T01:09:39.387046Z","end":"2026-08-12T01:09:39.533555Z","steps":["trace[1324201103] 'agreement among raft nodes before linearized reading' (duration: 146.381724ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:09:39.533363Z","caller":"traceutil/trace.go:171","msg":"trace[879708956] linearizableReadLoop","detail":"{readStateIndex:26714; appliedIndex:26712; }","duration":"146.230741ms","start":"2026-08-12T01:09:39.387101Z","end":"2026-08-12T01:09:39.533332Z","steps":["trace[879708956] 'read index received' (duration: 146.018736ms)","trace[879708956] 'applied index is now lower than readState.Index' (duration: 210.605µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T01:09:39.533626Z","caller":"traceutil/trace.go:171","msg":"trace[1819633572] transaction","detail":"{read_only:false; response_revision:25856; number_of_response:1; }","duration":"190.14203ms","start":"2026-08-12T01:09:39.343443Z","end":"2026-08-12T01:09:39.533585Z","steps":["trace[1819633572] 'process raft request' (duration: 189.840263ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:09:39.682897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.933579ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-12T01:09:39.683072Z","caller":"traceutil/trace.go:171","msg":"trace[54470011] linearizableReadLoop","detail":"{readStateIndex:26715; appliedIndex:26714; }","duration":"149.364101ms","start":"2026-08-12T01:09:39.53368Z","end":"2026-08-12T01:09:39.683044Z","steps":["trace[54470011] 'read index received' (duration: 207.565µs)","trace[54470011] 'applied index is now lower than readState.Index' (duration: 149.114615ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T01:09:39.683195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.984167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T01:09:39.68325Z","caller":"traceutil/trace.go:171","msg":"trace[1489537876] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25856; }","duration":"250.086449ms","start":"2026-08-12T01:09:39.433147Z","end":"2026-08-12T01:09:39.683234Z","steps":["trace[1489537876] 'agreement among raft nodes before linearized reading' (duration: 249.975407ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:09:40.011741Z","caller":"traceutil/trace.go:171","msg":"trace[558096880] transaction","detail":"{read_only:false; response_revision:25857; number_of_response:1; }","duration":"121.90064ms","start":"2026-08-12T01:09:39.889814Z","end":"2026-08-12T01:09:40.011715Z","steps":["trace[558096880] 'process raft request' (duration: 121.689525ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:09:40.4438Z","caller":"traceutil/trace.go:171","msg":"trace[551704881] transaction","detail":"{read_only:false; response_revision:25858; number_of_response:1; }","duration":"118.964375ms","start":"2026-08-12T01:09:40.32478Z","end":"2026-08-12T01:09:40.443745Z","steps":["trace[551704881] 'process raft request' (duration: 118.669728ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:09:40.746685Z","caller":"traceutil/trace.go:171","msg":"trace[261605838] transaction","detail":"{read_only:false; response_revision:25861; number_of_response:1; }","duration":"292.015469ms","start":"2026-08-12T01:09:40.454637Z","end":"2026-08-12T01:09:40.746653Z","steps":["trace[261605838] 'process raft request' (duration: 291.853785ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:09:41.08467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.40103ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-12T01:09:41.08517Z","caller":"traceutil/trace.go:171","msg":"trace[1344568576] transaction","detail":"{read_only:false; response_revision:25862; number_of_response:1; }","duration":"326.727334ms","start":"2026-08-12T01:09:40.758411Z","end":"2026-08-12T01:09:41.085138Z","steps":["trace[1344568576] 'process raft request' (duration: 169.681938ms)","trace[1344568576] 'compare' (duration: 155.901897ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T01:09:41.085311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:09:40.758387Z","time spent":"326.875928ms","remote":"127.0.0.1:35408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T01:09:41.286695Z","caller":"traceutil/trace.go:171","msg":"trace[151343066] transaction","detail":"{read_only:false; response_revision:25864; number_of_response:1; }","duration":"209.474224ms","start":"2026-08-12T01:09:41.077184Z","end":"2026-08-12T01:09:41.286659Z","steps":["trace[151343066] 'process raft request' (duration: 113.191118ms)","trace[151343066] 'compare' (duration: 96.157793ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T01:09:41.497384Z","caller":"traceutil/trace.go:171","msg":"trace[1215937862] linearizableReadLoop","detail":"{readStateIndex:26724; appliedIndex:26723; }","duration":"153.198227ms","start":"2026-08-12T01:09:41.344154Z","end":"2026-08-12T01:09:41.497352Z","steps":["trace[1215937862] 'read index received' (duration: 116.781328ms)","trace[1215937862] 'applied index is now lower than readState.Index' (duration: 36.415449ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T01:09:41.497442Z","caller":"traceutil/trace.go:171","msg":"trace[999939827] transaction","detail":"{read_only:false; response_revision:25865; number_of_response:1; }","duration":"177.165059ms","start":"2026-08-12T01:09:41.320219Z","end":"2026-08-12T01:09:41.497384Z","steps":["trace[999939827] 'process raft request' (duration: 140.601636ms)","trace[999939827] 'compare' (duration: 36.328937ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T01:09:41.497631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.464594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-08-12T01:09:41.497701Z","caller":"traceutil/trace.go:171","msg":"trace[1338069884] transaction","detail":"{read_only:false; response_revision:25866; number_of_response:1; }","duration":"153.461245ms","start":"2026-08-12T01:09:41.344229Z","end":"2026-08-12T01:09:41.49769Z","steps":["trace[1338069884] 'process raft request' (duration: 153.27968ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:09:41.497743Z","caller":"traceutil/trace.go:171","msg":"trace[951209393] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25865; }","duration":"153.616199ms","start":"2026-08-12T01:09:41.344089Z","end":"2026-08-12T01:09:41.497705Z","steps":["trace[951209393] 'agreement among raft nodes before linearized reading' (duration: 153.366532ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:09:41.497945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.537386ms","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-08-12T01:09:41.497983Z","caller":"traceutil/trace.go:171","msg":"trace[398553508] transaction","detail":"{read_only:false; response_revision:25867; number_of_response:1; }","duration":"150.725495ms","start":"2026-08-12T01:09:41.347242Z","end":"2026-08-12T01:09:41.497967Z","steps":["trace[398553508] 'process raft request' (duration: 150.384586ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:09:41.498023Z","caller":"traceutil/trace.go:171","msg":"trace[853151399] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25867; }","duration":"122.642418ms","start":"2026-08-12T01:09:41.375362Z","end":"2026-08-12T01:09:41.498005Z","steps":["trace[853151399] 'agreement among raft nodes before linearized reading' (duration: 122.352371ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:09:41.498132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.177958ms","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-08-12T01:09:41.498164Z","caller":"traceutil/trace.go:171","msg":"trace[485531434] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25867; }","duration":"106.312102ms","start":"2026-08-12T01:09:41.391843Z","end":"2026-08-12T01:09:41.498155Z","steps":["trace[485531434] 'agreement among raft nodes before linearized reading' (duration: 106.21169ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:09:41.793986Z","caller":"traceutil/trace.go:171","msg":"trace[1254866522] linearizableReadLoop","detail":"{readStateIndex:26729; appliedIndex:26727; }","duration":"259.035338ms","start":"2026-08-12T01:09:41.534926Z","end":"2026-08-12T01:09:41.793961Z","steps":["trace[1254866522] 'read index received' (duration: 219.252193ms)","trace[1254866522] 'applied index is now lower than readState.Index' (duration: 39.782355ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T01:09:41.794126Z","caller":"traceutil/trace.go:171","msg":"trace[314244871] transaction","detail":"{read_only:false; response_revision:25870; number_of_response:1; }","duration":"289.914146ms","start":"2026-08-12T01:09:41.504198Z","end":"2026-08-12T01:09:41.794112Z","steps":["trace[314244871] 'process raft request' (duration: 289.641909ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:09:41.794204Z","caller":"traceutil/trace.go:171","msg":"trace[546407293] transaction","detail":"{read_only:false; response_revision:25869; number_of_response:1; }","duration":"290.09266ms","start":"2026-08-12T01:09:41.504074Z","end":"2026-08-12T01:09:41.794167Z","steps":["trace[546407293] 'process raft request' (duration: 250.088379ms)","trace[546407293] 'compare' (duration: 39.399576ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T01:09:41.794387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.634827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-08-12T01:09:41.794481Z","caller":"traceutil/trace.go:171","msg":"trace[1086487173] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25870; }","duration":"256.760969ms","start":"2026-08-12T01:09:41.537694Z","end":"2026-08-12T01:09:41.794455Z","steps":["trace[1086487173] 'agreement among raft nodes before linearized reading' (duration: 256.499623ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:09:41.794662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.129741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-12T01:09:41.79468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.718206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18230"} {"level":"info","ts":"2026-08-12T01:09:41.794768Z","caller":"traceutil/trace.go:171","msg":"trace[1683533234] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:25870; }","duration":"192.333078ms","start":"2026-08-12T01:09:41.60241Z","end":"2026-08-12T01:09:41.794743Z","steps":["trace[1683533234] 'agreement among raft nodes before linearized reading' (duration: 192.118561ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:09:41.794775Z","caller":"traceutil/trace.go:171","msg":"trace[1308433174] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25870; }","duration":"259.84904ms","start":"2026-08-12T01:09:41.534903Z","end":"2026-08-12T01:09:41.794752Z","steps":["trace[1308433174] 'agreement among raft nodes before linearized reading' (duration: 259.357106ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:09:49.050821Z","caller":"traceutil/trace.go:171","msg":"trace[1031619216] linearizableReadLoop","detail":"{readStateIndex:26781; appliedIndex:26780; }","duration":"112.85773ms","start":"2026-08-12T01:09:48.937931Z","end":"2026-08-12T01:09:49.050789Z","steps":["trace[1031619216] 'read index received' (duration: 112.634364ms)","trace[1031619216] 'applied index is now lower than readState.Index' (duration: 222.245µs)"],"step_count":2} {"level":"warn","ts":"2026-08-12T01:09:49.051215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.27174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-08-12T01:09:49.051269Z","caller":"traceutil/trace.go:171","msg":"trace[1227366842] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:25921; }","duration":"113.399973ms","start":"2026-08-12T01:09:48.937857Z","end":"2026-08-12T01:09:49.051257Z","steps":["trace[1227366842] 'agreement among raft nodes before linearized reading' (duration: 113.047564ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:09:49.051653Z","caller":"traceutil/trace.go:171","msg":"trace[407532135] transaction","detail":"{read_only:false; response_revision:25921; number_of_response:1; }","duration":"258.869113ms","start":"2026-08-12T01:09:48.792772Z","end":"2026-08-12T01:09:49.051641Z","steps":["trace[407532135] 'process raft request' (duration: 257.868318ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:09:49.348622Z","caller":"traceutil/trace.go:171","msg":"trace[1134916258] transaction","detail":"{read_only:false; response_revision:25923; number_of_response:1; }","duration":"213.269701ms","start":"2026-08-12T01:09:49.135326Z","end":"2026-08-12T01:09:49.348595Z","steps":["trace[1134916258] 'process raft request' (duration: 213.153938ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:09:49.348636Z","caller":"traceutil/trace.go:171","msg":"trace[1329170878] transaction","detail":"{read_only:false; response_revision:25922; number_of_response:1; }","duration":"229.711911ms","start":"2026-08-12T01:09:49.118891Z","end":"2026-08-12T01:09:49.348603Z","steps":["trace[1329170878] 'process raft request' (duration: 227.417062ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:09:49.836369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.011721ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-12T01:09:50.114701Z","caller":"traceutil/trace.go:171","msg":"trace[600878431] transaction","detail":"{read_only:false; response_revision:25924; number_of_response:1; }","duration":"575.4294ms","start":"2026-08-12T01:09:49.539223Z","end":"2026-08-12T01:09:50.114652Z","steps":["trace[600878431] 'process raft request' (duration: 575.147593ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:09:50.114786Z","caller":"traceutil/trace.go:171","msg":"trace[1434869585] transaction","detail":"{read_only:false; response_revision:25925; number_of_response:1; }","duration":"573.313116ms","start":"2026-08-12T01:09:49.541405Z","end":"2026-08-12T01:09:50.114718Z","steps":["trace[1434869585] 'process raft request' (duration: 573.138071ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:09:50.114691Z","caller":"traceutil/trace.go:171","msg":"trace[1413637975] linearizableReadLoop","detail":"{readStateIndex:26787; appliedIndex:26783; }","duration":"530.938464ms","start":"2026-08-12T01:09:49.583726Z","end":"2026-08-12T01:09:50.114665Z","steps":["trace[1413637975] 'read index received' (duration: 83.626714ms)","trace[1413637975] 'applied index is now lower than readState.Index' (duration: 447.31045ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T01:09:50.11486Z","caller":"traceutil/trace.go:171","msg":"trace[666993052] transaction","detail":"{read_only:false; response_revision:25926; number_of_response:1; }","duration":"572.834885ms","start":"2026-08-12T01:09:49.54197Z","end":"2026-08-12T01:09:50.114805Z","steps":["trace[666993052] 'process raft request' (duration: 572.644869ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:09:50.115019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:09:49.541374Z","time spent":"573.520582ms","remote":"127.0.0.1:35408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T01:09:50.115118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.382277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-08-12T01:09:50.115139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:09:49.541957Z","time spent":"573.088761ms","remote":"127.0.0.1:35408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T01:09:50.115178Z","caller":"traceutil/trace.go:171","msg":"trace[1567419095] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25926; }","duration":"531.480839ms","start":"2026-08-12T01:09:49.583675Z","end":"2026-08-12T01:09:50.115155Z","steps":["trace[1567419095] 'agreement among raft nodes before linearized reading' (duration: 531.222323ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:09:50.115216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:09:49.583643Z","time spent":"531.564991ms","remote":"127.0.0.1:35408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-12T01:09:50.115564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:09:49.539185Z","time spent":"575.618806ms","remote":"127.0.0.1:35408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T01:09:50.127981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.572843ms","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-08-12T01:09:50.128038Z","caller":"traceutil/trace.go:171","msg":"trace[1536198251] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25927; }","duration":"292.692206ms","start":"2026-08-12T01:09:49.835332Z","end":"2026-08-12T01:09:50.128024Z","steps":["trace[1536198251] 'agreement among raft nodes before linearized reading' (duration: 292.484881ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:09:50.12814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.433398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-12T01:09:50.128206Z","caller":"traceutil/trace.go:171","msg":"trace[2055510845] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:25927; }","duration":"161.556431ms","start":"2026-08-12T01:09:49.966634Z","end":"2026-08-12T01:09:50.128191Z","steps":["trace[2055510845] 'agreement among raft nodes before linearized reading' (duration: 161.207773ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:09:50.12823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.492724ms","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-08-12T01:09:50.128378Z","caller":"traceutil/trace.go:171","msg":"trace[1772434290] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25927; }","duration":"258.70988ms","start":"2026-08-12T01:09:49.869613Z","end":"2026-08-12T01:09:50.128323Z","steps":["trace[1772434290] 'agreement among raft nodes before linearized reading' (duration: 258.288859ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:09:50.417837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.38564ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-12T01:09:50.418014Z","caller":"traceutil/trace.go:171","msg":"trace[1524528293] transaction","detail":"{read_only:false; response_revision:25929; number_of_response:1; }","duration":"285.734109ms","start":"2026-08-12T01:09:50.132236Z","end":"2026-08-12T01:09:50.41797Z","steps":["trace[1524528293] 'process raft request' (duration: 19.864927ms)","trace[1524528293] 'compare' (duration: 265.007009ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T01:09:50.720388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.374742ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-12T01:09:50.720712Z","caller":"traceutil/trace.go:171","msg":"trace[1276391188] linearizableReadLoop","detail":"{readStateIndex:26792; appliedIndex:26790; }","duration":"228.384797ms","start":"2026-08-12T01:09:50.492271Z","end":"2026-08-12T01:09:50.720656Z","steps":["trace[1276391188] 'read index received' (duration: 65.638925ms)","trace[1276391188] 'applied index is now lower than readState.Index' (duration: 162.744252ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T01:09:50.720875Z","caller":"traceutil/trace.go:171","msg":"trace[850375821] transaction","detail":"{read_only:false; response_revision:25930; number_of_response:1; }","duration":"588.488813ms","start":"2026-08-12T01:09:50.132367Z","end":"2026-08-12T01:09:50.720856Z","steps":["trace[850375821] 'process raft request' (duration: 425.526936ms)","trace[850375821] 'compare' (duration: 162.215378ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T01:09:50.720965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:09:50.132335Z","time spent":"588.582234ms","remote":"127.0.0.1:35408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T01:09:50.721088Z","caller":"traceutil/trace.go:171","msg":"trace[587063620] transaction","detail":"{read_only:false; response_revision:25931; number_of_response:1; }","duration":"234.898252ms","start":"2026-08-12T01:09:50.486139Z","end":"2026-08-12T01:09:50.721037Z","steps":["trace[587063620] 'process raft request' (duration: 234.4123ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:09:50.721207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.942381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-08-12T01:09:50.721243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.615779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-12T01:09:50.721303Z","caller":"traceutil/trace.go:171","msg":"trace[855333069] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25931; }","duration":"229.056614ms","start":"2026-08-12T01:09:50.492229Z","end":"2026-08-12T01:09:50.721286Z","steps":["trace[855333069] 'agreement among raft nodes before linearized reading' (duration: 228.751646ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:09:50.721339Z","caller":"traceutil/trace.go:171","msg":"trace[510878803] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25931; }","duration":"182.764892ms","start":"2026-08-12T01:09:50.538547Z","end":"2026-08-12T01:09:50.721312Z","steps":["trace[510878803] 'agreement among raft nodes before linearized reading' (duration: 182.500776ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:09:58.534466Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006901.wal"} {"level":"info","ts":"2026-08-12T01:10:00.191782Z","caller":"traceutil/trace.go:171","msg":"trace[939872782] transaction","detail":"{read_only:false; response_revision:26059; number_of_response:1; }","duration":"202.424484ms","start":"2026-08-12T01:09:59.98933Z","end":"2026-08-12T01:10:00.191755Z","steps":["trace[939872782] 'process raft request' (duration: 202.25261ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:10:00.469487Z","caller":"traceutil/trace.go:171","msg":"trace[79542863] transaction","detail":"{read_only:false; response_revision:26060; number_of_response:1; }","duration":"362.751065ms","start":"2026-08-12T01:10:00.106694Z","end":"2026-08-12T01:10:00.469445Z","steps":["trace[79542863] 'process raft request' (duration: 362.452927ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:10:00.469519Z","caller":"traceutil/trace.go:171","msg":"trace[865668018] transaction","detail":"{read_only:false; response_revision:26061; number_of_response:1; }","duration":"310.291356ms","start":"2026-08-12T01:10:00.159205Z","end":"2026-08-12T01:10:00.469496Z","steps":["trace[865668018] 'process raft request' (duration: 310.107281ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:10:00.469644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:10:00.106657Z","time spent":"362.916679ms","remote":"127.0.0.1:60060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5417,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-12T01:10:00.469739Z","caller":"traceutil/trace.go:171","msg":"trace[1438855696] linearizableReadLoop","detail":"{readStateIndex:26928; appliedIndex:26922; }","duration":"283.680387ms","start":"2026-08-12T01:10:00.186048Z","end":"2026-08-12T01:10:00.469729Z","steps":["trace[1438855696] 'read index received' (duration: 5.537441ms)","trace[1438855696] 'applied index is now lower than readState.Index' (duration: 278.142226ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T01:10:00.46982Z","caller":"traceutil/trace.go:171","msg":"trace[1708379644] transaction","detail":"{read_only:false; response_revision:26062; number_of_response:1; }","duration":"310.100822ms","start":"2026-08-12T01:10:00.159711Z","end":"2026-08-12T01:10:00.469812Z","steps":["trace[1708379644] 'process raft request' (duration: 309.665371ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:10:00.469893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:10:00.159671Z","time spent":"310.169023ms","remote":"127.0.0.1:35408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T01:10:00.469986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.944584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-08-12T01:10:00.470007Z","caller":"traceutil/trace.go:171","msg":"trace[1222346812] transaction","detail":"{read_only:false; response_revision:26063; number_of_response:1; }","duration":"308.872089ms","start":"2026-08-12T01:10:00.16112Z","end":"2026-08-12T01:10:00.469992Z","steps":["trace[1222346812] 'process raft request' (duration: 308.51364ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:10:00.470034Z","caller":"traceutil/trace.go:171","msg":"trace[696059780] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26064; }","duration":"284.044316ms","start":"2026-08-12T01:10:00.185978Z","end":"2026-08-12T01:10:00.470022Z","steps":["trace[696059780] 'agreement among raft nodes before linearized reading' (duration: 283.897992ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:10:00.470081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.566619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-12T01:10:00.470094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:10:00.161104Z","time spent":"308.945992ms","remote":"127.0.0.1:35408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T01:10:00.470124Z","caller":"traceutil/trace.go:171","msg":"trace[1101069450] transaction","detail":"{read_only:false; response_revision:26064; number_of_response:1; }","duration":"306.954381ms","start":"2026-08-12T01:10:00.163163Z","end":"2026-08-12T01:10:00.470117Z","steps":["trace[1101069450] 'process raft request' (duration: 306.533041ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:10:00.470132Z","caller":"traceutil/trace.go:171","msg":"trace[45520724] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:26064; }","duration":"155.660641ms","start":"2026-08-12T01:10:00.314459Z","end":"2026-08-12T01:10:00.47012Z","steps":["trace[45520724] 'agreement among raft nodes before linearized reading' (duration: 155.58533ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:10:00.470164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:10:00.163152Z","time spent":"306.987022ms","remote":"127.0.0.1:35408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T01:10:00.471425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:10:00.15917Z","time spent":"310.417609ms","remote":"127.0.0.1:35408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T01:10:00.707762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.177121ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:18"} {"level":"info","ts":"2026-08-12T01:10:00.707864Z","caller":"traceutil/trace.go:171","msg":"trace[1485156287] transaction","detail":"{read_only:false; response_revision:26065; number_of_response:1; }","duration":"233.503376ms","start":"2026-08-12T01:10:00.474335Z","end":"2026-08-12T01:10:00.707839Z","steps":["trace[1485156287] 'process raft request' (duration: 103.196301ms)","trace[1485156287] 'compare' (duration: 130.055138ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T01:10:00.719693Z","caller":"traceutil/trace.go:171","msg":"trace[1563571027] linearizableReadLoop","detail":"{readStateIndex:26933; appliedIndex:26928; }","duration":"143.774317ms","start":"2026-08-12T01:10:00.575899Z","end":"2026-08-12T01:10:00.719673Z","steps":["trace[1563571027] 'read index received' (duration: 1.734144ms)","trace[1563571027] 'applied index is now lower than readState.Index' (duration: 142.039333ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T01:10:00.719701Z","caller":"traceutil/trace.go:171","msg":"trace[1456778243] transaction","detail":"{read_only:false; response_revision:26066; number_of_response:1; }","duration":"244.295302ms","start":"2026-08-12T01:10:00.475368Z","end":"2026-08-12T01:10:00.719663Z","steps":["trace[1456778243] 'process raft request' (duration: 244.053076ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:10:00.719972Z","caller":"traceutil/trace.go:171","msg":"trace[777597122] transaction","detail":"{read_only:false; response_revision:26069; number_of_response:1; }","duration":"242.450335ms","start":"2026-08-12T01:10:00.477508Z","end":"2026-08-12T01:10:00.719958Z","steps":["trace[777597122] 'process raft request' (duration: 242.121536ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:10:00.72007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.159017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3236"} {"level":"info","ts":"2026-08-12T01:10:00.720112Z","caller":"traceutil/trace.go:171","msg":"trace[1683901633] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:26069; }","duration":"144.247069ms","start":"2026-08-12T01:10:00.575851Z","end":"2026-08-12T01:10:00.720098Z","steps":["trace[1683901633] 'agreement among raft nodes before linearized reading' (duration: 144.117326ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:10:00.720139Z","caller":"traceutil/trace.go:171","msg":"trace[1698886448] transaction","detail":"{read_only:false; response_revision:26067; number_of_response:1; }","duration":"244.451366ms","start":"2026-08-12T01:10:00.475681Z","end":"2026-08-12T01:10:00.720133Z","steps":["trace[1698886448] 'process raft request' (duration: 243.850471ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:10:00.72011Z","caller":"traceutil/trace.go:171","msg":"trace[697280961] transaction","detail":"{read_only:false; response_revision:26068; number_of_response:1; }","duration":"244.005544ms","start":"2026-08-12T01:10:00.476096Z","end":"2026-08-12T01:10:00.720101Z","steps":["trace[697280961] 'process raft request' (duration: 243.490781ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:10:00.868567Z","caller":"traceutil/trace.go:171","msg":"trace[1494002825] linearizableReadLoop","detail":"{readStateIndex:26934; appliedIndex:26933; }","duration":"142.792313ms","start":"2026-08-12T01:10:00.725733Z","end":"2026-08-12T01:10:00.868525Z","steps":["trace[1494002825] 'read index received' (duration: 141.145291ms)","trace[1494002825] 'applied index is now lower than readState.Index' (duration: 1.644902ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T01:10:00.868623Z","caller":"traceutil/trace.go:171","msg":"trace[1522093825] transaction","detail":"{read_only:false; response_revision:26070; number_of_response:1; }","duration":"144.004813ms","start":"2026-08-12T01:10:00.724577Z","end":"2026-08-12T01:10:00.868582Z","steps":["trace[1522093825] 'process raft request' (duration: 142.305301ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:10:00.868818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.06721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" ","response":"range_response_count:1 size:1155"} {"level":"info","ts":"2026-08-12T01:10:00.868883Z","caller":"traceutil/trace.go:171","msg":"trace[1253794142] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:1; response_revision:26070; }","duration":"143.165972ms","start":"2026-08-12T01:10:00.7257Z","end":"2026-08-12T01:10:00.868866Z","steps":["trace[1253794142] 'agreement among raft nodes before linearized reading' (duration: 142.936897ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:10:00.885345Z","caller":"traceutil/trace.go:171","msg":"trace[414857917] transaction","detail":"{read_only:false; response_revision:26071; number_of_response:1; }","duration":"158.959765ms","start":"2026-08-12T01:10:00.726346Z","end":"2026-08-12T01:10:00.885306Z","steps":["trace[414857917] 'process raft request' (duration: 158.669698ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:10:00.885804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.844469ms","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-08-12T01:10:00.88584Z","caller":"traceutil/trace.go:171","msg":"trace[665268590] transaction","detail":"{read_only:false; response_revision:26072; number_of_response:1; }","duration":"158.528364ms","start":"2026-08-12T01:10:00.727285Z","end":"2026-08-12T01:10:00.885813Z","steps":["trace[665268590] 'process raft request' (duration: 157.927209ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:10:00.885893Z","caller":"traceutil/trace.go:171","msg":"trace[1156188702] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26073; }","duration":"112.982182ms","start":"2026-08-12T01:10:00.772894Z","end":"2026-08-12T01:10:00.885876Z","steps":["trace[1156188702] 'agreement among raft nodes before linearized reading' (duration: 112.731496ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:10:00.88596Z","caller":"traceutil/trace.go:171","msg":"trace[1686393238] transaction","detail":"{read_only:false; response_revision:26073; number_of_response:1; }","duration":"127.883272ms","start":"2026-08-12T01:10:00.75806Z","end":"2026-08-12T01:10:00.885943Z","steps":["trace[1686393238] 'process raft request' (duration: 127.39148ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:10:00.886057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.945688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/networks/\" range_end:\"/registry/openstack.k-orc.cloud/networks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-12T01:10:00.886169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.936723ms","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-08-12T01:10:00.886221Z","caller":"traceutil/trace.go:171","msg":"trace[130188164] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26073; }","duration":"117.116628ms","start":"2026-08-12T01:10:00.769091Z","end":"2026-08-12T01:10:00.886207Z","steps":["trace[130188164] 'agreement among raft nodes before linearized reading' (duration: 116.8559ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:10:00.886217Z","caller":"traceutil/trace.go:171","msg":"trace[2095747864] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/networks/; range_end:/registry/openstack.k-orc.cloud/networks0; response_count:0; response_revision:26073; }","duration":"127.166664ms","start":"2026-08-12T01:10:00.759022Z","end":"2026-08-12T01:10:00.886189Z","steps":["trace[2095747864] 'agreement among raft nodes before linearized reading' (duration: 126.870326ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:10:00.886284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.454042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-12T01:10:00.886326Z","caller":"traceutil/trace.go:171","msg":"trace[1426892951] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26073; }","duration":"118.529604ms","start":"2026-08-12T01:10:00.767786Z","end":"2026-08-12T01:10:00.886316Z","steps":["trace[1426892951] 'agreement among raft nodes before linearized reading' (duration: 118.34501ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:10:01.078968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.180621ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:18"} {"level":"info","ts":"2026-08-12T01:10:01.079072Z","caller":"traceutil/trace.go:171","msg":"trace[1081583324] transaction","detail":"{read_only:false; response_revision:26074; number_of_response:1; }","duration":"188.719404ms","start":"2026-08-12T01:10:00.890335Z","end":"2026-08-12T01:10:01.079055Z","steps":["trace[1081583324] 'process raft request' (duration: 85.380939ms)","trace[1081583324] 'compare' (duration: 102.943765ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T01:10:01.166282Z","caller":"traceutil/trace.go:171","msg":"trace[1533446702] linearizableReadLoop","detail":"{readStateIndex:26940; appliedIndex:26937; }","duration":"272.373098ms","start":"2026-08-12T01:10:00.893881Z","end":"2026-08-12T01:10:01.166254Z","steps":["trace[1533446702] 'read index received' (duration: 81.854938ms)","trace[1533446702] 'applied index is now lower than readState.Index' (duration: 190.5169ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T01:10:01.166691Z","caller":"traceutil/trace.go:171","msg":"trace[1616531220] transaction","detail":"{read_only:false; response_revision:26076; number_of_response:1; }","duration":"274.39723ms","start":"2026-08-12T01:10:00.892272Z","end":"2026-08-12T01:10:01.166669Z","steps":["trace[1616531220] 'process raft request' (duration: 273.827695ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:10:01.166684Z","caller":"traceutil/trace.go:171","msg":"trace[1039184722] transaction","detail":"{read_only:false; response_revision:26077; number_of_response:1; }","duration":"272.096481ms","start":"2026-08-12T01:10:00.894477Z","end":"2026-08-12T01:10:01.166573Z","steps":["trace[1039184722] 'process raft request' (duration: 271.69009ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:10:01.167158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.087127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/heat-engine-cleaner-29774950-pwx8w\" ","response":"range_response_count:1 size:5678"} {"level":"info","ts":"2026-08-12T01:10:01.167573Z","caller":"traceutil/trace.go:171","msg":"trace[415577102] range","detail":"{range_begin:/registry/pods/openstack/heat-engine-cleaner-29774950-pwx8w; range_end:; response_count:1; response_revision:26078; }","duration":"246.564309ms","start":"2026-08-12T01:10:00.920992Z","end":"2026-08-12T01:10:01.167556Z","steps":["trace[415577102] 'agreement among raft nodes before linearized reading' (duration: 245.989235ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:10:01.167159Z","caller":"traceutil/trace.go:171","msg":"trace[156904879] transaction","detail":"{read_only:false; response_revision:26075; number_of_response:1; }","duration":"275.207671ms","start":"2026-08-12T01:10:00.891927Z","end":"2026-08-12T01:10:01.167134Z","steps":["trace[156904879] 'process raft request' (duration: 273.996469ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:10:01.167226Z","caller":"traceutil/trace.go:171","msg":"trace[763414533] transaction","detail":"{read_only:false; response_revision:26078; number_of_response:1; }","duration":"271.860005ms","start":"2026-08-12T01:10:00.895312Z","end":"2026-08-12T01:10:01.167172Z","steps":["trace[763414533] 'process raft request' (duration: 270.90084ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:10:01.167406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.495288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:1442"} {"level":"info","ts":"2026-08-12T01:10:01.167809Z","caller":"traceutil/trace.go:171","msg":"trace[699083933] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:26078; }","duration":"274.278556ms","start":"2026-08-12T01:10:00.893518Z","end":"2026-08-12T01:10:01.167797Z","steps":["trace[699083933] 'agreement among raft nodes before linearized reading' (duration: 273.672031ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:10:01.167459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.386416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/persistence-rabbitmq-manila-server-0.18cae98e10ccc9e6\" ","response":"range_response_count:1 size:1010"} {"level":"info","ts":"2026-08-12T01:10:01.167865Z","caller":"traceutil/trace.go:171","msg":"trace[1397958466] range","detail":"{range_begin:/registry/events/openstack/persistence-rabbitmq-manila-server-0.18cae98e10ccc9e6; range_end:; response_count:1; response_revision:26078; }","duration":"170.864588ms","start":"2026-08-12T01:10:00.996979Z","end":"2026-08-12T01:10:01.167844Z","steps":["trace[1397958466] 'agreement among raft nodes before linearized reading' (duration: 170.378806ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:10:01.167442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.750866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T01:10:01.16799Z","caller":"traceutil/trace.go:171","msg":"trace[2097765147] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:26078; }","duration":"162.432064ms","start":"2026-08-12T01:10:01.005545Z","end":"2026-08-12T01:10:01.167977Z","steps":["trace[2097765147] 'agreement among raft nodes before linearized reading' (duration: 161.677155ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:10:01.375556Z","caller":"traceutil/trace.go:171","msg":"trace[664824590] transaction","detail":"{read_only:false; response_revision:26082; number_of_response:1; }","duration":"101.757305ms","start":"2026-08-12T01:10:01.273753Z","end":"2026-08-12T01:10:01.37551Z","steps":["trace[664824590] 'process raft request' (duration: 78.519783ms)","trace[664824590] 'compare' (duration: 22.726329ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T01:10:03.321518Z","caller":"traceutil/trace.go:171","msg":"trace[1008723549] transaction","detail":"{read_only:false; response_revision:26102; number_of_response:1; }","duration":"144.909527ms","start":"2026-08-12T01:10:03.176572Z","end":"2026-08-12T01:10:03.321481Z","steps":["trace[1008723549] 'process raft request' (duration: 144.690562ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:10:03.477649Z","caller":"traceutil/trace.go:171","msg":"trace[1472432819] linearizableReadLoop","detail":"{readStateIndex:26968; appliedIndex:26965; }","duration":"269.401373ms","start":"2026-08-12T01:10:03.208224Z","end":"2026-08-12T01:10:03.477626Z","steps":["trace[1472432819] 'read index received' (duration: 113.033624ms)","trace[1472432819] 'applied index is now lower than readState.Index' (duration: 156.367119ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T01:10:03.477694Z","caller":"traceutil/trace.go:171","msg":"trace[1597281983] transaction","detail":"{read_only:false; response_revision:26104; number_of_response:1; }","duration":"300.585608ms","start":"2026-08-12T01:10:03.177066Z","end":"2026-08-12T01:10:03.477651Z","steps":["trace[1597281983] 'process raft request' (duration: 300.456685ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:10:03.477777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.546417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T01:10:03.477802Z","caller":"traceutil/trace.go:171","msg":"trace[1945688741] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0; response_count:0; response_revision:26104; }","duration":"269.615419ms","start":"2026-08-12T01:10:03.208178Z","end":"2026-08-12T01:10:03.477793Z","steps":["trace[1945688741] 'agreement among raft nodes before linearized reading' (duration: 269.553977ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:10:03.477905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:10:03.177058Z","time spent":"300.722302ms","remote":"127.0.0.1:35408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T01:10:03.477767Z","caller":"traceutil/trace.go:171","msg":"trace[1557611234] transaction","detail":"{read_only:false; response_revision:26103; number_of_response:1; }","duration":"300.801294ms","start":"2026-08-12T01:10:03.176931Z","end":"2026-08-12T01:10:03.477732Z","steps":["trace[1557611234] 'process raft request' (duration: 241.845011ms)","trace[1557611234] 'compare' (duration: 58.46576ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T01:10:03.478272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:10:03.176923Z","time spent":"301.142413ms","remote":"127.0.0.1:35408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T01:10:03.490836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.874976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-08-12T01:10:03.490888Z","caller":"traceutil/trace.go:171","msg":"trace[1363867561] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26106; }","duration":"213.975788ms","start":"2026-08-12T01:10:03.2769Z","end":"2026-08-12T01:10:03.490876Z","steps":["trace[1363867561] 'agreement among raft nodes before linearized reading' (duration: 213.809564ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:10:03.491199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.291521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3236"} {"level":"info","ts":"2026-08-12T01:10:03.491309Z","caller":"traceutil/trace.go:171","msg":"trace[574412837] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:26106; }","duration":"115.461245ms","start":"2026-08-12T01:10:03.375824Z","end":"2026-08-12T01:10:03.491285Z","steps":["trace[574412837] 'agreement among raft nodes before linearized reading' (duration: 115.057645ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:10:12.765459Z","caller":"traceutil/trace.go:171","msg":"trace[646608473] linearizableReadLoop","detail":"{readStateIndex:27074; appliedIndex:27073; }","duration":"142.024433ms","start":"2026-08-12T01:10:12.6234Z","end":"2026-08-12T01:10:12.765424Z","steps":["trace[646608473] 'read index received' (duration: 141.911919ms)","trace[646608473] 'applied index is now lower than readState.Index' (duration: 111.514µs)"],"step_count":2} {"level":"warn","ts":"2026-08-12T01:10:12.76571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.27267ms","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-08-12T01:10:12.765766Z","caller":"traceutil/trace.go:171","msg":"trace[2059190692] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26207; }","duration":"142.400674ms","start":"2026-08-12T01:10:12.623355Z","end":"2026-08-12T01:10:12.765756Z","steps":["trace[2059190692] 'agreement among raft nodes before linearized reading' (duration: 142.26559ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:10:12.765809Z","caller":"traceutil/trace.go:171","msg":"trace[1963959463] transaction","detail":"{read_only:false; response_revision:26207; number_of_response:1; }","duration":"148.393717ms","start":"2026-08-12T01:10:12.61738Z","end":"2026-08-12T01:10:12.765774Z","steps":["trace[1963959463] 'process raft request' (duration: 147.836289ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:10:12.870481Z","caller":"traceutil/trace.go:171","msg":"trace[672021923] transaction","detail":"{read_only:false; response_revision:26208; number_of_response:1; }","duration":"242.835866ms","start":"2026-08-12T01:10:12.627605Z","end":"2026-08-12T01:10:12.870441Z","steps":["trace[672021923] 'process raft request' (duration: 242.487394ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:10:12.870489Z","caller":"traceutil/trace.go:171","msg":"trace[725397453] transaction","detail":"{read_only:false; response_revision:26209; number_of_response:1; }","duration":"241.836831ms","start":"2026-08-12T01:10:12.62861Z","end":"2026-08-12T01:10:12.870447Z","steps":["trace[725397453] 'process raft request' (duration: 241.717547ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:10:12.870801Z","caller":"traceutil/trace.go:171","msg":"trace[1750033847] transaction","detail":"{read_only:false; response_revision:26210; number_of_response:1; }","duration":"242.13914ms","start":"2026-08-12T01:10:12.628628Z","end":"2026-08-12T01:10:12.870767Z","steps":["trace[1750033847] 'process raft request' (duration: 241.753468ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:10:12.87092Z","caller":"traceutil/trace.go:171","msg":"trace[85914712] transaction","detail":"{read_only:false; response_revision:26211; number_of_response:1; }","duration":"182.761686ms","start":"2026-08-12T01:10:12.688132Z","end":"2026-08-12T01:10:12.870893Z","steps":["trace[85914712] 'process raft request' (duration: 182.560739ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:10:12.870902Z","caller":"traceutil/trace.go:171","msg":"trace[215305926] linearizableReadLoop","detail":"{readStateIndex:27078; appliedIndex:27074; }","duration":"100.706717ms","start":"2026-08-12T01:10:12.770161Z","end":"2026-08-12T01:10:12.870868Z","steps":["trace[215305926] 'read index received' (duration: 99.904451ms)","trace[215305926] 'applied index is now lower than readState.Index' (duration: 800.466µs)"],"step_count":2} {"level":"warn","ts":"2026-08-12T01:10:12.871103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.910644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T01:10:12.87116Z","caller":"traceutil/trace.go:171","msg":"trace[594575154] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26211; }","duration":"101.069308ms","start":"2026-08-12T01:10:12.770079Z","end":"2026-08-12T01:10:12.871148Z","steps":["trace[594575154] 'agreement among raft nodes before linearized reading' (duration: 100.899173ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:10:25.272456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.559498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T01:10:25.272553Z","caller":"traceutil/trace.go:171","msg":"trace[1369733060] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:26313; }","duration":"158.711143ms","start":"2026-08-12T01:10:25.113821Z","end":"2026-08-12T01:10:25.272532Z","steps":["trace[1369733060] 'range keys from in-memory index tree' (duration: 158.478996ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:10:25.272621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.479566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/glance-api-8685dfd94f-m7glq.18cae7e8146d4420\" ","response":"range_response_count:1 size:2108"} {"level":"info","ts":"2026-08-12T01:10:25.272757Z","caller":"traceutil/trace.go:171","msg":"trace[1683666493] range","detail":"{range_begin:/registry/events/openstack/glance-api-8685dfd94f-m7glq.18cae7e8146d4420; range_end:; response_count:1; response_revision:26313; }","duration":"127.695513ms","start":"2026-08-12T01:10:25.145024Z","end":"2026-08-12T01:10:25.27272Z","steps":["trace[1683666493] 'range keys from in-memory index tree' (duration: 127.195337ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:10:25.279486Z","caller":"traceutil/trace.go:171","msg":"trace[812930928] transaction","detail":"{read_only:false; response_revision:26314; number_of_response:1; }","duration":"105.379851ms","start":"2026-08-12T01:10:25.174074Z","end":"2026-08-12T01:10:25.279454Z","steps":["trace[812930928] 'process raft request' (duration: 105.193486ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:10:44.632375Z","caller":"traceutil/trace.go:171","msg":"trace[317374247] linearizableReadLoop","detail":"{readStateIndex:27335; appliedIndex:27334; }","duration":"195.923747ms","start":"2026-08-12T01:10:44.436416Z","end":"2026-08-12T01:10:44.63234Z","steps":["trace[317374247] 'read index received' (duration: 168.952849ms)","trace[317374247] 'applied index is now lower than readState.Index' (duration: 26.968698ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T01:10:44.63243Z","caller":"traceutil/trace.go:171","msg":"trace[1330623228] transaction","detail":"{read_only:false; response_revision:26461; number_of_response:1; }","duration":"284.903064ms","start":"2026-08-12T01:10:44.347498Z","end":"2026-08-12T01:10:44.632401Z","steps":["trace[1330623228] 'process raft request' (duration: 257.979467ms)","trace[1330623228] 'compare' (duration: 25.795759ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T01:10:44.632561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.130893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T01:10:44.632602Z","caller":"traceutil/trace.go:171","msg":"trace[1642471797] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26461; }","duration":"196.232235ms","start":"2026-08-12T01:10:44.436356Z","end":"2026-08-12T01:10:44.632589Z","steps":["trace[1642471797] 'agreement among raft nodes before linearized reading' (duration: 196.093112ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:10:44.925596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.184018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1936"} {"level":"info","ts":"2026-08-12T01:10:44.925694Z","caller":"traceutil/trace.go:171","msg":"trace[246168976] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:26461; }","duration":"157.309821ms","start":"2026-08-12T01:10:44.768362Z","end":"2026-08-12T01:10:44.925672Z","steps":["trace[246168976] 'range keys from in-memory index tree' (duration: 156.996603ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:10:45.236347Z","caller":"traceutil/trace.go:171","msg":"trace[130601097] transaction","detail":"{read_only:false; response_revision:26463; number_of_response:1; }","duration":"233.005057ms","start":"2026-08-12T01:10:45.003317Z","end":"2026-08-12T01:10:45.236322Z","steps":["trace[130601097] 'process raft request' (duration: 232.792792ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:10:45.236739Z","caller":"traceutil/trace.go:171","msg":"trace[1749443516] transaction","detail":"{read_only:false; response_revision:26464; number_of_response:1; }","duration":"233.228443ms","start":"2026-08-12T01:10:45.003469Z","end":"2026-08-12T01:10:45.236698Z","steps":["trace[1749443516] 'process raft request' (duration: 232.787972ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:10:45.236913Z","caller":"traceutil/trace.go:171","msg":"trace[1890371516] transaction","detail":"{read_only:false; response_revision:26465; number_of_response:1; }","duration":"232.782682ms","start":"2026-08-12T01:10:45.004117Z","end":"2026-08-12T01:10:45.2369Z","steps":["trace[1890371516] 'process raft request' (duration: 232.470735ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:10:45.237306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.74562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-08-12T01:10:45.237359Z","caller":"traceutil/trace.go:171","msg":"trace[917753242] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26465; }","duration":"188.860223ms","start":"2026-08-12T01:10:45.048485Z","end":"2026-08-12T01:10:45.237345Z","steps":["trace[917753242] 'agreement among raft nodes before linearized reading' (duration: 188.703379ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:10:45.237147Z","caller":"traceutil/trace.go:171","msg":"trace[311442607] linearizableReadLoop","detail":"{readStateIndex:27339; appliedIndex:27339; }","duration":"188.582776ms","start":"2026-08-12T01:10:45.048543Z","end":"2026-08-12T01:10:45.237125Z","steps":["trace[311442607] 'read index received' (duration: 188.555185ms)","trace[311442607] 'applied index is now lower than readState.Index' (duration: 24.831µs)"],"step_count":2} {"level":"warn","ts":"2026-08-12T01:10:45.237967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.234129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-08-12T01:10:45.238047Z","caller":"traceutil/trace.go:171","msg":"trace[1966932642] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26465; }","duration":"184.336251ms","start":"2026-08-12T01:10:45.053692Z","end":"2026-08-12T01:10:45.238028Z","steps":["trace[1966932642] 'agreement among raft nodes before linearized reading' (duration: 184.163096ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:10:45.238679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.014926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusteridentities/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusteridentities0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-12T01:10:45.238791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.320714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-12T01:10:45.238798Z","caller":"traceutil/trace.go:171","msg":"trace[410112885] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusteridentities/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusteridentities0; response_count:0; response_revision:26465; }","duration":"168.271892ms","start":"2026-08-12T01:10:45.070492Z","end":"2026-08-12T01:10:45.238764Z","steps":["trace[410112885] 'agreement among raft nodes before linearized reading' (duration: 168.022426ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:10:45.23886Z","caller":"traceutil/trace.go:171","msg":"trace[1267834716] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:26465; }","duration":"143.430216ms","start":"2026-08-12T01:10:45.095414Z","end":"2026-08-12T01:10:45.238844Z","steps":["trace[1267834716] 'agreement among raft nodes before linearized reading' (duration: 142.985455ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:10:45.855077Z","caller":"traceutil/trace.go:171","msg":"trace[1395754151] transaction","detail":"{read_only:false; response_revision:26470; number_of_response:1; }","duration":"184.598087ms","start":"2026-08-12T01:10:45.67044Z","end":"2026-08-12T01:10:45.855038Z","steps":["trace[1395754151] 'process raft request' (duration: 184.382172ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:10:45.945306Z","caller":"traceutil/trace.go:171","msg":"trace[2074635098] linearizableReadLoop","detail":"{readStateIndex:27345; appliedIndex:27344; }","duration":"235.819867ms","start":"2026-08-12T01:10:45.709446Z","end":"2026-08-12T01:10:45.945266Z","steps":["trace[2074635098] 'read index received' (duration: 145.660342ms)","trace[2074635098] 'applied index is now lower than readState.Index' (duration: 90.158255ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T01:10:45.945605Z","caller":"traceutil/trace.go:171","msg":"trace[1412160516] transaction","detail":"{read_only:false; response_revision:26471; number_of_response:1; }","duration":"274.433115ms","start":"2026-08-12T01:10:45.671142Z","end":"2026-08-12T01:10:45.945575Z","steps":["trace[1412160516] 'process raft request' (duration: 259.254319ms)","trace[1412160516] 'compare' (duration: 14.471538ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T01:10:45.945625Z","caller":"traceutil/trace.go:171","msg":"trace[1646941607] transaction","detail":"{read_only:false; response_revision:26472; number_of_response:1; }","duration":"171.603165ms","start":"2026-08-12T01:10:45.773981Z","end":"2026-08-12T01:10:45.945584Z","steps":["trace[1646941607] 'process raft request' (duration: 171.202745ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:10:45.94577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.296279ms","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-08-12T01:10:45.945893Z","caller":"traceutil/trace.go:171","msg":"trace[1103035707] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26472; }","duration":"236.433452ms","start":"2026-08-12T01:10:45.709401Z","end":"2026-08-12T01:10:45.945834Z","steps":["trace[1103035707] 'agreement among raft nodes before linearized reading' (duration: 236.174676ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:10:45.945917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.690994ms","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-08-12T01:10:45.946Z","caller":"traceutil/trace.go:171","msg":"trace[933565746] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26472; }","duration":"231.809038ms","start":"2026-08-12T01:10:45.714172Z","end":"2026-08-12T01:10:45.945981Z","steps":["trace[933565746] 'agreement among raft nodes before linearized reading' (duration: 231.454659ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:10:45.946104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.177532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-12T01:10:45.946222Z","caller":"traceutil/trace.go:171","msg":"trace[1189225191] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26472; }","duration":"235.406467ms","start":"2026-08-12T01:10:45.710796Z","end":"2026-08-12T01:10:45.946203Z","steps":["trace[1189225191] 'agreement among raft nodes before linearized reading' (duration: 234.802112ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:10:47.992559Z","caller":"traceutil/trace.go:171","msg":"trace[1535120485] transaction","detail":"{read_only:false; response_revision:26491; number_of_response:1; }","duration":"130.832695ms","start":"2026-08-12T01:10:47.861697Z","end":"2026-08-12T01:10:47.99253Z","steps":["trace[1535120485] 'process raft request' (duration: 130.671921ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:10:48.125984Z","caller":"traceutil/trace.go:171","msg":"trace[596128206] transaction","detail":"{read_only:false; response_revision:26495; number_of_response:1; }","duration":"108.967832ms","start":"2026-08-12T01:10:48.016967Z","end":"2026-08-12T01:10:48.125935Z","steps":["trace[596128206] 'process raft request' (duration: 108.385376ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:10:48.126043Z","caller":"traceutil/trace.go:171","msg":"trace[1096379700] transaction","detail":"{read_only:false; response_revision:26496; number_of_response:1; }","duration":"108.973882ms","start":"2026-08-12T01:10:48.017036Z","end":"2026-08-12T01:10:48.12601Z","steps":["trace[1096379700] 'process raft request' (duration: 108.618053ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:10:48.126173Z","caller":"traceutil/trace.go:171","msg":"trace[359267871] transaction","detail":"{read_only:false; response_revision:26497; number_of_response:1; }","duration":"104.932483ms","start":"2026-08-12T01:10:48.021122Z","end":"2026-08-12T01:10:48.126055Z","steps":["trace[359267871] 'process raft request' (duration: 104.721637ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:13:21.305131Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25127} {"level":"info","ts":"2026-08-12T01:13:21.450057Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25127,"took":"140.373661ms","hash":3734798042,"current-db-size-bytes":58998784,"current-db-size":"59 MB","current-db-size-in-use-bytes":37064704,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-08-12T01:13:21.450115Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3734798042,"revision":25127,"compact-revision":21903} {"level":"info","ts":"2026-08-12T01:15:09.561912Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d71a1778b31d4ce","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-12T01:15:09.566458Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-08-12T01:15:09.566589Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-08-12T01:18:21.311174Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27921} {"level":"info","ts":"2026-08-12T01:18:21.447365Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27921,"took":"133.105056ms","hash":284296661,"current-db-size-bytes":58998784,"current-db-size":"59 MB","current-db-size-in-use-bytes":33234944,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-08-12T01:18:21.447446Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":284296661,"revision":27921,"compact-revision":25127} {"level":"info","ts":"2026-08-12T01:23:21.318745Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30486} {"level":"info","ts":"2026-08-12T01:23:21.454175Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30486,"took":"130.901637ms","hash":4291684718,"current-db-size-bytes":58998784,"current-db-size":"59 MB","current-db-size-in-use-bytes":30593024,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-12T01:23:21.454254Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4291684718,"revision":30486,"compact-revision":27921} {"level":"info","ts":"2026-08-12T01:28:21.32593Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32741} {"level":"info","ts":"2026-08-12T01:28:21.478808Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32741,"took":"147.953275ms","hash":700425332,"current-db-size-bytes":60043264,"current-db-size":"60 MB","current-db-size-in-use-bytes":30203904,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-12T01:28:21.478876Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":700425332,"revision":32741,"compact-revision":30486} {"level":"info","ts":"2026-08-12T01:33:21.334335Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34971} {"level":"info","ts":"2026-08-12T01:33:21.494266Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34971,"took":"155.119401ms","hash":1895680594,"current-db-size-bytes":60043264,"current-db-size":"60 MB","current-db-size-in-use-bytes":31084544,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-12T01:33:21.494377Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1895680594,"revision":34971,"compact-revision":32741} {"level":"info","ts":"2026-08-12T01:36:39.458246Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d71a1778b31d4ce","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-12T01:36:39.461391Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-08-12T01:36:39.461466Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-08-12T01:38:21.340875Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37268} {"level":"info","ts":"2026-08-12T01:38:21.458531Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37268,"took":"113.025926ms","hash":244766217,"current-db-size-bytes":60043264,"current-db-size":"60 MB","current-db-size-in-use-bytes":32010240,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-12T01:38:21.458608Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":244766217,"revision":37268,"compact-revision":34971} {"level":"info","ts":"2026-08-12T01:43:21.350571Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39540} {"level":"info","ts":"2026-08-12T01:43:21.477985Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39540,"took":"123.182298ms","hash":2334021341,"current-db-size-bytes":60043264,"current-db-size":"60 MB","current-db-size-in-use-bytes":30785536,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-12T01:43:21.478032Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2334021341,"revision":39540,"compact-revision":37268}