{"level":"warn","ts":"2026-07-02T05:13:02.766262Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-02T05:13:02.766383Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.248: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.248:2380","--initial-cluster=instance=https://199.204.45.248:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.248:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.248:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-02T05:13:02.766494Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-02T05:13:02.766512Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.248:2380"]} {"level":"info","ts":"2026-07-02T05:13:02.766538Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-02T05:13:02.767307Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"]} {"level":"info","ts":"2026-07-02T05:13:02.767407Z","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.248:2380"],"listen-peer-urls":["https://199.204.45.248:2380"],"advertise-client-urls":["https://199.204.45.248:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.248:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-02T05:13:02.772797Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.133514ms"} {"level":"info","ts":"2026-07-02T05:13:02.780862Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"39582465ceaa2f67","cluster-id":"87fd8ddedde3be45"} {"level":"info","ts":"2026-07-02T05:13:02.780996Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=()"} {"level":"info","ts":"2026-07-02T05:13:02.781031Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became follower at term 0"} {"level":"info","ts":"2026-07-02T05:13:02.78105Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 39582465ceaa2f67 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-02T05:13:02.781063Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became follower at term 1"} {"level":"info","ts":"2026-07-02T05:13:02.781116Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=(4132092677789986663)"} {"level":"warn","ts":"2026-07-02T05:13:02.790084Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-02T05:13:02.792281Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-02T05:13:02.793723Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-02T05:13:02.797077Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"39582465ceaa2f67","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-02T05:13:02.797223Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"39582465ceaa2f67","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-02T05:13:02.79721Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-02T05:13:02.797365Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-02T05:13:02.797418Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-02T05:13:02.799853Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=(4132092677789986663)"} {"level":"info","ts":"2026-07-02T05:13:02.800387Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"87fd8ddedde3be45","local-member-id":"39582465ceaa2f67","added-peer-id":"39582465ceaa2f67","added-peer-peer-urls":["https://199.204.45.248:2380"]} {"level":"info","ts":"2026-07-02T05:13:02.802937Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-02T05:13:02.803214Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"39582465ceaa2f67","initial-advertise-peer-urls":["https://199.204.45.248:2380"],"listen-peer-urls":["https://199.204.45.248:2380"],"advertise-client-urls":["https://199.204.45.248:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-02T05:13:02.803264Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-02T05:13:02.803323Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.248:2380"} {"level":"info","ts":"2026-07-02T05:13:02.803373Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.248:2380"} {"level":"info","ts":"2026-07-02T05:13:03.582279Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 is starting a new election at term 1"} {"level":"info","ts":"2026-07-02T05:13:03.582375Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-02T05:13:03.582416Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 received MsgPreVoteResp from 39582465ceaa2f67 at term 1"} {"level":"info","ts":"2026-07-02T05:13:03.582444Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became candidate at term 2"} {"level":"info","ts":"2026-07-02T05:13:03.582461Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 received MsgVoteResp from 39582465ceaa2f67 at term 2"} {"level":"info","ts":"2026-07-02T05:13:03.582482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became leader at term 2"} {"level":"info","ts":"2026-07-02T05:13:03.582496Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 39582465ceaa2f67 elected leader 39582465ceaa2f67 at term 2"} {"level":"info","ts":"2026-07-02T05:13:03.583501Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T05:13:03.58431Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-02T05:13:03.58431Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"39582465ceaa2f67","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.248:2379]}","request-path":"/0/members/39582465ceaa2f67/attributes","cluster-id":"87fd8ddedde3be45","publish-timeout":"7s"} {"level":"info","ts":"2026-07-02T05:13:03.584344Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-02T05:13:03.584632Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-02T05:13:03.584651Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-02T05:13:03.58483Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"87fd8ddedde3be45","local-member-id":"39582465ceaa2f67","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T05:13:03.584957Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T05:13:03.584999Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T05:13:03.587048Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.248:2379"} {"level":"info","ts":"2026-07-02T05:13:03.587135Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-02T05:13:05.227643Z","caller":"traceutil/trace.go:171","msg":"trace[1336744339] transaction","detail":"{read_only:false; response_revision:69; number_of_response:1; }","duration":"119.505732ms","start":"2026-07-02T05:13:05.108103Z","end":"2026-07-02T05:13:05.227608Z","steps":["trace[1336744339] 'process raft request' (duration: 113.693609ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:13:40.02409Z","caller":"traceutil/trace.go:171","msg":"trace[1821724291] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"100.00267ms","start":"2026-07-02T05:13:39.923782Z","end":"2026-07-02T05:13:40.023785Z","steps":["trace[1821724291] 'process raft request' (duration: 89.454472ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:13:45.986856Z","caller":"traceutil/trace.go:171","msg":"trace[833720468] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"130.367036ms","start":"2026-07-02T05:13:45.856456Z","end":"2026-07-02T05:13:45.986823Z","steps":["trace[833720468] 'process raft request' (duration: 124.988044ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:13:46.165151Z","caller":"traceutil/trace.go:171","msg":"trace[487349387] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:656; }","duration":"117.509344ms","start":"2026-07-02T05:13:46.047614Z","end":"2026-07-02T05:13:46.165124Z","steps":["trace[487349387] 'read index received' (duration: 40.220204ms)","trace[487349387] 'applied index is now lower than readState.Index' (duration: 77.28778ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:13:46.16544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.721399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:13:46.165481Z","caller":"traceutil/trace.go:171","msg":"trace[708386829] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"126.665381ms","start":"2026-07-02T05:13:46.038761Z","end":"2026-07-02T05:13:46.165427Z","steps":["trace[708386829] 'process raft request' (duration: 49.043833ms)","trace[708386829] 'compare' (duration: 77.164365ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:13:46.165523Z","caller":"traceutil/trace.go:171","msg":"trace[73184545] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:633; }","duration":"117.934496ms","start":"2026-07-02T05:13:46.047574Z","end":"2026-07-02T05:13:46.165509Z","steps":["trace[73184545] 'agreement among raft nodes before linearized reading' (duration: 117.703769ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:46.455967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.612011ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T05:13:46.456104Z","caller":"traceutil/trace.go:171","msg":"trace[1571416219] linearizableReadLoop","detail":"{readStateIndex:658; appliedIndex:657; }","duration":"290.834091ms","start":"2026-07-02T05:13:46.165238Z","end":"2026-07-02T05:13:46.456072Z","steps":["trace[1571416219] 'read index received' (duration: 180.408787ms)","trace[1571416219] 'applied index is now lower than readState.Index' (duration: 110.423034ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:13:46.456126Z","caller":"traceutil/trace.go:171","msg":"trace[1321450689] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"296.783029ms","start":"2026-07-02T05:13:46.15932Z","end":"2026-07-02T05:13:46.456103Z","steps":["trace[1321450689] 'process raft request' (duration: 186.403676ms)","trace[1321450689] 'compare' (duration: 109.479268ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:13:46.456231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.983329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-02T05:13:46.45627Z","caller":"traceutil/trace.go:171","msg":"trace[1937154114] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:634; }","duration":"383.047342ms","start":"2026-07-02T05:13:46.073213Z","end":"2026-07-02T05:13:46.45626Z","steps":["trace[1937154114] 'agreement among raft nodes before linearized reading' (duration: 382.973519ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:46.4563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:13:46.073201Z","time spent":"383.092402ms","remote":"127.0.0.1:44340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-02T05:13:46.456345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.215525ms","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-07-02T05:13:46.456423Z","caller":"traceutil/trace.go:171","msg":"trace[353373289] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:634; }","duration":"278.309007ms","start":"2026-07-02T05:13:46.178091Z","end":"2026-07-02T05:13:46.4564Z","steps":["trace[353373289] 'agreement among raft nodes before linearized reading' (duration: 278.176704ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:46.456497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.180293ms","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-07-02T05:13:46.456603Z","caller":"traceutil/trace.go:171","msg":"trace[419366509] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:634; }","duration":"162.331387ms","start":"2026-07-02T05:13:46.294239Z","end":"2026-07-02T05:13:46.45657Z","steps":["trace[419366509] 'agreement among raft nodes before linearized reading' (duration: 162.046019ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:13:46.930877Z","caller":"traceutil/trace.go:171","msg":"trace[163981759] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"430.052047ms","start":"2026-07-02T05:13:46.5008Z","end":"2026-07-02T05:13:46.930852Z","steps":["trace[163981759] 'process raft request' (duration: 429.904173ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:46.931045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:13:46.500772Z","time spent":"430.18864ms","remote":"127.0.0.1:43682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:13:46.931278Z","caller":"traceutil/trace.go:171","msg":"trace[183112616] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:661; }","duration":"238.872406ms","start":"2026-07-02T05:13:46.692394Z","end":"2026-07-02T05:13:46.931266Z","steps":["trace[183112616] 'read index received' (duration: 238.867966ms)","trace[183112616] 'applied index is now lower than readState.Index' (duration: 3.56µs)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:13:46.931363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.962948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.248\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-02T05:13:46.931432Z","caller":"traceutil/trace.go:171","msg":"trace[1048179879] range","detail":"{range_begin:/registry/masterleases/199.204.45.248; range_end:; response_count:1; response_revision:637; }","duration":"239.069441ms","start":"2026-07-02T05:13:46.692353Z","end":"2026-07-02T05:13:46.931422Z","steps":["trace[1048179879] 'agreement among raft nodes before linearized reading' (duration: 238.963218ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:14:01.799257Z","caller":"traceutil/trace.go:171","msg":"trace[2113151200] linearizableReadLoop","detail":"{readStateIndex:825; appliedIndex:824; }","duration":"190.990085ms","start":"2026-07-02T05:14:01.608241Z","end":"2026-07-02T05:14:01.799232Z","steps":["trace[2113151200] 'read index received' (duration: 190.714327ms)","trace[2113151200] 'applied index is now lower than readState.Index' (duration: 274.798µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:14:01.799318Z","caller":"traceutil/trace.go:171","msg":"trace[1471321429] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"209.844068ms","start":"2026-07-02T05:14:01.589437Z","end":"2026-07-02T05:14:01.799281Z","steps":["trace[1471321429] 'process raft request' (duration: 209.641882ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:14:01.799457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.192802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-02T05:14:01.799496Z","caller":"traceutil/trace.go:171","msg":"trace[1602125473] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:797; }","duration":"191.291354ms","start":"2026-07-02T05:14:01.608195Z","end":"2026-07-02T05:14:01.799486Z","steps":["trace[1602125473] 'agreement among raft nodes before linearized reading' (duration: 191.14301ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:16:54.624689Z","caller":"traceutil/trace.go:171","msg":"trace[1806667739] transaction","detail":"{read_only:false; response_revision:2610; number_of_response:1; }","duration":"175.418581ms","start":"2026-07-02T05:16:54.449244Z","end":"2026-07-02T05:16:54.624663Z","steps":["trace[1806667739] 'process raft request' (duration: 175.253536ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:17:23.658988Z","caller":"traceutil/trace.go:171","msg":"trace[1938857572] transaction","detail":"{read_only:false; response_revision:2790; number_of_response:1; }","duration":"121.361171ms","start":"2026-07-02T05:17:23.537599Z","end":"2026-07-02T05:17:23.65896Z","steps":["trace[1938857572] 'process raft request' (duration: 106.179027ms)","trace[1938857572] 'compare' (duration: 14.999779ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:17:23.659135Z","caller":"traceutil/trace.go:171","msg":"trace[1641915644] transaction","detail":"{read_only:false; response_revision:2791; number_of_response:1; }","duration":"105.496307ms","start":"2026-07-02T05:17:23.553604Z","end":"2026-07-02T05:17:23.6591Z","steps":["trace[1641915644] 'process raft request' (duration: 105.310692ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:17:29.229284Z","caller":"traceutil/trace.go:171","msg":"trace[1363788889] transaction","detail":"{read_only:false; response_revision:2818; number_of_response:1; }","duration":"118.452169ms","start":"2026-07-02T05:17:29.110806Z","end":"2026-07-02T05:17:29.229259Z","steps":["trace[1363788889] 'process raft request' (duration: 118.306155ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:17:29.415551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.560649ms","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-07-02T05:17:29.415633Z","caller":"traceutil/trace.go:171","msg":"trace[1959097797] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2818; }","duration":"115.656851ms","start":"2026-07-02T05:17:29.299957Z","end":"2026-07-02T05:17:29.415614Z","steps":["trace[1959097797] 'range keys from in-memory index tree' (duration: 115.340712ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:17:29.539669Z","caller":"traceutil/trace.go:171","msg":"trace[2034440475] transaction","detail":"{read_only:false; response_revision:2819; number_of_response:1; }","duration":"119.644152ms","start":"2026-07-02T05:17:29.419995Z","end":"2026-07-02T05:17:29.53964Z","steps":["trace[2034440475] 'process raft request' (duration: 119.507328ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:17:29.762924Z","caller":"traceutil/trace.go:171","msg":"trace[545827204] transaction","detail":"{read_only:false; response_revision:2820; number_of_response:1; }","duration":"162.576691ms","start":"2026-07-02T05:17:29.600321Z","end":"2026-07-02T05:17:29.762898Z","steps":["trace[545827204] 'process raft request' (duration: 76.015813ms)","trace[545827204] 'compare' (duration: 86.384703ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:21:34.220094Z","caller":"traceutil/trace.go:171","msg":"trace[50348117] transaction","detail":"{read_only:false; response_revision:5225; number_of_response:1; }","duration":"234.299196ms","start":"2026-07-02T05:21:33.98577Z","end":"2026-07-02T05:21:34.220069Z","steps":["trace[50348117] 'process raft request' (duration: 138.964493ms)","trace[50348117] 'compare' (duration: 94.814499ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:21:34.220262Z","caller":"traceutil/trace.go:171","msg":"trace[782335764] transaction","detail":"{read_only:false; response_revision:5226; number_of_response:1; }","duration":"230.799269ms","start":"2026-07-02T05:21:33.989446Z","end":"2026-07-02T05:21:34.220245Z","steps":["trace[782335764] 'process raft request' (duration: 230.576633ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:21:34.220464Z","caller":"traceutil/trace.go:171","msg":"trace[1905645989] transaction","detail":"{read_only:false; response_revision:5227; number_of_response:1; }","duration":"230.909222ms","start":"2026-07-02T05:21:33.989548Z","end":"2026-07-02T05:21:34.220457Z","steps":["trace[1905645989] 'process raft request' (duration: 230.636294ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:21:34.220627Z","caller":"traceutil/trace.go:171","msg":"trace[316738267] transaction","detail":"{read_only:false; response_revision:5228; number_of_response:1; }","duration":"229.02054ms","start":"2026-07-02T05:21:33.991591Z","end":"2026-07-02T05:21:34.220611Z","steps":["trace[316738267] 'process raft request' (duration: 228.782503ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:21:34.220787Z","caller":"traceutil/trace.go:171","msg":"trace[1428913855] linearizableReadLoop","detail":"{readStateIndex:5425; appliedIndex:5421; }","duration":"171.738112ms","start":"2026-07-02T05:21:34.049039Z","end":"2026-07-02T05:21:34.220777Z","steps":["trace[1428913855] 'read index received' (duration: 75.643767ms)","trace[1428913855] 'applied index is now lower than readState.Index' (duration: 96.093395ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:21:34.220863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.808803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:21:34.220895Z","caller":"traceutil/trace.go:171","msg":"trace[1258563778] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5229; }","duration":"171.883096ms","start":"2026-07-02T05:21:34.049004Z","end":"2026-07-02T05:21:34.220887Z","steps":["trace[1258563778] 'agreement among raft nodes before linearized reading' (duration: 171.816404ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:21:42.088433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.378649ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-02T05:21:42.226522Z","caller":"traceutil/trace.go:171","msg":"trace[1133025408] transaction","detail":"{read_only:false; response_revision:5394; number_of_response:1; }","duration":"114.142845ms","start":"2026-07-02T05:21:42.112351Z","end":"2026-07-02T05:21:42.226494Z","steps":["trace[1133025408] 'process raft request' (duration: 113.944739ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:21:52.95714Z","caller":"traceutil/trace.go:171","msg":"trace[1569159917] transaction","detail":"{read_only:false; response_revision:5546; number_of_response:1; }","duration":"105.865966ms","start":"2026-07-02T05:21:52.85125Z","end":"2026-07-02T05:21:52.957116Z","steps":["trace[1569159917] 'process raft request' (duration: 105.554607ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:23:03.615338Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3006} {"level":"info","ts":"2026-07-02T05:23:03.694263Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3006,"took":"77.333077ms","hash":3857098949,"current-db-size-bytes":57397248,"current-db-size":"57 MB","current-db-size-in-use-bytes":31854592,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-02T05:23:03.694368Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3857098949,"revision":3006,"compact-revision":-1} {"level":"info","ts":"2026-07-02T05:25:53.227224Z","caller":"traceutil/trace.go:171","msg":"trace[1131583215] transaction","detail":"{read_only:false; response_revision:7310; number_of_response:1; }","duration":"105.323859ms","start":"2026-07-02T05:25:53.121867Z","end":"2026-07-02T05:25:53.227191Z","steps":["trace[1131583215] 'process raft request' (duration: 105.126934ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:27:41.330512Z","caller":"traceutil/trace.go:171","msg":"trace[2108225937] transaction","detail":"{read_only:false; response_revision:8197; number_of_response:1; }","duration":"251.147094ms","start":"2026-07-02T05:27:41.079338Z","end":"2026-07-02T05:27:41.330485Z","steps":["trace[2108225937] 'process raft request' (duration: 250.984639ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:27:53.182Z","caller":"traceutil/trace.go:171","msg":"trace[1120965899] transaction","detail":"{read_only:false; response_revision:8256; number_of_response:1; }","duration":"253.55533ms","start":"2026-07-02T05:27:52.92842Z","end":"2026-07-02T05:27:53.181975Z","steps":["trace[1120965899] 'process raft request' (duration: 253.407366ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:27:53.279377Z","caller":"traceutil/trace.go:171","msg":"trace[18151422] linearizableReadLoop","detail":"{readStateIndex:8548; appliedIndex:8546; }","duration":"296.012737ms","start":"2026-07-02T05:27:52.983334Z","end":"2026-07-02T05:27:53.279346Z","steps":["trace[18151422] 'read index received' (duration: 198.485723ms)","trace[18151422] 'applied index is now lower than readState.Index' (duration: 97.525434ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:27:53.279487Z","caller":"traceutil/trace.go:171","msg":"trace[573569458] transaction","detail":"{read_only:false; response_revision:8257; number_of_response:1; }","duration":"348.719189ms","start":"2026-07-02T05:27:52.930739Z","end":"2026-07-02T05:27:53.279458Z","steps":["trace[573569458] 'process raft request' (duration: 348.440031ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:27:53.279582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.314793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-02T05:27:53.279623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:27:52.930723Z","time spent":"348.824321ms","remote":"127.0.0.1:44340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:27:53.279639Z","caller":"traceutil/trace.go:171","msg":"trace[357743249] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8257; }","duration":"231.421426ms","start":"2026-07-02T05:27:53.048203Z","end":"2026-07-02T05:27:53.279624Z","steps":["trace[357743249] 'agreement among raft nodes before linearized reading' (duration: 231.321643ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:27:53.279743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.387638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-07-02T05:27:53.27981Z","caller":"traceutil/trace.go:171","msg":"trace[1422414490] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8257; }","duration":"296.532302ms","start":"2026-07-02T05:27:52.983263Z","end":"2026-07-02T05:27:53.279795Z","steps":["trace[1422414490] 'agreement among raft nodes before linearized reading' (duration: 296.238964ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:27:53.954884Z","caller":"traceutil/trace.go:171","msg":"trace[1557743762] transaction","detail":"{read_only:false; response_revision:8260; number_of_response:1; }","duration":"144.584058ms","start":"2026-07-02T05:27:53.810278Z","end":"2026-07-02T05:27:53.954862Z","steps":["trace[1557743762] 'process raft request' (duration: 144.455835ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:27:54.197273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.175578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-02T05:27:54.197348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.001693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-02T05:27:54.197416Z","caller":"traceutil/trace.go:171","msg":"trace[2045545780] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8260; }","duration":"148.374223ms","start":"2026-07-02T05:27:54.049015Z","end":"2026-07-02T05:27:54.19739Z","steps":["trace[2045545780] 'range keys from in-memory index tree' (duration: 147.998764ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:27:54.197435Z","caller":"traceutil/trace.go:171","msg":"trace[39807054] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8260; }","duration":"113.103026ms","start":"2026-07-02T05:27:54.084304Z","end":"2026-07-02T05:27:54.197407Z","steps":["trace[39807054] 'range keys from in-memory index tree' (duration: 112.787537ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:27:55.473881Z","caller":"traceutil/trace.go:171","msg":"trace[122641142] linearizableReadLoop","detail":"{readStateIndex:8560; appliedIndex:8559; }","duration":"136.768282ms","start":"2026-07-02T05:27:55.337072Z","end":"2026-07-02T05:27:55.47384Z","steps":["trace[122641142] 'read index received' (duration: 120.438999ms)","trace[122641142] 'applied index is now lower than readState.Index' (duration: 16.327953ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:27:55.473935Z","caller":"traceutil/trace.go:171","msg":"trace[632532575] transaction","detail":"{read_only:false; response_revision:8269; number_of_response:1; }","duration":"158.00034ms","start":"2026-07-02T05:27:55.315882Z","end":"2026-07-02T05:27:55.473882Z","steps":["trace[632532575] 'process raft request' (duration: 141.715249ms)","trace[632532575] 'compare' (duration: 16.019404ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:27:55.474243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.137102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-07-02T05:27:55.474337Z","caller":"traceutil/trace.go:171","msg":"trace[391450283] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8269; }","duration":"137.295377ms","start":"2026-07-02T05:27:55.337021Z","end":"2026-07-02T05:27:55.474316Z","steps":["trace[391450283] 'agreement among raft nodes before linearized reading' (duration: 136.994199ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:28:03.621328Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6141} {"level":"info","ts":"2026-07-02T05:28:03.715612Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6141,"took":"92.136035ms","hash":2072975477,"current-db-size-bytes":57397248,"current-db-size":"57 MB","current-db-size-in-use-bytes":23429120,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-07-02T05:28:03.715691Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2072975477,"revision":6141,"compact-revision":3006} {"level":"warn","ts":"2026-07-02T05:28:22.588792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.345732ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T05:28:22.589005Z","caller":"traceutil/trace.go:171","msg":"trace[1704729011] linearizableReadLoop","detail":"{readStateIndex:8768; appliedIndex:8766; }","duration":"256.935704ms","start":"2026-07-02T05:28:22.332051Z","end":"2026-07-02T05:28:22.588987Z","steps":["trace[1704729011] 'read index received' (duration: 72.322065ms)","trace[1704729011] 'applied index is now lower than readState.Index' (duration: 184.612539ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:28:22.589089Z","caller":"traceutil/trace.go:171","msg":"trace[529787363] transaction","detail":"{read_only:false; response_revision:8468; number_of_response:1; }","duration":"299.034871ms","start":"2026-07-02T05:28:22.29004Z","end":"2026-07-02T05:28:22.589075Z","steps":["trace[529787363] 'process raft request' (duration: 298.883247ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:28:22.589187Z","caller":"traceutil/trace.go:171","msg":"trace[164610348] transaction","detail":"{read_only:false; response_revision:8467; number_of_response:1; }","duration":"476.787859ms","start":"2026-07-02T05:28:22.11236Z","end":"2026-07-02T05:28:22.589148Z","steps":["trace[164610348] 'process raft request' (duration: 291.997736ms)","trace[164610348] 'compare' (duration: 183.782455ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:28:22.5894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:28:22.112348Z","time spent":"476.965474ms","remote":"127.0.0.1:44340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:28:22.589417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.334575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-02T05:28:22.589603Z","caller":"traceutil/trace.go:171","msg":"trace[1836152342] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8468; }","duration":"257.557411ms","start":"2026-07-02T05:28:22.332015Z","end":"2026-07-02T05:28:22.589572Z","steps":["trace[1836152342] 'agreement among raft nodes before linearized reading' (duration: 257.058997ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:28:22.590714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.347362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-02T05:28:22.590753Z","caller":"traceutil/trace.go:171","msg":"trace[1064923090] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8469; }","duration":"254.427124ms","start":"2026-07-02T05:28:22.336316Z","end":"2026-07-02T05:28:22.590743Z","steps":["trace[1064923090] 'agreement among raft nodes before linearized reading' (duration: 254.27909ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:28:34.719802Z","caller":"traceutil/trace.go:171","msg":"trace[1297731624] transaction","detail":"{read_only:false; response_revision:8573; number_of_response:1; }","duration":"178.030905ms","start":"2026-07-02T05:28:34.541746Z","end":"2026-07-02T05:28:34.719777Z","steps":["trace[1297731624] 'process raft request' (duration: 91.315042ms)","trace[1297731624] 'compare' (duration: 86.473497ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:28:35.559477Z","caller":"traceutil/trace.go:171","msg":"trace[675738139] transaction","detail":"{read_only:false; response_revision:8579; number_of_response:1; }","duration":"164.176432ms","start":"2026-07-02T05:28:35.395258Z","end":"2026-07-02T05:28:35.559434Z","steps":["trace[675738139] 'process raft request' (duration: 163.948536ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:28:36.116419Z","caller":"traceutil/trace.go:171","msg":"trace[250437246] transaction","detail":"{read_only:false; response_revision:8585; number_of_response:1; }","duration":"142.759938ms","start":"2026-07-02T05:28:35.973626Z","end":"2026-07-02T05:28:36.116386Z","steps":["trace[250437246] 'process raft request' (duration: 142.443609ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:29:26.194399Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024ca.wal"} {"level":"info","ts":"2026-07-02T05:29:38.243726Z","caller":"traceutil/trace.go:171","msg":"trace[368007268] linearizableReadLoop","detail":"{readStateIndex:9575; appliedIndex:9574; }","duration":"123.567196ms","start":"2026-07-02T05:29:38.120128Z","end":"2026-07-02T05:29:38.243696Z","steps":["trace[368007268] 'read index received' (duration: 123.283978ms)","trace[368007268] 'applied index is now lower than readState.Index' (duration: 281.868µs)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:29:38.243966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.813553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-cl2h4\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-07-02T05:29:38.244035Z","caller":"traceutil/trace.go:171","msg":"trace[1255272909] range","detail":"{range_begin:/registry/pods/openstack/keepalived-cl2h4; range_end:; response_count:1; response_revision:9253; }","duration":"123.927956ms","start":"2026-07-02T05:29:38.120086Z","end":"2026-07-02T05:29:38.244014Z","steps":["trace[1255272909] 'agreement among raft nodes before linearized reading' (duration: 123.723651ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:29:38.243835Z","caller":"traceutil/trace.go:171","msg":"trace[1377301689] transaction","detail":"{read_only:false; response_revision:9253; number_of_response:1; }","duration":"123.857384ms","start":"2026-07-02T05:29:38.11994Z","end":"2026-07-02T05:29:38.243797Z","steps":["trace[1377301689] 'process raft request' (duration: 123.536905ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:29:50.357039Z","caller":"traceutil/trace.go:171","msg":"trace[379822670] linearizableReadLoop","detail":"{readStateIndex:9651; appliedIndex:9650; }","duration":"160.028166ms","start":"2026-07-02T05:29:50.196981Z","end":"2026-07-02T05:29:50.357009Z","steps":["trace[379822670] 'read index received' (duration: 151.306774ms)","trace[379822670] 'applied index is now lower than readState.Index' (duration: 8.720112ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:29:50.357078Z","caller":"traceutil/trace.go:171","msg":"trace[297026664] transaction","detail":"{read_only:false; response_revision:9327; number_of_response:1; }","duration":"265.15504ms","start":"2026-07-02T05:29:50.0919Z","end":"2026-07-02T05:29:50.357055Z","steps":["trace[297026664] 'process raft request' (duration: 256.364836ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:29:50.35722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.216981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-02T05:29:50.357371Z","caller":"traceutil/trace.go:171","msg":"trace[853703617] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:9327; }","duration":"160.448767ms","start":"2026-07-02T05:29:50.196895Z","end":"2026-07-02T05:29:50.357344Z","steps":["trace[853703617] 'agreement among raft nodes before linearized reading' (duration: 160.212851ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:30:37.339343Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"39582465ceaa2f67","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-02T05:30:37.344417Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-02T05:30:37.344495Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-02T05:33:03.628308Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8319} {"level":"info","ts":"2026-07-02T05:33:03.704623Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8319,"took":"73.808165ms","hash":1019903380,"current-db-size-bytes":57397248,"current-db-size":"57 MB","current-db-size-in-use-bytes":26550272,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-07-02T05:33:03.704703Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1019903380,"revision":8319,"compact-revision":6141} {"level":"info","ts":"2026-07-02T05:33:33.387646Z","caller":"traceutil/trace.go:171","msg":"trace[1151635669] transaction","detail":"{read_only:false; response_revision:11173; number_of_response:1; }","duration":"248.877237ms","start":"2026-07-02T05:33:33.138744Z","end":"2026-07-02T05:33:33.387621Z","steps":["trace[1151635669] 'process raft request' (duration: 240.376961ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:38:03.634455Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10977} {"level":"info","ts":"2026-07-02T05:38:03.714587Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10977,"took":"77.348367ms","hash":3823491579,"current-db-size-bytes":57397248,"current-db-size":"57 MB","current-db-size-in-use-bytes":28123136,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-07-02T05:38:03.714669Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3823491579,"revision":10977,"compact-revision":8319} {"level":"info","ts":"2026-07-02T05:39:18.759441Z","caller":"traceutil/trace.go:171","msg":"trace[2125111345] transaction","detail":"{read_only:false; response_revision:13551; number_of_response:1; }","duration":"101.320599ms","start":"2026-07-02T05:39:18.658083Z","end":"2026-07-02T05:39:18.759404Z","steps":["trace[2125111345] 'process raft request' (duration: 101.064822ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:39:53.055966Z","caller":"traceutil/trace.go:171","msg":"trace[726053020] transaction","detail":"{read_only:false; response_revision:13741; number_of_response:1; }","duration":"105.419102ms","start":"2026-07-02T05:39:52.950514Z","end":"2026-07-02T05:39:53.055933Z","steps":["trace[726053020] 'process raft request' (duration: 105.246357ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:40:15.844229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.98577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-07-02T05:40:15.844351Z","caller":"traceutil/trace.go:171","msg":"trace[1697076693] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:14056; }","duration":"109.186147ms","start":"2026-07-02T05:40:15.735142Z","end":"2026-07-02T05:40:15.844328Z","steps":["trace[1697076693] 'range keys from in-memory index tree' (duration: 108.658701ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:40:15.950552Z","caller":"traceutil/trace.go:171","msg":"trace[1536700891] transaction","detail":"{read_only:false; response_revision:14057; number_of_response:1; }","duration":"101.950816ms","start":"2026-07-02T05:40:15.84857Z","end":"2026-07-02T05:40:15.95052Z","steps":["trace[1536700891] 'process raft request' (duration: 101.71292ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:41:25.608023Z","caller":"traceutil/trace.go:171","msg":"trace[1023602559] transaction","detail":"{read_only:false; response_revision:14784; number_of_response:1; }","duration":"132.590662ms","start":"2026-07-02T05:41:25.475393Z","end":"2026-07-02T05:41:25.607983Z","steps":["trace[1023602559] 'process raft request' (duration: 128.069528ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:41:25.608123Z","caller":"traceutil/trace.go:171","msg":"trace[1292338259] linearizableReadLoop","detail":"{readStateIndex:15290; appliedIndex:15289; }","duration":"111.47942ms","start":"2026-07-02T05:41:25.496382Z","end":"2026-07-02T05:41:25.607861Z","steps":["trace[1292338259] 'read index received' (duration: 107.1224ms)","trace[1292338259] 'applied index is now lower than readState.Index' (duration: 4.3556ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:41:25.608348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.928622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-02T05:41:25.608442Z","caller":"traceutil/trace.go:171","msg":"trace[2108146658] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14784; }","duration":"112.066145ms","start":"2026-07-02T05:41:25.496355Z","end":"2026-07-02T05:41:25.608421Z","steps":["trace[2108146658] 'agreement among raft nodes before linearized reading' (duration: 111.804208ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:41:25.608481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.832729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-sxd4b\" ","response":"range_response_count:1 size:22131"} {"level":"info","ts":"2026-07-02T05:41:25.608663Z","caller":"traceutil/trace.go:171","msg":"trace[809086003] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-sxd4b; range_end:; response_count:1; response_revision:14784; }","duration":"112.027595ms","start":"2026-07-02T05:41:25.496617Z","end":"2026-07-02T05:41:25.608644Z","steps":["trace[809086003] 'agreement among raft nodes before linearized reading' (duration: 111.650894ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:41:31.156626Z","caller":"traceutil/trace.go:171","msg":"trace[869217915] transaction","detail":"{read_only:false; response_revision:14812; number_of_response:1; }","duration":"115.951753ms","start":"2026-07-02T05:41:31.040638Z","end":"2026-07-02T05:41:31.156589Z","steps":["trace[869217915] 'process raft request' (duration: 115.711517ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:41:32.171147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.788739ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-02T05:41:32.171311Z","caller":"traceutil/trace.go:171","msg":"trace[661524649] linearizableReadLoop","detail":"{readStateIndex:15325; appliedIndex:15324; }","duration":"279.591924ms","start":"2026-07-02T05:41:31.891654Z","end":"2026-07-02T05:41:32.171246Z","steps":["trace[661524649] 'read index received' (duration: 49.702µs)","trace[661524649] 'applied index is now lower than readState.Index' (duration: 279.538842ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:41:32.171573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.517883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-02T05:41:32.171634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.973974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-cl2h4\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-07-02T05:41:32.171655Z","caller":"traceutil/trace.go:171","msg":"trace[1899923101] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:14817; }","duration":"232.641267ms","start":"2026-07-02T05:41:31.938998Z","end":"2026-07-02T05:41:32.171639Z","steps":["trace[1899923101] 'agreement among raft nodes before linearized reading' (duration: 232.517293ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:41:32.171698Z","caller":"traceutil/trace.go:171","msg":"trace[1226597514] range","detail":"{range_begin:/registry/pods/openstack/keepalived-cl2h4; range_end:; response_count:1; response_revision:14817; }","duration":"280.079128ms","start":"2026-07-02T05:41:31.891587Z","end":"2026-07-02T05:41:32.171666Z","steps":["trace[1226597514] 'agreement among raft nodes before linearized reading' (duration: 279.908653ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:41:32.171724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.457571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"warn","ts":"2026-07-02T05:41:32.17191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.891322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-07-02T05:41:32.171858Z","caller":"traceutil/trace.go:171","msg":"trace[1432720311] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14817; }","duration":"224.746988ms","start":"2026-07-02T05:41:31.947082Z","end":"2026-07-02T05:41:32.171829Z","steps":["trace[1432720311] 'agreement among raft nodes before linearized reading' (duration: 224.43722ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:41:32.171951Z","caller":"traceutil/trace.go:171","msg":"trace[1666260770] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14817; }","duration":"224.953034ms","start":"2026-07-02T05:41:31.946988Z","end":"2026-07-02T05:41:32.171941Z","steps":["trace[1666260770] 'agreement among raft nodes before linearized reading' (duration: 224.849441ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:41:32.172187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.963185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:41:32.172233Z","caller":"traceutil/trace.go:171","msg":"trace[47801658] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14817; }","duration":"229.028857ms","start":"2026-07-02T05:41:31.94319Z","end":"2026-07-02T05:41:32.172219Z","steps":["trace[47801658] 'agreement among raft nodes before linearized reading' (duration: 228.947185ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:41:32.172239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.840312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-02T05:41:32.172308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.919946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:41:32.172342Z","caller":"traceutil/trace.go:171","msg":"trace[770182778] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:14817; }","duration":"232.978347ms","start":"2026-07-02T05:41:31.939337Z","end":"2026-07-02T05:41:32.172315Z","steps":["trace[770182778] 'agreement among raft nodes before linearized reading' (duration: 232.763491ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:41:32.172376Z","caller":"traceutil/trace.go:171","msg":"trace[1053033338] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14817; }","duration":"123.027199ms","start":"2026-07-02T05:41:32.049329Z","end":"2026-07-02T05:41:32.172357Z","steps":["trace[1053033338] 'agreement among raft nodes before linearized reading' (duration: 122.891625ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:43:03.640865Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13028} {"level":"info","ts":"2026-07-02T05:43:03.719111Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13028,"took":"76.109914ms","hash":1199420474,"current-db-size-bytes":57397248,"current-db-size":"57 MB","current-db-size-in-use-bytes":31080448,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-02T05:43:03.719172Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1199420474,"revision":13028,"compact-revision":10977} {"level":"info","ts":"2026-07-02T05:47:18.576431Z","caller":"traceutil/trace.go:171","msg":"trace[206460555] linearizableReadLoop","detail":"{readStateIndex:17988; appliedIndex:17987; }","duration":"257.378018ms","start":"2026-07-02T05:47:18.319026Z","end":"2026-07-02T05:47:18.576404Z","steps":["trace[206460555] 'read index received' (duration: 257.318557ms)","trace[206460555] 'applied index is now lower than readState.Index' (duration: 58.161µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:47:18.576537Z","caller":"traceutil/trace.go:171","msg":"trace[1758796067] transaction","detail":"{read_only:false; response_revision:17395; number_of_response:1; }","duration":"261.07308ms","start":"2026-07-02T05:47:18.315413Z","end":"2026-07-02T05:47:18.576486Z","steps":["trace[1758796067] 'process raft request' (duration: 260.728571ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:47:18.576702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.647447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-02T05:47:18.576782Z","caller":"traceutil/trace.go:171","msg":"trace[1912071458] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17395; }","duration":"257.767819ms","start":"2026-07-02T05:47:18.318994Z","end":"2026-07-02T05:47:18.576761Z","steps":["trace[1912071458] 'agreement among raft nodes before linearized reading' (duration: 257.552123ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:47:18.592534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.07385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-07-02T05:47:18.592633Z","caller":"traceutil/trace.go:171","msg":"trace[1135493864] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0; range_end:; response_count:1; response_revision:17396; }","duration":"177.228204ms","start":"2026-07-02T05:47:18.415381Z","end":"2026-07-02T05:47:18.592609Z","steps":["trace[1135493864] 'agreement among raft nodes before linearized reading' (duration: 176.973757ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:47:18.59262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.64271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-g76wl\" ","response":"range_response_count:1 size:9800"} {"level":"info","ts":"2026-07-02T05:47:18.592692Z","caller":"traceutil/trace.go:171","msg":"trace[657649697] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-g76wl; range_end:; response_count:1; response_revision:17396; }","duration":"125.762573ms","start":"2026-07-02T05:47:18.466911Z","end":"2026-07-02T05:47:18.592674Z","steps":["trace[657649697] 'agreement among raft nodes before linearized reading' (duration: 125.561637ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:47:18.724477Z","caller":"traceutil/trace.go:171","msg":"trace[1133099853] linearizableReadLoop","detail":"{readStateIndex:17990; appliedIndex:17989; }","duration":"128.51842ms","start":"2026-07-02T05:47:18.595915Z","end":"2026-07-02T05:47:18.724433Z","steps":["trace[1133099853] 'read index received' (duration: 81.916162ms)","trace[1133099853] 'applied index is now lower than readState.Index' (duration: 46.600748ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:47:18.724693Z","caller":"traceutil/trace.go:171","msg":"trace[1393105504] transaction","detail":"{read_only:false; response_revision:17397; number_of_response:1; }","duration":"143.656916ms","start":"2026-07-02T05:47:18.580979Z","end":"2026-07-02T05:47:18.724636Z","steps":["trace[1393105504] 'process raft request' (duration: 96.904085ms)","trace[1393105504] 'compare' (duration: 46.33194ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:47:18.724883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.931541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-db776131-04ca-4119-9827-8282d690376a\" ","response":"range_response_count:1 size:1320"} {"level":"info","ts":"2026-07-02T05:47:18.724945Z","caller":"traceutil/trace.go:171","msg":"trace[119093602] range","detail":"{range_begin:/registry/persistentvolumes/pvc-db776131-04ca-4119-9827-8282d690376a; range_end:; response_count:1; response_revision:17397; }","duration":"129.024063ms","start":"2026-07-02T05:47:18.5959Z","end":"2026-07-02T05:47:18.724924Z","steps":["trace[119093602] 'agreement among raft nodes before linearized reading' (duration: 128.755596ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:47:18.725611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.380488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:94 size:946129"} {"level":"info","ts":"2026-07-02T05:47:18.7257Z","caller":"traceutil/trace.go:171","msg":"trace[558538610] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:94; response_revision:17397; }","duration":"127.470691ms","start":"2026-07-02T05:47:18.598191Z","end":"2026-07-02T05:47:18.725661Z","steps":["trace[558538610] 'agreement among raft nodes before linearized reading' (duration: 126.534145ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:48:03.647698Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15417} {"level":"info","ts":"2026-07-02T05:48:03.74677Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15417,"took":"95.23176ms","hash":1175525865,"current-db-size-bytes":57397248,"current-db-size":"57 MB","current-db-size-in-use-bytes":33533952,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-02T05:48:03.746859Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1175525865,"revision":15417,"compact-revision":13028} {"level":"info","ts":"2026-07-02T05:51:12.89932Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"39582465ceaa2f67","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-02T05:51:12.908255Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-02T05:51:12.908352Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"warn","ts":"2026-07-02T05:52:48.09306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.393447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-07-02T05:52:48.093225Z","caller":"traceutil/trace.go:171","msg":"trace[916955345] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:19988; }","duration":"209.629254ms","start":"2026-07-02T05:52:47.883558Z","end":"2026-07-02T05:52:48.093187Z","steps":["trace[916955345] 'range keys from in-memory index tree' (duration: 209.15261ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:52:48.458043Z","caller":"traceutil/trace.go:171","msg":"trace[506441380] linearizableReadLoop","detail":"{readStateIndex:20666; appliedIndex:20665; }","duration":"407.295938ms","start":"2026-07-02T05:52:48.05071Z","end":"2026-07-02T05:52:48.458006Z","steps":["trace[506441380] 'read index received' (duration: 365.57885ms)","trace[506441380] 'applied index is now lower than readState.Index' (duration: 41.715428ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:52:48.458129Z","caller":"traceutil/trace.go:171","msg":"trace[1852609794] transaction","detail":"{read_only:false; response_revision:19990; number_of_response:1; }","duration":"459.653001ms","start":"2026-07-02T05:52:47.998443Z","end":"2026-07-02T05:52:48.458096Z","steps":["trace[1852609794] 'process raft request' (duration: 417.758357ms)","trace[1852609794] 'compare' (duration: 41.519054ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:52:48.458207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.477003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-02T05:52:48.458272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:52:47.998405Z","time spent":"459.788645ms","remote":"127.0.0.1:44340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:52:48.458289Z","caller":"traceutil/trace.go:171","msg":"trace[2124912247] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19990; }","duration":"407.609277ms","start":"2026-07-02T05:52:48.050657Z","end":"2026-07-02T05:52:48.458267Z","steps":["trace[2124912247] 'agreement among raft nodes before linearized reading' (duration: 407.453462ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:52:48.458333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:52:48.050629Z","time spent":"407.695399ms","remote":"127.0.0.1:43476","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-07-02T05:52:48.458623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.043335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-02T05:52:48.458755Z","caller":"traceutil/trace.go:171","msg":"trace[1896635648] transaction","detail":"{read_only:false; response_revision:19991; number_of_response:1; }","duration":"360.644513ms","start":"2026-07-02T05:52:48.098085Z","end":"2026-07-02T05:52:48.458729Z","steps":["trace[1896635648] 'process raft request' (duration: 360.096048ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:52:48.458883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.002259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-02T05:52:48.458761Z","caller":"traceutil/trace.go:171","msg":"trace[286623209] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:19991; }","duration":"324.2264ms","start":"2026-07-02T05:52:48.134492Z","end":"2026-07-02T05:52:48.458719Z","steps":["trace[286623209] 'agreement among raft nodes before linearized reading' (duration: 323.887001ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:52:48.458935Z","caller":"traceutil/trace.go:171","msg":"trace[631922424] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:19991; }","duration":"212.091392ms","start":"2026-07-02T05:52:48.246833Z","end":"2026-07-02T05:52:48.458924Z","steps":["trace[631922424] 'agreement among raft nodes before linearized reading' (duration: 211.896796ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:52:48.458928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:52:48.09805Z","time spent":"360.772367ms","remote":"127.0.0.1:44340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:52:48.459018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:52:48.134474Z","time spent":"324.494378ms","remote":"127.0.0.1:44340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-02T05:52:48.632964Z","caller":"traceutil/trace.go:171","msg":"trace[1942446791] transaction","detail":"{read_only:false; response_revision:19992; number_of_response:1; }","duration":"169.689575ms","start":"2026-07-02T05:52:48.463237Z","end":"2026-07-02T05:52:48.632927Z","steps":["trace[1942446791] 'process raft request' (duration: 138.423794ms)","trace[1942446791] 'compare' (duration: 30.911231ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:52:48.63319Z","caller":"traceutil/trace.go:171","msg":"trace[1335365971] transaction","detail":"{read_only:false; response_revision:19993; number_of_response:1; }","duration":"169.056406ms","start":"2026-07-02T05:52:48.464115Z","end":"2026-07-02T05:52:48.633172Z","steps":["trace[1335365971] 'process raft request' (duration: 168.696116ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:52:48.898472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.125102ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T05:52:48.898672Z","caller":"traceutil/trace.go:171","msg":"trace[451291854] linearizableReadLoop","detail":"{readStateIndex:20670; appliedIndex:20669; }","duration":"260.730442ms","start":"2026-07-02T05:52:48.637927Z","end":"2026-07-02T05:52:48.898658Z","steps":["trace[451291854] 'read index received' (duration: 117.278561ms)","trace[451291854] 'applied index is now lower than readState.Index' (duration: 143.450811ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:52:48.898764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.842764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-02T05:52:48.898789Z","caller":"traceutil/trace.go:171","msg":"trace[635254010] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:19994; }","duration":"260.910666ms","start":"2026-07-02T05:52:48.637871Z","end":"2026-07-02T05:52:48.898781Z","steps":["trace[635254010] 'agreement among raft nodes before linearized reading' (duration: 260.828834ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:52:48.89885Z","caller":"traceutil/trace.go:171","msg":"trace[1447511587] transaction","detail":"{read_only:false; response_revision:19994; number_of_response:1; }","duration":"261.687918ms","start":"2026-07-02T05:52:48.637149Z","end":"2026-07-02T05:52:48.898837Z","steps":["trace[1447511587] 'process raft request' (duration: 118.121843ms)","trace[1447511587] 'compare' (duration: 143.01703ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:52:49.164741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.806757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-07-02T05:52:49.164749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.866911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-02T05:52:49.164805Z","caller":"traceutil/trace.go:171","msg":"trace[939600296] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:19994; }","duration":"116.91792ms","start":"2026-07-02T05:52:49.047873Z","end":"2026-07-02T05:52:49.164791Z","steps":["trace[939600296] 'range keys from in-memory index tree' (duration: 116.664643ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:52:49.164818Z","caller":"traceutil/trace.go:171","msg":"trace[1253050142] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:19994; }","duration":"119.966294ms","start":"2026-07-02T05:52:49.044836Z","end":"2026-07-02T05:52:49.164802Z","steps":["trace[1253050142] 'range keys from in-memory index tree' (duration: 119.705986ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:52:49.365438Z","caller":"traceutil/trace.go:171","msg":"trace[1396566865] linearizableReadLoop","detail":"{readStateIndex:20672; appliedIndex:20670; }","duration":"183.467853ms","start":"2026-07-02T05:52:49.181939Z","end":"2026-07-02T05:52:49.365407Z","steps":["trace[1396566865] 'read index received' (duration: 24.311359ms)","trace[1396566865] 'applied index is now lower than readState.Index' (duration: 159.155114ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:52:49.365511Z","caller":"traceutil/trace.go:171","msg":"trace[800845017] transaction","detail":"{read_only:false; response_revision:19996; number_of_response:1; }","duration":"196.334908ms","start":"2026-07-02T05:52:49.169142Z","end":"2026-07-02T05:52:49.365477Z","steps":["trace[800845017] 'process raft request' (duration: 171.337869ms)","trace[800845017] 'compare' (duration: 24.65951ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:52:49.365913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.950067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-07-02T05:52:49.366017Z","caller":"traceutil/trace.go:171","msg":"trace[938783288] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:19996; }","duration":"184.09124ms","start":"2026-07-02T05:52:49.181894Z","end":"2026-07-02T05:52:49.365986Z","steps":["trace[938783288] 'agreement among raft nodes before linearized reading' (duration: 183.691279ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:53:03.653933Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17964} {"level":"info","ts":"2026-07-02T05:53:03.772704Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17964,"took":"115.730728ms","hash":387338134,"current-db-size-bytes":57397248,"current-db-size":"57 MB","current-db-size-in-use-bytes":30023680,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-02T05:53:03.772873Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":387338134,"revision":17964,"compact-revision":15417} {"level":"warn","ts":"2026-07-02T05:56:37.794376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.667083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-02T05:56:37.794409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.329358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-07-02T05:56:37.794458Z","caller":"traceutil/trace.go:171","msg":"trace[608728254] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:22413; }","duration":"149.764835ms","start":"2026-07-02T05:56:37.644679Z","end":"2026-07-02T05:56:37.794444Z","steps":["trace[608728254] 'count revisions from in-memory index tree' (duration: 149.551619ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:37.794467Z","caller":"traceutil/trace.go:171","msg":"trace[99415496] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:22413; }","duration":"155.42733ms","start":"2026-07-02T05:56:37.639022Z","end":"2026-07-02T05:56:37.794449Z","steps":["trace[99415496] 'range keys from in-memory index tree' (duration: 155.118753ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:57:31.458187Z","caller":"traceutil/trace.go:171","msg":"trace[497715040] transaction","detail":"{read_only:false; response_revision:22951; number_of_response:1; }","duration":"118.817842ms","start":"2026-07-02T05:57:31.339347Z","end":"2026-07-02T05:57:31.458165Z","steps":["trace[497715040] 'process raft request' (duration: 34.193991ms)","trace[497715040] 'compare' (duration: 84.517218ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:58:03.659808Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20069} {"level":"info","ts":"2026-07-02T05:58:03.767271Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20069,"took":"103.618614ms","hash":3650697391,"current-db-size-bytes":57397248,"current-db-size":"57 MB","current-db-size-in-use-bytes":43634688,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-07-02T05:58:03.767352Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3650697391,"revision":20069,"compact-revision":17964} {"level":"info","ts":"2026-07-02T05:59:59.065251Z","caller":"traceutil/trace.go:171","msg":"trace[1825293730] transaction","detail":"{read_only:false; response_revision:24460; number_of_response:1; }","duration":"112.18823ms","start":"2026-07-02T05:59:58.95304Z","end":"2026-07-02T05:59:59.065228Z","steps":["trace[1825293730] 'process raft request' (duration: 111.866511ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:59:59.392832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.250351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-02T05:59:59.39292Z","caller":"traceutil/trace.go:171","msg":"trace[439215433] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24463; }","duration":"179.389405ms","start":"2026-07-02T05:59:59.213512Z","end":"2026-07-02T05:59:59.392901Z","steps":["trace[439215433] 'range keys from in-memory index tree' (duration: 179.074056ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:59:59.481131Z","caller":"traceutil/trace.go:171","msg":"trace[1278973520] transaction","detail":"{read_only:false; response_revision:24464; number_of_response:1; }","duration":"163.663834ms","start":"2026-07-02T05:59:59.317419Z","end":"2026-07-02T05:59:59.481083Z","steps":["trace[1278973520] 'process raft request' (duration: 163.466688ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:59:59.661824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.50717ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T05:59:59.662016Z","caller":"traceutil/trace.go:171","msg":"trace[2029703433] transaction","detail":"{read_only:false; response_revision:24466; number_of_response:1; }","duration":"177.507424ms","start":"2026-07-02T05:59:59.48448Z","end":"2026-07-02T05:59:59.661987Z","steps":["trace[2029703433] 'process raft request' (duration: 177.446402ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:59:59.662081Z","caller":"traceutil/trace.go:171","msg":"trace[1896357475] linearizableReadLoop","detail":"{readStateIndex:25269; appliedIndex:25268; }","duration":"181.00611ms","start":"2026-07-02T05:59:59.481056Z","end":"2026-07-02T05:59:59.662062Z","steps":["trace[1896357475] 'read index received' (duration: 45.295584ms)","trace[1896357475] 'applied index is now lower than readState.Index' (duration: 135.708766ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:59:59.66213Z","caller":"traceutil/trace.go:171","msg":"trace[1768484770] transaction","detail":"{read_only:false; response_revision:24465; number_of_response:1; }","duration":"263.587517ms","start":"2026-07-02T05:59:59.398512Z","end":"2026-07-02T05:59:59.6621Z","steps":["trace[1768484770] 'process raft request' (duration: 127.727207ms)","trace[1768484770] 'compare' (duration: 135.361056ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:59:59.662214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.662641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T05:59:59.662304Z","caller":"traceutil/trace.go:171","msg":"trace[994709426] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:24466; }","duration":"223.805155ms","start":"2026-07-02T05:59:59.43848Z","end":"2026-07-02T05:59:59.662286Z","steps":["trace[994709426] 'agreement among raft nodes before linearized reading' (duration: 223.644531ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:59:59.683358Z","caller":"traceutil/trace.go:171","msg":"trace[1345791451] transaction","detail":"{read_only:false; response_revision:24467; number_of_response:1; }","duration":"145.291839ms","start":"2026-07-02T05:59:59.538034Z","end":"2026-07-02T05:59:59.683326Z","steps":["trace[1345791451] 'process raft request' (duration: 145.079363ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:59:59.683384Z","caller":"traceutil/trace.go:171","msg":"trace[67067080] transaction","detail":"{read_only:false; response_revision:24468; number_of_response:1; }","duration":"108.1736ms","start":"2026-07-02T05:59:59.575183Z","end":"2026-07-02T05:59:59.683357Z","steps":["trace[67067080] 'process raft request' (duration: 108.058076ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:59:59.683601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.311477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-02T05:59:59.683673Z","caller":"traceutil/trace.go:171","msg":"trace[1821279904] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24468; }","duration":"115.423009ms","start":"2026-07-02T05:59:59.568233Z","end":"2026-07-02T05:59:59.683656Z","steps":["trace[1821279904] 'agreement among raft nodes before linearized reading' (duration: 115.050648ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T06:00:04.00078Z","caller":"traceutil/trace.go:171","msg":"trace[466039326] transaction","detail":"{read_only:false; response_revision:24539; number_of_response:1; }","duration":"306.372521ms","start":"2026-07-02T06:00:03.694386Z","end":"2026-07-02T06:00:04.000758Z","steps":["trace[466039326] 'process raft request' (duration: 306.231897ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T06:00:04.000992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T06:00:03.694372Z","time spent":"306.481945ms","remote":"127.0.0.1:44340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T06:00:04.046123Z","caller":"traceutil/trace.go:171","msg":"trace[968418910] transaction","detail":"{read_only:false; response_revision:24541; number_of_response:1; }","duration":"346.552475ms","start":"2026-07-02T06:00:03.699545Z","end":"2026-07-02T06:00:04.046098Z","steps":["trace[968418910] 'process raft request' (duration: 346.466922ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T06:00:04.046302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T06:00:03.699531Z","time spent":"346.670418ms","remote":"127.0.0.1:44340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T06:00:04.046381Z","caller":"traceutil/trace.go:171","msg":"trace[127915832] transaction","detail":"{read_only:false; response_revision:24542; number_of_response:1; }","duration":"186.14997ms","start":"2026-07-02T06:00:03.860214Z","end":"2026-07-02T06:00:04.046364Z","steps":["trace[127915832] 'process raft request' (duration: 185.845942ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T06:00:04.046564Z","caller":"traceutil/trace.go:171","msg":"trace[1522014798] linearizableReadLoop","detail":"{readStateIndex:25346; appliedIndex:25343; }","duration":"346.916054ms","start":"2026-07-02T06:00:03.699638Z","end":"2026-07-02T06:00:04.046554Z","steps":["trace[1522014798] 'read index received' (duration: 301.032665ms)","trace[1522014798] 'applied index is now lower than readState.Index' (duration: 45.882569ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T06:00:04.046676Z","caller":"traceutil/trace.go:171","msg":"trace[1890610427] transaction","detail":"{read_only:false; response_revision:24543; number_of_response:1; }","duration":"179.117057ms","start":"2026-07-02T06:00:03.867551Z","end":"2026-07-02T06:00:04.046668Z","steps":["trace[1890610427] 'process raft request' (duration: 178.742547ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T06:00:04.046824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.175641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-02T06:00:04.046862Z","caller":"traceutil/trace.go:171","msg":"trace[634836484] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24543; }","duration":"347.243454ms","start":"2026-07-02T06:00:03.69961Z","end":"2026-07-02T06:00:04.046853Z","steps":["trace[634836484] 'agreement among raft nodes before linearized reading' (duration: 347.13061ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T06:00:04.046881Z","caller":"traceutil/trace.go:171","msg":"trace[1386477666] transaction","detail":"{read_only:false; response_revision:24540; number_of_response:1; }","duration":"347.329306ms","start":"2026-07-02T06:00:03.699537Z","end":"2026-07-02T06:00:04.046866Z","steps":["trace[1386477666] 'process raft request' (duration: 346.317498ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T06:00:04.046948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.380729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-02T06:00:04.046965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T06:00:03.699515Z","time spent":"347.404408ms","remote":"127.0.0.1:44340","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-07-02T06:00:04.046986Z","caller":"traceutil/trace.go:171","msg":"trace[314408717] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:24543; }","duration":"260.463111ms","start":"2026-07-02T06:00:03.786513Z","end":"2026-07-02T06:00:04.046976Z","steps":["trace[314408717] 'agreement among raft nodes before linearized reading' (duration: 260.407149ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T06:00:04.04689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T06:00:03.699587Z","time spent":"347.295255ms","remote":"127.0.0.1:44340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-02T06:00:04.047437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.444742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T06:00:04.047477Z","caller":"traceutil/trace.go:171","msg":"trace[648415619] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:24543; }","duration":"102.488163ms","start":"2026-07-02T06:00:03.944979Z","end":"2026-07-02T06:00:04.047467Z","steps":["trace[648415619] 'agreement among raft nodes before linearized reading' (duration: 102.422892ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T06:00:04.047699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.133521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-02T06:00:04.047741Z","caller":"traceutil/trace.go:171","msg":"trace[1613024292] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:24543; }","duration":"213.179213ms","start":"2026-07-02T06:00:03.834551Z","end":"2026-07-02T06:00:04.04773Z","steps":["trace[1613024292] 'agreement among raft nodes before linearized reading' (duration: 213.07751ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T06:00:04.356015Z","caller":"traceutil/trace.go:171","msg":"trace[2134730479] transaction","detail":"{read_only:false; response_revision:24544; number_of_response:1; }","duration":"303.723159ms","start":"2026-07-02T06:00:04.052262Z","end":"2026-07-02T06:00:04.355985Z","steps":["trace[2134730479] 'process raft request' (duration: 228.264297ms)","trace[2134730479] 'compare' (duration: 75.300858ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T06:00:04.356152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T06:00:04.052219Z","time spent":"303.866473ms","remote":"127.0.0.1:44340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T06:00:04.35948Z","caller":"traceutil/trace.go:171","msg":"trace[223181344] transaction","detail":"{read_only:false; response_revision:24545; number_of_response:1; }","duration":"257.017736ms","start":"2026-07-02T06:00:04.102413Z","end":"2026-07-02T06:00:04.359431Z","steps":["trace[223181344] 'process raft request' (duration: 256.846142ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T06:00:04.416762Z","caller":"traceutil/trace.go:171","msg":"trace[137538872] transaction","detail":"{read_only:false; response_revision:24546; number_of_response:1; }","duration":"129.831004ms","start":"2026-07-02T06:00:04.286814Z","end":"2026-07-02T06:00:04.416645Z","steps":["trace[137538872] 'process raft request' (duration: 129.604548ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T06:00:05.841343Z","caller":"traceutil/trace.go:171","msg":"trace[306989178] transaction","detail":"{read_only:false; response_revision:24551; number_of_response:1; }","duration":"125.194157ms","start":"2026-07-02T06:00:05.716133Z","end":"2026-07-02T06:00:05.841327Z","steps":["trace[306989178] 'process raft request' (duration: 125.068304ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T06:00:06.121365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.154453ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T06:00:06.121472Z","caller":"traceutil/trace.go:171","msg":"trace[2123215935] transaction","detail":"{read_only:false; response_revision:24552; number_of_response:1; }","duration":"352.677573ms","start":"2026-07-02T06:00:05.768775Z","end":"2026-07-02T06:00:06.121453Z","steps":["trace[2123215935] 'process raft request' (duration: 206.349026ms)","trace[2123215935] 'compare' (duration: 145.583237ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T06:00:06.121533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T06:00:05.768747Z","time spent":"352.756085ms","remote":"127.0.0.1:44340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T06:00:06.125421Z","caller":"traceutil/trace.go:171","msg":"trace[1678862755] transaction","detail":"{read_only:false; response_revision:24553; number_of_response:1; }","duration":"115.384378ms","start":"2026-07-02T06:00:06.009993Z","end":"2026-07-02T06:00:06.125378Z","steps":["trace[1678862755] 'process raft request' (duration: 114.988827ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T06:00:06.341539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.156828ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T06:00:06.341694Z","caller":"traceutil/trace.go:171","msg":"trace[578657883] linearizableReadLoop","detail":"{readStateIndex:25361; appliedIndex:25358; }","duration":"216.538205ms","start":"2026-07-02T06:00:06.125131Z","end":"2026-07-02T06:00:06.34167Z","steps":["trace[578657883] 'read index received' (duration: 115.150071ms)","trace[578657883] 'applied index is now lower than readState.Index' (duration: 101.387454ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T06:00:06.34177Z","caller":"traceutil/trace.go:171","msg":"trace[668439712] transaction","detail":"{read_only:false; response_revision:24555; number_of_response:1; }","duration":"289.692024ms","start":"2026-07-02T06:00:06.052032Z","end":"2026-07-02T06:00:06.341724Z","steps":["trace[668439712] 'process raft request' (duration: 289.582261ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T06:00:06.341799Z","caller":"traceutil/trace.go:171","msg":"trace[2141926102] transaction","detail":"{read_only:false; response_revision:24556; number_of_response:1; }","duration":"289.012695ms","start":"2026-07-02T06:00:06.052751Z","end":"2026-07-02T06:00:06.341763Z","steps":["trace[2141926102] 'process raft request' (duration: 288.891531ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T06:00:06.341845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.434182ms","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-07-02T06:00:06.341873Z","caller":"traceutil/trace.go:171","msg":"trace[118592544] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24556; }","duration":"291.498643ms","start":"2026-07-02T06:00:06.050365Z","end":"2026-07-02T06:00:06.341864Z","steps":["trace[118592544] 'agreement among raft nodes before linearized reading' (duration: 291.38685ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T06:00:06.341781Z","caller":"traceutil/trace.go:171","msg":"trace[983706879] transaction","detail":"{read_only:false; response_revision:24554; number_of_response:1; }","duration":"289.856958ms","start":"2026-07-02T06:00:06.051887Z","end":"2026-07-02T06:00:06.341744Z","steps":["trace[983706879] 'process raft request' (duration: 188.430843ms)","trace[983706879] 'compare' (duration: 101.047315ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T06:00:06.341933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.767253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T06:00:06.341991Z","caller":"traceutil/trace.go:171","msg":"trace[1967080238] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24556; }","duration":"215.866786ms","start":"2026-07-02T06:00:06.126106Z","end":"2026-07-02T06:00:06.341973Z","steps":["trace[1967080238] 'agreement among raft nodes before linearized reading' (duration: 215.742363ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T06:00:06.618927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.619493ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T06:00:06.619141Z","caller":"traceutil/trace.go:171","msg":"trace[210589676] transaction","detail":"{read_only:false; response_revision:24559; number_of_response:1; }","duration":"197.916284ms","start":"2026-07-02T06:00:06.421209Z","end":"2026-07-02T06:00:06.619125Z","steps":["trace[210589676] 'process raft request' (duration: 197.858102ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T06:00:06.619134Z","caller":"traceutil/trace.go:171","msg":"trace[1636531012] linearizableReadLoop","detail":"{readStateIndex:25362; appliedIndex:25361; }","duration":"259.764902ms","start":"2026-07-02T06:00:06.359343Z","end":"2026-07-02T06:00:06.619107Z","steps":["trace[1636531012] 'read index received' (duration: 115.976375ms)","trace[1636531012] 'applied index is now lower than readState.Index' (duration: 143.786267ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T06:00:06.619362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.011169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-02T06:00:06.619406Z","caller":"traceutil/trace.go:171","msg":"trace[2067885998] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24559; }","duration":"260.098551ms","start":"2026-07-02T06:00:06.359296Z","end":"2026-07-02T06:00:06.619395Z","steps":["trace[2067885998] 'agreement among raft nodes before linearized reading' (duration: 259.899696ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T06:00:06.619609Z","caller":"traceutil/trace.go:171","msg":"trace[333532032] transaction","detail":"{read_only:false; response_revision:24557; number_of_response:1; }","duration":"272.135782ms","start":"2026-07-02T06:00:06.347461Z","end":"2026-07-02T06:00:06.619596Z","steps":["trace[333532032] 'process raft request' (duration: 127.769878ms)","trace[333532032] 'compare' (duration: 143.482349ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T06:00:06.619669Z","caller":"traceutil/trace.go:171","msg":"trace[285917770] transaction","detail":"{read_only:false; response_revision:24558; number_of_response:1; }","duration":"255.465464ms","start":"2026-07-02T06:00:06.364186Z","end":"2026-07-02T06:00:06.619652Z","steps":["trace[285917770] 'process raft request' (duration: 254.835807ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T06:00:06.78612Z","caller":"traceutil/trace.go:171","msg":"trace[227059592] transaction","detail":"{read_only:false; response_revision:24560; number_of_response:1; }","duration":"267.486074ms","start":"2026-07-02T06:00:06.518617Z","end":"2026-07-02T06:00:06.786103Z","steps":["trace[227059592] 'process raft request' (duration: 267.35836ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T06:00:06.965748Z","caller":"traceutil/trace.go:171","msg":"trace[999412042] linearizableReadLoop","detail":"{readStateIndex:25366; appliedIndex:25365; }","duration":"110.3737ms","start":"2026-07-02T06:00:06.855351Z","end":"2026-07-02T06:00:06.965725Z","steps":["trace[999412042] 'read index received' (duration: 62.305771ms)","trace[999412042] 'applied index is now lower than readState.Index' (duration: 48.066989ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T06:00:06.966382Z","caller":"traceutil/trace.go:171","msg":"trace[1331386320] transaction","detail":"{read_only:false; response_revision:24561; number_of_response:1; }","duration":"339.068099ms","start":"2026-07-02T06:00:06.627266Z","end":"2026-07-02T06:00:06.966334Z","steps":["trace[1331386320] 'process raft request' (duration: 290.353482ms)","trace[1331386320] 'compare' (duration: 47.701139ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T06:00:06.966508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.137301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.248\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-07-02T06:00:06.966746Z","caller":"traceutil/trace.go:171","msg":"trace[170386528] range","detail":"{range_begin:/registry/masterleases/199.204.45.248; range_end:; response_count:1; response_revision:24561; }","duration":"111.415239ms","start":"2026-07-02T06:00:06.855309Z","end":"2026-07-02T06:00:06.966725Z","steps":["trace[170386528] 'agreement among raft nodes before linearized reading' (duration: 110.939766ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T06:00:06.966699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T06:00:06.627244Z","time spent":"339.382537ms","remote":"127.0.0.1:44340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T06:00:07.416737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.046743ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T06:00:07.416963Z","caller":"traceutil/trace.go:171","msg":"trace[963150245] transaction","detail":"{read_only:false; response_revision:24563; number_of_response:1; }","duration":"269.446588ms","start":"2026-07-02T06:00:07.14749Z","end":"2026-07-02T06:00:07.416937Z","steps":["trace[963150245] 'process raft request' (duration: 132.112827ms)","trace[963150245] 'compare' (duration: 136.912929ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T06:00:07.417072Z","caller":"traceutil/trace.go:171","msg":"trace[1136789567] transaction","detail":"{read_only:false; response_revision:24564; number_of_response:1; }","duration":"223.285681ms","start":"2026-07-02T06:00:07.193771Z","end":"2026-07-02T06:00:07.417056Z","steps":["trace[1136789567] 'process raft request' (duration: 223.190798ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T06:00:07.417089Z","caller":"traceutil/trace.go:171","msg":"trace[540931706] linearizableReadLoop","detail":"{readStateIndex:25369; appliedIndex:25368; }","duration":"227.145346ms","start":"2026-07-02T06:00:07.189912Z","end":"2026-07-02T06:00:07.417058Z","steps":["trace[540931706] 'read index received' (duration: 89.672322ms)","trace[540931706] 'applied index is now lower than readState.Index' (duration: 137.470384ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T06:00:07.417281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.417829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"} {"level":"warn","ts":"2026-07-02T06:00:07.417308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.851926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-02T06:00:07.417345Z","caller":"traceutil/trace.go:171","msg":"trace[185986276] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:24564; }","duration":"225.520291ms","start":"2026-07-02T06:00:07.191809Z","end":"2026-07-02T06:00:07.41733Z","steps":["trace[185986276] 'agreement among raft nodes before linearized reading' (duration: 225.412058ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T06:00:07.417359Z","caller":"traceutil/trace.go:171","msg":"trace[1304450423] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24564; }","duration":"258.938559ms","start":"2026-07-02T06:00:07.158409Z","end":"2026-07-02T06:00:07.417348Z","steps":["trace[1304450423] 'agreement among raft nodes before linearized reading' (duration: 258.773134ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T06:00:07.417364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.35092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-07-02T06:00:07.417428Z","caller":"traceutil/trace.go:171","msg":"trace[2112002249] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24564; }","duration":"220.460633ms","start":"2026-07-02T06:00:07.196954Z","end":"2026-07-02T06:00:07.417415Z","steps":["trace[2112002249] 'agreement among raft nodes before linearized reading' (duration: 220.275688ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T06:00:07.79125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.699832ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T06:00:07.791343Z","caller":"traceutil/trace.go:171","msg":"trace[1426629023] linearizableReadLoop","detail":"{readStateIndex:25372; appliedIndex:25371; }","duration":"246.993522ms","start":"2026-07-02T06:00:07.544334Z","end":"2026-07-02T06:00:07.791328Z","steps":["trace[1426629023] 'read index received' (duration: 66.184487ms)","trace[1426629023] 'applied index is now lower than readState.Index' (duration: 180.808005ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T06:00:07.791593Z","caller":"traceutil/trace.go:171","msg":"trace[1419415062] transaction","detail":"{read_only:false; response_revision:24566; number_of_response:1; }","duration":"369.33517ms","start":"2026-07-02T06:00:07.422244Z","end":"2026-07-02T06:00:07.791579Z","steps":["trace[1419415062] 'process raft request' (duration: 188.237587ms)","trace[1419415062] 'compare' (duration: 180.597719ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T06:00:07.791618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.60389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-02T06:00:07.79166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T06:00:07.422228Z","time spent":"369.399372ms","remote":"127.0.0.1:44340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T06:00:07.791702Z","caller":"traceutil/trace.go:171","msg":"trace[1754664877] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:24566; }","duration":"207.728043ms","start":"2026-07-02T06:00:07.583953Z","end":"2026-07-02T06:00:07.791681Z","steps":["trace[1754664877] 'agreement among raft nodes before linearized reading' (duration: 207.58885ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T06:00:07.792073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.730372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-07-02T06:00:07.79211Z","caller":"traceutil/trace.go:171","msg":"trace[763917486] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:24566; }","duration":"247.805604ms","start":"2026-07-02T06:00:07.544293Z","end":"2026-07-02T06:00:07.792099Z","steps":["trace[763917486] 'agreement among raft nodes before linearized reading' (duration: 247.621519ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T06:00:08.505562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.387174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T06:00:08.505643Z","caller":"traceutil/trace.go:171","msg":"trace[96650596] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24568; }","duration":"250.511918ms","start":"2026-07-02T06:00:08.255112Z","end":"2026-07-02T06:00:08.505624Z","steps":["trace[96650596] 'range keys from in-memory index tree' (duration: 250.302622ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T06:00:08.506728Z","caller":"traceutil/trace.go:171","msg":"trace[1920978065] transaction","detail":"{read_only:false; response_revision:24569; number_of_response:1; }","duration":"159.210511ms","start":"2026-07-02T06:00:08.347492Z","end":"2026-07-02T06:00:08.506703Z","steps":["trace[1920978065] 'process raft request' (duration: 156.99108ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T06:00:08.809429Z","caller":"traceutil/trace.go:171","msg":"trace[1464255813] transaction","detail":"{read_only:false; response_revision:24570; number_of_response:1; }","duration":"461.755227ms","start":"2026-07-02T06:00:08.347649Z","end":"2026-07-02T06:00:08.809405Z","steps":["trace[1464255813] 'process raft request' (duration: 460.467042ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T06:00:08.809551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T06:00:08.34764Z","time spent":"461.85236ms","remote":"127.0.0.1:44340","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-07-02T06:00:08.809578Z","caller":"traceutil/trace.go:171","msg":"trace[947734004] linearizableReadLoop","detail":"{readStateIndex:25377; appliedIndex:25375; }","duration":"300.15325ms","start":"2026-07-02T06:00:08.509402Z","end":"2026-07-02T06:00:08.809555Z","steps":["trace[947734004] 'read index received' (duration: 298.857164ms)","trace[947734004] 'applied index is now lower than readState.Index' (duration: 1.295096ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T06:00:08.809759Z","caller":"traceutil/trace.go:171","msg":"trace[187004267] transaction","detail":"{read_only:false; response_revision:24571; number_of_response:1; }","duration":"461.458099ms","start":"2026-07-02T06:00:08.348293Z","end":"2026-07-02T06:00:08.809751Z","steps":["trace[187004267] 'process raft request' (duration: 461.040038ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T06:00:08.809852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T06:00:08.348274Z","time spent":"461.50502ms","remote":"127.0.0.1:44340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T06:00:08.809863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.474009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T06:00:08.809893Z","caller":"traceutil/trace.go:171","msg":"trace[225085480] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24571; }","duration":"300.542511ms","start":"2026-07-02T06:00:08.509343Z","end":"2026-07-02T06:00:08.809885Z","steps":["trace[225085480] 'agreement among raft nodes before linearized reading' (duration: 300.49184ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T06:00:08.809914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T06:00:08.509322Z","time spent":"300.588143ms","remote":"127.0.0.1:43696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-07-02T06:00:08.828115Z","caller":"traceutil/trace.go:171","msg":"trace[1426332381] transaction","detail":"{read_only:false; response_revision:24573; number_of_response:1; }","duration":"200.604738ms","start":"2026-07-02T06:00:08.627484Z","end":"2026-07-02T06:00:08.828089Z","steps":["trace[1426332381] 'process raft request' (duration: 200.494575ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T06:00:08.828128Z","caller":"traceutil/trace.go:171","msg":"trace[15556041] transaction","detail":"{read_only:false; response_revision:24572; number_of_response:1; }","duration":"202.073058ms","start":"2026-07-02T06:00:08.626017Z","end":"2026-07-02T06:00:08.82809Z","steps":["trace[15556041] 'process raft request' (duration: 201.817161ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T06:00:08.828528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.820854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-02T06:00:08.828626Z","caller":"traceutil/trace.go:171","msg":"trace[1788412395] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24573; }","duration":"200.967237ms","start":"2026-07-02T06:00:08.627635Z","end":"2026-07-02T06:00:08.828602Z","steps":["trace[1788412395] 'agreement among raft nodes before linearized reading' (duration: 200.521345ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T06:00:09.118957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.531889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T06:00:09.119048Z","caller":"traceutil/trace.go:171","msg":"trace[766008703] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:24574; }","duration":"195.753595ms","start":"2026-07-02T06:00:08.923275Z","end":"2026-07-02T06:00:09.119028Z","steps":["trace[766008703] 'count revisions from in-memory index tree' (duration: 195.407045ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T06:00:09.119138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.354433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-02T06:00:09.119238Z","caller":"traceutil/trace.go:171","msg":"trace[425937252] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24574; }","duration":"148.496087ms","start":"2026-07-02T06:00:08.970716Z","end":"2026-07-02T06:00:09.119212Z","steps":["trace[425937252] 'range keys from in-memory index tree' (duration: 148.161598ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T06:00:09.119672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.760519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:32 size:274418"} {"level":"info","ts":"2026-07-02T06:00:09.11985Z","caller":"traceutil/trace.go:171","msg":"trace[444632910] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:32; response_revision:24574; }","duration":"111.999625ms","start":"2026-07-02T06:00:09.007829Z","end":"2026-07-02T06:00:09.119829Z","steps":["trace[444632910] 'range keys from in-memory index tree' (duration: 111.241874ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T06:00:09.417314Z","caller":"traceutil/trace.go:171","msg":"trace[138021234] transaction","detail":"{read_only:false; response_revision:24576; number_of_response:1; }","duration":"292.627444ms","start":"2026-07-02T06:00:09.124661Z","end":"2026-07-02T06:00:09.417288Z","steps":["trace[138021234] 'process raft request' (duration: 240.611296ms)","trace[138021234] 'compare' (duration: 50.589449ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T06:00:09.692322Z","caller":"traceutil/trace.go:171","msg":"trace[438572208] linearizableReadLoop","detail":"{readStateIndex:25383; appliedIndex:25382; }","duration":"261.175961ms","start":"2026-07-02T06:00:09.431129Z","end":"2026-07-02T06:00:09.692304Z","steps":["trace[438572208] 'read index received' (duration: 261.085279ms)","trace[438572208] 'applied index is now lower than readState.Index' (duration: 90.072µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T06:00:09.692431Z","caller":"traceutil/trace.go:171","msg":"trace[534314761] transaction","detail":"{read_only:false; response_revision:24577; number_of_response:1; }","duration":"271.155384ms","start":"2026-07-02T06:00:09.421264Z","end":"2026-07-02T06:00:09.692419Z","steps":["trace[534314761] 'process raft request' (duration: 270.873066ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T06:00:09.692635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.4953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-02T06:00:09.692664Z","caller":"traceutil/trace.go:171","msg":"trace[658339606] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24577; }","duration":"261.557351ms","start":"2026-07-02T06:00:09.431099Z","end":"2026-07-02T06:00:09.692657Z","steps":["trace[658339606] 'agreement among raft nodes before linearized reading' (duration: 261.442418ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T06:00:09.692799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.615623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-07-02T06:00:09.692894Z","caller":"traceutil/trace.go:171","msg":"trace[706933089] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0; range_end:; response_count:1; response_revision:24577; }","duration":"245.675925ms","start":"2026-07-02T06:00:09.447168Z","end":"2026-07-02T06:00:09.692844Z","steps":["trace[706933089] 'agreement among raft nodes before linearized reading' (duration: 245.561952ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T06:00:09.692799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.528478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-07-02T06:00:09.693166Z","caller":"traceutil/trace.go:171","msg":"trace[1116050411] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24577; }","duration":"258.916728ms","start":"2026-07-02T06:00:09.434237Z","end":"2026-07-02T06:00:09.693154Z","steps":["trace[1116050411] 'agreement among raft nodes before linearized reading' (duration: 258.407984ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T06:00:14.642241Z","caller":"traceutil/trace.go:171","msg":"trace[1129830233] transaction","detail":"{read_only:false; response_revision:24632; number_of_response:1; }","duration":"112.048537ms","start":"2026-07-02T06:00:14.530174Z","end":"2026-07-02T06:00:14.642222Z","steps":["trace[1129830233] 'process raft request' (duration: 111.696567ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T06:00:15.002194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.48736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T06:00:15.002251Z","caller":"traceutil/trace.go:171","msg":"trace[717448522] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:24632; }","duration":"233.583913ms","start":"2026-07-02T06:00:14.768654Z","end":"2026-07-02T06:00:15.002237Z","steps":["trace[717448522] 'count revisions from in-memory index tree' (duration: 233.426949ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T06:00:15.002492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.560009ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T06:00:15.002691Z","caller":"traceutil/trace.go:171","msg":"trace[1279658773] transaction","detail":"{read_only:false; response_revision:24633; number_of_response:1; }","duration":"174.026158ms","start":"2026-07-02T06:00:14.828612Z","end":"2026-07-02T06:00:15.002639Z","steps":["trace[1279658773] 'process raft request' (duration: 13.170941ms)","trace[1279658773] 'compare' (duration: 160.25233ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T06:00:15.002887Z","caller":"traceutil/trace.go:171","msg":"trace[490512031] transaction","detail":"{read_only:false; response_revision:24635; number_of_response:1; }","duration":"145.427323ms","start":"2026-07-02T06:00:14.857432Z","end":"2026-07-02T06:00:15.002859Z","steps":["trace[490512031] 'process raft request' (duration: 145.378402ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T06:00:15.00305Z","caller":"traceutil/trace.go:171","msg":"trace[1906308724] transaction","detail":"{read_only:false; response_revision:24634; number_of_response:1; }","duration":"172.259169ms","start":"2026-07-02T06:00:14.830784Z","end":"2026-07-02T06:00:15.003043Z","steps":["trace[1906308724] 'process raft request' (duration: 171.92786ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T06:00:15.2709Z","caller":"traceutil/trace.go:171","msg":"trace[1978666997] linearizableReadLoop","detail":"{readStateIndex:25443; appliedIndex:25439; }","duration":"365.72044ms","start":"2026-07-02T06:00:14.905154Z","end":"2026-07-02T06:00:15.270874Z","steps":["trace[1978666997] 'read index received' (duration: 82.570966ms)","trace[1978666997] 'applied index is now lower than readState.Index' (duration: 283.148314ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T06:00:15.271397Z","caller":"traceutil/trace.go:171","msg":"trace[676213964] transaction","detail":"{read_only:false; response_revision:24636; number_of_response:1; }","duration":"410.933932ms","start":"2026-07-02T06:00:14.860448Z","end":"2026-07-02T06:00:15.271382Z","steps":["trace[676213964] 'process raft request' (duration: 402.058799ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T06:00:15.271814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T06:00:14.860424Z","time spent":"411.331194ms","remote":"127.0.0.1:44340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T06:00:15.272178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.946666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T06:00:15.272642Z","caller":"traceutil/trace.go:171","msg":"trace[1577643336] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24636; }","duration":"225.43184ms","start":"2026-07-02T06:00:15.047197Z","end":"2026-07-02T06:00:15.272629Z","steps":["trace[1577643336] 'agreement among raft nodes before linearized reading' (duration: 224.950146ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T06:00:15.271581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.40777ms","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-07-02T06:00:15.273038Z","caller":"traceutil/trace.go:171","msg":"trace[156557826] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24636; }","duration":"367.927471ms","start":"2026-07-02T06:00:14.905093Z","end":"2026-07-02T06:00:15.273021Z","steps":["trace[156557826] 'agreement among raft nodes before linearized reading' (duration: 366.366648ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T06:00:15.273123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T06:00:14.905071Z","time spent":"368.037075ms","remote":"127.0.0.1:44340","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-07-02T06:00:15.945971Z","caller":"traceutil/trace.go:171","msg":"trace[1861311829] transaction","detail":"{read_only:false; response_revision:24640; number_of_response:1; }","duration":"188.806443ms","start":"2026-07-02T06:00:15.757128Z","end":"2026-07-02T06:00:15.945934Z","steps":["trace[1861311829] 'process raft request' (duration: 127.328175ms)","trace[1861311829] 'compare' (duration: 60.679766ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T06:00:15.946321Z","caller":"traceutil/trace.go:171","msg":"trace[390399237] transaction","detail":"{read_only:false; response_revision:24641; number_of_response:1; }","duration":"188.653169ms","start":"2026-07-02T06:00:15.757641Z","end":"2026-07-02T06:00:15.946294Z","steps":["trace[390399237] 'process raft request' (duration: 187.899198ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T06:00:16.128775Z","caller":"traceutil/trace.go:171","msg":"trace[1252099156] transaction","detail":"{read_only:false; response_revision:24642; number_of_response:1; }","duration":"177.02621ms","start":"2026-07-02T06:00:15.951727Z","end":"2026-07-02T06:00:16.128753Z","steps":["trace[1252099156] 'process raft request' (duration: 176.889767ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T06:00:16.131438Z","caller":"traceutil/trace.go:171","msg":"trace[1628449737] transaction","detail":"{read_only:false; response_revision:24643; number_of_response:1; }","duration":"179.346604ms","start":"2026-07-02T06:00:15.952071Z","end":"2026-07-02T06:00:16.131417Z","steps":["trace[1628449737] 'process raft request' (duration: 179.115637ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T06:00:16.435529Z","caller":"traceutil/trace.go:171","msg":"trace[1456546736] transaction","detail":"{read_only:false; response_revision:24644; number_of_response:1; }","duration":"228.358179ms","start":"2026-07-02T06:00:16.20714Z","end":"2026-07-02T06:00:16.435498Z","steps":["trace[1456546736] 'process raft request' (duration: 226.080507ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T06:00:16.798059Z","caller":"traceutil/trace.go:171","msg":"trace[1299427046] transaction","detail":"{read_only:false; response_revision:24645; number_of_response:1; }","duration":"150.489532ms","start":"2026-07-02T06:00:16.647527Z","end":"2026-07-02T06:00:16.798016Z","steps":["trace[1299427046] 'process raft request' (duration: 74.946498ms)","trace[1299427046] 'compare' (duration: 75.426081ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T06:00:22.128194Z","caller":"traceutil/trace.go:171","msg":"trace[1559028047] transaction","detail":"{read_only:false; response_revision:24756; number_of_response:1; }","duration":"112.888379ms","start":"2026-07-02T06:00:22.015277Z","end":"2026-07-02T06:00:22.128166Z","steps":["trace[1559028047] 'process raft request' (duration: 112.729325ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T06:00:22.27793Z","caller":"traceutil/trace.go:171","msg":"trace[1577690445] transaction","detail":"{read_only:false; response_revision:24757; number_of_response:1; }","duration":"253.745607ms","start":"2026-07-02T06:00:22.024146Z","end":"2026-07-02T06:00:22.277892Z","steps":["trace[1577690445] 'process raft request' (duration: 252.273406ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T06:00:22.368597Z","caller":"traceutil/trace.go:171","msg":"trace[173216639] linearizableReadLoop","detail":"{readStateIndex:25566; appliedIndex:25564; }","duration":"215.662121ms","start":"2026-07-02T06:00:22.152908Z","end":"2026-07-02T06:00:22.36857Z","steps":["trace[173216639] 'read index received' (duration: 123.525022ms)","trace[173216639] 'applied index is now lower than readState.Index' (duration: 92.135929ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T06:00:22.368933Z","caller":"traceutil/trace.go:171","msg":"trace[670755289] transaction","detail":"{read_only:false; response_revision:24758; number_of_response:1; }","duration":"217.123401ms","start":"2026-07-02T06:00:22.151788Z","end":"2026-07-02T06:00:22.368912Z","steps":["trace[670755289] 'process raft request' (duration: 216.642988ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T06:00:22.374966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.043796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-02T06:00:22.375025Z","caller":"traceutil/trace.go:171","msg":"trace[1689202306] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24758; }","duration":"222.143269ms","start":"2026-07-02T06:00:22.152868Z","end":"2026-07-02T06:00:22.375011Z","steps":["trace[1689202306] 'agreement among raft nodes before linearized reading' (duration: 221.953584ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T06:00:22.503142Z","caller":"traceutil/trace.go:171","msg":"trace[1748546333] linearizableReadLoop","detail":"{readStateIndex:25567; appliedIndex:25566; }","duration":"134.45225ms","start":"2026-07-02T06:00:22.368667Z","end":"2026-07-02T06:00:22.503119Z","steps":["trace[1748546333] 'read index received' (duration: 66.151126ms)","trace[1748546333] 'applied index is now lower than readState.Index' (duration: 68.300194ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T06:00:22.503392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.260865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-02T06:00:22.503429Z","caller":"traceutil/trace.go:171","msg":"trace[547102709] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24758; }","duration":"350.372639ms","start":"2026-07-02T06:00:22.153042Z","end":"2026-07-02T06:00:22.503415Z","steps":["trace[547102709] 'agreement among raft nodes before linearized reading' (duration: 350.233655ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T06:00:22.503465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T06:00:22.15301Z","time spent":"350.445581ms","remote":"127.0.0.1:44340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-02T06:00:22.503779Z","caller":"traceutil/trace.go:171","msg":"trace[1455938016] transaction","detail":"{read_only:false; response_revision:24759; number_of_response:1; }","duration":"120.742714ms","start":"2026-07-02T06:00:22.383027Z","end":"2026-07-02T06:00:22.503769Z","steps":["trace[1455938016] 'process raft request' (duration: 119.985743ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T06:00:23.946922Z","caller":"traceutil/trace.go:171","msg":"trace[22734365] transaction","detail":"{read_only:false; response_revision:24771; number_of_response:1; }","duration":"164.017203ms","start":"2026-07-02T06:00:23.78289Z","end":"2026-07-02T06:00:23.946907Z","steps":["trace[22734365] 'process raft request' (duration: 163.624582ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T06:00:24.967492Z","caller":"traceutil/trace.go:171","msg":"trace[1568530004] transaction","detail":"{read_only:false; response_revision:24782; number_of_response:1; }","duration":"129.189177ms","start":"2026-07-02T06:00:24.838278Z","end":"2026-07-02T06:00:24.967467Z","steps":["trace[1568530004] 'process raft request' (duration: 129.051674ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T06:00:27.235016Z","caller":"traceutil/trace.go:171","msg":"trace[897606081] linearizableReadLoop","detail":"{readStateIndex:25633; appliedIndex:25632; }","duration":"187.818747ms","start":"2026-07-02T06:00:27.047173Z","end":"2026-07-02T06:00:27.234992Z","steps":["trace[897606081] 'read index received' (duration: 187.643902ms)","trace[897606081] 'applied index is now lower than readState.Index' (duration: 173.754µs)"],"step_count":2} {"level":"warn","ts":"2026-07-02T06:00:27.235155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.96091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T06:00:27.235144Z","caller":"traceutil/trace.go:171","msg":"trace[181932168] transaction","detail":"{read_only:false; response_revision:24822; number_of_response:1; }","duration":"263.268418ms","start":"2026-07-02T06:00:26.971842Z","end":"2026-07-02T06:00:27.23511Z","steps":["trace[181932168] 'process raft request' (duration: 262.961669ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T06:00:27.235551Z","caller":"traceutil/trace.go:171","msg":"trace[124483417] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24822; }","duration":"188.372281ms","start":"2026-07-02T06:00:27.047163Z","end":"2026-07-02T06:00:27.235536Z","steps":["trace[124483417] 'agreement among raft nodes before linearized reading' (duration: 187.915499ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T06:00:27.310032Z","caller":"traceutil/trace.go:171","msg":"trace[1034553548] transaction","detail":"{read_only:false; response_revision:24823; number_of_response:1; }","duration":"181.445502ms","start":"2026-07-02T06:00:27.128562Z","end":"2026-07-02T06:00:27.310007Z","steps":["trace[1034553548] 'process raft request' (duration: 172.322152ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T06:00:27.310422Z","caller":"traceutil/trace.go:171","msg":"trace[492791301] transaction","detail":"{read_only:false; response_revision:24824; number_of_response:1; }","duration":"176.767823ms","start":"2026-07-02T06:00:27.133629Z","end":"2026-07-02T06:00:27.310397Z","steps":["trace[492791301] 'process raft request' (duration: 176.344531ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T06:00:27.310491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.708322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T06:00:27.310543Z","caller":"traceutil/trace.go:171","msg":"trace[1533840675] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:24825; }","duration":"172.791824ms","start":"2026-07-02T06:00:27.137737Z","end":"2026-07-02T06:00:27.310528Z","steps":["trace[1533840675] 'agreement among raft nodes before linearized reading' (duration: 172.697342ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T06:00:27.310731Z","caller":"traceutil/trace.go:171","msg":"trace[33260941] transaction","detail":"{read_only:false; response_revision:24825; number_of_response:1; }","duration":"176.823225ms","start":"2026-07-02T06:00:27.133897Z","end":"2026-07-02T06:00:27.31072Z","steps":["trace[33260941] 'process raft request' (duration: 176.325521ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T06:00:38.682203Z","caller":"traceutil/trace.go:171","msg":"trace[2020975543] transaction","detail":"{read_only:false; response_revision:24928; number_of_response:1; }","duration":"116.093627ms","start":"2026-07-02T06:00:38.566078Z","end":"2026-07-02T06:00:38.682172Z","steps":["trace[2020975543] 'process raft request' (duration: 115.909862ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T06:00:38.943312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.916339ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T06:00:38.943753Z","caller":"traceutil/trace.go:171","msg":"trace[1374807688] transaction","detail":"{read_only:false; response_revision:24929; number_of_response:1; }","duration":"257.122469ms","start":"2026-07-02T06:00:38.686593Z","end":"2026-07-02T06:00:38.943715Z","steps":["trace[1374807688] 'process raft request' (duration: 123.717256ms)","trace[1374807688] 'compare' (duration: 132.657832ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T06:00:38.943759Z","caller":"traceutil/trace.go:171","msg":"trace[395813622] transaction","detail":"{read_only:false; response_revision:24930; number_of_response:1; }","duration":"256.986866ms","start":"2026-07-02T06:00:38.686729Z","end":"2026-07-02T06:00:38.943716Z","steps":["trace[395813622] 'process raft request' (duration: 256.695038ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T06:00:39.208965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.235716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-02T06:00:39.209062Z","caller":"traceutil/trace.go:171","msg":"trace[1605116153] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:24930; }","duration":"114.38058ms","start":"2026-07-02T06:00:39.094664Z","end":"2026-07-02T06:00:39.209045Z","steps":["trace[1605116153] 'count revisions from in-memory index tree' (duration: 114.173395ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T06:00:39.595572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.087639ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T06:00:39.595689Z","caller":"traceutil/trace.go:171","msg":"trace[1584071589] transaction","detail":"{read_only:false; response_revision:24932; number_of_response:1; }","duration":"246.21266ms","start":"2026-07-02T06:00:39.349459Z","end":"2026-07-02T06:00:39.595672Z","steps":["trace[1584071589] 'process raft request' (duration: 118.944666ms)","trace[1584071589] 'compare' (duration: 126.959416ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T06:00:39.819427Z","caller":"traceutil/trace.go:171","msg":"trace[134175872] transaction","detail":"{read_only:false; response_revision:24934; number_of_response:1; }","duration":"465.749268ms","start":"2026-07-02T06:00:39.353653Z","end":"2026-07-02T06:00:39.819402Z","steps":["trace[134175872] 'process raft request' (duration: 465.638335ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T06:00:39.819474Z","caller":"traceutil/trace.go:171","msg":"trace[1624978066] linearizableReadLoop","detail":"{readStateIndex:25748; appliedIndex:25744; }","duration":"407.963441ms","start":"2026-07-02T06:00:39.411491Z","end":"2026-07-02T06:00:39.819455Z","steps":["trace[1624978066] 'read index received' (duration: 56.926053ms)","trace[1624978066] 'applied index is now lower than readState.Index' (duration: 351.036648ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T06:00:39.819557Z","caller":"traceutil/trace.go:171","msg":"trace[919285747] transaction","detail":"{read_only:false; response_revision:24935; number_of_response:1; }","duration":"465.654415ms","start":"2026-07-02T06:00:39.353895Z","end":"2026-07-02T06:00:39.819549Z","steps":["trace[919285747] 'process raft request' (duration: 465.46726ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T06:00:39.819563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T06:00:39.353627Z","time spent":"465.87041ms","remote":"127.0.0.1:44340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T06:00:39.819629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.145226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-07-02T06:00:39.819632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T06:00:39.353887Z","time spent":"465.684715ms","remote":"127.0.0.1:44340","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-07-02T06:00:39.819422Z","caller":"traceutil/trace.go:171","msg":"trace[1042403923] transaction","detail":"{read_only:false; response_revision:24933; number_of_response:1; }","duration":"466.301422ms","start":"2026-07-02T06:00:39.353084Z","end":"2026-07-02T06:00:39.819386Z","steps":["trace[1042403923] 'process raft request' (duration: 367.560931ms)","trace[1042403923] 'compare' (duration: 98.440623ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T06:00:39.819657Z","caller":"traceutil/trace.go:171","msg":"trace[793694973] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24935; }","duration":"408.215417ms","start":"2026-07-02T06:00:39.411434Z","end":"2026-07-02T06:00:39.819649Z","steps":["trace[793694973] 'agreement among raft nodes before linearized reading' (duration: 408.124185ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T06:00:39.819674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T06:00:39.411414Z","time spent":"408.256319ms","remote":"127.0.0.1:44340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-02T06:00:39.819692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T06:00:39.353067Z","time spent":"466.601061ms","remote":"127.0.0.1:44340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T06:00:39.819731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.936422ms","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-07-02T06:00:39.819793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.19385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-07-02T06:00:39.819816Z","caller":"traceutil/trace.go:171","msg":"trace[54136063] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24935; }","duration":"407.051135ms","start":"2026-07-02T06:00:39.412743Z","end":"2026-07-02T06:00:39.819794Z","steps":["trace[54136063] 'agreement among raft nodes before linearized reading' (duration: 406.825149ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T06:00:39.81984Z","caller":"traceutil/trace.go:171","msg":"trace[542264738] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24935; }","duration":"344.274522ms","start":"2026-07-02T06:00:39.475551Z","end":"2026-07-02T06:00:39.819826Z","steps":["trace[542264738] 'agreement among raft nodes before linearized reading' (duration: 344.092767ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T06:00:39.819859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T06:00:39.41273Z","time spent":"407.120078ms","remote":"127.0.0.1:44340","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":"warn","ts":"2026-07-02T06:00:39.819876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T06:00:39.475539Z","time spent":"344.327733ms","remote":"127.0.0.1:43682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-07-02T06:00:39.819757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.409565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" ","response":"range_response_count:1 size:1852"} {"level":"warn","ts":"2026-07-02T06:00:39.819897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.152786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-07-02T06:00:39.819937Z","caller":"traceutil/trace.go:171","msg":"trace[1430098868] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0; range_end:; response_count:1; response_revision:24935; }","duration":"360.616491ms","start":"2026-07-02T06:00:39.459305Z","end":"2026-07-02T06:00:39.819922Z","steps":["trace[1430098868] 'agreement among raft nodes before linearized reading' (duration: 360.355884ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T06:00:39.819973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T06:00:39.45929Z","time spent":"360.673912ms","remote":"127.0.0.1:43656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":79,"response count":1,"response size":1875,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" "} {"level":"info","ts":"2026-07-02T06:00:39.81998Z","caller":"traceutil/trace.go:171","msg":"trace[1138770714] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:24935; }","duration":"388.27282ms","start":"2026-07-02T06:00:39.431683Z","end":"2026-07-02T06:00:39.819956Z","steps":["trace[1138770714] 'agreement among raft nodes before linearized reading' (duration: 388.016273ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T06:00:39.820027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T06:00:39.431667Z","time spent":"388.351302ms","remote":"127.0.0.1:44340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":585,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-07-02T06:00:40.092746Z","caller":"traceutil/trace.go:171","msg":"trace[687830033] linearizableReadLoop","detail":"{readStateIndex:25750; appliedIndex:25748; }","duration":"268.671717ms","start":"2026-07-02T06:00:39.824046Z","end":"2026-07-02T06:00:40.092717Z","steps":["trace[687830033] 'read index received' (duration: 65.670523ms)","trace[687830033] 'applied index is now lower than readState.Index' (duration: 202.999974ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T06:00:40.092867Z","caller":"traceutil/trace.go:171","msg":"trace[590700328] transaction","detail":"{read_only:false; response_revision:24937; number_of_response:1; }","duration":"269.203691ms","start":"2026-07-02T06:00:39.823626Z","end":"2026-07-02T06:00:40.09283Z","steps":["trace[590700328] 'process raft request' (duration: 268.834611ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T06:00:40.093341Z","caller":"traceutil/trace.go:171","msg":"trace[1761638059] transaction","detail":"{read_only:false; response_revision:24939; number_of_response:1; }","duration":"269.194301ms","start":"2026-07-02T06:00:39.824103Z","end":"2026-07-02T06:00:40.093298Z","steps":["trace[1761638059] 'process raft request' (duration: 268.556953ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T06:00:40.093397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.335625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18229"} {"level":"info","ts":"2026-07-02T06:00:40.093546Z","caller":"traceutil/trace.go:171","msg":"trace[1703630358] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24939; }","duration":"269.51569ms","start":"2026-07-02T06:00:39.824016Z","end":"2026-07-02T06:00:40.093531Z","steps":["trace[1703630358] 'agreement among raft nodes before linearized reading' (duration: 269.268633ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T06:00:40.09801Z","caller":"traceutil/trace.go:171","msg":"trace[1399160174] transaction","detail":"{read_only:false; response_revision:24940; number_of_response:1; }","duration":"104.042877ms","start":"2026-07-02T06:00:39.993958Z","end":"2026-07-02T06:00:40.098001Z","steps":["trace[1399160174] 'process raft request' (duration: 103.840631ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T06:00:40.092875Z","caller":"traceutil/trace.go:171","msg":"trace[1926729392] transaction","detail":"{read_only:false; response_revision:24938; number_of_response:1; }","duration":"268.78173ms","start":"2026-07-02T06:00:39.824079Z","end":"2026-07-02T06:00:40.092861Z","steps":["trace[1926729392] 'process raft request' (duration: 268.537143ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T06:00:44.036316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.442543ms","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-07-02T06:00:44.036406Z","caller":"traceutil/trace.go:171","msg":"trace[1569639929] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24967; }","duration":"133.586166ms","start":"2026-07-02T06:00:43.902797Z","end":"2026-07-02T06:00:44.036383Z","steps":["trace[1569639929] 'range keys from in-memory index tree' (duration: 133.266388ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T06:00:44.241036Z","caller":"traceutil/trace.go:171","msg":"trace[1969595621] linearizableReadLoop","detail":"{readStateIndex:25783; appliedIndex:25782; }","duration":"127.860221ms","start":"2026-07-02T06:00:44.113155Z","end":"2026-07-02T06:00:44.241015Z","steps":["trace[1969595621] 'read index received' (duration: 48.133262ms)","trace[1969595621] 'applied index is now lower than readState.Index' (duration: 79.726209ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T06:00:44.241111Z","caller":"traceutil/trace.go:171","msg":"trace[1307858585] transaction","detail":"{read_only:false; response_revision:24969; number_of_response:1; }","duration":"130.520223ms","start":"2026-07-02T06:00:44.110561Z","end":"2026-07-02T06:00:44.241081Z","steps":["trace[1307858585] 'process raft request' (duration: 50.831046ms)","trace[1307858585] 'compare' (duration: 79.481232ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T06:00:44.241171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.004604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-02T06:00:44.241194Z","caller":"traceutil/trace.go:171","msg":"trace[457214248] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24969; }","duration":"128.111657ms","start":"2026-07-02T06:00:44.113075Z","end":"2026-07-02T06:00:44.241186Z","steps":["trace[457214248] 'agreement among raft nodes before linearized reading' (duration: 128.010134ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T06:00:44.430249Z","caller":"traceutil/trace.go:171","msg":"trace[1124668213] linearizableReadLoop","detail":"{readStateIndex:25784; appliedIndex:25783; }","duration":"183.784336ms","start":"2026-07-02T06:00:44.246442Z","end":"2026-07-02T06:00:44.430226Z","steps":["trace[1124668213] 'read index received' (duration: 183.717355ms)","trace[1124668213] 'applied index is now lower than readState.Index' (duration: 65.951µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T06:00:44.430364Z","caller":"traceutil/trace.go:171","msg":"trace[729592502] transaction","detail":"{read_only:false; response_revision:24970; number_of_response:1; }","duration":"184.887306ms","start":"2026-07-02T06:00:44.245445Z","end":"2026-07-02T06:00:44.430333Z","steps":["trace[729592502] 'process raft request' (duration: 184.634229ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T06:00:44.430488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.043913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-02T06:00:44.430531Z","caller":"traceutil/trace.go:171","msg":"trace[941036953] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24970; }","duration":"184.139785ms","start":"2026-07-02T06:00:44.24638Z","end":"2026-07-02T06:00:44.430519Z","steps":["trace[941036953] 'agreement among raft nodes before linearized reading' (duration: 183.974181ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T06:00:44.430618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.509249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T06:00:44.430697Z","caller":"traceutil/trace.go:171","msg":"trace[1739878781] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:24970; }","duration":"155.626252ms","start":"2026-07-02T06:00:44.275037Z","end":"2026-07-02T06:00:44.430663Z","steps":["trace[1739878781] 'agreement among raft nodes before linearized reading' (duration: 155.505099ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T06:00:45.737448Z","caller":"traceutil/trace.go:171","msg":"trace[1679010181] transaction","detail":"{read_only:false; response_revision:24977; number_of_response:1; }","duration":"124.037705ms","start":"2026-07-02T06:00:45.613384Z","end":"2026-07-02T06:00:45.737422Z","steps":["trace[1679010181] 'process raft request' (duration: 123.84404ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T06:00:54.260277Z","caller":"traceutil/trace.go:171","msg":"trace[1463184094] transaction","detail":"{read_only:false; response_revision:25050; number_of_response:1; }","duration":"110.667459ms","start":"2026-07-02T06:00:54.149576Z","end":"2026-07-02T06:00:54.260244Z","steps":["trace[1463184094] 'process raft request' (duration: 110.455793ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T06:00:54.538667Z","caller":"traceutil/trace.go:171","msg":"trace[827073829] transaction","detail":"{read_only:false; response_revision:25051; number_of_response:1; }","duration":"261.689295ms","start":"2026-07-02T06:00:54.276949Z","end":"2026-07-02T06:00:54.538638Z","steps":["trace[827073829] 'process raft request' (duration: 235.913557ms)","trace[827073829] 'compare' (duration: 25.317336ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T06:00:56.15099Z","caller":"traceutil/trace.go:171","msg":"trace[1352037326] transaction","detail":"{read_only:false; response_revision:25064; number_of_response:1; }","duration":"102.861304ms","start":"2026-07-02T06:00:56.0481Z","end":"2026-07-02T06:00:56.150961Z","steps":["trace[1352037326] 'process raft request' (duration: 102.758022ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T06:00:56.151148Z","caller":"traceutil/trace.go:171","msg":"trace[1977210685] transaction","detail":"{read_only:false; response_revision:25063; number_of_response:1; }","duration":"103.197443ms","start":"2026-07-02T06:00:56.047906Z","end":"2026-07-02T06:00:56.151103Z","steps":["trace[1977210685] 'process raft request' (duration: 65.673093ms)","trace[1977210685] 'compare' (duration: 37.13311ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T06:00:56.151295Z","caller":"traceutil/trace.go:171","msg":"trace[655074629] linearizableReadLoop","detail":"{readStateIndex:25880; appliedIndex:25879; }","duration":"103.266205ms","start":"2026-07-02T06:00:56.048015Z","end":"2026-07-02T06:00:56.151281Z","steps":["trace[655074629] 'read index received' (duration: 65.57329ms)","trace[655074629] 'applied index is now lower than readState.Index' (duration: 37.691465ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T06:00:56.151384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.353837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T06:00:56.151422Z","caller":"traceutil/trace.go:171","msg":"trace[2058186264] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25064; }","duration":"103.455051ms","start":"2026-07-02T06:00:56.047956Z","end":"2026-07-02T06:00:56.151411Z","steps":["trace[2058186264] 'agreement among raft nodes before linearized reading' (duration: 103.377759ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T06:00:56.54638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.335627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-02T06:00:56.546468Z","caller":"traceutil/trace.go:171","msg":"trace[742623004] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25064; }","duration":"280.462101ms","start":"2026-07-02T06:00:56.265986Z","end":"2026-07-02T06:00:56.546448Z","steps":["trace[742623004] 'range keys from in-memory index tree' (duration: 280.151321ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T06:01:12.933374Z","caller":"traceutil/trace.go:171","msg":"trace[298578172] transaction","detail":"{read_only:false; response_revision:25195; number_of_response:1; }","duration":"141.127334ms","start":"2026-07-02T06:01:12.792194Z","end":"2026-07-02T06:01:12.933321Z","steps":["trace[298578172] 'process raft request' (duration: 80.236513ms)","trace[298578172] 'compare' (duration: 60.718287ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T06:01:13.309017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.453703ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T06:01:13.309261Z","caller":"traceutil/trace.go:171","msg":"trace[1419013603] linearizableReadLoop","detail":"{readStateIndex:26020; appliedIndex:26016; }","duration":"261.202492ms","start":"2026-07-02T06:01:13.048043Z","end":"2026-07-02T06:01:13.309245Z","steps":["trace[1419013603] 'read index received' (duration: 22.861µs)","trace[1419013603] 'applied index is now lower than readState.Index' (duration: 261.17862ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T06:01:13.309291Z","caller":"traceutil/trace.go:171","msg":"trace[1300849681] transaction","detail":"{read_only:false; response_revision:25198; number_of_response:1; }","duration":"516.770148ms","start":"2026-07-02T06:01:12.792489Z","end":"2026-07-02T06:01:13.309259Z","steps":["trace[1300849681] 'process raft request' (duration: 516.678605ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T06:01:13.309306Z","caller":"traceutil/trace.go:171","msg":"trace[1645966295] transaction","detail":"{read_only:false; response_revision:25197; number_of_response:1; }","duration":"516.809249ms","start":"2026-07-02T06:01:12.792451Z","end":"2026-07-02T06:01:13.30926Z","steps":["trace[1645966295] 'process raft request' (duration: 516.664605ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T06:01:13.309388Z","caller":"traceutil/trace.go:171","msg":"trace[1846740293] transaction","detail":"{read_only:false; response_revision:25199; number_of_response:1; }","duration":"438.737585ms","start":"2026-07-02T06:01:12.870614Z","end":"2026-07-02T06:01:13.309352Z","steps":["trace[1846740293] 'process raft request' (duration: 438.588901ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T06:01:13.309446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.406917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-02T06:01:13.309454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T06:01:12.792447Z","time spent":"516.921262ms","remote":"127.0.0.1:44340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T06:01:13.309483Z","caller":"traceutil/trace.go:171","msg":"trace[111031252] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25199; }","duration":"261.485149ms","start":"2026-07-02T06:01:13.047985Z","end":"2026-07-02T06:01:13.30947Z","steps":["trace[111031252] 'agreement among raft nodes before linearized reading' (duration: 261.361385ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T06:01:13.309489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T06:01:12.792426Z","time spent":"516.966114ms","remote":"127.0.0.1:44340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T06:01:13.309496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T06:01:12.870581Z","time spent":"438.863569ms","remote":"127.0.0.1:44340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T06:01:13.309593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.014235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-07-02T06:01:13.309675Z","caller":"traceutil/trace.go:171","msg":"trace[413097386] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:25199; }","duration":"226.137359ms","start":"2026-07-02T06:01:13.083515Z","end":"2026-07-02T06:01:13.309653Z","steps":["trace[413097386] 'agreement among raft nodes before linearized reading' (duration: 225.879752ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T06:01:13.309738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.97512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-02T06:01:13.309782Z","caller":"traceutil/trace.go:171","msg":"trace[998602262] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25199; }","duration":"208.059002ms","start":"2026-07-02T06:01:13.101713Z","end":"2026-07-02T06:01:13.309772Z","steps":["trace[998602262] 'agreement among raft nodes before linearized reading' (duration: 207.886457ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T06:01:13.309812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.214914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-02T06:01:13.30987Z","caller":"traceutil/trace.go:171","msg":"trace[825669631] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25199; }","duration":"209.274275ms","start":"2026-07-02T06:01:13.100581Z","end":"2026-07-02T06:01:13.309856Z","steps":["trace[825669631] 'agreement among raft nodes before linearized reading' (duration: 209.06695ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T06:01:13.310117Z","caller":"traceutil/trace.go:171","msg":"trace[642990428] transaction","detail":"{read_only:false; response_revision:25196; number_of_response:1; }","duration":"517.757295ms","start":"2026-07-02T06:01:12.792332Z","end":"2026-07-02T06:01:13.310089Z","steps":["trace[642990428] 'process raft request' (duration: 253.14707ms)","trace[642990428] 'compare' (duration: 263.33925ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T06:01:13.310247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T06:01:12.792325Z","time spent":"517.851388ms","remote":"127.0.0.1:44340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T06:01:13.45475Z","caller":"traceutil/trace.go:171","msg":"trace[422885580] transaction","detail":"{read_only:false; response_revision:25201; number_of_response:1; }","duration":"140.382604ms","start":"2026-07-02T06:01:13.314338Z","end":"2026-07-02T06:01:13.454721Z","steps":["trace[422885580] 'process raft request' (duration: 139.577632ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T06:01:13.739534Z","caller":"traceutil/trace.go:171","msg":"trace[1011543461] transaction","detail":"{read_only:false; response_revision:25203; number_of_response:1; }","duration":"181.312497ms","start":"2026-07-02T06:01:13.558193Z","end":"2026-07-02T06:01:13.739505Z","steps":["trace[1011543461] 'process raft request' (duration: 105.265459ms)","trace[1011543461] 'compare' (duration: 75.920245ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T06:01:13.800817Z","caller":"traceutil/trace.go:171","msg":"trace[40348688] transaction","detail":"{read_only:false; response_revision:25204; number_of_response:1; }","duration":"118.167674ms","start":"2026-07-02T06:01:13.682625Z","end":"2026-07-02T06:01:13.800792Z","steps":["trace[40348688] 'process raft request' (duration: 118.02019ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T06:01:13.974055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.563221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-02T06:01:13.974142Z","caller":"traceutil/trace.go:171","msg":"trace[935912586] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:25204; }","duration":"104.707545ms","start":"2026-07-02T06:01:13.86941Z","end":"2026-07-02T06:01:13.974117Z","steps":["trace[935912586] 'count revisions from in-memory index tree' (duration: 104.438728ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T06:01:13.974264Z","caller":"traceutil/trace.go:171","msg":"trace[891970474] transaction","detail":"{read_only:false; response_revision:25205; number_of_response:1; }","duration":"101.350733ms","start":"2026-07-02T06:01:13.872902Z","end":"2026-07-02T06:01:13.974252Z","steps":["trace[891970474] 'process raft request' (duration: 98.619017ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T06:01:14.354524Z","caller":"traceutil/trace.go:171","msg":"trace[698075413] transaction","detail":"{read_only:false; response_revision:25208; number_of_response:1; }","duration":"130.837412ms","start":"2026-07-02T06:01:14.223664Z","end":"2026-07-02T06:01:14.354502Z","steps":["trace[698075413] 'process raft request' (duration: 130.803041ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T06:01:14.354712Z","caller":"traceutil/trace.go:171","msg":"trace[1612566419] transaction","detail":"{read_only:false; response_revision:25207; number_of_response:1; }","duration":"144.582139ms","start":"2026-07-02T06:01:14.210122Z","end":"2026-07-02T06:01:14.354704Z","steps":["trace[1612566419] 'process raft request' (duration: 69.423106ms)","trace[1612566419] 'compare' (duration: 74.806204ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T06:01:17.11Z","caller":"traceutil/trace.go:171","msg":"trace[699317762] transaction","detail":"{read_only:false; response_revision:25225; number_of_response:1; }","duration":"139.192851ms","start":"2026-07-02T06:01:16.970773Z","end":"2026-07-02T06:01:17.109966Z","steps":["trace[699317762] 'process raft request' (duration: 138.848452ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T06:01:22.674887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.9912ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T06:01:22.674988Z","caller":"traceutil/trace.go:171","msg":"trace[169535876] linearizableReadLoop","detail":"{readStateIndex:26090; appliedIndex:26089; }","duration":"226.737675ms","start":"2026-07-02T06:01:22.448235Z","end":"2026-07-02T06:01:22.674972Z","steps":["trace[169535876] 'read index received' (duration: 77.524458ms)","trace[169535876] 'applied index is now lower than readState.Index' (duration: 149.212197ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T06:01:22.675336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.100475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-07-02T06:01:22.675402Z","caller":"traceutil/trace.go:171","msg":"trace[1208538978] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0; range_end:; response_count:1; response_revision:25267; }","duration":"227.213868ms","start":"2026-07-02T06:01:22.448171Z","end":"2026-07-02T06:01:22.675385Z","steps":["trace[1208538978] 'agreement among raft nodes before linearized reading' (duration: 227.031863ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T06:01:22.67536Z","caller":"traceutil/trace.go:171","msg":"trace[2087591354] transaction","detail":"{read_only:false; response_revision:25267; number_of_response:1; }","duration":"267.501805ms","start":"2026-07-02T06:01:22.40782Z","end":"2026-07-02T06:01:22.675322Z","steps":["trace[2087591354] 'process raft request' (duration: 117.99744ms)","trace[2087591354] 'compare' (duration: 148.876347ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T06:01:41.844338Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000066ee.wal"} {"level":"info","ts":"2026-07-02T06:03:03.675606Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23324} {"level":"info","ts":"2026-07-02T06:03:03.969522Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23324,"took":"289.662493ms","hash":1974891552,"current-db-size-bytes":59092992,"current-db-size":"59 MB","current-db-size-in-use-bytes":37150720,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-07-02T06:03:03.969612Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1974891552,"revision":23324,"compact-revision":20069} {"level":"info","ts":"2026-07-02T06:03:03.970129Z","caller":"traceutil/trace.go:171","msg":"trace[81612601] transaction","detail":"{read_only:false; response_revision:26279; number_of_response:1; }","duration":"101.392124ms","start":"2026-07-02T06:03:03.868709Z","end":"2026-07-02T06:03:03.970101Z","steps":["trace[81612601] 'process raft request' (duration: 51.292779ms)","trace[81612601] 'compare' (duration: 49.773456ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T06:06:05.9501Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415873807517969248,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T06:06:06.802885Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415873807517969248,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T06:06:07.903288Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415873807517969248,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T06:06:08.288202Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.89309306s","expected-duration":"1s"} {"level":"info","ts":"2026-07-02T06:06:08.289285Z","caller":"traceutil/trace.go:171","msg":"trace[1162811796] transaction","detail":"{read_only:false; response_revision:28046; number_of_response:1; }","duration":"2.894278192s","start":"2026-07-02T06:06:05.394991Z","end":"2026-07-02T06:06:08.289269Z","steps":["trace[1162811796] 'process raft request' (duration: 2.894150519s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T06:06:08.289387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T06:06:05.39496Z","time spent":"2.894374315s","remote":"127.0.0.1:44340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T06:06:08.292739Z","caller":"traceutil/trace.go:171","msg":"trace[909542602] linearizableReadLoop","detail":"{readStateIndex:28941; appliedIndex:28939; }","duration":"2.842783129s","start":"2026-07-02T06:06:05.449937Z","end":"2026-07-02T06:06:08.29272Z","steps":["trace[909542602] 'read index received' (duration: 2.839220631s)","trace[909542602] 'applied index is now lower than readState.Index' (duration: 3.562048ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T06:06:08.293004Z","caller":"traceutil/trace.go:171","msg":"trace[270583865] transaction","detail":"{read_only:false; response_revision:28047; number_of_response:1; }","duration":"2.602332406s","start":"2026-07-02T06:06:05.690654Z","end":"2026-07-02T06:06:08.292986Z","steps":["trace[270583865] 'process raft request' (duration: 2.601452052s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T06:06:08.293101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T06:06:05.690612Z","time spent":"2.602434849s","remote":"127.0.0.1:44340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T06:06:08.294274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.844321061s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-07-02T06:06:08.294324Z","caller":"traceutil/trace.go:171","msg":"trace[1165590362] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0; range_end:; response_count:1; response_revision:28056; }","duration":"2.844434933s","start":"2026-07-02T06:06:05.449878Z","end":"2026-07-02T06:06:08.294313Z","steps":["trace[1165590362] 'agreement among raft nodes before linearized reading' (duration: 2.844286139s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T06:06:08.294344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T06:06:05.449853Z","time spent":"2.844485545s","remote":"127.0.0.1:43656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":1881,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" "} {"level":"warn","ts":"2026-07-02T06:06:08.294412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.439010738s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T06:06:08.294585Z","caller":"traceutil/trace.go:171","msg":"trace[2062006608] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:28056; }","duration":"1.439210854s","start":"2026-07-02T06:06:06.855351Z","end":"2026-07-02T06:06:08.294562Z","steps":["trace[2062006608] 'agreement among raft nodes before linearized reading' (duration: 1.438982598s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T06:06:08.294628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T06:06:06.855338Z","time spent":"1.439280676s","remote":"127.0.0.1:41512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-07-02T06:06:08.294929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.439398259s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T06:06:08.294964Z","caller":"traceutil/trace.go:171","msg":"trace[537754965] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0; response_count:0; response_revision:28056; }","duration":"1.439463781s","start":"2026-07-02T06:06:06.855491Z","end":"2026-07-02T06:06:08.294955Z","steps":["trace[537754965] 'agreement among raft nodes before linearized reading' (duration: 1.439400909s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T06:06:08.294983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T06:06:06.855478Z","time spent":"1.439498993s","remote":"127.0.0.1:34280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":142,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0\" count_only:true "} {"level":"warn","ts":"2026-07-02T06:06:08.29498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.565668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.248\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-07-02T06:06:08.295022Z","caller":"traceutil/trace.go:171","msg":"trace[866594862] range","detail":"{range_begin:/registry/masterleases/199.204.45.248; range_end:; response_count:1; response_revision:28056; }","duration":"392.635649ms","start":"2026-07-02T06:06:07.902381Z","end":"2026-07-02T06:06:08.295017Z","steps":["trace[866594862] 'agreement among raft nodes before linearized reading' (duration: 392.562717ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T06:06:08.295043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T06:06:07.902367Z","time spent":"392.67244ms","remote":"127.0.0.1:43518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":163,"request content":"key:\"/registry/masterleases/199.204.45.248\" "} {"level":"info","ts":"2026-07-02T06:08:03.682696Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26275} {"level":"info","ts":"2026-07-02T06:08:03.82803Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26275,"took":"141.937226ms","hash":489114245,"current-db-size-bytes":59092992,"current-db-size":"59 MB","current-db-size-in-use-bytes":34099200,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-02T06:08:03.828107Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":489114245,"revision":26275,"compact-revision":23324} {"level":"info","ts":"2026-07-02T06:08:27.124773Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"39582465ceaa2f67","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-02T06:08:27.130017Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-07-02T06:08:27.13017Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-07-02T06:13:03.69017Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28900} {"level":"info","ts":"2026-07-02T06:13:03.838196Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28900,"took":"143.745888ms","hash":1804762497,"current-db-size-bytes":59092992,"current-db-size":"59 MB","current-db-size-in-use-bytes":30396416,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-02T06:13:03.838317Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1804762497,"revision":28900,"compact-revision":26275} {"level":"info","ts":"2026-07-02T06:18:03.699606Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31113} {"level":"info","ts":"2026-07-02T06:18:03.813949Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31113,"took":"110.07682ms","hash":1202276043,"current-db-size-bytes":59092992,"current-db-size":"59 MB","current-db-size-in-use-bytes":31236096,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-02T06:18:03.814Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1202276043,"revision":31113,"compact-revision":28900} {"level":"info","ts":"2026-07-02T06:23:03.706583Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33379} {"level":"info","ts":"2026-07-02T06:23:03.847631Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33379,"took":"137.628755ms","hash":118038282,"current-db-size-bytes":59092992,"current-db-size":"59 MB","current-db-size-in-use-bytes":32317440,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-02T06:23:03.847763Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":118038282,"revision":33379,"compact-revision":31113} {"level":"info","ts":"2026-07-02T06:28:03.713314Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35673} {"level":"info","ts":"2026-07-02T06:28:03.85571Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35673,"took":"138.515177ms","hash":3555324967,"current-db-size-bytes":59092992,"current-db-size":"59 MB","current-db-size-in-use-bytes":31739904,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-02T06:28:03.855811Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3555324967,"revision":35673,"compact-revision":33379} {"level":"info","ts":"2026-07-02T06:29:57.717827Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"39582465ceaa2f67","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-02T06:29:57.723083Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-07-02T06:29:57.72321Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-07-02T06:33:03.718814Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37903} {"level":"info","ts":"2026-07-02T06:33:03.833707Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37903,"took":"111.130388ms","hash":1852303933,"current-db-size-bytes":59092992,"current-db-size":"59 MB","current-db-size-in-use-bytes":31178752,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-02T06:33:03.833763Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1852303933,"revision":37903,"compact-revision":35673}