{"level":"warn","ts":"2026-05-24T10:03:57.018459Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-24T10:03:57.018577Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.227: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.227:2380","--initial-cluster=instance=https://199.204.45.227:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.227:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.227: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-05-24T10:03:57.018676Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-24T10:03:57.018697Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.227:2380"]} {"level":"info","ts":"2026-05-24T10:03:57.018746Z","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-05-24T10:03:57.020699Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"]} {"level":"info","ts":"2026-05-24T10:03:57.02083Z","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.227:2380"],"listen-peer-urls":["https://199.204.45.227:2380"],"advertise-client-urls":["https://199.204.45.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.227: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-05-24T10:03:57.025469Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.31761ms"} {"level":"info","ts":"2026-05-24T10:03:57.034922Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"572db18442eba1c2","cluster-id":"fb1028a037bf3cd6"} {"level":"info","ts":"2026-05-24T10:03:57.034999Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=()"} {"level":"info","ts":"2026-05-24T10:03:57.03502Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became follower at term 0"} {"level":"info","ts":"2026-05-24T10:03:57.035029Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 572db18442eba1c2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-24T10:03:57.035035Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became follower at term 1"} {"level":"info","ts":"2026-05-24T10:03:57.035066Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=(6281872236868248002)"} {"level":"warn","ts":"2026-05-24T10:03:57.041893Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-24T10:03:57.04441Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-24T10:03:57.04595Z","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-05-24T10:03:57.047998Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"572db18442eba1c2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-24T10:03:57.048262Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"572db18442eba1c2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-24T10:03:57.04838Z","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-05-24T10:03:57.048471Z","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-05-24T10:03:57.048489Z","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-05-24T10:03:57.04948Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=(6281872236868248002)"} {"level":"info","ts":"2026-05-24T10:03:57.049708Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fb1028a037bf3cd6","local-member-id":"572db18442eba1c2","added-peer-id":"572db18442eba1c2","added-peer-peer-urls":["https://199.204.45.227:2380"]} {"level":"info","ts":"2026-05-24T10:03:57.050824Z","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-05-24T10:03:57.050943Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.227:2380"} {"level":"info","ts":"2026-05-24T10:03:57.050999Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.227:2380"} {"level":"info","ts":"2026-05-24T10:03:57.051101Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"572db18442eba1c2","initial-advertise-peer-urls":["https://199.204.45.227:2380"],"listen-peer-urls":["https://199.204.45.227:2380"],"advertise-client-urls":["https://199.204.45.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-24T10:03:57.051244Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-24T10:03:57.336037Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 is starting a new election at term 1"} {"level":"info","ts":"2026-05-24T10:03:57.336082Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-24T10:03:57.336103Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 received MsgPreVoteResp from 572db18442eba1c2 at term 1"} {"level":"info","ts":"2026-05-24T10:03:57.336114Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became candidate at term 2"} {"level":"info","ts":"2026-05-24T10:03:57.336119Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 received MsgVoteResp from 572db18442eba1c2 at term 2"} {"level":"info","ts":"2026-05-24T10:03:57.336128Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became leader at term 2"} {"level":"info","ts":"2026-05-24T10:03:57.336135Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 572db18442eba1c2 elected leader 572db18442eba1c2 at term 2"} {"level":"info","ts":"2026-05-24T10:03:57.337485Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"572db18442eba1c2","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.227:2379]}","request-path":"/0/members/572db18442eba1c2/attributes","cluster-id":"fb1028a037bf3cd6","publish-timeout":"7s"} {"level":"info","ts":"2026-05-24T10:03:57.33753Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-24T10:03:57.337609Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-24T10:03:57.337496Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-24T10:03:57.338665Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fb1028a037bf3cd6","local-member-id":"572db18442eba1c2","cluster-version":"3.5"} {"level":"info","ts":"2026-05-24T10:03:57.33878Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-24T10:03:57.338811Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-24T10:03:57.339675Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-24T10:03:57.33971Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-24T10:03:57.341301Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.227:2379"} {"level":"info","ts":"2026-05-24T10:03:57.342021Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-24T10:04:38.443993Z","caller":"traceutil/trace.go:171","msg":"trace[2103926287] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:610; }","duration":"102.513646ms","start":"2026-05-24T10:04:38.341451Z","end":"2026-05-24T10:04:38.443965Z","steps":["trace[2103926287] 'read index received' (duration: 101.840257ms)","trace[2103926287] 'applied index is now lower than readState.Index' (duration: 672.209µs)"],"step_count":2} {"level":"info","ts":"2026-05-24T10:04:38.444158Z","caller":"traceutil/trace.go:171","msg":"trace[489182486] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"188.841084ms","start":"2026-05-24T10:04:38.255285Z","end":"2026-05-24T10:04:38.444126Z","steps":["trace[489182486] 'process raft request' (duration: 188.202497ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:04:38.444517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.0403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-vhvc4\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-24T10:04:38.444649Z","caller":"traceutil/trace.go:171","msg":"trace[66576374] range","detail":"{range_begin:/registry/pods/kube-system/cilium-vhvc4; range_end:; response_count:1; response_revision:585; }","duration":"103.219135ms","start":"2026-05-24T10:04:38.341404Z","end":"2026-05-24T10:04:38.444623Z","steps":["trace[66576374] 'agreement among raft nodes before linearized reading' (duration: 102.947587ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:04:38.969926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.811058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T10:04:38.970043Z","caller":"traceutil/trace.go:171","msg":"trace[358053425] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:587; }","duration":"440.987943ms","start":"2026-05-24T10:04:38.529028Z","end":"2026-05-24T10:04:38.970016Z","steps":["trace[358053425] 'range keys from in-memory index tree' (duration: 440.690935ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:04:38.970103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T10:04:38.52901Z","time spent":"441.078766ms","remote":"127.0.0.1:37934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-05-24T10:04:38.970222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.624058ms","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-05-24T10:04:38.970298Z","caller":"traceutil/trace.go:171","msg":"trace[1463912670] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:587; }","duration":"110.739342ms","start":"2026-05-24T10:04:38.85954Z","end":"2026-05-24T10:04:38.970279Z","steps":["trace[1463912670] 'range keys from in-memory index tree' (duration: 110.379262ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T10:04:54.553005Z","caller":"traceutil/trace.go:171","msg":"trace[1496725591] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"100.269151ms","start":"2026-05-24T10:04:54.452705Z","end":"2026-05-24T10:04:54.552974Z","steps":["trace[1496725591] 'process raft request' (duration: 100.086946ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:06:12.793772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.336005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-24T10:06:12.793862Z","caller":"traceutil/trace.go:171","msg":"trace[691756043] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1830; }","duration":"514.437598ms","start":"2026-05-24T10:06:12.279406Z","end":"2026-05-24T10:06:12.793843Z","steps":["trace[691756043] 'range keys from in-memory index tree' (duration: 514.170191ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:06:12.79392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T10:06:12.279357Z","time spent":"514.553162ms","remote":"127.0.0.1:37970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-24T10:06:12.794119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.748364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-77d07460-98ad-4784-95c8-cece93327d5a\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-24T10:06:12.794192Z","caller":"traceutil/trace.go:171","msg":"trace[1487178041] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-77d07460-98ad-4784-95c8-cece93327d5a; range_end:; response_count:1; response_revision:1830; }","duration":"403.856977ms","start":"2026-05-24T10:06:12.390318Z","end":"2026-05-24T10:06:12.794175Z","steps":["trace[1487178041] 'range keys from in-memory index tree' (duration: 403.21287ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:06:12.794248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T10:06:12.390293Z","time spent":"403.94047ms","remote":"127.0.0.1:37882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-77d07460-98ad-4784-95c8-cece93327d5a\" "} {"level":"warn","ts":"2026-05-24T10:06:12.7945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.348918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-24T10:06:12.79471Z","caller":"traceutil/trace.go:171","msg":"trace[1949986544] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1830; }","duration":"161.681327ms","start":"2026-05-24T10:06:12.633009Z","end":"2026-05-24T10:06:12.79469Z","steps":["trace[1949986544] 'range keys from in-memory index tree' (duration: 160.577826ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:06:13.041147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.272305ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-24T10:06:13.041308Z","caller":"traceutil/trace.go:171","msg":"trace[1227688929] transaction","detail":"{read_only:false; response_revision:1832; number_of_response:1; }","duration":"240.514285ms","start":"2026-05-24T10:06:12.800765Z","end":"2026-05-24T10:06:13.041279Z","steps":["trace[1227688929] 'process raft request' (duration: 118.494129ms)","trace[1227688929] 'compare' (duration: 121.105821ms)"],"step_count":2} {"level":"warn","ts":"2026-05-24T10:06:13.041801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.270281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-77d07460-98ad-4784-95c8-cece93327d5a\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-24T10:06:13.041901Z","caller":"traceutil/trace.go:171","msg":"trace[813365159] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-77d07460-98ad-4784-95c8-cece93327d5a; range_end:; response_count:1; response_revision:1834; }","duration":"219.415665ms","start":"2026-05-24T10:06:12.822474Z","end":"2026-05-24T10:06:13.041889Z","steps":["trace[813365159] 'agreement among raft nodes before linearized reading' (duration: 219.004413ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T10:06:13.041444Z","caller":"traceutil/trace.go:171","msg":"trace[1358460158] linearizableReadLoop","detail":"{readStateIndex:1907; appliedIndex:1905; }","duration":"218.88752ms","start":"2026-05-24T10:06:12.822528Z","end":"2026-05-24T10:06:13.041416Z","steps":["trace[1358460158] 'read index received' (duration: 32.629757ms)","trace[1358460158] 'applied index is now lower than readState.Index' (duration: 186.255153ms)"],"step_count":2} {"level":"warn","ts":"2026-05-24T10:06:13.346957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.643028ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-24T10:06:13.347077Z","caller":"traceutil/trace.go:171","msg":"trace[1291236459] transaction","detail":"{read_only:false; response_revision:1835; number_of_response:1; }","duration":"300.203726ms","start":"2026-05-24T10:06:13.046852Z","end":"2026-05-24T10:06:13.347056Z","steps":["trace[1291236459] 'process raft request' (duration: 183.372254ms)","trace[1291236459] 'compare' (duration: 116.478803ms)"],"step_count":2} {"level":"warn","ts":"2026-05-24T10:06:13.347145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T10:06:13.046824Z","time spent":"300.293588ms","remote":"127.0.0.1:37970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-24T10:06:13.367591Z","caller":"traceutil/trace.go:171","msg":"trace[1764039610] transaction","detail":"{read_only:false; response_revision:1836; number_of_response:1; }","duration":"318.375156ms","start":"2026-05-24T10:06:13.049175Z","end":"2026-05-24T10:06:13.36755Z","steps":["trace[1764039610] 'process raft request' (duration: 318.225062ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:06:13.367825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T10:06:13.049162Z","time spent":"318.50472ms","remote":"127.0.0.1:37882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-24T10:06:25.874547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.457375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T10:06:25.874659Z","caller":"traceutil/trace.go:171","msg":"trace[1545566327] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1939; }","duration":"171.65405ms","start":"2026-05-24T10:06:25.702987Z","end":"2026-05-24T10:06:25.874641Z","steps":["trace[1545566327] 'count revisions from in-memory index tree' (duration: 171.346502ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:06:25.874818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.307841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11979"} {"level":"info","ts":"2026-05-24T10:06:25.874936Z","caller":"traceutil/trace.go:171","msg":"trace[363478932] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1939; }","duration":"211.481186ms","start":"2026-05-24T10:06:25.663429Z","end":"2026-05-24T10:06:25.874911Z","steps":["trace[363478932] 'range keys from in-memory index tree' (duration: 211.014614ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:06:26.093039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.25393ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-24T10:06:26.093387Z","caller":"traceutil/trace.go:171","msg":"trace[758316991] linearizableReadLoop","detail":"{readStateIndex:2018; appliedIndex:2016; }","duration":"331.407314ms","start":"2026-05-24T10:06:25.761958Z","end":"2026-05-24T10:06:26.093365Z","steps":["trace[758316991] 'read index received' (duration: 27.814784ms)","trace[758316991] 'applied index is now lower than readState.Index' (duration: 303.59131ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T10:06:26.093382Z","caller":"traceutil/trace.go:171","msg":"trace[463141332] transaction","detail":"{read_only:false; response_revision:1940; number_of_response:1; }","duration":"335.001483ms","start":"2026-05-24T10:06:25.758351Z","end":"2026-05-24T10:06:26.093352Z","steps":["trace[463141332] 'process raft request' (duration: 334.841629ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:06:26.093572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T10:06:25.758323Z","time spent":"335.141957ms","remote":"127.0.0.1:37970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-24T10:06:26.093605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.506847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T10:06:26.093644Z","caller":"traceutil/trace.go:171","msg":"trace[1979969247] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:1941; }","duration":"207.576439ms","start":"2026-05-24T10:06:25.886052Z","end":"2026-05-24T10:06:26.093629Z","steps":["trace[1979969247] 'agreement among raft nodes before linearized reading' (duration: 207.498537ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T10:06:26.09367Z","caller":"traceutil/trace.go:171","msg":"trace[1050043992] transaction","detail":"{read_only:false; response_revision:1941; number_of_response:1; }","duration":"223.381674ms","start":"2026-05-24T10:06:25.870232Z","end":"2026-05-24T10:06:26.093614Z","steps":["trace[1050043992] 'process raft request' (duration: 223.091476ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:06:26.093752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.775455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-24T10:06:26.093818Z","caller":"traceutil/trace.go:171","msg":"trace[1521638218] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1941; }","duration":"331.881958ms","start":"2026-05-24T10:06:25.761916Z","end":"2026-05-24T10:06:26.093798Z","steps":["trace[1521638218] 'agreement among raft nodes before linearized reading' (duration: 331.554089ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:06:26.09386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T10:06:25.761905Z","time spent":"331.942609ms","remote":"127.0.0.1:37970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-24T10:06:29.090562Z","caller":"traceutil/trace.go:171","msg":"trace[1470981059] transaction","detail":"{read_only:false; response_revision:1965; number_of_response:1; }","duration":"156.506144ms","start":"2026-05-24T10:06:28.934031Z","end":"2026-05-24T10:06:29.090537Z","steps":["trace[1470981059] 'process raft request' (duration: 156.376791ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T10:07:08.336807Z","caller":"traceutil/trace.go:171","msg":"trace[1418474486] transaction","detail":"{read_only:false; response_revision:2182; number_of_response:1; }","duration":"106.343925ms","start":"2026-05-24T10:07:08.230434Z","end":"2026-05-24T10:07:08.336778Z","steps":["trace[1418474486] 'process raft request' (duration: 106.188961ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T10:07:08.398315Z","caller":"traceutil/trace.go:171","msg":"trace[759639124] transaction","detail":"{read_only:false; response_revision:2183; number_of_response:1; }","duration":"167.638981ms","start":"2026-05-24T10:07:08.230653Z","end":"2026-05-24T10:07:08.398292Z","steps":["trace[759639124] 'process raft request' (duration: 167.452406ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T10:07:14.117428Z","caller":"traceutil/trace.go:171","msg":"trace[2136756389] transaction","detail":"{read_only:false; response_revision:2212; number_of_response:1; }","duration":"270.18922ms","start":"2026-05-24T10:07:13.847214Z","end":"2026-05-24T10:07:14.117403Z","steps":["trace[2136756389] 'process raft request' (duration: 269.993185ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:07:14.404468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.041214ms","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-05-24T10:07:14.404558Z","caller":"traceutil/trace.go:171","msg":"trace[1250500564] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2212; }","duration":"147.174888ms","start":"2026-05-24T10:07:14.257363Z","end":"2026-05-24T10:07:14.404538Z","steps":["trace[1250500564] 'count revisions from in-memory index tree' (duration: 146.930001ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:07:14.404575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.486071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-24T10:07:14.404625Z","caller":"traceutil/trace.go:171","msg":"trace[19534203] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2212; }","duration":"105.575424ms","start":"2026-05-24T10:07:14.299034Z","end":"2026-05-24T10:07:14.40461Z","steps":["trace[19534203] 'range keys from in-memory index tree' (duration: 105.361458ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T10:08:14.297985Z","caller":"traceutil/trace.go:171","msg":"trace[330291667] transaction","detail":"{read_only:false; response_revision:2704; number_of_response:1; }","duration":"151.191999ms","start":"2026-05-24T10:08:14.146757Z","end":"2026-05-24T10:08:14.297949Z","steps":["trace[330291667] 'process raft request' (duration: 150.974043ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:08:48.928849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.227782ms","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-05-24T10:08:48.929003Z","caller":"traceutil/trace.go:171","msg":"trace[1423312262] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2911; }","duration":"282.381186ms","start":"2026-05-24T10:08:48.646592Z","end":"2026-05-24T10:08:48.928973Z","steps":["trace[1423312262] 'range keys from in-memory index tree' (duration: 282.038716ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T10:08:48.929046Z","caller":"traceutil/trace.go:171","msg":"trace[1661802923] transaction","detail":"{read_only:false; response_revision:2912; number_of_response:1; }","duration":"263.789815ms","start":"2026-05-24T10:08:48.665227Z","end":"2026-05-24T10:08:48.929016Z","steps":["trace[1661802923] 'process raft request' (duration: 177.193713ms)","trace[1661802923] 'compare' (duration: 86.277673ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T10:08:48.929114Z","caller":"traceutil/trace.go:171","msg":"trace[1734404658] transaction","detail":"{read_only:false; response_revision:2913; number_of_response:1; }","duration":"177.357748ms","start":"2026-05-24T10:08:48.751729Z","end":"2026-05-24T10:08:48.929086Z","steps":["trace[1734404658] 'process raft request' (duration: 177.251285ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T10:08:48.929409Z","caller":"traceutil/trace.go:171","msg":"trace[607062098] transaction","detail":"{read_only:false; response_revision:2914; number_of_response:1; }","duration":"174.623332ms","start":"2026-05-24T10:08:48.754714Z","end":"2026-05-24T10:08:48.929337Z","steps":["trace[607062098] 'process raft request' (duration: 174.354785ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T10:11:15.431975Z","caller":"traceutil/trace.go:171","msg":"trace[1775221731] transaction","detail":"{read_only:false; response_revision:3888; number_of_response:1; }","duration":"100.389035ms","start":"2026-05-24T10:11:15.331556Z","end":"2026-05-24T10:11:15.431945Z","steps":["trace[1775221731] 'process raft request' (duration: 100.124028ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T10:12:38.980688Z","caller":"traceutil/trace.go:171","msg":"trace[780735931] linearizableReadLoop","detail":"{readStateIndex:5095; appliedIndex:5094; }","duration":"162.99308ms","start":"2026-05-24T10:12:38.81767Z","end":"2026-05-24T10:12:38.980663Z","steps":["trace[780735931] 'read index received' (duration: 89.270483ms)","trace[780735931] 'applied index is now lower than readState.Index' (duration: 73.721737ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T10:12:38.98071Z","caller":"traceutil/trace.go:171","msg":"trace[497861018] transaction","detail":"{read_only:false; response_revision:4904; number_of_response:1; }","duration":"237.234201ms","start":"2026-05-24T10:12:38.74344Z","end":"2026-05-24T10:12:38.980674Z","steps":["trace[497861018] 'process raft request' (duration: 163.564086ms)","trace[497861018] 'compare' (duration: 73.546951ms)"],"step_count":2} {"level":"warn","ts":"2026-05-24T10:12:38.980985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.291417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-wl4sj\" ","response":"range_response_count:1 size:9871"} {"level":"info","ts":"2026-05-24T10:12:38.981099Z","caller":"traceutil/trace.go:171","msg":"trace[1094770629] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-wl4sj; range_end:; response_count:1; response_revision:4904; }","duration":"163.441312ms","start":"2026-05-24T10:12:38.817633Z","end":"2026-05-24T10:12:38.981074Z","steps":["trace[1094770629] 'agreement among raft nodes before linearized reading' (duration: 163.125123ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:12:38.981673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.364896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:50 size:2209851"} {"level":"info","ts":"2026-05-24T10:12:38.981725Z","caller":"traceutil/trace.go:171","msg":"trace[861425710] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:50; response_revision:4904; }","duration":"133.434567ms","start":"2026-05-24T10:12:38.848279Z","end":"2026-05-24T10:12:38.981714Z","steps":["trace[861425710] 'agreement among raft nodes before linearized reading' (duration: 132.535633ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:12:41.057954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.546997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:897"} {"level":"info","ts":"2026-05-24T10:12:41.058095Z","caller":"traceutil/trace.go:171","msg":"trace[1097352603] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:5014; }","duration":"142.755423ms","start":"2026-05-24T10:12:40.915309Z","end":"2026-05-24T10:12:41.058064Z","steps":["trace[1097352603] 'range keys from in-memory index tree' (duration: 142.320421ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:12:41.058086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.276739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/loki\" ","response":"range_response_count:1 size:800"} {"level":"warn","ts":"2026-05-24T10:12:41.058004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.036801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/loki\" ","response":"range_response_count:1 size:800"} {"level":"info","ts":"2026-05-24T10:12:41.05824Z","caller":"traceutil/trace.go:171","msg":"trace[1891982768] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/loki; range_end:; response_count:1; response_revision:5014; }","duration":"142.463135ms","start":"2026-05-24T10:12:40.915741Z","end":"2026-05-24T10:12:41.058204Z","steps":["trace[1891982768] 'range keys from in-memory index tree' (duration: 142.060254ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T10:12:41.058246Z","caller":"traceutil/trace.go:171","msg":"trace[1153591978] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/loki; range_end:; response_count:1; response_revision:5014; }","duration":"143.35285ms","start":"2026-05-24T10:12:40.914874Z","end":"2026-05-24T10:12:41.058227Z","steps":["trace[1153591978] 'range keys from in-memory index tree' (duration: 142.854186ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:12:41.05827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.086342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/loki\" ","response":"range_response_count:1 size:800"} {"level":"info","ts":"2026-05-24T10:12:41.058335Z","caller":"traceutil/trace.go:171","msg":"trace[1809383814] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/loki; range_end:; response_count:1; response_revision:5014; }","duration":"143.182224ms","start":"2026-05-24T10:12:40.915138Z","end":"2026-05-24T10:12:41.05832Z","steps":["trace[1809383814] 'range keys from in-memory index tree' (duration: 143.00179ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:12:44.035008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.118832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-05-24T10:12:44.035047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.615747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-mwb5q\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-05-24T10:12:44.035146Z","caller":"traceutil/trace.go:171","msg":"trace[460025483] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5097; }","duration":"140.310667ms","start":"2026-05-24T10:12:43.894813Z","end":"2026-05-24T10:12:44.035123Z","steps":["trace[460025483] 'range keys from in-memory index tree' (duration: 139.923186ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T10:12:44.035248Z","caller":"traceutil/trace.go:171","msg":"trace[1789743078] range","detail":"{range_begin:/registry/pods/openstack/keepalived-mwb5q; range_end:; response_count:1; response_revision:5097; }","duration":"135.836534ms","start":"2026-05-24T10:12:43.899387Z","end":"2026-05-24T10:12:44.035223Z","steps":["trace[1789743078] 'range keys from in-memory index tree' (duration: 135.493174ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T10:12:44.279777Z","caller":"traceutil/trace.go:171","msg":"trace[1079024410] transaction","detail":"{read_only:false; response_revision:5100; number_of_response:1; }","duration":"142.253859ms","start":"2026-05-24T10:12:44.137492Z","end":"2026-05-24T10:12:44.279746Z","steps":["trace[1079024410] 'process raft request' (duration: 57.071358ms)","trace[1079024410] 'compare' (duration: 84.987576ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T10:12:44.279958Z","caller":"traceutil/trace.go:171","msg":"trace[573697686] linearizableReadLoop","detail":"{readStateIndex:5298; appliedIndex:5296; }","duration":"109.523471ms","start":"2026-05-24T10:12:44.170406Z","end":"2026-05-24T10:12:44.279929Z","steps":["trace[573697686] 'read index received' (duration: 24.182175ms)","trace[573697686] 'applied index is now lower than readState.Index' (duration: 85.338745ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T10:12:44.279989Z","caller":"traceutil/trace.go:171","msg":"trace[267023753] transaction","detail":"{read_only:false; response_revision:5101; number_of_response:1; }","duration":"140.142701ms","start":"2026-05-24T10:12:44.139835Z","end":"2026-05-24T10:12:44.279978Z","steps":["trace[267023753] 'process raft request' (duration: 139.858023ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:12:44.280197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.772167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-05-24T10:12:44.280236Z","caller":"traceutil/trace.go:171","msg":"trace[1211306195] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5101; }","duration":"109.882781ms","start":"2026-05-24T10:12:44.170342Z","end":"2026-05-24T10:12:44.280225Z","steps":["trace[1211306195] 'agreement among raft nodes before linearized reading' (duration: 109.688535ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T10:12:50.42082Z","caller":"traceutil/trace.go:171","msg":"trace[953065787] transaction","detail":"{read_only:false; response_revision:5226; number_of_response:1; }","duration":"143.466933ms","start":"2026-05-24T10:12:50.277311Z","end":"2026-05-24T10:12:50.420778Z","steps":["trace[953065787] 'process raft request' (duration: 138.25275ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T10:12:50.420913Z","caller":"traceutil/trace.go:171","msg":"trace[1081590759] transaction","detail":"{read_only:false; response_revision:5227; number_of_response:1; }","duration":"132.299356ms","start":"2026-05-24T10:12:50.288582Z","end":"2026-05-24T10:12:50.420881Z","steps":["trace[1081590759] 'process raft request' (duration: 131.906335ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T10:12:50.421232Z","caller":"traceutil/trace.go:171","msg":"trace[1991723657] transaction","detail":"{read_only:false; response_revision:5228; number_of_response:1; }","duration":"132.503952ms","start":"2026-05-24T10:12:50.288669Z","end":"2026-05-24T10:12:50.421173Z","steps":["trace[1991723657] 'process raft request' (duration: 132.131152ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:12:50.421315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.441073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T10:12:50.421361Z","caller":"traceutil/trace.go:171","msg":"trace[934007663] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:5228; }","duration":"107.536346ms","start":"2026-05-24T10:12:50.313812Z","end":"2026-05-24T10:12:50.421348Z","steps":["trace[934007663] 'agreement among raft nodes before linearized reading' (duration: 107.419953ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T10:12:50.421148Z","caller":"traceutil/trace.go:171","msg":"trace[1778961265] linearizableReadLoop","detail":"{readStateIndex:5431; appliedIndex:5428; }","duration":"107.243008ms","start":"2026-05-24T10:12:50.313853Z","end":"2026-05-24T10:12:50.421096Z","steps":["trace[1778961265] 'read index received' (duration: 101.806579ms)","trace[1778961265] 'applied index is now lower than readState.Index' (duration: 5.432519ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T10:12:50.458876Z","caller":"traceutil/trace.go:171","msg":"trace[1291577943] transaction","detail":"{read_only:false; response_revision:5231; number_of_response:1; }","duration":"127.744771ms","start":"2026-05-24T10:12:50.331098Z","end":"2026-05-24T10:12:50.458843Z","steps":["trace[1291577943] 'process raft request' (duration: 127.657819ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T10:12:50.459116Z","caller":"traceutil/trace.go:171","msg":"trace[513588604] transaction","detail":"{read_only:false; response_revision:5230; number_of_response:1; }","duration":"137.583962ms","start":"2026-05-24T10:12:50.3215Z","end":"2026-05-24T10:12:50.459084Z","steps":["trace[513588604] 'process raft request' (duration: 137.177391ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T10:12:50.459325Z","caller":"traceutil/trace.go:171","msg":"trace[1840950770] transaction","detail":"{read_only:false; response_revision:5229; number_of_response:1; }","duration":"137.960872ms","start":"2026-05-24T10:12:50.321342Z","end":"2026-05-24T10:12:50.459303Z","steps":["trace[1840950770] 'process raft request' (duration: 137.16008ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:12:50.459741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.90895ms","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-05-24T10:12:50.459782Z","caller":"traceutil/trace.go:171","msg":"trace[1976693432] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5231; }","duration":"142.000173ms","start":"2026-05-24T10:12:50.317772Z","end":"2026-05-24T10:12:50.459772Z","steps":["trace[1976693432] 'agreement among raft nodes before linearized reading' (duration: 141.831718ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:12:50.459879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.844769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-24T10:12:50.459958Z","caller":"traceutil/trace.go:171","msg":"trace[884270473] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5231; }","duration":"145.947501ms","start":"2026-05-24T10:12:50.31399Z","end":"2026-05-24T10:12:50.459938Z","steps":["trace[884270473] 'agreement among raft nodes before linearized reading' (duration: 145.701175ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:12:50.459967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.044955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-pushgateway-7b8659c68b-pbsrm\" ","response":"range_response_count:1 size:3128"} {"level":"warn","ts":"2026-05-24T10:12:50.460055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.97398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:28 size:825230"} {"level":"info","ts":"2026-05-24T10:12:50.460624Z","caller":"traceutil/trace.go:171","msg":"trace[1086379492] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:28; response_revision:5231; }","duration":"111.053952ms","start":"2026-05-24T10:12:50.349055Z","end":"2026-05-24T10:12:50.460109Z","steps":["trace[1086379492] 'agreement among raft nodes before linearized reading' (duration: 110.586239ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T10:12:50.461266Z","caller":"traceutil/trace.go:171","msg":"trace[293270303] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-pushgateway-7b8659c68b-pbsrm; range_end:; response_count:1; response_revision:5231; }","duration":"142.116606ms","start":"2026-05-24T10:12:50.317893Z","end":"2026-05-24T10:12:50.46001Z","steps":["trace[293270303] 'agreement among raft nodes before linearized reading' (duration: 141.708935ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T10:12:50.578068Z","caller":"traceutil/trace.go:171","msg":"trace[870983290] transaction","detail":"{read_only:false; response_revision:5236; number_of_response:1; }","duration":"103.923436ms","start":"2026-05-24T10:12:50.47412Z","end":"2026-05-24T10:12:50.578044Z","steps":["trace[870983290] 'process raft request' (duration: 103.874645ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T10:12:50.579489Z","caller":"traceutil/trace.go:171","msg":"trace[975677654] transaction","detail":"{read_only:false; response_revision:5232; number_of_response:1; }","duration":"150.349303ms","start":"2026-05-24T10:12:50.428978Z","end":"2026-05-24T10:12:50.579327Z","steps":["trace[975677654] 'process raft request' (duration: 95.234788ms)","trace[975677654] 'compare' (duration: 53.455259ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T10:12:50.579587Z","caller":"traceutil/trace.go:171","msg":"trace[73289628] transaction","detail":"{read_only:false; response_revision:5233; number_of_response:1; }","duration":"115.436123ms","start":"2026-05-24T10:12:50.46412Z","end":"2026-05-24T10:12:50.579556Z","steps":["trace[73289628] 'process raft request' (duration: 113.727966ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T10:12:50.57964Z","caller":"traceutil/trace.go:171","msg":"trace[326762583] linearizableReadLoop","detail":"{readStateIndex:5435; appliedIndex:5434; }","duration":"120.03638ms","start":"2026-05-24T10:12:50.459576Z","end":"2026-05-24T10:12:50.579612Z","steps":["trace[326762583] 'read index received' (duration: 64.650847ms)","trace[326762583] 'applied index is now lower than readState.Index' (duration: 55.382613ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T10:12:50.579722Z","caller":"traceutil/trace.go:171","msg":"trace[2142728927] transaction","detail":"{read_only:false; response_revision:5234; number_of_response:1; }","duration":"109.53121ms","start":"2026-05-24T10:12:50.470176Z","end":"2026-05-24T10:12:50.579707Z","steps":["trace[2142728927] 'process raft request' (duration: 107.730141ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:12:50.579797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.354815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T10:12:50.579828Z","caller":"traceutil/trace.go:171","msg":"trace[1247331766] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5236; }","duration":"137.450087ms","start":"2026-05-24T10:12:50.44237Z","end":"2026-05-24T10:12:50.57982Z","steps":["trace[1247331766] 'agreement among raft nodes before linearized reading' (duration: 137.365235ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T10:12:50.579928Z","caller":"traceutil/trace.go:171","msg":"trace[339388894] transaction","detail":"{read_only:false; response_revision:5235; number_of_response:1; }","duration":"109.50575ms","start":"2026-05-24T10:12:50.470416Z","end":"2026-05-24T10:12:50.579921Z","steps":["trace[339388894] 'process raft request' (duration: 107.540376ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:12:50.579918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.851918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/prometheus-pushgateway\" ","response":"range_response_count:1 size:910"} {"level":"info","ts":"2026-05-24T10:12:50.579985Z","caller":"traceutil/trace.go:171","msg":"trace[1870126656] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/prometheus-pushgateway; range_end:; response_count:1; response_revision:5236; }","duration":"145.966041ms","start":"2026-05-24T10:12:50.434Z","end":"2026-05-24T10:12:50.579966Z","steps":["trace[1870126656] 'agreement among raft nodes before linearized reading' (duration: 145.776166ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:12:50.625698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.8209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-wl4sj.18b278bbe3cc23f4\" ","response":"range_response_count:1 size:832"} {"level":"info","ts":"2026-05-24T10:12:50.625793Z","caller":"traceutil/trace.go:171","msg":"trace[1809958758] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-wl4sj.18b278bbe3cc23f4; range_end:; response_count:1; response_revision:5238; }","duration":"160.964624ms","start":"2026-05-24T10:12:50.464806Z","end":"2026-05-24T10:12:50.62577Z","steps":["trace[1809958758] 'agreement among raft nodes before linearized reading' (duration: 160.583164ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:13:06.092919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.42489ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-24T10:13:06.09325Z","caller":"traceutil/trace.go:171","msg":"trace[1136094297] linearizableReadLoop","detail":"{readStateIndex:5765; appliedIndex:5764; }","duration":"233.674193ms","start":"2026-05-24T10:13:05.859544Z","end":"2026-05-24T10:13:06.093218Z","steps":["trace[1136094297] 'read index received' (duration: 45.711µs)","trace[1136094297] 'applied index is now lower than readState.Index' (duration: 233.625762ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T10:13:06.093295Z","caller":"traceutil/trace.go:171","msg":"trace[241438022] transaction","detail":"{read_only:false; response_revision:5555; number_of_response:1; }","duration":"229.869148ms","start":"2026-05-24T10:13:05.863393Z","end":"2026-05-24T10:13:06.093262Z","steps":["trace[241438022] 'process raft request' (duration: 229.653512ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:13:06.224522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.950681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/persistence-rabbitmq-keystone-server-0.18b278c3a4fa45b9\" ","response":"range_response_count:1 size:1012"} {"level":"info","ts":"2026-05-24T10:13:06.224621Z","caller":"traceutil/trace.go:171","msg":"trace[1799405934] range","detail":"{range_begin:/registry/events/openstack/persistence-rabbitmq-keystone-server-0.18b278c3a4fa45b9; range_end:; response_count:1; response_revision:5555; }","duration":"365.095825ms","start":"2026-05-24T10:13:05.859496Z","end":"2026-05-24T10:13:06.224592Z","steps":["trace[1799405934] 'agreement among raft nodes before linearized reading' (duration: 233.842157ms)","trace[1799405934] 'range keys from in-memory index tree' (duration: 131.035911ms)"],"step_count":2} {"level":"warn","ts":"2026-05-24T10:13:06.224676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T10:13:05.859459Z","time spent":"365.203318ms","remote":"127.0.0.1:37760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":1,"response size":1035,"request content":"key:\"/registry/events/openstack/persistence-rabbitmq-keystone-server-0.18b278c3a4fa45b9\" "} {"level":"info","ts":"2026-05-24T10:13:06.254102Z","caller":"traceutil/trace.go:171","msg":"trace[1391785127] linearizableReadLoop","detail":"{readStateIndex:5767; appliedIndex:5766; }","duration":"160.768259ms","start":"2026-05-24T10:13:06.093311Z","end":"2026-05-24T10:13:06.25408Z","steps":["trace[1391785127] 'read index received' (duration: 160.580424ms)","trace[1391785127] 'applied index is now lower than readState.Index' (duration: 186.745µs)"],"step_count":2} {"level":"info","ts":"2026-05-24T10:13:06.254378Z","caller":"traceutil/trace.go:171","msg":"trace[1179494874] transaction","detail":"{read_only:false; response_revision:5556; number_of_response:1; }","duration":"383.874381ms","start":"2026-05-24T10:13:05.870472Z","end":"2026-05-24T10:13:06.254346Z","steps":["trace[1179494874] 'process raft request' (duration: 383.438789ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:13:06.254379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.212831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3f8ffe8e-68e6-4248-abf7-2d49cda40a15\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-05-24T10:13:06.254458Z","caller":"traceutil/trace.go:171","msg":"trace[1294294227] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3f8ffe8e-68e6-4248-abf7-2d49cda40a15; range_end:; response_count:1; response_revision:5556; }","duration":"205.344094ms","start":"2026-05-24T10:13:06.049094Z","end":"2026-05-24T10:13:06.254438Z","steps":["trace[1294294227] 'agreement among raft nodes before linearized reading' (duration: 205.127228ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:13:06.25452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T10:13:05.870456Z","time spent":"383.979694ms","remote":"127.0.0.1:37970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-24T10:13:06.254466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.916041ms","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-05-24T10:13:06.254668Z","caller":"traceutil/trace.go:171","msg":"trace[930282446] 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:5556; }","duration":"174.139366ms","start":"2026-05-24T10:13:06.080478Z","end":"2026-05-24T10:13:06.254617Z","steps":["trace[930282446] 'agreement among raft nodes before linearized reading' (duration: 173.708934ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:13:06.273614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.643745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T10:13:06.273681Z","caller":"traceutil/trace.go:171","msg":"trace[1719467030] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:5557; }","duration":"168.748978ms","start":"2026-05-24T10:13:06.104913Z","end":"2026-05-24T10:13:06.273662Z","steps":["trace[1719467030] 'agreement among raft nodes before linearized reading' (duration: 168.630695ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T10:13:57.205091Z","caller":"traceutil/trace.go:171","msg":"trace[53806720] linearizableReadLoop","detail":"{readStateIndex:6224; appliedIndex:6223; }","duration":"133.400721ms","start":"2026-05-24T10:13:57.07166Z","end":"2026-05-24T10:13:57.20506Z","steps":["trace[53806720] 'read index received' (duration: 80.474769ms)","trace[53806720] 'applied index is now lower than readState.Index' (duration: 52.924522ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T10:13:57.205274Z","caller":"traceutil/trace.go:171","msg":"trace[1907815712] transaction","detail":"{read_only:false; response_revision:6001; number_of_response:1; }","duration":"145.634737ms","start":"2026-05-24T10:13:57.059626Z","end":"2026-05-24T10:13:57.205261Z","steps":["trace[1907815712] 'process raft request' (duration: 92.5401ms)","trace[1907815712] 'compare' (duration: 52.723557ms)"],"step_count":2} {"level":"warn","ts":"2026-05-24T10:13:57.205692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.020109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T10:13:57.205776Z","caller":"traceutil/trace.go:171","msg":"trace[2042737286] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:6001; }","duration":"134.121311ms","start":"2026-05-24T10:13:57.071634Z","end":"2026-05-24T10:13:57.205755Z","steps":["trace[2042737286] 'agreement among raft nodes before linearized reading' (duration: 133.964227ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T10:13:57.422878Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2960} {"level":"info","ts":"2026-05-24T10:13:57.644201Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2960,"took":"219.020501ms","hash":2702689269,"current-db-size-bytes":57815040,"current-db-size":"58 MB","current-db-size-in-use-bytes":33300480,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-05-24T10:13:57.644345Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2702689269,"revision":2960,"compact-revision":-1} {"level":"info","ts":"2026-05-24T10:14:10.818261Z","caller":"traceutil/trace.go:171","msg":"trace[1213282075] linearizableReadLoop","detail":"{readStateIndex:6333; appliedIndex:6332; }","duration":"106.070932ms","start":"2026-05-24T10:14:10.712163Z","end":"2026-05-24T10:14:10.818234Z","steps":["trace[1213282075] 'read index received' (duration: 100.016536ms)","trace[1213282075] 'applied index is now lower than readState.Index' (duration: 6.052486ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T10:14:10.818372Z","caller":"traceutil/trace.go:171","msg":"trace[1573932964] transaction","detail":"{read_only:false; response_revision:6106; number_of_response:1; }","duration":"106.595486ms","start":"2026-05-24T10:14:10.711743Z","end":"2026-05-24T10:14:10.818339Z","steps":["trace[1573932964] 'process raft request' (duration: 100.463807ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:14:10.818539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.345639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-24T10:14:10.818605Z","caller":"traceutil/trace.go:171","msg":"trace[1046253067] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:6106; }","duration":"106.458822ms","start":"2026-05-24T10:14:10.712128Z","end":"2026-05-24T10:14:10.818586Z","steps":["trace[1046253067] 'agreement among raft nodes before linearized reading' (duration: 106.264847ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T10:18:57.436071Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6009} {"level":"info","ts":"2026-05-24T10:18:57.535153Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6009,"took":"97.271841ms","hash":4185101635,"current-db-size-bytes":57815040,"current-db-size":"58 MB","current-db-size-in-use-bytes":21831680,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-05-24T10:18:57.535244Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4185101635,"revision":6009,"compact-revision":2960} {"level":"info","ts":"2026-05-24T10:19:13.846009Z","caller":"traceutil/trace.go:171","msg":"trace[232368418] transaction","detail":"{read_only:false; response_revision:8345; number_of_response:1; }","duration":"290.366435ms","start":"2026-05-24T10:19:13.555609Z","end":"2026-05-24T10:19:13.845976Z","steps":["trace[232368418] 'process raft request' (duration: 290.208401ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T10:19:25.399817Z","caller":"traceutil/trace.go:171","msg":"trace[1552996001] linearizableReadLoop","detail":"{readStateIndex:8707; appliedIndex:8706; }","duration":"105.725423ms","start":"2026-05-24T10:19:25.294063Z","end":"2026-05-24T10:19:25.399788Z","steps":["trace[1552996001] 'read index received' (duration: 105.533358ms)","trace[1552996001] 'applied index is now lower than readState.Index' (duration: 190.845µs)"],"step_count":2} {"level":"info","ts":"2026-05-24T10:19:25.399892Z","caller":"traceutil/trace.go:171","msg":"trace[2034442529] transaction","detail":"{read_only:false; response_revision:8404; number_of_response:1; }","duration":"137.720582ms","start":"2026-05-24T10:19:25.262132Z","end":"2026-05-24T10:19:25.399852Z","steps":["trace[2034442529] 'process raft request' (duration: 137.493596ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:19:25.40001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.934329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-24T10:19:25.400052Z","caller":"traceutil/trace.go:171","msg":"trace[1522282040] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8404; }","duration":"106.036462ms","start":"2026-05-24T10:19:25.294006Z","end":"2026-05-24T10:19:25.400042Z","steps":["trace[1522282040] 'agreement among raft nodes before linearized reading' (duration: 105.885078ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T10:19:25.58258Z","caller":"traceutil/trace.go:171","msg":"trace[1488030446] transaction","detail":"{read_only:false; response_revision:8406; number_of_response:1; }","duration":"127.529372ms","start":"2026-05-24T10:19:25.455Z","end":"2026-05-24T10:19:25.58253Z","steps":["trace[1488030446] 'process raft request' (duration: 67.965687ms)","trace[1488030446] 'compare' (duration: 59.316539ms)"],"step_count":2} {"level":"warn","ts":"2026-05-24T10:19:26.453974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.799743ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-24T10:19:26.454082Z","caller":"traceutil/trace.go:171","msg":"trace[1508628341] linearizableReadLoop","detail":"{readStateIndex:8713; appliedIndex:8712; }","duration":"142.218175ms","start":"2026-05-24T10:19:26.311844Z","end":"2026-05-24T10:19:26.454063Z","steps":["trace[1508628341] 'read index received' (duration: 70.451µs)","trace[1508628341] 'applied index is now lower than readState.Index' (duration: 142.146274ms)"],"step_count":2} {"level":"warn","ts":"2026-05-24T10:19:26.454204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.35394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-24T10:19:26.454282Z","caller":"traceutil/trace.go:171","msg":"trace[933617572] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:8409; }","duration":"142.481763ms","start":"2026-05-24T10:19:26.311781Z","end":"2026-05-24T10:19:26.454263Z","steps":["trace[933617572] 'agreement among raft nodes before linearized reading' (duration: 142.346699ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:19:26.454223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.230164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T10:19:26.454394Z","caller":"traceutil/trace.go:171","msg":"trace[140961900] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:8409; }","duration":"139.401618ms","start":"2026-05-24T10:19:26.314951Z","end":"2026-05-24T10:19:26.454352Z","steps":["trace[140961900] 'agreement among raft nodes before linearized reading' (duration: 139.217283ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T10:19:27.570605Z","caller":"traceutil/trace.go:171","msg":"trace[38654924] transaction","detail":"{read_only:false; response_revision:8414; number_of_response:1; }","duration":"124.857089ms","start":"2026-05-24T10:19:27.445682Z","end":"2026-05-24T10:19:27.570539Z","steps":["trace[38654924] 'process raft request' (duration: 75.883354ms)","trace[38654924] 'compare' (duration: 48.81402ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T10:19:54.857469Z","caller":"traceutil/trace.go:171","msg":"trace[561935974] transaction","detail":"{read_only:false; response_revision:8619; number_of_response:1; }","duration":"221.472807ms","start":"2026-05-24T10:19:54.635953Z","end":"2026-05-24T10:19:54.857425Z","steps":["trace[561935974] 'process raft request' (duration: 221.239481ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T10:19:55.034098Z","caller":"traceutil/trace.go:171","msg":"trace[606637370] linearizableReadLoop","detail":"{readStateIndex:8931; appliedIndex:8930; }","duration":"169.106434ms","start":"2026-05-24T10:19:54.864957Z","end":"2026-05-24T10:19:55.034064Z","steps":["trace[606637370] 'read index received' (duration: 143.034234ms)","trace[606637370] 'applied index is now lower than readState.Index' (duration: 26.070869ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T10:19:55.03412Z","caller":"traceutil/trace.go:171","msg":"trace[1476489919] transaction","detail":"{read_only:false; response_revision:8620; number_of_response:1; }","duration":"171.086338ms","start":"2026-05-24T10:19:54.862982Z","end":"2026-05-24T10:19:55.034069Z","steps":["trace[1476489919] 'process raft request' (duration: 145.007388ms)","trace[1476489919] 'compare' (duration: 25.862064ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T10:19:55.034215Z","caller":"traceutil/trace.go:171","msg":"trace[280575153] transaction","detail":"{read_only:false; response_revision:8621; number_of_response:1; }","duration":"169.110383ms","start":"2026-05-24T10:19:54.865093Z","end":"2026-05-24T10:19:55.034203Z","steps":["trace[280575153] 'process raft request' (duration: 168.932858ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:19:55.034569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.593247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228867"} {"level":"info","ts":"2026-05-24T10:19:55.034651Z","caller":"traceutil/trace.go:171","msg":"trace[379482389] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8621; }","duration":"169.72177ms","start":"2026-05-24T10:19:54.86491Z","end":"2026-05-24T10:19:55.034632Z","steps":["trace[379482389] 'agreement among raft nodes before linearized reading' (duration: 169.262918ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T10:19:57.888412Z","caller":"traceutil/trace.go:171","msg":"trace[1565961462] transaction","detail":"{read_only:false; response_revision:8633; number_of_response:1; }","duration":"110.628ms","start":"2026-05-24T10:19:57.777742Z","end":"2026-05-24T10:19:57.88837Z","steps":["trace[1565961462] 'process raft request' (duration: 49.016981ms)","trace[1565961462] 'compare' (duration: 61.379633ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T10:19:58.262366Z","caller":"traceutil/trace.go:171","msg":"trace[1939996287] linearizableReadLoop","detail":"{readStateIndex:8946; appliedIndex:8945; }","duration":"242.132297ms","start":"2026-05-24T10:19:58.02017Z","end":"2026-05-24T10:19:58.262302Z","steps":["trace[1939996287] 'read index received' (duration: 231.01009ms)","trace[1939996287] 'applied index is now lower than readState.Index' (duration: 11.120177ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T10:19:58.262375Z","caller":"traceutil/trace.go:171","msg":"trace[354277921] transaction","detail":"{read_only:false; response_revision:8634; number_of_response:1; }","duration":"262.844478ms","start":"2026-05-24T10:19:57.999486Z","end":"2026-05-24T10:19:58.26233Z","steps":["trace[354277921] 'process raft request' (duration: 251.792754ms)","trace[354277921] 'compare' (duration: 10.633433ms)"],"step_count":2} {"level":"warn","ts":"2026-05-24T10:19:58.262569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.335793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T10:19:58.262712Z","caller":"traceutil/trace.go:171","msg":"trace[524309093] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:8634; }","duration":"242.548718ms","start":"2026-05-24T10:19:58.020133Z","end":"2026-05-24T10:19:58.262682Z","steps":["trace[524309093] 'agreement among raft nodes before linearized reading' (duration: 242.279151ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T10:20:07.552512Z","caller":"traceutil/trace.go:171","msg":"trace[862234719] transaction","detail":"{read_only:false; response_revision:8727; number_of_response:1; }","duration":"112.079711ms","start":"2026-05-24T10:20:07.440409Z","end":"2026-05-24T10:20:07.552489Z","steps":["trace[862234719] 'process raft request' (duration: 83.595325ms)","trace[862234719] 'compare' (duration: 28.177937ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T10:20:23.957322Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023f5.wal"} {"level":"info","ts":"2026-05-24T10:21:24.800233Z","caller":"traceutil/trace.go:171","msg":"trace[1884432370] transaction","detail":"{read_only:false; response_revision:9481; number_of_response:1; }","duration":"124.131713ms","start":"2026-05-24T10:21:24.676062Z","end":"2026-05-24T10:21:24.800193Z","steps":["trace[1884432370] 'process raft request' (duration: 95.715189ms)","trace[1884432370] 'compare' (duration: 28.130966ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T10:21:47.779041Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"572db18442eba1c2","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-24T10:21:47.785656Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-24T10:21:47.785765Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-24T10:23:57.445832Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8117} {"level":"info","ts":"2026-05-24T10:23:57.508458Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8117,"took":"60.12603ms","hash":1081049892,"current-db-size-bytes":57815040,"current-db-size":"58 MB","current-db-size-in-use-bytes":29204480,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-24T10:23:57.508533Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1081049892,"revision":8117,"compact-revision":6009} {"level":"info","ts":"2026-05-24T10:24:51.221168Z","caller":"traceutil/trace.go:171","msg":"trace[488568031] transaction","detail":"{read_only:false; response_revision:11234; number_of_response:1; }","duration":"145.681923ms","start":"2026-05-24T10:24:51.075449Z","end":"2026-05-24T10:24:51.221131Z","steps":["trace[488568031] 'process raft request' (duration: 107.123058ms)","trace[488568031] 'compare' (duration: 38.37812ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T10:25:08.876384Z","caller":"traceutil/trace.go:171","msg":"trace[1024089998] transaction","detail":"{read_only:false; response_revision:11331; number_of_response:1; }","duration":"161.234702ms","start":"2026-05-24T10:25:08.71508Z","end":"2026-05-24T10:25:08.876315Z","steps":["trace[1024089998] 'process raft request' (duration: 89.212683ms)","trace[1024089998] 'compare' (duration: 71.70648ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T10:25:08.87645Z","caller":"traceutil/trace.go:171","msg":"trace[1359754722] transaction","detail":"{read_only:false; response_revision:11332; number_of_response:1; }","duration":"160.692677ms","start":"2026-05-24T10:25:08.71572Z","end":"2026-05-24T10:25:08.876412Z","steps":["trace[1359754722] 'process raft request' (duration: 160.581714ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T10:25:08.876439Z","caller":"traceutil/trace.go:171","msg":"trace[1532054875] linearizableReadLoop","detail":"{readStateIndex:11721; appliedIndex:11720; }","duration":"161.012725ms","start":"2026-05-24T10:25:08.715393Z","end":"2026-05-24T10:25:08.876405Z","steps":["trace[1532054875] 'read index received' (duration: 88.915394ms)","trace[1532054875] 'applied index is now lower than readState.Index' (duration: 72.094621ms)"],"step_count":2} {"level":"warn","ts":"2026-05-24T10:25:08.87664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.226522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19042"} {"level":"info","ts":"2026-05-24T10:25:08.876699Z","caller":"traceutil/trace.go:171","msg":"trace[1886633751] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:11332; }","duration":"161.317104ms","start":"2026-05-24T10:25:08.715369Z","end":"2026-05-24T10:25:08.876686Z","steps":["trace[1886633751] 'agreement among raft nodes before linearized reading' (duration: 161.128539ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T10:25:09.100614Z","caller":"traceutil/trace.go:171","msg":"trace[897360065] linearizableReadLoop","detail":"{readStateIndex:11725; appliedIndex:11722; }","duration":"163.588777ms","start":"2026-05-24T10:25:08.936999Z","end":"2026-05-24T10:25:09.100588Z","steps":["trace[897360065] 'read index received' (duration: 88.885585ms)","trace[897360065] 'applied index is now lower than readState.Index' (duration: 74.701962ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T10:25:09.100733Z","caller":"traceutil/trace.go:171","msg":"trace[1139168721] transaction","detail":"{read_only:false; response_revision:11333; number_of_response:1; }","duration":"218.485973ms","start":"2026-05-24T10:25:08.882231Z","end":"2026-05-24T10:25:09.100717Z","steps":["trace[1139168721] 'process raft request' (duration: 143.558214ms)","trace[1139168721] 'compare' (duration: 74.524708ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T10:25:09.100887Z","caller":"traceutil/trace.go:171","msg":"trace[668459702] transaction","detail":"{read_only:false; response_revision:11335; number_of_response:1; }","duration":"217.090074ms","start":"2026-05-24T10:25:08.883767Z","end":"2026-05-24T10:25:09.100857Z","steps":["trace[668459702] 'process raft request' (duration: 216.758055ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T10:25:09.10088Z","caller":"traceutil/trace.go:171","msg":"trace[1217089604] transaction","detail":"{read_only:false; response_revision:11334; number_of_response:1; }","duration":"218.249976ms","start":"2026-05-24T10:25:08.882591Z","end":"2026-05-24T10:25:09.100841Z","steps":["trace[1217089604] 'process raft request' (duration: 217.881676ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:25:09.101115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.05951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T10:25:09.101209Z","caller":"traceutil/trace.go:171","msg":"trace[2042452674] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:11335; }","duration":"164.248615ms","start":"2026-05-24T10:25:08.936944Z","end":"2026-05-24T10:25:09.101193Z","steps":["trace[2042452674] 'agreement among raft nodes before linearized reading' (duration: 164.06945ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:25:09.114618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.369338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T10:25:09.114676Z","caller":"traceutil/trace.go:171","msg":"trace[1588564787] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:11336; }","duration":"160.494361ms","start":"2026-05-24T10:25:08.954168Z","end":"2026-05-24T10:25:09.114662Z","steps":["trace[1588564787] 'agreement among raft nodes before linearized reading' (duration: 160.365268ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T10:25:09.487734Z","caller":"traceutil/trace.go:171","msg":"trace[909156737] linearizableReadLoop","detail":"{readStateIndex:11727; appliedIndex:11726; }","duration":"136.207021ms","start":"2026-05-24T10:25:09.351505Z","end":"2026-05-24T10:25:09.487712Z","steps":["trace[909156737] 'read index received' (duration: 136.136699ms)","trace[909156737] 'applied index is now lower than readState.Index' (duration: 69.202µs)"],"step_count":2} {"level":"info","ts":"2026-05-24T10:25:09.487793Z","caller":"traceutil/trace.go:171","msg":"trace[919486202] transaction","detail":"{read_only:false; response_revision:11337; number_of_response:1; }","duration":"184.489054ms","start":"2026-05-24T10:25:09.303275Z","end":"2026-05-24T10:25:09.487764Z","steps":["trace[919486202] 'process raft request' (duration: 184.291129ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:25:09.488069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.551351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-mwb5q\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-05-24T10:25:09.48813Z","caller":"traceutil/trace.go:171","msg":"trace[1640077984] range","detail":"{range_begin:/registry/pods/openstack/keepalived-mwb5q; range_end:; response_count:1; response_revision:11337; }","duration":"136.661924ms","start":"2026-05-24T10:25:09.351449Z","end":"2026-05-24T10:25:09.488111Z","steps":["trace[1640077984] 'agreement among raft nodes before linearized reading' (duration: 136.382966ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:25:09.506928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.199264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-24T10:25:09.506984Z","caller":"traceutil/trace.go:171","msg":"trace[1829405399] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:11338; }","duration":"118.303456ms","start":"2026-05-24T10:25:09.388664Z","end":"2026-05-24T10:25:09.506967Z","steps":["trace[1829405399] 'agreement among raft nodes before linearized reading' (duration: 118.175813ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:25:09.922473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.478893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-24T10:25:09.922518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.423214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T10:25:09.922585Z","caller":"traceutil/trace.go:171","msg":"trace[897485575] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11341; }","duration":"201.642177ms","start":"2026-05-24T10:25:09.720917Z","end":"2026-05-24T10:25:09.922559Z","steps":["trace[897485575] 'range keys from in-memory index tree' (duration: 201.336969ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T10:25:09.922602Z","caller":"traceutil/trace.go:171","msg":"trace[1138187432] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11341; }","duration":"200.569298ms","start":"2026-05-24T10:25:09.722014Z","end":"2026-05-24T10:25:09.922583Z","steps":["trace[1138187432] 'range keys from in-memory index tree' (duration: 200.329612ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:25:09.922617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.590107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T10:25:09.922681Z","caller":"traceutil/trace.go:171","msg":"trace[1905738295] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:11341; }","duration":"201.720319ms","start":"2026-05-24T10:25:09.720942Z","end":"2026-05-24T10:25:09.922662Z","steps":["trace[1905738295] 'range keys from in-memory index tree' (duration: 201.519434ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:25:09.922747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.762661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T10:25:09.922816Z","caller":"traceutil/trace.go:171","msg":"trace[1930757340] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:11341; }","duration":"201.898954ms","start":"2026-05-24T10:25:09.720903Z","end":"2026-05-24T10:25:09.922802Z","steps":["trace[1930757340] 'range keys from in-memory index tree' (duration: 201.636018ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:25:09.923307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.10742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5077"} {"level":"info","ts":"2026-05-24T10:25:09.923403Z","caller":"traceutil/trace.go:171","msg":"trace[443380064] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:11341; }","duration":"202.235164ms","start":"2026-05-24T10:25:09.721149Z","end":"2026-05-24T10:25:09.923385Z","steps":["trace[443380064] 'range keys from in-memory index tree' (duration: 201.880674ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T10:28:57.451007Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10644} {"level":"info","ts":"2026-05-24T10:28:57.528154Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10644,"took":"74.785443ms","hash":4066304920,"current-db-size-bytes":57815040,"current-db-size":"58 MB","current-db-size-in-use-bytes":27447296,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-05-24T10:28:57.528209Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4066304920,"revision":10644,"compact-revision":8117} {"level":"info","ts":"2026-05-24T10:31:05.210948Z","caller":"traceutil/trace.go:171","msg":"trace[934471536] transaction","detail":"{read_only:false; response_revision:13758; number_of_response:1; }","duration":"103.560447ms","start":"2026-05-24T10:31:05.107361Z","end":"2026-05-24T10:31:05.210921Z","steps":["trace[934471536] 'process raft request' (duration: 103.420693ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T10:31:42.63419Z","caller":"traceutil/trace.go:171","msg":"trace[1736294054] transaction","detail":"{read_only:false; response_revision:13964; number_of_response:1; }","duration":"177.503397ms","start":"2026-05-24T10:31:42.456655Z","end":"2026-05-24T10:31:42.634158Z","steps":["trace[1736294054] 'process raft request' (duration: 177.24592ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:31:43.634676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.126599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T10:31:43.634777Z","caller":"traceutil/trace.go:171","msg":"trace[2066269797] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:13972; }","duration":"143.276902ms","start":"2026-05-24T10:31:43.491478Z","end":"2026-05-24T10:31:43.634755Z","steps":["trace[2066269797] 'count revisions from in-memory index tree' (duration: 142.998875ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:31:43.634956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.056616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-24T10:31:43.635042Z","caller":"traceutil/trace.go:171","msg":"trace[1715050931] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13972; }","duration":"131.217ms","start":"2026-05-24T10:31:43.503805Z","end":"2026-05-24T10:31:43.635022Z","steps":["trace[1715050931] 'range keys from in-memory index tree' (duration: 130.797559ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:31:46.340161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.469662ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-24T10:31:46.340252Z","caller":"traceutil/trace.go:171","msg":"trace[1964712652] linearizableReadLoop","detail":"{readStateIndex:14480; appliedIndex:14479; }","duration":"122.747546ms","start":"2026-05-24T10:31:46.217486Z","end":"2026-05-24T10:31:46.340233Z","steps":["trace[1964712652] 'read index received' (duration: 104.813µs)","trace[1964712652] 'applied index is now lower than readState.Index' (duration: 122.640943ms)"],"step_count":2} {"level":"warn","ts":"2026-05-24T10:31:46.34034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.849099ms","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-05-24T10:31:46.340378Z","caller":"traceutil/trace.go:171","msg":"trace[1058266853] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:13996; }","duration":"122.939002ms","start":"2026-05-24T10:31:46.217428Z","end":"2026-05-24T10:31:46.340367Z","steps":["trace[1058266853] 'agreement among raft nodes before linearized reading' (duration: 122.857349ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:32:05.684002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.702036ms","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-05-24T10:32:05.684084Z","caller":"traceutil/trace.go:171","msg":"trace[965551557] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14281; }","duration":"138.800719ms","start":"2026-05-24T10:32:05.545267Z","end":"2026-05-24T10:32:05.684067Z","steps":["trace[965551557] 'range keys from in-memory index tree' (duration: 138.511322ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T10:33:31.334041Z","caller":"traceutil/trace.go:171","msg":"trace[237388892] transaction","detail":"{read_only:false; response_revision:15092; number_of_response:1; }","duration":"133.464201ms","start":"2026-05-24T10:33:31.200555Z","end":"2026-05-24T10:33:31.334019Z","steps":["trace[237388892] 'process raft request' (duration: 133.305547ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T10:33:31.341433Z","caller":"traceutil/trace.go:171","msg":"trace[347608266] linearizableReadLoop","detail":"{readStateIndex:15612; appliedIndex:15610; }","duration":"122.132928ms","start":"2026-05-24T10:33:31.219282Z","end":"2026-05-24T10:33:31.341415Z","steps":["trace[347608266] 'read index received' (duration: 114.540429ms)","trace[347608266] 'applied index is now lower than readState.Index' (duration: 7.591729ms)"],"step_count":2} {"level":"warn","ts":"2026-05-24T10:33:31.341551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.250112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T10:33:31.341572Z","caller":"traceutil/trace.go:171","msg":"trace[7625117] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:15093; }","duration":"122.315913ms","start":"2026-05-24T10:33:31.219249Z","end":"2026-05-24T10:33:31.341565Z","steps":["trace[7625117] 'agreement among raft nodes before linearized reading' (duration: 122.247912ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T10:33:31.341489Z","caller":"traceutil/trace.go:171","msg":"trace[1098744180] transaction","detail":"{read_only:false; response_revision:15093; number_of_response:1; }","duration":"140.326781ms","start":"2026-05-24T10:33:31.201139Z","end":"2026-05-24T10:33:31.341466Z","steps":["trace[1098744180] 'process raft request' (duration: 140.184487ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T10:33:31.787517Z","caller":"traceutil/trace.go:171","msg":"trace[1847683425] linearizableReadLoop","detail":"{readStateIndex:15614; appliedIndex:15613; }","duration":"372.136995ms","start":"2026-05-24T10:33:31.415358Z","end":"2026-05-24T10:33:31.787495Z","steps":["trace[1847683425] 'read index received' (duration: 371.989241ms)","trace[1847683425] 'applied index is now lower than readState.Index' (duration: 146.524µs)"],"step_count":2} {"level":"warn","ts":"2026-05-24T10:33:31.787724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.355661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-thfzv\" ","response":"range_response_count:1 size:20754"} {"level":"warn","ts":"2026-05-24T10:33:31.787724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.600408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T10:33:31.787771Z","caller":"traceutil/trace.go:171","msg":"trace[687112574] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-thfzv; range_end:; response_count:1; response_revision:15095; }","duration":"372.433073ms","start":"2026-05-24T10:33:31.415315Z","end":"2026-05-24T10:33:31.787748Z","steps":["trace[687112574] 'agreement among raft nodes before linearized reading' (duration: 372.30143ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T10:33:31.787776Z","caller":"traceutil/trace.go:171","msg":"trace[207454616] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15095; }","duration":"347.697651ms","start":"2026-05-24T10:33:31.440066Z","end":"2026-05-24T10:33:31.787763Z","steps":["trace[207454616] 'agreement among raft nodes before linearized reading' (duration: 347.608248ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:33:31.787793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.395055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-mwb5q\" ","response":"range_response_count:1 size:6530"} {"level":"warn","ts":"2026-05-24T10:33:31.78781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T10:33:31.440043Z","time spent":"347.755803ms","remote":"127.0.0.1:37676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-24T10:33:31.78781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T10:33:31.415299Z","time spent":"372.496236ms","remote":"127.0.0.1:37882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":20777,"request content":"key:\"/registry/pods/openstack/libvirt-libvirt-default-thfzv\" "} {"level":"info","ts":"2026-05-24T10:33:31.787833Z","caller":"traceutil/trace.go:171","msg":"trace[2143990649] range","detail":"{range_begin:/registry/pods/openstack/keepalived-mwb5q; range_end:; response_count:1; response_revision:15095; }","duration":"172.470717ms","start":"2026-05-24T10:33:31.615349Z","end":"2026-05-24T10:33:31.78782Z","steps":["trace[2143990649] 'agreement among raft nodes before linearized reading' (duration: 172.366864ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:33:31.787939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.648956ms","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-05-24T10:33:31.788012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.213982ms","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-05-24T10:33:31.788018Z","caller":"traceutil/trace.go:171","msg":"trace[1928557577] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15095; }","duration":"204.771798ms","start":"2026-05-24T10:33:31.583227Z","end":"2026-05-24T10:33:31.787999Z","steps":["trace[1928557577] 'agreement among raft nodes before linearized reading' (duration: 204.510841ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T10:33:31.78804Z","caller":"traceutil/trace.go:171","msg":"trace[66393706] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15095; }","duration":"258.275454ms","start":"2026-05-24T10:33:31.529757Z","end":"2026-05-24T10:33:31.788033Z","steps":["trace[66393706] 'agreement among raft nodes before linearized reading' (duration: 258.199642ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T10:33:31.787646Z","caller":"traceutil/trace.go:171","msg":"trace[1248705686] transaction","detail":"{read_only:false; response_revision:15095; number_of_response:1; }","duration":"389.921176ms","start":"2026-05-24T10:33:31.397694Z","end":"2026-05-24T10:33:31.787615Z","steps":["trace[1248705686] 'process raft request' (duration: 389.668449ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:33:31.788337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T10:33:31.397673Z","time spent":"390.587054ms","remote":"127.0.0.1:37970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-24T10:33:32.258173Z","caller":"traceutil/trace.go:171","msg":"trace[1605033158] transaction","detail":"{read_only:false; response_revision:15098; number_of_response:1; }","duration":"161.352511ms","start":"2026-05-24T10:33:32.096761Z","end":"2026-05-24T10:33:32.258114Z","steps":["trace[1605033158] 'process raft request' (duration: 161.015522ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T10:33:32.393895Z","caller":"traceutil/trace.go:171","msg":"trace[50730729] transaction","detail":"{read_only:false; response_revision:15099; number_of_response:1; }","duration":"128.70822ms","start":"2026-05-24T10:33:32.265137Z","end":"2026-05-24T10:33:32.393846Z","steps":["trace[50730729] 'process raft request' (duration: 67.266585ms)","trace[50730729] 'compare' (duration: 61.145967ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T10:33:57.456553Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12811} {"level":"info","ts":"2026-05-24T10:33:57.531538Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12811,"took":"72.403067ms","hash":2139613208,"current-db-size-bytes":57815040,"current-db-size":"58 MB","current-db-size-in-use-bytes":29806592,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-24T10:33:57.531613Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2139613208,"revision":12811,"compact-revision":10644} {"level":"info","ts":"2026-05-24T10:38:01.474811Z","caller":"traceutil/trace.go:171","msg":"trace[1274853748] linearizableReadLoop","detail":"{readStateIndex:17719; appliedIndex:17718; }","duration":"183.074179ms","start":"2026-05-24T10:38:01.291713Z","end":"2026-05-24T10:38:01.474787Z","steps":["trace[1274853748] 'read index received' (duration: 182.915235ms)","trace[1274853748] 'applied index is now lower than readState.Index' (duration: 157.704µs)"],"step_count":2} {"level":"warn","ts":"2026-05-24T10:38:01.475063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.302826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-24T10:38:01.474868Z","caller":"traceutil/trace.go:171","msg":"trace[1728587642] transaction","detail":"{read_only:false; response_revision:17134; number_of_response:1; }","duration":"227.401232ms","start":"2026-05-24T10:38:01.247431Z","end":"2026-05-24T10:38:01.474832Z","steps":["trace[1728587642] 'process raft request' (duration: 227.212306ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T10:38:01.475174Z","caller":"traceutil/trace.go:171","msg":"trace[1509395430] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:17134; }","duration":"183.399958ms","start":"2026-05-24T10:38:01.291705Z","end":"2026-05-24T10:38:01.475105Z","steps":["trace[1509395430] 'agreement among raft nodes before linearized reading' (duration: 183.278195ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T10:38:01.476333Z","caller":"traceutil/trace.go:171","msg":"trace[515468917] transaction","detail":"{read_only:false; response_revision:17135; number_of_response:1; }","duration":"177.484835ms","start":"2026-05-24T10:38:01.29882Z","end":"2026-05-24T10:38:01.476305Z","steps":["trace[515468917] 'process raft request' (duration: 177.354912ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T10:38:57.462269Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15225} {"level":"info","ts":"2026-05-24T10:38:57.559333Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15225,"took":"93.688624ms","hash":2524601016,"current-db-size-bytes":57815040,"current-db-size":"58 MB","current-db-size-in-use-bytes":31715328,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-24T10:38:57.559383Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2524601016,"revision":15225,"compact-revision":12811} {"level":"info","ts":"2026-05-24T10:43:57.468149Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17524} {"level":"info","ts":"2026-05-24T10:43:57.560528Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17524,"took":"89.466006ms","hash":4279785863,"current-db-size-bytes":57815040,"current-db-size":"58 MB","current-db-size-in-use-bytes":26742784,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-05-24T10:43:57.560587Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4279785863,"revision":17524,"compact-revision":15225} {"level":"info","ts":"2026-05-24T10:44:41.362173Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"572db18442eba1c2","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-24T10:44:41.370727Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-24T10:44:41.370836Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"warn","ts":"2026-05-24T10:47:09.962291Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656052892573102082,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-24T10:47:10.465544Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656052892573102082,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-24T10:47:10.969672Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656052892573102082,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-24T10:47:11.469941Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656052892573102082,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-24T10:47:11.590957Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.353099446s","expected-duration":"1s"} {"level":"info","ts":"2026-05-24T10:47:11.591349Z","caller":"traceutil/trace.go:171","msg":"trace[569022643] transaction","detail":"{read_only:false; response_revision:20090; number_of_response:1; }","duration":"2.353557869s","start":"2026-05-24T10:47:09.237768Z","end":"2026-05-24T10:47:11.591326Z","steps":["trace[569022643] 'process raft request' (duration: 2.353411955s)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:47:11.591484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T10:47:09.237752Z","time spent":"2.353662682s","remote":"127.0.0.1:37970","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-05-24T10:47:11.595489Z","caller":"traceutil/trace.go:171","msg":"trace[1930972917] linearizableReadLoop","detail":"{readStateIndex:20800; appliedIndex:20794; }","duration":"2.161709152s","start":"2026-05-24T10:47:09.43376Z","end":"2026-05-24T10:47:11.59547Z","steps":["trace[1930972917] 'read index received' (duration: 2.157431554s)","trace[1930972917] 'applied index is now lower than readState.Index' (duration: 4.276858ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T10:47:11.595498Z","caller":"traceutil/trace.go:171","msg":"trace[150773409] transaction","detail":"{read_only:false; response_revision:20091; number_of_response:1; }","duration":"1.63349761s","start":"2026-05-24T10:47:09.961967Z","end":"2026-05-24T10:47:11.595465Z","steps":["trace[150773409] 'process raft request' (duration: 1.633187062s)"],"step_count":1} {"level":"info","ts":"2026-05-24T10:47:11.595561Z","caller":"traceutil/trace.go:171","msg":"trace[67795566] transaction","detail":"{read_only:false; response_revision:20092; number_of_response:1; }","duration":"1.633555002s","start":"2026-05-24T10:47:09.961999Z","end":"2026-05-24T10:47:11.595554Z","steps":["trace[67795566] 'process raft request' (duration: 1.633378557s)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:47:11.59563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T10:47:09.961956Z","time spent":"1.633606683s","remote":"127.0.0.1:37970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-24T10:47:11.595663Z","caller":"traceutil/trace.go:171","msg":"trace[800146300] transaction","detail":"{read_only:false; response_revision:20093; number_of_response:1; }","duration":"1.480480362s","start":"2026-05-24T10:47:10.115177Z","end":"2026-05-24T10:47:11.595658Z","steps":["trace[800146300] 'process raft request' (duration: 1.480228365s)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:47:11.595688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T10:47:10.115167Z","time spent":"1.480508953s","remote":"127.0.0.1:37970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-24T10:47:11.595639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T10:47:09.961993Z","time spent":"1.633584873s","remote":"127.0.0.1:37970","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-05-24T10:47:11.595874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.162069412s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:701"} {"level":"info","ts":"2026-05-24T10:47:11.595903Z","caller":"traceutil/trace.go:171","msg":"trace[958973478] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:20095; }","duration":"2.162104652s","start":"2026-05-24T10:47:09.433792Z","end":"2026-05-24T10:47:11.595896Z","steps":["trace[958973478] 'agreement among raft nodes before linearized reading' (duration: 2.161993859s)"],"step_count":1} {"level":"info","ts":"2026-05-24T10:47:11.595903Z","caller":"traceutil/trace.go:171","msg":"trace[1388127587] transaction","detail":"{read_only:false; response_revision:20094; number_of_response:1; }","duration":"1.070053488s","start":"2026-05-24T10:47:10.52583Z","end":"2026-05-24T10:47:11.595883Z","steps":["trace[1388127587] 'process raft request' (duration: 1.069593985s)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:47:11.595924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T10:47:09.433785Z","time spent":"2.162133543s","remote":"127.0.0.1:37856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":724,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"warn","ts":"2026-05-24T10:47:11.596052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T10:47:10.525821Z","time spent":"1.07012681s","remote":"127.0.0.1:37970","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-05-24T10:47:11.596149Z","caller":"traceutil/trace.go:171","msg":"trace[1697186534] transaction","detail":"{read_only:false; response_revision:20095; number_of_response:1; }","duration":"974.053382ms","start":"2026-05-24T10:47:10.622085Z","end":"2026-05-24T10:47:11.596138Z","steps":["trace[1697186534] 'process raft request' (duration: 973.361523ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:47:11.596176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.070228773s","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":"warn","ts":"2026-05-24T10:47:11.59616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.162286177s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:701"} {"level":"warn","ts":"2026-05-24T10:47:11.596279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.15064127s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-24T10:47:11.59629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.070609733s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-24T10:47:11.596285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.598268439s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-24T10:47:11.596318Z","caller":"traceutil/trace.go:171","msg":"trace[316835667] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:20095; }","duration":"1.150689661s","start":"2026-05-24T10:47:10.445618Z","end":"2026-05-24T10:47:11.596307Z","steps":["trace[316835667] 'agreement among raft nodes before linearized reading' (duration: 1.150622089s)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:47:11.596366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"841.921269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.227\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-05-24T10:47:11.59639Z","caller":"traceutil/trace.go:171","msg":"trace[794374704] range","detail":"{range_begin:/registry/masterleases/199.204.45.227; range_end:; response_count:1; response_revision:20095; }","duration":"841.95322ms","start":"2026-05-24T10:47:10.754431Z","end":"2026-05-24T10:47:11.596384Z","steps":["trace[794374704] 'agreement among raft nodes before linearized reading' (duration: 841.914139ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:47:11.596389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T10:47:10.445612Z","time spent":"1.150759052s","remote":"127.0.0.1:37960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":31,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-05-24T10:47:11.596411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T10:47:10.754425Z","time spent":"841.97989ms","remote":"127.0.0.1:37718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":163,"request content":"key:\"/registry/masterleases/199.204.45.227\" "} {"level":"warn","ts":"2026-05-24T10:47:11.596393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.262581076s","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-05-24T10:47:11.596377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.534711377s","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-05-24T10:47:11.59645Z","caller":"traceutil/trace.go:171","msg":"trace[696534279] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20095; }","duration":"1.262659337s","start":"2026-05-24T10:47:10.333775Z","end":"2026-05-24T10:47:11.596434Z","steps":["trace[696534279] 'agreement among raft nodes before linearized reading' (duration: 1.262557544s)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:47:11.596485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T10:47:10.333738Z","time spent":"1.262740299s","remote":"127.0.0.1:37970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-24T10:47:11.596504Z","caller":"traceutil/trace.go:171","msg":"trace[903772298] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:20095; }","duration":"2.162613097s","start":"2026-05-24T10:47:09.433726Z","end":"2026-05-24T10:47:11.596339Z","steps":["trace[903772298] 'agreement among raft nodes before linearized reading' (duration: 2.16202559s)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:47:11.596635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"870.873598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-24T10:47:11.596655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.63457484s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-metadata-agent-default-8f2kz\" ","response":"range_response_count:1 size:14277"} {"level":"info","ts":"2026-05-24T10:47:11.596674Z","caller":"traceutil/trace.go:171","msg":"trace[543313517] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:20095; }","duration":"870.918468ms","start":"2026-05-24T10:47:10.725744Z","end":"2026-05-24T10:47:11.596663Z","steps":["trace[543313517] 'agreement among raft nodes before linearized reading' (duration: 870.871867ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T10:47:11.596681Z","caller":"traceutil/trace.go:171","msg":"trace[1729583611] range","detail":"{range_begin:/registry/pods/openstack/neutron-metadata-agent-default-8f2kz; range_end:; response_count:1; response_revision:20095; }","duration":"1.634603921s","start":"2026-05-24T10:47:09.962068Z","end":"2026-05-24T10:47:11.596672Z","steps":["trace[1729583611] 'agreement among raft nodes before linearized reading' (duration: 1.634536599s)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:47:11.596682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.634707994s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:701"} {"level":"warn","ts":"2026-05-24T10:47:11.596703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T10:47:09.962062Z","time spent":"1.634636621s","remote":"127.0.0.1:37882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":14300,"request content":"key:\"/registry/pods/openstack/neutron-metadata-agent-default-8f2kz\" "} {"level":"warn","ts":"2026-05-24T10:47:11.596706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T10:47:10.725739Z","time spent":"870.958389ms","remote":"127.0.0.1:37916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-05-24T10:47:11.596712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.574977638s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-05-24T10:47:11.596245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.953923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1854"} {"level":"info","ts":"2026-05-24T10:47:11.596758Z","caller":"traceutil/trace.go:171","msg":"trace[812194381] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20095; }","duration":"1.575033349s","start":"2026-05-24T10:47:10.021713Z","end":"2026-05-24T10:47:11.596746Z","steps":["trace[812194381] 'agreement among raft nodes before linearized reading' (duration: 1.574876224s)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:47:11.596206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.291872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:701"} {"level":"info","ts":"2026-05-24T10:47:11.596789Z","caller":"traceutil/trace.go:171","msg":"trace[1041467947] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20095; }","duration":"1.598379652s","start":"2026-05-24T10:47:09.99797Z","end":"2026-05-24T10:47:11.59635Z","steps":["trace[1041467947] 'agreement among raft nodes before linearized reading' (duration: 1.598123255s)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:47:11.596804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.634744625s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-l3-agent-default-p9zcq\" ","response":"range_response_count:1 size:18672"} {"level":"info","ts":"2026-05-24T10:47:11.596801Z","caller":"traceutil/trace.go:171","msg":"trace[1417039687] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:20095; }","duration":"430.519158ms","start":"2026-05-24T10:47:11.166255Z","end":"2026-05-24T10:47:11.596774Z","steps":["trace[1417039687] 'agreement among raft nodes before linearized reading' (duration: 429.963892ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:47:11.596838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T10:47:09.997963Z","time spent":"1.598862305s","remote":"127.0.0.1:37970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-24T10:47:11.596868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T10:47:11.16624Z","time spent":"430.609711ms","remote":"127.0.0.1:37854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":1877,"request content":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-05-24T10:47:11.596854Z","caller":"traceutil/trace.go:171","msg":"trace[203254172] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:20095; }","duration":"426.94125ms","start":"2026-05-24T10:47:11.16988Z","end":"2026-05-24T10:47:11.596821Z","steps":["trace[203254172] 'agreement among raft nodes before linearized reading' (duration: 426.291142ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:47:11.596405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.150690771s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-mwb5q\" ","response":"range_response_count:1 size:6531"} {"level":"warn","ts":"2026-05-24T10:47:11.596907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.634501998s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-dhcp-agent-default-9qmnm\" ","response":"range_response_count:1 size:18647"} {"level":"info","ts":"2026-05-24T10:47:11.596951Z","caller":"traceutil/trace.go:171","msg":"trace[1078761979] range","detail":"{range_begin:/registry/pods/openstack/keepalived-mwb5q; range_end:; response_count:1; response_revision:20095; }","duration":"1.151244396s","start":"2026-05-24T10:47:10.445698Z","end":"2026-05-24T10:47:11.596943Z","steps":["trace[1078761979] 'agreement among raft nodes before linearized reading' (duration: 1.150636579s)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:47:11.596955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.617448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-thfzv\" ","response":"range_response_count:1 size:20755"} {"level":"info","ts":"2026-05-24T10:47:11.596715Z","caller":"traceutil/trace.go:171","msg":"trace[1827698907] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:20095; }","duration":"1.634765066s","start":"2026-05-24T10:47:09.961941Z","end":"2026-05-24T10:47:11.596706Z","steps":["trace[1827698907] 'agreement among raft nodes before linearized reading' (duration: 1.634651863s)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:47:11.596969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T10:47:11.169866Z","time spent":"427.064553ms","remote":"127.0.0.1:37856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":724,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"info","ts":"2026-05-24T10:47:11.596997Z","caller":"traceutil/trace.go:171","msg":"trace[1661480482] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-thfzv; range_end:; response_count:1; response_revision:20095; }","duration":"398.67867ms","start":"2026-05-24T10:47:11.198307Z","end":"2026-05-24T10:47:11.596985Z","steps":["trace[1661480482] 'agreement among raft nodes before linearized reading' (duration: 398.554777ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:47:11.597011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T10:47:09.961926Z","time spent":"1.635072324s","remote":"127.0.0.1:37856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":724,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"info","ts":"2026-05-24T10:47:11.597018Z","caller":"traceutil/trace.go:171","msg":"trace[1477930286] range","detail":"{range_begin:/registry/pods/openstack/neutron-dhcp-agent-default-9qmnm; range_end:; response_count:1; response_revision:20095; }","duration":"1.634967361s","start":"2026-05-24T10:47:09.962026Z","end":"2026-05-24T10:47:11.596994Z","steps":["trace[1477930286] 'agreement among raft nodes before linearized reading' (duration: 1.634301292s)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:47:11.597034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T10:47:11.198295Z","time spent":"398.730921ms","remote":"127.0.0.1:37882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":20778,"request content":"key:\"/registry/pods/openstack/libvirt-libvirt-default-thfzv\" "} {"level":"info","ts":"2026-05-24T10:47:11.59659Z","caller":"traceutil/trace.go:171","msg":"trace[767254456] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20095; }","duration":"1.070381907s","start":"2026-05-24T10:47:10.525869Z","end":"2026-05-24T10:47:11.596251Z","steps":["trace[767254456] 'agreement among raft nodes before linearized reading' (duration: 1.07012638s)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:47:11.596656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T10:47:09.433713Z","time spent":"2.162920895s","remote":"127.0.0.1:37856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":724,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"warn","ts":"2026-05-24T10:47:11.597117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.635009052s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-05-24T10:47:11.597169Z","caller":"traceutil/trace.go:171","msg":"trace[1376721122] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:20095; }","duration":"1.635068054s","start":"2026-05-24T10:47:09.962091Z","end":"2026-05-24T10:47:11.597159Z","steps":["trace[1376721122] 'agreement among raft nodes before linearized reading' (duration: 1.634349254s)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:47:11.597113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T10:47:09.962013Z","time spent":"1.635081724s","remote":"127.0.0.1:37882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":18670,"request content":"key:\"/registry/pods/openstack/neutron-dhcp-agent-default-9qmnm\" "} {"level":"warn","ts":"2026-05-24T10:47:11.597197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T10:47:09.962086Z","time spent":"1.635102545s","remote":"127.0.0.1:37882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-24T10:47:11.596563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.181457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:701"} {"level":"info","ts":"2026-05-24T10:47:11.597238Z","caller":"traceutil/trace.go:171","msg":"trace[171530490] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:20095; }","duration":"431.880725ms","start":"2026-05-24T10:47:11.165351Z","end":"2026-05-24T10:47:11.597232Z","steps":["trace[171530490] 'agreement among raft nodes before linearized reading' (duration: 431.168586ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:47:11.597264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T10:47:11.165339Z","time spent":"431.918617ms","remote":"127.0.0.1:37856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":724,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"warn","ts":"2026-05-24T10:47:11.597037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"974.953166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-5x6mp\" ","response":"range_response_count:1 size:22101"} {"level":"warn","ts":"2026-05-24T10:47:11.597087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T10:47:10.525865Z","time spent":"1.07120564s","remote":"127.0.0.1:37970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-24T10:47:11.597381Z","caller":"traceutil/trace.go:171","msg":"trace[1170366629] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-5x6mp; range_end:; response_count:1; response_revision:20095; }","duration":"975.397459ms","start":"2026-05-24T10:47:10.621928Z","end":"2026-05-24T10:47:11.597326Z","steps":["trace[1170366629] 'agreement among raft nodes before linearized reading' (duration: 974.73037ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T10:47:11.596321Z","caller":"traceutil/trace.go:171","msg":"trace[1153863637] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20095; }","duration":"1.070637244s","start":"2026-05-24T10:47:10.525676Z","end":"2026-05-24T10:47:11.596314Z","steps":["trace[1153863637] 'agreement among raft nodes before linearized reading' (duration: 1.070590863s)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:47:11.597438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T10:47:10.525662Z","time spent":"1.071766146s","remote":"127.0.0.1:37676","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-05-24T10:47:11.597507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T10:47:10.621912Z","time spent":"975.511321ms","remote":"127.0.0.1:37882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22124,"request content":"key:\"/registry/pods/openstack/nova-compute-default-5x6mp\" "} {"level":"warn","ts":"2026-05-24T10:47:11.596213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T10:47:10.622075Z","time spent":"974.107293ms","remote":"127.0.0.1:37970","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-05-24T10:47:11.596475Z","caller":"traceutil/trace.go:171","msg":"trace[905985726] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:20095; }","duration":"1.5348252s","start":"2026-05-24T10:47:10.061631Z","end":"2026-05-24T10:47:11.596456Z","steps":["trace[905985726] 'agreement among raft nodes before linearized reading' (duration: 1.534681886s)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:47:11.597739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T10:47:10.061625Z","time spent":"1.536103886s","remote":"127.0.0.1:38148","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-05-24T10:47:11.596837Z","caller":"traceutil/trace.go:171","msg":"trace[34890902] range","detail":"{range_begin:/registry/pods/openstack/neutron-l3-agent-default-p9zcq; range_end:; response_count:1; response_revision:20095; }","duration":"1.634783736s","start":"2026-05-24T10:47:09.962043Z","end":"2026-05-24T10:47:11.596827Z","steps":["trace[34890902] 'agreement among raft nodes before linearized reading' (duration: 1.634691253s)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:47:11.597921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T10:47:09.962037Z","time spent":"1.635871656s","remote":"127.0.0.1:37882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":18695,"request content":"key:\"/registry/pods/openstack/neutron-l3-agent-default-p9zcq\" "} {"level":"warn","ts":"2026-05-24T10:47:11.596588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.213077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-24T10:47:11.597988Z","caller":"traceutil/trace.go:171","msg":"trace[439029142] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20095; }","duration":"432.634376ms","start":"2026-05-24T10:47:11.165346Z","end":"2026-05-24T10:47:11.597981Z","steps":["trace[439029142] 'agreement among raft nodes before linearized reading' (duration: 431.229747ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:47:11.598016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T10:47:11.16533Z","time spent":"432.676697ms","remote":"127.0.0.1:37688","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-05-24T10:47:11.596597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"974.37161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-24T10:47:11.59808Z","caller":"traceutil/trace.go:171","msg":"trace[959191061] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:20095; }","duration":"975.858601ms","start":"2026-05-24T10:47:10.622206Z","end":"2026-05-24T10:47:11.598065Z","steps":["trace[959191061] 'agreement among raft nodes before linearized reading' (duration: 974.36302ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:47:11.598129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T10:47:10.622202Z","time spent":"975.912242ms","remote":"127.0.0.1:58414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-05-24T10:47:11.598138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.422387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5165"} {"level":"warn","ts":"2026-05-24T10:47:11.596819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T10:47:10.021707Z","time spent":"1.57510163s","remote":"127.0.0.1:37970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-24T10:47:11.596975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T10:47:10.445695Z","time spent":"1.151273837s","remote":"127.0.0.1:37882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6554,"request content":"key:\"/registry/pods/openstack/keepalived-mwb5q\" "} {"level":"warn","ts":"2026-05-24T10:47:11.596152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.653171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-4hqqb\" ","response":"range_response_count:1 size:9792"} {"level":"info","ts":"2026-05-24T10:47:11.5984Z","caller":"traceutil/trace.go:171","msg":"trace[2100994423] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:20095; }","duration":"407.631177ms","start":"2026-05-24T10:47:11.190755Z","end":"2026-05-24T10:47:11.598386Z","steps":["trace[2100994423] 'agreement among raft nodes before linearized reading' (duration: 405.354464ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:47:11.598956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T10:47:11.190741Z","time spent":"408.200203ms","remote":"127.0.0.1:37882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":5188,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"info","ts":"2026-05-24T10:47:11.598744Z","caller":"traceutil/trace.go:171","msg":"trace[1032599370] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-4hqqb; range_end:; response_count:1; response_revision:20095; }","duration":"433.273734ms","start":"2026-05-24T10:47:11.165445Z","end":"2026-05-24T10:47:11.598719Z","steps":["trace[1032599370] 'agreement among raft nodes before linearized reading' (duration: 430.642052ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T10:47:11.599143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T10:47:11.165427Z","time spent":"433.698625ms","remote":"127.0.0.1:37882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9815,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-4hqqb\" "}