{"level":"warn","ts":"2026-06-02T12:21:22.57263Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-02T12:21:22.572749Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.57: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.57:2380","--initial-cluster=instance=https://199.204.45.57:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.57:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.57: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-06-02T12:21:22.572826Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-02T12:21:22.572846Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.57:2380"]} {"level":"info","ts":"2026-06-02T12:21:22.572866Z","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-06-02T12:21:22.573571Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"]} {"level":"info","ts":"2026-06-02T12:21:22.573664Z","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.57:2380"],"listen-peer-urls":["https://199.204.45.57:2380"],"advertise-client-urls":["https://199.204.45.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.57: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-06-02T12:21:22.580583Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.685618ms"} {"level":"info","ts":"2026-06-02T12:21:22.588713Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b6478f0ac5aeb8e1","cluster-id":"6c58cff80d4a5b62"} {"level":"info","ts":"2026-06-02T12:21:22.58884Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=()"} {"level":"info","ts":"2026-06-02T12:21:22.588901Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became follower at term 0"} {"level":"info","ts":"2026-06-02T12:21:22.588921Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b6478f0ac5aeb8e1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-02T12:21:22.588935Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became follower at term 1"} {"level":"info","ts":"2026-06-02T12:21:22.588996Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=(13134624114678348001)"} {"level":"warn","ts":"2026-06-02T12:21:22.597508Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-02T12:21:22.601667Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-02T12:21:22.60371Z","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-06-02T12:21:22.606755Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b6478f0ac5aeb8e1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-02T12:21:22.606924Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b6478f0ac5aeb8e1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-02T12:21:22.607102Z","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-06-02T12:21:22.607275Z","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-06-02T12:21:22.607289Z","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-06-02T12:21:22.608139Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=(13134624114678348001)"} {"level":"info","ts":"2026-06-02T12:21:22.608363Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6c58cff80d4a5b62","local-member-id":"b6478f0ac5aeb8e1","added-peer-id":"b6478f0ac5aeb8e1","added-peer-peer-urls":["https://199.204.45.57:2380"]} {"level":"info","ts":"2026-06-02T12:21:22.609611Z","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-06-02T12:21:22.609749Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.57:2380"} {"level":"info","ts":"2026-06-02T12:21:22.609807Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.57:2380"} {"level":"info","ts":"2026-06-02T12:21:22.610016Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b6478f0ac5aeb8e1","initial-advertise-peer-urls":["https://199.204.45.57:2380"],"listen-peer-urls":["https://199.204.45.57:2380"],"advertise-client-urls":["https://199.204.45.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-02T12:21:22.610058Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-02T12:21:22.9907Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 is starting a new election at term 1"} {"level":"info","ts":"2026-06-02T12:21:22.990814Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-02T12:21:22.990852Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 received MsgPreVoteResp from b6478f0ac5aeb8e1 at term 1"} {"level":"info","ts":"2026-06-02T12:21:22.990875Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became candidate at term 2"} {"level":"info","ts":"2026-06-02T12:21:22.990893Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 received MsgVoteResp from b6478f0ac5aeb8e1 at term 2"} {"level":"info","ts":"2026-06-02T12:21:22.990914Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became leader at term 2"} {"level":"info","ts":"2026-06-02T12:21:22.990939Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b6478f0ac5aeb8e1 elected leader b6478f0ac5aeb8e1 at term 2"} {"level":"info","ts":"2026-06-02T12:21:22.998851Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T12:21:23.006969Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b6478f0ac5aeb8e1","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.57:2379]}","request-path":"/0/members/b6478f0ac5aeb8e1/attributes","cluster-id":"6c58cff80d4a5b62","publish-timeout":"7s"} {"level":"info","ts":"2026-06-02T12:21:23.007159Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-02T12:21:23.007199Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-02T12:21:23.010683Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.57:2379"} {"level":"info","ts":"2026-06-02T12:21:23.011191Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-02T12:21:23.011479Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-02T12:21:23.011545Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-02T12:21:23.014685Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6c58cff80d4a5b62","local-member-id":"b6478f0ac5aeb8e1","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T12:21:23.014794Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T12:21:23.014832Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-06-02T12:22:08.600265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.02505ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T12:22:08.600555Z","caller":"traceutil/trace.go:171","msg":"trace[1985130833] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"178.286014ms","start":"2026-06-02T12:22:08.42224Z","end":"2026-06-02T12:22:08.600526Z","steps":["trace[1985130833] 'process raft request' (duration: 45.448304ms)","trace[1985130833] 'compare' (duration: 131.875737ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T12:22:08.967608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.056314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T12:22:08.967694Z","caller":"traceutil/trace.go:171","msg":"trace[209754210] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:602; }","duration":"124.384042ms","start":"2026-06-02T12:22:08.843289Z","end":"2026-06-02T12:22:08.967673Z","steps":["trace[209754210] 'range keys from in-memory index tree' (duration: 124.000362ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:22:09.020572Z","caller":"traceutil/trace.go:171","msg":"trace[1722634554] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"156.988821ms","start":"2026-06-02T12:22:08.86356Z","end":"2026-06-02T12:22:09.020549Z","steps":["trace[1722634554] 'process raft request' (duration: 156.772556ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:22:09.515453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.247575ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-02T12:22:09.515606Z","caller":"traceutil/trace.go:171","msg":"trace[69145430] linearizableReadLoop","detail":"{readStateIndex:630; appliedIndex:629; }","duration":"346.475761ms","start":"2026-06-02T12:22:09.169105Z","end":"2026-06-02T12:22:09.515581Z","steps":["trace[69145430] 'read index received' (duration: 169.93055ms)","trace[69145430] 'applied index is now lower than readState.Index' (duration: 176.543581ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T12:22:09.515695Z","caller":"traceutil/trace.go:171","msg":"trace[675054048] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"413.172027ms","start":"2026-06-02T12:22:09.102475Z","end":"2026-06-02T12:22:09.515647Z","steps":["trace[675054048] 'process raft request' (duration: 236.632406ms)","trace[675054048] 'compare' (duration: 175.981727ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T12:22:09.515847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:22:09.10245Z","time spent":"413.337891ms","remote":"127.0.0.1:44558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-02T12:22:09.515931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.811179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-02T12:22:09.515984Z","caller":"traceutil/trace.go:171","msg":"trace[1943112884] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:605; }","duration":"346.87196ms","start":"2026-06-02T12:22:09.169096Z","end":"2026-06-02T12:22:09.515968Z","steps":["trace[1943112884] 'agreement among raft nodes before linearized reading' (duration: 346.615324ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:22:09.516045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:22:09.169031Z","time spent":"347.000624ms","remote":"127.0.0.1:44578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-02T12:22:09.516082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.805128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-02T12:22:09.516148Z","caller":"traceutil/trace.go:171","msg":"trace[911473772] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:605; }","duration":"346.925911ms","start":"2026-06-02T12:22:09.169201Z","end":"2026-06-02T12:22:09.516126Z","steps":["trace[911473772] 'agreement among raft nodes before linearized reading' (duration: 346.772638ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:22:09.516205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:22:09.169148Z","time spent":"347.044385ms","remote":"127.0.0.1:44578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-02T12:23:43.52673Z","caller":"traceutil/trace.go:171","msg":"trace[1583674165] linearizableReadLoop","detail":"{readStateIndex:1919; appliedIndex:1918; }","duration":"305.396562ms","start":"2026-06-02T12:23:43.22129Z","end":"2026-06-02T12:23:43.526687Z","steps":["trace[1583674165] 'read index received' (duration: 304.995005ms)","trace[1583674165] 'applied index is now lower than readState.Index' (duration: 400.257µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T12:23:43.526807Z","caller":"traceutil/trace.go:171","msg":"trace[440426474] transaction","detail":"{read_only:false; response_revision:1845; number_of_response:1; }","duration":"352.715885ms","start":"2026-06-02T12:23:43.174044Z","end":"2026-06-02T12:23:43.52676Z","steps":["trace[440426474] 'process raft request' (duration: 352.249187ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:43.527055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.198865ms","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-06-02T12:23:43.527097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.793589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2409f500-f261-4159-83e0-896a45116380\" ","response":"range_response_count:1 size:3626"} {"level":"warn","ts":"2026-06-02T12:23:43.527097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:23:43.174027Z","time spent":"352.881959ms","remote":"127.0.0.1:44578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T12:23:43.527143Z","caller":"traceutil/trace.go:171","msg":"trace[688121976] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1845; }","duration":"134.327637ms","start":"2026-06-02T12:23:43.392791Z","end":"2026-06-02T12:23:43.527118Z","steps":["trace[688121976] 'agreement among raft nodes before linearized reading' (duration: 134.130803ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:23:43.527165Z","caller":"traceutil/trace.go:171","msg":"trace[183669167] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2409f500-f261-4159-83e0-896a45116380; range_end:; response_count:1; response_revision:1845; }","duration":"305.905312ms","start":"2026-06-02T12:23:43.221241Z","end":"2026-06-02T12:23:43.527146Z","steps":["trace[183669167] 'agreement among raft nodes before linearized reading' (duration: 305.618886ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:43.527203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:23:43.221222Z","time spent":"305.972633ms","remote":"127.0.0.1:44496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2409f500-f261-4159-83e0-896a45116380\" "} {"level":"warn","ts":"2026-06-02T12:23:43.889664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.233565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-06-02T12:23:43.889757Z","caller":"traceutil/trace.go:171","msg":"trace[797603310] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:1846; }","duration":"172.385568ms","start":"2026-06-02T12:23:43.717353Z","end":"2026-06-02T12:23:43.889738Z","steps":["trace[797603310] 'range keys from in-memory index tree' (duration: 172.075093ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:24:34.819461Z","caller":"traceutil/trace.go:171","msg":"trace[1674948898] transaction","detail":"{read_only:false; response_revision:2176; number_of_response:1; }","duration":"124.443167ms","start":"2026-06-02T12:24:34.694989Z","end":"2026-06-02T12:24:34.819432Z","steps":["trace[1674948898] 'process raft request' (duration: 124.291475ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:28:34.561614Z","caller":"traceutil/trace.go:171","msg":"trace[990961986] linearizableReadLoop","detail":"{readStateIndex:3994; appliedIndex:3993; }","duration":"105.945635ms","start":"2026-06-02T12:28:34.455627Z","end":"2026-06-02T12:28:34.561572Z","steps":["trace[990961986] 'read index received' (duration: 105.75493ms)","trace[990961986] 'applied index is now lower than readState.Index' (duration: 189.195µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T12:28:34.561813Z","caller":"traceutil/trace.go:171","msg":"trace[997559584] transaction","detail":"{read_only:false; response_revision:3845; number_of_response:1; }","duration":"124.519053ms","start":"2026-06-02T12:28:34.437269Z","end":"2026-06-02T12:28:34.561788Z","steps":["trace[997559584] 'process raft request' (duration: 124.072754ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:28:34.561861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.212849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-06-02T12:28:34.561915Z","caller":"traceutil/trace.go:171","msg":"trace[2054756939] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3845; }","duration":"106.322921ms","start":"2026-06-02T12:28:34.455578Z","end":"2026-06-02T12:28:34.5619Z","steps":["trace[2054756939] 'agreement among raft nodes before linearized reading' (duration: 106.126727ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:30:01.190664Z","caller":"traceutil/trace.go:171","msg":"trace[518539130] linearizableReadLoop","detail":"{readStateIndex:5208; appliedIndex:5206; }","duration":"165.523441ms","start":"2026-06-02T12:30:01.025115Z","end":"2026-06-02T12:30:01.190639Z","steps":["trace[518539130] 'read index received' (duration: 33.833976ms)","trace[518539130] 'applied index is now lower than readState.Index' (duration: 131.687315ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T12:30:01.190771Z","caller":"traceutil/trace.go:171","msg":"trace[676421344] transaction","detail":"{read_only:false; response_revision:5015; number_of_response:1; }","duration":"165.657914ms","start":"2026-06-02T12:30:01.025067Z","end":"2026-06-02T12:30:01.190725Z","steps":["trace[676421344] 'process raft request' (duration: 165.252175ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:30:01.191016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.880067ms","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-06-02T12:30:01.192743Z","caller":"traceutil/trace.go:171","msg":"trace[1633834960] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5015; }","duration":"167.680442ms","start":"2026-06-02T12:30:01.025049Z","end":"2026-06-02T12:30:01.19273Z","steps":["trace[1633834960] 'agreement among raft nodes before linearized reading' (duration: 165.789517ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:30:01.236452Z","caller":"traceutil/trace.go:171","msg":"trace[2018723814] transaction","detail":"{read_only:false; response_revision:5016; number_of_response:1; }","duration":"162.251299ms","start":"2026-06-02T12:30:01.074173Z","end":"2026-06-02T12:30:01.236425Z","steps":["trace[2018723814] 'process raft request' (duration: 162.035695ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:30:01.236737Z","caller":"traceutil/trace.go:171","msg":"trace[1907560319] transaction","detail":"{read_only:false; number_of_response:1; response_revision:5016; }","duration":"161.178148ms","start":"2026-06-02T12:30:01.075504Z","end":"2026-06-02T12:30:01.236682Z","steps":["trace[1907560319] 'process raft request' (duration: 160.885803ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:30:01.237716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.722844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-06-02T12:30:01.237791Z","caller":"traceutil/trace.go:171","msg":"trace[1843452181] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:5018; }","duration":"174.837536ms","start":"2026-06-02T12:30:01.062937Z","end":"2026-06-02T12:30:01.237775Z","steps":["trace[1843452181] 'agreement among raft nodes before linearized reading' (duration: 174.665133ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:30:07.707235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.808202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2224956"} {"level":"info","ts":"2026-06-02T12:30:07.707335Z","caller":"traceutil/trace.go:171","msg":"trace[262870008] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:5147; }","duration":"127.964925ms","start":"2026-06-02T12:30:07.57935Z","end":"2026-06-02T12:30:07.707315Z","steps":["trace[262870008] 'range keys from in-memory index tree' (duration: 126.788302ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:30:08.179395Z","caller":"traceutil/trace.go:171","msg":"trace[1138813421] linearizableReadLoop","detail":"{readStateIndex:5349; appliedIndex:5347; }","duration":"119.21895ms","start":"2026-06-02T12:30:08.060159Z","end":"2026-06-02T12:30:08.179378Z","steps":["trace[1138813421] 'read index received' (duration: 22.573224ms)","trace[1138813421] 'applied index is now lower than readState.Index' (duration: 96.644716ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T12:30:08.179627Z","caller":"traceutil/trace.go:171","msg":"trace[2025411962] transaction","detail":"{read_only:false; response_revision:5149; number_of_response:1; }","duration":"173.330678ms","start":"2026-06-02T12:30:08.006287Z","end":"2026-06-02T12:30:08.179618Z","steps":["trace[2025411962] 'process raft request' (duration: 172.843479ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:30:08.179956Z","caller":"traceutil/trace.go:171","msg":"trace[1722939967] transaction","detail":"{read_only:false; response_revision:5150; number_of_response:1; }","duration":"108.420628ms","start":"2026-06-02T12:30:08.071478Z","end":"2026-06-02T12:30:08.179898Z","steps":["trace[1722939967] 'process raft request' (duration: 107.853387ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:30:08.180151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.955784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-06-02T12:30:08.180269Z","caller":"traceutil/trace.go:171","msg":"trace[1444709232] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5150; }","duration":"120.113458ms","start":"2026-06-02T12:30:08.060123Z","end":"2026-06-02T12:30:08.180236Z","steps":["trace[1444709232] 'agreement among raft nodes before linearized reading' (duration: 119.770561ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:30:08.22978Z","caller":"traceutil/trace.go:171","msg":"trace[1541807918] transaction","detail":"{read_only:false; response_revision:5151; number_of_response:1; }","duration":"142.292004ms","start":"2026-06-02T12:30:08.087467Z","end":"2026-06-02T12:30:08.229759Z","steps":["trace[1541807918] 'process raft request' (duration: 142.149812ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:30:08.230021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.60024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-02T12:30:08.230102Z","caller":"traceutil/trace.go:171","msg":"trace[625749670] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5151; }","duration":"125.719163ms","start":"2026-06-02T12:30:08.104365Z","end":"2026-06-02T12:30:08.230084Z","steps":["trace[625749670] 'agreement among raft nodes before linearized reading' (duration: 125.426938ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:30:08.230125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.275569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-06-02T12:30:08.230107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.34497ms","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-06-02T12:30:08.230159Z","caller":"traceutil/trace.go:171","msg":"trace[1596913360] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5151; }","duration":"154.32162ms","start":"2026-06-02T12:30:08.075829Z","end":"2026-06-02T12:30:08.23015Z","steps":["trace[1596913360] 'agreement among raft nodes before linearized reading' (duration: 154.183978ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:30:08.23021Z","caller":"traceutil/trace.go:171","msg":"trace[595705159] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5151; }","duration":"130.499342ms","start":"2026-06-02T12:30:08.099685Z","end":"2026-06-02T12:30:08.230185Z","steps":["trace[595705159] 'agreement among raft nodes before linearized reading' (duration: 130.118155ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:30:08.387304Z","caller":"traceutil/trace.go:171","msg":"trace[1227571319] transaction","detail":"{read_only:false; response_revision:5152; number_of_response:1; }","duration":"201.104229ms","start":"2026-06-02T12:30:08.186171Z","end":"2026-06-02T12:30:08.387275Z","steps":["trace[1227571319] 'process raft request' (duration: 200.774233ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:30:08.387315Z","caller":"traceutil/trace.go:171","msg":"trace[58918002] transaction","detail":"{read_only:false; response_revision:5153; number_of_response:1; }","duration":"200.55736ms","start":"2026-06-02T12:30:08.186731Z","end":"2026-06-02T12:30:08.387289Z","steps":["trace[58918002] 'process raft request' (duration: 200.412166ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:30:08.387528Z","caller":"traceutil/trace.go:171","msg":"trace[2029747412] transaction","detail":"{read_only:false; response_revision:5154; number_of_response:1; }","duration":"200.407926ms","start":"2026-06-02T12:30:08.187096Z","end":"2026-06-02T12:30:08.387504Z","steps":["trace[2029747412] 'process raft request' (duration: 200.100309ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:30:08.387682Z","caller":"traceutil/trace.go:171","msg":"trace[2028839431] linearizableReadLoop","detail":"{readStateIndex:5355; appliedIndex:5351; }","duration":"157.941478ms","start":"2026-06-02T12:30:08.229728Z","end":"2026-06-02T12:30:08.38767Z","steps":["trace[2028839431] 'read index received' (duration: 157.320426ms)","trace[2028839431] 'applied index is now lower than readState.Index' (duration: 619.922µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T12:30:08.387765Z","caller":"traceutil/trace.go:171","msg":"trace[1021015965] transaction","detail":"{read_only:false; response_revision:5155; number_of_response:1; }","duration":"200.376315ms","start":"2026-06-02T12:30:08.18733Z","end":"2026-06-02T12:30:08.387706Z","steps":["trace[1021015965] 'process raft request' (duration: 200.09668ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:30:08.387881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.776482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-2237a984-3a93-4096-ac21-10eedd521e72\" ","response":"range_response_count:1 size:1435"} {"level":"info","ts":"2026-06-02T12:30:08.38792Z","caller":"traceutil/trace.go:171","msg":"trace[216983570] range","detail":"{range_begin:/registry/persistentvolumes/pvc-2237a984-3a93-4096-ac21-10eedd521e72; range_end:; response_count:1; response_revision:5155; }","duration":"201.881044ms","start":"2026-06-02T12:30:08.186026Z","end":"2026-06-02T12:30:08.387907Z","steps":["trace[216983570] 'agreement among raft nodes before linearized reading' (duration: 201.717491ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:30:08.38861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.80652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:27 size:809127"} {"level":"info","ts":"2026-06-02T12:30:08.388741Z","caller":"traceutil/trace.go:171","msg":"trace[1616080164] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:27; response_revision:5155; }","duration":"162.019145ms","start":"2026-06-02T12:30:08.226695Z","end":"2026-06-02T12:30:08.388714Z","steps":["trace[1616080164] 'agreement among raft nodes before linearized reading' (duration: 161.013346ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:30:08.390933Z","caller":"traceutil/trace.go:171","msg":"trace[497789526] transaction","detail":"{read_only:false; response_revision:5156; number_of_response:1; }","duration":"156.504121ms","start":"2026-06-02T12:30:08.234402Z","end":"2026-06-02T12:30:08.390906Z","steps":["trace[497789526] 'process raft request' (duration: 156.307688ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:30:08.391587Z","caller":"traceutil/trace.go:171","msg":"trace[513416930] transaction","detail":"{read_only:false; response_revision:5157; number_of_response:1; }","duration":"153.375213ms","start":"2026-06-02T12:30:08.23815Z","end":"2026-06-02T12:30:08.391525Z","steps":["trace[513416930] 'process raft request' (duration: 152.70673ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:30:08.391661Z","caller":"traceutil/trace.go:171","msg":"trace[1231639812] transaction","detail":"{read_only:false; response_revision:5158; number_of_response:1; }","duration":"153.482645ms","start":"2026-06-02T12:30:08.238158Z","end":"2026-06-02T12:30:08.391641Z","steps":["trace[1231639812] 'process raft request' (duration: 152.943614ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:30:15.213089Z","caller":"traceutil/trace.go:171","msg":"trace[1706015646] transaction","detail":"{read_only:false; response_revision:5277; number_of_response:1; }","duration":"108.074012ms","start":"2026-06-02T12:30:15.104973Z","end":"2026-06-02T12:30:15.213047Z","steps":["trace[1706015646] 'process raft request' (duration: 107.810946ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:30:23.509159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.846221ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T12:30:23.50937Z","caller":"traceutil/trace.go:171","msg":"trace[334574159] linearizableReadLoop","detail":"{readStateIndex:5635; appliedIndex:5633; }","duration":"108.566921ms","start":"2026-06-02T12:30:23.400786Z","end":"2026-06-02T12:30:23.509353Z","steps":["trace[334574159] 'read index received' (duration: 76.940796ms)","trace[334574159] 'applied index is now lower than readState.Index' (duration: 31.625015ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T12:30:23.509497Z","caller":"traceutil/trace.go:171","msg":"trace[2070461951] transaction","detail":"{read_only:false; response_revision:5429; number_of_response:1; }","duration":"161.451894ms","start":"2026-06-02T12:30:23.347973Z","end":"2026-06-02T12:30:23.509425Z","steps":["trace[2070461951] 'process raft request' (duration: 40.246966ms)","trace[2070461951] 'compare' (duration: 120.561836ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T12:30:23.509579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.493915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-r6pvs\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-06-02T12:30:23.509613Z","caller":"traceutil/trace.go:171","msg":"trace[1904045728] range","detail":"{range_begin:/registry/pods/openstack/keepalived-r6pvs; range_end:; response_count:1; response_revision:5430; }","duration":"104.530435ms","start":"2026-06-02T12:30:23.405073Z","end":"2026-06-02T12:30:23.509603Z","steps":["trace[1904045728] 'agreement among raft nodes before linearized reading' (duration: 104.384303ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:30:23.509465Z","caller":"traceutil/trace.go:171","msg":"trace[233717517] transaction","detail":"{read_only:false; response_revision:5430; number_of_response:1; }","duration":"131.183095ms","start":"2026-06-02T12:30:23.37825Z","end":"2026-06-02T12:30:23.509433Z","steps":["trace[233717517] 'process raft request' (duration: 131.020702ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:30:23.509665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.860516ms","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-06-02T12:30:23.509742Z","caller":"traceutil/trace.go:171","msg":"trace[2109062918] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5430; }","duration":"108.967538ms","start":"2026-06-02T12:30:23.400753Z","end":"2026-06-02T12:30:23.50972Z","steps":["trace[2109062918] 'agreement among raft nodes before linearized reading' (duration: 108.715654ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:30:25.562772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.740166ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T12:30:25.563158Z","caller":"traceutil/trace.go:171","msg":"trace[1263973697] linearizableReadLoop","detail":"{readStateIndex:5654; appliedIndex:5653; }","duration":"175.804474ms","start":"2026-06-02T12:30:25.387329Z","end":"2026-06-02T12:30:25.563134Z","steps":["trace[1263973697] 'read index received' (duration: 68.540338ms)","trace[1263973697] 'applied index is now lower than readState.Index' (duration: 107.261746ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T12:30:25.563306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.963877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T12:30:25.563287Z","caller":"traceutil/trace.go:171","msg":"trace[1633637480] transaction","detail":"{read_only:false; response_revision:5449; number_of_response:1; }","duration":"321.511342ms","start":"2026-06-02T12:30:25.241695Z","end":"2026-06-02T12:30:25.563207Z","steps":["trace[1633637480] 'process raft request' (duration: 214.244746ms)","trace[1633637480] 'compare' (duration: 106.590043ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T12:30:25.563379Z","caller":"traceutil/trace.go:171","msg":"trace[170666233] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:5451; }","duration":"176.085009ms","start":"2026-06-02T12:30:25.387273Z","end":"2026-06-02T12:30:25.563358Z","steps":["trace[170666233] 'agreement among raft nodes before linearized reading' (duration: 175.955837ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:30:25.563545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:30:25.241673Z","time spent":"321.701996ms","remote":"127.0.0.1:44776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8237,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T12:31:23.513685Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2959} {"level":"info","ts":"2026-06-02T12:31:23.585501Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2959,"took":"69.697691ms","hash":1295298379,"current-db-size-bytes":57397248,"current-db-size":"57 MB","current-db-size-in-use-bytes":34045952,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-02T12:31:23.585581Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1295298379,"revision":2959,"compact-revision":-1} {"level":"info","ts":"2026-06-02T12:36:23.519804Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6036} {"level":"info","ts":"2026-06-02T12:36:23.618208Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6036,"took":"96.460938ms","hash":1586625707,"current-db-size-bytes":57397248,"current-db-size":"57 MB","current-db-size-in-use-bytes":21819392,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-06-02T12:36:23.618284Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1586625707,"revision":6036,"compact-revision":2959} {"level":"warn","ts":"2026-06-02T12:36:42.11642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.388521ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-02T12:36:42.11654Z","caller":"traceutil/trace.go:171","msg":"trace[1059074269] linearizableReadLoop","detail":"{readStateIndex:8659; appliedIndex:8658; }","duration":"373.306078ms","start":"2026-06-02T12:36:41.743215Z","end":"2026-06-02T12:36:42.116521Z","steps":["trace[1059074269] 'read index received' (duration: 191.845566ms)","trace[1059074269] 'applied index is now lower than readState.Index' (duration: 181.459002ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T12:36:42.116667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.641394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T12:36:42.116702Z","caller":"traceutil/trace.go:171","msg":"trace[1523456323] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8358; }","duration":"270.736836ms","start":"2026-06-02T12:36:41.845956Z","end":"2026-06-02T12:36:42.116692Z","steps":["trace[1523456323] 'agreement among raft nodes before linearized reading' (duration: 270.661964ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:36:42.116751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.523802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-r6pvs\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-06-02T12:36:42.116822Z","caller":"traceutil/trace.go:171","msg":"trace[1279308521] range","detail":"{range_begin:/registry/pods/openstack/keepalived-r6pvs; range_end:; response_count:1; response_revision:8358; }","duration":"373.664725ms","start":"2026-06-02T12:36:41.74314Z","end":"2026-06-02T12:36:42.116804Z","steps":["trace[1279308521] 'agreement among raft nodes before linearized reading' (duration: 373.42664ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:36:42.116863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:36:41.74311Z","time spent":"373.743586ms","remote":"127.0.0.1:44496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6550,"request content":"key:\"/registry/pods/openstack/keepalived-r6pvs\" "} {"level":"warn","ts":"2026-06-02T12:36:42.116966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.244398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-02T12:36:42.117Z","caller":"traceutil/trace.go:171","msg":"trace[1559026586] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8358; }","duration":"107.32817ms","start":"2026-06-02T12:36:42.009662Z","end":"2026-06-02T12:36:42.116991Z","steps":["trace[1559026586] 'agreement among raft nodes before linearized reading' (duration: 107.265959ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:37:51.659699Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023fb.wal"} {"level":"info","ts":"2026-06-02T12:39:13.481572Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b6478f0ac5aeb8e1","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-02T12:39:13.487979Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-06-02T12:39:13.488133Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-06-02T12:41:23.527524Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8093} {"level":"info","ts":"2026-06-02T12:41:23.584785Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8093,"took":"55.399344ms","hash":2707023889,"current-db-size-bytes":57397248,"current-db-size":"57 MB","current-db-size-in-use-bytes":29171712,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-06-02T12:41:23.584844Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2707023889,"revision":8093,"compact-revision":6036} {"level":"info","ts":"2026-06-02T12:46:23.53881Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10649} {"level":"info","ts":"2026-06-02T12:46:23.619802Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10649,"took":"78.652618ms","hash":4039816686,"current-db-size-bytes":57397248,"current-db-size":"57 MB","current-db-size-in-use-bytes":27631616,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-06-02T12:46:23.619857Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4039816686,"revision":10649,"compact-revision":8093} {"level":"info","ts":"2026-06-02T12:48:28.364465Z","caller":"traceutil/trace.go:171","msg":"trace[737556278] transaction","detail":"{read_only:false; response_revision:13745; number_of_response:1; }","duration":"107.481708ms","start":"2026-06-02T12:48:28.256948Z","end":"2026-06-02T12:48:28.36443Z","steps":["trace[737556278] 'process raft request' (duration: 107.255483ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:48:40.309392Z","caller":"traceutil/trace.go:171","msg":"trace[1318755008] transaction","detail":"{read_only:false; response_revision:13812; number_of_response:1; }","duration":"149.320168ms","start":"2026-06-02T12:48:40.160047Z","end":"2026-06-02T12:48:40.309367Z","steps":["trace[1318755008] 'process raft request' (duration: 75.343246ms)","trace[1318755008] 'compare' (duration: 73.90381ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T12:49:32.183789Z","caller":"traceutil/trace.go:171","msg":"trace[1991897764] transaction","detail":"{read_only:false; response_revision:14091; number_of_response:1; }","duration":"189.50888ms","start":"2026-06-02T12:49:31.994256Z","end":"2026-06-02T12:49:32.183765Z","steps":["trace[1991897764] 'process raft request' (duration: 189.307456ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:49:32.184672Z","caller":"traceutil/trace.go:171","msg":"trace[1686312805] transaction","detail":"{read_only:false; response_revision:14092; number_of_response:1; }","duration":"174.417606ms","start":"2026-06-02T12:49:32.010236Z","end":"2026-06-02T12:49:32.184653Z","steps":["trace[1686312805] 'process raft request' (duration: 174.167991ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:49:55.214258Z","caller":"traceutil/trace.go:171","msg":"trace[1040567178] transaction","detail":"{read_only:false; response_revision:14402; number_of_response:1; }","duration":"199.354783ms","start":"2026-06-02T12:49:55.014875Z","end":"2026-06-02T12:49:55.21423Z","steps":["trace[1040567178] 'process raft request' (duration: 199.188349ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:51:04.369735Z","caller":"traceutil/trace.go:171","msg":"trace[412829525] linearizableReadLoop","detail":"{readStateIndex:15654; appliedIndex:15653; }","duration":"117.634348ms","start":"2026-06-02T12:51:04.252079Z","end":"2026-06-02T12:51:04.369713Z","steps":["trace[412829525] 'read index received' (duration: 117.178529ms)","trace[412829525] 'applied index is now lower than readState.Index' (duration: 455.169µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T12:51:04.369848Z","caller":"traceutil/trace.go:171","msg":"trace[558508656] transaction","detail":"{read_only:false; response_revision:15131; number_of_response:1; }","duration":"155.641416ms","start":"2026-06-02T12:51:04.214199Z","end":"2026-06-02T12:51:04.36984Z","steps":["trace[558508656] 'process raft request' (duration: 155.225197ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:51:04.370277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.175178ms","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-06-02T12:51:04.370366Z","caller":"traceutil/trace.go:171","msg":"trace[376872734] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15131; }","duration":"118.305681ms","start":"2026-06-02T12:51:04.252041Z","end":"2026-06-02T12:51:04.370347Z","steps":["trace[376872734] 'agreement among raft nodes before linearized reading' (duration: 118.072056ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:51:04.669884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.519085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-02T12:51:04.670061Z","caller":"traceutil/trace.go:171","msg":"trace[1748973214] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:15131; }","duration":"245.681567ms","start":"2026-06-02T12:51:04.424279Z","end":"2026-06-02T12:51:04.66996Z","steps":["trace[1748973214] 'count revisions from in-memory index tree' (duration: 245.430453ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:51:04.670261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.203244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-t7xn8\" ","response":"range_response_count:1 size:20766"} {"level":"info","ts":"2026-06-02T12:51:04.670322Z","caller":"traceutil/trace.go:171","msg":"trace[927015725] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-t7xn8; range_end:; response_count:1; response_revision:15131; }","duration":"157.313466ms","start":"2026-06-02T12:51:04.512995Z","end":"2026-06-02T12:51:04.670308Z","steps":["trace[927015725] 'range keys from in-memory index tree' (duration: 156.818007ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:51:05.071752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.793825ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T12:51:05.071996Z","caller":"traceutil/trace.go:171","msg":"trace[1535497008] transaction","detail":"{read_only:false; response_revision:15134; number_of_response:1; }","duration":"163.338457ms","start":"2026-06-02T12:51:04.908639Z","end":"2026-06-02T12:51:05.071977Z","steps":["trace[1535497008] 'process raft request' (duration: 163.249885ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:51:05.071986Z","caller":"traceutil/trace.go:171","msg":"trace[1116229293] transaction","detail":"{read_only:false; response_revision:15133; number_of_response:1; }","duration":"472.906058ms","start":"2026-06-02T12:51:04.599033Z","end":"2026-06-02T12:51:05.071939Z","steps":["trace[1116229293] 'process raft request' (duration: 289.818998ms)","trace[1116229293] 'compare' (duration: 182.52873ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T12:51:05.072019Z","caller":"traceutil/trace.go:171","msg":"trace[2122875494] linearizableReadLoop","detail":"{readStateIndex:15656; appliedIndex:15655; }","duration":"401.835194ms","start":"2026-06-02T12:51:04.670148Z","end":"2026-06-02T12:51:05.071984Z","steps":["trace[2122875494] 'read index received' (duration: 218.749305ms)","trace[2122875494] 'applied index is now lower than readState.Index' (duration: 183.082739ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T12:51:05.072136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:51:04.599016Z","time spent":"473.04123ms","remote":"127.0.0.1:44578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T12:51:05.072227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.580984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-m6cbt\" ","response":"range_response_count:1 size:22128"} {"level":"info","ts":"2026-06-02T12:51:05.072357Z","caller":"traceutil/trace.go:171","msg":"trace[1376986255] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-m6cbt; range_end:; response_count:1; response_revision:15134; }","duration":"434.660465ms","start":"2026-06-02T12:51:04.637623Z","end":"2026-06-02T12:51:05.072283Z","steps":["trace[1376986255] 'agreement among raft nodes before linearized reading' (duration: 434.458672ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:51:05.072394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:51:04.637607Z","time spent":"434.779768ms","remote":"127.0.0.1:44496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22151,"request content":"key:\"/registry/pods/openstack/nova-compute-default-m6cbt\" "} {"level":"warn","ts":"2026-06-02T12:51:05.07242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.355827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-02T12:51:05.072483Z","caller":"traceutil/trace.go:171","msg":"trace[715502151] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15134; }","duration":"200.460239ms","start":"2026-06-02T12:51:04.872005Z","end":"2026-06-02T12:51:05.072466Z","steps":["trace[715502151] 'agreement among raft nodes before linearized reading' (duration: 200.317077ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:51:05.07252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.257929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-06-02T12:51:05.07257Z","caller":"traceutil/trace.go:171","msg":"trace[1005839713] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15134; }","duration":"149.32324ms","start":"2026-06-02T12:51:04.923235Z","end":"2026-06-02T12:51:05.072559Z","steps":["trace[1005839713] 'agreement among raft nodes before linearized reading' (duration: 149.175457ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:51:05.072594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.304999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T12:51:05.07264Z","caller":"traceutil/trace.go:171","msg":"trace[1787524253] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15134; }","duration":"149.372091ms","start":"2026-06-02T12:51:04.923256Z","end":"2026-06-02T12:51:05.072628Z","steps":["trace[1787524253] 'agreement among raft nodes before linearized reading' (duration: 149.31032ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:51:05.072622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.543614ms","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-06-02T12:51:05.072701Z","caller":"traceutil/trace.go:171","msg":"trace[36823083] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15134; }","duration":"149.664636ms","start":"2026-06-02T12:51:04.923015Z","end":"2026-06-02T12:51:05.07268Z","steps":["trace[36823083] 'agreement among raft nodes before linearized reading' (duration: 149.408502ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:51:05.072717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.283219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-02T12:51:05.072748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.333306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T12:51:05.07276Z","caller":"traceutil/trace.go:171","msg":"trace[2031092339] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15134; }","duration":"228.34398ms","start":"2026-06-02T12:51:04.844403Z","end":"2026-06-02T12:51:05.072747Z","steps":["trace[2031092339] 'agreement among raft nodes before linearized reading' (duration: 228.262669ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:51:05.07278Z","caller":"traceutil/trace.go:171","msg":"trace[1133245189] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:15134; }","duration":"279.410908ms","start":"2026-06-02T12:51:04.793359Z","end":"2026-06-02T12:51:05.07277Z","steps":["trace[1133245189] 'agreement among raft nodes before linearized reading' (duration: 279.346397ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:51:05.072587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.161653ms","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:7"} {"level":"info","ts":"2026-06-02T12:51:05.07282Z","caller":"traceutil/trace.go:171","msg":"trace[411970147] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:15134; }","duration":"242.481199ms","start":"2026-06-02T12:51:04.830325Z","end":"2026-06-02T12:51:05.072807Z","steps":["trace[411970147] 'agreement among raft nodes before linearized reading' (duration: 242.114313ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:51:05.072884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.332709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-r6pvs\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-06-02T12:51:05.073012Z","caller":"traceutil/trace.go:171","msg":"trace[2136989893] range","detail":"{range_begin:/registry/pods/openstack/keepalived-r6pvs; range_end:; response_count:1; response_revision:15134; }","duration":"356.507422ms","start":"2026-06-02T12:51:04.716473Z","end":"2026-06-02T12:51:05.072981Z","steps":["trace[2136989893] 'agreement among raft nodes before linearized reading' (duration: 356.125676ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:51:05.073072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:51:04.716458Z","time spent":"356.602844ms","remote":"127.0.0.1:44496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6550,"request content":"key:\"/registry/pods/openstack/keepalived-r6pvs\" "} {"level":"warn","ts":"2026-06-02T12:51:05.073543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.016836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:76 size:737580"} {"level":"info","ts":"2026-06-02T12:51:05.073598Z","caller":"traceutil/trace.go:171","msg":"trace[870282580] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:76; response_revision:15134; }","duration":"396.102947ms","start":"2026-06-02T12:51:04.677481Z","end":"2026-06-02T12:51:05.073584Z","steps":["trace[870282580] 'agreement among raft nodes before linearized reading' (duration: 394.684451ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:51:05.073635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:51:04.677467Z","time spent":"396.155928ms","remote":"127.0.0.1:44496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":76,"response size":737603,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-06-02T12:51:05.073214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.494997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-06-02T12:51:05.073722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.728951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:76 size:737580"} {"level":"info","ts":"2026-06-02T12:51:05.073743Z","caller":"traceutil/trace.go:171","msg":"trace[1851150895] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15134; }","duration":"202.325472ms","start":"2026-06-02T12:51:04.871395Z","end":"2026-06-02T12:51:05.07372Z","steps":["trace[1851150895] 'agreement among raft nodes before linearized reading' (duration: 201.049909ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:51:05.073767Z","caller":"traceutil/trace.go:171","msg":"trace[2022764056] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:76; response_revision:15134; }","duration":"395.801613ms","start":"2026-06-02T12:51:04.677953Z","end":"2026-06-02T12:51:05.073755Z","steps":["trace[2022764056] 'agreement among raft nodes before linearized reading' (duration: 394.727283ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:51:05.07383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:51:04.677932Z","time spent":"395.886234ms","remote":"127.0.0.1:44496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":76,"response size":737603,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-06-02T12:51:23.545289Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12819} {"level":"info","ts":"2026-06-02T12:51:23.624363Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12819,"took":"76.29051ms","hash":1567850065,"current-db-size-bytes":57397248,"current-db-size":"57 MB","current-db-size-in-use-bytes":29908992,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-02T12:51:23.624412Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1567850065,"revision":12819,"compact-revision":10649} {"level":"info","ts":"2026-06-02T12:56:23.551239Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15230} {"level":"info","ts":"2026-06-02T12:56:23.64482Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15230,"took":"89.333178ms","hash":1164046077,"current-db-size-bytes":57397248,"current-db-size":"57 MB","current-db-size-in-use-bytes":31846400,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-02T12:56:23.644893Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1164046077,"revision":15230,"compact-revision":12819} {"level":"info","ts":"2026-06-02T12:57:12.86416Z","caller":"traceutil/trace.go:171","msg":"trace[1215116107] transaction","detail":"{read_only:false; response_revision:17822; number_of_response:1; }","duration":"206.917743ms","start":"2026-06-02T12:57:12.657017Z","end":"2026-06-02T12:57:12.863934Z","steps":["trace[1215116107] 'process raft request' (duration: 206.74209ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:57:13.404985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.55536ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T12:57:13.405421Z","caller":"traceutil/trace.go:171","msg":"trace[505177355] transaction","detail":"{read_only:false; response_revision:17824; number_of_response:1; }","duration":"449.745654ms","start":"2026-06-02T12:57:12.955636Z","end":"2026-06-02T12:57:13.405382Z","steps":["trace[505177355] 'process raft request' (duration: 317.694913ms)","trace[505177355] 'compare' (duration: 131.379247ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T12:57:13.405543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:57:12.955618Z","time spent":"449.859715ms","remote":"127.0.0.1:44578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T12:57:13.405544Z","caller":"traceutil/trace.go:171","msg":"trace[236364282] transaction","detail":"{read_only:false; response_revision:17825; number_of_response:1; }","duration":"448.978938ms","start":"2026-06-02T12:57:12.956536Z","end":"2026-06-02T12:57:13.405515Z","steps":["trace[236364282] 'process raft request' (duration: 448.636721ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:57:13.405812Z","caller":"traceutil/trace.go:171","msg":"trace[738101938] transaction","detail":"{read_only:false; response_revision:17826; number_of_response:1; }","duration":"358.505236ms","start":"2026-06-02T12:57:13.047288Z","end":"2026-06-02T12:57:13.405793Z","steps":["trace[738101938] 'process raft request' (duration: 358.14829ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:57:13.405896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:57:12.956501Z","time spent":"449.145672ms","remote":"127.0.0.1:44578","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-06-02T12:57:13.405994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.925145ms","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-06-02T12:57:13.405822Z","caller":"traceutil/trace.go:171","msg":"trace[1652900946] transaction","detail":"{read_only:false; response_revision:17827; number_of_response:1; }","duration":"202.941835ms","start":"2026-06-02T12:57:13.202859Z","end":"2026-06-02T12:57:13.405801Z","steps":["trace[1652900946] 'process raft request' (duration: 202.761691ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:57:13.406044Z","caller":"traceutil/trace.go:171","msg":"trace[1059254919] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:17827; }","duration":"265.028447ms","start":"2026-06-02T12:57:13.141Z","end":"2026-06-02T12:57:13.406028Z","steps":["trace[1059254919] 'agreement among raft nodes before linearized reading' (duration: 264.939895ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:57:13.405989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:57:13.047269Z","time spent":"358.626639ms","remote":"127.0.0.1:44578","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-06-02T12:57:13.405757Z","caller":"traceutil/trace.go:171","msg":"trace[1553461504] linearizableReadLoop","detail":"{readStateIndex:18438; appliedIndex:18435; }","duration":"264.67713ms","start":"2026-06-02T12:57:13.141064Z","end":"2026-06-02T12:57:13.405741Z","steps":["trace[1553461504] 'read index received' (duration: 132.383226ms)","trace[1553461504] 'applied index is now lower than readState.Index' (duration: 132.292634ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T12:58:01.71588Z","caller":"traceutil/trace.go:171","msg":"trace[1779265047] transaction","detail":"{read_only:false; response_revision:18451; number_of_response:1; }","duration":"160.933918ms","start":"2026-06-02T12:58:01.554921Z","end":"2026-06-02T12:58:01.715855Z","steps":["trace[1779265047] 'process raft request' (duration: 160.775405ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:58:01.728994Z","caller":"traceutil/trace.go:171","msg":"trace[1070577487] linearizableReadLoop","detail":"{readStateIndex:19080; appliedIndex:19079; }","duration":"117.669026ms","start":"2026-06-02T12:58:01.611295Z","end":"2026-06-02T12:58:01.728964Z","steps":["trace[1070577487] 'read index received' (duration: 104.582149ms)","trace[1070577487] 'applied index is now lower than readState.Index' (duration: 13.085597ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T12:58:01.729351Z","caller":"traceutil/trace.go:171","msg":"trace[1889232329] transaction","detail":"{read_only:false; response_revision:18452; number_of_response:1; }","duration":"154.142934ms","start":"2026-06-02T12:58:01.575197Z","end":"2026-06-02T12:58:01.72934Z","steps":["trace[1889232329] 'process raft request' (duration: 153.597753ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:58:01.729408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.103944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-02T12:58:01.729415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.095494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-bootstrap\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-02T12:58:01.729468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.204308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T12:58:01.729487Z","caller":"traceutil/trace.go:171","msg":"trace[850968789] range","detail":"{range_begin:/registry/jobs/openstack/heat-domain-ks-user; range_end:; response_count:0; response_revision:18452; }","duration":"118.237137ms","start":"2026-06-02T12:58:01.611229Z","end":"2026-06-02T12:58:01.729466Z","steps":["trace[850968789] 'agreement among raft nodes before linearized reading' (duration: 118.117745ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:58:01.7295Z","caller":"traceutil/trace.go:171","msg":"trace[1343765325] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-user; range_end:; response_count:0; response_revision:18452; }","duration":"116.255008ms","start":"2026-06-02T12:58:01.613233Z","end":"2026-06-02T12:58:01.729488Z","steps":["trace[1343765325] 'agreement among raft nodes before linearized reading' (duration: 116.217148ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:58:01.729508Z","caller":"traceutil/trace.go:171","msg":"trace[1474827822] range","detail":"{range_begin:/registry/jobs/openstack/heat-bootstrap; range_end:; response_count:0; response_revision:18452; }","duration":"118.206996ms","start":"2026-06-02T12:58:01.611278Z","end":"2026-06-02T12:58:01.729485Z","steps":["trace[1474827822] 'agreement among raft nodes before linearized reading' (duration: 118.019483ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:58:01.729409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.639815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T12:58:01.729599Z","caller":"traceutil/trace.go:171","msg":"trace[739360609] range","detail":"{range_begin:/registry/jobs/openstack/heat-rabbit-init; range_end:; response_count:0; response_revision:18452; }","duration":"117.882571ms","start":"2026-06-02T12:58:01.61171Z","end":"2026-06-02T12:58:01.729593Z","steps":["trace[739360609] 'agreement among raft nodes before linearized reading' (duration: 117.636996ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:58:01.729591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.265599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-db-sync\" ","response":"range_response_count:1 size:5032"} {"level":"warn","ts":"2026-06-02T12:58:01.72961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.855331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T12:58:01.729633Z","caller":"traceutil/trace.go:171","msg":"trace[2045784370] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-endpoints; range_end:; response_count:0; response_revision:18452; }","duration":"117.893971ms","start":"2026-06-02T12:58:01.611733Z","end":"2026-06-02T12:58:01.729627Z","steps":["trace[2045784370] 'agreement among raft nodes before linearized reading' (duration: 117.85952ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:58:01.729637Z","caller":"traceutil/trace.go:171","msg":"trace[702753374] range","detail":"{range_begin:/registry/jobs/openstack/heat-db-sync; range_end:; response_count:1; response_revision:18452; }","duration":"114.354721ms","start":"2026-06-02T12:58:01.615268Z","end":"2026-06-02T12:58:01.729622Z","steps":["trace[702753374] 'agreement among raft nodes before linearized reading' (duration: 114.170576ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:00:03.643533Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b6478f0ac5aeb8e1","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-02T13:00:03.649741Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-06-02T13:00:03.649823Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-06-02T13:01:23.556685Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17507} {"level":"info","ts":"2026-06-02T13:01:23.651343Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17507,"took":"91.181769ms","hash":852158133,"current-db-size-bytes":57397248,"current-db-size":"57 MB","current-db-size-in-use-bytes":34447360,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-02T13:01:23.651493Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":852158133,"revision":17507,"compact-revision":15230} {"level":"info","ts":"2026-06-02T13:02:52.98507Z","caller":"traceutil/trace.go:171","msg":"trace[277195216] linearizableReadLoop","detail":"{readStateIndex:21232; appliedIndex:21231; }","duration":"140.638944ms","start":"2026-06-02T13:02:52.844405Z","end":"2026-06-02T13:02:52.985044Z","steps":["trace[277195216] 'read index received' (duration: 98.274451ms)","trace[277195216] 'applied index is now lower than readState.Index' (duration: 42.363523ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T13:02:52.985188Z","caller":"traceutil/trace.go:171","msg":"trace[613055618] transaction","detail":"{read_only:false; response_revision:20533; number_of_response:1; }","duration":"188.028131ms","start":"2026-06-02T13:02:52.797119Z","end":"2026-06-02T13:02:52.985147Z","steps":["trace[613055618] 'process raft request' (duration: 145.634258ms)","trace[613055618] 'compare' (duration: 42.131609ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T13:02:52.985256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.837738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T13:02:52.985292Z","caller":"traceutil/trace.go:171","msg":"trace[1167970094] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20533; }","duration":"140.92177ms","start":"2026-06-02T13:02:52.84436Z","end":"2026-06-02T13:02:52.985282Z","steps":["trace[1167970094] 'agreement among raft nodes before linearized reading' (duration: 140.842387ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:02:53.388151Z","caller":"traceutil/trace.go:171","msg":"trace[366215136] linearizableReadLoop","detail":"{readStateIndex:21233; appliedIndex:21232; }","duration":"190.120248ms","start":"2026-06-02T13:02:53.198006Z","end":"2026-06-02T13:02:53.388126Z","steps":["trace[366215136] 'read index received' (duration: 189.941305ms)","trace[366215136] 'applied index is now lower than readState.Index' (duration: 177.903µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T13:02:53.388185Z","caller":"traceutil/trace.go:171","msg":"trace[1411859207] transaction","detail":"{read_only:false; response_revision:20534; number_of_response:1; }","duration":"199.258769ms","start":"2026-06-02T13:02:53.188896Z","end":"2026-06-02T13:02:53.388155Z","steps":["trace[1411859207] 'process raft request' (duration: 199.060445ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:02:53.388441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.14632ms","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-06-02T13:02:53.388485Z","caller":"traceutil/trace.go:171","msg":"trace[446369298] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20534; }","duration":"189.263662ms","start":"2026-06-02T13:02:53.199211Z","end":"2026-06-02T13:02:53.388475Z","steps":["trace[446369298] 'agreement among raft nodes before linearized reading' (duration: 189.058848ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:02:53.388595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.521646ms","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-06-02T13:02:53.388698Z","caller":"traceutil/trace.go:171","msg":"trace[1525196001] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20534; }","duration":"190.72744ms","start":"2026-06-02T13:02:53.197955Z","end":"2026-06-02T13:02:53.388683Z","steps":["trace[1525196001] 'agreement among raft nodes before linearized reading' (duration: 190.290542ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:02:53.511045Z","caller":"traceutil/trace.go:171","msg":"trace[123755917] transaction","detail":"{read_only:false; response_revision:20535; number_of_response:1; }","duration":"118.61385ms","start":"2026-06-02T13:02:53.392402Z","end":"2026-06-02T13:02:53.511016Z","steps":["trace[123755917] 'process raft request' (duration: 95.59694ms)","trace[123755917] 'compare' (duration: 22.788946ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T13:02:53.511219Z","caller":"traceutil/trace.go:171","msg":"trace[1516552360] transaction","detail":"{read_only:false; response_revision:20536; number_of_response:1; }","duration":"117.587241ms","start":"2026-06-02T13:02:53.393604Z","end":"2026-06-02T13:02:53.511191Z","steps":["trace[1516552360] 'process raft request' (duration: 117.343506ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:02:53.79449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.028046ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T13:02:53.79474Z","caller":"traceutil/trace.go:171","msg":"trace[738684692] transaction","detail":"{read_only:false; response_revision:20538; number_of_response:1; }","duration":"279.115264ms","start":"2026-06-02T13:02:53.515606Z","end":"2026-06-02T13:02:53.794721Z","steps":["trace[738684692] 'process raft request' (duration: 279.055393ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:02:53.794854Z","caller":"traceutil/trace.go:171","msg":"trace[636311940] transaction","detail":"{read_only:false; response_revision:20537; number_of_response:1; }","duration":"279.733575ms","start":"2026-06-02T13:02:53.515089Z","end":"2026-06-02T13:02:53.794822Z","steps":["trace[636311940] 'process raft request' (duration: 144.28043ms)","trace[636311940] 'compare' (duration: 134.895954ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T13:02:53.993282Z","caller":"traceutil/trace.go:171","msg":"trace[604545409] transaction","detail":"{read_only:false; response_revision:20539; number_of_response:1; }","duration":"225.684963ms","start":"2026-06-02T13:02:53.767568Z","end":"2026-06-02T13:02:53.993253Z","steps":["trace[604545409] 'process raft request' (duration: 161.310009ms)","trace[604545409] 'compare' (duration: 63.971577ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T13:02:53.993443Z","caller":"traceutil/trace.go:171","msg":"trace[2097927617] linearizableReadLoop","detail":"{readStateIndex:21239; appliedIndex:21237; }","duration":"147.905008ms","start":"2026-06-02T13:02:53.845506Z","end":"2026-06-02T13:02:53.993411Z","steps":["trace[2097927617] 'read index received' (duration: 83.253737ms)","trace[2097927617] 'applied index is now lower than readState.Index' (duration: 64.649761ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T13:02:53.993543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.03065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T13:02:53.993591Z","caller":"traceutil/trace.go:171","msg":"trace[787940249] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20540; }","duration":"148.122102ms","start":"2026-06-02T13:02:53.845455Z","end":"2026-06-02T13:02:53.993577Z","steps":["trace[787940249] 'agreement among raft nodes before linearized reading' (duration: 148.037401ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:02:53.993693Z","caller":"traceutil/trace.go:171","msg":"trace[1651883586] transaction","detail":"{read_only:false; response_revision:20540; number_of_response:1; }","duration":"213.347353ms","start":"2026-06-02T13:02:53.780304Z","end":"2026-06-02T13:02:53.993651Z","steps":["trace[1651883586] 'process raft request' (duration: 212.901394ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:06:23.573562Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19957} {"level":"info","ts":"2026-06-02T13:06:23.68454Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19957,"took":"106.702557ms","hash":3124271062,"current-db-size-bytes":57397248,"current-db-size":"57 MB","current-db-size-in-use-bytes":31125504,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-02T13:06:23.68462Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3124271062,"revision":19957,"compact-revision":17507} {"level":"warn","ts":"2026-06-02T13:06:51.167311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.847882ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T13:06:51.167839Z","caller":"traceutil/trace.go:171","msg":"trace[221638885] transaction","detail":"{read_only:false; response_revision:23020; number_of_response:1; }","duration":"162.483151ms","start":"2026-06-02T13:06:51.005334Z","end":"2026-06-02T13:06:51.167817Z","steps":["trace[221638885] 'process raft request' (duration: 162.104013ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:06:51.167697Z","caller":"traceutil/trace.go:171","msg":"trace[1069263276] transaction","detail":"{read_only:false; response_revision:23019; number_of_response:1; }","duration":"163.027432ms","start":"2026-06-02T13:06:51.004634Z","end":"2026-06-02T13:06:51.167661Z","steps":["trace[1069263276] 'process raft request' (duration: 33.723281ms)","trace[1069263276] 'compare' (duration: 128.633518ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T13:06:51.167952Z","caller":"traceutil/trace.go:171","msg":"trace[507868524] transaction","detail":"{read_only:false; response_revision:23021; number_of_response:1; }","duration":"137.064255ms","start":"2026-06-02T13:06:51.030866Z","end":"2026-06-02T13:06:51.16793Z","steps":["trace[507868524] 'process raft request' (duration: 136.913792ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:10:03.450979Z","caller":"traceutil/trace.go:171","msg":"trace[1752555035] transaction","detail":"{read_only:false; response_revision:25006; number_of_response:1; }","duration":"168.116285ms","start":"2026-06-02T13:10:03.282831Z","end":"2026-06-02T13:10:03.450947Z","steps":["trace[1752555035] 'process raft request' (duration: 167.968742ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:10:03.950575Z","caller":"traceutil/trace.go:171","msg":"trace[2031251028] transaction","detail":"{read_only:false; response_revision:25007; number_of_response:1; }","duration":"359.407429ms","start":"2026-06-02T13:10:03.591148Z","end":"2026-06-02T13:10:03.950555Z","steps":["trace[2031251028] 'process raft request' (duration: 359.216905ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:03.950744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T13:10:03.591128Z","time spent":"359.554741ms","remote":"127.0.0.1:44496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8449,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T13:10:04.165789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.101329ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T13:10:04.165998Z","caller":"traceutil/trace.go:171","msg":"trace[835622675] linearizableReadLoop","detail":"{readStateIndex:25831; appliedIndex:25828; }","duration":"465.441356ms","start":"2026-06-02T13:10:03.700544Z","end":"2026-06-02T13:10:04.165985Z","steps":["trace[835622675] 'read index received' (duration: 249.847062ms)","trace[835622675] 'applied index is now lower than readState.Index' (duration: 215.593484ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T13:10:04.166059Z","caller":"traceutil/trace.go:171","msg":"trace[1732221451] transaction","detail":"{read_only:false; response_revision:25009; number_of_response:1; }","duration":"557.90758ms","start":"2026-06-02T13:10:03.608144Z","end":"2026-06-02T13:10:04.166052Z","steps":["trace[1732221451] 'process raft request' (duration: 557.731775ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:10:04.166042Z","caller":"traceutil/trace.go:171","msg":"trace[388771186] transaction","detail":"{read_only:false; response_revision:25008; number_of_response:1; }","duration":"565.599294ms","start":"2026-06-02T13:10:03.600406Z","end":"2026-06-02T13:10:04.166006Z","steps":["trace[388771186] 'process raft request' (duration: 392.165079ms)","trace[388771186] 'compare' (duration: 172.811903ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T13:10:04.166113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T13:10:03.608134Z","time spent":"557.95574ms","remote":"127.0.0.1:44578","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-06-02T13:10:04.166151Z","caller":"traceutil/trace.go:171","msg":"trace[1389873944] transaction","detail":"{read_only:false; response_revision:25010; number_of_response:1; }","duration":"427.401878ms","start":"2026-06-02T13:10:03.738716Z","end":"2026-06-02T13:10:04.166118Z","steps":["trace[1389873944] 'process raft request' (duration: 427.239194ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:04.166205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T13:10:03.600389Z","time spent":"565.726388ms","remote":"127.0.0.1:44578","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-06-02T13:10:04.16625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.697443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-06-02T13:10:04.166283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T13:10:03.738691Z","time spent":"427.522939ms","remote":"127.0.0.1:44578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T13:10:04.166322Z","caller":"traceutil/trace.go:171","msg":"trace[1665986679] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25010; }","duration":"465.819735ms","start":"2026-06-02T13:10:03.700484Z","end":"2026-06-02T13:10:04.166304Z","steps":["trace[1665986679] 'agreement among raft nodes before linearized reading' (duration: 465.627142ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:04.166367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T13:10:03.700464Z","time spent":"465.894316ms","remote":"127.0.0.1:44578","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":"info","ts":"2026-06-02T13:10:04.243753Z","caller":"traceutil/trace.go:171","msg":"trace[141620044] transaction","detail":"{read_only:false; response_revision:25014; number_of_response:1; }","duration":"282.617724ms","start":"2026-06-02T13:10:03.961105Z","end":"2026-06-02T13:10:04.243723Z","steps":["trace[141620044] 'process raft request' (duration: 282.538732ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:10:04.24395Z","caller":"traceutil/trace.go:171","msg":"trace[1039193941] transaction","detail":"{read_only:false; response_revision:25012; number_of_response:1; }","duration":"286.861484ms","start":"2026-06-02T13:10:03.957053Z","end":"2026-06-02T13:10:04.243915Z","steps":["trace[1039193941] 'process raft request' (duration: 286.194082ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:10:04.244201Z","caller":"traceutil/trace.go:171","msg":"trace[1004732674] transaction","detail":"{read_only:false; response_revision:25013; number_of_response:1; }","duration":"286.034388ms","start":"2026-06-02T13:10:03.958153Z","end":"2026-06-02T13:10:04.244188Z","steps":["trace[1004732674] 'process raft request' (duration: 285.403477ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:10:04.24436Z","caller":"traceutil/trace.go:171","msg":"trace[1458922746] transaction","detail":"{read_only:false; response_revision:25011; number_of_response:1; }","duration":"287.379004ms","start":"2026-06-02T13:10:03.956971Z","end":"2026-06-02T13:10:04.24435Z","steps":["trace[1458922746] 'process raft request' (duration: 286.101271ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:04.24489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.087404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-02T13:10:04.244948Z","caller":"traceutil/trace.go:171","msg":"trace[488500471] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25014; }","duration":"425.199856ms","start":"2026-06-02T13:10:03.819737Z","end":"2026-06-02T13:10:04.244936Z","steps":["trace[488500471] 'agreement among raft nodes before linearized reading' (duration: 425.052973ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:04.244948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.568125ms","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":"warn","ts":"2026-06-02T13:10:04.244978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T13:10:03.81971Z","time spent":"425.259776ms","remote":"127.0.0.1:44578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-02T13:10:04.245001Z","caller":"traceutil/trace.go:171","msg":"trace[1132658366] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/volumetypes/; range_end:/registry/openstack.k-orc.cloud/volumetypes0; response_count:0; response_revision:25014; }","duration":"243.632176ms","start":"2026-06-02T13:10:04.001353Z","end":"2026-06-02T13:10:04.244985Z","steps":["trace[1132658366] 'agreement among raft nodes before linearized reading' (duration: 243.540424ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:04.245143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.948407ms","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-06-02T13:10:04.245183Z","caller":"traceutil/trace.go:171","msg":"trace[329833176] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:25014; }","duration":"305.015689ms","start":"2026-06-02T13:10:03.940155Z","end":"2026-06-02T13:10:04.245171Z","steps":["trace[329833176] 'agreement among raft nodes before linearized reading' (duration: 304.951698ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:04.245202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.961696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/endpoints/\" range_end:\"/registry/openstack.k-orc.cloud/endpoints0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-02T13:10:04.245258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.034187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T13:10:04.245313Z","caller":"traceutil/trace.go:171","msg":"trace[525443305] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25014; }","duration":"402.105908ms","start":"2026-06-02T13:10:03.843197Z","end":"2026-06-02T13:10:04.245303Z","steps":["trace[525443305] 'agreement among raft nodes before linearized reading' (duration: 402.032846ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:04.24534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T13:10:03.843186Z","time spent":"402.147508ms","remote":"127.0.0.1:44348","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-06-02T13:10:04.245407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.802062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-06-02T13:10:04.245458Z","caller":"traceutil/trace.go:171","msg":"trace[61875041] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25014; }","duration":"174.899633ms","start":"2026-06-02T13:10:04.070546Z","end":"2026-06-02T13:10:04.245445Z","steps":["trace[61875041] 'agreement among raft nodes before linearized reading' (duration: 174.73673ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:04.245641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.240713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:418249"} {"level":"info","ts":"2026-06-02T13:10:04.245684Z","caller":"traceutil/trace.go:171","msg":"trace[1014006965] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:25014; }","duration":"419.306323ms","start":"2026-06-02T13:10:03.826369Z","end":"2026-06-02T13:10:04.245675Z","steps":["trace[1014006965] 'agreement among raft nodes before linearized reading' (duration: 419.11505ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:04.245711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T13:10:03.826359Z","time spent":"419.343074ms","remote":"127.0.0.1:34878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":418272,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" "} {"level":"info","ts":"2026-06-02T13:10:04.245258Z","caller":"traceutil/trace.go:171","msg":"trace[1807637565] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/endpoints/; range_end:/registry/openstack.k-orc.cloud/endpoints0; response_count:0; response_revision:25014; }","duration":"173.033888ms","start":"2026-06-02T13:10:04.072211Z","end":"2026-06-02T13:10:04.245245Z","steps":["trace[1807637565] 'agreement among raft nodes before linearized reading' (duration: 172.960667ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:04.245212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T13:10:03.940141Z","time spent":"305.06125ms","remote":"127.0.0.1:53554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":31,"request content":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true "} {"level":"info","ts":"2026-06-02T13:10:04.351442Z","caller":"traceutil/trace.go:171","msg":"trace[1921199177] linearizableReadLoop","detail":"{readStateIndex:25837; appliedIndex:25836; }","duration":"104.132882ms","start":"2026-06-02T13:10:04.247254Z","end":"2026-06-02T13:10:04.351386Z","steps":["trace[1921199177] 'read index received' (duration: 28.53424ms)","trace[1921199177] 'applied index is now lower than readState.Index' (duration: 75.597632ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T13:10:04.351499Z","caller":"traceutil/trace.go:171","msg":"trace[112521179] transaction","detail":"{read_only:false; response_revision:25015; number_of_response:1; }","duration":"180.481ms","start":"2026-06-02T13:10:04.170974Z","end":"2026-06-02T13:10:04.351455Z","steps":["trace[112521179] 'process raft request' (duration: 104.798126ms)","trace[112521179] 'compare' (duration: 75.47345ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T13:10:04.351657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.367907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T13:10:04.351755Z","caller":"traceutil/trace.go:171","msg":"trace[691753594] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25015; }","duration":"104.525541ms","start":"2026-06-02T13:10:04.247208Z","end":"2026-06-02T13:10:04.351733Z","steps":["trace[691753594] 'agreement among raft nodes before linearized reading' (duration: 104.337277ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:10:04.442676Z","caller":"traceutil/trace.go:171","msg":"trace[1476930136] transaction","detail":"{read_only:false; response_revision:25017; number_of_response:1; }","duration":"186.718387ms","start":"2026-06-02T13:10:04.255926Z","end":"2026-06-02T13:10:04.442644Z","steps":["trace[1476930136] 'process raft request' (duration: 186.540494ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:10:04.442701Z","caller":"traceutil/trace.go:171","msg":"trace[521907188] transaction","detail":"{read_only:false; response_revision:25016; number_of_response:1; }","duration":"191.65388ms","start":"2026-06-02T13:10:04.251016Z","end":"2026-06-02T13:10:04.44267Z","steps":["trace[521907188] 'process raft request' (duration: 191.297434ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:04.442757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.684631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-06-02T13:10:04.442828Z","caller":"traceutil/trace.go:171","msg":"trace[1508616600] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25017; }","duration":"191.804223ms","start":"2026-06-02T13:10:04.251007Z","end":"2026-06-02T13:10:04.442811Z","steps":["trace[1508616600] 'agreement among raft nodes before linearized reading' (duration: 191.536898ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:04.442959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.648055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-02T13:10:04.443046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.571886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T13:10:04.443107Z","caller":"traceutil/trace.go:171","msg":"trace[120947048] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:25017; }","duration":"175.681838ms","start":"2026-06-02T13:10:04.267398Z","end":"2026-06-02T13:10:04.44308Z","steps":["trace[120947048] 'agreement among raft nodes before linearized reading' (duration: 175.582656ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:10:04.443101Z","caller":"traceutil/trace.go:171","msg":"trace[569276819] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:25017; }","duration":"149.859459ms","start":"2026-06-02T13:10:04.293206Z","end":"2026-06-02T13:10:04.443065Z","steps":["trace[569276819] 'agreement among raft nodes before linearized reading' (duration: 149.495342ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:04.443252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.920249ms","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-06-02T13:10:04.443404Z","caller":"traceutil/trace.go:171","msg":"trace[2134848065] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25017; }","duration":"160.069603ms","start":"2026-06-02T13:10:04.283262Z","end":"2026-06-02T13:10:04.443331Z","steps":["trace[2134848065] 'agreement among raft nodes before linearized reading' (duration: 159.706326ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:04.443521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.450451ms","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-06-02T13:10:04.443824Z","caller":"traceutil/trace.go:171","msg":"trace[1688120743] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25017; }","duration":"150.766616ms","start":"2026-06-02T13:10:04.293044Z","end":"2026-06-02T13:10:04.44381Z","steps":["trace[1688120743] 'agreement among raft nodes before linearized reading' (duration: 150.385809ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:10:04.661986Z","caller":"traceutil/trace.go:171","msg":"trace[1212634752] transaction","detail":"{read_only:false; response_revision:25018; number_of_response:1; }","duration":"214.571566ms","start":"2026-06-02T13:10:04.447376Z","end":"2026-06-02T13:10:04.661948Z","steps":["trace[1212634752] 'process raft request' (duration: 214.215648ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:10:04.972136Z","caller":"traceutil/trace.go:171","msg":"trace[93896062] transaction","detail":"{read_only:false; response_revision:25019; number_of_response:1; }","duration":"523.461877ms","start":"2026-06-02T13:10:04.448646Z","end":"2026-06-02T13:10:04.972108Z","steps":["trace[93896062] 'process raft request' (duration: 523.159672ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:04.972278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T13:10:04.448614Z","time spent":"523.586449ms","remote":"127.0.0.1:44578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T13:10:04.972484Z","caller":"traceutil/trace.go:171","msg":"trace[1465259642] linearizableReadLoop","detail":"{readStateIndex:25841; appliedIndex:25839; }","duration":"461.453842ms","start":"2026-06-02T13:10:04.511001Z","end":"2026-06-02T13:10:04.972455Z","steps":["trace[1465259642] 'read index received' (duration: 150.723986ms)","trace[1465259642] 'applied index is now lower than readState.Index' (duration: 310.728636ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T13:10:04.972651Z","caller":"traceutil/trace.go:171","msg":"trace[1538068385] transaction","detail":"{read_only:false; response_revision:25020; number_of_response:1; }","duration":"328.28845ms","start":"2026-06-02T13:10:04.64435Z","end":"2026-06-02T13:10:04.972638Z","steps":["trace[1538068385] 'process raft request' (duration: 327.80779ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:04.972791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T13:10:04.644323Z","time spent":"328.360851ms","remote":"127.0.0.1:34878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418457,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T13:10:04.97283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.793659ms","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-06-02T13:10:04.972921Z","caller":"traceutil/trace.go:171","msg":"trace[1177389609] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25020; }","duration":"461.946412ms","start":"2026-06-02T13:10:04.510953Z","end":"2026-06-02T13:10:04.9729Z","steps":["trace[1177389609] 'agreement among raft nodes before linearized reading' (duration: 461.761458ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:04.972979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T13:10:04.510936Z","time spent":"462.033423ms","remote":"127.0.0.1:44756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-06-02T13:10:05.28551Z","caller":"traceutil/trace.go:171","msg":"trace[881046841] transaction","detail":"{read_only:false; response_revision:25021; number_of_response:1; }","duration":"561.593589ms","start":"2026-06-02T13:10:04.723896Z","end":"2026-06-02T13:10:05.28549Z","steps":["trace[881046841] 'process raft request' (duration: 561.424296ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:05.285634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T13:10:04.723871Z","time spent":"561.697741ms","remote":"127.0.0.1:44578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T13:10:05.285855Z","caller":"traceutil/trace.go:171","msg":"trace[1236397967] linearizableReadLoop","detail":"{readStateIndex:25845; appliedIndex:25842; }","duration":"313.232793ms","start":"2026-06-02T13:10:04.972597Z","end":"2026-06-02T13:10:05.28583Z","steps":["trace[1236397967] 'read index received' (duration: 312.792265ms)","trace[1236397967] 'applied index is now lower than readState.Index' (duration: 439.578µs)"],"step_count":2} {"level":"warn","ts":"2026-06-02T13:10:05.285967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.26427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T13:10:05.286004Z","caller":"traceutil/trace.go:171","msg":"trace[133981606] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25023; }","duration":"441.34731ms","start":"2026-06-02T13:10:04.844648Z","end":"2026-06-02T13:10:05.285996Z","steps":["trace[133981606] 'agreement among raft nodes before linearized reading' (duration: 441.270989ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:05.286026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T13:10:04.844627Z","time spent":"441.395692ms","remote":"127.0.0.1:44348","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-06-02T13:10:05.286044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.022925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/roles/\" range_end:\"/registry/openstack.k-orc.cloud/roles0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T13:10:05.286091Z","caller":"traceutil/trace.go:171","msg":"trace[466398851] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/roles/; range_end:/registry/openstack.k-orc.cloud/roles0; response_count:0; response_revision:25023; }","duration":"545.093506ms","start":"2026-06-02T13:10:04.740984Z","end":"2026-06-02T13:10:05.286078Z","steps":["trace[466398851] 'agreement among raft nodes before linearized reading' (duration: 544.998294ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:10:05.286129Z","caller":"traceutil/trace.go:171","msg":"trace[2044286934] transaction","detail":"{read_only:false; response_revision:25023; number_of_response:1; }","duration":"316.656939ms","start":"2026-06-02T13:10:04.969464Z","end":"2026-06-02T13:10:05.286121Z","steps":["trace[2044286934] 'process raft request' (duration: 316.254681ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:05.286139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T13:10:04.740935Z","time spent":"545.192228ms","remote":"127.0.0.1:59868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/roles/\" range_end:\"/registry/openstack.k-orc.cloud/roles0\" count_only:true "} {"level":"warn","ts":"2026-06-02T13:10:05.286289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T13:10:04.969427Z","time spent":"316.726801ms","remote":"127.0.0.1:44578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T13:10:05.286278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.310688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T13:10:05.286349Z","caller":"traceutil/trace.go:171","msg":"trace[87046001] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:25023; }","duration":"614.43967ms","start":"2026-06-02T13:10:04.671892Z","end":"2026-06-02T13:10:05.286331Z","steps":["trace[87046001] 'agreement among raft nodes before linearized reading' (duration: 614.282388ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:05.286398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T13:10:04.671872Z","time spent":"614.516831ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true "} {"level":"info","ts":"2026-06-02T13:10:05.286637Z","caller":"traceutil/trace.go:171","msg":"trace[916661480] transaction","detail":"{read_only:false; response_revision:25022; number_of_response:1; }","duration":"438.305243ms","start":"2026-06-02T13:10:04.847477Z","end":"2026-06-02T13:10:05.285782Z","steps":["trace[916661480] 'process raft request' (duration: 437.978347ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:05.28676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T13:10:04.847459Z","time spent":"439.238921ms","remote":"127.0.0.1:44578","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-06-02T13:10:05.286978Z","caller":"traceutil/trace.go:171","msg":"trace[1941757977] transaction","detail":"{read_only:false; response_revision:25024; number_of_response:1; }","duration":"178.340398ms","start":"2026-06-02T13:10:05.108631Z","end":"2026-06-02T13:10:05.286971Z","steps":["trace[1941757977] 'process raft request' (duration: 178.234566ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:05.287038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.012181ms","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-06-02T13:10:05.287069Z","caller":"traceutil/trace.go:171","msg":"trace[75822997] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25024; }","duration":"311.075073ms","start":"2026-06-02T13:10:04.975985Z","end":"2026-06-02T13:10:05.28706Z","steps":["trace[75822997] 'agreement among raft nodes before linearized reading' (duration: 310.986091ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:05.287094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T13:10:04.975969Z","time spent":"311.119313ms","remote":"127.0.0.1:44510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-06-02T13:10:05.287163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.861982ms","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-06-02T13:10:05.287186Z","caller":"traceutil/trace.go:171","msg":"trace[2081161828] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25024; }","duration":"221.931444ms","start":"2026-06-02T13:10:05.065249Z","end":"2026-06-02T13:10:05.287181Z","steps":["trace[2081161828] 'agreement among raft nodes before linearized reading' (duration: 221.826812ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:09.836061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.370977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/ports/\" range_end:\"/registry/openstack.k-orc.cloud/ports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T13:10:09.836155Z","caller":"traceutil/trace.go:171","msg":"trace[1310663526] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/ports/; range_end:/registry/openstack.k-orc.cloud/ports0; response_count:0; response_revision:25063; }","duration":"104.515839ms","start":"2026-06-02T13:10:09.731619Z","end":"2026-06-02T13:10:09.836135Z","steps":["trace[1310663526] 'count revisions from in-memory index tree' (duration: 104.263535ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:10:10.372794Z","caller":"traceutil/trace.go:171","msg":"trace[1078616089] transaction","detail":"{read_only:false; response_revision:25064; number_of_response:1; }","duration":"186.097924ms","start":"2026-06-02T13:10:10.186665Z","end":"2026-06-02T13:10:10.372763Z","steps":["trace[1078616089] 'process raft request' (duration: 185.84237ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:10:10.377477Z","caller":"traceutil/trace.go:171","msg":"trace[1542839021] transaction","detail":"{read_only:false; response_revision:25065; number_of_response:1; }","duration":"188.685724ms","start":"2026-06-02T13:10:10.188772Z","end":"2026-06-02T13:10:10.377458Z","steps":["trace[1542839021] 'process raft request' (duration: 188.547702ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:10:10.377647Z","caller":"traceutil/trace.go:171","msg":"trace[888854216] linearizableReadLoop","detail":"{readStateIndex:25889; appliedIndex:25886; }","duration":"130.806609ms","start":"2026-06-02T13:10:10.246803Z","end":"2026-06-02T13:10:10.37761Z","steps":["trace[888854216] 'read index received' (duration: 125.869935ms)","trace[888854216] 'applied index is now lower than readState.Index' (duration: 4.931274ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T13:10:10.377984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.164395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-02T13:10:10.378025Z","caller":"traceutil/trace.go:171","msg":"trace[1808860540] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:25066; }","duration":"131.282877ms","start":"2026-06-02T13:10:10.24673Z","end":"2026-06-02T13:10:10.378013Z","steps":["trace[1808860540] 'agreement among raft nodes before linearized reading' (duration: 131.160285ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:10:10.378227Z","caller":"traceutil/trace.go:171","msg":"trace[1906160454] transaction","detail":"{read_only:false; response_revision:25066; number_of_response:1; }","duration":"186.368741ms","start":"2026-06-02T13:10:10.191846Z","end":"2026-06-02T13:10:10.378215Z","steps":["trace[1906160454] 'process raft request' (duration: 185.568646ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:10.707079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.103073ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T13:10:10.707227Z","caller":"traceutil/trace.go:171","msg":"trace[1036628375] linearizableReadLoop","detail":"{readStateIndex:25890; appliedIndex:25889; }","duration":"324.095859ms","start":"2026-06-02T13:10:10.38311Z","end":"2026-06-02T13:10:10.707206Z","steps":["trace[1036628375] 'read index received' (duration: 59.67221ms)","trace[1036628375] 'applied index is now lower than readState.Index' (duration: 264.422239ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T13:10:10.7073Z","caller":"traceutil/trace.go:171","msg":"trace[983009812] transaction","detail":"{read_only:false; response_revision:25067; number_of_response:1; }","duration":"325.525086ms","start":"2026-06-02T13:10:10.381708Z","end":"2026-06-02T13:10:10.707233Z","steps":["trace[983009812] 'process raft request' (duration: 61.153138ms)","trace[983009812] 'compare' (duration: 263.342969ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T13:10:10.707473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T13:10:10.381684Z","time spent":"325.69804ms","remote":"127.0.0.1:44578","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-06-02T13:10:10.707489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.754762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-02T13:10:10.707541Z","caller":"traceutil/trace.go:171","msg":"trace[2007941291] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25067; }","duration":"239.841714ms","start":"2026-06-02T13:10:10.467687Z","end":"2026-06-02T13:10:10.707529Z","steps":["trace[2007941291] 'agreement among raft nodes before linearized reading' (duration: 239.67158ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:10.707483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.355685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-06-02T13:10:10.707632Z","caller":"traceutil/trace.go:171","msg":"trace[591691919] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25067; }","duration":"324.521248ms","start":"2026-06-02T13:10:10.383091Z","end":"2026-06-02T13:10:10.707612Z","steps":["trace[591691919] 'agreement among raft nodes before linearized reading' (duration: 324.217451ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:10.707658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T13:10:10.383081Z","time spent":"324.571689ms","remote":"127.0.0.1:44496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-06-02T13:10:11.233633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.168274ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T13:10:11.233856Z","caller":"traceutil/trace.go:171","msg":"trace[501898150] linearizableReadLoop","detail":"{readStateIndex:25892; appliedIndex:25890; }","duration":"442.778208ms","start":"2026-06-02T13:10:10.79106Z","end":"2026-06-02T13:10:11.233838Z","steps":["trace[501898150] 'read index received' (duration: 225.320959ms)","trace[501898150] 'applied index is now lower than readState.Index' (duration: 217.456319ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T13:10:11.23396Z","caller":"traceutil/trace.go:171","msg":"trace[1893108677] transaction","detail":"{read_only:false; response_revision:25068; number_of_response:1; }","duration":"521.772315ms","start":"2026-06-02T13:10:10.712175Z","end":"2026-06-02T13:10:11.233948Z","steps":["trace[1893108677] 'process raft request' (duration: 304.140442ms)","trace[1893108677] 'compare' (duration: 216.852158ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T13:10:11.234029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T13:10:10.712158Z","time spent":"521.835866ms","remote":"127.0.0.1:44578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T13:10:11.234353Z","caller":"traceutil/trace.go:171","msg":"trace[789261533] transaction","detail":"{read_only:false; response_revision:25069; number_of_response:1; }","duration":"521.579921ms","start":"2026-06-02T13:10:10.712761Z","end":"2026-06-02T13:10:11.23434Z","steps":["trace[789261533] 'process raft request' (duration: 520.98893ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:11.234417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T13:10:10.712736Z","time spent":"521.651643ms","remote":"127.0.0.1:44578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T13:10:11.234587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.499712ms","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-06-02T13:10:11.234722Z","caller":"traceutil/trace.go:171","msg":"trace[696528679] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25069; }","duration":"443.697956ms","start":"2026-06-02T13:10:10.790997Z","end":"2026-06-02T13:10:11.234695Z","steps":["trace[696528679] 'agreement among raft nodes before linearized reading' (duration: 443.470312ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:11.23478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T13:10:10.790974Z","time spent":"443.795917ms","remote":"127.0.0.1:44756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-06-02T13:10:11.234909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.676151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T13:10:11.234959Z","caller":"traceutil/trace.go:171","msg":"trace[1662989283] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25069; }","duration":"389.769473ms","start":"2026-06-02T13:10:10.845176Z","end":"2026-06-02T13:10:11.234946Z","steps":["trace[1662989283] 'agreement among raft nodes before linearized reading' (duration: 389.690112ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:11.234996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T13:10:10.845152Z","time spent":"389.836805ms","remote":"127.0.0.1:44348","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-06-02T13:10:11.235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.665125ms","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-06-02T13:10:11.235043Z","caller":"traceutil/trace.go:171","msg":"trace[888922829] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25069; }","duration":"234.738357ms","start":"2026-06-02T13:10:11.000286Z","end":"2026-06-02T13:10:11.235024Z","steps":["trace[888922829] 'agreement among raft nodes before linearized reading' (duration: 234.608234ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:10:11.504059Z","caller":"traceutil/trace.go:171","msg":"trace[1391052175] linearizableReadLoop","detail":"{readStateIndex:25893; appliedIndex:25892; }","duration":"262.993582ms","start":"2026-06-02T13:10:11.241038Z","end":"2026-06-02T13:10:11.504031Z","steps":["trace[1391052175] 'read index received' (duration: 262.857109ms)","trace[1391052175] 'applied index is now lower than readState.Index' (duration: 135.023µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T13:10:11.504127Z","caller":"traceutil/trace.go:171","msg":"trace[1841236995] transaction","detail":"{read_only:false; response_revision:25070; number_of_response:1; }","duration":"263.241727ms","start":"2026-06-02T13:10:11.240836Z","end":"2026-06-02T13:10:11.504078Z","steps":["trace[1841236995] 'process raft request' (duration: 263.004762ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:11.504327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.257037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-06-02T13:10:11.504381Z","caller":"traceutil/trace.go:171","msg":"trace[994229864] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25070; }","duration":"263.345319ms","start":"2026-06-02T13:10:11.241022Z","end":"2026-06-02T13:10:11.504367Z","steps":["trace[994229864] 'agreement among raft nodes before linearized reading' (duration: 263.139765ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:10:11.663268Z","caller":"traceutil/trace.go:171","msg":"trace[1405987739] transaction","detail":"{read_only:false; response_revision:25072; number_of_response:1; }","duration":"353.16338ms","start":"2026-06-02T13:10:11.310071Z","end":"2026-06-02T13:10:11.663235Z","steps":["trace[1405987739] 'process raft request' (duration: 353.048778ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:10:11.663312Z","caller":"traceutil/trace.go:171","msg":"trace[2051754998] transaction","detail":"{read_only:false; response_revision:25071; number_of_response:1; }","duration":"353.287023ms","start":"2026-06-02T13:10:11.30995Z","end":"2026-06-02T13:10:11.663237Z","steps":["trace[2051754998] 'process raft request' (duration: 352.967317ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:11.663457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T13:10:11.310064Z","time spent":"353.290343ms","remote":"127.0.0.1:44578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T13:10:11.663561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T13:10:11.309919Z","time spent":"353.508266ms","remote":"127.0.0.1:44578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T13:10:11.663728Z","caller":"traceutil/trace.go:171","msg":"trace[1595305742] transaction","detail":"{read_only:false; response_revision:25073; number_of_response:1; }","duration":"352.022379ms","start":"2026-06-02T13:10:11.311692Z","end":"2026-06-02T13:10:11.663714Z","steps":["trace[1595305742] 'process raft request' (duration: 351.496629ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:11.663799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T13:10:11.311676Z","time spent":"352.085071ms","remote":"127.0.0.1:44578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T13:10:11.663813Z","caller":"traceutil/trace.go:171","msg":"trace[485269156] linearizableReadLoop","detail":"{readStateIndex:25899; appliedIndex:25893; }","duration":"159.656595ms","start":"2026-06-02T13:10:11.504132Z","end":"2026-06-02T13:10:11.663789Z","steps":["trace[485269156] 'read index received' (duration: 158.755618ms)","trace[485269156] 'applied index is now lower than readState.Index' (duration: 899.717µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T13:10:11.663914Z","caller":"traceutil/trace.go:171","msg":"trace[1248353183] transaction","detail":"{read_only:false; response_revision:25076; number_of_response:1; }","duration":"183.1218ms","start":"2026-06-02T13:10:11.480766Z","end":"2026-06-02T13:10:11.663888Z","steps":["trace[1248353183] 'process raft request' (duration: 182.970546ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:10:11.663974Z","caller":"traceutil/trace.go:171","msg":"trace[924664249] transaction","detail":"{read_only:false; response_revision:25074; number_of_response:1; }","duration":"350.99339ms","start":"2026-06-02T13:10:11.312966Z","end":"2026-06-02T13:10:11.663959Z","steps":["trace[924664249] 'process raft request' (duration: 350.5133ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:11.664045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T13:10:11.31295Z","time spent":"351.056531ms","remote":"127.0.0.1:44578","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-06-02T13:10:11.664145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.234611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"warn","ts":"2026-06-02T13:10:11.664157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.85628ms","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-06-02T13:10:11.664196Z","caller":"traceutil/trace.go:171","msg":"trace[1538523432] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:25076; }","duration":"269.339553ms","start":"2026-06-02T13:10:11.394843Z","end":"2026-06-02T13:10:11.664183Z","steps":["trace[1538523432] 'agreement among raft nodes before linearized reading' (duration: 269.101389ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:10:11.664208Z","caller":"traceutil/trace.go:171","msg":"trace[1815103781] transaction","detail":"{read_only:false; response_revision:25075; number_of_response:1; }","duration":"237.716373ms","start":"2026-06-02T13:10:11.426467Z","end":"2026-06-02T13:10:11.664183Z","steps":["trace[1815103781] 'process raft request' (duration: 237.219673ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:10:11.664232Z","caller":"traceutil/trace.go:171","msg":"trace[1263616753] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25076; }","duration":"329.003193ms","start":"2026-06-02T13:10:11.335216Z","end":"2026-06-02T13:10:11.664219Z","steps":["trace[1263616753] 'agreement among raft nodes before linearized reading' (duration: 328.740858ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:11.664273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T13:10:11.33517Z","time spent":"329.093255ms","remote":"127.0.0.1:44578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-02T13:10:11.664265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.823517ms","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":"warn","ts":"2026-06-02T13:10:11.664343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.626505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18419"} {"level":"info","ts":"2026-06-02T13:10:11.664375Z","caller":"traceutil/trace.go:171","msg":"trace[2086271507] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:25076; }","duration":"226.99335ms","start":"2026-06-02T13:10:11.437355Z","end":"2026-06-02T13:10:11.664349Z","steps":["trace[2086271507] 'agreement among raft nodes before linearized reading' (duration: 226.773866ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:10:11.664449Z","caller":"traceutil/trace.go:171","msg":"trace[172081317] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25076; }","duration":"158.748427ms","start":"2026-06-02T13:10:11.505674Z","end":"2026-06-02T13:10:11.664423Z","steps":["trace[172081317] 'agreement among raft nodes before linearized reading' (duration: 158.488652ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:10:11.772189Z","caller":"traceutil/trace.go:171","msg":"trace[2041217568] transaction","detail":"{read_only:false; response_revision:25077; number_of_response:1; }","duration":"103.169145ms","start":"2026-06-02T13:10:11.668994Z","end":"2026-06-02T13:10:11.772163Z","steps":["trace[2041217568] 'process raft request' (duration: 100.697618ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:10:12.551092Z","caller":"traceutil/trace.go:171","msg":"trace[585465231] linearizableReadLoop","detail":"{readStateIndex:25904; appliedIndex:25902; }","duration":"106.305204ms","start":"2026-06-02T13:10:12.444764Z","end":"2026-06-02T13:10:12.551069Z","steps":["trace[585465231] 'read index received' (duration: 105.997889ms)","trace[585465231] 'applied index is now lower than readState.Index' (duration: 306.575µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T13:10:12.551141Z","caller":"traceutil/trace.go:171","msg":"trace[1174593661] transaction","detail":"{read_only:false; response_revision:25080; number_of_response:1; }","duration":"166.860491ms","start":"2026-06-02T13:10:12.384239Z","end":"2026-06-02T13:10:12.5511Z","steps":["trace[1174593661] 'process raft request' (duration: 166.7933ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:12.551247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.443547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T13:10:12.551334Z","caller":"traceutil/trace.go:171","msg":"trace[191946458] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesets/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesets0; response_count:0; response_revision:25080; }","duration":"106.5993ms","start":"2026-06-02T13:10:12.444712Z","end":"2026-06-02T13:10:12.551312Z","steps":["trace[191946458] 'agreement among raft nodes before linearized reading' (duration: 106.408666ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:10:12.551444Z","caller":"traceutil/trace.go:171","msg":"trace[1365527571] transaction","detail":"{read_only:false; response_revision:25079; number_of_response:1; }","duration":"168.424661ms","start":"2026-06-02T13:10:12.383001Z","end":"2026-06-02T13:10:12.551425Z","steps":["trace[1365527571] 'process raft request' (duration: 167.84703ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:12.858549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.159609ms","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-06-02T13:10:12.858707Z","caller":"traceutil/trace.go:171","msg":"trace[1532355242] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25080; }","duration":"136.361153ms","start":"2026-06-02T13:10:12.72232Z","end":"2026-06-02T13:10:12.858681Z","steps":["trace[1532355242] 'range keys from in-memory index tree' (duration: 135.970776ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:10:15.886305Z","caller":"traceutil/trace.go:171","msg":"trace[1484625098] transaction","detail":"{read_only:false; response_revision:25101; number_of_response:1; }","duration":"209.06859ms","start":"2026-06-02T13:10:15.677196Z","end":"2026-06-02T13:10:15.886264Z","steps":["trace[1484625098] 'process raft request' (duration: 208.845366ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:10:16.124866Z","caller":"traceutil/trace.go:171","msg":"trace[1827313571] transaction","detail":"{read_only:false; response_revision:25102; number_of_response:1; }","duration":"447.334815ms","start":"2026-06-02T13:10:15.677476Z","end":"2026-06-02T13:10:16.124811Z","steps":["trace[1827313571] 'process raft request' (duration: 446.618801ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:10:16.124952Z","caller":"traceutil/trace.go:171","msg":"trace[1605385806] transaction","detail":"{read_only:false; response_revision:25103; number_of_response:1; }","duration":"446.56903ms","start":"2026-06-02T13:10:15.678355Z","end":"2026-06-02T13:10:16.124924Z","steps":["trace[1605385806] 'process raft request' (duration: 446.344476ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:16.125128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T13:10:15.677453Z","time spent":"447.530208ms","remote":"127.0.0.1:44578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T13:10:16.125193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T13:10:15.678344Z","time spent":"446.690772ms","remote":"127.0.0.1:44578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T13:10:16.125267Z","caller":"traceutil/trace.go:171","msg":"trace[222691078] transaction","detail":"{read_only:false; response_revision:25104; number_of_response:1; }","duration":"446.754694ms","start":"2026-06-02T13:10:15.678482Z","end":"2026-06-02T13:10:16.125237Z","steps":["trace[222691078] 'process raft request' (duration: 446.364006ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:10:16.125357Z","caller":"traceutil/trace.go:171","msg":"trace[1276898041] linearizableReadLoop","detail":"{readStateIndex:25928; appliedIndex:25924; }","duration":"446.666571ms","start":"2026-06-02T13:10:15.678667Z","end":"2026-06-02T13:10:16.125334Z","steps":["trace[1276898041] 'read index received' (duration: 207.330837ms)","trace[1276898041] 'applied index is now lower than readState.Index' (duration: 239.334534ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T13:10:16.125428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T13:10:15.678443Z","time spent":"446.897855ms","remote":"127.0.0.1:44578","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-06-02T13:10:16.125453Z","caller":"traceutil/trace.go:171","msg":"trace[624529906] transaction","detail":"{read_only:false; response_revision:25105; number_of_response:1; }","duration":"445.303586ms","start":"2026-06-02T13:10:15.680122Z","end":"2026-06-02T13:10:16.125426Z","steps":["trace[624529906] 'process raft request' (duration: 445.041751ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:10:16.125506Z","caller":"traceutil/trace.go:171","msg":"trace[619887412] transaction","detail":"{read_only:false; response_revision:25106; number_of_response:1; }","duration":"414.466972ms","start":"2026-06-02T13:10:15.711025Z","end":"2026-06-02T13:10:16.125492Z","steps":["trace[619887412] 'process raft request' (duration: 414.256498ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:16.125563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.866715ms","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":"warn","ts":"2026-06-02T13:10:16.125571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T13:10:15.710999Z","time spent":"414.539433ms","remote":"127.0.0.1:44578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T13:10:16.125609Z","caller":"traceutil/trace.go:171","msg":"trace[293297511] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:25106; }","duration":"446.967047ms","start":"2026-06-02T13:10:15.678629Z","end":"2026-06-02T13:10:16.125596Z","steps":["trace[293297511] 'agreement among raft nodes before linearized reading' (duration: 446.870825ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:16.125678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T13:10:15.678614Z","time spent":"447.052758ms","remote":"127.0.0.1:59304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":29,"request content":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true "} {"level":"warn","ts":"2026-06-02T13:10:16.12575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.959202ms","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-06-02T13:10:16.125795Z","caller":"traceutil/trace.go:171","msg":"trace[1531004080] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25106; }","duration":"338.055993ms","start":"2026-06-02T13:10:15.787728Z","end":"2026-06-02T13:10:16.125784Z","steps":["trace[1531004080] 'agreement among raft nodes before linearized reading' (duration: 337.903501ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:16.125802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.46877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"warn","ts":"2026-06-02T13:10:16.125824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T13:10:15.787693Z","time spent":"338.124845ms","remote":"127.0.0.1:44578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-02T13:10:16.125866Z","caller":"traceutil/trace.go:171","msg":"trace[493872456] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25106; }","duration":"178.581023ms","start":"2026-06-02T13:10:15.94727Z","end":"2026-06-02T13:10:16.125851Z","steps":["trace[493872456] 'agreement among raft nodes before linearized reading' (duration: 178.387949ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:16.126445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T13:10:15.68011Z","time spent":"446.201153ms","remote":"127.0.0.1:44578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T13:10:24.881184Z","caller":"traceutil/trace.go:171","msg":"trace[901934427] transaction","detail":"{read_only:false; response_revision:25182; number_of_response:1; }","duration":"273.340029ms","start":"2026-06-02T13:10:24.607812Z","end":"2026-06-02T13:10:24.881152Z","steps":["trace[901934427] 'process raft request' (duration: 273.176465ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:10:24.921649Z","caller":"traceutil/trace.go:171","msg":"trace[1160397046] transaction","detail":"{read_only:false; response_revision:25183; number_of_response:1; }","duration":"313.511819ms","start":"2026-06-02T13:10:24.608106Z","end":"2026-06-02T13:10:24.921618Z","steps":["trace[1160397046] 'process raft request' (duration: 312.823226ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:24.921826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T13:10:24.608099Z","time spent":"313.617241ms","remote":"127.0.0.1:44578","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-06-02T13:10:24.921876Z","caller":"traceutil/trace.go:171","msg":"trace[184151199] transaction","detail":"{read_only:false; response_revision:25184; number_of_response:1; }","duration":"298.993665ms","start":"2026-06-02T13:10:24.622847Z","end":"2026-06-02T13:10:24.921841Z","steps":["trace[184151199] 'process raft request' (duration: 298.684608ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:25.533365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.188015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-02T13:10:25.533464Z","caller":"traceutil/trace.go:171","msg":"trace[1344354697] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:25185; }","duration":"273.363238ms","start":"2026-06-02T13:10:25.260079Z","end":"2026-06-02T13:10:25.533442Z","steps":["trace[1344354697] 'count revisions from in-memory index tree' (duration: 273.033192ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:25.533511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.12283ms","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-06-02T13:10:25.533602Z","caller":"traceutil/trace.go:171","msg":"trace[858404995] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25185; }","duration":"202.258912ms","start":"2026-06-02T13:10:25.331323Z","end":"2026-06-02T13:10:25.533582Z","steps":["trace[858404995] 'range keys from in-memory index tree' (duration: 201.924075ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:25.53351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.310112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-02T13:10:25.533747Z","caller":"traceutil/trace.go:171","msg":"trace[1165766693] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25185; }","duration":"213.578477ms","start":"2026-06-02T13:10:25.320125Z","end":"2026-06-02T13:10:25.533704Z","steps":["trace[1165766693] 'range keys from in-memory index tree' (duration: 213.155168ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:25.533785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.342331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T13:10:25.533835Z","caller":"traceutil/trace.go:171","msg":"trace[1069363713] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:25185; }","duration":"489.439172ms","start":"2026-06-02T13:10:25.044382Z","end":"2026-06-02T13:10:25.533822Z","steps":["trace[1069363713] 'count revisions from in-memory index tree' (duration: 489.245399ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:25.533868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T13:10:25.044359Z","time spent":"489.498514ms","remote":"127.0.0.1:59712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true "} {"level":"info","ts":"2026-06-02T13:10:25.810082Z","caller":"traceutil/trace.go:171","msg":"trace[1871657314] transaction","detail":"{read_only:false; response_revision:25186; number_of_response:1; }","duration":"272.258578ms","start":"2026-06-02T13:10:25.537794Z","end":"2026-06-02T13:10:25.810052Z","steps":["trace[1871657314] 'process raft request' (duration: 272.043594ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:10:25.813336Z","caller":"traceutil/trace.go:171","msg":"trace[352569590] transaction","detail":"{read_only:false; response_revision:25187; number_of_response:1; }","duration":"274.970788ms","start":"2026-06-02T13:10:25.538352Z","end":"2026-06-02T13:10:25.813323Z","steps":["trace[352569590] 'process raft request' (duration: 274.725234ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:10:25.813232Z","caller":"traceutil/trace.go:171","msg":"trace[2067002795] linearizableReadLoop","detail":"{readStateIndex:26013; appliedIndex:26011; }","duration":"166.341411ms","start":"2026-06-02T13:10:25.646864Z","end":"2026-06-02T13:10:25.813205Z","steps":["trace[2067002795] 'read index received' (duration: 163.019219ms)","trace[2067002795] 'applied index is now lower than readState.Index' (duration: 3.320832ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T13:10:25.813588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.72498ms","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-06-02T13:10:25.813619Z","caller":"traceutil/trace.go:171","msg":"trace[476247913] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25187; }","duration":"166.824992ms","start":"2026-06-02T13:10:25.646786Z","end":"2026-06-02T13:10:25.813611Z","steps":["trace[476247913] 'agreement among raft nodes before linearized reading' (duration: 166.70899ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:26.118918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.852518ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T13:10:26.119168Z","caller":"traceutil/trace.go:171","msg":"trace[1398114336] transaction","detail":"{read_only:false; response_revision:25189; number_of_response:1; }","duration":"200.864355ms","start":"2026-06-02T13:10:25.918281Z","end":"2026-06-02T13:10:26.119145Z","steps":["trace[1398114336] 'process raft request' (duration: 200.752733ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:10:26.119185Z","caller":"traceutil/trace.go:171","msg":"trace[453571350] transaction","detail":"{read_only:false; response_revision:25188; number_of_response:1; }","duration":"299.968802ms","start":"2026-06-02T13:10:25.819156Z","end":"2026-06-02T13:10:26.119125Z","steps":["trace[453571350] 'process raft request' (duration: 25.806068ms)","trace[453571350] 'compare' (duration: 273.371989ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T13:10:26.119432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T13:10:25.819125Z","time spent":"300.167076ms","remote":"127.0.0.1:44578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T13:10:26.119559Z","caller":"traceutil/trace.go:171","msg":"trace[574379183] linearizableReadLoop","detail":"{readStateIndex:26014; appliedIndex:26013; }","duration":"274.927768ms","start":"2026-06-02T13:10:25.844599Z","end":"2026-06-02T13:10:26.119527Z","steps":["trace[574379183] 'read index received' (duration: 294.146µs)","trace[574379183] 'applied index is now lower than readState.Index' (duration: 274.631822ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T13:10:26.119674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.066121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T13:10:26.119701Z","caller":"traceutil/trace.go:171","msg":"trace[1360298212] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25189; }","duration":"275.153952ms","start":"2026-06-02T13:10:25.844537Z","end":"2026-06-02T13:10:26.119691Z","steps":["trace[1360298212] 'agreement among raft nodes before linearized reading' (duration: 275.081891ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:26.688775Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322103480899939285,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-02T13:10:26.723086Z","caller":"traceutil/trace.go:171","msg":"trace[897243856] transaction","detail":"{read_only:false; response_revision:25190; number_of_response:1; }","duration":"547.331059ms","start":"2026-06-02T13:10:26.175727Z","end":"2026-06-02T13:10:26.723058Z","steps":["trace[897243856] 'process raft request' (duration: 547.102704ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:26.72325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T13:10:26.175697Z","time spent":"547.466132ms","remote":"127.0.0.1:44578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T13:10:27.179727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T13:10:26.822779Z","time spent":"356.937182ms","remote":"127.0.0.1:55368","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-06-02T13:10:27.18498Z","caller":"traceutil/trace.go:171","msg":"trace[1281416703] transaction","detail":"{read_only:false; response_revision:25191; number_of_response:1; }","duration":"1.005253384s","start":"2026-06-02T13:10:26.179687Z","end":"2026-06-02T13:10:27.18494Z","steps":["trace[1281416703] 'process raft request' (duration: 1.005072511s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:27.185118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T13:10:26.179663Z","time spent":"1.005378456s","remote":"127.0.0.1:44578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T13:10:27.185258Z","caller":"traceutil/trace.go:171","msg":"trace[589699476] transaction","detail":"{read_only:false; response_revision:25192; number_of_response:1; }","duration":"1.004522369s","start":"2026-06-02T13:10:26.180718Z","end":"2026-06-02T13:10:27.18524Z","steps":["trace[589699476] 'process raft request' (duration: 1.004186143s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:27.185324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T13:10:26.180693Z","time spent":"1.004598601s","remote":"127.0.0.1:44578","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-06-02T13:10:27.185384Z","caller":"traceutil/trace.go:171","msg":"trace[75472846] transaction","detail":"{read_only:false; response_revision:25193; number_of_response:1; }","duration":"1.001410791s","start":"2026-06-02T13:10:26.183949Z","end":"2026-06-02T13:10:27.18536Z","steps":["trace[75472846] 'process raft request' (duration: 1.001204907s)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:10:27.185515Z","caller":"traceutil/trace.go:171","msg":"trace[1217666994] transaction","detail":"{read_only:false; response_revision:25194; number_of_response:1; }","duration":"895.979394ms","start":"2026-06-02T13:10:26.289526Z","end":"2026-06-02T13:10:27.185505Z","steps":["trace[1217666994] 'process raft request' (duration: 895.76288ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:27.185523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T13:10:26.183942Z","time spent":"1.001496842s","remote":"127.0.0.1:44578","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-06-02T13:10:27.185563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T13:10:26.289507Z","time spent":"896.033645ms","remote":"127.0.0.1:44578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T13:10:27.185727Z","caller":"traceutil/trace.go:171","msg":"trace[2051321622] linearizableReadLoop","detail":"{readStateIndex:26022; appliedIndex:26015; }","duration":"997.65949ms","start":"2026-06-02T13:10:26.188059Z","end":"2026-06-02T13:10:27.185719Z","steps":["trace[2051321622] 'read index received' (duration: 534.953234ms)","trace[2051321622] 'applied index is now lower than readState.Index' (duration: 462.705466ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T13:10:27.18571Z","caller":"traceutil/trace.go:171","msg":"trace[599459389] transaction","detail":"{read_only:false; response_revision:25195; number_of_response:1; }","duration":"685.360854ms","start":"2026-06-02T13:10:26.500311Z","end":"2026-06-02T13:10:27.185672Z","steps":["trace[599459389] 'process raft request' (duration: 685.144789ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:10:27.185779Z","caller":"traceutil/trace.go:171","msg":"trace[1657176449] transaction","detail":"{read_only:false; response_revision:25196; number_of_response:1; }","duration":"499.820159ms","start":"2026-06-02T13:10:26.685951Z","end":"2026-06-02T13:10:27.185771Z","steps":["trace[1657176449] 'process raft request' (duration: 499.653305ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:27.185828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T13:10:26.685918Z","time spent":"499.88045ms","remote":"127.0.0.1:44578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":626,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T13:10:27.185875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"997.825294ms","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-06-02T13:10:27.185913Z","caller":"traceutil/trace.go:171","msg":"trace[1754869461] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25196; }","duration":"997.924386ms","start":"2026-06-02T13:10:26.187979Z","end":"2026-06-02T13:10:27.185903Z","steps":["trace[1754869461] 'agreement among raft nodes before linearized reading' (duration: 997.799943ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:27.185909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T13:10:26.50028Z","time spent":"685.525718ms","remote":"127.0.0.1:44578","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-06-02T13:10:27.185938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T13:10:26.187952Z","time spent":"997.982246ms","remote":"127.0.0.1:44578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-02T13:10:27.20839Z","caller":"traceutil/trace.go:171","msg":"trace[577629558] transaction","detail":"{read_only:false; response_revision:25197; number_of_response:1; }","duration":"321.40751ms","start":"2026-06-02T13:10:26.886954Z","end":"2026-06-02T13:10:27.208362Z","steps":["trace[577629558] 'process raft request' (duration: 321.171196ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:27.208613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T13:10:26.886921Z","time spent":"321.590333ms","remote":"127.0.0.1:44578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T13:10:27.208816Z","caller":"traceutil/trace.go:171","msg":"trace[128931554] transaction","detail":"{read_only:false; response_revision:25198; number_of_response:1; }","duration":"281.629075ms","start":"2026-06-02T13:10:26.927163Z","end":"2026-06-02T13:10:27.208792Z","steps":["trace[128931554] 'process raft request' (duration: 281.126996ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:27.209364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.822105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-06-02T13:10:27.209435Z","caller":"traceutil/trace.go:171","msg":"trace[699045820] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25198; }","duration":"199.911026ms","start":"2026-06-02T13:10:27.009504Z","end":"2026-06-02T13:10:27.209415Z","steps":["trace[699045820] 'agreement among raft nodes before linearized reading' (duration: 199.727363ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:27.209553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.204587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-02T13:10:27.209618Z","caller":"traceutil/trace.go:171","msg":"trace[576824212] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:25198; }","duration":"576.324679ms","start":"2026-06-02T13:10:26.633275Z","end":"2026-06-02T13:10:27.2096Z","steps":["trace[576824212] 'agreement among raft nodes before linearized reading' (duration: 576.220687ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:27.209439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.364243ms","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":"warn","ts":"2026-06-02T13:10:27.209693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T13:10:26.633253Z","time spent":"576.40525ms","remote":"127.0.0.1:44750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":31,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-06-02T13:10:27.209748Z","caller":"traceutil/trace.go:171","msg":"trace[1693150108] 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:25198; }","duration":"656.687581ms","start":"2026-06-02T13:10:26.553026Z","end":"2026-06-02T13:10:27.209714Z","steps":["trace[1693150108] 'agreement among raft nodes before linearized reading' (duration: 656.348514ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:27.209866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T13:10:26.55301Z","time spent":"656.828493ms","remote":"127.0.0.1:59738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"warn","ts":"2026-06-02T13:10:27.209819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.449041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.57\" ","response":"range_response_count:1 size:137"} {"level":"warn","ts":"2026-06-02T13:10:27.209629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"963.37673ms","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":"info","ts":"2026-06-02T13:10:27.210005Z","caller":"traceutil/trace.go:171","msg":"trace[893417362] range","detail":"{range_begin:/registry/masterleases/199.204.45.57; range_end:; response_count:1; response_revision:25198; }","duration":"501.839768ms","start":"2026-06-02T13:10:26.708083Z","end":"2026-06-02T13:10:27.209923Z","steps":["trace[893417362] 'agreement among raft nodes before linearized reading' (duration: 501.263426ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:10:27.210015Z","caller":"traceutil/trace.go:171","msg":"trace[846706709] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:25198; }","duration":"963.833269ms","start":"2026-06-02T13:10:26.24615Z","end":"2026-06-02T13:10:27.209983Z","steps":["trace[846706709] 'agreement among raft nodes before linearized reading' (duration: 963.33799ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:27.210055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T13:10:26.708062Z","time spent":"501.98008ms","remote":"127.0.0.1:44384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":160,"request content":"key:\"/registry/masterleases/199.204.45.57\" "} {"level":"warn","ts":"2026-06-02T13:10:27.210069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.713042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-06-02T13:10:27.210084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T13:10:26.246129Z","time spent":"963.938522ms","remote":"127.0.0.1:42192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true "} {"level":"info","ts":"2026-06-02T13:10:27.210144Z","caller":"traceutil/trace.go:171","msg":"trace[1329041235] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:25198; }","duration":"267.788213ms","start":"2026-06-02T13:10:26.942336Z","end":"2026-06-02T13:10:27.210124Z","steps":["trace[1329041235] 'agreement among raft nodes before linearized reading' (duration: 267.678031ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:27.210182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.450853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-02T13:10:27.210248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.639201ms","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-06-02T13:10:27.210343Z","caller":"traceutil/trace.go:171","msg":"trace[603724635] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25198; }","duration":"219.800104ms","start":"2026-06-02T13:10:26.990522Z","end":"2026-06-02T13:10:27.210322Z","steps":["trace[603724635] 'agreement among raft nodes before linearized reading' (duration: 219.58125ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:10:27.210355Z","caller":"traceutil/trace.go:171","msg":"trace[1674004375] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25198; }","duration":"365.659827ms","start":"2026-06-02T13:10:26.844632Z","end":"2026-06-02T13:10:27.210292Z","steps":["trace[1674004375] 'agreement among raft nodes before linearized reading' (duration: 365.403023ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:27.210439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T13:10:26.844617Z","time spent":"365.80919ms","remote":"127.0.0.1:44348","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-06-02T13:10:27.575013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.327492ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T13:10:27.575208Z","caller":"traceutil/trace.go:171","msg":"trace[1615365104] transaction","detail":"{read_only:false; response_revision:25199; number_of_response:1; }","duration":"385.084785ms","start":"2026-06-02T13:10:27.190085Z","end":"2026-06-02T13:10:27.57517Z","steps":["trace[1615365104] 'process raft request' (duration: 96.503388ms)","trace[1615365104] 'compare' (duration: 288.132378ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T13:10:27.575427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T13:10:27.190062Z","time spent":"385.244468ms","remote":"127.0.0.1:44578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T13:10:27.575454Z","caller":"traceutil/trace.go:171","msg":"trace[1598130287] linearizableReadLoop","detail":"{readStateIndex:26025; appliedIndex:26024; }","duration":"366.815329ms","start":"2026-06-02T13:10:27.208608Z","end":"2026-06-02T13:10:27.575423Z","steps":["trace[1598130287] 'read index received' (duration: 77.965287ms)","trace[1598130287] 'applied index is now lower than readState.Index' (duration: 288.847311ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T13:10:27.575596Z","caller":"traceutil/trace.go:171","msg":"trace[863118307] transaction","detail":"{read_only:false; response_revision:25200; number_of_response:1; }","duration":"361.432556ms","start":"2026-06-02T13:10:27.214143Z","end":"2026-06-02T13:10:27.575576Z","steps":["trace[863118307] 'process raft request' (duration: 361.252643ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:27.575709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.137008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T13:10:27.57575Z","caller":"traceutil/trace.go:171","msg":"trace[1389964100] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25200; }","duration":"364.23901ms","start":"2026-06-02T13:10:27.211501Z","end":"2026-06-02T13:10:27.57574Z","steps":["trace[1389964100] 'agreement among raft nodes before linearized reading' (duration: 364.109358ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:27.575723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T13:10:27.214127Z","time spent":"361.552299ms","remote":"127.0.0.1:44578","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-06-02T13:10:27.575788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T13:10:27.211474Z","time spent":"364.305831ms","remote":"127.0.0.1:44364","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-06-02T13:10:27.575997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.671052ms","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-06-02T13:10:27.57606Z","caller":"traceutil/trace.go:171","msg":"trace[1957683684] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:25200; }","duration":"360.742023ms","start":"2026-06-02T13:10:27.215301Z","end":"2026-06-02T13:10:27.576043Z","steps":["trace[1957683684] 'agreement among raft nodes before linearized reading' (duration: 360.618261ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:27.576032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.623244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-02T13:10:27.576096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T13:10:27.215269Z","time spent":"360.818825ms","remote":"127.0.0.1:44510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"warn","ts":"2026-06-02T13:10:27.5761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.062938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-06-02T13:10:27.576137Z","caller":"traceutil/trace.go:171","msg":"trace[903502756] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25200; }","duration":"362.137609ms","start":"2026-06-02T13:10:27.21399Z","end":"2026-06-02T13:10:27.576127Z","steps":["trace[903502756] 'agreement among raft nodes before linearized reading' (duration: 362.000187ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:27.576108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.102169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-06-02T13:10:27.576173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T13:10:27.213967Z","time spent":"362.198821ms","remote":"127.0.0.1:44496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-06-02T13:10:27.576199Z","caller":"traceutil/trace.go:171","msg":"trace[482601423] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:25200; }","duration":"287.243091ms","start":"2026-06-02T13:10:27.288936Z","end":"2026-06-02T13:10:27.576179Z","steps":["trace[482601423] 'agreement among raft nodes before linearized reading' (duration: 286.954016ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:10:27.576177Z","caller":"traceutil/trace.go:171","msg":"trace[965738622] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:25200; }","duration":"367.802257ms","start":"2026-06-02T13:10:27.20831Z","end":"2026-06-02T13:10:27.576112Z","steps":["trace[965738622] 'agreement among raft nodes before linearized reading' (duration: 367.274397ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:27.576292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T13:10:27.208282Z","time spent":"367.989851ms","remote":"127.0.0.1:59360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":29,"request content":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true "} {"level":"warn","ts":"2026-06-02T13:10:27.575602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T13:10:27.211397Z","time spent":"364.188338ms","remote":"127.0.0.1:44384","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-06-02T13:10:27.894444Z","caller":"traceutil/trace.go:171","msg":"trace[2135000779] transaction","detail":"{read_only:false; response_revision:25201; number_of_response:1; }","duration":"316.72555ms","start":"2026-06-02T13:10:27.577667Z","end":"2026-06-02T13:10:27.894392Z","steps":["trace[2135000779] 'process raft request' (duration: 273.835747ms)","trace[2135000779] 'compare' (duration: 42.73248ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T13:10:27.894717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T13:10:27.577639Z","time spent":"316.934055ms","remote":"127.0.0.1:44384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":119,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T13:10:27.894709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.843297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T13:10:27.894814Z","caller":"traceutil/trace.go:171","msg":"trace[2070806458] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25201; }","duration":"312.988199ms","start":"2026-06-02T13:10:27.5818Z","end":"2026-06-02T13:10:27.894788Z","steps":["trace[2070806458] 'agreement among raft nodes before linearized reading' (duration: 312.732545ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:27.894871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T13:10:27.581786Z","time spent":"313.074081ms","remote":"127.0.0.1:44586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" "} {"level":"info","ts":"2026-06-02T13:10:27.895003Z","caller":"traceutil/trace.go:171","msg":"trace[1017347519] linearizableReadLoop","detail":"{readStateIndex:26028; appliedIndex:26027; }","duration":"312.552632ms","start":"2026-06-02T13:10:27.581827Z","end":"2026-06-02T13:10:27.894379Z","steps":["trace[1017347519] 'read index received' (duration: 269.635629ms)","trace[1017347519] 'applied index is now lower than readState.Index' (duration: 42.915643ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T13:10:27.895229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.340767ms","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-06-02T13:10:27.895276Z","caller":"traceutil/trace.go:171","msg":"trace[1723003898] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25201; }","duration":"255.424048ms","start":"2026-06-02T13:10:27.639838Z","end":"2026-06-02T13:10:27.895262Z","steps":["trace[1723003898] 'agreement among raft nodes before linearized reading' (duration: 255.325166ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:10:33.456897Z","caller":"traceutil/trace.go:171","msg":"trace[2140206829] transaction","detail":"{read_only:false; response_revision:25297; number_of_response:1; }","duration":"250.930784ms","start":"2026-06-02T13:10:33.20594Z","end":"2026-06-02T13:10:33.456871Z","steps":["trace[2140206829] 'process raft request' (duration: 250.650838ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:10:33.456897Z","caller":"traceutil/trace.go:171","msg":"trace[801199282] transaction","detail":"{read_only:false; response_revision:25298; number_of_response:1; }","duration":"250.21197ms","start":"2026-06-02T13:10:33.206654Z","end":"2026-06-02T13:10:33.456866Z","steps":["trace[801199282] 'process raft request' (duration: 250.115657ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:10:33.457052Z","caller":"traceutil/trace.go:171","msg":"trace[1691742536] transaction","detail":"{read_only:false; response_revision:25299; number_of_response:1; }","duration":"249.518368ms","start":"2026-06-02T13:10:33.207499Z","end":"2026-06-02T13:10:33.457018Z","steps":["trace[1691742536] 'process raft request' (duration: 249.326844ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:10:33.514155Z","caller":"traceutil/trace.go:171","msg":"trace[1427507979] transaction","detail":"{read_only:false; response_revision:25300; number_of_response:1; }","duration":"288.642408ms","start":"2026-06-02T13:10:33.225485Z","end":"2026-06-02T13:10:33.514127Z","steps":["trace[1427507979] 'process raft request' (duration: 288.427104ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:10:33.51433Z","caller":"traceutil/trace.go:171","msg":"trace[1184001087] linearizableReadLoop","detail":"{readStateIndex:26130; appliedIndex:26125; }","duration":"263.836709ms","start":"2026-06-02T13:10:33.250461Z","end":"2026-06-02T13:10:33.514298Z","steps":["trace[1184001087] 'read index received' (duration: 206.198866ms)","trace[1184001087] 'applied index is now lower than readState.Index' (duration: 57.636003ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T13:10:33.514336Z","caller":"traceutil/trace.go:171","msg":"trace[1400738158] transaction","detail":"{read_only:false; response_revision:25301; number_of_response:1; }","duration":"285.678722ms","start":"2026-06-02T13:10:33.22863Z","end":"2026-06-02T13:10:33.514309Z","steps":["trace[1400738158] 'process raft request' (duration: 285.446407ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:33.514538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.037082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-02T13:10:33.514648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.192966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e562151b-c1cc-4e5f-b3fa-60784f440e11\" ","response":"range_response_count:1 size:3643"} {"level":"info","ts":"2026-06-02T13:10:33.514641Z","caller":"traceutil/trace.go:171","msg":"trace[2005084678] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:25301; }","duration":"264.198836ms","start":"2026-06-02T13:10:33.250421Z","end":"2026-06-02T13:10:33.514619Z","steps":["trace[2005084678] 'agreement among raft nodes before linearized reading' (duration: 264.008802ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:10:33.514685Z","caller":"traceutil/trace.go:171","msg":"trace[787673262] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e562151b-c1cc-4e5f-b3fa-60784f440e11; range_end:; response_count:1; response_revision:25301; }","duration":"254.328289ms","start":"2026-06-02T13:10:33.260345Z","end":"2026-06-02T13:10:33.514673Z","steps":["trace[787673262] 'agreement among raft nodes before linearized reading' (duration: 254.099494ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:10:33.850669Z","caller":"traceutil/trace.go:171","msg":"trace[1123008717] transaction","detail":"{read_only:false; response_revision:25302; number_of_response:1; }","duration":"326.915213ms","start":"2026-06-02T13:10:33.52372Z","end":"2026-06-02T13:10:33.850635Z","steps":["trace[1123008717] 'process raft request' (duration: 326.586917ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:10:33.850487Z","caller":"traceutil/trace.go:171","msg":"trace[583682911] linearizableReadLoop","detail":"{readStateIndex:26131; appliedIndex:26130; }","duration":"249.186411ms","start":"2026-06-02T13:10:33.60128Z","end":"2026-06-02T13:10:33.850466Z","steps":["trace[583682911] 'read index received' (duration: 248.908565ms)","trace[583682911] 'applied index is now lower than readState.Index' (duration: 276.826µs)"],"step_count":2} {"level":"warn","ts":"2026-06-02T13:10:33.850991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.651691ms","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-06-02T13:10:33.851057Z","caller":"traceutil/trace.go:171","msg":"trace[1094144765] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25302; }","duration":"248.734863ms","start":"2026-06-02T13:10:33.602305Z","end":"2026-06-02T13:10:33.85104Z","steps":["trace[1094144765] 'agreement among raft nodes before linearized reading' (duration: 248.551879ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:33.851046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.745251ms","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-06-02T13:10:33.851148Z","caller":"traceutil/trace.go:171","msg":"trace[808960893] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25302; }","duration":"249.859253ms","start":"2026-06-02T13:10:33.601266Z","end":"2026-06-02T13:10:33.851126Z","steps":["trace[808960893] 'agreement among raft nodes before linearized reading' (duration: 249.467776ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:33.851601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T13:10:33.523701Z","time spent":"327.047086ms","remote":"127.0.0.1:44496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3745,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T13:10:33.990356Z","caller":"traceutil/trace.go:171","msg":"trace[524598319] transaction","detail":"{read_only:false; response_revision:25304; number_of_response:1; }","duration":"133.226285ms","start":"2026-06-02T13:10:33.857097Z","end":"2026-06-02T13:10:33.990323Z","steps":["trace[524598319] 'process raft request' (duration: 132.942108ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:10:35.671223Z","caller":"traceutil/trace.go:171","msg":"trace[1888226843] transaction","detail":"{read_only:false; response_revision:25314; number_of_response:1; }","duration":"208.759344ms","start":"2026-06-02T13:10:35.462439Z","end":"2026-06-02T13:10:35.671198Z","steps":["trace[1888226843] 'process raft request' (duration: 208.683653ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:10:35.671225Z","caller":"traceutil/trace.go:171","msg":"trace[846356391] transaction","detail":"{read_only:false; response_revision:25313; number_of_response:1; }","duration":"208.896737ms","start":"2026-06-02T13:10:35.462295Z","end":"2026-06-02T13:10:35.671192Z","steps":["trace[846356391] 'process raft request' (duration: 164.840933ms)","trace[846356391] 'compare' (duration: 43.81806ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T13:10:35.697703Z","caller":"traceutil/trace.go:171","msg":"trace[314307407] transaction","detail":"{read_only:false; response_revision:25315; number_of_response:1; }","duration":"178.328788ms","start":"2026-06-02T13:10:35.519287Z","end":"2026-06-02T13:10:35.697616Z","steps":["trace[314307407] 'process raft request' (duration: 178.126644ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:10:35.697823Z","caller":"traceutil/trace.go:171","msg":"trace[1356409252] transaction","detail":"{read_only:false; response_revision:25316; number_of_response:1; }","duration":"176.29261ms","start":"2026-06-02T13:10:35.521503Z","end":"2026-06-02T13:10:35.697795Z","steps":["trace[1356409252] 'process raft request' (duration: 176.058016ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:40.27933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.397946ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T13:10:40.279757Z","caller":"traceutil/trace.go:171","msg":"trace[1986778922] transaction","detail":"{read_only:false; response_revision:25371; number_of_response:1; }","duration":"257.699211ms","start":"2026-06-02T13:10:40.021764Z","end":"2026-06-02T13:10:40.279463Z","steps":["trace[1986778922] 'process raft request' (duration: 38.03135ms)","trace[1986778922] 'compare' (duration: 219.113231ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T13:10:40.279945Z","caller":"traceutil/trace.go:171","msg":"trace[1601901248] transaction","detail":"{read_only:false; response_revision:25372; number_of_response:1; }","duration":"256.375997ms","start":"2026-06-02T13:10:40.023542Z","end":"2026-06-02T13:10:40.279918Z","steps":["trace[1601901248] 'process raft request' (duration: 256.209694ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:10:40.280069Z","caller":"traceutil/trace.go:171","msg":"trace[1880116564] linearizableReadLoop","detail":"{readStateIndex:26204; appliedIndex:26202; }","duration":"208.073422ms","start":"2026-06-02T13:10:40.071961Z","end":"2026-06-02T13:10:40.280035Z","steps":["trace[1880116564] 'read index received' (duration: 207.738076ms)","trace[1880116564] 'applied index is now lower than readState.Index' (duration: 334.286µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T13:10:40.280256Z","caller":"traceutil/trace.go:171","msg":"trace[2001431703] transaction","detail":"{read_only:false; response_revision:25373; number_of_response:1; }","duration":"255.599372ms","start":"2026-06-02T13:10:40.024629Z","end":"2026-06-02T13:10:40.280229Z","steps":["trace[2001431703] 'process raft request' (duration: 255.243686ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:40.280282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.319537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-02T13:10:40.280355Z","caller":"traceutil/trace.go:171","msg":"trace[1730564774] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:25373; }","duration":"208.442989ms","start":"2026-06-02T13:10:40.07189Z","end":"2026-06-02T13:10:40.280333Z","steps":["trace[1730564774] 'agreement among raft nodes before linearized reading' (duration: 208.331237ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:10:40.340493Z","caller":"traceutil/trace.go:171","msg":"trace[1979697822] transaction","detail":"{read_only:false; response_revision:25374; number_of_response:1; }","duration":"174.414414ms","start":"2026-06-02T13:10:40.166048Z","end":"2026-06-02T13:10:40.340463Z","steps":["trace[1979697822] 'process raft request' (duration: 174.203181ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:40.340579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.956122ms","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-06-02T13:10:40.34066Z","caller":"traceutil/trace.go:171","msg":"trace[651054919] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25374; }","duration":"159.089864ms","start":"2026-06-02T13:10:40.181549Z","end":"2026-06-02T13:10:40.340639Z","steps":["trace[651054919] 'agreement among raft nodes before linearized reading' (duration: 158.855419ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:40.586575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.989667ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T13:10:40.586837Z","caller":"traceutil/trace.go:171","msg":"trace[933427190] transaction","detail":"{read_only:false; response_revision:25375; number_of_response:1; }","duration":"241.75025ms","start":"2026-06-02T13:10:40.345046Z","end":"2026-06-02T13:10:40.586796Z","steps":["trace[933427190] 'process raft request' (duration: 97.410856ms)","trace[933427190] 'compare' (duration: 143.789463ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T13:10:45.106913Z","caller":"traceutil/trace.go:171","msg":"trace[1505802212] transaction","detail":"{read_only:false; response_revision:25409; number_of_response:1; }","duration":"198.787876ms","start":"2026-06-02T13:10:44.908091Z","end":"2026-06-02T13:10:45.106879Z","steps":["trace[1505802212] 'process raft request' (duration: 134.500728ms)","trace[1505802212] 'compare' (duration: 64.165255ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T13:10:50.442893Z","caller":"traceutil/trace.go:171","msg":"trace[1609983507] transaction","detail":"{read_only:false; response_revision:25452; number_of_response:1; }","duration":"113.124753ms","start":"2026-06-02T13:10:50.329739Z","end":"2026-06-02T13:10:50.442864Z","steps":["trace[1609983507] 'process raft request' (duration: 112.95963ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:10:50.465375Z","caller":"traceutil/trace.go:171","msg":"trace[1357178538] transaction","detail":"{read_only:false; response_revision:25453; number_of_response:1; }","duration":"135.238451ms","start":"2026-06-02T13:10:50.330113Z","end":"2026-06-02T13:10:50.465351Z","steps":["trace[1357178538] 'process raft request' (duration: 134.696721ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:10:50.465728Z","caller":"traceutil/trace.go:171","msg":"trace[1157453901] transaction","detail":"{read_only:false; response_revision:25454; number_of_response:1; }","duration":"134.217222ms","start":"2026-06-02T13:10:50.331494Z","end":"2026-06-02T13:10:50.465711Z","steps":["trace[1157453901] 'process raft request' (duration: 133.436507ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:10:54.067295Z","caller":"traceutil/trace.go:171","msg":"trace[387393472] transaction","detail":"{read_only:false; response_revision:25478; number_of_response:1; }","duration":"116.251922ms","start":"2026-06-02T13:10:53.951018Z","end":"2026-06-02T13:10:54.067269Z","steps":["trace[387393472] 'process raft request' (duration: 116.091099ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:10:56.65641Z","caller":"traceutil/trace.go:171","msg":"trace[419222254] linearizableReadLoop","detail":"{readStateIndex:26328; appliedIndex:26326; }","duration":"169.860557ms","start":"2026-06-02T13:10:56.486524Z","end":"2026-06-02T13:10:56.656384Z","steps":["trace[419222254] 'read index received' (duration: 46.946818ms)","trace[419222254] 'applied index is now lower than readState.Index' (duration: 122.912419ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T13:10:56.656536Z","caller":"traceutil/trace.go:171","msg":"trace[1023849978] transaction","detail":"{read_only:false; response_revision:25494; number_of_response:1; }","duration":"170.819896ms","start":"2026-06-02T13:10:56.485668Z","end":"2026-06-02T13:10:56.656488Z","steps":["trace[1023849978] 'process raft request' (duration: 170.530141ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:56.656664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.123263ms","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-06-02T13:10:56.656712Z","caller":"traceutil/trace.go:171","msg":"trace[2146703342] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25494; }","duration":"170.246485ms","start":"2026-06-02T13:10:56.486453Z","end":"2026-06-02T13:10:56.656699Z","steps":["trace[2146703342] 'agreement among raft nodes before linearized reading' (duration: 170.063292ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:10:56.65691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.091411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-02T13:10:56.657Z","caller":"traceutil/trace.go:171","msg":"trace[2065825203] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25494; }","duration":"170.208774ms","start":"2026-06-02T13:10:56.486769Z","end":"2026-06-02T13:10:56.656978Z","steps":["trace[2065825203] 'agreement among raft nodes before linearized reading' (duration: 169.97845ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:10:58.338453Z","caller":"traceutil/trace.go:171","msg":"trace[1534996137] transaction","detail":"{read_only:false; response_revision:25509; number_of_response:1; }","duration":"209.58489ms","start":"2026-06-02T13:10:58.128836Z","end":"2026-06-02T13:10:58.338421Z","steps":["trace[1534996137] 'process raft request' (duration: 209.396416ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:11:02.922208Z","caller":"traceutil/trace.go:171","msg":"trace[886111602] transaction","detail":"{read_only:false; response_revision:25558; number_of_response:1; }","duration":"111.167685ms","start":"2026-06-02T13:11:02.811019Z","end":"2026-06-02T13:11:02.922186Z","steps":["trace[886111602] 'process raft request' (duration: 111.050023ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:11:23.579228Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22278} {"level":"info","ts":"2026-06-02T13:11:23.694468Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22278,"took":"110.941822ms","hash":4039289235,"current-db-size-bytes":57397248,"current-db-size":"57 MB","current-db-size-in-use-bytes":44290048,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-06-02T13:11:23.694526Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4039289235,"revision":22278,"compact-revision":19957} {"level":"info","ts":"2026-06-02T13:11:27.00419Z","caller":"traceutil/trace.go:171","msg":"trace[943709105] linearizableReadLoop","detail":"{readStateIndex:26584; appliedIndex:26583; }","duration":"102.673375ms","start":"2026-06-02T13:11:26.901492Z","end":"2026-06-02T13:11:27.004166Z","steps":["trace[943709105] 'read index received' (duration: 102.519162ms)","trace[943709105] 'applied index is now lower than readState.Index' (duration: 152.933µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T13:11:27.004249Z","caller":"traceutil/trace.go:171","msg":"trace[1122392198] transaction","detail":"{read_only:false; response_revision:25742; number_of_response:1; }","duration":"129.395791ms","start":"2026-06-02T13:11:26.874819Z","end":"2026-06-02T13:11:27.004215Z","steps":["trace[1122392198] 'process raft request' (duration: 129.185317ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:11:27.00436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.847868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/roles/\" range_end:\"/registry/openstack.k-orc.cloud/roles0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T13:11:27.004404Z","caller":"traceutil/trace.go:171","msg":"trace[2122016562] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/roles/; range_end:/registry/openstack.k-orc.cloud/roles0; response_count:0; response_revision:25742; }","duration":"102.915129ms","start":"2026-06-02T13:11:26.901478Z","end":"2026-06-02T13:11:27.004393Z","steps":["trace[2122016562] 'agreement among raft nodes before linearized reading' (duration: 102.822937ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:11:27.194571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.724882ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T13:11:27.194847Z","caller":"traceutil/trace.go:171","msg":"trace[1139412718] linearizableReadLoop","detail":"{readStateIndex:26585; appliedIndex:26584; }","duration":"171.993008ms","start":"2026-06-02T13:11:27.022815Z","end":"2026-06-02T13:11:27.194808Z","steps":["trace[1139412718] 'read index received' (duration: 45.845899ms)","trace[1139412718] 'applied index is now lower than readState.Index' (duration: 126.145439ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T13:11:27.195003Z","caller":"traceutil/trace.go:171","msg":"trace[899706851] transaction","detail":"{read_only:false; response_revision:25743; number_of_response:1; }","duration":"186.084875ms","start":"2026-06-02T13:11:27.008869Z","end":"2026-06-02T13:11:27.194954Z","steps":["trace[899706851] 'process raft request' (duration: 59.893734ms)","trace[899706851] 'compare' (duration: 125.625841ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T13:11:27.195157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.337584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-02T13:11:27.195198Z","caller":"traceutil/trace.go:171","msg":"trace[81726453] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:25744; }","duration":"172.424196ms","start":"2026-06-02T13:11:27.022764Z","end":"2026-06-02T13:11:27.195188Z","steps":["trace[81726453] 'agreement among raft nodes before linearized reading' (duration: 172.328065ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:11:27.195276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.745567ms","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-06-02T13:11:27.195321Z","caller":"traceutil/trace.go:171","msg":"trace[678218856] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25744; }","duration":"158.814809ms","start":"2026-06-02T13:11:27.036495Z","end":"2026-06-02T13:11:27.19531Z","steps":["trace[678218856] 'agreement among raft nodes before linearized reading' (duration: 158.685786ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:11:27.195581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.950361ms","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-06-02T13:11:27.195622Z","caller":"traceutil/trace.go:171","msg":"trace[1386092270] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25744; }","duration":"159.027913ms","start":"2026-06-02T13:11:27.036583Z","end":"2026-06-02T13:11:27.195611Z","steps":["trace[1386092270] 'agreement among raft nodes before linearized reading' (duration: 158.928341ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:11:27.195595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.534703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T13:11:27.195691Z","caller":"traceutil/trace.go:171","msg":"trace[226171148] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:25744; }","duration":"149.698206ms","start":"2026-06-02T13:11:27.045966Z","end":"2026-06-02T13:11:27.195664Z","steps":["trace[226171148] 'agreement among raft nodes before linearized reading' (duration: 149.543173ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:11:27.38365Z","caller":"traceutil/trace.go:171","msg":"trace[1837151329] transaction","detail":"{read_only:false; response_revision:25745; number_of_response:1; }","duration":"184.300832ms","start":"2026-06-02T13:11:27.199322Z","end":"2026-06-02T13:11:27.383623Z","steps":["trace[1837151329] 'process raft request' (duration: 174.981435ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:11:27.421258Z","caller":"traceutil/trace.go:171","msg":"trace[889118459] transaction","detail":"{read_only:false; response_revision:25746; number_of_response:1; }","duration":"220.174711ms","start":"2026-06-02T13:11:27.201051Z","end":"2026-06-02T13:11:27.421226Z","steps":["trace[889118459] 'process raft request' (duration: 219.899576ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:11:32.385947Z","caller":"traceutil/trace.go:171","msg":"trace[1027240582] transaction","detail":"{read_only:false; response_revision:25785; number_of_response:1; }","duration":"112.693925ms","start":"2026-06-02T13:11:32.27322Z","end":"2026-06-02T13:11:32.385914Z","steps":["trace[1027240582] 'process raft request' (duration: 112.356488ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:11:32.536421Z","caller":"traceutil/trace.go:171","msg":"trace[128258497] transaction","detail":"{read_only:false; response_revision:25786; number_of_response:1; }","duration":"109.050896ms","start":"2026-06-02T13:11:32.427337Z","end":"2026-06-02T13:11:32.536388Z","steps":["trace[128258497] 'process raft request' (duration: 60.690705ms)","trace[128258497] 'compare' (duration: 48.027125ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T13:11:32.794572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.51215ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T13:11:32.794676Z","caller":"traceutil/trace.go:171","msg":"trace[1747201079] linearizableReadLoop","detail":"{readStateIndex:26630; appliedIndex:26629; }","duration":"258.331422ms","start":"2026-06-02T13:11:32.536333Z","end":"2026-06-02T13:11:32.794664Z","steps":["trace[1747201079] 'read index received' (duration: 116.66144ms)","trace[1747201079] 'applied index is now lower than readState.Index' (duration: 141.668942ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T13:11:32.794772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.850229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T13:11:32.794759Z","caller":"traceutil/trace.go:171","msg":"trace[1982217480] transaction","detail":"{read_only:false; response_revision:25787; number_of_response:1; }","duration":"275.271432ms","start":"2026-06-02T13:11:32.519454Z","end":"2026-06-02T13:11:32.794725Z","steps":["trace[1982217480] 'process raft request' (duration: 133.548908ms)","trace[1982217480] 'compare' (duration: 141.169832ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T13:11:32.794811Z","caller":"traceutil/trace.go:171","msg":"trace[508192785] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:25787; }","duration":"269.906641ms","start":"2026-06-02T13:11:32.524884Z","end":"2026-06-02T13:11:32.794791Z","steps":["trace[508192785] 'agreement among raft nodes before linearized reading' (duration: 269.84128ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:11:33.003331Z","caller":"traceutil/trace.go:171","msg":"trace[769057380] linearizableReadLoop","detail":"{readStateIndex:26631; appliedIndex:26630; }","duration":"156.806508ms","start":"2026-06-02T13:11:32.846492Z","end":"2026-06-02T13:11:33.003299Z","steps":["trace[769057380] 'read index received' (duration: 81.754012ms)","trace[769057380] 'applied index is now lower than readState.Index' (duration: 75.051256ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T13:11:33.003518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.011331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T13:11:33.003563Z","caller":"traceutil/trace.go:171","msg":"trace[277037822] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25788; }","duration":"157.105652ms","start":"2026-06-02T13:11:32.846446Z","end":"2026-06-02T13:11:33.003551Z","steps":["trace[277037822] 'agreement among raft nodes before linearized reading' (duration: 156.97505ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:11:33.00391Z","caller":"traceutil/trace.go:171","msg":"trace[418656736] transaction","detail":"{read_only:false; response_revision:25788; number_of_response:1; }","duration":"198.403711ms","start":"2026-06-02T13:11:32.805491Z","end":"2026-06-02T13:11:33.003895Z","steps":["trace[418656736] 'process raft request' (duration: 122.724804ms)","trace[418656736] 'compare' (duration: 74.163349ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T13:11:33.375419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.989345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T13:11:33.375497Z","caller":"traceutil/trace.go:171","msg":"trace[1325507895] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:25789; }","duration":"171.091186ms","start":"2026-06-02T13:11:33.204389Z","end":"2026-06-02T13:11:33.37548Z","steps":["trace[1325507895] 'count revisions from in-memory index tree' (duration: 170.893393ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:11:33.376084Z","caller":"traceutil/trace.go:171","msg":"trace[1676402208] linearizableReadLoop","detail":"{readStateIndex:26633; appliedIndex:26632; }","duration":"158.769145ms","start":"2026-06-02T13:11:33.217303Z","end":"2026-06-02T13:11:33.376072Z","steps":["trace[1676402208] 'read index received' (duration: 99.434977ms)","trace[1676402208] 'applied index is now lower than readState.Index' (duration: 59.332748ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T13:11:33.376324Z","caller":"traceutil/trace.go:171","msg":"trace[397245659] transaction","detail":"{read_only:false; response_revision:25790; number_of_response:1; }","duration":"160.983346ms","start":"2026-06-02T13:11:33.215292Z","end":"2026-06-02T13:11:33.376276Z","steps":["trace[397245659] 'process raft request' (duration: 101.337791ms)","trace[397245659] 'compare' (duration: 58.663546ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T13:11:33.376885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.58702ms","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-06-02T13:11:33.376923Z","caller":"traceutil/trace.go:171","msg":"trace[409639432] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25790; }","duration":"159.666971ms","start":"2026-06-02T13:11:33.217245Z","end":"2026-06-02T13:11:33.376912Z","steps":["trace[409639432] 'agreement among raft nodes before linearized reading' (duration: 158.866177ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:11:33.68364Z","caller":"traceutil/trace.go:171","msg":"trace[730008630] transaction","detail":"{read_only:false; response_revision:25792; number_of_response:1; }","duration":"202.906457ms","start":"2026-06-02T13:11:33.480697Z","end":"2026-06-02T13:11:33.683604Z","steps":["trace[730008630] 'process raft request' (duration: 202.700122ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:11:33.940713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.564091ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T13:11:33.940898Z","caller":"traceutil/trace.go:171","msg":"trace[1760116626] transaction","detail":"{read_only:false; response_revision:25794; number_of_response:1; }","duration":"234.981011ms","start":"2026-06-02T13:11:33.7059Z","end":"2026-06-02T13:11:33.940882Z","steps":["trace[1760116626] 'process raft request' (duration: 234.91128ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:11:33.940961Z","caller":"traceutil/trace.go:171","msg":"trace[1563148571] transaction","detail":"{read_only:false; response_revision:25793; number_of_response:1; }","duration":"456.09612ms","start":"2026-06-02T13:11:33.484814Z","end":"2026-06-02T13:11:33.94091Z","steps":["trace[1563148571] 'process raft request' (duration: 228.248394ms)","trace[1563148571] 'compare' (duration: 227.457359ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T13:11:33.941108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T13:11:33.484793Z","time spent":"456.241723ms","remote":"127.0.0.1:44578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T13:11:33.971488Z","caller":"traceutil/trace.go:171","msg":"trace[1691832051] transaction","detail":"{read_only:false; response_revision:25795; number_of_response:1; }","duration":"252.640544ms","start":"2026-06-02T13:11:33.718811Z","end":"2026-06-02T13:11:33.971452Z","steps":["trace[1691832051] 'process raft request' (duration: 252.487201ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:11:34.15579Z","caller":"traceutil/trace.go:171","msg":"trace[1349348685] transaction","detail":"{read_only:false; response_revision:25796; number_of_response:1; }","duration":"270.597104ms","start":"2026-06-02T13:11:33.885165Z","end":"2026-06-02T13:11:34.155762Z","steps":["trace[1349348685] 'process raft request' (duration: 241.379673ms)","trace[1349348685] 'compare' (duration: 28.981837ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T13:11:34.156532Z","caller":"traceutil/trace.go:171","msg":"trace[504646557] transaction","detail":"{read_only:false; response_revision:25798; number_of_response:1; }","duration":"191.940879ms","start":"2026-06-02T13:11:33.964551Z","end":"2026-06-02T13:11:34.156492Z","steps":["trace[504646557] 'process raft request' (duration: 191.451031ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:11:34.15614Z","caller":"traceutil/trace.go:171","msg":"trace[1138590391] transaction","detail":"{read_only:false; response_revision:25797; number_of_response:1; }","duration":"269.108415ms","start":"2026-06-02T13:11:33.887003Z","end":"2026-06-02T13:11:34.156112Z","steps":["trace[1138590391] 'process raft request' (duration: 268.677777ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:11:34.203218Z","caller":"traceutil/trace.go:171","msg":"trace[571291542] transaction","detail":"{read_only:false; response_revision:25799; number_of_response:1; }","duration":"229.270754ms","start":"2026-06-02T13:11:33.973928Z","end":"2026-06-02T13:11:34.203198Z","steps":["trace[571291542] 'process raft request' (duration: 229.164222ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T13:11:34.453662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.745752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-06-02T13:11:34.453863Z","caller":"traceutil/trace.go:171","msg":"trace[1134855380] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25799; }","duration":"125.004688ms","start":"2026-06-02T13:11:34.328835Z","end":"2026-06-02T13:11:34.45384Z","steps":["trace[1134855380] 'range keys from in-memory index tree' (duration: 124.526639ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T13:11:35.204257Z","caller":"traceutil/trace.go:171","msg":"trace[1368462570] transaction","detail":"{read_only:false; response_revision:25802; number_of_response:1; }","duration":"118.399393ms","start":"2026-06-02T13:11:35.085831Z","end":"2026-06-02T13:11:35.20423Z","steps":["trace[1368462570] 'process raft request' (duration: 44.022209ms)","trace[1368462570] 'compare' (duration: 73.869654ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T13:11:51.440477Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000068ff.wal"} {"level":"info","ts":"2026-06-02T13:16:23.585319Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25716} {"level":"info","ts":"2026-06-02T13:16:23.746759Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25716,"took":"156.321723ms","hash":1958950724,"current-db-size-bytes":59928576,"current-db-size":"60 MB","current-db-size-in-use-bytes":36343808,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-06-02T13:16:23.746821Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1958950724,"revision":25716,"compact-revision":22278} {"level":"info","ts":"2026-06-02T13:17:27.487955Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b6478f0ac5aeb8e1","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-02T13:17:27.492183Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-06-02T13:17:27.492295Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-06-02T13:21:23.592898Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28608} {"level":"info","ts":"2026-06-02T13:21:23.730093Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28608,"took":"133.797242ms","hash":2427598528,"current-db-size-bytes":59928576,"current-db-size":"60 MB","current-db-size-in-use-bytes":31686656,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-02T13:21:23.730182Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2427598528,"revision":28608,"compact-revision":25716} {"level":"info","ts":"2026-06-02T13:26:23.602555Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30833} {"level":"info","ts":"2026-06-02T13:26:23.715932Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30833,"took":"109.325124ms","hash":1893887592,"current-db-size-bytes":59928576,"current-db-size":"60 MB","current-db-size-in-use-bytes":33067008,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-06-02T13:26:23.715993Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1893887592,"revision":30833,"compact-revision":28608} {"level":"info","ts":"2026-06-02T13:31:23.608811Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33099} {"level":"info","ts":"2026-06-02T13:31:23.764069Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33099,"took":"152.084105ms","hash":389515480,"current-db-size-bytes":59928576,"current-db-size":"60 MB","current-db-size-in-use-bytes":31821824,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-02T13:31:23.76414Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":389515480,"revision":33099,"compact-revision":30833} {"level":"info","ts":"2026-06-02T13:36:23.614737Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35395} {"level":"info","ts":"2026-06-02T13:36:23.756795Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35395,"took":"135.461732ms","hash":2904623966,"current-db-size-bytes":59928576,"current-db-size":"60 MB","current-db-size-in-use-bytes":31539200,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-02T13:36:23.756874Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2904623966,"revision":35395,"compact-revision":33099} {"level":"info","ts":"2026-06-02T13:38:56.028787Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b6478f0ac5aeb8e1","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-02T13:38:56.034029Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-06-02T13:38:56.034145Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-06-02T13:41:23.620823Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37627} {"level":"info","ts":"2026-06-02T13:41:23.746635Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37627,"took":"122.629332ms","hash":3542445949,"current-db-size-bytes":60772352,"current-db-size":"61 MB","current-db-size-in-use-bytes":32354304,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-02T13:41:23.746704Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3542445949,"revision":37627,"compact-revision":35395}