{"level":"warn","ts":"2026-08-04T21:35:31.326838Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-04T21:35:31.326927Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.77: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.77:2380","--initial-cluster=instance=https://199.204.45.77:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.77:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.77:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-08-04T21:35:31.326996Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-04T21:35:31.327004Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.77:2380"]} {"level":"info","ts":"2026-08-04T21:35:31.327023Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-04T21:35:31.327746Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.77:2379"]} {"level":"info","ts":"2026-08-04T21:35:31.327848Z","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.77:2380"],"listen-peer-urls":["https://199.204.45.77:2380"],"advertise-client-urls":["https://199.204.45.77:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.77:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.77:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-08-04T21:35:31.331275Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.210707ms"} {"level":"info","ts":"2026-08-04T21:35:31.337046Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9133559b3198d4ec","cluster-id":"60f1aee33b0786f5"} {"level":"info","ts":"2026-08-04T21:35:31.337113Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec switched to configuration voters=()"} {"level":"info","ts":"2026-08-04T21:35:31.337127Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became follower at term 0"} {"level":"info","ts":"2026-08-04T21:35:31.337137Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9133559b3198d4ec [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-04T21:35:31.337146Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became follower at term 1"} {"level":"info","ts":"2026-08-04T21:35:31.337174Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec switched to configuration voters=(10462800484352185580)"} {"level":"warn","ts":"2026-08-04T21:35:31.347268Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-04T21:35:31.348879Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-04T21:35:31.35012Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-08-04T21:35:31.352963Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9133559b3198d4ec","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-04T21:35:31.353119Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9133559b3198d4ec","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-04T21:35:31.353236Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-04T21:35:31.355795Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-04T21:35:31.355842Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-04T21:35:31.356929Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec switched to configuration voters=(10462800484352185580)"} {"level":"info","ts":"2026-08-04T21:35:31.357262Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"60f1aee33b0786f5","local-member-id":"9133559b3198d4ec","added-peer-id":"9133559b3198d4ec","added-peer-peer-urls":["https://199.204.45.77:2380"]} {"level":"info","ts":"2026-08-04T21:35:31.359377Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-04T21:35:31.359497Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.77:2380"} {"level":"info","ts":"2026-08-04T21:35:31.359532Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.77:2380"} {"level":"info","ts":"2026-08-04T21:35:31.359752Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9133559b3198d4ec","initial-advertise-peer-urls":["https://199.204.45.77:2380"],"listen-peer-urls":["https://199.204.45.77:2380"],"advertise-client-urls":["https://199.204.45.77:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.77:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-04T21:35:31.359797Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-04T21:35:32.037714Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec is starting a new election at term 1"} {"level":"info","ts":"2026-08-04T21:35:32.037794Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became pre-candidate at term 1"} {"level":"info","ts":"2026-08-04T21:35:32.037817Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec received MsgPreVoteResp from 9133559b3198d4ec at term 1"} {"level":"info","ts":"2026-08-04T21:35:32.037837Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became candidate at term 2"} {"level":"info","ts":"2026-08-04T21:35:32.037848Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec received MsgVoteResp from 9133559b3198d4ec at term 2"} {"level":"info","ts":"2026-08-04T21:35:32.037864Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became leader at term 2"} {"level":"info","ts":"2026-08-04T21:35:32.037887Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9133559b3198d4ec elected leader 9133559b3198d4ec at term 2"} {"level":"info","ts":"2026-08-04T21:35:32.038929Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9133559b3198d4ec","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.77:2379]}","request-path":"/0/members/9133559b3198d4ec/attributes","cluster-id":"60f1aee33b0786f5","publish-timeout":"7s"} {"level":"info","ts":"2026-08-04T21:35:32.038966Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-04T21:35:32.038974Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-04T21:35:32.039125Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-04T21:35:32.039355Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-04T21:35:32.03944Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-04T21:35:32.040166Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"60f1aee33b0786f5","local-member-id":"9133559b3198d4ec","cluster-version":"3.5"} {"level":"info","ts":"2026-08-04T21:35:32.04033Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-04T21:35:32.040374Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-04T21:35:32.043125Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-04T21:35:32.043281Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.77:2379"} {"level":"warn","ts":"2026-08-04T21:36:13.06215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.163553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-lzc9k\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-08-04T21:36:13.062345Z","caller":"traceutil/trace.go:171","msg":"trace[1721709808] range","detail":"{range_begin:/registry/pods/kube-system/cilium-lzc9k; range_end:; response_count:1; response_revision:589; }","duration":"125.435139ms","start":"2026-08-04T21:36:12.936866Z","end":"2026-08-04T21:36:13.062301Z","steps":["trace[1721709808] 'range keys from in-memory index tree' (duration: 124.830906ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T21:36:13.448866Z","caller":"traceutil/trace.go:171","msg":"trace[1143363268] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"256.665408ms","start":"2026-08-04T21:36:13.192146Z","end":"2026-08-04T21:36:13.448811Z","steps":["trace[1143363268] 'process raft request' (duration: 250.04122ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T21:36:13.785263Z","caller":"traceutil/trace.go:171","msg":"trace[997354727] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"276.619341ms","start":"2026-08-04T21:36:13.508588Z","end":"2026-08-04T21:36:13.785207Z","steps":["trace[997354727] 'process raft request' (duration: 276.406146ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T21:36:13.981077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.109483ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-04T21:36:13.981219Z","caller":"traceutil/trace.go:171","msg":"trace[1688732097] linearizableReadLoop","detail":"{readStateIndex:614; appliedIndex:613; }","duration":"185.676606ms","start":"2026-08-04T21:36:13.795519Z","end":"2026-08-04T21:36:13.981196Z","steps":["trace[1688732097] 'read index received' (duration: 30.540841ms)","trace[1688732097] 'applied index is now lower than readState.Index' (duration: 155.134195ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T21:36:13.981337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.790498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T21:36:13.981391Z","caller":"traceutil/trace.go:171","msg":"trace[2068737365] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:594; }","duration":"185.909731ms","start":"2026-08-04T21:36:13.795464Z","end":"2026-08-04T21:36:13.981374Z","steps":["trace[2068737365] 'agreement among raft nodes before linearized reading' (duration: 185.799298ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T21:36:13.981504Z","caller":"traceutil/trace.go:171","msg":"trace[668016713] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"190.455056ms","start":"2026-08-04T21:36:13.791016Z","end":"2026-08-04T21:36:13.981471Z","steps":["trace[668016713] 'process raft request' (duration: 35.120818ms)","trace[668016713] 'compare' (duration: 153.944098ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T21:36:31.421652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.546595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-08-04T21:36:31.421771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.761488ms","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-08-04T21:36:31.421796Z","caller":"traceutil/trace.go:171","msg":"trace[574058870] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:762; }","duration":"185.744519ms","start":"2026-08-04T21:36:31.236Z","end":"2026-08-04T21:36:31.421745Z","steps":["trace[574058870] 'range keys from in-memory index tree' (duration: 185.278868ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T21:36:31.42185Z","caller":"traceutil/trace.go:171","msg":"trace[670410382] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:762; }","duration":"183.868129ms","start":"2026-08-04T21:36:31.23796Z","end":"2026-08-04T21:36:31.421828Z","steps":["trace[670410382] 'range keys from in-memory index tree' (duration: 183.594194ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T21:37:19.450495Z","caller":"traceutil/trace.go:171","msg":"trace[1843987069] transaction","detail":"{read_only:false; response_revision:1407; number_of_response:1; }","duration":"110.660176ms","start":"2026-08-04T21:37:19.339811Z","end":"2026-08-04T21:37:19.450471Z","steps":["trace[1843987069] 'process raft request' (duration: 110.527063ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T21:37:46.094381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.030164ms","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":"warn","ts":"2026-08-04T21:37:46.094443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.171679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-04T21:37:46.094481Z","caller":"traceutil/trace.go:171","msg":"trace[823680859] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1829; }","duration":"260.192038ms","start":"2026-08-04T21:37:45.834271Z","end":"2026-08-04T21:37:46.094463Z","steps":["trace[823680859] 'range keys from in-memory index tree' (duration: 259.836451ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T21:37:46.094526Z","caller":"traceutil/trace.go:171","msg":"trace[1877556242] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1829; }","duration":"261.305681ms","start":"2026-08-04T21:37:45.833201Z","end":"2026-08-04T21:37:46.094506Z","steps":["trace[1877556242] 'range keys from in-memory index tree' (duration: 260.967394ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T21:37:46.09444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.593801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5cf3fe60-d6cc-48f5-9305-61ed8d0f1513\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-08-04T21:37:46.094655Z","caller":"traceutil/trace.go:171","msg":"trace[1320013137] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5cf3fe60-d6cc-48f5-9305-61ed8d0f1513; range_end:; response_count:1; response_revision:1829; }","duration":"226.828197ms","start":"2026-08-04T21:37:45.867788Z","end":"2026-08-04T21:37:46.094616Z","steps":["trace[1320013137] 'range keys from in-memory index tree' (duration: 226.406667ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T21:37:46.46334Z","caller":"traceutil/trace.go:171","msg":"trace[70676815] transaction","detail":"{read_only:false; response_revision:1832; number_of_response:1; }","duration":"277.001428ms","start":"2026-08-04T21:37:46.186316Z","end":"2026-08-04T21:37:46.463317Z","steps":["trace[70676815] 'process raft request' (duration: 202.055093ms)","trace[70676815] 'compare' (duration: 74.69331ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T21:37:55.847849Z","caller":"traceutil/trace.go:171","msg":"trace[791365942] transaction","detail":"{read_only:false; response_revision:1923; number_of_response:1; }","duration":"100.594817ms","start":"2026-08-04T21:37:55.747208Z","end":"2026-08-04T21:37:55.847803Z","steps":["trace[791365942] 'process raft request' (duration: 99.607757ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T21:38:38.143189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.849966ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-04T21:38:38.143338Z","caller":"traceutil/trace.go:171","msg":"trace[906185] transaction","detail":"{read_only:false; response_revision:2168; number_of_response:1; }","duration":"344.094414ms","start":"2026-08-04T21:38:37.799204Z","end":"2026-08-04T21:38:38.143298Z","steps":["trace[906185] 'process raft request' (duration: 63.050854ms)","trace[906185] 'compare' (duration: 280.614861ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T21:38:38.14337Z","caller":"traceutil/trace.go:171","msg":"trace[1815479277] transaction","detail":"{read_only:false; response_revision:2169; number_of_response:1; }","duration":"146.633701ms","start":"2026-08-04T21:38:37.996722Z","end":"2026-08-04T21:38:38.143355Z","steps":["trace[1815479277] 'process raft request' (duration: 146.57463ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T21:38:38.14342Z","caller":"traceutil/trace.go:171","msg":"trace[835167731] linearizableReadLoop","detail":"{readStateIndex:2250; appliedIndex:2249; }","duration":"318.333601ms","start":"2026-08-04T21:38:37.825022Z","end":"2026-08-04T21:38:38.143355Z","steps":["trace[835167731] 'read index received' (duration: 37.300821ms)","trace[835167731] 'applied index is now lower than readState.Index' (duration: 281.02582ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T21:38:38.143456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T21:38:37.799182Z","time spent":"344.222927ms","remote":"127.0.0.1:50944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T21:38:38.143654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.599956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-04T21:38:38.143742Z","caller":"traceutil/trace.go:171","msg":"trace[602600397] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2169; }","duration":"318.705379ms","start":"2026-08-04T21:38:37.825015Z","end":"2026-08-04T21:38:38.14372Z","steps":["trace[602600397] 'agreement among raft nodes before linearized reading' (duration: 318.411173ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T21:38:38.143778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T21:38:37.824975Z","time spent":"318.796581ms","remote":"127.0.0.1:39880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-08-04T21:40:05.116514Z","caller":"traceutil/trace.go:171","msg":"trace[1313114133] linearizableReadLoop","detail":"{readStateIndex:2939; appliedIndex:2937; }","duration":"113.255613ms","start":"2026-08-04T21:40:05.003233Z","end":"2026-08-04T21:40:05.116488Z","steps":["trace[1313114133] 'read index received' (duration: 5.700178ms)","trace[1313114133] 'applied index is now lower than readState.Index' (duration: 107.554575ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T21:40:05.116689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.422056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T21:40:05.116724Z","caller":"traceutil/trace.go:171","msg":"trace[1906839204] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:2831; }","duration":"113.534319ms","start":"2026-08-04T21:40:05.003179Z","end":"2026-08-04T21:40:05.116714Z","steps":["trace[1906839204] 'agreement among raft nodes before linearized reading' (duration: 113.419936ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T21:40:05.116725Z","caller":"traceutil/trace.go:171","msg":"trace[348938118] transaction","detail":"{read_only:false; response_revision:2831; number_of_response:1; }","duration":"159.699479ms","start":"2026-08-04T21:40:04.95695Z","end":"2026-08-04T21:40:05.116649Z","steps":["trace[348938118] 'process raft request' (duration: 116.615893ms)","trace[348938118] 'compare' (duration: 42.78947ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T21:40:05.291918Z","caller":"traceutil/trace.go:171","msg":"trace[991689921] linearizableReadLoop","detail":"{readStateIndex:2940; appliedIndex:2939; }","duration":"102.975421ms","start":"2026-08-04T21:40:05.188916Z","end":"2026-08-04T21:40:05.291891Z","steps":["trace[991689921] 'read index received' (duration: 92.140438ms)","trace[991689921] 'applied index is now lower than readState.Index' (duration: 10.833143ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T21:40:05.292107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.174535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-08-04T21:40:05.292181Z","caller":"traceutil/trace.go:171","msg":"trace[1202702191] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2831; }","duration":"103.269857ms","start":"2026-08-04T21:40:05.188896Z","end":"2026-08-04T21:40:05.292165Z","steps":["trace[1202702191] 'agreement among raft nodes before linearized reading' (duration: 103.078233ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T21:40:10.953486Z","caller":"traceutil/trace.go:171","msg":"trace[957303952] linearizableReadLoop","detail":"{readStateIndex:2971; appliedIndex:2970; }","duration":"111.365941ms","start":"2026-08-04T21:40:10.8421Z","end":"2026-08-04T21:40:10.953466Z","steps":["trace[957303952] 'read index received' (duration: 111.157186ms)","trace[957303952] 'applied index is now lower than readState.Index' (duration: 208.175µs)"],"step_count":2} {"level":"warn","ts":"2026-08-04T21:40:10.953641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.531075ms","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-08-04T21:40:10.953665Z","caller":"traceutil/trace.go:171","msg":"trace[341468072] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2861; }","duration":"111.617177ms","start":"2026-08-04T21:40:10.84204Z","end":"2026-08-04T21:40:10.953657Z","steps":["trace[341468072] 'agreement among raft nodes before linearized reading' (duration: 111.504814ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T21:40:10.953729Z","caller":"traceutil/trace.go:171","msg":"trace[1819533435] transaction","detail":"{read_only:false; response_revision:2861; number_of_response:1; }","duration":"115.987806ms","start":"2026-08-04T21:40:10.837543Z","end":"2026-08-04T21:40:10.953531Z","steps":["trace[1819533435] 'process raft request' (duration: 115.791661ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T21:40:11.22196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.354772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-04T21:40:11.222111Z","caller":"traceutil/trace.go:171","msg":"trace[2037501132] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:2862; }","duration":"238.473305ms","start":"2026-08-04T21:40:10.983563Z","end":"2026-08-04T21:40:11.222036Z","steps":["trace[2037501132] 'count revisions from in-memory index tree' (duration: 238.260981ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T21:40:11.222169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.201011ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-04T21:40:11.222277Z","caller":"traceutil/trace.go:171","msg":"trace[2088097379] transaction","detail":"{read_only:false; response_revision:2863; number_of_response:1; }","duration":"189.624674ms","start":"2026-08-04T21:40:11.032632Z","end":"2026-08-04T21:40:11.222257Z","steps":["trace[2088097379] 'process raft request' (duration: 50.25363ms)","trace[2088097379] 'compare' (duration: 138.951496ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T21:40:11.289483Z","caller":"traceutil/trace.go:171","msg":"trace[744811699] linearizableReadLoop","detail":"{readStateIndex:2974; appliedIndex:2973; }","duration":"100.702402ms","start":"2026-08-04T21:40:11.188761Z","end":"2026-08-04T21:40:11.289463Z","steps":["trace[744811699] 'read index received' (duration: 100.624221ms)","trace[744811699] 'applied index is now lower than readState.Index' (duration: 77.031µs)"],"step_count":2} {"level":"info","ts":"2026-08-04T21:40:11.289543Z","caller":"traceutil/trace.go:171","msg":"trace[345063590] transaction","detail":"{read_only:false; response_revision:2864; number_of_response:1; }","duration":"147.805367ms","start":"2026-08-04T21:40:11.141708Z","end":"2026-08-04T21:40:11.289514Z","steps":["trace[345063590] 'process raft request' (duration: 147.627484ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T21:40:11.289789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.009939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-08-04T21:40:11.289881Z","caller":"traceutil/trace.go:171","msg":"trace[1512132502] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2864; }","duration":"101.149472ms","start":"2026-08-04T21:40:11.188711Z","end":"2026-08-04T21:40:11.28986Z","steps":["trace[1512132502] 'agreement among raft nodes before linearized reading' (duration: 100.890757ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T21:44:02.064416Z","caller":"traceutil/trace.go:171","msg":"trace[36642927] transaction","detail":"{read_only:false; response_revision:4829; number_of_response:1; }","duration":"105.842641ms","start":"2026-08-04T21:44:01.958556Z","end":"2026-08-04T21:44:02.064399Z","steps":["trace[36642927] 'process raft request' (duration: 105.710519ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T21:44:02.157894Z","caller":"traceutil/trace.go:171","msg":"trace[1692224733] transaction","detail":"{read_only:false; response_revision:4830; number_of_response:1; }","duration":"100.435551ms","start":"2026-08-04T21:44:02.057426Z","end":"2026-08-04T21:44:02.157861Z","steps":["trace[1692224733] 'process raft request' (duration: 100.188166ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T21:44:07.121496Z","caller":"traceutil/trace.go:171","msg":"trace[228496105] transaction","detail":"{read_only:false; response_revision:5028; number_of_response:1; }","duration":"119.201244ms","start":"2026-08-04T21:44:07.002272Z","end":"2026-08-04T21:44:07.121473Z","steps":["trace[228496105] 'process raft request' (duration: 119.075232ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T21:44:07.291517Z","caller":"traceutil/trace.go:171","msg":"trace[519819573] transaction","detail":"{read_only:false; response_revision:5031; number_of_response:1; }","duration":"164.398843ms","start":"2026-08-04T21:44:07.127087Z","end":"2026-08-04T21:44:07.291486Z","steps":["trace[519819573] 'process raft request' (duration: 164.351262ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T21:44:07.292043Z","caller":"traceutil/trace.go:171","msg":"trace[1085319999] transaction","detail":"{read_only:false; response_revision:5029; number_of_response:1; }","duration":"281.889413ms","start":"2026-08-04T21:44:07.010133Z","end":"2026-08-04T21:44:07.292022Z","steps":["trace[1085319999] 'process raft request' (duration: 251.762505ms)","trace[1085319999] 'compare' (duration: 28.29428ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T21:44:07.292526Z","caller":"traceutil/trace.go:171","msg":"trace[1872726016] transaction","detail":"{read_only:false; response_revision:5030; number_of_response:1; }","duration":"218.467071ms","start":"2026-08-04T21:44:07.074014Z","end":"2026-08-04T21:44:07.292481Z","steps":["trace[1872726016] 'process raft request' (duration: 217.346929ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T21:44:07.292707Z","caller":"traceutil/trace.go:171","msg":"trace[395716932] linearizableReadLoop","detail":"{readStateIndex:5222; appliedIndex:5220; }","duration":"206.348993ms","start":"2026-08-04T21:44:07.086344Z","end":"2026-08-04T21:44:07.292693Z","steps":["trace[395716932] 'read index received' (duration: 35.314684ms)","trace[395716932] 'applied index is now lower than readState.Index' (duration: 171.032749ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T21:44:07.292865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.493625ms","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-08-04T21:44:07.29291Z","caller":"traceutil/trace.go:171","msg":"trace[132057226] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:5031; }","duration":"206.599018ms","start":"2026-08-04T21:44:07.086301Z","end":"2026-08-04T21:44:07.2929Z","steps":["trace[132057226] 'agreement among raft nodes before linearized reading' (duration: 206.495316ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T21:44:07.292933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.458031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-04T21:44:07.29302Z","caller":"traceutil/trace.go:171","msg":"trace[919860508] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:5031; }","duration":"161.815599ms","start":"2026-08-04T21:44:07.131183Z","end":"2026-08-04T21:44:07.292999Z","steps":["trace[919860508] 'agreement among raft nodes before linearized reading' (duration: 161.647595ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T21:44:07.293026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.960741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-04T21:44:07.293034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.999234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c893fc00-e741-462d-86ba-cc025145d4c6\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-08-04T21:44:07.293074Z","caller":"traceutil/trace.go:171","msg":"trace[1475035811] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:5031; }","duration":"139.051422ms","start":"2026-08-04T21:44:07.154009Z","end":"2026-08-04T21:44:07.293061Z","steps":["trace[1475035811] 'agreement among raft nodes before linearized reading' (duration: 138.9359ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T21:44:07.293122Z","caller":"traceutil/trace.go:171","msg":"trace[1680165816] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c893fc00-e741-462d-86ba-cc025145d4c6; range_end:; response_count:1; response_revision:5031; }","duration":"105.159057ms","start":"2026-08-04T21:44:07.187942Z","end":"2026-08-04T21:44:07.293101Z","steps":["trace[1680165816] 'agreement among raft nodes before linearized reading' (duration: 104.895062ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T21:44:07.563661Z","caller":"traceutil/trace.go:171","msg":"trace[848935120] transaction","detail":"{read_only:false; response_revision:5045; number_of_response:1; }","duration":"116.143984ms","start":"2026-08-04T21:44:07.44748Z","end":"2026-08-04T21:44:07.563624Z","steps":["trace[848935120] 'process raft request' (duration: 115.664974ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T21:44:07.575434Z","caller":"traceutil/trace.go:171","msg":"trace[868439999] transaction","detail":"{read_only:false; response_revision:5046; number_of_response:1; }","duration":"115.650262ms","start":"2026-08-04T21:44:07.459759Z","end":"2026-08-04T21:44:07.575409Z","steps":["trace[868439999] 'process raft request' (duration: 115.05067ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T21:44:09.605578Z","caller":"traceutil/trace.go:171","msg":"trace[538520955] linearizableReadLoop","detail":"{readStateIndex:5283; appliedIndex:5282; }","duration":"139.160225ms","start":"2026-08-04T21:44:09.46639Z","end":"2026-08-04T21:44:09.60555Z","steps":["trace[538520955] 'read index received' (duration: 117.704575ms)","trace[538520955] 'applied index is now lower than readState.Index' (duration: 21.45466ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T21:44:09.60562Z","caller":"traceutil/trace.go:171","msg":"trace[1341285616] transaction","detail":"{read_only:false; response_revision:5090; number_of_response:1; }","duration":"159.634725ms","start":"2026-08-04T21:44:09.445945Z","end":"2026-08-04T21:44:09.60558Z","steps":["trace[1341285616] 'process raft request' (duration: 138.133274ms)","trace[1341285616] 'compare' (duration: 21.319477ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T21:44:09.605805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.38963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:17475"} {"level":"info","ts":"2026-08-04T21:44:09.605919Z","caller":"traceutil/trace.go:171","msg":"trace[1936088418] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:5090; }","duration":"139.546652ms","start":"2026-08-04T21:44:09.466361Z","end":"2026-08-04T21:44:09.605907Z","steps":["trace[1936088418] 'agreement among raft nodes before linearized reading' (duration: 139.288447ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T21:44:09.619591Z","caller":"traceutil/trace.go:171","msg":"trace[204554360] transaction","detail":"{read_only:false; response_revision:5091; number_of_response:1; }","duration":"149.113369ms","start":"2026-08-04T21:44:09.470452Z","end":"2026-08-04T21:44:09.619565Z","steps":["trace[204554360] 'process raft request' (duration: 148.988486ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T21:44:20.059935Z","caller":"traceutil/trace.go:171","msg":"trace[1798155046] linearizableReadLoop","detail":"{readStateIndex:5490; appliedIndex:5489; }","duration":"247.183672ms","start":"2026-08-04T21:44:19.812714Z","end":"2026-08-04T21:44:20.059897Z","steps":["trace[1798155046] 'read index received' (duration: 178.435651ms)","trace[1798155046] 'applied index is now lower than readState.Index' (duration: 68.746351ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T21:44:20.059993Z","caller":"traceutil/trace.go:171","msg":"trace[549727436] transaction","detail":"{read_only:false; response_revision:5290; number_of_response:1; }","duration":"248.999758ms","start":"2026-08-04T21:44:19.810969Z","end":"2026-08-04T21:44:20.059969Z","steps":["trace[549727436] 'process raft request' (duration: 180.266198ms)","trace[549727436] 'compare' (duration: 68.404493ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T21:44:20.060098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.374805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T21:44:20.060122Z","caller":"traceutil/trace.go:171","msg":"trace[429499023] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5290; }","duration":"247.412766ms","start":"2026-08-04T21:44:19.812704Z","end":"2026-08-04T21:44:20.060117Z","steps":["trace[429499023] 'agreement among raft nodes before linearized reading' (duration: 247.331224ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T21:44:28.453634Z","caller":"traceutil/trace.go:171","msg":"trace[143250554] transaction","detail":"{read_only:false; response_revision:5469; number_of_response:1; }","duration":"130.50007ms","start":"2026-08-04T21:44:28.323115Z","end":"2026-08-04T21:44:28.453615Z","steps":["trace[143250554] 'process raft request' (duration: 130.394648ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T21:44:38.915083Z","caller":"traceutil/trace.go:171","msg":"trace[972222088] transaction","detail":"{read_only:false; response_revision:5625; number_of_response:1; }","duration":"140.812312ms","start":"2026-08-04T21:44:38.774237Z","end":"2026-08-04T21:44:38.915049Z","steps":["trace[972222088] 'process raft request' (duration: 51.764614ms)","trace[972222088] 'compare' (duration: 88.834724ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T21:44:38.91529Z","caller":"traceutil/trace.go:171","msg":"trace[470517804] transaction","detail":"{read_only:false; response_revision:5627; number_of_response:1; }","duration":"140.488155ms","start":"2026-08-04T21:44:38.774789Z","end":"2026-08-04T21:44:38.915278Z","steps":["trace[470517804] 'process raft request' (duration: 140.295872ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T21:44:38.915344Z","caller":"traceutil/trace.go:171","msg":"trace[112954295] transaction","detail":"{read_only:false; response_revision:5631; number_of_response:1; }","duration":"111.901578ms","start":"2026-08-04T21:44:38.803426Z","end":"2026-08-04T21:44:38.915327Z","steps":["trace[112954295] 'process raft request' (duration: 111.857047ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T21:44:38.915296Z","caller":"traceutil/trace.go:171","msg":"trace[994309610] transaction","detail":"{read_only:false; response_revision:5626; number_of_response:1; }","duration":"140.476435ms","start":"2026-08-04T21:44:38.774773Z","end":"2026-08-04T21:44:38.91525Z","steps":["trace[994309610] 'process raft request' (duration: 140.207179ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T21:44:38.915484Z","caller":"traceutil/trace.go:171","msg":"trace[589840104] transaction","detail":"{read_only:false; response_revision:5629; number_of_response:1; }","duration":"139.496065ms","start":"2026-08-04T21:44:38.77598Z","end":"2026-08-04T21:44:38.915476Z","steps":["trace[589840104] 'process raft request' (duration: 139.204359ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T21:44:38.915574Z","caller":"traceutil/trace.go:171","msg":"trace[102964997] linearizableReadLoop","detail":"{readStateIndex:5838; appliedIndex:5833; }","duration":"114.646414ms","start":"2026-08-04T21:44:38.800906Z","end":"2026-08-04T21:44:38.915552Z","steps":["trace[102964997] 'read index received' (duration: 25.107165ms)","trace[102964997] 'applied index is now lower than readState.Index' (duration: 89.537599ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T21:44:38.915452Z","caller":"traceutil/trace.go:171","msg":"trace[1318147950] transaction","detail":"{read_only:false; response_revision:5628; number_of_response:1; }","duration":"139.982325ms","start":"2026-08-04T21:44:38.77546Z","end":"2026-08-04T21:44:38.915442Z","steps":["trace[1318147950] 'process raft request' (duration: 139.679448ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T21:44:38.915655Z","caller":"traceutil/trace.go:171","msg":"trace[1827387604] transaction","detail":"{read_only:false; response_revision:5630; number_of_response:1; }","duration":"113.086033ms","start":"2026-08-04T21:44:38.80255Z","end":"2026-08-04T21:44:38.915636Z","steps":["trace[1827387604] 'process raft request' (duration: 112.681074ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T21:44:38.915777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.856359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-6a736f11-13b2-439a-b0d7-f9ca9732ca09\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-08-04T21:44:38.915812Z","caller":"traceutil/trace.go:171","msg":"trace[1444867306] range","detail":"{range_begin:/registry/persistentvolumes/pvc-6a736f11-13b2-439a-b0d7-f9ca9732ca09; range_end:; response_count:1; response_revision:5631; }","duration":"114.946651ms","start":"2026-08-04T21:44:38.800853Z","end":"2026-08-04T21:44:38.9158Z","steps":["trace[1444867306] 'agreement among raft nodes before linearized reading' (duration: 114.819788ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T21:45:32.065797Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2978} {"level":"info","ts":"2026-08-04T21:45:32.132287Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2978,"took":"64.886164ms","hash":3868437147,"current-db-size-bytes":58052608,"current-db-size":"58 MB","current-db-size-in-use-bytes":34140160,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-04T21:45:32.13235Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3868437147,"revision":2978,"compact-revision":-1} {"level":"info","ts":"2026-08-04T21:49:51.799109Z","caller":"traceutil/trace.go:171","msg":"trace[1464020976] transaction","detail":"{read_only:false; response_revision:7863; number_of_response:1; }","duration":"107.16278ms","start":"2026-08-04T21:49:51.691902Z","end":"2026-08-04T21:49:51.799065Z","steps":["trace[1464020976] 'process raft request' (duration: 95.003551ms)","trace[1464020976] 'compare' (duration: 11.777601ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T21:49:51.799139Z","caller":"traceutil/trace.go:171","msg":"trace[782759383] linearizableReadLoop","detail":"{readStateIndex:8144; appliedIndex:8142; }","duration":"104.409974ms","start":"2026-08-04T21:49:51.694706Z","end":"2026-08-04T21:49:51.799116Z","steps":["trace[782759383] 'read index received' (duration: 18.591933ms)","trace[782759383] 'applied index is now lower than readState.Index' (duration: 85.816671ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T21:49:51.799636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.907924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/barbican-ks-endpoints\" ","response":"range_response_count:1 size:12707"} {"level":"info","ts":"2026-08-04T21:49:51.799686Z","caller":"traceutil/trace.go:171","msg":"trace[44130728] range","detail":"{range_begin:/registry/jobs/openstack/barbican-ks-endpoints; range_end:; response_count:1; response_revision:7864; }","duration":"104.997325ms","start":"2026-08-04T21:49:51.694676Z","end":"2026-08-04T21:49:51.799673Z","steps":["trace[44130728] 'agreement among raft nodes before linearized reading' (duration: 104.607267ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T21:50:32.086391Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6060} {"level":"info","ts":"2026-08-04T21:50:32.246667Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6060,"took":"158.77704ms","hash":2778625000,"current-db-size-bytes":58052608,"current-db-size":"58 MB","current-db-size-in-use-bytes":21299200,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-08-04T21:50:32.246754Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2778625000,"revision":6060,"compact-revision":2978} {"level":"info","ts":"2026-08-04T21:50:59.538172Z","caller":"traceutil/trace.go:171","msg":"trace[268979034] linearizableReadLoop","detail":"{readStateIndex:8716; appliedIndex:8715; }","duration":"451.97255ms","start":"2026-08-04T21:50:59.086174Z","end":"2026-08-04T21:50:59.538147Z","steps":["trace[268979034] 'read index received' (duration: 451.710755ms)","trace[268979034] 'applied index is now lower than readState.Index' (duration: 260.545µs)"],"step_count":2} {"level":"info","ts":"2026-08-04T21:50:59.538222Z","caller":"traceutil/trace.go:171","msg":"trace[717660720] transaction","detail":"{read_only:false; response_revision:8417; number_of_response:1; }","duration":"518.316533ms","start":"2026-08-04T21:50:59.019868Z","end":"2026-08-04T21:50:59.538185Z","steps":["trace[717660720] 'process raft request' (duration: 518.038677ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T21:50:59.538396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T21:50:59.019848Z","time spent":"518.448526ms","remote":"127.0.0.1:50944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T21:50:59.538397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.191224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T21:50:59.53852Z","caller":"traceutil/trace.go:171","msg":"trace[1212647810] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:8417; }","duration":"452.370788ms","start":"2026-08-04T21:50:59.086122Z","end":"2026-08-04T21:50:59.538493Z","steps":["trace[1212647810] 'agreement among raft nodes before linearized reading' (duration: 452.173584ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T21:50:59.53859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T21:50:59.086104Z","time spent":"452.468189ms","remote":"127.0.0.1:59324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"warn","ts":"2026-08-04T21:50:59.539555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.798959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-04T21:50:59.539617Z","caller":"traceutil/trace.go:171","msg":"trace[573959414] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:8418; }","duration":"194.902691ms","start":"2026-08-04T21:50:59.3447Z","end":"2026-08-04T21:50:59.539603Z","steps":["trace[573959414] 'agreement among raft nodes before linearized reading' (duration: 194.775159ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T21:50:59.539642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.685971ms","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-08-04T21:50:59.539676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.322619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-04T21:50:59.539709Z","caller":"traceutil/trace.go:171","msg":"trace[173289919] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8418; }","duration":"389.803743ms","start":"2026-08-04T21:50:59.149888Z","end":"2026-08-04T21:50:59.539692Z","steps":["trace[173289919] 'agreement among raft nodes before linearized reading' (duration: 389.476516ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T21:50:59.539737Z","caller":"traceutil/trace.go:171","msg":"trace[2087268359] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8418; }","duration":"326.431232ms","start":"2026-08-04T21:50:59.213291Z","end":"2026-08-04T21:50:59.539722Z","steps":["trace[2087268359] 'agreement among raft nodes before linearized reading' (duration: 326.247868ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T21:50:59.539775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T21:50:59.213278Z","time spent":"326.488802ms","remote":"127.0.0.1:50944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-04T21:50:59.539775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T21:50:59.149863Z","time spent":"389.899385ms","remote":"127.0.0.1:50944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-04T21:50:59.539815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.553708ms","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-08-04T21:50:59.539556Z","caller":"traceutil/trace.go:171","msg":"trace[471393292] transaction","detail":"{read_only:false; response_revision:8418; number_of_response:1; }","duration":"414.895809ms","start":"2026-08-04T21:50:59.124639Z","end":"2026-08-04T21:50:59.539535Z","steps":["trace[471393292] 'process raft request' (duration: 414.591243ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T21:50:59.539916Z","caller":"traceutil/trace.go:171","msg":"trace[1525037493] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8418; }","duration":"352.701562ms","start":"2026-08-04T21:50:59.187191Z","end":"2026-08-04T21:50:59.539893Z","steps":["trace[1525037493] 'agreement among raft nodes before linearized reading' (duration: 352.367454ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T21:50:59.539968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T21:50:59.187176Z","time spent":"352.781463ms","remote":"127.0.0.1:50944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-04T21:50:59.540047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T21:50:59.124616Z","time spent":"415.301579ms","remote":"127.0.0.1:50944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T21:51:02.354222Z","caller":"traceutil/trace.go:171","msg":"trace[1625753910] transaction","detail":"{read_only:false; response_revision:8433; number_of_response:1; }","duration":"258.764873ms","start":"2026-08-04T21:51:02.095351Z","end":"2026-08-04T21:51:02.354116Z","steps":["trace[1625753910] 'process raft request' (duration: 258.601059ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T21:51:02.366947Z","caller":"traceutil/trace.go:171","msg":"trace[1404333869] transaction","detail":"{read_only:false; response_revision:8434; number_of_response:1; }","duration":"179.26485ms","start":"2026-08-04T21:51:02.187661Z","end":"2026-08-04T21:51:02.366926Z","steps":["trace[1404333869] 'process raft request' (duration: 179.047366ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T21:51:17.751099Z","caller":"traceutil/trace.go:171","msg":"trace[413443857] transaction","detail":"{read_only:false; response_revision:8520; number_of_response:1; }","duration":"131.003579ms","start":"2026-08-04T21:51:17.620073Z","end":"2026-08-04T21:51:17.751077Z","steps":["trace[413443857] 'process raft request' (duration: 130.850986ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T21:51:18.334041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T21:51:17.881889Z","time spent":"452.144193ms","remote":"127.0.0.1:39896","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-08-04T21:51:18.334986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.552339ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-04T21:51:18.335435Z","caller":"traceutil/trace.go:171","msg":"trace[1928487107] linearizableReadLoop","detail":"{readStateIndex:8824; appliedIndex:8822; }","duration":"647.976724ms","start":"2026-08-04T21:51:17.687099Z","end":"2026-08-04T21:51:18.335076Z","steps":["trace[1928487107] 'read index received' (duration: 63.749958ms)","trace[1928487107] 'applied index is now lower than readState.Index' (duration: 584.225346ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T21:51:18.33555Z","caller":"traceutil/trace.go:171","msg":"trace[1124996953] transaction","detail":"{read_only:false; response_revision:8521; number_of_response:1; }","duration":"655.55684ms","start":"2026-08-04T21:51:17.679902Z","end":"2026-08-04T21:51:18.335458Z","steps":["trace[1124996953] 'process raft request' (duration: 547.44353ms)","trace[1124996953] 'compare' (duration: 106.817513ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T21:51:18.335849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.620897ms","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-08-04T21:51:18.33599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T21:51:17.679878Z","time spent":"655.842836ms","remote":"127.0.0.1:50944","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-08-04T21:51:18.335957Z","caller":"traceutil/trace.go:171","msg":"trace[399754624] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8521; }","duration":"648.879484ms","start":"2026-08-04T21:51:17.687048Z","end":"2026-08-04T21:51:18.335927Z","steps":["trace[399754624] 'agreement among raft nodes before linearized reading' (duration: 648.467115ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T21:51:18.336213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T21:51:17.687025Z","time spent":"649.169239ms","remote":"127.0.0.1:50944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-04T21:51:18.380103Z","caller":"traceutil/trace.go:171","msg":"trace[1529352628] transaction","detail":"{read_only:false; response_revision:8522; number_of_response:1; }","duration":"296.896706ms","start":"2026-08-04T21:51:18.083175Z","end":"2026-08-04T21:51:18.380071Z","steps":["trace[1529352628] 'process raft request' (duration: 296.708772ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T21:51:18.38036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.515758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-04T21:51:18.380479Z","caller":"traceutil/trace.go:171","msg":"trace[1334410560] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8522; }","duration":"691.671892ms","start":"2026-08-04T21:51:17.688779Z","end":"2026-08-04T21:51:18.380451Z","steps":["trace[1334410560] 'agreement among raft nodes before linearized reading' (duration: 691.353775ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T21:51:18.380565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T21:51:17.688769Z","time spent":"691.781024ms","remote":"127.0.0.1:50944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-04T21:51:18.380789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"692.137051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-04T21:51:18.380929Z","caller":"traceutil/trace.go:171","msg":"trace[1714735789] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8522; }","duration":"692.317285ms","start":"2026-08-04T21:51:17.68859Z","end":"2026-08-04T21:51:18.380907Z","steps":["trace[1714735789] 'agreement among raft nodes before linearized reading' (duration: 691.449917ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T21:51:18.381049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T21:51:17.688579Z","time spent":"692.454638ms","remote":"127.0.0.1:50944","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-08-04T21:51:46.146667Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002371.wal"} {"level":"info","ts":"2026-08-04T21:51:46.147148Z","caller":"traceutil/trace.go:171","msg":"trace[370285627] linearizableReadLoop","detail":"{readStateIndex:9072; appliedIndex:9071; }","duration":"195.000194ms","start":"2026-08-04T21:51:45.952119Z","end":"2026-08-04T21:51:46.147119Z","steps":["trace[370285627] 'read index received' (duration: 194.922643ms)","trace[370285627] 'applied index is now lower than readState.Index' (duration: 76.181µs)"],"step_count":2} {"level":"info","ts":"2026-08-04T21:51:46.147317Z","caller":"traceutil/trace.go:171","msg":"trace[227316618] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8758; }","duration":"197.544947ms","start":"2026-08-04T21:51:45.949736Z","end":"2026-08-04T21:51:46.147281Z","steps":["trace[227316618] 'process raft request' (duration: 197.1973ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T21:51:46.147408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.26244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-phvvr\" ","response":"range_response_count:1 size:2801"} {"level":"info","ts":"2026-08-04T21:51:46.1475Z","caller":"traceutil/trace.go:171","msg":"trace[1346495223] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-phvvr; range_end:; response_count:1; response_revision:8758; }","duration":"195.356821ms","start":"2026-08-04T21:51:45.952097Z","end":"2026-08-04T21:51:46.147454Z","steps":["trace[1346495223] 'agreement among raft nodes before linearized reading' (duration: 195.160037ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T21:51:46.391138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.029216ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-04T21:51:46.391274Z","caller":"traceutil/trace.go:171","msg":"trace[777880028] linearizableReadLoop","detail":"{readStateIndex:9073; appliedIndex:9072; }","duration":"244.03042ms","start":"2026-08-04T21:51:46.147228Z","end":"2026-08-04T21:51:46.391259Z","steps":["trace[777880028] 'read index received' (duration: 83.660007ms)","trace[777880028] 'applied index is now lower than readState.Index' (duration: 160.369143ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T21:51:46.391313Z","caller":"traceutil/trace.go:171","msg":"trace[1817484321] transaction","detail":"{read_only:false; response_revision:8759; number_of_response:1; }","duration":"400.485403ms","start":"2026-08-04T21:51:45.990792Z","end":"2026-08-04T21:51:46.391278Z","steps":["trace[1817484321] 'process raft request' (duration: 240.239543ms)","trace[1817484321] 'compare' (duration: 159.924634ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T21:51:46.39149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.873585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-phvvr\" ","response":"range_response_count:1 size:4664"} {"level":"warn","ts":"2026-08-04T21:51:46.391544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T21:51:45.990748Z","time spent":"400.646797ms","remote":"127.0.0.1:50944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T21:51:46.391527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.28417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-phvvr\" ","response":"range_response_count:1 size:4664"} {"level":"info","ts":"2026-08-04T21:51:46.391566Z","caller":"traceutil/trace.go:171","msg":"trace[999354608] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-phvvr; range_end:; response_count:1; response_revision:8759; }","duration":"239.939077ms","start":"2026-08-04T21:51:46.151599Z","end":"2026-08-04T21:51:46.391538Z","steps":["trace[999354608] 'agreement among raft nodes before linearized reading' (duration: 239.757313ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T21:51:46.391624Z","caller":"traceutil/trace.go:171","msg":"trace[1381302831] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-phvvr; range_end:; response_count:1; response_revision:8759; }","duration":"439.402742ms","start":"2026-08-04T21:51:45.952199Z","end":"2026-08-04T21:51:46.391602Z","steps":["trace[1381302831] 'agreement among raft nodes before linearized reading' (duration: 439.120536ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T21:51:46.391687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T21:51:45.952195Z","time spent":"439.480623ms","remote":"127.0.0.1:50860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":4687,"request content":"key:\"/registry/pods/openstack/rook-ceph-detect-version-phvvr\" "} {"level":"info","ts":"2026-08-04T21:51:46.588975Z","caller":"traceutil/trace.go:171","msg":"trace[1231588642] transaction","detail":"{read_only:false; response_revision:8761; number_of_response:1; }","duration":"190.158814ms","start":"2026-08-04T21:51:46.398786Z","end":"2026-08-04T21:51:46.588945Z","steps":["trace[1231588642] 'process raft request' (duration: 189.924869ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T21:51:46.589009Z","caller":"traceutil/trace.go:171","msg":"trace[885285879] transaction","detail":"{read_only:false; response_revision:8762; number_of_response:1; }","duration":"183.412686ms","start":"2026-08-04T21:51:46.405569Z","end":"2026-08-04T21:51:46.588982Z","steps":["trace[885285879] 'process raft request' (duration: 183.346945ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T21:51:46.589048Z","caller":"traceutil/trace.go:171","msg":"trace[1453859339] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8761; }","duration":"187.873098ms","start":"2026-08-04T21:51:46.401138Z","end":"2026-08-04T21:51:46.589011Z","steps":["trace[1453859339] 'process raft request' (duration: 187.740605ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T21:51:46.634709Z","caller":"traceutil/trace.go:171","msg":"trace[325425566] transaction","detail":"{read_only:false; response_revision:8763; number_of_response:1; }","duration":"139.046785ms","start":"2026-08-04T21:51:46.49564Z","end":"2026-08-04T21:51:46.634687Z","steps":["trace[325425566] 'process raft request' (duration: 138.855451ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T21:51:46.634704Z","caller":"traceutil/trace.go:171","msg":"trace[825051944] transaction","detail":"{read_only:false; response_revision:8764; number_of_response:1; }","duration":"132.349017ms","start":"2026-08-04T21:51:46.502334Z","end":"2026-08-04T21:51:46.634683Z","steps":["trace[825051944] 'process raft request' (duration: 132.281456ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T21:51:47.113484Z","caller":"traceutil/trace.go:171","msg":"trace[2101786608] transaction","detail":"{read_only:false; response_revision:8772; number_of_response:1; }","duration":"109.899026ms","start":"2026-08-04T21:51:47.00353Z","end":"2026-08-04T21:51:47.113429Z","steps":["trace[2101786608] 'process raft request' (duration: 68.961775ms)","trace[2101786608] 'compare' (duration: 40.580014ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T21:51:47.750374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.912084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:5146"} {"level":"info","ts":"2026-08-04T21:51:47.750449Z","caller":"traceutil/trace.go:171","msg":"trace[2114103543] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:8773; }","duration":"199.049617ms","start":"2026-08-04T21:51:47.551384Z","end":"2026-08-04T21:51:47.750434Z","steps":["trace[2114103543] 'range keys from in-memory index tree' (duration: 198.71937ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T21:51:47.863781Z","caller":"traceutil/trace.go:171","msg":"trace[579171009] transaction","detail":"{read_only:false; response_revision:8774; number_of_response:1; }","duration":"103.542696ms","start":"2026-08-04T21:51:47.760223Z","end":"2026-08-04T21:51:47.863766Z","steps":["trace[579171009] 'process raft request' (duration: 103.170409ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T21:53:09.229547Z","caller":"traceutil/trace.go:171","msg":"trace[1591428177] linearizableReadLoop","detail":"{readStateIndex:9882; appliedIndex:9880; }","duration":"111.797864ms","start":"2026-08-04T21:53:09.117719Z","end":"2026-08-04T21:53:09.229517Z","steps":["trace[1591428177] 'read index received' (duration: 111.60764ms)","trace[1591428177] 'applied index is now lower than readState.Index' (duration: 189.334µs)"],"step_count":2} {"level":"info","ts":"2026-08-04T21:53:09.2296Z","caller":"traceutil/trace.go:171","msg":"trace[2113576390] transaction","detail":"{read_only:false; response_revision:9547; number_of_response:1; }","duration":"189.845656ms","start":"2026-08-04T21:53:09.039715Z","end":"2026-08-04T21:53:09.22956Z","steps":["trace[2113576390] 'process raft request' (duration: 189.533029ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T21:53:09.229672Z","caller":"traceutil/trace.go:171","msg":"trace[1134769930] transaction","detail":"{read_only:false; response_revision:9548; number_of_response:1; }","duration":"189.799955ms","start":"2026-08-04T21:53:09.039839Z","end":"2026-08-04T21:53:09.229639Z","steps":["trace[1134769930] 'process raft request' (duration: 189.627022ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T21:53:09.229827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.100641ms","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-08-04T21:53:09.22989Z","caller":"traceutil/trace.go:171","msg":"trace[840232651] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9548; }","duration":"112.222053ms","start":"2026-08-04T21:53:09.117654Z","end":"2026-08-04T21:53:09.229876Z","steps":["trace[840232651] 'agreement among raft nodes before linearized reading' (duration: 112.015569ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T21:53:09.254367Z","caller":"traceutil/trace.go:171","msg":"trace[1847399930] transaction","detail":"{read_only:false; response_revision:9549; number_of_response:1; }","duration":"125.202509ms","start":"2026-08-04T21:53:09.129133Z","end":"2026-08-04T21:53:09.254336Z","steps":["trace[1847399930] 'process raft request' (duration: 124.979864ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T21:53:23.224512Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"9133559b3198d4ec","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-04T21:53:23.23012Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-04T21:53:23.23051Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-04T21:55:32.093113Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8106} {"level":"info","ts":"2026-08-04T21:55:32.16206Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8106,"took":"66.712205ms","hash":3957064023,"current-db-size-bytes":58052608,"current-db-size":"58 MB","current-db-size-in-use-bytes":28450816,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-04T21:55:32.162151Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3957064023,"revision":8106,"compact-revision":6060} {"level":"info","ts":"2026-08-04T21:57:23.386952Z","caller":"traceutil/trace.go:171","msg":"trace[372890122] transaction","detail":"{read_only:false; response_revision:11184; number_of_response:1; }","duration":"122.595501ms","start":"2026-08-04T21:57:23.264317Z","end":"2026-08-04T21:57:23.386913Z","steps":["trace[372890122] 'process raft request' (duration: 118.767023ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T21:58:29.451648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.010399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-04T21:58:29.452036Z","caller":"traceutil/trace.go:171","msg":"trace[1130092657] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:11877; }","duration":"132.446728ms","start":"2026-08-04T21:58:29.319562Z","end":"2026-08-04T21:58:29.452009Z","steps":["trace[1130092657] 'count revisions from in-memory index tree' (duration: 131.886827ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T21:58:52.021456Z","caller":"traceutil/trace.go:171","msg":"trace[95035536] transaction","detail":"{read_only:false; response_revision:12001; number_of_response:1; }","duration":"176.173799ms","start":"2026-08-04T21:58:51.845249Z","end":"2026-08-04T21:58:52.021422Z","steps":["trace[95035536] 'process raft request' (duration: 168.258798ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T21:58:52.276588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.727036ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-04T21:58:52.276689Z","caller":"traceutil/trace.go:171","msg":"trace[1483429845] transaction","detail":"{read_only:false; response_revision:12002; number_of_response:1; }","duration":"170.705829ms","start":"2026-08-04T21:58:52.105967Z","end":"2026-08-04T21:58:52.276673Z","steps":["trace[1483429845] 'process raft request' (duration: 32.784949ms)","trace[1483429845] 'compare' (duration: 137.499251ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T22:00:32.102294Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10502} {"level":"info","ts":"2026-08-04T22:00:32.206643Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10502,"took":"100.60402ms","hash":1885195879,"current-db-size-bytes":58052608,"current-db-size":"58 MB","current-db-size-in-use-bytes":26431488,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-04T22:00:32.20672Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1885195879,"revision":10502,"compact-revision":8106} {"level":"warn","ts":"2026-08-04T22:04:57.619805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.712469ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-04T22:04:57.619978Z","caller":"traceutil/trace.go:171","msg":"trace[2136341419] linearizableReadLoop","detail":"{readStateIndex:14972; appliedIndex:14970; }","duration":"128.988122ms","start":"2026-08-04T22:04:57.490974Z","end":"2026-08-04T22:04:57.619962Z","steps":["trace[2136341419] 'read index received' (duration: 125.95247ms)","trace[2136341419] 'applied index is now lower than readState.Index' (duration: 3.034772ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T22:04:57.620165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.185886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-vb62c\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-08-04T22:04:57.620155Z","caller":"traceutil/trace.go:171","msg":"trace[78020464] transaction","detail":"{read_only:false; response_revision:14471; number_of_response:1; }","duration":"150.978171ms","start":"2026-08-04T22:04:57.469152Z","end":"2026-08-04T22:04:57.62013Z","steps":["trace[78020464] 'process raft request' (duration: 150.760377ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T22:04:57.620201Z","caller":"traceutil/trace.go:171","msg":"trace[281834564] range","detail":"{range_begin:/registry/pods/openstack/keepalived-vb62c; range_end:; response_count:1; response_revision:14471; }","duration":"129.249178ms","start":"2026-08-04T22:04:57.490942Z","end":"2026-08-04T22:04:57.620192Z","steps":["trace[281834564] 'agreement among raft nodes before linearized reading' (duration: 129.075544ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T22:04:57.620217Z","caller":"traceutil/trace.go:171","msg":"trace[1348378541] transaction","detail":"{read_only:false; response_revision:14470; number_of_response:1; }","duration":"151.210616ms","start":"2026-08-04T22:04:57.468973Z","end":"2026-08-04T22:04:57.620184Z","steps":["trace[1348378541] 'process raft request' (duration: 17.037527ms)","trace[1348378541] 'compare' (duration: 133.611687ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T22:04:57.62734Z","caller":"traceutil/trace.go:171","msg":"trace[2062050317] transaction","detail":"{read_only:false; response_revision:14472; number_of_response:1; }","duration":"100.632563ms","start":"2026-08-04T22:04:57.526692Z","end":"2026-08-04T22:04:57.627324Z","steps":["trace[2062050317] 'process raft request' (duration: 100.365427ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T22:05:32.109769Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12666} {"level":"info","ts":"2026-08-04T22:05:32.184943Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12666,"took":"72.936738ms","hash":2589299785,"current-db-size-bytes":58052608,"current-db-size":"58 MB","current-db-size-in-use-bytes":27353088,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-08-04T22:05:32.185003Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2589299785,"revision":12666,"compact-revision":10502} {"level":"warn","ts":"2026-08-04T22:05:36.101206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.009898ms","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-08-04T22:05:36.101322Z","caller":"traceutil/trace.go:171","msg":"trace[1372177911] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14722; }","duration":"119.12555ms","start":"2026-08-04T22:05:35.98215Z","end":"2026-08-04T22:05:36.101275Z","steps":["trace[1372177911] 'range keys from in-memory index tree' (duration: 118.860585ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T22:05:47.047227Z","caller":"traceutil/trace.go:171","msg":"trace[98755135] transaction","detail":"{read_only:false; response_revision:14896; number_of_response:1; }","duration":"405.393473ms","start":"2026-08-04T22:05:46.641804Z","end":"2026-08-04T22:05:47.047198Z","steps":["trace[98755135] 'process raft request' (duration: 405.156888ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T22:05:47.047415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T22:05:46.641769Z","time spent":"405.550806ms","remote":"127.0.0.1:50944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T22:05:47.272265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.4038ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-04T22:05:47.272345Z","caller":"traceutil/trace.go:171","msg":"trace[448838150] linearizableReadLoop","detail":"{readStateIndex:15412; appliedIndex:15410; }","duration":"577.765039ms","start":"2026-08-04T22:05:46.694569Z","end":"2026-08-04T22:05:47.272334Z","steps":["trace[448838150] 'read index received' (duration: 352.438861ms)","trace[448838150] 'applied index is now lower than readState.Index' (duration: 225.325208ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T22:05:47.272406Z","caller":"traceutil/trace.go:171","msg":"trace[1210495388] transaction","detail":"{read_only:false; response_revision:14897; number_of_response:1; }","duration":"629.528396ms","start":"2026-08-04T22:05:46.642871Z","end":"2026-08-04T22:05:47.272399Z","steps":["trace[1210495388] 'process raft request' (duration: 475.925342ms)","trace[1210495388] 'compare' (duration: 153.272738ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T22:05:47.272444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T22:05:46.642827Z","time spent":"629.595858ms","remote":"127.0.0.1:50944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T22:05:47.272568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.190581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T22:05:47.27265Z","caller":"traceutil/trace.go:171","msg":"trace[2021359532] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14897; }","duration":"510.313163ms","start":"2026-08-04T22:05:46.762317Z","end":"2026-08-04T22:05:47.27263Z","steps":["trace[2021359532] 'agreement among raft nodes before linearized reading' (duration: 510.194261ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T22:05:47.272644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.40164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-04T22:05:47.27269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T22:05:46.762294Z","time spent":"510.388205ms","remote":"127.0.0.1:50648","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-08-04T22:05:47.272693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.856322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b7e4c399-18b9-4bf6-a6d6-f683cae1105f\" ","response":"range_response_count:1 size:3606"} {"level":"info","ts":"2026-08-04T22:05:47.272719Z","caller":"traceutil/trace.go:171","msg":"trace[208101643] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:14897; }","duration":"249.529272ms","start":"2026-08-04T22:05:47.023169Z","end":"2026-08-04T22:05:47.272698Z","steps":["trace[208101643] 'agreement among raft nodes before linearized reading' (duration: 249.370959ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T22:05:47.272733Z","caller":"traceutil/trace.go:171","msg":"trace[122035391] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b7e4c399-18b9-4bf6-a6d6-f683cae1105f; range_end:; response_count:1; response_revision:14897; }","duration":"382.897653ms","start":"2026-08-04T22:05:46.889824Z","end":"2026-08-04T22:05:47.272722Z","steps":["trace[122035391] 'agreement among raft nodes before linearized reading' (duration: 382.73624ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T22:05:47.272761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T22:05:46.889772Z","time spent":"382.981355ms","remote":"127.0.0.1:50860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3629,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b7e4c399-18b9-4bf6-a6d6-f683cae1105f\" "} {"level":"warn","ts":"2026-08-04T22:05:47.27279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.240938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/ovn-controller-default-st7fn.18c8b97098062397\" ","response":"range_response_count:1 size:783"} {"level":"info","ts":"2026-08-04T22:05:47.272817Z","caller":"traceutil/trace.go:171","msg":"trace[983688769] range","detail":"{range_begin:/registry/events/openstack/ovn-controller-default-st7fn.18c8b97098062397; range_end:; response_count:1; response_revision:14897; }","duration":"256.30718ms","start":"2026-08-04T22:05:47.016501Z","end":"2026-08-04T22:05:47.272808Z","steps":["trace[983688769] 'agreement among raft nodes before linearized reading' (duration: 256.204228ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T22:05:47.272902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.821672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T22:05:47.272938Z","caller":"traceutil/trace.go:171","msg":"trace[2030295767] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:14897; }","duration":"508.891474ms","start":"2026-08-04T22:05:46.764033Z","end":"2026-08-04T22:05:47.272925Z","steps":["trace[2030295767] 'agreement among raft nodes before linearized reading' (duration: 508.831663ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T22:05:47.27295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.188159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-04T22:05:47.272967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T22:05:46.764022Z","time spent":"508.937175ms","remote":"127.0.0.1:39728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true "} {"level":"info","ts":"2026-08-04T22:05:47.272987Z","caller":"traceutil/trace.go:171","msg":"trace[1207121062] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:14897; }","duration":"112.264051ms","start":"2026-08-04T22:05:47.160711Z","end":"2026-08-04T22:05:47.272975Z","steps":["trace[1207121062] 'agreement among raft nodes before linearized reading' (duration: 112.20456ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T22:05:47.272949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.381982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T22:05:47.273131Z","caller":"traceutil/trace.go:171","msg":"trace[1930348968] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:14897; }","duration":"578.579415ms","start":"2026-08-04T22:05:46.694518Z","end":"2026-08-04T22:05:47.273098Z","steps":["trace[1930348968] 'agreement among raft nodes before linearized reading' (duration: 578.395482ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T22:05:47.273229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T22:05:46.694498Z","time spent":"578.695078ms","remote":"127.0.0.1:39766","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true "} {"level":"warn","ts":"2026-08-04T22:07:11.82055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.39408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-qbr7g\" ","response":"range_response_count:1 size:20632"} {"level":"info","ts":"2026-08-04T22:07:11.820619Z","caller":"traceutil/trace.go:171","msg":"trace[800383818] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-qbr7g; range_end:; response_count:1; response_revision:15907; }","duration":"137.503773ms","start":"2026-08-04T22:07:11.683102Z","end":"2026-08-04T22:07:11.820606Z","steps":["trace[800383818] 'range keys from in-memory index tree' (duration: 137.206137ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T22:07:13.027159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.296315ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-04T22:07:13.027307Z","caller":"traceutil/trace.go:171","msg":"trace[746501226] linearizableReadLoop","detail":"{readStateIndex:16461; appliedIndex:16460; }","duration":"234.458138ms","start":"2026-08-04T22:07:12.792827Z","end":"2026-08-04T22:07:13.027285Z","steps":["trace[746501226] 'read index received' (duration: 132.839868ms)","trace[746501226] 'applied index is now lower than readState.Index' (duration: 101.61621ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T22:07:13.027393Z","caller":"traceutil/trace.go:171","msg":"trace[1390411036] transaction","detail":"{read_only:false; response_revision:15914; number_of_response:1; }","duration":"236.231823ms","start":"2026-08-04T22:07:12.791113Z","end":"2026-08-04T22:07:13.027344Z","steps":["trace[1390411036] 'process raft request' (duration: 134.651464ms)","trace[1390411036] 'compare' (duration: 101.079409ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T22:07:13.027579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.564084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-vb62c\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-08-04T22:07:13.027653Z","caller":"traceutil/trace.go:171","msg":"trace[1952113921] range","detail":"{range_begin:/registry/pods/openstack/keepalived-vb62c; range_end:; response_count:1; response_revision:15914; }","duration":"112.677726ms","start":"2026-08-04T22:07:12.914957Z","end":"2026-08-04T22:07:13.027635Z","steps":["trace[1952113921] 'agreement among raft nodes before linearized reading' (duration: 112.483882ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T22:07:13.028466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.625222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:80 size:776670"} {"level":"info","ts":"2026-08-04T22:07:13.028523Z","caller":"traceutil/trace.go:171","msg":"trace[740680019] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:80; response_revision:15914; }","duration":"235.699213ms","start":"2026-08-04T22:07:12.792809Z","end":"2026-08-04T22:07:13.028508Z","steps":["trace[740680019] 'agreement among raft nodes before linearized reading' (duration: 234.57922ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T22:10:32.120675Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14687} {"level":"info","ts":"2026-08-04T22:10:32.212716Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14687,"took":"89.710768ms","hash":3462853537,"current-db-size-bytes":58052608,"current-db-size":"58 MB","current-db-size-in-use-bytes":32702464,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-08-04T22:10:32.212785Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3462853537,"revision":14687,"compact-revision":12666} {"level":"info","ts":"2026-08-04T22:13:04.933789Z","caller":"traceutil/trace.go:171","msg":"trace[1379274736] linearizableReadLoop","detail":"{readStateIndex:19071; appliedIndex:19070; }","duration":"423.108833ms","start":"2026-08-04T22:13:04.510658Z","end":"2026-08-04T22:13:04.933767Z","steps":["trace[1379274736] 'read index received' (duration: 422.94354ms)","trace[1379274736] 'applied index is now lower than readState.Index' (duration: 161.833µs)"],"step_count":2} {"level":"info","ts":"2026-08-04T22:13:04.933831Z","caller":"traceutil/trace.go:171","msg":"trace[445628741] transaction","detail":"{read_only:false; response_revision:18438; number_of_response:1; }","duration":"481.897396ms","start":"2026-08-04T22:13:04.451908Z","end":"2026-08-04T22:13:04.933805Z","steps":["trace[445628741] 'process raft request' (duration: 481.715482ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T22:13:04.933963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.294948ms","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-08-04T22:13:04.933968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T22:13:04.451878Z","time spent":"482.010908ms","remote":"127.0.0.1:45392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7438,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T22:13:04.933989Z","caller":"traceutil/trace.go:171","msg":"trace[1173210939] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18438; }","duration":"423.355068ms","start":"2026-08-04T22:13:04.510624Z","end":"2026-08-04T22:13:04.933979Z","steps":["trace[1173210939] 'agreement among raft nodes before linearized reading' (duration: 423.246146ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T22:13:04.934012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T22:13:04.510607Z","time spent":"423.40029ms","remote":"127.0.0.1:50944","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-08-04T22:13:05.091333Z","caller":"traceutil/trace.go:171","msg":"trace[320812103] transaction","detail":"{read_only:false; response_revision:18440; number_of_response:1; }","duration":"150.413176ms","start":"2026-08-04T22:13:04.940904Z","end":"2026-08-04T22:13:05.091317Z","steps":["trace[320812103] 'process raft request' (duration: 150.378646ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T22:13:05.091406Z","caller":"traceutil/trace.go:171","msg":"trace[982721197] transaction","detail":"{read_only:false; response_revision:18439; number_of_response:1; }","duration":"294.459913ms","start":"2026-08-04T22:13:04.796921Z","end":"2026-08-04T22:13:05.091381Z","steps":["trace[982721197] 'process raft request' (duration: 212.306852ms)","trace[982721197] 'compare' (duration: 81.950497ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T22:13:05.091547Z","caller":"traceutil/trace.go:171","msg":"trace[763416748] linearizableReadLoop","detail":"{readStateIndex:19072; appliedIndex:19071; }","duration":"157.691265ms","start":"2026-08-04T22:13:04.933849Z","end":"2026-08-04T22:13:05.091541Z","steps":["trace[763416748] 'read index received' (duration: 75.29424ms)","trace[763416748] 'applied index is now lower than readState.Index' (duration: 82.396275ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T22:13:05.091596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.337636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-04T22:13:05.091611Z","caller":"traceutil/trace.go:171","msg":"trace[116983826] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18440; }","duration":"327.400166ms","start":"2026-08-04T22:13:04.764206Z","end":"2026-08-04T22:13:05.091606Z","steps":["trace[116983826] 'agreement among raft nodes before linearized reading' (duration: 327.354865ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T22:13:05.091627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T22:13:04.764183Z","time spent":"327.440368ms","remote":"127.0.0.1:50648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-04T22:13:12.824458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.337317ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-04T22:13:12.824604Z","caller":"traceutil/trace.go:171","msg":"trace[845756382] linearizableReadLoop","detail":"{readStateIndex:19141; appliedIndex:19139; }","duration":"277.650059ms","start":"2026-08-04T22:13:12.546941Z","end":"2026-08-04T22:13:12.824591Z","steps":["trace[845756382] 'read index received' (duration: 121.094027ms)","trace[845756382] 'applied index is now lower than readState.Index' (duration: 156.555142ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T22:13:12.825739Z","caller":"traceutil/trace.go:171","msg":"trace[234906695] transaction","detail":"{read_only:false; response_revision:18505; number_of_response:1; }","duration":"412.572797ms","start":"2026-08-04T22:13:12.413147Z","end":"2026-08-04T22:13:12.82572Z","steps":["trace[234906695] 'process raft request' (duration: 254.906792ms)","trace[234906695] 'compare' (duration: 156.021671ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T22:13:12.825801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T22:13:12.413114Z","time spent":"412.65678ms","remote":"127.0.0.1:50944","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-08-04T22:13:12.825872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.886544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-qbr7g\" ","response":"range_response_count:1 size:20735"} {"level":"info","ts":"2026-08-04T22:13:12.825983Z","caller":"traceutil/trace.go:171","msg":"trace[281178450] transaction","detail":"{read_only:false; response_revision:18506; number_of_response:1; }","duration":"355.830037ms","start":"2026-08-04T22:13:12.470146Z","end":"2026-08-04T22:13:12.825976Z","steps":["trace[281178450] 'process raft request' (duration: 354.400768ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T22:13:12.826016Z","caller":"traceutil/trace.go:171","msg":"trace[770658726] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-qbr7g; range_end:; response_count:1; response_revision:18506; }","duration":"279.090399ms","start":"2026-08-04T22:13:12.546901Z","end":"2026-08-04T22:13:12.825991Z","steps":["trace[770658726] 'agreement among raft nodes before linearized reading' (duration: 277.73013ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T22:13:12.826026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T22:13:12.470121Z","time spent":"355.882749ms","remote":"127.0.0.1:50944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T22:13:12.947577Z","caller":"traceutil/trace.go:171","msg":"trace[1675504375] transaction","detail":"{read_only:false; response_revision:18508; number_of_response:1; }","duration":"102.258011ms","start":"2026-08-04T22:13:12.845293Z","end":"2026-08-04T22:13:12.947551Z","steps":["trace[1675504375] 'process raft request' (duration: 102.024806ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T22:14:29.161798Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"9133559b3198d4ec","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-04T22:14:29.169338Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-04T22:14:29.169458Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-04T22:15:32.126943Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17283} {"level":"info","ts":"2026-08-04T22:15:32.230474Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17283,"took":"99.003035ms","hash":172899451,"current-db-size-bytes":58052608,"current-db-size":"58 MB","current-db-size-in-use-bytes":35028992,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-08-04T22:15:32.230544Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":172899451,"revision":17283,"compact-revision":14687} {"level":"info","ts":"2026-08-04T22:19:49.306385Z","caller":"traceutil/trace.go:171","msg":"trace[1128499490] transaction","detail":"{read_only:false; response_revision:21475; number_of_response:1; }","duration":"127.435994ms","start":"2026-08-04T22:19:49.178921Z","end":"2026-08-04T22:19:49.306357Z","steps":["trace[1128499490] 'process raft request' (duration: 127.143148ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T22:20:32.134871Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19810} {"level":"info","ts":"2026-08-04T22:20:32.258165Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19810,"took":"117.548772ms","hash":3839999697,"current-db-size-bytes":58052608,"current-db-size":"58 MB","current-db-size-in-use-bytes":30310400,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-04T22:20:32.258262Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3839999697,"revision":19810,"compact-revision":17283} {"level":"info","ts":"2026-08-04T22:24:03.712957Z","caller":"traceutil/trace.go:171","msg":"trace[1291833695] transaction","detail":"{read_only:false; response_revision:24041; number_of_response:1; }","duration":"121.764687ms","start":"2026-08-04T22:24:03.591155Z","end":"2026-08-04T22:24:03.71292Z","steps":["trace[1291833695] 'process raft request' (duration: 112.420616ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T22:24:04.055836Z","caller":"traceutil/trace.go:171","msg":"trace[1669940162] linearizableReadLoop","detail":"{readStateIndex:24855; appliedIndex:24854; }","duration":"194.450303ms","start":"2026-08-04T22:24:03.86136Z","end":"2026-08-04T22:24:04.055811Z","steps":["trace[1669940162] 'read index received' (duration: 194.339479ms)","trace[1669940162] 'applied index is now lower than readState.Index' (duration: 109.774µs)"],"step_count":2} {"level":"info","ts":"2026-08-04T22:24:04.056054Z","caller":"traceutil/trace.go:171","msg":"trace[831955540] transaction","detail":"{read_only:false; response_revision:24043; number_of_response:1; }","duration":"256.925479ms","start":"2026-08-04T22:24:03.799088Z","end":"2026-08-04T22:24:04.056013Z","steps":["trace[831955540] 'process raft request' (duration: 256.573341ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T22:24:04.056231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.85183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-04T22:24:04.056345Z","caller":"traceutil/trace.go:171","msg":"trace[1093515111] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24043; }","duration":"195.038005ms","start":"2026-08-04T22:24:03.861281Z","end":"2026-08-04T22:24:04.056319Z","steps":["trace[1093515111] 'agreement among raft nodes before linearized reading' (duration: 194.738899ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T22:24:04.056874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.717526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-08-04T22:24:04.056937Z","caller":"traceutil/trace.go:171","msg":"trace[1445948618] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24044; }","duration":"191.826119ms","start":"2026-08-04T22:24:03.865092Z","end":"2026-08-04T22:24:04.056918Z","steps":["trace[1445948618] 'agreement among raft nodes before linearized reading' (duration: 191.586014ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T22:25:32.158042Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21966} {"level":"info","ts":"2026-08-04T22:25:32.280122Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21966,"took":"118.309134ms","hash":713776939,"current-db-size-bytes":59748352,"current-db-size":"60 MB","current-db-size-in-use-bytes":43978752,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-08-04T22:25:32.280202Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":713776939,"revision":21966,"compact-revision":19810} {"level":"info","ts":"2026-08-04T22:30:09.592737Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006d49.wal"} {"level":"info","ts":"2026-08-04T22:30:32.168101Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25017} {"level":"info","ts":"2026-08-04T22:30:32.31015Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25017,"took":"135.388553ms","hash":2871205588,"current-db-size-bytes":59748352,"current-db-size":"60 MB","current-db-size-in-use-bytes":30953472,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-04T22:30:32.310249Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2871205588,"revision":25017,"compact-revision":21966} {"level":"info","ts":"2026-08-04T22:33:08.997745Z","caller":"traceutil/trace.go:171","msg":"trace[115788752] transaction","detail":"{read_only:false; response_revision:28395; number_of_response:1; }","duration":"475.972512ms","start":"2026-08-04T22:33:08.521753Z","end":"2026-08-04T22:33:08.997725Z","steps":["trace[115788752] 'process raft request' (duration: 475.946131ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T22:33:08.997877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T22:33:08.521747Z","time spent":"476.070835ms","remote":"127.0.0.1:50944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T22:33:08.99812Z","caller":"traceutil/trace.go:171","msg":"trace[1752520033] transaction","detail":"{read_only:false; response_revision:28393; number_of_response:1; }","duration":"478.059965ms","start":"2026-08-04T22:33:08.520054Z","end":"2026-08-04T22:33:08.998114Z","steps":["trace[1752520033] 'process raft request' (duration: 477.480433ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T22:33:08.99816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T22:33:08.520044Z","time spent":"478.095116ms","remote":"127.0.0.1:50944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T22:33:08.998245Z","caller":"traceutil/trace.go:171","msg":"trace[1728466053] linearizableReadLoop","detail":"{readStateIndex:29327; appliedIndex:29326; }","duration":"477.721698ms","start":"2026-08-04T22:33:08.520518Z","end":"2026-08-04T22:33:08.998239Z","steps":["trace[1728466053] 'read index received' (duration: 476.917252ms)","trace[1728466053] 'applied index is now lower than readState.Index' (duration: 803.936µs)"],"step_count":2} {"level":"info","ts":"2026-08-04T22:33:08.998287Z","caller":"traceutil/trace.go:171","msg":"trace[1888615545] transaction","detail":"{read_only:false; response_revision:28394; number_of_response:1; }","duration":"476.587665ms","start":"2026-08-04T22:33:08.521695Z","end":"2026-08-04T22:33:08.998283Z","steps":["trace[1888615545] 'process raft request' (duration: 475.973103ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T22:33:08.998318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T22:33:08.521684Z","time spent":"476.617695ms","remote":"127.0.0.1:50944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T22:33:08.998456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.949552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-04T22:33:08.998481Z","caller":"traceutil/trace.go:171","msg":"trace[768423082] range","detail":"{range_begin:/registry/jobs/openstack/magnum-domain-ks-user; range_end:; response_count:0; response_revision:28395; }","duration":"477.996314ms","start":"2026-08-04T22:33:08.520479Z","end":"2026-08-04T22:33:08.998475Z","steps":["trace[768423082] 'agreement among raft nodes before linearized reading' (duration: 477.954753ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T22:33:08.998497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T22:33:08.520467Z","time spent":"478.026664ms","remote":"127.0.0.1:50914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/magnum-domain-ks-user\" "} {"level":"warn","ts":"2026-08-04T22:33:08.998588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.473322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-04T22:33:08.998607Z","caller":"traceutil/trace.go:171","msg":"trace[1075733221] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-endpoints; range_end:; response_count:0; response_revision:28395; }","duration":"476.505873ms","start":"2026-08-04T22:33:08.522097Z","end":"2026-08-04T22:33:08.998603Z","steps":["trace[1075733221] 'agreement among raft nodes before linearized reading' (duration: 476.476183ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T22:33:08.998622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T22:33:08.522091Z","time spent":"476.526374ms","remote":"127.0.0.1:50914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/magnum-ks-endpoints\" "} {"level":"warn","ts":"2026-08-04T22:33:08.998905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.309353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-04T22:33:08.998927Z","caller":"traceutil/trace.go:171","msg":"trace[2001736033] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:28395; }","duration":"227.397455ms","start":"2026-08-04T22:33:08.771525Z","end":"2026-08-04T22:33:08.998922Z","steps":["trace[2001736033] 'agreement among raft nodes before linearized reading' (duration: 227.364984ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T22:33:08.999016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.789754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-04T22:33:08.999036Z","caller":"traceutil/trace.go:171","msg":"trace[1734214314] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:28395; }","duration":"256.862826ms","start":"2026-08-04T22:33:08.742168Z","end":"2026-08-04T22:33:08.999031Z","steps":["trace[1734214314] 'agreement among raft nodes before linearized reading' (duration: 256.828385ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T22:33:09.001711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.435268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/heat-engine-6df6dc5c5b.18c8b9e548a0a1bd\\000\" range_end:\"/registry/events/openstack0\" limit:1000 revision:28392 ","response":"range_response_count:1000 size:780512"} {"level":"info","ts":"2026-08-04T22:33:09.001739Z","caller":"traceutil/trace.go:171","msg":"trace[2104986150] range","detail":"{range_begin:/registry/events/openstack/heat-engine-6df6dc5c5b.18c8b9e548a0a1bd\u0000; range_end:/registry/events/openstack0; response_count:1000; response_revision:28395; }","duration":"469.51407ms","start":"2026-08-04T22:33:08.53222Z","end":"2026-08-04T22:33:09.001734Z","steps":["trace[2104986150] 'agreement among raft nodes before linearized reading' (duration: 466.890696ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T22:33:09.001771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T22:33:08.532195Z","time spent":"469.55578ms","remote":"127.0.0.1:50746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":1424,"response size":780535,"request content":"key:\"/registry/events/openstack/heat-engine-6df6dc5c5b.18c8b9e548a0a1bd\\000\" range_end:\"/registry/events/openstack0\" limit:1000 revision:28392 "} {"level":"warn","ts":"2026-08-04T22:33:09.0021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.877121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-db-sync\" ","response":"range_response_count:1 size:5080"} {"level":"info","ts":"2026-08-04T22:33:09.002122Z","caller":"traceutil/trace.go:171","msg":"trace[1376046430] range","detail":"{range_begin:/registry/jobs/openstack/magnum-db-sync; range_end:; response_count:1; response_revision:28395; }","duration":"479.908472ms","start":"2026-08-04T22:33:08.522209Z","end":"2026-08-04T22:33:09.002118Z","steps":["trace[1376046430] 'agreement among raft nodes before linearized reading' (duration: 479.850741ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T22:33:09.002135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T22:33:08.522203Z","time spent":"479.928962ms","remote":"127.0.0.1:50914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":5103,"request content":"key:\"/registry/jobs/openstack/magnum-db-sync\" "} {"level":"warn","ts":"2026-08-04T22:33:09.00221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.990673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-04T22:33:09.002228Z","caller":"traceutil/trace.go:171","msg":"trace[889466774] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-user; range_end:; response_count:0; response_revision:28395; }","duration":"480.029445ms","start":"2026-08-04T22:33:08.522195Z","end":"2026-08-04T22:33:09.002224Z","steps":["trace[889466774] 'agreement among raft nodes before linearized reading' (duration: 480.003014ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T22:33:09.002242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T22:33:08.522168Z","time spent":"480.071075ms","remote":"127.0.0.1:50914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/magnum-ks-user\" "} {"level":"warn","ts":"2026-08-04T22:33:09.002309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.122236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-04T22:33:09.002328Z","caller":"traceutil/trace.go:171","msg":"trace[9535414] range","detail":"{range_begin:/registry/jobs/openstack/magnum-domain-ks-user; range_end:; response_count:0; response_revision:28395; }","duration":"480.150747ms","start":"2026-08-04T22:33:08.522173Z","end":"2026-08-04T22:33:09.002323Z","steps":["trace[9535414] 'agreement among raft nodes before linearized reading' (duration: 480.124716ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T22:33:09.002341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T22:33:08.522169Z","time spent":"480.168407ms","remote":"127.0.0.1:50914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/magnum-domain-ks-user\" "} {"level":"warn","ts":"2026-08-04T22:33:09.002408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.241458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-04T22:33:09.002427Z","caller":"traceutil/trace.go:171","msg":"trace[742624587] range","detail":"{range_begin:/registry/jobs/openstack/magnum-rabbit-init; range_end:; response_count:0; response_revision:28395; }","duration":"480.261579ms","start":"2026-08-04T22:33:08.52216Z","end":"2026-08-04T22:33:09.002422Z","steps":["trace[742624587] 'agreement among raft nodes before linearized reading' (duration: 480.232308ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T22:33:09.00244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T22:33:08.522149Z","time spent":"480.288ms","remote":"127.0.0.1:50914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/magnum-rabbit-init\" "} {"level":"warn","ts":"2026-08-04T22:33:09.002506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.358831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-04T22:33:09.002525Z","caller":"traceutil/trace.go:171","msg":"trace[1512025168] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-endpoints; range_end:; response_count:0; response_revision:28395; }","duration":"480.388512ms","start":"2026-08-04T22:33:08.522132Z","end":"2026-08-04T22:33:09.002521Z","steps":["trace[1512025168] 'agreement among raft nodes before linearized reading' (duration: 480.362661ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T22:33:09.002538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T22:33:08.522128Z","time spent":"480.406462ms","remote":"127.0.0.1:50914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/magnum-ks-endpoints\" "} {"level":"info","ts":"2026-08-04T22:33:09.775496Z","caller":"traceutil/trace.go:171","msg":"trace[484284691] transaction","detail":"{read_only:false; response_revision:28396; number_of_response:1; }","duration":"391.215851ms","start":"2026-08-04T22:33:09.38423Z","end":"2026-08-04T22:33:09.775446Z","steps":["trace[484284691] 'process raft request' (duration: 390.911335ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T22:33:09.775729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T22:33:09.384204Z","time spent":"391.415235ms","remote":"127.0.0.1:50944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T22:33:09.776275Z","caller":"traceutil/trace.go:171","msg":"trace[243293561] linearizableReadLoop","detail":"{readStateIndex:29330; appliedIndex:29329; }","duration":"288.135771ms","start":"2026-08-04T22:33:09.488129Z","end":"2026-08-04T22:33:09.776265Z","steps":["trace[243293561] 'read index received' (duration: 286.742102ms)","trace[243293561] 'applied index is now lower than readState.Index' (duration: 1.373978ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T22:33:09.776397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.260563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-04T22:33:09.776427Z","caller":"traceutil/trace.go:171","msg":"trace[1440641935] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:28396; }","duration":"288.337475ms","start":"2026-08-04T22:33:09.488078Z","end":"2026-08-04T22:33:09.776416Z","steps":["trace[1440641935] 'agreement among raft nodes before linearized reading' (duration: 288.244843ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T22:33:09.801865Z","caller":"traceutil/trace.go:171","msg":"trace[918575483] transaction","detail":"{read_only:false; response_revision:28398; number_of_response:1; }","duration":"140.951686ms","start":"2026-08-04T22:33:09.660846Z","end":"2026-08-04T22:33:09.801798Z","steps":["trace[918575483] 'process raft request' (duration: 140.746802ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T22:33:09.802034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.792108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-08-04T22:33:09.802145Z","caller":"traceutil/trace.go:171","msg":"trace[1969792025] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:28398; }","duration":"220.9282ms","start":"2026-08-04T22:33:09.581188Z","end":"2026-08-04T22:33:09.802116Z","steps":["trace[1969792025] 'agreement among raft nodes before linearized reading' (duration: 220.614224ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T22:33:09.802381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.14558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-04T22:33:09.802402Z","caller":"traceutil/trace.go:171","msg":"trace[1361990338] transaction","detail":"{read_only:false; response_revision:28397; number_of_response:1; }","duration":"208.113498ms","start":"2026-08-04T22:33:09.594279Z","end":"2026-08-04T22:33:09.802392Z","steps":["trace[1361990338] 'process raft request' (duration: 205.941023ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T22:33:09.802441Z","caller":"traceutil/trace.go:171","msg":"trace[1235172026] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:28398; }","duration":"314.242842ms","start":"2026-08-04T22:33:09.488179Z","end":"2026-08-04T22:33:09.802422Z","steps":["trace[1235172026] 'agreement among raft nodes before linearized reading' (duration: 314.145601ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T22:33:09.802494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T22:33:09.488152Z","time spent":"314.323855ms","remote":"127.0.0.1:39754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true "}