{"level":"warn","ts":"2026-04-23T07:38:54.562256Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-23T07:38:54.562385Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.209: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.209:2380","--initial-cluster=instance=https://199.204.45.209:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.209:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.209: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-04-23T07:38:54.562472Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-23T07:38:54.562488Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.209:2380"]} {"level":"info","ts":"2026-04-23T07:38:54.562536Z","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-04-23T07:38:54.563647Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.209:2379"]} {"level":"info","ts":"2026-04-23T07:38:54.563739Z","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":8,"max-cpu-available":8,"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.209:2380"],"listen-peer-urls":["https://199.204.45.209:2380"],"advertise-client-urls":["https://199.204.45.209:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.209:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.209: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-04-23T07:38:54.569217Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.094446ms"} {"level":"info","ts":"2026-04-23T07:38:54.577113Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"adfb641115bf6cbd","cluster-id":"3da6b677d4b80c6"} {"level":"info","ts":"2026-04-23T07:38:54.57719Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd switched to configuration voters=()"} {"level":"info","ts":"2026-04-23T07:38:54.577212Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd became follower at term 0"} {"level":"info","ts":"2026-04-23T07:38:54.577226Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft adfb641115bf6cbd [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-23T07:38:54.577239Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd became follower at term 1"} {"level":"info","ts":"2026-04-23T07:38:54.577277Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd switched to configuration voters=(12536724012257995965)"} {"level":"warn","ts":"2026-04-23T07:38:54.584057Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-23T07:38:54.585813Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-23T07:38:54.588973Z","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-04-23T07:38:54.592693Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"adfb641115bf6cbd","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-23T07:38:54.592937Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"adfb641115bf6cbd","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-23T07:38:54.593142Z","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-04-23T07:38:54.593203Z","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-04-23T07:38:54.593215Z","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-04-23T07:38:54.595527Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd switched to configuration voters=(12536724012257995965)"} {"level":"info","ts":"2026-04-23T07:38:54.596771Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3da6b677d4b80c6","local-member-id":"adfb641115bf6cbd","added-peer-id":"adfb641115bf6cbd","added-peer-peer-urls":["https://199.204.45.209:2380"]} {"level":"info","ts":"2026-04-23T07:38:54.598624Z","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-04-23T07:38:54.598897Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"adfb641115bf6cbd","initial-advertise-peer-urls":["https://199.204.45.209:2380"],"listen-peer-urls":["https://199.204.45.209:2380"],"advertise-client-urls":["https://199.204.45.209:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.209:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-23T07:38:54.598963Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-23T07:38:54.59912Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.209:2380"} {"level":"info","ts":"2026-04-23T07:38:54.599157Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.209:2380"} {"level":"info","ts":"2026-04-23T07:38:55.377441Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd is starting a new election at term 1"} {"level":"info","ts":"2026-04-23T07:38:55.377492Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd became pre-candidate at term 1"} {"level":"info","ts":"2026-04-23T07:38:55.377523Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd received MsgPreVoteResp from adfb641115bf6cbd at term 1"} {"level":"info","ts":"2026-04-23T07:38:55.377539Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd became candidate at term 2"} {"level":"info","ts":"2026-04-23T07:38:55.377549Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd received MsgVoteResp from adfb641115bf6cbd at term 2"} {"level":"info","ts":"2026-04-23T07:38:55.377563Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd became leader at term 2"} {"level":"info","ts":"2026-04-23T07:38:55.377583Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: adfb641115bf6cbd elected leader adfb641115bf6cbd at term 2"} {"level":"info","ts":"2026-04-23T07:38:55.378659Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T07:38:55.379468Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-23T07:38:55.379444Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"adfb641115bf6cbd","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.209:2379]}","request-path":"/0/members/adfb641115bf6cbd/attributes","cluster-id":"3da6b677d4b80c6","publish-timeout":"7s"} {"level":"info","ts":"2026-04-23T07:38:55.37979Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-23T07:38:55.379843Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-23T07:38:55.382164Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-23T07:38:55.387115Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-23T07:38:55.389058Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3da6b677d4b80c6","local-member-id":"adfb641115bf6cbd","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T07:38:55.389256Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T07:38:55.389306Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T07:38:55.390968Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.209:2379"} {"level":"info","ts":"2026-04-23T07:38:59.862786Z","caller":"traceutil/trace.go:171","msg":"trace[402249483] linearizableReadLoop","detail":"{readStateIndex:213; appliedIndex:212; }","duration":"190.365989ms","start":"2026-04-23T07:38:59.672394Z","end":"2026-04-23T07:38:59.86276Z","steps":["trace[402249483] 'read index received' (duration: 190.242576ms)","trace[402249483] 'applied index is now lower than readState.Index' (duration: 122.313µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:38:59.862906Z","caller":"traceutil/trace.go:171","msg":"trace[1208939036] transaction","detail":"{read_only:false; response_revision:208; number_of_response:1; }","duration":"480.106459ms","start":"2026-04-23T07:38:59.382766Z","end":"2026-04-23T07:38:59.862872Z","steps":["trace[1208939036] 'process raft request' (duration: 479.841873ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:38:59.862978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.507763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T07:38:59.863046Z","caller":"traceutil/trace.go:171","msg":"trace[301176996] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:208; }","duration":"190.676458ms","start":"2026-04-23T07:38:59.672356Z","end":"2026-04-23T07:38:59.863033Z","steps":["trace[301176996] 'agreement among raft nodes before linearized reading' (duration: 190.521853ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:38:59.863535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:38:59.382738Z","time spent":"480.259903ms","remote":"127.0.0.1:34040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4003,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T07:39:00.310922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.65176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-dca1nz\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T07:39:00.311004Z","caller":"traceutil/trace.go:171","msg":"trace[1220287919] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-dca1nz; range_end:; response_count:0; response_revision:208; }","duration":"443.778784ms","start":"2026-04-23T07:38:59.867206Z","end":"2026-04-23T07:39:00.310985Z","steps":["trace[1220287919] 'range keys from in-memory index tree' (duration: 443.519317ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:39:00.311043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:38:59.867184Z","time spent":"443.850955ms","remote":"127.0.0.1:33982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/kube-system/bootstrap-token-dca1nz\" "} {"level":"warn","ts":"2026-04-23T07:39:00.311431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.288744ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-23T07:39:00.311683Z","caller":"traceutil/trace.go:171","msg":"trace[1609371788] transaction","detail":"{read_only:false; response_revision:209; number_of_response:1; }","duration":"444.179912ms","start":"2026-04-23T07:38:59.867352Z","end":"2026-04-23T07:39:00.311532Z","steps":["trace[1609371788] 'process raft request' (duration: 207.581792ms)","trace[1609371788] 'compare' (duration: 235.622276ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T07:39:00.311795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:38:59.86733Z","time spent":"444.409099ms","remote":"127.0.0.1:34114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T07:39:06.192461Z","caller":"traceutil/trace.go:171","msg":"trace[254799728] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"230.885063ms","start":"2026-04-23T07:39:05.961554Z","end":"2026-04-23T07:39:06.192439Z","steps":["trace[254799728] 'process raft request' (duration: 230.73446ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:39:07.231306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.69244ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T07:39:07.231532Z","caller":"traceutil/trace.go:171","msg":"trace[1367007402] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"289.254096ms","start":"2026-04-23T07:39:06.942224Z","end":"2026-04-23T07:39:07.231478Z","steps":["trace[1367007402] 'process raft request' (duration: 137.272939ms)","trace[1367007402] 'compare' (duration: 151.504164ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:39:07.231548Z","caller":"traceutil/trace.go:171","msg":"trace[168830507] linearizableReadLoop","detail":"{readStateIndex:366; appliedIndex:365; }","duration":"111.424515ms","start":"2026-04-23T07:39:07.120099Z","end":"2026-04-23T07:39:07.231524Z","steps":["trace[168830507] 'read index received' (duration: 41.451µs)","trace[168830507] 'applied index is now lower than readState.Index' (duration: 111.381834ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T07:39:07.231906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.797473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-23T07:39:07.231945Z","caller":"traceutil/trace.go:171","msg":"trace[820175520] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:355; }","duration":"111.895675ms","start":"2026-04-23T07:39:07.12004Z","end":"2026-04-23T07:39:07.231935Z","steps":["trace[820175520] 'agreement among raft nodes before linearized reading' (duration: 111.578148ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:39:08.513855Z","caller":"traceutil/trace.go:171","msg":"trace[175744468] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"152.557506ms","start":"2026-04-23T07:39:08.361261Z","end":"2026-04-23T07:39:08.513819Z","steps":["trace[175744468] 'process raft request' (duration: 152.29798ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:39:08.514736Z","caller":"traceutil/trace.go:171","msg":"trace[1005896034] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"150.121468ms","start":"2026-04-23T07:39:08.36459Z","end":"2026-04-23T07:39:08.514712Z","steps":["trace[1005896034] 'process raft request' (duration: 149.993595ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:39:24.804892Z","caller":"traceutil/trace.go:171","msg":"trace[1545003399] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"220.25585ms","start":"2026-04-23T07:39:24.584612Z","end":"2026-04-23T07:39:24.804868Z","steps":["trace[1545003399] 'process raft request' (duration: 220.079175ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:39:24.805365Z","caller":"traceutil/trace.go:171","msg":"trace[820091641] linearizableReadLoop","detail":"{readStateIndex:481; appliedIndex:481; }","duration":"216.5851ms","start":"2026-04-23T07:39:24.588769Z","end":"2026-04-23T07:39:24.805354Z","steps":["trace[820091641] 'read index received' (duration: 216.58061ms)","trace[820091641] 'applied index is now lower than readState.Index' (duration: 3.63µs)"],"step_count":2} {"level":"warn","ts":"2026-04-23T07:39:24.805454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.672362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-23T07:39:24.805492Z","caller":"traceutil/trace.go:171","msg":"trace[1705470159] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:465; }","duration":"216.746184ms","start":"2026-04-23T07:39:24.588734Z","end":"2026-04-23T07:39:24.80548Z","steps":["trace[1705470159] 'agreement among raft nodes before linearized reading' (duration: 216.669792ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:39:24.815353Z","caller":"traceutil/trace.go:171","msg":"trace[1168482062] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"105.191151ms","start":"2026-04-23T07:39:24.710118Z","end":"2026-04-23T07:39:24.815309Z","steps":["trace[1168482062] 'process raft request' (duration: 105.077298ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:39:24.815473Z","caller":"traceutil/trace.go:171","msg":"trace[2033050021] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"108.002399ms","start":"2026-04-23T07:39:24.707411Z","end":"2026-04-23T07:39:24.815414Z","steps":["trace[2033050021] 'process raft request' (duration: 107.704332ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:39:25.032779Z","caller":"traceutil/trace.go:171","msg":"trace[473838006] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"209.451438ms","start":"2026-04-23T07:39:24.823293Z","end":"2026-04-23T07:39:25.032744Z","steps":["trace[473838006] 'process raft request' (duration: 155.835239ms)","trace[473838006] 'compare' (duration: 53.478016ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:39:25.034653Z","caller":"traceutil/trace.go:171","msg":"trace[1875917099] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"211.010166ms","start":"2026-04-23T07:39:24.823607Z","end":"2026-04-23T07:39:25.034617Z","steps":["trace[1875917099] 'process raft request' (duration: 210.823032ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:39:25.03484Z","caller":"traceutil/trace.go:171","msg":"trace[108291796] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"202.700056ms","start":"2026-04-23T07:39:24.832128Z","end":"2026-04-23T07:39:25.034828Z","steps":["trace[108291796] 'process raft request' (duration: 202.564213ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:39:25.035031Z","caller":"traceutil/trace.go:171","msg":"trace[114628096] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"202.945832ms","start":"2026-04-23T07:39:24.83203Z","end":"2026-04-23T07:39:25.034976Z","steps":["trace[114628096] 'process raft request' (duration: 202.518761ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:39:25.03506Z","caller":"traceutil/trace.go:171","msg":"trace[1787017451] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"195.699107ms","start":"2026-04-23T07:39:24.839333Z","end":"2026-04-23T07:39:25.035032Z","steps":["trace[1787017451] 'process raft request' (duration: 195.607115ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:39:25.035138Z","caller":"traceutil/trace.go:171","msg":"trace[595275505] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"197.662324ms","start":"2026-04-23T07:39:24.837374Z","end":"2026-04-23T07:39:25.035036Z","steps":["trace[595275505] 'process raft request' (duration: 197.352307ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:39:25.035386Z","caller":"traceutil/trace.go:171","msg":"trace[938900460] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"197.961991ms","start":"2026-04-23T07:39:24.837411Z","end":"2026-04-23T07:39:25.035373Z","steps":["trace[938900460] 'process raft request' (duration: 197.341046ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:39:25.035482Z","caller":"traceutil/trace.go:171","msg":"trace[1920814405] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"197.719266ms","start":"2026-04-23T07:39:24.837753Z","end":"2026-04-23T07:39:25.035473Z","steps":["trace[1920814405] 'process raft request' (duration: 197.06682ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:39:25.035689Z","caller":"traceutil/trace.go:171","msg":"trace[2096244047] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"198.197208ms","start":"2026-04-23T07:39:24.83747Z","end":"2026-04-23T07:39:25.035667Z","steps":["trace[2096244047] 'process raft request' (duration: 197.309346ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:39:25.035937Z","caller":"traceutil/trace.go:171","msg":"trace[1827375621] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"197.997033ms","start":"2026-04-23T07:39:24.837928Z","end":"2026-04-23T07:39:25.035925Z","steps":["trace[1827375621] 'process raft request' (duration: 196.930697ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:39:25.036341Z","caller":"traceutil/trace.go:171","msg":"trace[978957220] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"196.983028ms","start":"2026-04-23T07:39:24.839344Z","end":"2026-04-23T07:39:25.036327Z","steps":["trace[978957220] 'process raft request' (duration: 195.640376ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:39:25.036588Z","caller":"traceutil/trace.go:171","msg":"trace[938024406] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"144.748101ms","start":"2026-04-23T07:39:24.891827Z","end":"2026-04-23T07:39:25.036575Z","steps":["trace[938024406] 'process raft request' (duration: 144.377303ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:39:30.293254Z","caller":"traceutil/trace.go:171","msg":"trace[2020132815] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"397.5039ms","start":"2026-04-23T07:39:29.895726Z","end":"2026-04-23T07:39:30.29323Z","steps":["trace[2020132815] 'process raft request' (duration: 396.964557ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:39:30.293462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:39:29.895698Z","time spent":"397.650874ms","remote":"127.0.0.1:33982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036217,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T07:39:31.356738Z","caller":"traceutil/trace.go:171","msg":"trace[860490982] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"273.687178ms","start":"2026-04-23T07:39:31.083024Z","end":"2026-04-23T07:39:31.356711Z","steps":["trace[860490982] 'process raft request' (duration: 273.515934ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:39:31.877727Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835592345781073356,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-23T07:39:31.941596Z","caller":"traceutil/trace.go:171","msg":"trace[1565424508] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"699.262191ms","start":"2026-04-23T07:39:31.242304Z","end":"2026-04-23T07:39:31.941566Z","steps":["trace[1565424508] 'process raft request' (duration: 697.679954ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:39:31.941753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:39:31.242276Z","time spent":"699.390193ms","remote":"127.0.0.1:33982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036277,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T07:39:32.124219Z","caller":"traceutil/trace.go:171","msg":"trace[1232533183] linearizableReadLoop","detail":"{readStateIndex:593; appliedIndex:591; }","duration":"747.01608ms","start":"2026-04-23T07:39:31.377173Z","end":"2026-04-23T07:39:32.124189Z","steps":["trace[1232533183] 'read index received' (duration: 562.104779ms)","trace[1232533183] 'applied index is now lower than readState.Index' (duration: 184.909841ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:39:32.124342Z","caller":"traceutil/trace.go:171","msg":"trace[154344936] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"735.134817ms","start":"2026-04-23T07:39:31.389158Z","end":"2026-04-23T07:39:32.124293Z","steps":["trace[154344936] 'process raft request' (duration: 683.480421ms)","trace[154344936] 'compare' (duration: 51.332787ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T07:39:32.124608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:39:31.38913Z","time spent":"735.396474ms","remote":"127.0.0.1:34114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T07:39:32.124606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"747.40087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-23T07:39:32.124729Z","caller":"traceutil/trace.go:171","msg":"trace[1083812505] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:575; }","duration":"747.603285ms","start":"2026-04-23T07:39:31.377097Z","end":"2026-04-23T07:39:32.124701Z","steps":["trace[1083812505] 'agreement among raft nodes before linearized reading' (duration: 747.256056ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:39:32.124787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:39:31.37707Z","time spent":"747.705297ms","remote":"127.0.0.1:34114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-23T07:39:32.184313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.886667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T07:39:32.184406Z","caller":"traceutil/trace.go:171","msg":"trace[442975109] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"186.625252ms","start":"2026-04-23T07:39:31.997763Z","end":"2026-04-23T07:39:32.184388Z","steps":["trace[442975109] 'process raft request' (duration: 186.367225ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:39:32.184419Z","caller":"traceutil/trace.go:171","msg":"trace[1287722547] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:576; }","duration":"378.038391ms","start":"2026-04-23T07:39:31.80636Z","end":"2026-04-23T07:39:32.184398Z","steps":["trace[1287722547] 'agreement among raft nodes before linearized reading' (duration: 377.892377ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:39:32.184464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:39:31.806339Z","time spent":"378.116603ms","remote":"127.0.0.1:33870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T07:39:32.184736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.357259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-bds5v\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-23T07:39:32.184783Z","caller":"traceutil/trace.go:171","msg":"trace[1640895311] range","detail":"{range_begin:/registry/pods/kube-system/cilium-bds5v; range_end:; response_count:1; response_revision:576; }","duration":"294.409231ms","start":"2026-04-23T07:39:31.890363Z","end":"2026-04-23T07:39:32.184772Z","steps":["trace[1640895311] 'agreement among raft nodes before linearized reading' (duration: 294.117194ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:39:32.685882Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835592345781073363,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-23T07:39:33.192628Z","caller":"traceutil/trace.go:171","msg":"trace[490754212] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"1.063408469s","start":"2026-04-23T07:39:32.129167Z","end":"2026-04-23T07:39:33.192576Z","steps":["trace[490754212] 'process raft request' (duration: 1.020204657s)","trace[490754212] 'compare' (duration: 39.903043ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T07:39:33.192879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:39:32.129138Z","time spent":"1.063617314s","remote":"127.0.0.1:34114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T07:39:33.197415Z","caller":"traceutil/trace.go:171","msg":"trace[1368299275] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"995.435243ms","start":"2026-04-23T07:39:32.201939Z","end":"2026-04-23T07:39:33.197374Z","steps":["trace[1368299275] 'process raft request' (duration: 994.03017ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:39:33.19745Z","caller":"traceutil/trace.go:171","msg":"trace[2029653389] linearizableReadLoop","detail":"{readStateIndex:596; appliedIndex:594; }","duration":"1.01204312s","start":"2026-04-23T07:39:32.185312Z","end":"2026-04-23T07:39:33.197355Z","steps":["trace[2029653389] 'read index received' (duration: 964.039454ms)","trace[2029653389] 'applied index is now lower than readState.Index' (duration: 47.997396ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T07:39:33.197586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.012254075s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T07:39:33.197964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.001598141s","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-04-23T07:39:33.198047Z","caller":"traceutil/trace.go:171","msg":"trace[47161313] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:578; }","duration":"1.001717294s","start":"2026-04-23T07:39:32.196305Z","end":"2026-04-23T07:39:33.198022Z","steps":["trace[47161313] 'agreement among raft nodes before linearized reading' (duration: 1.00154904s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:39:33.198114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.675184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-23T07:39:33.198152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:39:32.196281Z","time spent":"1.001857537s","remote":"127.0.0.1:34088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"info","ts":"2026-04-23T07:39:33.197976Z","caller":"traceutil/trace.go:171","msg":"trace[953154686] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:578; }","duration":"1.012684326s","start":"2026-04-23T07:39:32.185263Z","end":"2026-04-23T07:39:33.197947Z","steps":["trace[953154686] 'agreement among raft nodes before linearized reading' (duration: 1.012224234s)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:39:33.198224Z","caller":"traceutil/trace.go:171","msg":"trace[338532301] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:578; }","duration":"349.850398ms","start":"2026-04-23T07:39:32.848343Z","end":"2026-04-23T07:39:33.198193Z","steps":["trace[338532301] 'agreement among raft nodes before linearized reading' (duration: 349.579622ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:39:33.198286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:39:32.848328Z","time spent":"349.942931ms","remote":"127.0.0.1:34114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-23T07:39:33.198297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:39:32.185237Z","time spent":"1.013036914s","remote":"127.0.0.1:33876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T07:39:33.197752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:39:32.201918Z","time spent":"995.560855ms","remote":"127.0.0.1:34042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T07:39:33.198673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.627458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-23T07:39:33.198807Z","caller":"traceutil/trace.go:171","msg":"trace[2122008472] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:578; }","duration":"363.852113ms","start":"2026-04-23T07:39:32.83494Z","end":"2026-04-23T07:39:33.198792Z","steps":["trace[2122008472] 'agreement among raft nodes before linearized reading' (duration: 363.168787ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:39:33.198974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:39:32.834918Z","time spent":"364.015568ms","remote":"127.0.0.1:34114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-23T07:39:48.607051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.104457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.209\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-23T07:39:48.607167Z","caller":"traceutil/trace.go:171","msg":"trace[1918670265] range","detail":"{range_begin:/registry/masterleases/199.204.45.209; range_end:; response_count:1; response_revision:741; }","duration":"357.278641ms","start":"2026-04-23T07:39:48.249868Z","end":"2026-04-23T07:39:48.607147Z","steps":["trace[1918670265] 'range keys from in-memory index tree' (duration: 356.885642ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:39:48.607209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:39:48.249845Z","time spent":"357.355583ms","remote":"127.0.0.1:33926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.209\" "} {"level":"warn","ts":"2026-04-23T07:39:49.141262Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835592345781073752,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T07:39:49.535136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:39:48.608378Z","time spent":"926.753685ms","remote":"127.0.0.1:33926","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-23T07:39:49.594919Z","caller":"traceutil/trace.go:171","msg":"trace[633930470] linearizableReadLoop","detail":"{readStateIndex:766; appliedIndex:765; }","duration":"954.410418ms","start":"2026-04-23T07:39:48.640411Z","end":"2026-04-23T07:39:49.594822Z","steps":["trace[633930470] 'read index received' (duration: 894.689097ms)","trace[633930470] 'applied index is now lower than readState.Index' (duration: 59.719541ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:39:49.594972Z","caller":"traceutil/trace.go:171","msg":"trace[1427346423] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"563.269344ms","start":"2026-04-23T07:39:49.031666Z","end":"2026-04-23T07:39:49.594936Z","steps":["trace[1427346423] 'process raft request' (duration: 562.934497ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:39:49.595245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:39:49.031637Z","time spent":"563.420398ms","remote":"127.0.0.1:34088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T07:39:49.595326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"954.879168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-23T07:39:49.595411Z","caller":"traceutil/trace.go:171","msg":"trace[360966045] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:742; }","duration":"955.036622ms","start":"2026-04-23T07:39:48.640357Z","end":"2026-04-23T07:39:49.595394Z","steps":["trace[360966045] 'agreement among raft nodes before linearized reading' (duration: 954.697744ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:39:49.595492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:39:48.640338Z","time spent":"955.139075ms","remote":"127.0.0.1:34114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-23T07:39:49.600801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.668485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-23T07:39:49.600852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.574558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-23T07:39:49.600953Z","caller":"traceutil/trace.go:171","msg":"trace[372724170] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:743; }","duration":"301.712701ms","start":"2026-04-23T07:39:49.299204Z","end":"2026-04-23T07:39:49.600917Z","steps":["trace[372724170] 'agreement among raft nodes before linearized reading' (duration: 301.422005ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:39:49.600964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.489891ms","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-04-23T07:39:49.600992Z","caller":"traceutil/trace.go:171","msg":"trace[1532774424] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:743; }","duration":"154.570223ms","start":"2026-04-23T07:39:49.446414Z","end":"2026-04-23T07:39:49.600984Z","steps":["trace[1532774424] 'agreement among raft nodes before linearized reading' (duration: 154.492811ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:39:49.601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:39:49.299185Z","time spent":"301.805364ms","remote":"127.0.0.1:34114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-23T07:39:49.600875Z","caller":"traceutil/trace.go:171","msg":"trace[852655033] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:743; }","duration":"291.812598ms","start":"2026-04-23T07:39:49.309045Z","end":"2026-04-23T07:39:49.600857Z","steps":["trace[852655033] 'agreement among raft nodes before linearized reading' (duration: 291.551522ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:39:49.601202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.765006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-23T07:39:49.601409Z","caller":"traceutil/trace.go:171","msg":"trace[699141958] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:743; }","duration":"322.027923ms","start":"2026-04-23T07:39:49.27936Z","end":"2026-04-23T07:39:49.601388Z","steps":["trace[699141958] 'agreement among raft nodes before linearized reading' (duration: 321.666854ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:39:49.601473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:39:49.279337Z","time spent":"322.113605ms","remote":"127.0.0.1:34114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-23T07:39:49.601414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"794.944276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T07:39:49.601548Z","caller":"traceutil/trace.go:171","msg":"trace[1758756794] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:743; }","duration":"795.10735ms","start":"2026-04-23T07:39:48.806423Z","end":"2026-04-23T07:39:49.601531Z","steps":["trace[1758756794] 'agreement among raft nodes before linearized reading' (duration: 794.158097ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:39:49.601587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:39:48.806411Z","time spent":"795.166301ms","remote":"127.0.0.1:33870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-23T07:40:38.861765Z","caller":"traceutil/trace.go:171","msg":"trace[2117678627] linearizableReadLoop","detail":"{readStateIndex:1437; appliedIndex:1436; }","duration":"101.40478ms","start":"2026-04-23T07:40:38.760334Z","end":"2026-04-23T07:40:38.861738Z","steps":["trace[2117678627] 'read index received' (duration: 101.170404ms)","trace[2117678627] 'applied index is now lower than readState.Index' (duration: 233.156µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:40:38.861801Z","caller":"traceutil/trace.go:171","msg":"trace[840218617] transaction","detail":"{read_only:false; response_revision:1395; number_of_response:1; }","duration":"115.366494ms","start":"2026-04-23T07:40:38.746401Z","end":"2026-04-23T07:40:38.861768Z","steps":["trace[840218617] 'process raft request' (duration: 115.20678ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:40:38.862107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.753199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/ingress-nginx/ingress-nginx-admission\" ","response":"range_response_count:1 size:1827"} {"level":"info","ts":"2026-04-23T07:40:38.862198Z","caller":"traceutil/trace.go:171","msg":"trace[103738169] range","detail":"{range_begin:/registry/secrets/ingress-nginx/ingress-nginx-admission; range_end:; response_count:1; response_revision:1395; }","duration":"101.894092ms","start":"2026-04-23T07:40:38.76028Z","end":"2026-04-23T07:40:38.862174Z","steps":["trace[103738169] 'agreement among raft nodes before linearized reading' (duration: 101.594135ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:40:43.610363Z","caller":"traceutil/trace.go:171","msg":"trace[357492463] transaction","detail":"{read_only:false; response_revision:1446; number_of_response:1; }","duration":"125.678829ms","start":"2026-04-23T07:40:43.484657Z","end":"2026-04-23T07:40:43.610336Z","steps":["trace[357492463] 'process raft request' (duration: 125.521685ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:40:43.650855Z","caller":"traceutil/trace.go:171","msg":"trace[1885301249] transaction","detail":"{read_only:false; response_revision:1447; number_of_response:1; }","duration":"112.124435ms","start":"2026-04-23T07:40:43.538715Z","end":"2026-04-23T07:40:43.65084Z","steps":["trace[1885301249] 'process raft request' (duration: 112.062323ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:40:48.034754Z","caller":"traceutil/trace.go:171","msg":"trace[1074981383] transaction","detail":"{read_only:false; response_revision:1507; number_of_response:1; }","duration":"118.463989ms","start":"2026-04-23T07:40:47.916267Z","end":"2026-04-23T07:40:48.034731Z","steps":["trace[1074981383] 'process raft request' (duration: 82.631831ms)","trace[1074981383] 'compare' (duration: 35.624063ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T07:40:48.034825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.649118ms","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-04-23T07:40:48.034905Z","caller":"traceutil/trace.go:171","msg":"trace[623000631] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1507; }","duration":"116.75408ms","start":"2026-04-23T07:40:47.918131Z","end":"2026-04-23T07:40:48.034885Z","steps":["trace[623000631] 'agreement among raft nodes before linearized reading' (duration: 116.554885ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:40:57.069108Z","caller":"traceutil/trace.go:171","msg":"trace[116011993] transaction","detail":"{read_only:false; response_revision:1691; number_of_response:1; }","duration":"100.806206ms","start":"2026-04-23T07:40:56.968269Z","end":"2026-04-23T07:40:57.069075Z","steps":["trace[116011993] 'process raft request' (duration: 100.443208ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:41:04.371617Z","caller":"traceutil/trace.go:171","msg":"trace[1695574655] transaction","detail":"{read_only:false; response_revision:1799; number_of_response:1; }","duration":"352.455ms","start":"2026-04-23T07:41:04.01914Z","end":"2026-04-23T07:41:04.371595Z","steps":["trace[1695574655] 'process raft request' (duration: 352.296357ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:41:04.371777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:41:04.019113Z","time spent":"352.573784ms","remote":"127.0.0.1:34114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T07:41:04.372276Z","caller":"traceutil/trace.go:171","msg":"trace[1454333762] linearizableReadLoop","detail":"{readStateIndex:1867; appliedIndex:1867; }","duration":"337.746472ms","start":"2026-04-23T07:41:04.034517Z","end":"2026-04-23T07:41:04.372264Z","steps":["trace[1454333762] 'read index received' (duration: 337.741692ms)","trace[1454333762] 'applied index is now lower than readState.Index' (duration: 4.1µs)"],"step_count":2} {"level":"warn","ts":"2026-04-23T07:41:04.372449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.936076ms","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-04-23T07:41:04.372485Z","caller":"traceutil/trace.go:171","msg":"trace[872674438] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1799; }","duration":"338.013338ms","start":"2026-04-23T07:41:04.034463Z","end":"2026-04-23T07:41:04.372477Z","steps":["trace[872674438] 'agreement among raft nodes before linearized reading' (duration: 337.874125ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:41:04.372514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:41:04.034441Z","time spent":"338.06617ms","remote":"127.0.0.1:34114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-23T07:41:04.45407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.363901ms","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-04-23T07:41:04.454163Z","caller":"traceutil/trace.go:171","msg":"trace[1150777450] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1800; }","duration":"343.498073ms","start":"2026-04-23T07:41:04.110647Z","end":"2026-04-23T07:41:04.454145Z","steps":["trace[1150777450] 'agreement among raft nodes before linearized reading' (duration: 343.259228ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:41:04.454226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:41:04.110637Z","time spent":"343.560595ms","remote":"127.0.0.1:34114","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-04-23T07:41:04.454218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.403222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-23T07:41:04.454072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.097295ms","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-04-23T07:41:04.454222Z","caller":"traceutil/trace.go:171","msg":"trace[1121156714] transaction","detail":"{read_only:false; response_revision:1800; number_of_response:1; }","duration":"123.262719ms","start":"2026-04-23T07:41:04.330869Z","end":"2026-04-23T07:41:04.454132Z","steps":["trace[1121156714] 'process raft request' (duration: 122.822609ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:41:04.454321Z","caller":"traceutil/trace.go:171","msg":"trace[513468240] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1800; }","duration":"343.551866ms","start":"2026-04-23T07:41:04.110735Z","end":"2026-04-23T07:41:04.454286Z","steps":["trace[513468240] 'agreement among raft nodes before linearized reading' (duration: 343.263259ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:41:04.454337Z","caller":"traceutil/trace.go:171","msg":"trace[1288876578] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1800; }","duration":"330.396282ms","start":"2026-04-23T07:41:04.123905Z","end":"2026-04-23T07:41:04.454301Z","steps":["trace[1288876578] 'agreement among raft nodes before linearized reading' (duration: 329.976332ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:41:04.454374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:41:04.110712Z","time spent":"343.652477ms","remote":"127.0.0.1:34114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-23T07:41:04.454424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:41:04.123879Z","time spent":"330.517934ms","remote":"127.0.0.1:34114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-23T07:41:04.454125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.438108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-23T07:41:04.454733Z","caller":"traceutil/trace.go:171","msg":"trace[262648023] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1800; }","duration":"346.116785ms","start":"2026-04-23T07:41:04.108596Z","end":"2026-04-23T07:41:04.454713Z","steps":["trace[262648023] 'agreement among raft nodes before linearized reading' (duration: 345.427668ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:41:04.455192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:41:04.108574Z","time spent":"346.600585ms","remote":"127.0.0.1:34114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-23T07:41:04.534352Z","caller":"traceutil/trace.go:171","msg":"trace[653462525] transaction","detail":"{read_only:false; response_revision:1801; number_of_response:1; }","duration":"156.934056ms","start":"2026-04-23T07:41:04.377389Z","end":"2026-04-23T07:41:04.534323Z","steps":["trace[653462525] 'process raft request' (duration: 97.546797ms)","trace[653462525] 'compare' (duration: 59.207825ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T07:41:04.534795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.493281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-32389ca4-0339-4baf-8cbe-f459763939d5\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-23T07:41:04.534853Z","caller":"traceutil/trace.go:171","msg":"trace[783308808] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-32389ca4-0339-4baf-8cbe-f459763939d5; range_end:; response_count:1; response_revision:1801; }","duration":"125.599783ms","start":"2026-04-23T07:41:04.409237Z","end":"2026-04-23T07:41:04.534836Z","steps":["trace[783308808] 'agreement among raft nodes before linearized reading' (duration: 125.037911ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:41:04.7504Z","caller":"traceutil/trace.go:171","msg":"trace[1687950678] transaction","detail":"{read_only:false; response_revision:1803; number_of_response:1; }","duration":"290.684709ms","start":"2026-04-23T07:41:04.459693Z","end":"2026-04-23T07:41:04.750378Z","steps":["trace[1687950678] 'process raft request' (duration: 290.539205ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:41:04.75072Z","caller":"traceutil/trace.go:171","msg":"trace[1920271441] transaction","detail":"{read_only:false; response_revision:1802; number_of_response:1; }","duration":"291.810445ms","start":"2026-04-23T07:41:04.458858Z","end":"2026-04-23T07:41:04.750669Z","steps":["trace[1920271441] 'process raft request' (duration: 226.834178ms)","trace[1920271441] 'compare' (duration: 64.22967ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:41:04.751115Z","caller":"traceutil/trace.go:171","msg":"trace[2126820648] transaction","detail":"{read_only:false; response_revision:1804; number_of_response:1; }","duration":"291.155369ms","start":"2026-04-23T07:41:04.459939Z","end":"2026-04-23T07:41:04.751095Z","steps":["trace[2126820648] 'process raft request' (duration: 290.398722ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:41:04.751532Z","caller":"traceutil/trace.go:171","msg":"trace[612968796] transaction","detail":"{read_only:false; response_revision:1805; number_of_response:1; }","duration":"290.463594ms","start":"2026-04-23T07:41:04.461055Z","end":"2026-04-23T07:41:04.751519Z","steps":["trace[612968796] 'process raft request' (duration: 290.065284ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:41:04.751662Z","caller":"traceutil/trace.go:171","msg":"trace[246807617] linearizableReadLoop","detail":"{readStateIndex:1874; appliedIndex:1869; }","duration":"124.800986ms","start":"2026-04-23T07:41:04.626845Z","end":"2026-04-23T07:41:04.751646Z","steps":["trace[246807617] 'read index received' (duration: 58.720733ms)","trace[246807617] 'applied index is now lower than readState.Index' (duration: 66.078763ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:41:04.751791Z","caller":"traceutil/trace.go:171","msg":"trace[654019435] transaction","detail":"{read_only:false; response_revision:1806; number_of_response:1; }","duration":"288.121569ms","start":"2026-04-23T07:41:04.463659Z","end":"2026-04-23T07:41:04.75178Z","steps":["trace[654019435] 'process raft request' (duration: 287.73012ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:41:04.752145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.291507ms","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-04-23T07:41:04.752369Z","caller":"traceutil/trace.go:171","msg":"trace[507945558] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1806; }","duration":"125.567853ms","start":"2026-04-23T07:41:04.62679Z","end":"2026-04-23T07:41:04.752358Z","steps":["trace[507945558] 'agreement among raft nodes before linearized reading' (duration: 125.213165ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:41:04.752357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.313034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18a8ec7a25a540ec\" ","response":"range_response_count:1 size:982"} {"level":"info","ts":"2026-04-23T07:41:04.752563Z","caller":"traceutil/trace.go:171","msg":"trace[645187346] range","detail":"{range_begin:/registry/events/openstack/datadir-percona-xtradb-pxc-0.18a8ec7a25a540ec; range_end:; response_count:1; response_revision:1806; }","duration":"124.485768ms","start":"2026-04-23T07:41:04.628Z","end":"2026-04-23T07:41:04.752486Z","steps":["trace[645187346] 'agreement among raft nodes before linearized reading' (duration: 124.083289ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:41:04.753119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.432092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T07:41:04.753224Z","caller":"traceutil/trace.go:171","msg":"trace[485430606] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1806; }","duration":"106.591585ms","start":"2026-04-23T07:41:04.646608Z","end":"2026-04-23T07:41:04.753199Z","steps":["trace[485430606] 'agreement among raft nodes before linearized reading' (duration: 106.402961ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:41:05.114806Z","caller":"traceutil/trace.go:171","msg":"trace[229162970] linearizableReadLoop","detail":"{readStateIndex:1877; appliedIndex:1876; }","duration":"199.059976ms","start":"2026-04-23T07:41:04.915721Z","end":"2026-04-23T07:41:05.114781Z","steps":["trace[229162970] 'read index received' (duration: 198.140046ms)","trace[229162970] 'applied index is now lower than readState.Index' (duration: 918.79µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:41:05.115012Z","caller":"traceutil/trace.go:171","msg":"trace[487625176] transaction","detail":"{read_only:false; response_revision:1809; number_of_response:1; }","duration":"246.933019ms","start":"2026-04-23T07:41:04.868051Z","end":"2026-04-23T07:41:05.114984Z","steps":["trace[487625176] 'process raft request' (duration: 245.836124ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:41:05.115281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.505356ms","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:5"} {"level":"info","ts":"2026-04-23T07:41:05.115386Z","caller":"traceutil/trace.go:171","msg":"trace[909862239] 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:1809; }","duration":"199.717841ms","start":"2026-04-23T07:41:04.915654Z","end":"2026-04-23T07:41:05.115372Z","steps":["trace[909862239] 'agreement among raft nodes before linearized reading' (duration: 199.486906ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:41:19.989301Z","caller":"traceutil/trace.go:171","msg":"trace[1787946621] linearizableReadLoop","detail":"{readStateIndex:2001; appliedIndex:2000; }","duration":"128.268329ms","start":"2026-04-23T07:41:19.861015Z","end":"2026-04-23T07:41:19.989283Z","steps":["trace[1787946621] 'read index received' (duration: 128.208747ms)","trace[1787946621] 'applied index is now lower than readState.Index' (duration: 58.822µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:41:19.989385Z","caller":"traceutil/trace.go:171","msg":"trace[564881070] transaction","detail":"{read_only:false; response_revision:1930; number_of_response:1; }","duration":"240.759885ms","start":"2026-04-23T07:41:19.748593Z","end":"2026-04-23T07:41:19.989352Z","steps":["trace[564881070] 'process raft request' (duration: 240.57439ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:41:19.989523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.483104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-23T07:41:19.989549Z","caller":"traceutil/trace.go:171","msg":"trace[1967607951] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1930; }","duration":"128.560195ms","start":"2026-04-23T07:41:19.86098Z","end":"2026-04-23T07:41:19.98954Z","steps":["trace[1967607951] 'agreement among raft nodes before linearized reading' (duration: 128.393471ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:41:19.997547Z","caller":"traceutil/trace.go:171","msg":"trace[1946313358] transaction","detail":"{read_only:false; response_revision:1931; number_of_response:1; }","duration":"109.399325ms","start":"2026-04-23T07:41:19.888125Z","end":"2026-04-23T07:41:19.997524Z","steps":["trace[1946313358] 'process raft request' (duration: 109.284062ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:41:42.860607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.504761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-23T07:41:42.860704Z","caller":"traceutil/trace.go:171","msg":"trace[1151131614] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2069; }","duration":"142.657866ms","start":"2026-04-23T07:41:42.718027Z","end":"2026-04-23T07:41:42.860684Z","steps":["trace[1151131614] 'range keys from in-memory index tree' (duration: 142.264224ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:41:44.307194Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835592345781076470,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-23T07:41:44.320328Z","caller":"traceutil/trace.go:171","msg":"trace[134832999] transaction","detail":"{read_only:false; response_revision:2077; number_of_response:1; }","duration":"614.71891ms","start":"2026-04-23T07:41:43.705581Z","end":"2026-04-23T07:41:44.3203Z","steps":["trace[134832999] 'process raft request' (duration: 614.487104ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:41:44.320612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:41:43.70556Z","time spent":"614.829393ms","remote":"127.0.0.1:34114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T07:41:44.749297Z","caller":"traceutil/trace.go:171","msg":"trace[404211203] linearizableReadLoop","detail":"{readStateIndex:2154; appliedIndex:2152; }","duration":"942.402869ms","start":"2026-04-23T07:41:43.806857Z","end":"2026-04-23T07:41:44.74926Z","steps":["trace[404211203] 'read index received' (duration: 513.351591ms)","trace[404211203] 'applied index is now lower than readState.Index' (duration: 429.049488ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:41:44.749311Z","caller":"traceutil/trace.go:171","msg":"trace[1311565446] transaction","detail":"{read_only:false; response_revision:2078; number_of_response:1; }","duration":"668.417977ms","start":"2026-04-23T07:41:44.080858Z","end":"2026-04-23T07:41:44.749276Z","steps":["trace[1311565446] 'process raft request' (duration: 652.6998ms)","trace[1311565446] 'compare' (duration: 15.313155ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T07:41:44.749464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"942.585353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T07:41:44.749495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:41:44.080833Z","time spent":"668.58469ms","remote":"127.0.0.1:34114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T07:41:44.749542Z","caller":"traceutil/trace.go:171","msg":"trace[595186293] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2078; }","duration":"942.709817ms","start":"2026-04-23T07:41:43.80681Z","end":"2026-04-23T07:41:44.74952Z","steps":["trace[595186293] 'agreement among raft nodes before linearized reading' (duration: 942.581313ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:41:44.749583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:41:43.80679Z","time spent":"942.78497ms","remote":"127.0.0.1:33870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T07:41:44.805461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.45014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-04-23T07:41:44.805519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.346249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18a8ec818ed7f9b2\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-04-23T07:41:44.805572Z","caller":"traceutil/trace.go:171","msg":"trace[1437359909] transaction","detail":"{read_only:false; response_revision:2079; number_of_response:1; }","duration":"217.902856ms","start":"2026-04-23T07:41:44.587619Z","end":"2026-04-23T07:41:44.805522Z","steps":["trace[1437359909] 'process raft request' (duration: 217.413973ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:41:44.805661Z","caller":"traceutil/trace.go:171","msg":"trace[385084103] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18a8ec818ed7f9b2; range_end:; response_count:1; response_revision:2079; }","duration":"611.496794ms","start":"2026-04-23T07:41:44.19409Z","end":"2026-04-23T07:41:44.805587Z","steps":["trace[385084103] 'agreement among raft nodes before linearized reading' (duration: 611.141535ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:41:44.805552Z","caller":"traceutil/trace.go:171","msg":"trace[1303692172] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2079; }","duration":"646.592775ms","start":"2026-04-23T07:41:44.158936Z","end":"2026-04-23T07:41:44.805529Z","steps":["trace[1303692172] 'agreement among raft nodes before linearized reading' (duration: 646.335778ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:41:44.806035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:41:44.158913Z","time spent":"647.057447ms","remote":"127.0.0.1:48206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-23T07:41:44.805846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:41:44.194072Z","time spent":"611.759331ms","remote":"127.0.0.1:33966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":869,"request content":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18a8ec818ed7f9b2\" "} {"level":"info","ts":"2026-04-23T07:41:45.00381Z","caller":"traceutil/trace.go:171","msg":"trace[1935513603] linearizableReadLoop","detail":"{readStateIndex:2156; appliedIndex:2155; }","duration":"189.26578ms","start":"2026-04-23T07:41:44.814521Z","end":"2026-04-23T07:41:45.003786Z","steps":["trace[1935513603] 'read index received' (duration: 189.043554ms)","trace[1935513603] 'applied index is now lower than readState.Index' (duration: 221.016µs)"],"step_count":2} {"level":"warn","ts":"2026-04-23T07:41:45.004182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.61652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T07:41:45.004981Z","caller":"traceutil/trace.go:171","msg":"trace[1562146503] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2080; }","duration":"190.481353ms","start":"2026-04-23T07:41:44.814463Z","end":"2026-04-23T07:41:45.004944Z","steps":["trace[1562146503] 'agreement among raft nodes before linearized reading' (duration: 189.608409ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:41:45.004438Z","caller":"traceutil/trace.go:171","msg":"trace[1449893136] transaction","detail":"{read_only:false; response_revision:2080; number_of_response:1; }","duration":"195.025516ms","start":"2026-04-23T07:41:44.809396Z","end":"2026-04-23T07:41:45.004422Z","steps":["trace[1449893136] 'process raft request' (duration: 194.244505ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:41:45.476294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.837207ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T07:41:45.476437Z","caller":"traceutil/trace.go:171","msg":"trace[941046944] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2080; }","duration":"109.008451ms","start":"2026-04-23T07:41:45.367409Z","end":"2026-04-23T07:41:45.476417Z","steps":["trace[941046944] 'range keys from in-memory index tree' (duration: 108.818937ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:41:45.476619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.408864ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T07:41:45.477163Z","caller":"traceutil/trace.go:171","msg":"trace[2086578043] linearizableReadLoop","detail":"{readStateIndex:2158; appliedIndex:2156; }","duration":"418.908204ms","start":"2026-04-23T07:41:45.058178Z","end":"2026-04-23T07:41:45.477086Z","steps":["trace[2086578043] 'read index received' (duration: 264.933134ms)","trace[2086578043] 'applied index is now lower than readState.Index' (duration: 153.97278ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:41:45.477306Z","caller":"traceutil/trace.go:171","msg":"trace[1813464145] transaction","detail":"{read_only:false; response_revision:2082; number_of_response:1; }","duration":"467.302174ms","start":"2026-04-23T07:41:45.009967Z","end":"2026-04-23T07:41:45.47727Z","steps":["trace[1813464145] 'process raft request' (duration: 466.835202ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:41:45.477332Z","caller":"traceutil/trace.go:171","msg":"trace[325001973] transaction","detail":"{read_only:false; response_revision:2083; number_of_response:1; }","duration":"210.349106ms","start":"2026-04-23T07:41:45.266944Z","end":"2026-04-23T07:41:45.477293Z","steps":["trace[325001973] 'process raft request' (duration: 210.066718ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:41:45.477552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:41:45.009951Z","time spent":"467.430588ms","remote":"127.0.0.1:34114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T07:41:45.47756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.370976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-23T07:41:45.477604Z","caller":"traceutil/trace.go:171","msg":"trace[1359763056] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2083; }","duration":"419.446758ms","start":"2026-04-23T07:41:45.058144Z","end":"2026-04-23T07:41:45.477591Z","steps":["trace[1359763056] 'agreement among raft nodes before linearized reading' (duration: 419.14148ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:41:45.477641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.348155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-23T07:41:45.477651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:41:45.058132Z","time spent":"419.508929ms","remote":"127.0.0.1:34114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-23T07:41:45.477658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.466536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-04-23T07:41:45.47769Z","caller":"traceutil/trace.go:171","msg":"trace[329403935] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2083; }","duration":"419.431008ms","start":"2026-04-23T07:41:45.058244Z","end":"2026-04-23T07:41:45.477675Z","steps":["trace[329403935] 'agreement among raft nodes before linearized reading' (duration: 419.271143ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:41:45.47773Z","caller":"traceutil/trace.go:171","msg":"trace[513900626] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2083; }","duration":"132.57155ms","start":"2026-04-23T07:41:45.34514Z","end":"2026-04-23T07:41:45.477712Z","steps":["trace[513900626] 'agreement among raft nodes before linearized reading' (duration: 132.309633ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:41:45.477736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:41:45.058221Z","time spent":"419.506279ms","remote":"127.0.0.1:34114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-23T07:41:45.477785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.54623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-23T07:41:45.477653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.401576ms","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-04-23T07:41:45.477842Z","caller":"traceutil/trace.go:171","msg":"trace[629964186] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2083; }","duration":"419.632873ms","start":"2026-04-23T07:41:45.058196Z","end":"2026-04-23T07:41:45.477828Z","steps":["trace[629964186] 'agreement among raft nodes before linearized reading' (duration: 419.13822ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:41:45.477844Z","caller":"traceutil/trace.go:171","msg":"trace[912905080] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2083; }","duration":"419.609031ms","start":"2026-04-23T07:41:45.058214Z","end":"2026-04-23T07:41:45.477823Z","steps":["trace[912905080] 'agreement among raft nodes before linearized reading' (duration: 419.479738ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:41:45.477873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:41:45.058184Z","time spent":"419.678014ms","remote":"127.0.0.1:34114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-23T07:41:45.477891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:41:45.058209Z","time spent":"419.669813ms","remote":"127.0.0.1:34114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-23T07:41:45.478044Z","caller":"traceutil/trace.go:171","msg":"trace[1962362876] transaction","detail":"{read_only:false; response_revision:2081; number_of_response:1; }","duration":"468.064015ms","start":"2026-04-23T07:41:45.009953Z","end":"2026-04-23T07:41:45.478017Z","steps":["trace[1962362876] 'process raft request' (duration: 313.102578ms)","trace[1962362876] 'compare' (duration: 153.076085ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T07:41:45.478132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:41:45.009929Z","time spent":"468.162887ms","remote":"127.0.0.1:34114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T07:42:34.918616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.902524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-w4622\" ","response":"range_response_count:1 size:7818"} {"level":"info","ts":"2026-04-23T07:42:34.918705Z","caller":"traceutil/trace.go:171","msg":"trace[1751319313] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-w4622; range_end:; response_count:1; response_revision:2392; }","duration":"139.066789ms","start":"2026-04-23T07:42:34.779618Z","end":"2026-04-23T07:42:34.918684Z","steps":["trace[1751319313] 'range keys from in-memory index tree' (duration: 138.697789ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:42:43.48564Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835592345781077442,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-23T07:42:43.507434Z","caller":"traceutil/trace.go:171","msg":"trace[31646951] transaction","detail":"{read_only:false; response_revision:2490; number_of_response:1; }","duration":"567.485466ms","start":"2026-04-23T07:42:42.93993Z","end":"2026-04-23T07:42:43.507415Z","steps":["trace[31646951] 'process raft request' (duration: 567.373883ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:42:43.507553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:42:42.939903Z","time spent":"567.591899ms","remote":"127.0.0.1:34114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T07:42:43.509106Z","caller":"traceutil/trace.go:171","msg":"trace[1029902206] transaction","detail":"{read_only:false; response_revision:2491; number_of_response:1; }","duration":"510.413958ms","start":"2026-04-23T07:42:42.998609Z","end":"2026-04-23T07:42:43.509023Z","steps":["trace[1029902206] 'process raft request' (duration: 510.128351ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:42:43.509176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:42:42.998592Z","time spent":"510.539751ms","remote":"127.0.0.1:34114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T07:42:43.510483Z","caller":"traceutil/trace.go:171","msg":"trace[764717535] linearizableReadLoop","detail":"{readStateIndex:2581; appliedIndex:2578; }","duration":"524.995993ms","start":"2026-04-23T07:42:42.985478Z","end":"2026-04-23T07:42:43.510473Z","steps":["trace[764717535] 'read index received' (duration: 521.780811ms)","trace[764717535] 'applied index is now lower than readState.Index' (duration: 3.214532ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T07:42:43.510684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.212689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1366"} {"level":"info","ts":"2026-04-23T07:42:43.510711Z","caller":"traceutil/trace.go:171","msg":"trace[1700964489] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2491; }","duration":"525.275351ms","start":"2026-04-23T07:42:42.985416Z","end":"2026-04-23T07:42:43.510704Z","steps":["trace[1700964489] 'agreement among raft nodes before linearized reading' (duration: 525.187768ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:42:43.51073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:42:42.985398Z","time spent":"525.326902ms","remote":"127.0.0.1:34050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1389,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-04-23T07:42:43.510861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.835499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-23T07:42:43.510885Z","caller":"traceutil/trace.go:171","msg":"trace[388855617] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2491; }","duration":"268.899391ms","start":"2026-04-23T07:42:43.241979Z","end":"2026-04-23T07:42:43.510878Z","steps":["trace[388855617] 'agreement among raft nodes before linearized reading' (duration: 268.85817ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:43:33.545423Z","caller":"traceutil/trace.go:171","msg":"trace[603503060] linearizableReadLoop","detail":"{readStateIndex:2861; appliedIndex:2860; }","duration":"128.540315ms","start":"2026-04-23T07:43:33.416857Z","end":"2026-04-23T07:43:33.545397Z","steps":["trace[603503060] 'read index received' (duration: 56.579001ms)","trace[603503060] 'applied index is now lower than readState.Index' (duration: 71.959894ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T07:43:33.545761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.859862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T07:43:33.545891Z","caller":"traceutil/trace.go:171","msg":"trace[1771305232] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:2761; }","duration":"129.048527ms","start":"2026-04-23T07:43:33.416809Z","end":"2026-04-23T07:43:33.545858Z","steps":["trace[1771305232] 'agreement among raft nodes before linearized reading' (duration: 128.805301ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:43:33.546142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.761556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-23T07:43:33.546196Z","caller":"traceutil/trace.go:171","msg":"trace[171887114] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:2761; }","duration":"123.880898ms","start":"2026-04-23T07:43:33.422303Z","end":"2026-04-23T07:43:33.546184Z","steps":["trace[171887114] 'agreement among raft nodes before linearized reading' (duration: 123.759396ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:43:33.820362Z","caller":"traceutil/trace.go:171","msg":"trace[769530525] transaction","detail":"{read_only:false; response_revision:2763; number_of_response:1; }","duration":"115.88275ms","start":"2026-04-23T07:43:33.704459Z","end":"2026-04-23T07:43:33.820342Z","steps":["trace[769530525] 'process raft request' (duration: 115.717675ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:43:34.125828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.808363ms","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-04-23T07:43:34.125909Z","caller":"traceutil/trace.go:171","msg":"trace[1425327872] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2763; }","duration":"147.893225ms","start":"2026-04-23T07:43:33.977994Z","end":"2026-04-23T07:43:34.125888Z","steps":["trace[1425327872] 'range keys from in-memory index tree' (duration: 147.659919ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:43:34.233901Z","caller":"traceutil/trace.go:171","msg":"trace[593107686] transaction","detail":"{read_only:false; response_revision:2769; number_of_response:1; }","duration":"103.203121ms","start":"2026-04-23T07:43:34.130677Z","end":"2026-04-23T07:43:34.23388Z","steps":["trace[593107686] 'process raft request' (duration: 94.898134ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:43:34.233963Z","caller":"traceutil/trace.go:171","msg":"trace[1675159093] transaction","detail":"{read_only:false; response_revision:2770; number_of_response:1; }","duration":"102.548246ms","start":"2026-04-23T07:43:34.131389Z","end":"2026-04-23T07:43:34.233938Z","steps":["trace[1675159093] 'process raft request' (duration: 102.458493ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:44:00.072609Z","caller":"traceutil/trace.go:171","msg":"trace[1324760806] transaction","detail":"{read_only:false; response_revision:2901; number_of_response:1; }","duration":"206.362921ms","start":"2026-04-23T07:43:59.866225Z","end":"2026-04-23T07:44:00.072588Z","steps":["trace[1324760806] 'process raft request' (duration: 206.242028ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:44:00.073122Z","caller":"traceutil/trace.go:171","msg":"trace[1451098439] transaction","detail":"{read_only:false; response_revision:2902; number_of_response:1; }","duration":"169.878653ms","start":"2026-04-23T07:43:59.903217Z","end":"2026-04-23T07:44:00.073096Z","steps":["trace[1451098439] 'process raft request' (duration: 169.73607ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:44:07.832331Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835592345781078775,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T07:44:08.332478Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835592345781078775,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T07:44:08.338437Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.006833703s","expected-duration":"1s"} {"level":"info","ts":"2026-04-23T07:44:08.347025Z","caller":"traceutil/trace.go:171","msg":"trace[1027673700] linearizableReadLoop","detail":"{readStateIndex:3049; appliedIndex:3046; }","duration":"1.015194458s","start":"2026-04-23T07:44:07.331794Z","end":"2026-04-23T07:44:08.346989Z","steps":["trace[1027673700] 'read index received' (duration: 1.006826373s)","trace[1027673700] 'applied index is now lower than readState.Index' (duration: 8.367225ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:44:08.347412Z","caller":"traceutil/trace.go:171","msg":"trace[692580127] transaction","detail":"{read_only:false; response_revision:2940; number_of_response:1; }","duration":"1.015912905s","start":"2026-04-23T07:44:07.331484Z","end":"2026-04-23T07:44:08.347397Z","steps":["trace[692580127] 'process raft request' (duration: 1.00712788s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:44:08.348033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.016167063s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T07:44:08.348081Z","caller":"traceutil/trace.go:171","msg":"trace[2118187578] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:2942; }","duration":"1.016229384s","start":"2026-04-23T07:44:07.33184Z","end":"2026-04-23T07:44:08.348069Z","steps":["trace[2118187578] 'agreement among raft nodes before linearized reading' (duration: 1.016155082s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:44:08.348118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.016306535s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-04-23T07:44:08.348109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:44:07.331833Z","time spent":"1.016268234s","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true "} {"level":"info","ts":"2026-04-23T07:44:08.348266Z","caller":"traceutil/trace.go:171","msg":"trace[1132691952] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2942; }","duration":"1.01648651s","start":"2026-04-23T07:44:07.331689Z","end":"2026-04-23T07:44:08.348175Z","steps":["trace[1132691952] 'agreement among raft nodes before linearized reading' (duration: 1.016267784s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:44:08.348313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:44:07.331669Z","time spent":"1.016633013s","remote":"127.0.0.1:34042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-23T07:44:08.34855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.184416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T07:44:08.34861Z","caller":"traceutil/trace.go:171","msg":"trace[586617042] transaction","detail":"{read_only:false; response_revision:2941; number_of_response:1; }","duration":"247.609375ms","start":"2026-04-23T07:44:08.100988Z","end":"2026-04-23T07:44:08.348597Z","steps":["trace[586617042] 'process raft request' (duration: 245.866742ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:44:08.348624Z","caller":"traceutil/trace.go:171","msg":"trace[1011773725] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2942; }","duration":"542.298339ms","start":"2026-04-23T07:44:07.806306Z","end":"2026-04-23T07:44:08.348604Z","steps":["trace[1011773725] 'agreement among raft nodes before linearized reading' (duration: 542.176666ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:44:08.348664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:44:07.806288Z","time spent":"542.368462ms","remote":"127.0.0.1:33870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T07:44:08.348578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"862.683964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-23T07:44:08.348818Z","caller":"traceutil/trace.go:171","msg":"trace[55205261] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:2942; }","duration":"862.97093ms","start":"2026-04-23T07:44:07.485822Z","end":"2026-04-23T07:44:08.348793Z","steps":["trace[55205261] 'agreement among raft nodes before linearized reading' (duration: 862.668963ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:44:08.348922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:44:07.485804Z","time spent":"863.097793ms","remote":"127.0.0.1:34136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":3,"response size":30,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-23T07:44:08.349182Z","caller":"traceutil/trace.go:171","msg":"trace[1221169010] transaction","detail":"{read_only:false; response_revision:2942; number_of_response:1; }","duration":"245.589286ms","start":"2026-04-23T07:44:08.103181Z","end":"2026-04-23T07:44:08.34877Z","steps":["trace[1221169010] 'process raft request' (duration: 243.772762ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:44:08.349181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"861.679479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1366"} {"level":"info","ts":"2026-04-23T07:44:08.349434Z","caller":"traceutil/trace.go:171","msg":"trace[359685016] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2942; }","duration":"861.923595ms","start":"2026-04-23T07:44:07.487439Z","end":"2026-04-23T07:44:08.349362Z","steps":["trace[359685016] 'agreement among raft nodes before linearized reading' (duration: 861.099025ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:44:08.349647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:44:07.48742Z","time spent":"862.158761ms","remote":"127.0.0.1:34050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1389,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-04-23T07:44:08.35111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:44:07.331461Z","time spent":"1.015988297s","remote":"127.0.0.1:34114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T07:44:12.632768Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835592345781078844,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T07:44:13.132992Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835592345781078844,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T07:44:13.633592Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835592345781078844,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T07:44:13.957606Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.825238309s","expected-duration":"1s"} {"level":"info","ts":"2026-04-23T07:44:13.958034Z","caller":"traceutil/trace.go:171","msg":"trace[2010565705] transaction","detail":"{read_only:false; response_revision:2962; number_of_response:1; }","duration":"1.82570066s","start":"2026-04-23T07:44:12.132305Z","end":"2026-04-23T07:44:13.958005Z","steps":["trace[2010565705] 'process raft request' (duration: 1.825443883s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:44:13.958211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:44:12.132295Z","time spent":"1.825801424s","remote":"127.0.0.1:33966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":766,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T07:44:13.967346Z","caller":"traceutil/trace.go:171","msg":"trace[316048336] transaction","detail":"{read_only:false; response_revision:2964; number_of_response:1; }","duration":"1.605848424s","start":"2026-04-23T07:44:12.361474Z","end":"2026-04-23T07:44:13.967323Z","steps":["trace[316048336] 'process raft request' (duration: 1.605779112s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:44:13.967499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:44:12.36146Z","time spent":"1.605959616s","remote":"127.0.0.1:34114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T07:44:13.96736Z","caller":"traceutil/trace.go:171","msg":"trace[2083085960] transaction","detail":"{read_only:false; response_revision:2963; number_of_response:1; }","duration":"1.833895031s","start":"2026-04-23T07:44:12.133442Z","end":"2026-04-23T07:44:13.967337Z","steps":["trace[2083085960] 'process raft request' (duration: 1.833671056s)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:44:13.967786Z","caller":"traceutil/trace.go:171","msg":"trace[399058883] transaction","detail":"{read_only:false; response_revision:2965; number_of_response:1; }","duration":"1.605186348s","start":"2026-04-23T07:44:12.362584Z","end":"2026-04-23T07:44:13.96777Z","steps":["trace[399058883] 'process raft request' (duration: 1.604706946s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:44:13.967812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:44:12.133434Z","time spent":"1.83428707s","remote":"127.0.0.1:34114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T07:44:13.967839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:44:12.362579Z","time spent":"1.605234579s","remote":"127.0.0.1:34114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T07:44:13.97519Z","caller":"traceutil/trace.go:171","msg":"trace[1045468058] linearizableReadLoop","detail":"{readStateIndex:3074; appliedIndex:3069; }","duration":"1.842752808s","start":"2026-04-23T07:44:12.132424Z","end":"2026-04-23T07:44:13.975177Z","steps":["trace[1045468058] 'read index received' (duration: 1.825315101s)","trace[1045468058] 'applied index is now lower than readState.Index' (duration: 17.437047ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T07:44:13.975415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.842973274s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T07:44:13.97545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.842577424s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1317"} {"level":"info","ts":"2026-04-23T07:44:13.975487Z","caller":"traceutil/trace.go:171","msg":"trace[278923018] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:2966; }","duration":"1.842629106s","start":"2026-04-23T07:44:12.132848Z","end":"2026-04-23T07:44:13.975477Z","steps":["trace[278923018] 'agreement among raft nodes before linearized reading' (duration: 1.842429671s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:44:13.975493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.912767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-04-23T07:44:13.975527Z","caller":"traceutil/trace.go:171","msg":"trace[944042422] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2966; }","duration":"533.980458ms","start":"2026-04-23T07:44:13.441534Z","end":"2026-04-23T07:44:13.975515Z","steps":["trace[944042422] 'agreement among raft nodes before linearized reading' (duration: 533.870916ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:44:13.975517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.505722362s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-04-23T07:44:13.975558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:44:13.441517Z","time spent":"534.03485ms","remote":"127.0.0.1:34042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-04-23T07:44:13.975577Z","caller":"traceutil/trace.go:171","msg":"trace[1175515710] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2966; }","duration":"1.505804613s","start":"2026-04-23T07:44:12.469758Z","end":"2026-04-23T07:44:13.975563Z","steps":["trace[1175515710] 'agreement among raft nodes before linearized reading' (duration: 1.505533117s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:44:13.975649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.538079194s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-23T07:44:13.975646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.498138706s","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":"warn","ts":"2026-04-23T07:44:13.975677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:44:12.469753Z","time spent":"1.505914776s","remote":"127.0.0.1:34114","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-04-23T07:44:13.975705Z","caller":"traceutil/trace.go:171","msg":"trace[1351381457] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2966; }","duration":"1.498193497s","start":"2026-04-23T07:44:12.477485Z","end":"2026-04-23T07:44:13.975678Z","steps":["trace[1351381457] 'agreement among raft nodes before linearized reading' (duration: 1.498062224s)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:44:13.975704Z","caller":"traceutil/trace.go:171","msg":"trace[276704690] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2966; }","duration":"1.538131666s","start":"2026-04-23T07:44:12.437541Z","end":"2026-04-23T07:44:13.975672Z","steps":["trace[276704690] 'agreement among raft nodes before linearized reading' (duration: 1.538013693s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:44:13.975772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:44:12.437533Z","time spent":"1.538229788s","remote":"127.0.0.1:34114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-23T07:44:13.975785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:44:12.477478Z","time spent":"1.498296059s","remote":"127.0.0.1:34114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-23T07:44:13.975827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.50364359s","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-04-23T07:44:13.975856Z","caller":"traceutil/trace.go:171","msg":"trace[1479959971] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2966; }","duration":"1.503688882s","start":"2026-04-23T07:44:12.47216Z","end":"2026-04-23T07:44:13.975849Z","steps":["trace[1479959971] 'agreement among raft nodes before linearized reading' (duration: 1.5036193s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:44:13.975878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:44:12.472155Z","time spent":"1.503719053s","remote":"127.0.0.1:34114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-23T07:44:13.975934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.528036008s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"warn","ts":"2026-04-23T07:44:13.975959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.598822912s","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-04-23T07:44:13.975971Z","caller":"traceutil/trace.go:171","msg":"trace[232875139] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2966; }","duration":"1.528080959s","start":"2026-04-23T07:44:12.447881Z","end":"2026-04-23T07:44:13.975961Z","steps":["trace[232875139] 'agreement among raft nodes before linearized reading' (duration: 1.527993127s)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:44:13.975979Z","caller":"traceutil/trace.go:171","msg":"trace[111789431] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2966; }","duration":"1.598889604s","start":"2026-04-23T07:44:12.377082Z","end":"2026-04-23T07:44:13.975972Z","steps":["trace[111789431] 'agreement among raft nodes before linearized reading' (duration: 1.598841783s)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:44:13.975971Z","caller":"traceutil/trace.go:171","msg":"trace[364694997] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2966; }","duration":"1.84324563s","start":"2026-04-23T07:44:12.132224Z","end":"2026-04-23T07:44:13.975469Z","steps":["trace[364694997] 'agreement among raft nodes before linearized reading' (duration: 1.843043965s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:44:13.976006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:44:12.377064Z","time spent":"1.598938195s","remote":"127.0.0.1:34114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-23T07:44:13.976007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:44:12.447876Z","time spent":"1.52812459s","remote":"127.0.0.1:34114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":594,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-04-23T07:44:13.976037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:44:12.132218Z","time spent":"1.843801544s","remote":"127.0.0.1:33870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T07:44:13.975827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.578005271s","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-04-23T07:44:13.976153Z","caller":"traceutil/trace.go:171","msg":"trace[272258255] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2966; }","duration":"1.578443792s","start":"2026-04-23T07:44:12.397699Z","end":"2026-04-23T07:44:13.976143Z","steps":["trace[272258255] 'agreement among raft nodes before linearized reading' (duration: 1.577916199s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:44:13.97654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:44:12.397688Z","time spent":"1.578839112s","remote":"127.0.0.1:34114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-23T07:44:13.976309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:44:12.132838Z","time spent":"1.842674687s","remote":"127.0.0.1:34050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":1340,"request content":"key:\"/registry/services/specs/auth-system/keycloak\" "} {"level":"info","ts":"2026-04-23T07:44:46.33573Z","caller":"traceutil/trace.go:171","msg":"trace[616358128] linearizableReadLoop","detail":"{readStateIndex:3249; appliedIndex:3247; }","duration":"113.96604ms","start":"2026-04-23T07:44:46.221745Z","end":"2026-04-23T07:44:46.335711Z","steps":["trace[616358128] 'read index received' (duration: 24.36222ms)","trace[616358128] 'applied index is now lower than readState.Index' (duration: 89.60286ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:44:46.335844Z","caller":"traceutil/trace.go:171","msg":"trace[1928186806] transaction","detail":"{read_only:false; response_revision:3133; number_of_response:1; }","duration":"135.921662ms","start":"2026-04-23T07:44:46.199894Z","end":"2026-04-23T07:44:46.335815Z","steps":["trace[1928186806] 'process raft request' (duration: 135.520572ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:44:46.336088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.70141ms","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-04-23T07:44:46.336136Z","caller":"traceutil/trace.go:171","msg":"trace[866565473] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3133; }","duration":"105.779101ms","start":"2026-04-23T07:44:46.230346Z","end":"2026-04-23T07:44:46.336125Z","steps":["trace[866565473] 'agreement among raft nodes before linearized reading' (duration: 105.659138ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:44:46.336293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.224216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-23T07:44:46.336369Z","caller":"traceutil/trace.go:171","msg":"trace[2022884529] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3133; }","duration":"114.619086ms","start":"2026-04-23T07:44:46.221703Z","end":"2026-04-23T07:44:46.336322Z","steps":["trace[2022884529] 'agreement among raft nodes before linearized reading' (duration: 114.129714ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:44:46.336266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.305066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-23T07:44:46.337465Z","caller":"traceutil/trace.go:171","msg":"trace[910232538] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3133; }","duration":"110.529406ms","start":"2026-04-23T07:44:46.226903Z","end":"2026-04-23T07:44:46.337433Z","steps":["trace[910232538] 'agreement among raft nodes before linearized reading' (duration: 109.202494ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:44:48.513892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.811922ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-04-23T07:44:48.514369Z","caller":"traceutil/trace.go:171","msg":"trace[2071248973] linearizableReadLoop","detail":"{readStateIndex:3259; appliedIndex:3257; }","duration":"171.079931ms","start":"2026-04-23T07:44:48.343267Z","end":"2026-04-23T07:44:48.514347Z","steps":["trace[2071248973] 'read index received' (duration: 52.822639ms)","trace[2071248973] 'applied index is now lower than readState.Index' (duration: 118.256192ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:44:48.514583Z","caller":"traceutil/trace.go:171","msg":"trace[1794036358] transaction","detail":"{read_only:false; response_revision:3142; number_of_response:1; }","duration":"171.489111ms","start":"2026-04-23T07:44:48.343059Z","end":"2026-04-23T07:44:48.514549Z","steps":["trace[1794036358] 'process raft request' (duration: 171.02884ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:44:48.514799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.602491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-04-23T07:44:48.514874Z","caller":"traceutil/trace.go:171","msg":"trace[1522770158] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:3142; }","duration":"168.729614ms","start":"2026-04-23T07:44:48.346122Z","end":"2026-04-23T07:44:48.514851Z","steps":["trace[1522770158] 'agreement among raft nodes before linearized reading' (duration: 168.503159ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:44:48.514817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.549923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T07:44:48.515168Z","caller":"traceutil/trace.go:171","msg":"trace[1106825801] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:3142; }","duration":"171.932762ms","start":"2026-04-23T07:44:48.343221Z","end":"2026-04-23T07:44:48.515153Z","steps":["trace[1106825801] 'agreement among raft nodes before linearized reading' (duration: 171.310467ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:44:48.85306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.837692ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T07:44:48.853318Z","caller":"traceutil/trace.go:171","msg":"trace[1415132489] transaction","detail":"{read_only:false; response_revision:3143; number_of_response:1; }","duration":"337.475879ms","start":"2026-04-23T07:44:48.515775Z","end":"2026-04-23T07:44:48.853251Z","steps":["trace[1415132489] 'process raft request' (duration: 107.370309ms)","trace[1415132489] 'compare' (duration: 229.713439ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:44:48.853399Z","caller":"traceutil/trace.go:171","msg":"trace[1360655227] transaction","detail":"{read_only:false; response_revision:3150; number_of_response:1; }","duration":"332.315253ms","start":"2026-04-23T07:44:48.521071Z","end":"2026-04-23T07:44:48.853386Z","steps":["trace[1360655227] 'process raft request' (duration: 332.283022ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:44:48.853542Z","caller":"traceutil/trace.go:171","msg":"trace[313297409] transaction","detail":"{read_only:false; response_revision:3144; number_of_response:1; }","duration":"333.751818ms","start":"2026-04-23T07:44:48.51977Z","end":"2026-04-23T07:44:48.853522Z","steps":["trace[313297409] 'process raft request' (duration: 333.388648ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:44:48.8536Z","caller":"traceutil/trace.go:171","msg":"trace[1993256698] transaction","detail":"{read_only:false; response_revision:3147; number_of_response:1; }","duration":"333.586294ms","start":"2026-04-23T07:44:48.519998Z","end":"2026-04-23T07:44:48.853584Z","steps":["trace[1993256698] 'process raft request' (duration: 333.272457ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:44:48.853647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:44:48.519747Z","time spent":"333.84947ms","remote":"127.0.0.1:34114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T07:44:48.853675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:44:48.519973Z","time spent":"333.662586ms","remote":"127.0.0.1:34114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T07:44:48.853646Z","caller":"traceutil/trace.go:171","msg":"trace[1794382414] transaction","detail":"{read_only:false; response_revision:3148; number_of_response:1; }","duration":"333.609134ms","start":"2026-04-23T07:44:48.520019Z","end":"2026-04-23T07:44:48.853628Z","steps":["trace[1794382414] 'process raft request' (duration: 333.278947ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:44:48.853691Z","caller":"traceutil/trace.go:171","msg":"trace[2119911269] transaction","detail":"{read_only:false; response_revision:3149; number_of_response:1; }","duration":"333.593634ms","start":"2026-04-23T07:44:48.520076Z","end":"2026-04-23T07:44:48.85367Z","steps":["trace[2119911269] 'process raft request' (duration: 333.254036ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:44:48.853734Z","caller":"traceutil/trace.go:171","msg":"trace[1204146896] transaction","detail":"{read_only:false; response_revision:3145; number_of_response:1; }","duration":"333.846781ms","start":"2026-04-23T07:44:48.519869Z","end":"2026-04-23T07:44:48.853716Z","steps":["trace[1204146896] 'process raft request' (duration: 333.344869ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:44:48.85379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:44:48.519998Z","time spent":"333.718128ms","remote":"127.0.0.1:34114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T07:44:48.853844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:44:48.520067Z","time spent":"333.694597ms","remote":"127.0.0.1:34114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T07:44:48.853478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:44:48.521058Z","time spent":"332.377115ms","remote":"127.0.0.1:34114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T07:44:48.853485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:44:48.515755Z","time spent":"337.629063ms","remote":"127.0.0.1:33926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T07:44:48.853951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:44:48.519858Z","time spent":"333.972463ms","remote":"127.0.0.1:34114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T07:44:48.854662Z","caller":"traceutil/trace.go:171","msg":"trace[1099520378] transaction","detail":"{read_only:false; response_revision:3146; number_of_response:1; }","duration":"333.533413ms","start":"2026-04-23T07:44:48.519931Z","end":"2026-04-23T07:44:48.853464Z","steps":["trace[1099520378] 'process raft request' (duration: 333.312057ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:44:48.85511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:44:48.51992Z","time spent":"334.797202ms","remote":"127.0.0.1:34114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T07:45:49.280529Z","caller":"traceutil/trace.go:171","msg":"trace[1508075298] transaction","detail":"{read_only:false; response_revision:3549; number_of_response:1; }","duration":"119.507023ms","start":"2026-04-23T07:45:49.160996Z","end":"2026-04-23T07:45:49.280503Z","steps":["trace[1508075298] 'process raft request' (duration: 119.316249ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:45:49.443467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.376196ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T07:45:49.443694Z","caller":"traceutil/trace.go:171","msg":"trace[1577477457] transaction","detail":"{read_only:false; response_revision:3550; number_of_response:1; }","duration":"158.599045ms","start":"2026-04-23T07:45:49.285073Z","end":"2026-04-23T07:45:49.443673Z","steps":["trace[1577477457] 'process raft request' (duration: 44.84231ms)","trace[1577477457] 'compare' (duration: 113.179332ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:45:49.44394Z","caller":"traceutil/trace.go:171","msg":"trace[624709858] linearizableReadLoop","detail":"{readStateIndex:3688; appliedIndex:3682; }","duration":"104.76674ms","start":"2026-04-23T07:45:49.339151Z","end":"2026-04-23T07:45:49.443918Z","steps":["trace[624709858] 'read index received' (duration: 103.974851ms)","trace[624709858] 'applied index is now lower than readState.Index' (duration: 790.589µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:45:49.443985Z","caller":"traceutil/trace.go:171","msg":"trace[187754331] transaction","detail":"{read_only:false; response_revision:3552; number_of_response:1; }","duration":"156.931566ms","start":"2026-04-23T07:45:49.287047Z","end":"2026-04-23T07:45:49.443979Z","steps":["trace[187754331] 'process raft request' (duration: 156.66381ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:45:49.444097Z","caller":"traceutil/trace.go:171","msg":"trace[1824994904] transaction","detail":"{read_only:false; response_revision:3551; number_of_response:1; }","duration":"157.12357ms","start":"2026-04-23T07:45:49.286968Z","end":"2026-04-23T07:45:49.444092Z","steps":["trace[1824994904] 'process raft request' (duration: 156.633529ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:45:49.444122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.954435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-23T07:45:49.444163Z","caller":"traceutil/trace.go:171","msg":"trace[1728534850] transaction","detail":"{read_only:false; response_revision:3553; number_of_response:1; }","duration":"156.984117ms","start":"2026-04-23T07:45:49.287172Z","end":"2026-04-23T07:45:49.444156Z","steps":["trace[1728534850] 'process raft request' (duration: 156.593877ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:45:49.444172Z","caller":"traceutil/trace.go:171","msg":"trace[581064664] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:3555; }","duration":"105.034077ms","start":"2026-04-23T07:45:49.339122Z","end":"2026-04-23T07:45:49.444156Z","steps":["trace[581064664] 'agreement among raft nodes before linearized reading' (duration: 104.903224ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:45:49.444212Z","caller":"traceutil/trace.go:171","msg":"trace[2052823248] transaction","detail":"{read_only:false; response_revision:3555; number_of_response:1; }","duration":"156.914535ms","start":"2026-04-23T07:45:49.287292Z","end":"2026-04-23T07:45:49.444207Z","steps":["trace[2052823248] 'process raft request' (duration: 156.578657ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:45:49.444144Z","caller":"traceutil/trace.go:171","msg":"trace[1905530120] transaction","detail":"{read_only:false; response_revision:3554; number_of_response:1; }","duration":"156.853863ms","start":"2026-04-23T07:45:49.287286Z","end":"2026-04-23T07:45:49.44414Z","steps":["trace[1905530120] 'process raft request' (duration: 156.532106ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:45:49.755549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.954886ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-23T07:45:49.755653Z","caller":"traceutil/trace.go:171","msg":"trace[1391989613] linearizableReadLoop","detail":"{readStateIndex:3689; appliedIndex:3688; }","duration":"193.817606ms","start":"2026-04-23T07:45:49.561817Z","end":"2026-04-23T07:45:49.755635Z","steps":["trace[1391989613] 'read index received' (duration: 36.748917ms)","trace[1391989613] 'applied index is now lower than readState.Index' (duration: 157.067448ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:45:49.755785Z","caller":"traceutil/trace.go:171","msg":"trace[657050131] transaction","detail":"{read_only:false; response_revision:3556; number_of_response:1; }","duration":"214.268873ms","start":"2026-04-23T07:45:49.541474Z","end":"2026-04-23T07:45:49.755743Z","steps":["trace[657050131] 'process raft request' (duration: 57.021451ms)","trace[657050131] 'compare' (duration: 156.667288ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T07:45:49.755996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.160054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-23T07:45:49.756194Z","caller":"traceutil/trace.go:171","msg":"trace[924651047] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:3556; }","duration":"194.384399ms","start":"2026-04-23T07:45:49.561793Z","end":"2026-04-23T07:45:49.756177Z","steps":["trace[924651047] 'agreement among raft nodes before linearized reading' (duration: 194.027671ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:45:49.975451Z","caller":"traceutil/trace.go:171","msg":"trace[319127821] transaction","detail":"{read_only:false; response_revision:3558; number_of_response:1; }","duration":"170.002088ms","start":"2026-04-23T07:45:49.805424Z","end":"2026-04-23T07:45:49.975426Z","steps":["trace[319127821] 'process raft request' (duration: 121.732036ms)","trace[319127821] 'compare' (duration: 48.129849ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:46:03.667707Z","caller":"traceutil/trace.go:171","msg":"trace[191421366] transaction","detail":"{read_only:false; response_revision:3629; number_of_response:1; }","duration":"129.151875ms","start":"2026-04-23T07:46:03.538526Z","end":"2026-04-23T07:46:03.667678Z","steps":["trace[191421366] 'process raft request' (duration: 110.513351ms)","trace[191421366] 'compare' (duration: 18.417678ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:46:03.667769Z","caller":"traceutil/trace.go:171","msg":"trace[1847767661] transaction","detail":"{read_only:false; response_revision:3632; number_of_response:1; }","duration":"127.803452ms","start":"2026-04-23T07:46:03.539942Z","end":"2026-04-23T07:46:03.667745Z","steps":["trace[1847767661] 'process raft request' (duration: 127.759441ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:03.667973Z","caller":"traceutil/trace.go:171","msg":"trace[1965543350] transaction","detail":"{read_only:false; response_revision:3630; number_of_response:1; }","duration":"128.997851ms","start":"2026-04-23T07:46:03.538956Z","end":"2026-04-23T07:46:03.667954Z","steps":["trace[1965543350] 'process raft request' (duration: 128.658433ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:03.668039Z","caller":"traceutil/trace.go:171","msg":"trace[2125640352] transaction","detail":"{read_only:false; response_revision:3631; number_of_response:1; }","duration":"128.618462ms","start":"2026-04-23T07:46:03.539408Z","end":"2026-04-23T07:46:03.668026Z","steps":["trace[2125640352] 'process raft request' (duration: 128.257763ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:13.907565Z","caller":"traceutil/trace.go:171","msg":"trace[1098015463] linearizableReadLoop","detail":"{readStateIndex:3822; appliedIndex:3821; }","duration":"101.316688ms","start":"2026-04-23T07:46:13.806212Z","end":"2026-04-23T07:46:13.907529Z","steps":["trace[1098015463] 'read index received' (duration: 14.235278ms)","trace[1098015463] 'applied index is now lower than readState.Index' (duration: 87.07778ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T07:46:13.997349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.794543ms","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-04-23T07:46:13.997439Z","caller":"traceutil/trace.go:171","msg":"trace[661806623] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3685; }","duration":"189.928046ms","start":"2026-04-23T07:46:13.807489Z","end":"2026-04-23T07:46:13.997417Z","steps":["trace[661806623] 'agreement among raft nodes before linearized reading' (duration: 100.601871ms)","trace[661806623] 'range keys from in-memory index tree' (duration: 89.062989ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T07:46:13.997487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.250107ms","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-04-23T07:46:13.997562Z","caller":"traceutil/trace.go:171","msg":"trace[1710651459] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3685; }","duration":"191.359999ms","start":"2026-04-23T07:46:13.806177Z","end":"2026-04-23T07:46:13.997537Z","steps":["trace[1710651459] 'agreement among raft nodes before linearized reading' (duration: 101.81019ms)","trace[1710651459] 'range keys from in-memory index tree' (duration: 89.308394ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T07:46:13.998067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.284238ms","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-04-23T07:46:13.998114Z","caller":"traceutil/trace.go:171","msg":"trace[463659004] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3685; }","duration":"191.35507ms","start":"2026-04-23T07:46:13.806744Z","end":"2026-04-23T07:46:13.998099Z","steps":["trace[463659004] 'agreement among raft nodes before linearized reading' (duration: 101.342579ms)","trace[463659004] 'range keys from in-memory index tree' (duration: 89.856577ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:46:14.212689Z","caller":"traceutil/trace.go:171","msg":"trace[143745638] transaction","detail":"{read_only:false; response_revision:3689; number_of_response:1; }","duration":"122.643345ms","start":"2026-04-23T07:46:14.090006Z","end":"2026-04-23T07:46:14.21265Z","steps":["trace[143745638] 'process raft request' (duration: 36.812895ms)","trace[143745638] 'compare' (duration: 85.645336ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:46:23.08587Z","caller":"traceutil/trace.go:171","msg":"trace[25370070] linearizableReadLoop","detail":"{readStateIndex:3873; appliedIndex:3872; }","duration":"108.956717ms","start":"2026-04-23T07:46:22.976881Z","end":"2026-04-23T07:46:23.085838Z","steps":["trace[25370070] 'read index received' (duration: 108.753933ms)","trace[25370070] 'applied index is now lower than readState.Index' (duration: 201.264µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:46:23.085928Z","caller":"traceutil/trace.go:171","msg":"trace[2079460507] transaction","detail":"{read_only:false; response_revision:3734; number_of_response:1; }","duration":"119.142979ms","start":"2026-04-23T07:46:22.966756Z","end":"2026-04-23T07:46:23.085899Z","steps":["trace[2079460507] 'process raft request' (duration: 118.862482ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:23.086054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.133891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T07:46:23.086114Z","caller":"traceutil/trace.go:171","msg":"trace[1859721873] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:3734; }","duration":"109.270474ms","start":"2026-04-23T07:46:22.976827Z","end":"2026-04-23T07:46:23.086098Z","steps":["trace[1859721873] 'agreement among raft nodes before linearized reading' (duration: 109.133191ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:23.833064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.472723ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T07:46:23.833192Z","caller":"traceutil/trace.go:171","msg":"trace[618311475] linearizableReadLoop","detail":"{readStateIndex:3876; appliedIndex:3875; }","duration":"176.081939ms","start":"2026-04-23T07:46:23.657088Z","end":"2026-04-23T07:46:23.83317Z","steps":["trace[618311475] 'read index received' (duration: 59.309328ms)","trace[618311475] 'applied index is now lower than readState.Index' (duration: 116.770781ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:46:23.833295Z","caller":"traceutil/trace.go:171","msg":"trace[1504365082] transaction","detail":"{read_only:false; response_revision:3736; number_of_response:1; }","duration":"217.013812ms","start":"2026-04-23T07:46:23.616237Z","end":"2026-04-23T07:46:23.833251Z","steps":["trace[1504365082] 'process raft request' (duration: 100.239691ms)","trace[1504365082] 'compare' (duration: 116.32947ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T07:46:23.833383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.297924ms","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-04-23T07:46:23.833596Z","caller":"traceutil/trace.go:171","msg":"trace[1451134342] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3736; }","duration":"176.52208ms","start":"2026-04-23T07:46:23.657049Z","end":"2026-04-23T07:46:23.833571Z","steps":["trace[1451134342] 'agreement among raft nodes before linearized reading' (duration: 176.226702ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:23.833709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.917495ms","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-04-23T07:46:23.833946Z","caller":"traceutil/trace.go:171","msg":"trace[231837167] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3736; }","duration":"165.078658ms","start":"2026-04-23T07:46:23.668735Z","end":"2026-04-23T07:46:23.833814Z","steps":["trace[231837167] 'agreement among raft nodes before linearized reading' (duration: 164.635007ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:38.309146Z","caller":"traceutil/trace.go:171","msg":"trace[221784374] linearizableReadLoop","detail":"{readStateIndex:4024; appliedIndex:4023; }","duration":"102.019977ms","start":"2026-04-23T07:46:38.207101Z","end":"2026-04-23T07:46:38.309121Z","steps":["trace[221784374] 'read index received' (duration: 101.74187ms)","trace[221784374] 'applied index is now lower than readState.Index' (duration: 277.257µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:46:38.309202Z","caller":"traceutil/trace.go:171","msg":"trace[671881564] transaction","detail":"{read_only:false; response_revision:3879; number_of_response:1; }","duration":"102.306263ms","start":"2026-04-23T07:46:38.206869Z","end":"2026-04-23T07:46:38.309176Z","steps":["trace[671881564] 'process raft request' (duration: 102.060377ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:38.30941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.318694ms","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-04-23T07:46:38.309455Z","caller":"traceutil/trace.go:171","msg":"trace[896637287] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3879; }","duration":"102.420296ms","start":"2026-04-23T07:46:38.207023Z","end":"2026-04-23T07:46:38.309444Z","steps":["trace[896637287] 'agreement among raft nodes before linearized reading' (duration: 102.235902ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:38.944889Z","caller":"traceutil/trace.go:171","msg":"trace[962414328] transaction","detail":"{read_only:false; response_revision:3884; number_of_response:1; }","duration":"101.013973ms","start":"2026-04-23T07:46:38.843853Z","end":"2026-04-23T07:46:38.944867Z","steps":["trace[962414328] 'process raft request' (duration: 100.88336ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:39.191957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.335222ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T07:46:39.192401Z","caller":"traceutil/trace.go:171","msg":"trace[42424065] linearizableReadLoop","detail":"{readStateIndex:4031; appliedIndex:4030; }","duration":"101.909454ms","start":"2026-04-23T07:46:39.09047Z","end":"2026-04-23T07:46:39.192379Z","steps":["trace[42424065] 'read index received' (duration: 37.151µs)","trace[42424065] 'applied index is now lower than readState.Index' (duration: 101.870833ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:46:39.192389Z","caller":"traceutil/trace.go:171","msg":"trace[765889343] transaction","detail":"{read_only:false; response_revision:3885; number_of_response:1; }","duration":"276.069969ms","start":"2026-04-23T07:46:38.91622Z","end":"2026-04-23T07:46:39.19229Z","steps":["trace[765889343] 'process raft request' (duration: 133.320397ms)","trace[765889343] 'compare' (duration: 142.24455ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T07:46:39.19264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.133279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/kube-prometheus-stack-dashboard-hosts-overview\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T07:46:39.192703Z","caller":"traceutil/trace.go:171","msg":"trace[756144077] range","detail":"{range_begin:/registry/configmaps/monitoring/kube-prometheus-stack-dashboard-hosts-overview; range_end:; response_count:0; response_revision:3886; }","duration":"102.270143ms","start":"2026-04-23T07:46:39.090418Z","end":"2026-04-23T07:46:39.192688Z","steps":["trace[756144077] 'agreement among raft nodes before linearized reading' (duration: 102.061848ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:39.333746Z","caller":"traceutil/trace.go:171","msg":"trace[2045877782] transaction","detail":"{read_only:false; response_revision:3887; number_of_response:1; }","duration":"133.110963ms","start":"2026-04-23T07:46:39.200609Z","end":"2026-04-23T07:46:39.33372Z","steps":["trace[2045877782] 'process raft request' (duration: 123.69359ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:42.079891Z","caller":"traceutil/trace.go:171","msg":"trace[1929703756] linearizableReadLoop","detail":"{readStateIndex:4048; appliedIndex:4047; }","duration":"136.682877ms","start":"2026-04-23T07:46:41.943182Z","end":"2026-04-23T07:46:42.079865Z","steps":["trace[1929703756] 'read index received' (duration: 136.40976ms)","trace[1929703756] 'applied index is now lower than readState.Index' (duration: 271.617µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:46:42.079928Z","caller":"traceutil/trace.go:171","msg":"trace[1206123684] transaction","detail":"{read_only:false; response_revision:3902; number_of_response:1; }","duration":"155.05421ms","start":"2026-04-23T07:46:41.924841Z","end":"2026-04-23T07:46:42.079895Z","steps":["trace[1206123684] 'process raft request' (duration: 154.832395ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:42.080154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.994724ms","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-04-23T07:46:42.080303Z","caller":"traceutil/trace.go:171","msg":"trace[616931028] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3902; }","duration":"137.184488ms","start":"2026-04-23T07:46:41.943091Z","end":"2026-04-23T07:46:42.080276Z","steps":["trace[616931028] 'agreement among raft nodes before linearized reading' (duration: 136.869331ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:42.080482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.580392ms","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-04-23T07:46:42.080548Z","caller":"traceutil/trace.go:171","msg":"trace[1463048790] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3902; }","duration":"127.697944ms","start":"2026-04-23T07:46:41.952838Z","end":"2026-04-23T07:46:42.080536Z","steps":["trace[1463048790] 'agreement among raft nodes before linearized reading' (duration: 127.219942ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:52.531628Z","caller":"traceutil/trace.go:171","msg":"trace[1681967506] transaction","detail":"{read_only:false; response_revision:3961; number_of_response:1; }","duration":"115.908821ms","start":"2026-04-23T07:46:52.415689Z","end":"2026-04-23T07:46:52.531598Z","steps":["trace[1681967506] 'process raft request' (duration: 91.674218ms)","trace[1681967506] 'compare' (duration: 24.037609ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:46:52.531753Z","caller":"traceutil/trace.go:171","msg":"trace[2111184294] transaction","detail":"{read_only:false; response_revision:3962; number_of_response:1; }","duration":"115.071081ms","start":"2026-04-23T07:46:52.416654Z","end":"2026-04-23T07:46:52.531725Z","steps":["trace[2111184294] 'process raft request' (duration: 114.897737ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:54.065224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.888367ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-23T07:46:54.065328Z","caller":"traceutil/trace.go:171","msg":"trace[387014481] linearizableReadLoop","detail":"{readStateIndex:4124; appliedIndex:4123; }","duration":"214.580435ms","start":"2026-04-23T07:46:53.850703Z","end":"2026-04-23T07:46:54.065283Z","steps":["trace[387014481] 'read index received' (duration: 25.703158ms)","trace[387014481] 'applied index is now lower than readState.Index' (duration: 188.876317ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T07:46:54.065377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.668797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T07:46:54.065395Z","caller":"traceutil/trace.go:171","msg":"trace[836714039] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3965; }","duration":"214.724608ms","start":"2026-04-23T07:46:53.850662Z","end":"2026-04-23T07:46:54.065387Z","steps":["trace[836714039] 'agreement among raft nodes before linearized reading' (duration: 214.682887ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:54.074165Z","caller":"traceutil/trace.go:171","msg":"trace[1477837868] transaction","detail":"{read_only:false; response_revision:3966; number_of_response:1; }","duration":"116.241238ms","start":"2026-04-23T07:46:53.957912Z","end":"2026-04-23T07:46:54.074153Z","steps":["trace[1477837868] 'process raft request' (duration: 116.107345ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:54.469757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.166692ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T07:46:54.469837Z","caller":"traceutil/trace.go:171","msg":"trace[1647311415] transaction","detail":"{read_only:false; response_revision:3969; number_of_response:1; }","duration":"158.570349ms","start":"2026-04-23T07:46:54.311253Z","end":"2026-04-23T07:46:54.469823Z","steps":["trace[1647311415] 'process raft request' (duration: 54.271803ms)","trace[1647311415] 'compare' (duration: 104.06176ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:46:54.706755Z","caller":"traceutil/trace.go:171","msg":"trace[2008664944] transaction","detail":"{read_only:false; response_revision:3971; number_of_response:1; }","duration":"168.990877ms","start":"2026-04-23T07:46:54.537737Z","end":"2026-04-23T07:46:54.706728Z","steps":["trace[2008664944] 'process raft request' (duration: 168.679779ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:54.732935Z","caller":"traceutil/trace.go:171","msg":"trace[477201453] transaction","detail":"{read_only:false; response_revision:3972; number_of_response:1; }","duration":"194.836028ms","start":"2026-04-23T07:46:54.538078Z","end":"2026-04-23T07:46:54.732914Z","steps":["trace[477201453] 'process raft request' (duration: 194.732485ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:56.491746Z","caller":"traceutil/trace.go:171","msg":"trace[1539339835] linearizableReadLoop","detail":"{readStateIndex:4136; appliedIndex:4135; }","duration":"180.670741ms","start":"2026-04-23T07:46:56.311052Z","end":"2026-04-23T07:46:56.491722Z","steps":["trace[1539339835] 'read index received' (duration: 180.553408ms)","trace[1539339835] 'applied index is now lower than readState.Index' (duration: 116.343µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:46:56.491887Z","caller":"traceutil/trace.go:171","msg":"trace[524990053] transaction","detail":"{read_only:false; response_revision:3977; number_of_response:1; }","duration":"262.347372ms","start":"2026-04-23T07:46:56.229494Z","end":"2026-04-23T07:46:56.491841Z","steps":["trace[524990053] 'process raft request' (duration: 262.082556ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:56.492021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.958348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-04-23T07:46:56.492064Z","caller":"traceutil/trace.go:171","msg":"trace[359873578] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:3977; }","duration":"181.04425ms","start":"2026-04-23T07:46:56.311005Z","end":"2026-04-23T07:46:56.49205Z","steps":["trace[359873578] 'agreement among raft nodes before linearized reading' (duration: 180.866556ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:47:11.692513Z","caller":"traceutil/trace.go:171","msg":"trace[124285928] transaction","detail":"{read_only:false; response_revision:4396; number_of_response:1; }","duration":"126.747262ms","start":"2026-04-23T07:47:11.565741Z","end":"2026-04-23T07:47:11.692489Z","steps":["trace[124285928] 'process raft request' (duration: 125.990464ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:47:17.001329Z","caller":"traceutil/trace.go:171","msg":"trace[257992603] transaction","detail":"{read_only:false; response_revision:4529; number_of_response:1; }","duration":"121.000825ms","start":"2026-04-23T07:47:16.880303Z","end":"2026-04-23T07:47:17.001304Z","steps":["trace[257992603] 'process raft request' (duration: 120.667477ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:47:17.004073Z","caller":"traceutil/trace.go:171","msg":"trace[263184792] linearizableReadLoop","detail":"{readStateIndex:4701; appliedIndex:4699; }","duration":"106.761319ms","start":"2026-04-23T07:47:16.897297Z","end":"2026-04-23T07:47:17.004058Z","steps":["trace[263184792] 'read index received' (duration: 103.975763ms)","trace[263184792] 'applied index is now lower than readState.Index' (duration: 2.784656ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:47:17.00434Z","caller":"traceutil/trace.go:171","msg":"trace[937255392] transaction","detail":"{read_only:false; response_revision:4530; number_of_response:1; }","duration":"122.340647ms","start":"2026-04-23T07:47:16.881968Z","end":"2026-04-23T07:47:17.004309Z","steps":["trace[937255392] 'process raft request' (duration: 122.002129ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:47:17.004611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.292921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:897"} {"level":"info","ts":"2026-04-23T07:47:17.004894Z","caller":"traceutil/trace.go:171","msg":"trace[1818717290] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:4530; }","duration":"107.602238ms","start":"2026-04-23T07:47:16.897269Z","end":"2026-04-23T07:47:17.004871Z","steps":["trace[1818717290] 'agreement among raft nodes before linearized reading' (duration: 107.040625ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:47:17.990118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.68794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T07:47:17.990176Z","caller":"traceutil/trace.go:171","msg":"trace[1166640019] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4566; }","duration":"182.782942ms","start":"2026-04-23T07:47:17.80738Z","end":"2026-04-23T07:47:17.990163Z","steps":["trace[1166640019] 'range keys from in-memory index tree' (duration: 182.645689ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:47:17.991143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.180746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2348486"} {"level":"info","ts":"2026-04-23T07:47:17.991176Z","caller":"traceutil/trace.go:171","msg":"trace[240965597] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4566; }","duration":"173.229517ms","start":"2026-04-23T07:47:17.817939Z","end":"2026-04-23T07:47:17.991168Z","steps":["trace[240965597] 'range keys from in-memory index tree' (duration: 172.339576ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:47:18.21128Z","caller":"traceutil/trace.go:171","msg":"trace[1254454280] transaction","detail":"{read_only:false; response_revision:4570; number_of_response:1; }","duration":"116.57658ms","start":"2026-04-23T07:47:18.094644Z","end":"2026-04-23T07:47:18.21122Z","steps":["trace[1254454280] 'process raft request' (duration: 116.109619ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:47:18.524449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.790051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T07:47:18.524512Z","caller":"traceutil/trace.go:171","msg":"trace[2105617184] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:4570; }","duration":"126.910563ms","start":"2026-04-23T07:47:18.397588Z","end":"2026-04-23T07:47:18.524498Z","steps":["trace[2105617184] 'count revisions from in-memory index tree' (duration: 126.692468ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:47:18.524563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.062205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.209\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-23T07:47:18.52462Z","caller":"traceutil/trace.go:171","msg":"trace[1884212670] range","detail":"{range_begin:/registry/masterleases/199.204.45.209; range_end:; response_count:1; response_revision:4570; }","duration":"246.153887ms","start":"2026-04-23T07:47:18.27845Z","end":"2026-04-23T07:47:18.524604Z","steps":["trace[1884212670] 'range keys from in-memory index tree' (duration: 245.882531ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:47:21.4967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.76663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-ccjnt\" ","response":"range_response_count:1 size:9716"} {"level":"info","ts":"2026-04-23T07:47:21.496836Z","caller":"traceutil/trace.go:171","msg":"trace[855224506] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-ccjnt; range_end:; response_count:1; response_revision:4633; }","duration":"492.956165ms","start":"2026-04-23T07:47:21.003834Z","end":"2026-04-23T07:47:21.49679Z","steps":["trace[855224506] 'range keys from in-memory index tree' (duration: 492.582376ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:47:21.496884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:47:21.003817Z","time spent":"493.055607ms","remote":"127.0.0.1:34042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":9739,"request content":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-ccjnt\" "} {"level":"warn","ts":"2026-04-23T07:47:21.497244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.015881ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-23T07:47:21.497917Z","caller":"traceutil/trace.go:171","msg":"trace[1559855133] linearizableReadLoop","detail":"{readStateIndex:4808; appliedIndex:4807; }","duration":"484.981597ms","start":"2026-04-23T07:47:21.01292Z","end":"2026-04-23T07:47:21.497901Z","steps":["trace[1559855133] 'read index received' (duration: 330.179837ms)","trace[1559855133] 'applied index is now lower than readState.Index' (duration: 154.80015ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:47:21.497964Z","caller":"traceutil/trace.go:171","msg":"trace[188060776] transaction","detail":"{read_only:false; response_revision:4634; number_of_response:1; }","duration":"492.095335ms","start":"2026-04-23T07:47:21.005837Z","end":"2026-04-23T07:47:21.497933Z","steps":["trace[188060776] 'process raft request' (duration: 337.310535ms)","trace[188060776] 'compare' (duration: 153.406477ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T07:47:21.498084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:47:21.005826Z","time spent":"492.212377ms","remote":"127.0.0.1:33966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":947,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T07:47:21.498195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.259823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-23T07:47:21.498271Z","caller":"traceutil/trace.go:171","msg":"trace[750110922] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4634; }","duration":"485.373536ms","start":"2026-04-23T07:47:21.012881Z","end":"2026-04-23T07:47:21.498255Z","steps":["trace[750110922] 'agreement among raft nodes before linearized reading' (duration: 485.145421ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:47:21.49832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:47:21.012865Z","time spent":"485.446698ms","remote":"127.0.0.1:34114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-23T07:47:21.503942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.489581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-52b8a028-caa0-4764-824b-079c6605c0e9\" ","response":"range_response_count:1 size:4065"} {"level":"info","ts":"2026-04-23T07:47:21.503998Z","caller":"traceutil/trace.go:171","msg":"trace[1899141162] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-52b8a028-caa0-4764-824b-079c6605c0e9; range_end:; response_count:1; response_revision:4636; }","duration":"282.551672ms","start":"2026-04-23T07:47:21.221433Z","end":"2026-04-23T07:47:21.503984Z","steps":["trace[1899141162] 'agreement among raft nodes before linearized reading' (duration: 282.368538ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:47:25.729186Z","caller":"traceutil/trace.go:171","msg":"trace[100857419] transaction","detail":"{read_only:false; response_revision:4743; number_of_response:1; }","duration":"210.249106ms","start":"2026-04-23T07:47:25.518918Z","end":"2026-04-23T07:47:25.729167Z","steps":["trace[100857419] 'process raft request' (duration: 210.125923ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:47:26.249244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.359993ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T07:47:26.249352Z","caller":"traceutil/trace.go:171","msg":"trace[2110865440] linearizableReadLoop","detail":"{readStateIndex:4920; appliedIndex:4918; }","duration":"721.591589ms","start":"2026-04-23T07:47:25.527743Z","end":"2026-04-23T07:47:26.249335Z","steps":["trace[2110865440] 'read index received' (duration: 201.352576ms)","trace[2110865440] 'applied index is now lower than readState.Index' (duration: 520.237703ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:47:26.249557Z","caller":"traceutil/trace.go:171","msg":"trace[1666415655] transaction","detail":"{read_only:false; response_revision:4744; number_of_response:1; }","duration":"728.616485ms","start":"2026-04-23T07:47:25.520926Z","end":"2026-04-23T07:47:26.249543Z","steps":["trace[1666415655] 'process raft request' (duration: 611.857833ms)","trace[1666415655] 'compare' (duration: 116.238479ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T07:47:26.249622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:47:25.520905Z","time spent":"728.685446ms","remote":"127.0.0.1:34114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T07:47:26.249942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"722.190313ms","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-04-23T07:47:26.250039Z","caller":"traceutil/trace.go:171","msg":"trace[1164020180] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4744; }","duration":"722.304436ms","start":"2026-04-23T07:47:25.527705Z","end":"2026-04-23T07:47:26.25001Z","steps":["trace[1164020180] 'agreement among raft nodes before linearized reading' (duration: 722.134752ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:47:26.250052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.338735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"warn","ts":"2026-04-23T07:47:26.249943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.429253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0654a3eb-50f2-418a-a43c-7700a3442d75\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-04-23T07:47:26.250136Z","caller":"traceutil/trace.go:171","msg":"trace[1853120963] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:4744; }","duration":"386.455438ms","start":"2026-04-23T07:47:25.863663Z","end":"2026-04-23T07:47:26.250118Z","steps":["trace[1853120963] 'agreement among raft nodes before linearized reading' (duration: 386.233233ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:47:26.250028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.887938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T07:47:26.250089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.110171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0654a3eb-50f2-418a-a43c-7700a3442d75\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-04-23T07:47:26.250202Z","caller":"traceutil/trace.go:171","msg":"trace[1955723003] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0654a3eb-50f2-418a-a43c-7700a3442d75; range_end:; response_count:1; response_revision:4744; }","duration":"625.717959ms","start":"2026-04-23T07:47:25.624455Z","end":"2026-04-23T07:47:26.250173Z","steps":["trace[1955723003] 'agreement among raft nodes before linearized reading' (duration: 625.34021ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:47:26.250288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:47:25.52769Z","time spent":"722.577272ms","remote":"127.0.0.1:34114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-23T07:47:26.250294Z","caller":"traceutil/trace.go:171","msg":"trace[931625957] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0654a3eb-50f2-418a-a43c-7700a3442d75; range_end:; response_count:1; response_revision:4744; }","duration":"204.368147ms","start":"2026-04-23T07:47:26.045905Z","end":"2026-04-23T07:47:26.250273Z","steps":["trace[931625957] 'agreement among raft nodes before linearized reading' (duration: 203.933527ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:47:26.250322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:47:25.624436Z","time spent":"625.853532ms","remote":"127.0.0.1:34042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3669,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0654a3eb-50f2-418a-a43c-7700a3442d75\" "} {"level":"warn","ts":"2026-04-23T07:47:26.250188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:47:25.86365Z","time spent":"386.52907ms","remote":"127.0.0.1:34114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":582,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-04-23T07:47:26.250226Z","caller":"traceutil/trace.go:171","msg":"trace[665127208] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4744; }","duration":"443.097563ms","start":"2026-04-23T07:47:25.807089Z","end":"2026-04-23T07:47:26.250186Z","steps":["trace[665127208] 'agreement among raft nodes before linearized reading' (duration: 442.907358ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:47:26.250648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:47:25.807045Z","time spent":"443.587004ms","remote":"127.0.0.1:33870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-23T07:47:26.388099Z","caller":"traceutil/trace.go:171","msg":"trace[357418007] transaction","detail":"{read_only:false; response_revision:4746; number_of_response:1; }","duration":"133.011455ms","start":"2026-04-23T07:47:26.255057Z","end":"2026-04-23T07:47:26.388068Z","steps":["trace[357418007] 'process raft request' (duration: 112.530612ms)","trace[357418007] 'compare' (duration: 20.234017ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:47:26.388402Z","caller":"traceutil/trace.go:171","msg":"trace[948049715] transaction","detail":"{read_only:false; response_revision:4748; number_of_response:1; }","duration":"129.449632ms","start":"2026-04-23T07:47:26.258941Z","end":"2026-04-23T07:47:26.388391Z","steps":["trace[948049715] 'process raft request' (duration: 129.3818ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:47:26.388444Z","caller":"traceutil/trace.go:171","msg":"trace[677850212] transaction","detail":"{read_only:false; response_revision:4747; number_of_response:1; }","duration":"133.043046ms","start":"2026-04-23T07:47:26.255382Z","end":"2026-04-23T07:47:26.388425Z","steps":["trace[677850212] 'process raft request' (duration: 132.635847ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:47:29.195259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.271575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16233"} {"level":"warn","ts":"2026-04-23T07:47:29.195273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.727732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-5f45f7b476-s54f4.18a8ecd3f3fff09d\" ","response":"range_response_count:1 size:898"} {"level":"info","ts":"2026-04-23T07:47:29.195329Z","caller":"traceutil/trace.go:171","msg":"trace[1387366169] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4777; }","duration":"112.381338ms","start":"2026-04-23T07:47:29.082932Z","end":"2026-04-23T07:47:29.195313Z","steps":["trace[1387366169] 'range keys from in-memory index tree' (duration: 112.109751ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:47:29.195344Z","caller":"traceutil/trace.go:171","msg":"trace[2069204817] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-5f45f7b476-s54f4.18a8ecd3f3fff09d; range_end:; response_count:1; response_revision:4777; }","duration":"108.814593ms","start":"2026-04-23T07:47:29.086515Z","end":"2026-04-23T07:47:29.19533Z","steps":["trace[2069204817] 'range keys from in-memory index tree' (duration: 108.595258ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:47:32.843359Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835592345781083341,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-23T07:47:33.027275Z","caller":"traceutil/trace.go:171","msg":"trace[197752678] transaction","detail":"{read_only:false; response_revision:4837; number_of_response:1; }","duration":"759.35462ms","start":"2026-04-23T07:47:32.267891Z","end":"2026-04-23T07:47:33.027245Z","steps":["trace[197752678] 'process raft request' (duration: 759.160196ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:47:33.027595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:47:32.267864Z","time spent":"759.498543ms","remote":"127.0.0.1:34114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T07:47:33.028285Z","caller":"traceutil/trace.go:171","msg":"trace[310352185] linearizableReadLoop","detail":"{readStateIndex:5015; appliedIndex:5013; }","duration":"685.998232ms","start":"2026-04-23T07:47:32.342258Z","end":"2026-04-23T07:47:33.028256Z","steps":["trace[310352185] 'read index received' (duration: 684.838795ms)","trace[310352185] 'applied index is now lower than readState.Index' (duration: 1.158797ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:47:33.02837Z","caller":"traceutil/trace.go:171","msg":"trace[994446713] transaction","detail":"{read_only:false; response_revision:4838; number_of_response:1; }","duration":"386.173378ms","start":"2026-04-23T07:47:32.642186Z","end":"2026-04-23T07:47:33.02836Z","steps":["trace[994446713] 'process raft request' (duration: 386.003224ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:47:33.028439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:47:32.642159Z","time spent":"386.23954ms","remote":"127.0.0.1:34114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T07:47:33.028594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.34473ms","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-04-23T07:47:33.028635Z","caller":"traceutil/trace.go:171","msg":"trace[1636834602] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4838; }","duration":"686.417782ms","start":"2026-04-23T07:47:32.342207Z","end":"2026-04-23T07:47:33.028625Z","steps":["trace[1636834602] 'agreement among raft nodes before linearized reading' (duration: 686.309839ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:47:33.028665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:47:32.342189Z","time spent":"686.469242ms","remote":"127.0.0.1:34114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-23T07:47:33.028839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.089117ms","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-04-23T07:47:33.028906Z","caller":"traceutil/trace.go:171","msg":"trace[1297872253] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4838; }","duration":"600.18601ms","start":"2026-04-23T07:47:32.428698Z","end":"2026-04-23T07:47:33.028884Z","steps":["trace[1297872253] 'agreement among raft nodes before linearized reading' (duration: 599.900033ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:47:33.028948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:47:32.428681Z","time spent":"600.258572ms","remote":"127.0.0.1:34114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-23T07:47:33.029112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.664806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T07:47:33.029169Z","caller":"traceutil/trace.go:171","msg":"trace[1069422709] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4838; }","duration":"222.763479ms","start":"2026-04-23T07:47:32.806396Z","end":"2026-04-23T07:47:33.029159Z","steps":["trace[1069422709] 'agreement among raft nodes before linearized reading' (duration: 222.690958ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:47:33.029399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.317457ms","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":"warn","ts":"2026-04-23T07:47:33.029417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.489245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-04-23T07:47:33.029437Z","caller":"traceutil/trace.go:171","msg":"trace[1405564612] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4838; }","duration":"289.393319ms","start":"2026-04-23T07:47:32.740032Z","end":"2026-04-23T07:47:33.029425Z","steps":["trace[1405564612] 'agreement among raft nodes before linearized reading' (duration: 289.274206ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:47:33.029452Z","caller":"traceutil/trace.go:171","msg":"trace[160110647] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4838; }","duration":"490.558287ms","start":"2026-04-23T07:47:32.538883Z","end":"2026-04-23T07:47:33.029441Z","steps":["trace[160110647] 'agreement among raft nodes before linearized reading' (duration: 490.467935ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:47:33.029483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:47:32.538866Z","time spent":"490.609059ms","remote":"127.0.0.1:34042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-23T07:47:33.02956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.775542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T07:47:33.029599Z","caller":"traceutil/trace.go:171","msg":"trace[750369408] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:4838; }","duration":"566.817553ms","start":"2026-04-23T07:47:32.46277Z","end":"2026-04-23T07:47:33.029588Z","steps":["trace[750369408] 'agreement among raft nodes before linearized reading' (duration: 566.755132ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:47:33.029622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.493924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T07:47:33.029637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:47:32.462744Z","time spent":"566.880955ms","remote":"127.0.0.1:34102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"info","ts":"2026-04-23T07:47:33.029653Z","caller":"traceutil/trace.go:171","msg":"trace[941782597] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:4838; }","duration":"464.546275ms","start":"2026-04-23T07:47:32.565098Z","end":"2026-04-23T07:47:33.029645Z","steps":["trace[941782597] 'agreement among raft nodes before linearized reading' (duration: 464.498264ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:47:33.029702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:47:32.565088Z","time spent":"464.583765ms","remote":"127.0.0.1:34088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-04-23T07:47:39.068673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.263256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-23T07:47:39.068751Z","caller":"traceutil/trace.go:171","msg":"trace[671254416] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:4903; }","duration":"513.354188ms","start":"2026-04-23T07:47:38.555382Z","end":"2026-04-23T07:47:39.068736Z","steps":["trace[671254416] 'count revisions from in-memory index tree' (duration: 513.165714ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:47:39.068789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:47:38.555313Z","time spent":"513.466361ms","remote":"127.0.0.1:34156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":30,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-23T07:47:43.733197Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835592345781083618,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T07:47:44.233712Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835592345781083618,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T07:47:44.734423Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835592345781083618,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T07:47:44.896212Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.566680702s","expected-duration":"1s"} {"level":"info","ts":"2026-04-23T07:47:44.896741Z","caller":"traceutil/trace.go:171","msg":"trace[2082590989] transaction","detail":"{read_only:false; response_revision:4943; number_of_response:1; }","duration":"1.69240093s","start":"2026-04-23T07:47:43.204315Z","end":"2026-04-23T07:47:44.896716Z","steps":["trace[2082590989] 'process raft request' (duration: 1.692237038s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:47:44.896974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:47:43.204287Z","time spent":"1.692519464s","remote":"127.0.0.1:34042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10667,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T07:47:45.012129Z","caller":"traceutil/trace.go:171","msg":"trace[1115920917] transaction","detail":"{read_only:false; response_revision:4944; number_of_response:1; }","duration":"1.786227978s","start":"2026-04-23T07:47:43.225871Z","end":"2026-04-23T07:47:45.012099Z","steps":["trace[1115920917] 'process raft request' (duration: 1.734998443s)","trace[1115920917] 'compare' (duration: 51.067681ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T07:47:45.012308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:47:43.225859Z","time spent":"1.786330591s","remote":"127.0.0.1:34114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T07:47:45.175901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.842017ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-23T07:47:45.176289Z","caller":"traceutil/trace.go:171","msg":"trace[41879307] linearizableReadLoop","detail":"{readStateIndex:5128; appliedIndex:5124; }","duration":"1.944203928s","start":"2026-04-23T07:47:43.232056Z","end":"2026-04-23T07:47:45.17626Z","steps":["trace[41879307] 'read index received' (duration: 1.664327251s)","trace[41879307] 'applied index is now lower than readState.Index' (duration: 279.875167ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:47:45.176553Z","caller":"traceutil/trace.go:171","msg":"trace[134363999] transaction","detail":"{read_only:false; response_revision:4945; number_of_response:1; }","duration":"1.387173017s","start":"2026-04-23T07:47:43.789231Z","end":"2026-04-23T07:47:45.176404Z","steps":["trace[134363999] 'process raft request' (duration: 1.386763787s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:47:45.17674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:47:43.789201Z","time spent":"1.387419832s","remote":"127.0.0.1:34114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T07:47:45.274544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.04246297s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T07:47:45.274647Z","caller":"traceutil/trace.go:171","msg":"trace[1988470617] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagerconfigs/; range_end:/registry/monitoring.coreos.com/alertmanagerconfigs0; response_count:0; response_revision:4945; }","duration":"2.042571392s","start":"2026-04-23T07:47:43.232027Z","end":"2026-04-23T07:47:45.274599Z","steps":["trace[1988470617] 'agreement among raft nodes before linearized reading' (duration: 1.944469023s)","trace[1988470617] 'count revisions from in-memory index tree' (duration: 97.993175ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T07:47:45.274704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:47:43.232016Z","time spent":"2.042675674s","remote":"127.0.0.1:33328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true "} {"level":"info","ts":"2026-04-23T07:47:45.274962Z","caller":"traceutil/trace.go:171","msg":"trace[1666522511] transaction","detail":"{read_only:false; response_revision:4946; number_of_response:1; }","duration":"372.509637ms","start":"2026-04-23T07:47:44.902412Z","end":"2026-04-23T07:47:45.274922Z","steps":["trace[1666522511] 'process raft request' (duration: 273.840735ms)","trace[1666522511] 'compare' (duration: 98.029447ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:47:45.275123Z","caller":"traceutil/trace.go:171","msg":"trace[331200152] transaction","detail":"{read_only:false; response_revision:4947; number_of_response:1; }","duration":"372.445606ms","start":"2026-04-23T07:47:44.902659Z","end":"2026-04-23T07:47:45.275105Z","steps":["trace[331200152] 'process raft request' (duration: 372.158539ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:47:45.27516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:47:44.902373Z","time spent":"372.669861ms","remote":"127.0.0.1:34130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1505,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T07:47:45.275291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:47:44.902636Z","time spent":"372.561789ms","remote":"127.0.0.1:34038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":892,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T07:47:45.275321Z","caller":"traceutil/trace.go:171","msg":"trace[20008064] transaction","detail":"{read_only:false; response_revision:4948; number_of_response:1; }","duration":"371.232037ms","start":"2026-04-23T07:47:44.904073Z","end":"2026-04-23T07:47:45.275305Z","steps":["trace[20008064] 'process raft request' (duration: 370.9351ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:47:45.275482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:47:44.904057Z","time spent":"371.37565ms","remote":"127.0.0.1:34038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":950,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T07:47:45.275522Z","caller":"traceutil/trace.go:171","msg":"trace[1235818133] transaction","detail":"{read_only:false; response_revision:4949; number_of_response:1; }","duration":"371.426712ms","start":"2026-04-23T07:47:44.904082Z","end":"2026-04-23T07:47:45.275509Z","steps":["trace[1235818133] 'process raft request' (duration: 371.159886ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:47:45.275606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:47:44.904057Z","time spent":"371.510633ms","remote":"127.0.0.1:34130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1428,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T07:47:45.275901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.047824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-23T07:47:45.275949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.080866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-23T07:47:45.275958Z","caller":"traceutil/trace.go:171","msg":"trace[1827832762] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4950; }","duration":"165.149196ms","start":"2026-04-23T07:47:45.110794Z","end":"2026-04-23T07:47:45.275943Z","steps":["trace[1827832762] 'agreement among raft nodes before linearized reading' (duration: 164.980322ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:47:45.275987Z","caller":"traceutil/trace.go:171","msg":"trace[1465372441] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4950; }","duration":"154.142107ms","start":"2026-04-23T07:47:45.121834Z","end":"2026-04-23T07:47:45.275976Z","steps":["trace[1465372441] 'agreement among raft nodes before linearized reading' (duration: 153.993244ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:47:45.276092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.032124237s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T07:47:45.276145Z","caller":"traceutil/trace.go:171","msg":"trace[218334041] transaction","detail":"{read_only:false; response_revision:4950; number_of_response:1; }","duration":"181.604044ms","start":"2026-04-23T07:47:45.094528Z","end":"2026-04-23T07:47:45.276132Z","steps":["trace[218334041] 'process raft request' (duration: 180.905687ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:47:45.276151Z","caller":"traceutil/trace.go:171","msg":"trace[272451640] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4950; }","duration":"2.032233049s","start":"2026-04-23T07:47:43.243906Z","end":"2026-04-23T07:47:45.276139Z","steps":["trace[272451640] 'agreement among raft nodes before linearized reading' (duration: 2.031699946s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:47:45.276237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:47:43.2439Z","time spent":"2.032327571s","remote":"127.0.0.1:34328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-04-23T07:47:45.276426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.420178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-04-23T07:47:45.276455Z","caller":"traceutil/trace.go:171","msg":"trace[1056950492] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4950; }","duration":"175.47449ms","start":"2026-04-23T07:47:45.100974Z","end":"2026-04-23T07:47:45.276448Z","steps":["trace[1056950492] 'agreement among raft nodes before linearized reading' (duration: 175.191984ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:47:45.276485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"941.904218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-04-23T07:47:45.276524Z","caller":"traceutil/trace.go:171","msg":"trace[1052765620] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:4950; }","duration":"942.00533ms","start":"2026-04-23T07:47:44.33451Z","end":"2026-04-23T07:47:45.276516Z","steps":["trace[1052765620] 'agreement among raft nodes before linearized reading' (duration: 941.911598ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:47:45.27656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:47:44.334483Z","time spent":"942.067632ms","remote":"127.0.0.1:34114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":553,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-04-23T07:47:45.276646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.191806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T07:47:45.276674Z","caller":"traceutil/trace.go:171","msg":"trace[302387314] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4950; }","duration":"470.251537ms","start":"2026-04-23T07:47:44.806415Z","end":"2026-04-23T07:47:45.276667Z","steps":["trace[302387314] 'agreement among raft nodes before linearized reading' (duration: 470.208386ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:47:45.276697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:47:44.806392Z","time spent":"470.299669ms","remote":"127.0.0.1:33870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T07:47:45.276865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.23681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-23T07:47:45.276906Z","caller":"traceutil/trace.go:171","msg":"trace[1957943443] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4950; }","duration":"612.335493ms","start":"2026-04-23T07:47:44.66456Z","end":"2026-04-23T07:47:45.276896Z","steps":["trace[1957943443] 'agreement among raft nodes before linearized reading' (duration: 612.272671ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:47:45.276934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:47:44.664528Z","time spent":"612.398743ms","remote":"127.0.0.1:48176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-04-23T07:47:45.277106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.006059198s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-23T07:47:45.277484Z","caller":"traceutil/trace.go:171","msg":"trace[1853886909] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:4950; }","duration":"1.006483508s","start":"2026-04-23T07:47:44.270991Z","end":"2026-04-23T07:47:45.277475Z","steps":["trace[1853886909] 'agreement among raft nodes before linearized reading' (duration: 1.005518756s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:47:45.277428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.988081ms","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-04-23T07:47:45.277618Z","caller":"traceutil/trace.go:171","msg":"trace[2141723773] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4950; }","duration":"156.116934ms","start":"2026-04-23T07:47:45.121424Z","end":"2026-04-23T07:47:45.277541Z","steps":["trace[2141723773] 'agreement among raft nodes before linearized reading' (duration: 155.08435ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:47:45.277652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:47:44.270967Z","time spent":"1.00656112s","remote":"127.0.0.1:34186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":26,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-04-23T07:47:45.277111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.661450403s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1986"} {"level":"warn","ts":"2026-04-23T07:47:45.278454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.058014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-23T07:47:45.278496Z","caller":"traceutil/trace.go:171","msg":"trace[1514336650] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4950; }","duration":"165.149927ms","start":"2026-04-23T07:47:45.113334Z","end":"2026-04-23T07:47:45.278484Z","steps":["trace[1514336650] 'agreement among raft nodes before linearized reading' (duration: 164.017881ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:47:45.278491Z","caller":"traceutil/trace.go:171","msg":"trace[1449875910] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:4950; }","duration":"1.662877496s","start":"2026-04-23T07:47:43.615597Z","end":"2026-04-23T07:47:45.278475Z","steps":["trace[1449875910] 'agreement among raft nodes before linearized reading' (duration: 1.661431542s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:47:45.278542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:47:43.615564Z","time spent":"1.662962478s","remote":"127.0.0.1:34050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":2009,"request content":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" "} {"level":"warn","ts":"2026-04-23T07:47:45.277132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"915.324002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3244"} {"level":"info","ts":"2026-04-23T07:47:45.27879Z","caller":"traceutil/trace.go:171","msg":"trace[438171724] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4950; }","duration":"917.019792ms","start":"2026-04-23T07:47:44.361758Z","end":"2026-04-23T07:47:45.278778Z","steps":["trace[438171724] 'agreement among raft nodes before linearized reading' (duration: 915.301682ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:47:45.276448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.897691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-23T07:47:45.279195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:47:44.361735Z","time spent":"917.089324ms","remote":"127.0.0.1:47982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3267,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"info","ts":"2026-04-23T07:47:45.279379Z","caller":"traceutil/trace.go:171","msg":"trace[1114904318] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4950; }","duration":"167.864341ms","start":"2026-04-23T07:47:45.111504Z","end":"2026-04-23T07:47:45.279368Z","steps":["trace[1114904318] 'agreement among raft nodes before linearized reading' (duration: 164.85478ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:47:45.277323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.636521ms","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-04-23T07:47:45.279533Z","caller":"traceutil/trace.go:171","msg":"trace[2059808426] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4950; }","duration":"162.893924ms","start":"2026-04-23T07:47:45.116624Z","end":"2026-04-23T07:47:45.279518Z","steps":["trace[2059808426] 'agreement among raft nodes before linearized reading' (duration: 160.642581ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:47:45.277374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.016400576s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-04-23T07:47:45.279624Z","caller":"traceutil/trace.go:171","msg":"trace[1802229921] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4950; }","duration":"2.018650559s","start":"2026-04-23T07:47:43.260943Z","end":"2026-04-23T07:47:45.279594Z","steps":["trace[1802229921] 'agreement among raft nodes before linearized reading' (duration: 2.016365335s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:47:45.279714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:47:43.260933Z","time spent":"2.018751992s","remote":"127.0.0.1:34036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-04-23T07:47:45.42137Z","caller":"traceutil/trace.go:171","msg":"trace[735330712] transaction","detail":"{read_only:false; response_revision:4961; number_of_response:1; }","duration":"128.316531ms","start":"2026-04-23T07:47:45.293024Z","end":"2026-04-23T07:47:45.421341Z","steps":["trace[735330712] 'process raft request' (duration: 105.659907ms)","trace[735330712] 'compare' (duration: 22.416588ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:47:45.689481Z","caller":"traceutil/trace.go:171","msg":"trace[1049385897] linearizableReadLoop","detail":"{readStateIndex:5145; appliedIndex:5144; }","duration":"257.840668ms","start":"2026-04-23T07:47:45.431612Z","end":"2026-04-23T07:47:45.689452Z","steps":["trace[1049385897] 'read index received' (duration: 244.586706ms)","trace[1049385897] 'applied index is now lower than readState.Index' (duration: 13.252482ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:47:45.689652Z","caller":"traceutil/trace.go:171","msg":"trace[228654472] transaction","detail":"{read_only:false; response_revision:4962; number_of_response:1; }","duration":"264.861253ms","start":"2026-04-23T07:47:45.424767Z","end":"2026-04-23T07:47:45.689628Z","steps":["trace[228654472] 'process raft request' (duration: 251.497138ms)","trace[228654472] 'compare' (duration: 12.761521ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T07:47:45.689965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.335561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5683"} {"level":"info","ts":"2026-04-23T07:47:45.690023Z","caller":"traceutil/trace.go:171","msg":"trace[1161688781] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:4962; }","duration":"258.438062ms","start":"2026-04-23T07:47:45.431572Z","end":"2026-04-23T07:47:45.69001Z","steps":["trace[1161688781] 'agreement among raft nodes before linearized reading' (duration: 257.981692ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:47:47.328941Z","caller":"traceutil/trace.go:171","msg":"trace[631097236] transaction","detail":"{read_only:false; response_revision:4969; number_of_response:1; }","duration":"102.144014ms","start":"2026-04-23T07:47:47.22675Z","end":"2026-04-23T07:47:47.328894Z","steps":["trace[631097236] 'process raft request' (duration: 50.664452ms)","trace[631097236] 'compare' (duration: 51.165964ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:48:55.412471Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2876} {"level":"info","ts":"2026-04-23T07:48:55.481841Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2876,"took":"67.454551ms","hash":3389862792,"current-db-size-bytes":54218752,"current-db-size":"54 MB","current-db-size-in-use-bytes":29356032,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-23T07:48:55.482001Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3389862792,"revision":2876,"compact-revision":-1} {"level":"info","ts":"2026-04-23T07:53:55.417142Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5545} {"level":"info","ts":"2026-04-23T07:53:55.491676Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5545,"took":"73.322071ms","hash":3705460492,"current-db-size-bytes":54218752,"current-db-size":"54 MB","current-db-size-in-use-bytes":18526208,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-23T07:53:55.491746Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3705460492,"revision":5545,"compact-revision":2876}