{"level":"warn","ts":"2026-04-14T04:24:24.242755Z","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-14T04:24:24.242952Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.115: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.115:2380","--initial-cluster=instance=https://199.204.45.115:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.115:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.115: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-14T04:24:24.243075Z","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-14T04:24:24.243094Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.115:2380"]} {"level":"info","ts":"2026-04-14T04:24:24.243126Z","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-14T04:24:24.244203Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"]} {"level":"info","ts":"2026-04-14T04:24:24.244348Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.115:2380"],"listen-peer-urls":["https://199.204.45.115:2380"],"advertise-client-urls":["https://199.204.45.115:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.115: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-14T04:24:24.250181Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.493003ms"} {"level":"info","ts":"2026-04-14T04:24:24.256796Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e3aaa5c123e150e0","cluster-id":"61ec519698823541"} {"level":"info","ts":"2026-04-14T04:24:24.257025Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=()"} {"level":"info","ts":"2026-04-14T04:24:24.257065Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became follower at term 0"} {"level":"info","ts":"2026-04-14T04:24:24.257082Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e3aaa5c123e150e0 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-14T04:24:24.257098Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became follower at term 1"} {"level":"info","ts":"2026-04-14T04:24:24.257151Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=(16405106841599693024)"} {"level":"warn","ts":"2026-04-14T04:24:24.264131Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-14T04:24:24.265728Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-14T04:24:24.26711Z","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-14T04:24:24.269501Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e3aaa5c123e150e0","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-14T04:24:24.269656Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e3aaa5c123e150e0","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-14T04:24:24.269871Z","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-14T04:24:24.270052Z","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-14T04:24:24.270091Z","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-14T04:24:24.271735Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=(16405106841599693024)"} {"level":"info","ts":"2026-04-14T04:24:24.272282Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"61ec519698823541","local-member-id":"e3aaa5c123e150e0","added-peer-id":"e3aaa5c123e150e0","added-peer-peer-urls":["https://199.204.45.115:2380"]} {"level":"info","ts":"2026-04-14T04:24:24.273702Z","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-14T04:24:24.273814Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.115:2380"} {"level":"info","ts":"2026-04-14T04:24:24.273884Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.115:2380"} {"level":"info","ts":"2026-04-14T04:24:24.274153Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e3aaa5c123e150e0","initial-advertise-peer-urls":["https://199.204.45.115:2380"],"listen-peer-urls":["https://199.204.45.115:2380"],"advertise-client-urls":["https://199.204.45.115:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-14T04:24:24.274197Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-14T04:24:25.157687Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 is starting a new election at term 1"} {"level":"info","ts":"2026-04-14T04:24:25.157779Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-14T04:24:25.157807Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 received MsgPreVoteResp from e3aaa5c123e150e0 at term 1"} {"level":"info","ts":"2026-04-14T04:24:25.157828Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became candidate at term 2"} {"level":"info","ts":"2026-04-14T04:24:25.15784Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 received MsgVoteResp from e3aaa5c123e150e0 at term 2"} {"level":"info","ts":"2026-04-14T04:24:25.157856Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became leader at term 2"} {"level":"info","ts":"2026-04-14T04:24:25.15788Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e3aaa5c123e150e0 elected leader e3aaa5c123e150e0 at term 2"} {"level":"info","ts":"2026-04-14T04:24:25.158804Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T04:24:25.159718Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-14T04:24:25.159711Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e3aaa5c123e150e0","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.115:2379]}","request-path":"/0/members/e3aaa5c123e150e0/attributes","cluster-id":"61ec519698823541","publish-timeout":"7s"} {"level":"info","ts":"2026-04-14T04:24:25.159764Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-14T04:24:25.160031Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-14T04:24:25.160102Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-14T04:24:25.160208Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"61ec519698823541","local-member-id":"e3aaa5c123e150e0","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T04:24:25.160392Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T04:24:25.160442Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T04:24:25.16599Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.115:2379"} {"level":"info","ts":"2026-04-14T04:24:25.166122Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-04-14T04:24:42.139034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.46523ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-14T04:24:42.139325Z","caller":"traceutil/trace.go:171","msg":"trace[1051989760] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"342.685049ms","start":"2026-04-14T04:24:41.796612Z","end":"2026-04-14T04:24:42.139297Z","steps":["trace[1051989760] 'process raft request' (duration: 194.357415ms)","trace[1051989760] 'compare' (duration: 147.259074ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:24:42.139387Z","caller":"traceutil/trace.go:171","msg":"trace[1544293434] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"342.586456ms","start":"2026-04-14T04:24:41.796754Z","end":"2026-04-14T04:24:42.139341Z","steps":["trace[1544293434] 'process raft request' (duration: 342.429752ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:24:42.139458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:24:41.796602Z","time spent":"342.788482ms","remote":"127.0.0.1:44066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":687,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T04:24:42.13934Z","caller":"traceutil/trace.go:171","msg":"trace[645954928] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"340.697673ms","start":"2026-04-14T04:24:41.798621Z","end":"2026-04-14T04:24:42.139318Z","steps":["trace[645954928] 'process raft request' (duration: 340.628531ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:24:42.139553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:24:41.796738Z","time spent":"342.73102ms","remote":"127.0.0.1:44158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3563,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T04:24:42.139884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:24:41.798598Z","time spent":"340.952881ms","remote":"127.0.0.1:44422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T04:24:52.796165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.740958ms","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-14T04:24:52.796317Z","caller":"traceutil/trace.go:171","msg":"trace[2100539905] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:468; }","duration":"128.964954ms","start":"2026-04-14T04:24:52.667325Z","end":"2026-04-14T04:24:52.796289Z","steps":["trace[2100539905] 'range keys from in-memory index tree' (duration: 128.572603ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:25:01.835292Z","caller":"traceutil/trace.go:171","msg":"trace[1468335209] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:634; }","duration":"172.911199ms","start":"2026-04-14T04:25:01.662319Z","end":"2026-04-14T04:25:01.83523Z","steps":["trace[1468335209] 'read index received' (duration: 172.090676ms)","trace[1468335209] 'applied index is now lower than readState.Index' (duration: 819.503µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:25:01.835375Z","caller":"traceutil/trace.go:171","msg":"trace[1752709441] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"187.897626ms","start":"2026-04-14T04:25:01.647448Z","end":"2026-04-14T04:25:01.835345Z","steps":["trace[1752709441] 'process raft request' (duration: 187.215407ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:25:01.835482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.145045ms","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-14T04:25:01.835522Z","caller":"traceutil/trace.go:171","msg":"trace[2068120239] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:614; }","duration":"173.235738ms","start":"2026-04-14T04:25:01.662277Z","end":"2026-04-14T04:25:01.835512Z","steps":["trace[2068120239] 'agreement among raft nodes before linearized reading' (duration: 173.092154ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:25:01.994792Z","caller":"traceutil/trace.go:171","msg":"trace[811806113] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"107.407813ms","start":"2026-04-14T04:25:01.887329Z","end":"2026-04-14T04:25:01.994737Z","steps":["trace[811806113] 'process raft request' (duration: 87.523749ms)","trace[811806113] 'compare' (duration: 19.544005ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T04:25:02.484818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.244159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T04:25:02.48493Z","caller":"traceutil/trace.go:171","msg":"trace[1823851863] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:616; }","duration":"280.428254ms","start":"2026-04-14T04:25:02.204472Z","end":"2026-04-14T04:25:02.484901Z","steps":["trace[1823851863] 'range keys from in-memory index tree' (duration: 280.107595ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:25:02.484996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.125395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-zg8mm\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-14T04:25:02.485103Z","caller":"traceutil/trace.go:171","msg":"trace[1443707676] range","detail":"{range_begin:/registry/pods/kube-system/cilium-zg8mm; range_end:; response_count:1; response_revision:616; }","duration":"238.2888ms","start":"2026-04-14T04:25:02.246791Z","end":"2026-04-14T04:25:02.48508Z","steps":["trace[1443707676] 'range keys from in-memory index tree' (duration: 237.901769ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:25:22.193814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.731736ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T04:25:22.193949Z","caller":"traceutil/trace.go:171","msg":"trace[1037634450] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"229.448564ms","start":"2026-04-14T04:25:21.964482Z","end":"2026-04-14T04:25:22.19393Z","steps":["trace[1037634450] 'process raft request' (duration: 85.519164ms)","trace[1037634450] 'compare' (duration: 143.607511ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:25:49.050449Z","caller":"traceutil/trace.go:171","msg":"trace[328017029] transaction","detail":"{read_only:false; response_revision:1182; number_of_response:1; }","duration":"179.152663ms","start":"2026-04-14T04:25:48.871265Z","end":"2026-04-14T04:25:49.050418Z","steps":["trace[328017029] 'process raft request' (duration: 178.978788ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:26:36.385623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.787562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-04-14T04:26:36.38567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.098956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8a860c0e-cc74-434b-a793-cf6e93abefad\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-14T04:26:36.385722Z","caller":"traceutil/trace.go:171","msg":"trace[1365060780] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1860; }","duration":"119.939427ms","start":"2026-04-14T04:26:36.265763Z","end":"2026-04-14T04:26:36.385702Z","steps":["trace[1365060780] 'range keys from in-memory index tree' (duration: 119.595345ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:26:36.385775Z","caller":"traceutil/trace.go:171","msg":"trace[871361968] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8a860c0e-cc74-434b-a793-cf6e93abefad; range_end:; response_count:1; response_revision:1860; }","duration":"189.26173ms","start":"2026-04-14T04:26:36.196484Z","end":"2026-04-14T04:26:36.385745Z","steps":["trace[871361968] 'range keys from in-memory index tree' (duration: 188.90177ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:26:36.385786Z","caller":"traceutil/trace.go:171","msg":"trace[882032298] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"116.150168ms","start":"2026-04-14T04:26:36.269587Z","end":"2026-04-14T04:26:36.385737Z","steps":["trace[882032298] 'process raft request' (duration: 94.617155ms)","trace[882032298] 'compare' (duration: 21.223154ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:26:49.830404Z","caller":"traceutil/trace.go:171","msg":"trace[721725376] transaction","detail":"{read_only:false; response_revision:1972; number_of_response:1; }","duration":"162.507879ms","start":"2026-04-14T04:26:49.667847Z","end":"2026-04-14T04:26:49.830355Z","steps":["trace[721725376] 'process raft request' (duration: 98.735153ms)","trace[721725376] 'compare' (duration: 63.395995ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:27:26.830076Z","caller":"traceutil/trace.go:171","msg":"trace[2104635505] transaction","detail":"{read_only:false; response_revision:2184; number_of_response:1; }","duration":"100.394415ms","start":"2026-04-14T04:27:26.729649Z","end":"2026-04-14T04:27:26.830043Z","steps":["trace[2104635505] 'process raft request' (duration: 100.175819ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:27:32.755986Z","caller":"traceutil/trace.go:171","msg":"trace[1915454372] linearizableReadLoop","detail":"{readStateIndex:2296; appliedIndex:2295; }","duration":"118.851062ms","start":"2026-04-14T04:27:32.637114Z","end":"2026-04-14T04:27:32.755965Z","steps":["trace[1915454372] 'read index received' (duration: 118.625465ms)","trace[1915454372] 'applied index is now lower than readState.Index' (duration: 224.657µs)"],"step_count":2} {"level":"warn","ts":"2026-04-14T04:27:32.756145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.003086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T04:27:32.75621Z","caller":"traceutil/trace.go:171","msg":"trace[2113685354] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:2215; }","duration":"119.140451ms","start":"2026-04-14T04:27:32.637058Z","end":"2026-04-14T04:27:32.756199Z","steps":["trace[2113685354] 'agreement among raft nodes before linearized reading' (duration: 119.003987ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:27:32.756484Z","caller":"traceutil/trace.go:171","msg":"trace[704457302] transaction","detail":"{read_only:false; response_revision:2215; number_of_response:1; }","duration":"144.905326ms","start":"2026-04-14T04:27:32.611508Z","end":"2026-04-14T04:27:32.756413Z","steps":["trace[704457302] 'process raft request' (duration: 144.315258ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:27:32.965394Z","caller":"traceutil/trace.go:171","msg":"trace[1629508826] transaction","detail":"{read_only:false; response_revision:2216; number_of_response:1; }","duration":"108.187767ms","start":"2026-04-14T04:27:32.857172Z","end":"2026-04-14T04:27:32.965359Z","steps":["trace[1629508826] 'process raft request' (duration: 105.270144ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:28:41.130999Z","caller":"traceutil/trace.go:171","msg":"trace[1780081921] transaction","detail":"{read_only:false; response_revision:2782; number_of_response:1; }","duration":"119.068408ms","start":"2026-04-14T04:28:41.011903Z","end":"2026-04-14T04:28:41.130971Z","steps":["trace[1780081921] 'process raft request' (duration: 118.79367ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:32:37.900583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.836661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T04:32:37.900689Z","caller":"traceutil/trace.go:171","msg":"trace[1551986595] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1; range_end:; response_count:0; response_revision:4842; }","duration":"115.972616ms","start":"2026-04-14T04:32:37.784695Z","end":"2026-04-14T04:32:37.900668Z","steps":["trace[1551986595] 'range keys from in-memory index tree' (duration: 115.7835ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:32:42.055946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.842934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T04:32:42.056073Z","caller":"traceutil/trace.go:171","msg":"trace[1599166401] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:0; response_revision:4974; }","duration":"263.019038ms","start":"2026-04-14T04:32:41.793025Z","end":"2026-04-14T04:32:42.056044Z","steps":["trace[1599166401] 'range keys from in-memory index tree' (duration: 262.73131ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:32:42.056594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.752358ms","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-14T04:32:42.056651Z","caller":"traceutil/trace.go:171","msg":"trace[1056288265] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:4974; }","duration":"229.83143ms","start":"2026-04-14T04:32:41.826804Z","end":"2026-04-14T04:32:42.056635Z","steps":["trace[1056288265] 'range keys from in-memory index tree' (duration: 229.517031ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:32:42.056695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.427135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-022f0d95-c46a-4725-b2c6-de0975963736\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-04-14T04:32:42.056819Z","caller":"traceutil/trace.go:171","msg":"trace[392376399] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-022f0d95-c46a-4725-b2c6-de0975963736; range_end:; response_count:1; response_revision:4974; }","duration":"125.644982ms","start":"2026-04-14T04:32:41.931146Z","end":"2026-04-14T04:32:42.056791Z","steps":["trace[392376399] 'range keys from in-memory index tree' (duration: 125.058784ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:32:42.459193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.545808ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-14T04:32:42.459299Z","caller":"traceutil/trace.go:171","msg":"trace[222778405] transaction","detail":"{read_only:false; response_revision:4980; number_of_response:1; }","duration":"209.246842ms","start":"2026-04-14T04:32:42.250036Z","end":"2026-04-14T04:32:42.459283Z","steps":["trace[222778405] 'process raft request' (duration: 47.538449ms)","trace[222778405] 'compare' (duration: 161.398674ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T04:32:53.99497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.884436ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T04:32:53.995187Z","caller":"traceutil/trace.go:171","msg":"trace[1716243514] transaction","detail":"{read_only:false; response_revision:5210; number_of_response:1; }","duration":"166.463817ms","start":"2026-04-14T04:32:53.828707Z","end":"2026-04-14T04:32:53.995171Z","steps":["trace[1716243514] 'process raft request' (duration: 166.386045ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:32:53.995327Z","caller":"traceutil/trace.go:171","msg":"trace[1260368618] transaction","detail":"{read_only:false; response_revision:5209; number_of_response:1; }","duration":"168.343162ms","start":"2026-04-14T04:32:53.826952Z","end":"2026-04-14T04:32:53.995295Z","steps":["trace[1260368618] 'process raft request' (duration: 54.003893ms)","trace[1260368618] 'compare' (duration: 113.749522ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:33:01.937514Z","caller":"traceutil/trace.go:171","msg":"trace[1536526079] transaction","detail":"{read_only:false; response_revision:5376; number_of_response:1; }","duration":"142.580866ms","start":"2026-04-14T04:33:01.794903Z","end":"2026-04-14T04:33:01.937484Z","steps":["trace[1536526079] 'process raft request' (duration: 123.937343ms)","trace[1536526079] 'compare' (duration: 17.4717ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:33:02.088531Z","caller":"traceutil/trace.go:171","msg":"trace[728256998] transaction","detail":"{read_only:false; response_revision:5377; number_of_response:1; }","duration":"143.279425ms","start":"2026-04-14T04:33:01.945217Z","end":"2026-04-14T04:33:02.088496Z","steps":["trace[728256998] 'process raft request' (duration: 101.737717ms)","trace[728256998] 'compare' (duration: 41.389324ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:33:02.152653Z","caller":"traceutil/trace.go:171","msg":"trace[1453864251] transaction","detail":"{read_only:false; response_revision:5378; number_of_response:1; }","duration":"206.879393ms","start":"2026-04-14T04:33:01.945754Z","end":"2026-04-14T04:33:02.152633Z","steps":["trace[1453864251] 'process raft request' (duration: 206.76074ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:34:25.186344Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3024} {"level":"info","ts":"2026-04-14T04:34:25.272018Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3024,"took":"83.275628ms","hash":2312261119,"current-db-size-bytes":57856000,"current-db-size":"58 MB","current-db-size-in-use-bytes":31571968,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-14T04:34:25.272317Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2312261119,"revision":3024,"compact-revision":-1} {"level":"info","ts":"2026-04-14T04:39:12.112471Z","caller":"traceutil/trace.go:171","msg":"trace[2096909700] linearizableReadLoop","detail":"{readStateIndex:8522; appliedIndex:8521; }","duration":"145.358001ms","start":"2026-04-14T04:39:11.967084Z","end":"2026-04-14T04:39:12.112442Z","steps":["trace[2096909700] 'read index received' (duration: 144.97778ms)","trace[2096909700] 'applied index is now lower than readState.Index' (duration: 379.261µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:39:12.113041Z","caller":"traceutil/trace.go:171","msg":"trace[562991965] transaction","detail":"{read_only:false; response_revision:8235; number_of_response:1; }","duration":"198.976793ms","start":"2026-04-14T04:39:11.914045Z","end":"2026-04-14T04:39:12.113022Z","steps":["trace[562991965] 'process raft request' (duration: 198.1473ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:39:12.114255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.373678ms","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-14T04:39:12.114432Z","caller":"traceutil/trace.go:171","msg":"trace[1317591732] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8235; }","duration":"147.372478ms","start":"2026-04-14T04:39:11.96701Z","end":"2026-04-14T04:39:12.114383Z","steps":["trace[1317591732] 'agreement among raft nodes before linearized reading' (duration: 146.340788ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:39:24.59506Z","caller":"traceutil/trace.go:171","msg":"trace[1101290227] transaction","detail":"{read_only:false; response_revision:8300; number_of_response:1; }","duration":"245.217698ms","start":"2026-04-14T04:39:24.349801Z","end":"2026-04-14T04:39:24.595019Z","steps":["trace[1101290227] 'process raft request' (duration: 244.994011ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:39:24.690088Z","caller":"traceutil/trace.go:171","msg":"trace[173789770] transaction","detail":"{read_only:false; response_revision:8301; number_of_response:1; }","duration":"336.681977ms","start":"2026-04-14T04:39:24.353369Z","end":"2026-04-14T04:39:24.690051Z","steps":["trace[173789770] 'process raft request' (duration: 336.420369ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:39:24.690307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:39:24.353338Z","time spent":"336.850202ms","remote":"127.0.0.1:44228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T04:39:25.305032Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6139} {"level":"warn","ts":"2026-04-14T04:39:25.68182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.371789ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T04:39:25.682235Z","caller":"traceutil/trace.go:171","msg":"trace[432899667] linearizableReadLoop","detail":"{readStateIndex:8600; appliedIndex:8599; }","duration":"226.876876ms","start":"2026-04-14T04:39:25.455311Z","end":"2026-04-14T04:39:25.682188Z","steps":["trace[432899667] 'read index received' (duration: 85.892µs)","trace[432899667] 'applied index is now lower than readState.Index' (duration: 226.787364ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:39:25.681993Z","caller":"traceutil/trace.go:171","msg":"trace[1345933793] transaction","detail":"{read_only:false; response_revision:8308; number_of_response:1; }","duration":"341.318907ms","start":"2026-04-14T04:39:25.34063Z","end":"2026-04-14T04:39:25.681949Z","steps":["trace[1345933793] 'process raft request' (duration: 93.687571ms)","trace[1345933793] 'compare' (duration: 219.844017ms)","trace[1345933793] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/kube-scheduler; req_size:461; } (duration: 27.167121ms)"],"step_count":3} {"level":"warn","ts":"2026-04-14T04:39:25.682554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:39:25.340604Z","time spent":"341.859843ms","remote":"127.0.0.1:44228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T04:39:25.682719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.37091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T04:39:25.682909Z","caller":"traceutil/trace.go:171","msg":"trace[516288420] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:8309; }","duration":"227.650338ms","start":"2026-04-14T04:39:25.455227Z","end":"2026-04-14T04:39:25.682878Z","steps":["trace[516288420] 'agreement among raft nodes before linearized reading' (duration: 227.081702ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:39:25.831779Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6139,"took":"524.227504ms","hash":2340838534,"current-db-size-bytes":57856000,"current-db-size":"58 MB","current-db-size-in-use-bytes":23179264,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-14T04:39:25.831878Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2340838534,"revision":6139,"compact-revision":3024} {"level":"info","ts":"2026-04-14T04:39:26.148941Z","caller":"traceutil/trace.go:171","msg":"trace[1264668317] transaction","detail":"{read_only:false; response_revision:8310; number_of_response:1; }","duration":"121.942894ms","start":"2026-04-14T04:39:26.026971Z","end":"2026-04-14T04:39:26.148914Z","steps":["trace[1264668317] 'process raft request' (duration: 121.746899ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:39:26.845862Z","caller":"traceutil/trace.go:171","msg":"trace[1962669666] transaction","detail":"{read_only:false; response_revision:8314; number_of_response:1; }","duration":"122.453749ms","start":"2026-04-14T04:39:26.723359Z","end":"2026-04-14T04:39:26.845813Z","steps":["trace[1962669666] 'process raft request' (duration: 121.362339ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:39:54.015297Z","caller":"traceutil/trace.go:171","msg":"trace[621640864] transaction","detail":"{read_only:false; response_revision:8514; number_of_response:1; }","duration":"211.753866ms","start":"2026-04-14T04:39:53.803499Z","end":"2026-04-14T04:39:54.015252Z","steps":["trace[621640864] 'process raft request' (duration: 211.302484ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:39:54.015687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.59212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-14T04:39:54.015783Z","caller":"traceutil/trace.go:171","msg":"trace[1568825634] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8514; }","duration":"187.729203ms","start":"2026-04-14T04:39:53.82803Z","end":"2026-04-14T04:39:54.015759Z","steps":["trace[1568825634] 'agreement among raft nodes before linearized reading' (duration: 187.463106ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:39:54.016292Z","caller":"traceutil/trace.go:171","msg":"trace[268536201] linearizableReadLoop","detail":"{readStateIndex:8814; appliedIndex:8813; }","duration":"187.115116ms","start":"2026-04-14T04:39:53.828071Z","end":"2026-04-14T04:39:54.015186Z","steps":["trace[268536201] 'read index received' (duration: 186.812478ms)","trace[268536201] 'applied index is now lower than readState.Index' (duration: 301.268µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:39:54.188341Z","caller":"traceutil/trace.go:171","msg":"trace[1384398051] transaction","detail":"{read_only:false; response_revision:8515; number_of_response:1; }","duration":"167.267721ms","start":"2026-04-14T04:39:54.021041Z","end":"2026-04-14T04:39:54.188309Z","steps":["trace[1384398051] 'process raft request' (duration: 152.76729ms)","trace[1384398051] 'compare' (duration: 14.148991ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T04:40:07.232788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.610723ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T04:40:07.232985Z","caller":"traceutil/trace.go:171","msg":"trace[339520488] linearizableReadLoop","detail":"{readStateIndex:8931; appliedIndex:8930; }","duration":"132.408612ms","start":"2026-04-14T04:40:07.100552Z","end":"2026-04-14T04:40:07.232961Z","steps":["trace[339520488] 'read index received' (duration: 40.691µs)","trace[339520488] 'applied index is now lower than readState.Index' (duration: 132.365141ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:40:07.233075Z","caller":"traceutil/trace.go:171","msg":"trace[41460910] transaction","detail":"{read_only:false; response_revision:8626; number_of_response:1; }","duration":"185.310597ms","start":"2026-04-14T04:40:07.047718Z","end":"2026-04-14T04:40:07.233028Z","steps":["trace[41460910] 'process raft request' (duration: 49.249541ms)","trace[41460910] 'compare' (duration: 134.588424ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T04:40:07.233205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.648249ms","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-14T04:40:07.233249Z","caller":"traceutil/trace.go:171","msg":"trace[886649149] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8626; }","duration":"132.766661ms","start":"2026-04-14T04:40:07.100471Z","end":"2026-04-14T04:40:07.233238Z","steps":["trace[886649149] 'agreement among raft nodes before linearized reading' (duration: 132.549355ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:40:54.91609Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024d1.wal"} {"level":"info","ts":"2026-04-14T04:42:01.862839Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"e3aaa5c123e150e0","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-14T04:42:01.869804Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-14T04:42:01.869921Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-14T04:44:25.313775Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8307} {"level":"info","ts":"2026-04-14T04:44:25.405841Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8307,"took":"89.577139ms","hash":1790733295,"current-db-size-bytes":57856000,"current-db-size":"58 MB","current-db-size-in-use-bytes":23900160,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-04-14T04:44:25.405959Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1790733295,"revision":8307,"compact-revision":6139} {"level":"info","ts":"2026-04-14T04:49:25.320024Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10529} {"level":"info","ts":"2026-04-14T04:49:25.397303Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10529,"took":"74.088872ms","hash":533465418,"current-db-size-bytes":57856000,"current-db-size":"58 MB","current-db-size-in-use-bytes":26173440,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-04-14T04:49:25.397376Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":533465418,"revision":10529,"compact-revision":8307} {"level":"info","ts":"2026-04-14T04:53:19.882004Z","caller":"traceutil/trace.go:171","msg":"trace[2020542123] transaction","detail":"{read_only:false; response_revision:14346; number_of_response:1; }","duration":"105.342398ms","start":"2026-04-14T04:53:19.776644Z","end":"2026-04-14T04:53:19.881987Z","steps":["trace[2020542123] 'process raft request' (duration: 105.274897ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:53:19.882048Z","caller":"traceutil/trace.go:171","msg":"trace[1319715550] transaction","detail":"{read_only:false; response_revision:14345; number_of_response:1; }","duration":"106.404159ms","start":"2026-04-14T04:53:19.775621Z","end":"2026-04-14T04:53:19.882025Z","steps":["trace[1319715550] 'process raft request' (duration: 105.41425ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:53:33.612804Z","caller":"traceutil/trace.go:171","msg":"trace[1113325130] transaction","detail":"{read_only:false; response_revision:14415; number_of_response:1; }","duration":"284.147984ms","start":"2026-04-14T04:53:33.328631Z","end":"2026-04-14T04:53:33.612779Z","steps":["trace[1113325130] 'process raft request' (duration: 283.922628ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:53:46.056458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.018395ms","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-14T04:53:46.056551Z","caller":"traceutil/trace.go:171","msg":"trace[1064043174] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14611; }","duration":"113.17929ms","start":"2026-04-14T04:53:45.943353Z","end":"2026-04-14T04:53:46.056532Z","steps":["trace[1064043174] 'range keys from in-memory index tree' (duration: 112.82583ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:53:46.271848Z","caller":"traceutil/trace.go:171","msg":"trace[1809169770] transaction","detail":"{read_only:false; response_revision:14614; number_of_response:1; }","duration":"210.383538ms","start":"2026-04-14T04:53:46.061428Z","end":"2026-04-14T04:53:46.271811Z","steps":["trace[1809169770] 'process raft request' (duration: 210.273315ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:53:46.272252Z","caller":"traceutil/trace.go:171","msg":"trace[1894638951] transaction","detail":"{read_only:false; response_revision:14613; number_of_response:1; }","duration":"210.850602ms","start":"2026-04-14T04:53:46.06136Z","end":"2026-04-14T04:53:46.272211Z","steps":["trace[1894638951] 'process raft request' (duration: 209.691319ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:54:25.329391Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12784} {"level":"info","ts":"2026-04-14T04:54:25.411978Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12784,"took":"78.923162ms","hash":243830577,"current-db-size-bytes":57856000,"current-db-size":"58 MB","current-db-size-in-use-bytes":29061120,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-14T04:54:25.412057Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":243830577,"revision":12784,"compact-revision":10529} {"level":"warn","ts":"2026-04-14T04:55:13.339413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.87982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-14T04:55:13.339477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.175858ms","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-14T04:55:13.339525Z","caller":"traceutil/trace.go:171","msg":"trace[1012006871] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15637; }","duration":"135.057925ms","start":"2026-04-14T04:55:13.204445Z","end":"2026-04-14T04:55:13.339503Z","steps":["trace[1012006871] 'range keys from in-memory index tree' (duration: 134.770307ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:55:13.339599Z","caller":"traceutil/trace.go:171","msg":"trace[894444993] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15637; }","duration":"105.363424ms","start":"2026-04-14T04:55:13.234209Z","end":"2026-04-14T04:55:13.339572Z","steps":["trace[894444993] 'range keys from in-memory index tree' (duration: 104.89247ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:55:13.339643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.104703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-04-14T04:55:13.339703Z","caller":"traceutil/trace.go:171","msg":"trace[1129506587] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:15637; }","duration":"173.212486ms","start":"2026-04-14T04:55:13.166474Z","end":"2026-04-14T04:55:13.339687Z","steps":["trace[1129506587] 'range keys from in-memory index tree' (duration: 172.869966ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:55:13.446941Z","caller":"traceutil/trace.go:171","msg":"trace[1590064114] transaction","detail":"{read_only:false; response_revision:15638; number_of_response:1; }","duration":"101.782433ms","start":"2026-04-14T04:55:13.34512Z","end":"2026-04-14T04:55:13.446902Z","steps":["trace[1590064114] 'process raft request' (duration: 101.603728ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:55:13.534045Z","caller":"traceutil/trace.go:171","msg":"trace[582454483] transaction","detail":"{read_only:false; response_revision:15639; number_of_response:1; }","duration":"188.768917ms","start":"2026-04-14T04:55:13.345242Z","end":"2026-04-14T04:55:13.534011Z","steps":["trace[582454483] 'process raft request' (duration: 188.515109ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:55:13.655344Z","caller":"traceutil/trace.go:171","msg":"trace[1627692914] transaction","detail":"{read_only:false; response_revision:15640; number_of_response:1; }","duration":"116.313635ms","start":"2026-04-14T04:55:13.539003Z","end":"2026-04-14T04:55:13.655316Z","steps":["trace[1627692914] 'process raft request' (duration: 47.797374ms)","trace[1627692914] 'compare' (duration: 68.311845ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:59:25.335492Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15055} {"level":"info","ts":"2026-04-14T04:59:25.432368Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15055,"took":"92.78267ms","hash":499824700,"current-db-size-bytes":57856000,"current-db-size":"58 MB","current-db-size-in-use-bytes":30527488,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-14T04:59:25.432474Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":499824700,"revision":15055,"compact-revision":12784} {"level":"info","ts":"2026-04-14T05:01:04.657694Z","caller":"traceutil/trace.go:171","msg":"trace[851359047] transaction","detail":"{read_only:false; response_revision:18267; number_of_response:1; }","duration":"184.862578ms","start":"2026-04-14T05:01:04.472796Z","end":"2026-04-14T05:01:04.657658Z","steps":["trace[851359047] 'process raft request' (duration: 111.256692ms)","trace[851359047] 'compare' (duration: 73.131681ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T05:01:07.339489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.668041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1854"} {"level":"info","ts":"2026-04-14T05:01:07.339617Z","caller":"traceutil/trace.go:171","msg":"trace[699819730] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:18337; }","duration":"119.834505ms","start":"2026-04-14T05:01:07.219757Z","end":"2026-04-14T05:01:07.339591Z","steps":["trace[699819730] 'range keys from in-memory index tree' (duration: 119.456455ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:01:07.534224Z","caller":"traceutil/trace.go:171","msg":"trace[1655726469] linearizableReadLoop","detail":"{readStateIndex:18960; appliedIndex:18959; }","duration":"102.139204ms","start":"2026-04-14T05:01:07.432056Z","end":"2026-04-14T05:01:07.534195Z","steps":["trace[1655726469] 'read index received' (duration: 25.659467ms)","trace[1655726469] 'applied index is now lower than readState.Index' (duration: 76.478057ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:01:07.534337Z","caller":"traceutil/trace.go:171","msg":"trace[1951485177] transaction","detail":"{read_only:false; response_revision:18339; number_of_response:1; }","duration":"146.489161ms","start":"2026-04-14T05:01:07.38782Z","end":"2026-04-14T05:01:07.534309Z","steps":["trace[1951485177] 'process raft request' (duration: 146.260343ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:01:07.534636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.538236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-frsxl\" ","response":"range_response_count:1 size:9713"} {"level":"info","ts":"2026-04-14T05:01:07.534856Z","caller":"traceutil/trace.go:171","msg":"trace[1749240080] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-frsxl; range_end:; response_count:1; response_revision:18339; }","duration":"102.720901ms","start":"2026-04-14T05:01:07.432011Z","end":"2026-04-14T05:01:07.534732Z","steps":["trace[1749240080] 'agreement among raft nodes before linearized reading' (duration: 102.3254ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:01:07.644419Z","caller":"traceutil/trace.go:171","msg":"trace[1269620460] transaction","detail":"{read_only:false; response_revision:18340; number_of_response:1; }","duration":"101.540636ms","start":"2026-04-14T05:01:07.542855Z","end":"2026-04-14T05:01:07.644396Z","steps":["trace[1269620460] 'process raft request' (duration: 101.263239ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:01:10.291886Z","caller":"traceutil/trace.go:171","msg":"trace[1978689579] transaction","detail":"{read_only:false; response_revision:18357; number_of_response:1; }","duration":"233.63642ms","start":"2026-04-14T05:01:10.058224Z","end":"2026-04-14T05:01:10.29186Z","steps":["trace[1978689579] 'process raft request' (duration: 233.477215ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:01:10.292845Z","caller":"traceutil/trace.go:171","msg":"trace[1194229216] linearizableReadLoop","detail":"{readStateIndex:18980; appliedIndex:18978; }","duration":"153.840218ms","start":"2026-04-14T05:01:10.138983Z","end":"2026-04-14T05:01:10.292823Z","steps":["trace[1194229216] 'read index received' (duration: 152.673106ms)","trace[1194229216] 'applied index is now lower than readState.Index' (duration: 1.162542ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:01:10.293057Z","caller":"traceutil/trace.go:171","msg":"trace[392548477] transaction","detail":"{read_only:false; response_revision:18358; number_of_response:1; }","duration":"234.527084ms","start":"2026-04-14T05:01:10.058491Z","end":"2026-04-14T05:01:10.293018Z","steps":["trace[392548477] 'process raft request' (duration: 234.229246ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:01:10.293129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.298257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-79622dd0-91a6-4184-9585-630d414f6d9e\" ","response":"range_response_count:1 size:3946"} {"level":"info","ts":"2026-04-14T05:01:10.293179Z","caller":"traceutil/trace.go:171","msg":"trace[1044396845] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-79622dd0-91a6-4184-9585-630d414f6d9e; range_end:; response_count:1; response_revision:18358; }","duration":"145.396569ms","start":"2026-04-14T05:01:10.147766Z","end":"2026-04-14T05:01:10.293162Z","steps":["trace[1044396845] 'agreement among raft nodes before linearized reading' (duration: 145.182703ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:01:10.293508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.472226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T05:01:10.293568Z","caller":"traceutil/trace.go:171","msg":"trace[509813800] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:18358; }","duration":"154.60217ms","start":"2026-04-14T05:01:10.138948Z","end":"2026-04-14T05:01:10.29355Z","steps":["trace[509813800] 'agreement among raft nodes before linearized reading' (duration: 153.997543ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:02:54.761743Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"e3aaa5c123e150e0","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-14T05:02:54.767656Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-04-14T05:02:54.767763Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-04-14T05:04:25.342083Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17500} {"level":"info","ts":"2026-04-14T05:04:25.448324Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17500,"took":"101.900626ms","hash":2775331585,"current-db-size-bytes":57856000,"current-db-size":"58 MB","current-db-size-in-use-bytes":34570240,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-04-14T05:04:25.448403Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2775331585,"revision":17500,"compact-revision":15055} {"level":"info","ts":"2026-04-14T05:06:45.732198Z","caller":"traceutil/trace.go:171","msg":"trace[1347987534] transaction","detail":"{read_only:false; response_revision:20969; number_of_response:1; }","duration":"106.551669ms","start":"2026-04-14T05:06:45.625617Z","end":"2026-04-14T05:06:45.732168Z","steps":["trace[1347987534] 'process raft request' (duration: 106.367954ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:07:02.185064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.428939ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-14T05:07:02.185264Z","caller":"traceutil/trace.go:171","msg":"trace[643978199] transaction","detail":"{read_only:false; response_revision:21055; number_of_response:1; }","duration":"162.845023ms","start":"2026-04-14T05:07:02.022401Z","end":"2026-04-14T05:07:02.185246Z","steps":["trace[643978199] 'process raft request' (duration: 162.783721ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:07:02.185292Z","caller":"traceutil/trace.go:171","msg":"trace[1008473561] transaction","detail":"{read_only:false; response_revision:21054; number_of_response:1; }","duration":"374.765378ms","start":"2026-04-14T05:07:01.810491Z","end":"2026-04-14T05:07:02.185256Z","steps":["trace[1008473561] 'process raft request' (duration: 228.050981ms)","trace[1008473561] 'compare' (duration: 146.308436ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T05:07:02.185456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:07:01.810455Z","time spent":"374.908922ms","remote":"127.0.0.1:44228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T05:07:02.185506Z","caller":"traceutil/trace.go:171","msg":"trace[1593960559] linearizableReadLoop","detail":"{readStateIndex:21767; appliedIndex:21765; }","duration":"365.543398ms","start":"2026-04-14T05:07:01.819951Z","end":"2026-04-14T05:07:02.185495Z","steps":["trace[1593960559] 'read index received' (duration: 86.175223ms)","trace[1593960559] 'applied index is now lower than readState.Index' (duration: 279.366515ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T05:07:02.185773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.820645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-14T05:07:02.18579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.369196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T05:07:02.185812Z","caller":"traceutil/trace.go:171","msg":"trace[1814106522] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21055; }","duration":"365.890947ms","start":"2026-04-14T05:07:01.81991Z","end":"2026-04-14T05:07:02.185801Z","steps":["trace[1814106522] 'agreement among raft nodes before linearized reading' (duration: 365.773064ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:07:02.185831Z","caller":"traceutil/trace.go:171","msg":"trace[565562582] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:21055; }","duration":"105.447888ms","start":"2026-04-14T05:07:02.080369Z","end":"2026-04-14T05:07:02.185817Z","steps":["trace[565562582] 'agreement among raft nodes before linearized reading' (duration: 105.380966ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:07:02.185841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:07:01.819896Z","time spent":"365.940698ms","remote":"127.0.0.1:44228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-14T05:07:02.462786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.622619ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-14T05:07:02.462975Z","caller":"traceutil/trace.go:171","msg":"trace[1207334142] transaction","detail":"{read_only:false; response_revision:21056; number_of_response:1; }","duration":"272.724567ms","start":"2026-04-14T05:07:02.190188Z","end":"2026-04-14T05:07:02.462912Z","steps":["trace[1207334142] 'process raft request' (duration: 98.810539ms)","trace[1207334142] 'compare' (duration: 173.426784ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:07:02.463033Z","caller":"traceutil/trace.go:171","msg":"trace[1335583703] linearizableReadLoop","detail":"{readStateIndex:21769; appliedIndex:21768; }","duration":"259.794271ms","start":"2026-04-14T05:07:02.203197Z","end":"2026-04-14T05:07:02.462991Z","steps":["trace[1335583703] 'read index received' (duration: 85.726209ms)","trace[1335583703] 'applied index is now lower than readState.Index' (duration: 174.063212ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:07:02.463029Z","caller":"traceutil/trace.go:171","msg":"trace[259536561] transaction","detail":"{read_only:false; response_revision:21057; number_of_response:1; }","duration":"200.338226ms","start":"2026-04-14T05:07:02.262663Z","end":"2026-04-14T05:07:02.463002Z","steps":["trace[259536561] 'process raft request' (duration: 200.259974ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:07:02.463207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.983536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T05:07:02.463278Z","caller":"traceutil/trace.go:171","msg":"trace[1396863511] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21057; }","duration":"260.089519ms","start":"2026-04-14T05:07:02.203172Z","end":"2026-04-14T05:07:02.463261Z","steps":["trace[1396863511] 'agreement among raft nodes before linearized reading' (duration: 259.947875ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:07:02.901708Z","caller":"traceutil/trace.go:171","msg":"trace[285003511] transaction","detail":"{read_only:false; response_revision:21060; number_of_response:1; }","duration":"136.450757ms","start":"2026-04-14T05:07:02.765218Z","end":"2026-04-14T05:07:02.901669Z","steps":["trace[285003511] 'process raft request' (duration: 136.321652ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:07:02.901881Z","caller":"traceutil/trace.go:171","msg":"trace[478812887] transaction","detail":"{read_only:false; response_revision:21059; number_of_response:1; }","duration":"136.993091ms","start":"2026-04-14T05:07:02.764881Z","end":"2026-04-14T05:07:02.901874Z","steps":["trace[478812887] 'process raft request' (duration: 134.586753ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:07:02.902237Z","caller":"traceutil/trace.go:171","msg":"trace[221697522] transaction","detail":"{read_only:false; response_revision:21061; number_of_response:1; }","duration":"136.91679ms","start":"2026-04-14T05:07:02.765225Z","end":"2026-04-14T05:07:02.902141Z","steps":["trace[221697522] 'process raft request' (duration: 136.397665ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:09:25.348411Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19922} {"level":"info","ts":"2026-04-14T05:09:25.468233Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19922,"took":"114.716386ms","hash":917530253,"current-db-size-bytes":57856000,"current-db-size":"58 MB","current-db-size-in-use-bytes":30470144,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-14T05:09:25.468322Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":917530253,"revision":19922,"compact-revision":17500} {"level":"warn","ts":"2026-04-14T05:10:59.49695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.307198ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-14T05:10:59.497339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.288159ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-14T05:10:59.497381Z","caller":"traceutil/trace.go:171","msg":"trace[157089284] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:23493; }","duration":"100.576805ms","start":"2026-04-14T05:10:59.396572Z","end":"2026-04-14T05:10:59.497148Z","steps":["trace[157089284] 'range keys from in-memory index tree' (duration: 100.257976ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:10:59.497626Z","caller":"traceutil/trace.go:171","msg":"trace[1708325857] transaction","detail":"{read_only:false; response_revision:23494; number_of_response:1; }","duration":"358.865361ms","start":"2026-04-14T05:10:59.138726Z","end":"2026-04-14T05:10:59.497591Z","steps":["trace[1708325857] 'process raft request' (duration: 113.031477ms)","trace[1708325857] 'compare' (duration: 245.148185ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T05:10:59.497809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:10:59.138692Z","time spent":"359.020476ms","remote":"127.0.0.1:44228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T05:10:59.498318Z","caller":"traceutil/trace.go:171","msg":"trace[1045463349] transaction","detail":"{read_only:false; response_revision:23495; number_of_response:1; }","duration":"328.742039ms","start":"2026-04-14T05:10:59.168999Z","end":"2026-04-14T05:10:59.497741Z","steps":["trace[1045463349] 'process raft request' (duration: 328.44055ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:10:59.498532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:10:59.168967Z","time spent":"329.405027ms","remote":"127.0.0.1:44228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T05:10:59.498372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.212224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-04-14T05:10:59.498739Z","caller":"traceutil/trace.go:171","msg":"trace[1650864895] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:23495; }","duration":"240.678768ms","start":"2026-04-14T05:10:59.258047Z","end":"2026-04-14T05:10:59.498726Z","steps":["trace[1650864895] 'agreement among raft nodes before linearized reading' (duration: 240.152903ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:10:59.498761Z","caller":"traceutil/trace.go:171","msg":"trace[301033120] linearizableReadLoop","detail":"{readStateIndex:24279; appliedIndex:24277; }","duration":"239.375592ms","start":"2026-04-14T05:10:59.258111Z","end":"2026-04-14T05:10:59.497487Z","steps":["trace[301033120] 'read index received' (duration: 238.374103ms)","trace[301033120] 'applied index is now lower than readState.Index' (duration: 1.000378ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:10:59.648772Z","caller":"traceutil/trace.go:171","msg":"trace[1466612742] linearizableReadLoop","detail":"{readStateIndex:24280; appliedIndex:24279; }","duration":"149.92213ms","start":"2026-04-14T05:10:59.498813Z","end":"2026-04-14T05:10:59.648735Z","steps":["trace[1466612742] 'read index received' (duration: 143.305823ms)","trace[1466612742] 'applied index is now lower than readState.Index' (duration: 6.615147ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T05:10:59.648979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.23451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T05:10:59.649023Z","caller":"traceutil/trace.go:171","msg":"trace[16879686] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23496; }","duration":"348.324613ms","start":"2026-04-14T05:10:59.300688Z","end":"2026-04-14T05:10:59.649013Z","steps":["trace[16879686] 'agreement among raft nodes before linearized reading' (duration: 348.181369ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:10:59.649089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:10:59.300677Z","time spent":"348.397175ms","remote":"127.0.0.1:43982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-14T05:10:59.649094Z","caller":"traceutil/trace.go:171","msg":"trace[1759072367] transaction","detail":"{read_only:false; response_revision:23496; number_of_response:1; }","duration":"375.635536ms","start":"2026-04-14T05:10:59.273428Z","end":"2026-04-14T05:10:59.649063Z","steps":["trace[1759072367] 'process raft request' (duration: 368.68918ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:10:59.649101Z","caller":"traceutil/trace.go:171","msg":"trace[1709116636] transaction","detail":"{read_only:false; response_revision:23497; number_of_response:1; }","duration":"143.286903ms","start":"2026-04-14T05:10:59.50577Z","end":"2026-04-14T05:10:59.649057Z","steps":["trace[1709116636] 'process raft request' (duration: 143.108868ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:10:59.649278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:10:59.273374Z","time spent":"375.79229ms","remote":"127.0.0.1:44228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T05:12:52.087822Z","caller":"traceutil/trace.go:171","msg":"trace[1886756311] transaction","detail":"{read_only:false; response_revision:24630; number_of_response:1; }","duration":"123.294707ms","start":"2026-04-14T05:12:51.96449Z","end":"2026-04-14T05:12:52.087785Z","steps":["trace[1886756311] 'process raft request' (duration: 123.049121ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:14:25.354092Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22170} {"level":"info","ts":"2026-04-14T05:14:25.471652Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22170,"took":"111.060032ms","hash":4284610323,"current-db-size-bytes":57856000,"current-db-size":"58 MB","current-db-size-in-use-bytes":42885120,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-04-14T05:14:25.471761Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4284610323,"revision":22170,"compact-revision":19922} {"level":"info","ts":"2026-04-14T05:14:40.845621Z","caller":"traceutil/trace.go:171","msg":"trace[932795309] transaction","detail":"{read_only:false; response_revision:25656; number_of_response:1; }","duration":"183.409999ms","start":"2026-04-14T05:14:40.662159Z","end":"2026-04-14T05:14:40.845569Z","steps":["trace[932795309] 'process raft request' (duration: 183.159862ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:14:41.21292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.195452ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-14T05:14:41.213149Z","caller":"traceutil/trace.go:171","msg":"trace[39281788] transaction","detail":"{read_only:false; response_revision:25658; number_of_response:1; }","duration":"286.837544ms","start":"2026-04-14T05:14:40.926293Z","end":"2026-04-14T05:14:41.21313Z","steps":["trace[39281788] 'process raft request' (duration: 286.752912ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:14:41.213153Z","caller":"traceutil/trace.go:171","msg":"trace[1378488753] transaction","detail":"{read_only:false; response_revision:25657; number_of_response:1; }","duration":"319.478607ms","start":"2026-04-14T05:14:40.893656Z","end":"2026-04-14T05:14:41.213134Z","steps":["trace[1378488753] 'process raft request' (duration: 100.974686ms)","trace[1378488753] 'compare' (duration: 217.847923ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T05:14:41.213212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:14:40.893578Z","time spent":"319.605431ms","remote":"127.0.0.1:44228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T05:14:41.311324Z","caller":"traceutil/trace.go:171","msg":"trace[1701792483] linearizableReadLoop","detail":"{readStateIndex:26502; appliedIndex:26499; }","duration":"153.956995ms","start":"2026-04-14T05:14:41.157329Z","end":"2026-04-14T05:14:41.311286Z","steps":["trace[1701792483] 'read index received' (duration: 51.118005ms)","trace[1701792483] 'applied index is now lower than readState.Index' (duration: 102.83758ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:14:41.311441Z","caller":"traceutil/trace.go:171","msg":"trace[1845632802] transaction","detail":"{read_only:false; response_revision:25659; number_of_response:1; }","duration":"250.885877ms","start":"2026-04-14T05:14:41.060509Z","end":"2026-04-14T05:14:41.311395Z","steps":["trace[1845632802] 'process raft request' (duration: 250.515957ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:14:41.31183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.800449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T05:14:41.311889Z","caller":"traceutil/trace.go:171","msg":"trace[319881610] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0; response_count:0; response_revision:25659; }","duration":"102.155939ms","start":"2026-04-14T05:14:41.209716Z","end":"2026-04-14T05:14:41.311872Z","steps":["trace[319881610] 'agreement among raft nodes before linearized reading' (duration: 101.792389ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:14:41.312507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.130079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-14T05:14:41.312686Z","caller":"traceutil/trace.go:171","msg":"trace[303943096] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25659; }","duration":"155.330685ms","start":"2026-04-14T05:14:41.157283Z","end":"2026-04-14T05:14:41.312614Z","steps":["trace[303943096] 'agreement among raft nodes before linearized reading' (duration: 154.197082ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:14:41.482268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.468645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T05:14:41.482368Z","caller":"traceutil/trace.go:171","msg":"trace[653178661] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25659; }","duration":"168.62427ms","start":"2026-04-14T05:14:41.31372Z","end":"2026-04-14T05:14:41.482344Z","steps":["trace[653178661] 'range keys from in-memory index tree' (duration: 168.395203ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:14:41.482729Z","caller":"traceutil/trace.go:171","msg":"trace[1151146004] transaction","detail":"{read_only:false; response_revision:25660; number_of_response:1; }","duration":"164.886864ms","start":"2026-04-14T05:14:41.317806Z","end":"2026-04-14T05:14:41.482693Z","steps":["trace[1151146004] 'process raft request' (duration: 154.989745ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:14:41.894863Z","caller":"traceutil/trace.go:171","msg":"trace[657762206] transaction","detail":"{read_only:false; response_revision:25661; number_of_response:1; }","duration":"406.874619ms","start":"2026-04-14T05:14:41.487577Z","end":"2026-04-14T05:14:41.894452Z","steps":["trace[657762206] 'process raft request' (duration: 406.709294ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:14:41.895012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:14:41.487541Z","time spent":"407.386563ms","remote":"127.0.0.1:44228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T05:14:41.913419Z","caller":"traceutil/trace.go:171","msg":"trace[622435248] transaction","detail":"{read_only:false; response_revision:25664; number_of_response:1; }","duration":"149.504089ms","start":"2026-04-14T05:14:41.7639Z","end":"2026-04-14T05:14:41.913404Z","steps":["trace[622435248] 'process raft request' (duration: 149.459378ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:14:41.913558Z","caller":"traceutil/trace.go:171","msg":"trace[1878093153] transaction","detail":"{read_only:false; response_revision:25662; number_of_response:1; }","duration":"425.417844ms","start":"2026-04-14T05:14:41.4881Z","end":"2026-04-14T05:14:41.913518Z","steps":["trace[1878093153] 'process raft request' (duration: 425.134477ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:14:41.913751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:14:41.488066Z","time spent":"425.586489ms","remote":"127.0.0.1:44228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T05:14:41.913614Z","caller":"traceutil/trace.go:171","msg":"trace[1321020757] transaction","detail":"{read_only:false; response_revision:25663; number_of_response:1; }","duration":"424.834828ms","start":"2026-04-14T05:14:41.488772Z","end":"2026-04-14T05:14:41.913607Z","steps":["trace[1321020757] 'process raft request' (duration: 424.55798ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:14:41.913901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:14:41.488759Z","time spent":"425.072014ms","remote":"127.0.0.1:44228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T05:14:42.222672Z","caller":"traceutil/trace.go:171","msg":"trace[2104773017] transaction","detail":"{read_only:false; response_revision:25665; number_of_response:1; }","duration":"135.296617ms","start":"2026-04-14T05:14:42.08734Z","end":"2026-04-14T05:14:42.222637Z","steps":["trace[2104773017] 'process raft request' (duration: 134.887216ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:14:42.222718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.06322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/services/\" range_end:\"/registry/openstack.k-orc.cloud/services0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T05:14:42.222834Z","caller":"traceutil/trace.go:171","msg":"trace[1546125831] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/services/; range_end:/registry/openstack.k-orc.cloud/services0; response_count:0; response_revision:25665; }","duration":"100.204114ms","start":"2026-04-14T05:14:42.122599Z","end":"2026-04-14T05:14:42.222804Z","steps":["trace[1546125831] 'agreement among raft nodes before linearized reading' (duration: 100.023609ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:14:42.495651Z","caller":"traceutil/trace.go:171","msg":"trace[13388411] transaction","detail":"{read_only:false; response_revision:25666; number_of_response:1; }","duration":"299.672227ms","start":"2026-04-14T05:14:42.195939Z","end":"2026-04-14T05:14:42.495612Z","steps":["trace[13388411] 'process raft request' (duration: 207.88519ms)","trace[13388411] 'compare' (duration: 91.448427ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:14:42.495834Z","caller":"traceutil/trace.go:171","msg":"trace[1324446447] linearizableReadLoop","detail":"{readStateIndex:26510; appliedIndex:26508; }","duration":"273.223259ms","start":"2026-04-14T05:14:42.222591Z","end":"2026-04-14T05:14:42.495815Z","steps":["trace[1324446447] 'read index received' (duration: 181.320309ms)","trace[1324446447] 'applied index is now lower than readState.Index' (duration: 91.90167ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:14:42.495966Z","caller":"traceutil/trace.go:171","msg":"trace[1477216174] transaction","detail":"{read_only:false; response_revision:25667; number_of_response:1; }","duration":"298.318819ms","start":"2026-04-14T05:14:42.197635Z","end":"2026-04-14T05:14:42.495954Z","steps":["trace[1477216174] 'process raft request' (duration: 297.905698ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:14:42.496293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.894466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:418304"} {"level":"info","ts":"2026-04-14T05:14:42.496367Z","caller":"traceutil/trace.go:171","msg":"trace[2052579122] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:25667; }","duration":"350.997798ms","start":"2026-04-14T05:14:42.145325Z","end":"2026-04-14T05:14:42.496323Z","steps":["trace[2052579122] 'agreement among raft nodes before linearized reading' (duration: 350.719271ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:14:42.496404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:14:42.145298Z","time spent":"351.093901ms","remote":"127.0.0.1:52748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":418327,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" "} {"level":"info","ts":"2026-04-14T05:14:42.498495Z","caller":"traceutil/trace.go:171","msg":"trace[434762816] transaction","detail":"{read_only:false; response_revision:25668; number_of_response:1; }","duration":"240.171054ms","start":"2026-04-14T05:14:42.258301Z","end":"2026-04-14T05:14:42.498472Z","steps":["trace[434762816] 'process raft request' (duration: 239.64902ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:14:42.498725Z","caller":"traceutil/trace.go:171","msg":"trace[1911048894] transaction","detail":"{read_only:false; response_revision:25669; number_of_response:1; }","duration":"187.70624ms","start":"2026-04-14T05:14:42.31099Z","end":"2026-04-14T05:14:42.498696Z","steps":["trace[1911048894] 'process raft request' (duration: 187.132684ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:14:42.681898Z","caller":"traceutil/trace.go:171","msg":"trace[1570891362] transaction","detail":"{read_only:false; response_revision:25670; number_of_response:1; }","duration":"139.815585ms","start":"2026-04-14T05:14:42.542042Z","end":"2026-04-14T05:14:42.681857Z","steps":["trace[1570891362] 'process raft request' (duration: 126.310423ms)","trace[1570891362] 'compare' (duration: 13.08238ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:14:43.152476Z","caller":"traceutil/trace.go:171","msg":"trace[654244276] transaction","detail":"{read_only:false; response_revision:25671; number_of_response:1; }","duration":"301.554369ms","start":"2026-04-14T05:14:42.85089Z","end":"2026-04-14T05:14:43.152445Z","steps":["trace[654244276] 'process raft request' (duration: 301.367554ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:14:43.152697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:14:42.850865Z","time spent":"301.707094ms","remote":"127.0.0.1:44228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T05:14:43.802131Z","caller":"traceutil/trace.go:171","msg":"trace[1806510632] linearizableReadLoop","detail":"{readStateIndex:26515; appliedIndex:26513; }","duration":"813.42606ms","start":"2026-04-14T05:14:42.988672Z","end":"2026-04-14T05:14:43.802098Z","steps":["trace[1806510632] 'read index received' (duration: 163.6702ms)","trace[1806510632] 'applied index is now lower than readState.Index' (duration: 649.75457ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:14:43.802298Z","caller":"traceutil/trace.go:171","msg":"trace[267211464] transaction","detail":"{read_only:false; response_revision:25672; number_of_response:1; }","duration":"885.514809ms","start":"2026-04-14T05:14:42.916731Z","end":"2026-04-14T05:14:43.802245Z","steps":["trace[267211464] 'process raft request' (duration: 884.956643ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:14:43.802439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:14:42.916697Z","time spent":"885.633782ms","remote":"127.0.0.1:52748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418362,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T05:14:43.802419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"813.690167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T05:14:43.802538Z","caller":"traceutil/trace.go:171","msg":"trace[1615442182] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:25672; }","duration":"813.882632ms","start":"2026-04-14T05:14:42.988631Z","end":"2026-04-14T05:14:43.802513Z","steps":["trace[1615442182] 'agreement among raft nodes before linearized reading' (duration: 813.628725ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:14:43.80263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:14:42.988616Z","time spent":"814.001725ms","remote":"127.0.0.1:44194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"info","ts":"2026-04-14T05:14:43.808301Z","caller":"traceutil/trace.go:171","msg":"trace[123574690] transaction","detail":"{read_only:false; response_revision:25673; number_of_response:1; }","duration":"590.101522ms","start":"2026-04-14T05:14:43.218179Z","end":"2026-04-14T05:14:43.80828Z","steps":["trace[123574690] 'process raft request' (duration: 589.854135ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:14:43.80842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:14:43.218152Z","time spent":"590.190814ms","remote":"127.0.0.1:44228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T05:14:43.808705Z","caller":"traceutil/trace.go:171","msg":"trace[83060464] transaction","detail":"{read_only:false; response_revision:25674; number_of_response:1; }","duration":"491.089832ms","start":"2026-04-14T05:14:43.317572Z","end":"2026-04-14T05:14:43.808661Z","steps":["trace[83060464] 'process raft request' (duration: 490.572537ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:14:43.808869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:14:43.31754Z","time spent":"491.263477ms","remote":"127.0.0.1:44228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T05:14:43.810615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.229399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T05:14:43.810701Z","caller":"traceutil/trace.go:171","msg":"trace[1780019072] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25674; }","duration":"606.362282ms","start":"2026-04-14T05:14:43.204312Z","end":"2026-04-14T05:14:43.810674Z","steps":["trace[1780019072] 'agreement among raft nodes before linearized reading' (duration: 606.223488ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:14:43.810752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:14:43.204289Z","time spent":"606.456554ms","remote":"127.0.0.1:43982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-14T05:14:43.810813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.965385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T05:14:43.810884Z","caller":"traceutil/trace.go:171","msg":"trace[1106662871] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0; response_count:0; response_revision:25674; }","duration":"306.061858ms","start":"2026-04-14T05:14:43.504802Z","end":"2026-04-14T05:14:43.810864Z","steps":["trace[1106662871] 'agreement among raft nodes before linearized reading' (duration: 305.942024ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:14:43.810915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.962336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-14T05:14:43.810937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:14:43.50479Z","time spent":"306.133329ms","remote":"127.0.0.1:45666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":142,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0\" count_only:true "} {"level":"warn","ts":"2026-04-14T05:14:43.810915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.955909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-04-14T05:14:43.811004Z","caller":"traceutil/trace.go:171","msg":"trace[187484173] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25674; }","duration":"323.11842ms","start":"2026-04-14T05:14:43.487865Z","end":"2026-04-14T05:14:43.810983Z","steps":["trace[187484173] 'agreement among raft nodes before linearized reading' (duration: 322.826582ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:14:43.81109Z","caller":"traceutil/trace.go:171","msg":"trace[314462900] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25674; }","duration":"356.201566ms","start":"2026-04-14T05:14:43.454872Z","end":"2026-04-14T05:14:43.811074Z","steps":["trace[314462900] 'agreement among raft nodes before linearized reading' (duration: 355.849246ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:14:43.81114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:14:43.45482Z","time spent":"356.308899ms","remote":"127.0.0.1:44158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-14T05:14:43.811158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:14:43.487838Z","time spent":"323.308996ms","remote":"127.0.0.1:44228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-14T05:14:43.812026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.516623ms","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:8"} {"level":"info","ts":"2026-04-14T05:14:43.812076Z","caller":"traceutil/trace.go:171","msg":"trace[154313498] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25674; }","duration":"247.628625ms","start":"2026-04-14T05:14:43.564436Z","end":"2026-04-14T05:14:43.812065Z","steps":["trace[154313498] 'agreement among raft nodes before linearized reading' (duration: 247.488691ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:14:51.297085Z","caller":"traceutil/trace.go:171","msg":"trace[1429678421] transaction","detail":"{read_only:false; response_revision:25736; number_of_response:1; }","duration":"105.892916ms","start":"2026-04-14T05:14:51.19115Z","end":"2026-04-14T05:14:51.297042Z","steps":["trace[1429678421] 'process raft request' (duration: 105.565387ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:14:51.401658Z","caller":"traceutil/trace.go:171","msg":"trace[287060433] linearizableReadLoop","detail":"{readStateIndex:26582; appliedIndex:26581; }","duration":"104.675501ms","start":"2026-04-14T05:14:51.296956Z","end":"2026-04-14T05:14:51.401631Z","steps":["trace[287060433] 'read index received' (duration: 92.158927ms)","trace[287060433] 'applied index is now lower than readState.Index' (duration: 12.515234ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:14:51.401705Z","caller":"traceutil/trace.go:171","msg":"trace[2047810917] transaction","detail":"{read_only:false; response_revision:25737; number_of_response:1; }","duration":"141.124461ms","start":"2026-04-14T05:14:51.260551Z","end":"2026-04-14T05:14:51.401676Z","steps":["trace[2047810917] 'process raft request' (duration: 128.663169ms)","trace[2047810917] 'compare' (duration: 12.056111ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T05:14:51.401849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.362204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T05:14:51.401892Z","caller":"traceutil/trace.go:171","msg":"trace[139915544] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25737; }","duration":"103.471867ms","start":"2026-04-14T05:14:51.29841Z","end":"2026-04-14T05:14:51.401882Z","steps":["trace[139915544] 'agreement among raft nodes before linearized reading' (duration: 103.361704ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:14:51.402032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.117481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T05:14:51.402179Z","caller":"traceutil/trace.go:171","msg":"trace[882693557] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:25737; }","duration":"165.321546ms","start":"2026-04-14T05:14:51.236788Z","end":"2026-04-14T05:14:51.40211Z","steps":["trace[882693557] 'agreement among raft nodes before linearized reading' (duration: 164.960266ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:14:51.992514Z","caller":"traceutil/trace.go:171","msg":"trace[1682017611] linearizableReadLoop","detail":"{readStateIndex:26584; appliedIndex:26583; }","duration":"141.171593ms","start":"2026-04-14T05:14:51.851321Z","end":"2026-04-14T05:14:51.992493Z","steps":["trace[1682017611] 'read index received' (duration: 20.109699ms)","trace[1682017611] 'applied index is now lower than readState.Index' (duration: 121.060424ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T05:14:51.992745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.386399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T05:14:51.992693Z","caller":"traceutil/trace.go:171","msg":"trace[443798812] transaction","detail":"{read_only:false; response_revision:25739; number_of_response:1; }","duration":"145.413384ms","start":"2026-04-14T05:14:51.847196Z","end":"2026-04-14T05:14:51.992609Z","steps":["trace[443798812] 'process raft request' (duration: 145.128996ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:14:51.992792Z","caller":"traceutil/trace.go:171","msg":"trace[1791400018] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:25739; }","duration":"141.502702ms","start":"2026-04-14T05:14:51.851274Z","end":"2026-04-14T05:14:51.992777Z","steps":["trace[1791400018] 'agreement among raft nodes before linearized reading' (duration: 141.340688ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:14:52.287901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.934099ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-14T05:14:52.288035Z","caller":"traceutil/trace.go:171","msg":"trace[1264299235] linearizableReadLoop","detail":"{readStateIndex:26585; appliedIndex:26584; }","duration":"295.429596ms","start":"2026-04-14T05:14:51.992583Z","end":"2026-04-14T05:14:52.288013Z","steps":["trace[1264299235] 'read index received' (duration: 156.205689ms)","trace[1264299235] 'applied index is now lower than readState.Index' (duration: 139.222117ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T05:14:52.288472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.967614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-04-14T05:14:52.288509Z","caller":"traceutil/trace.go:171","msg":"trace[823283815] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25740; }","duration":"348.047616ms","start":"2026-04-14T05:14:51.940452Z","end":"2026-04-14T05:14:52.2885Z","steps":["trace[823283815] 'agreement among raft nodes before linearized reading' (duration: 347.85381ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:14:52.288546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:14:51.940433Z","time spent":"348.103838ms","remote":"127.0.0.1:44228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-14T05:14:52.28852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.425284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-04-14T05:14:52.288653Z","caller":"traceutil/trace.go:171","msg":"trace[23014706] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25740; }","duration":"320.617989ms","start":"2026-04-14T05:14:51.968006Z","end":"2026-04-14T05:14:52.288624Z","steps":["trace[23014706] 'agreement among raft nodes before linearized reading' (duration: 320.29759ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:14:52.288722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:14:51.967976Z","time spent":"320.732673ms","remote":"127.0.0.1:44228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-14T05:14:52.288828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.719329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-14T05:14:52.28887Z","caller":"traceutil/trace.go:171","msg":"trace[1007920099] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25740; }","duration":"433.792251ms","start":"2026-04-14T05:14:51.855067Z","end":"2026-04-14T05:14:52.28886Z","steps":["trace[1007920099] 'agreement among raft nodes before linearized reading' (duration: 433.682218ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:14:52.288904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:14:51.855047Z","time spent":"433.844833ms","remote":"127.0.0.1:44228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-14T05:14:52.289302Z","caller":"traceutil/trace.go:171","msg":"trace[1975500579] transaction","detail":"{read_only:false; response_revision:25740; number_of_response:1; }","duration":"322.465142ms","start":"2026-04-14T05:14:51.966776Z","end":"2026-04-14T05:14:52.289242Z","steps":["trace[1975500579] 'process raft request' (duration: 182.05876ms)","trace[1975500579] 'compare' (duration: 138.698043ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T05:14:52.289357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.646687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-04-14T05:14:52.289417Z","caller":"traceutil/trace.go:171","msg":"trace[2122574211] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25740; }","duration":"316.75395ms","start":"2026-04-14T05:14:51.972645Z","end":"2026-04-14T05:14:52.289399Z","steps":["trace[2122574211] 'agreement among raft nodes before linearized reading' (duration: 316.589755ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:14:52.289473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:14:51.972616Z","time spent":"316.846773ms","remote":"127.0.0.1:44228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-14T05:14:52.289531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:14:51.966748Z","time spent":"322.648307ms","remote":"127.0.0.1:44228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T05:14:52.480259Z","caller":"traceutil/trace.go:171","msg":"trace[1537585166] transaction","detail":"{read_only:false; response_revision:25742; number_of_response:1; }","duration":"184.080417ms","start":"2026-04-14T05:14:52.296144Z","end":"2026-04-14T05:14:52.480224Z","steps":["trace[1537585166] 'process raft request' (duration: 183.997575ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:14:52.480497Z","caller":"traceutil/trace.go:171","msg":"trace[479315061] transaction","detail":"{read_only:false; response_revision:25741; number_of_response:1; }","duration":"184.351165ms","start":"2026-04-14T05:14:52.296119Z","end":"2026-04-14T05:14:52.48047Z","steps":["trace[479315061] 'process raft request' (duration: 183.790749ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:14:52.816717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.16228ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-14T05:14:52.817248Z","caller":"traceutil/trace.go:171","msg":"trace[1013334877] transaction","detail":"{read_only:false; response_revision:25743; number_of_response:1; }","duration":"520.72563ms","start":"2026-04-14T05:14:52.29649Z","end":"2026-04-14T05:14:52.817216Z","steps":["trace[1013334877] 'process raft request' (duration: 359.85139ms)","trace[1013334877] 'compare' (duration: 159.925404ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:14:52.817347Z","caller":"traceutil/trace.go:171","msg":"trace[517422379] transaction","detail":"{read_only:false; response_revision:25745; number_of_response:1; }","duration":"519.7052ms","start":"2026-04-14T05:14:52.29761Z","end":"2026-04-14T05:14:52.817316Z","steps":["trace[517422379] 'process raft request' (duration: 519.32996ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:14:52.817337Z","caller":"traceutil/trace.go:171","msg":"trace[1476071198] transaction","detail":"{read_only:false; response_revision:25744; number_of_response:1; }","duration":"520.188034ms","start":"2026-04-14T05:14:52.297111Z","end":"2026-04-14T05:14:52.817299Z","steps":["trace[1476071198] 'process raft request' (duration: 519.745151ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:14:52.817408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:14:52.29647Z","time spent":"520.842053ms","remote":"127.0.0.1:44228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T05:14:52.817506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:14:52.297587Z","time spent":"519.824344ms","remote":"127.0.0.1:44228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T05:14:52.817501Z","caller":"traceutil/trace.go:171","msg":"trace[2124411541] transaction","detail":"{read_only:false; response_revision:25746; number_of_response:1; }","duration":"519.093223ms","start":"2026-04-14T05:14:52.29837Z","end":"2026-04-14T05:14:52.817463Z","steps":["trace[2124411541] 'process raft request' (duration: 518.6124ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:14:52.817525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:14:52.297099Z","time spent":"520.323868ms","remote":"127.0.0.1:44228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T05:14:52.817675Z","caller":"traceutil/trace.go:171","msg":"trace[1994161430] transaction","detail":"{read_only:false; response_revision:25748; number_of_response:1; }","duration":"279.301961ms","start":"2026-04-14T05:14:52.53835Z","end":"2026-04-14T05:14:52.817652Z","steps":["trace[1994161430] 'process raft request' (duration: 279.200798ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:14:52.817703Z","caller":"traceutil/trace.go:171","msg":"trace[783353449] transaction","detail":"{read_only:false; response_revision:25747; number_of_response:1; }","duration":"280.096633ms","start":"2026-04-14T05:14:52.537593Z","end":"2026-04-14T05:14:52.81769Z","steps":["trace[783353449] 'process raft request' (duration: 279.861406ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:14:52.817706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:14:52.298362Z","time spent":"519.222278ms","remote":"127.0.0.1:44228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T05:14:52.817852Z","caller":"traceutil/trace.go:171","msg":"trace[926521297] transaction","detail":"{read_only:false; response_revision:25749; number_of_response:1; }","duration":"276.710978ms","start":"2026-04-14T05:14:52.541126Z","end":"2026-04-14T05:14:52.817837Z","steps":["trace[926521297] 'process raft request' (duration: 276.48069ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:14:52.817901Z","caller":"traceutil/trace.go:171","msg":"trace[776695576] linearizableReadLoop","detail":"{readStateIndex:26594; appliedIndex:26587; }","duration":"109.611662ms","start":"2026-04-14T05:14:52.708276Z","end":"2026-04-14T05:14:52.817888Z","steps":["trace[776695576] 'read index received' (duration: 52.242µs)","trace[776695576] 'applied index is now lower than readState.Index' (duration: 109.55828ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T05:14:52.817988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.713595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T05:14:52.818016Z","caller":"traceutil/trace.go:171","msg":"trace[1500362531] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:25750; }","duration":"109.780997ms","start":"2026-04-14T05:14:52.708226Z","end":"2026-04-14T05:14:52.818007Z","steps":["trace[1500362531] 'agreement among raft nodes before linearized reading' (duration: 109.705834ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:14:53.411925Z","caller":"traceutil/trace.go:171","msg":"trace[1878961699] transaction","detail":"{read_only:false; response_revision:25751; number_of_response:1; }","duration":"109.104926ms","start":"2026-04-14T05:14:53.302791Z","end":"2026-04-14T05:14:53.411896Z","steps":["trace[1878961699] 'process raft request' (duration: 108.903231ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:14:54.149927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.697135ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-14T05:14:54.150626Z","caller":"traceutil/trace.go:171","msg":"trace[681783088] transaction","detail":"{read_only:false; response_revision:25752; number_of_response:1; }","duration":"271.660735ms","start":"2026-04-14T05:14:53.878873Z","end":"2026-04-14T05:14:54.150534Z","steps":["trace[681783088] 'process raft request' (duration: 271.188922ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:14:54.294315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.651014ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-14T05:14:54.294431Z","caller":"traceutil/trace.go:171","msg":"trace[1893175919] linearizableReadLoop","detail":"{readStateIndex:26599; appliedIndex:26596; }","duration":"185.642323ms","start":"2026-04-14T05:14:54.108769Z","end":"2026-04-14T05:14:54.294412Z","steps":["trace[1893175919] 'read index received' (duration: 31.072799ms)","trace[1893175919] 'applied index is now lower than readState.Index' (duration: 154.567864ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T05:14:54.294725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.951411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-04-14T05:14:54.294827Z","caller":"traceutil/trace.go:171","msg":"trace[2131883199] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25753; }","duration":"186.092505ms","start":"2026-04-14T05:14:54.108721Z","end":"2026-04-14T05:14:54.294813Z","steps":["trace[2131883199] 'agreement among raft nodes before linearized reading' (duration: 185.742855ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:14:54.2947Z","caller":"traceutil/trace.go:171","msg":"trace[1668186040] transaction","detail":"{read_only:false; response_revision:25753; number_of_response:1; }","duration":"293.011919ms","start":"2026-04-14T05:14:54.001629Z","end":"2026-04-14T05:14:54.294641Z","steps":["trace[1668186040] 'process raft request' (duration: 148.893512ms)","trace[1668186040] 'compare' (duration: 143.481898ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:14:54.712208Z","caller":"traceutil/trace.go:171","msg":"trace[356301734] linearizableReadLoop","detail":"{readStateIndex:26601; appliedIndex:26600; }","duration":"147.322877ms","start":"2026-04-14T05:14:54.564831Z","end":"2026-04-14T05:14:54.712154Z","steps":["trace[356301734] 'read index received' (duration: 147.04227ms)","trace[356301734] 'applied index is now lower than readState.Index' (duration: 278.527µs)"],"step_count":2} {"level":"warn","ts":"2026-04-14T05:14:54.712633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.75429ms","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:8"} {"level":"info","ts":"2026-04-14T05:14:54.712743Z","caller":"traceutil/trace.go:171","msg":"trace[903437836] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25755; }","duration":"147.945425ms","start":"2026-04-14T05:14:54.56477Z","end":"2026-04-14T05:14:54.712716Z","steps":["trace[903437836] 'agreement among raft nodes before linearized reading' (duration: 147.680978ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:14:54.713074Z","caller":"traceutil/trace.go:171","msg":"trace[71608446] transaction","detail":"{read_only:false; response_revision:25755; number_of_response:1; }","duration":"223.790581ms","start":"2026-04-14T05:14:54.489264Z","end":"2026-04-14T05:14:54.713055Z","steps":["trace[71608446] 'process raft request' (duration: 222.647769ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:14:55.046377Z","caller":"traceutil/trace.go:171","msg":"trace[1261846459] transaction","detail":"{read_only:false; response_revision:25756; number_of_response:1; }","duration":"397.125084ms","start":"2026-04-14T05:14:54.649206Z","end":"2026-04-14T05:14:55.046331Z","steps":["trace[1261846459] 'process raft request' (duration: 396.414124ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:14:55.046408Z","caller":"traceutil/trace.go:171","msg":"trace[897966037] linearizableReadLoop","detail":"{readStateIndex:26602; appliedIndex:26601; }","duration":"333.900945ms","start":"2026-04-14T05:14:54.71247Z","end":"2026-04-14T05:14:55.046371Z","steps":["trace[897966037] 'read index received' (duration: 333.660398ms)","trace[897966037] 'applied index is now lower than readState.Index' (duration: 238.837µs)"],"step_count":2} {"level":"warn","ts":"2026-04-14T05:14:55.046614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:14:54.64917Z","time spent":"397.304589ms","remote":"127.0.0.1:44228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T05:14:55.04677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.127543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-14T05:14:55.04683Z","caller":"traceutil/trace.go:171","msg":"trace[1822314622] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:25757; }","duration":"368.275817ms","start":"2026-04-14T05:14:54.678537Z","end":"2026-04-14T05:14:55.046812Z","steps":["trace[1822314622] 'agreement among raft nodes before linearized reading' (duration: 368.087222ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:14:55.046874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:14:54.678496Z","time spent":"368.36601ms","remote":"127.0.0.1:44228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":19,"response size":31,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"info","ts":"2026-04-14T05:14:55.047272Z","caller":"traceutil/trace.go:171","msg":"trace[1883549455] transaction","detail":"{read_only:false; response_revision:25757; number_of_response:1; }","duration":"223.983526ms","start":"2026-04-14T05:14:54.823252Z","end":"2026-04-14T05:14:55.047235Z","steps":["trace[1883549455] 'process raft request' (duration: 223.068031ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:14:55.047375Z","caller":"traceutil/trace.go:171","msg":"trace[244514471] transaction","detail":"{read_only:false; response_revision:25758; number_of_response:1; }","duration":"223.195673ms","start":"2026-04-14T05:14:54.824158Z","end":"2026-04-14T05:14:55.047354Z","steps":["trace[244514471] 'process raft request' (duration: 222.608606ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:14:55.048014Z","caller":"traceutil/trace.go:171","msg":"trace[730177055] transaction","detail":"{read_only:false; response_revision:25759; number_of_response:1; }","duration":"223.138313ms","start":"2026-04-14T05:14:54.824855Z","end":"2026-04-14T05:14:55.047994Z","steps":["trace[730177055] 'process raft request' (duration: 222.36296ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:14:55.048441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.573419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-04-14T05:14:55.04856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.75775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-04-14T05:14:55.048661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.402767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-14T05:14:55.048698Z","caller":"traceutil/trace.go:171","msg":"trace[1673339348] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25760; }","duration":"223.915014ms","start":"2026-04-14T05:14:54.824761Z","end":"2026-04-14T05:14:55.048676Z","steps":["trace[1673339348] 'agreement among raft nodes before linearized reading' (duration: 223.608585ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:14:55.048783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.52707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-04-14T05:14:55.048825Z","caller":"traceutil/trace.go:171","msg":"trace[1238151096] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25760; }","duration":"225.609883ms","start":"2026-04-14T05:14:54.823205Z","end":"2026-04-14T05:14:55.048815Z","steps":["trace[1238151096] 'agreement among raft nodes before linearized reading' (duration: 225.493489ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:14:55.048713Z","caller":"traceutil/trace.go:171","msg":"trace[1086320362] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25760; }","duration":"225.497789ms","start":"2026-04-14T05:14:54.823202Z","end":"2026-04-14T05:14:55.0487Z","steps":["trace[1086320362] 'agreement among raft nodes before linearized reading' (duration: 225.317004ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:14:55.04866Z","caller":"traceutil/trace.go:171","msg":"trace[1243823610] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25760; }","duration":"226.781265ms","start":"2026-04-14T05:14:54.821799Z","end":"2026-04-14T05:14:55.04858Z","steps":["trace[1243823610] 'agreement among raft nodes before linearized reading' (duration: 226.389984ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:14:55.048863Z","caller":"traceutil/trace.go:171","msg":"trace[1947565959] transaction","detail":"{read_only:false; response_revision:25760; number_of_response:1; }","duration":"223.819401ms","start":"2026-04-14T05:14:54.824991Z","end":"2026-04-14T05:14:55.048811Z","steps":["trace[1947565959] 'process raft request' (duration: 222.923026ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:14:55.227323Z","caller":"traceutil/trace.go:171","msg":"trace[1850933466] transaction","detail":"{read_only:false; response_revision:25763; number_of_response:1; }","duration":"173.510408ms","start":"2026-04-14T05:14:55.053789Z","end":"2026-04-14T05:14:55.227299Z","steps":["trace[1850933466] 'process raft request' (duration: 173.431685ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:14:55.227334Z","caller":"traceutil/trace.go:171","msg":"trace[697245973] transaction","detail":"{read_only:false; response_revision:25762; number_of_response:1; }","duration":"173.670442ms","start":"2026-04-14T05:14:55.053619Z","end":"2026-04-14T05:14:55.227289Z","steps":["trace[697245973] 'process raft request' (duration: 173.450796ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:14:55.227748Z","caller":"traceutil/trace.go:171","msg":"trace[1171258141] transaction","detail":"{read_only:false; response_revision:25764; number_of_response:1; }","duration":"173.884318ms","start":"2026-04-14T05:14:55.053839Z","end":"2026-04-14T05:14:55.227723Z","steps":["trace[1171258141] 'process raft request' (duration: 173.424146ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:15:01.334016Z","caller":"traceutil/trace.go:171","msg":"trace[1563045596] transaction","detail":"{read_only:false; response_revision:25816; number_of_response:1; }","duration":"262.30436ms","start":"2026-04-14T05:15:01.071656Z","end":"2026-04-14T05:15:01.333961Z","steps":["trace[1563045596] 'process raft request' (duration: 262.060002ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:15:01.938785Z","caller":"traceutil/trace.go:171","msg":"trace[1817615427] transaction","detail":"{read_only:false; response_revision:25817; number_of_response:1; }","duration":"864.083422ms","start":"2026-04-14T05:15:01.074637Z","end":"2026-04-14T05:15:01.93872Z","steps":["trace[1817615427] 'process raft request' (duration: 859.779161ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:15:01.938976Z","caller":"traceutil/trace.go:171","msg":"trace[1261985015] linearizableReadLoop","detail":"{readStateIndex:26666; appliedIndex:26662; }","duration":"854.623985ms","start":"2026-04-14T05:15:01.084331Z","end":"2026-04-14T05:15:01.938955Z","steps":["trace[1261985015] 'read index received' (duration: 249.364334ms)","trace[1261985015] 'applied index is now lower than readState.Index' (duration: 605.258441ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T05:15:01.939028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:15:01.074622Z","time spent":"864.261777ms","remote":"127.0.0.1:44228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T05:15:01.939074Z","caller":"traceutil/trace.go:171","msg":"trace[1282027852] transaction","detail":"{read_only:false; response_revision:25818; number_of_response:1; }","duration":"863.535968ms","start":"2026-04-14T05:15:01.075529Z","end":"2026-04-14T05:15:01.939065Z","steps":["trace[1282027852] 'process raft request' (duration: 863.128926ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:15:01.939174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:15:01.075514Z","time spent":"863.580239ms","remote":"127.0.0.1:44228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T05:15:01.939262Z","caller":"traceutil/trace.go:171","msg":"trace[1238139515] transaction","detail":"{read_only:false; response_revision:25819; number_of_response:1; }","duration":"854.947355ms","start":"2026-04-14T05:15:01.084277Z","end":"2026-04-14T05:15:01.939224Z","steps":["trace[1238139515] 'process raft request' (duration: 854.582294ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:15:01.93933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"854.997416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-04-14T05:15:01.939352Z","caller":"traceutil/trace.go:171","msg":"trace[1575155231] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25819; }","duration":"855.053128ms","start":"2026-04-14T05:15:01.084291Z","end":"2026-04-14T05:15:01.939344Z","steps":["trace[1575155231] 'agreement among raft nodes before linearized reading' (duration: 854.959275ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:15:01.93937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:15:01.084277Z","time spent":"855.089389ms","remote":"127.0.0.1:44228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-14T05:15:01.939434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:15:01.084262Z","time spent":"855.073389ms","remote":"127.0.0.1:44228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T05:15:01.951727Z","caller":"traceutil/trace.go:171","msg":"trace[1745838537] transaction","detail":"{read_only:false; response_revision:25820; number_of_response:1; }","duration":"504.852341ms","start":"2026-04-14T05:15:01.446858Z","end":"2026-04-14T05:15:01.95171Z","steps":["trace[1745838537] 'process raft request' (duration: 504.716217ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:15:01.951853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:15:01.446828Z","time spent":"504.969455ms","remote":"127.0.0.1:44228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T05:15:01.951957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.977655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-04-14T05:15:01.951996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"747.201627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T05:15:01.952067Z","caller":"traceutil/trace.go:171","msg":"trace[673472708] transaction","detail":"{read_only:false; response_revision:25821; number_of_response:1; }","duration":"431.98956ms","start":"2026-04-14T05:15:01.520061Z","end":"2026-04-14T05:15:01.95205Z","steps":["trace[673472708] 'process raft request' (duration: 431.611529ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:15:01.952105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.970794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-14T05:15:01.952129Z","caller":"traceutil/trace.go:171","msg":"trace[61925602] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25821; }","duration":"686.054537ms","start":"2026-04-14T05:15:01.266069Z","end":"2026-04-14T05:15:01.952124Z","steps":["trace[61925602] 'agreement among raft nodes before linearized reading' (duration: 685.980845ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:15:01.952142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:15:01.520034Z","time spent":"432.077572ms","remote":"127.0.0.1:44228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T05:15:01.952166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:15:01.266031Z","time spent":"686.117568ms","remote":"127.0.0.1:44228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-14T05:15:01.952071Z","caller":"traceutil/trace.go:171","msg":"trace[968044540] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25821; }","duration":"747.31562ms","start":"2026-04-14T05:15:01.20474Z","end":"2026-04-14T05:15:01.952056Z","steps":["trace[968044540] 'agreement among raft nodes before linearized reading' (duration: 747.203577ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:15:01.952187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.157639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-04-14T05:15:01.952214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.231829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T05:15:01.952468Z","caller":"traceutil/trace.go:171","msg":"trace[165124522] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:25821; }","duration":"606.62653ms","start":"2026-04-14T05:15:01.345817Z","end":"2026-04-14T05:15:01.952444Z","steps":["trace[165124522] 'agreement among raft nodes before linearized reading' (duration: 606.145866ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:15:01.95274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:15:01.345802Z","time spent":"606.920557ms","remote":"127.0.0.1:32928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" count_only:true "} {"level":"warn","ts":"2026-04-14T05:15:01.952278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:15:01.204717Z","time spent":"747.524016ms","remote":"127.0.0.1:43982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-14T05:15:01.95236Z","caller":"traceutil/trace.go:171","msg":"trace[537744146] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25821; }","duration":"686.399027ms","start":"2026-04-14T05:15:01.265932Z","end":"2026-04-14T05:15:01.952331Z","steps":["trace[537744146] 'agreement among raft nodes before linearized reading' (duration: 685.927103ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:15:01.953319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:15:01.265912Z","time spent":"687.362914ms","remote":"127.0.0.1:44228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-14T05:15:01.951967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"832.345015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/servers/\" range_end:\"/registry/openstack.k-orc.cloud/servers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T05:15:01.953634Z","caller":"traceutil/trace.go:171","msg":"trace[1955222085] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/servers/; range_end:/registry/openstack.k-orc.cloud/servers0; response_count:0; response_revision:25821; }","duration":"834.068114ms","start":"2026-04-14T05:15:01.119523Z","end":"2026-04-14T05:15:01.953591Z","steps":["trace[1955222085] 'agreement among raft nodes before linearized reading' (duration: 832.312164ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:15:01.953686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:15:01.119501Z","time spent":"834.167987ms","remote":"127.0.0.1:53952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/servers/\" range_end:\"/registry/openstack.k-orc.cloud/servers0\" count_only:true "} {"level":"warn","ts":"2026-04-14T05:15:01.952003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.806634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T05:15:01.953855Z","caller":"traceutil/trace.go:171","msg":"trace[1198456710] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:25821; }","duration":"387.707367ms","start":"2026-04-14T05:15:01.566121Z","end":"2026-04-14T05:15:01.953829Z","steps":["trace[1198456710] 'agreement among raft nodes before linearized reading' (duration: 385.829294ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:15:01.953895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:15:01.566083Z","time spent":"387.79966ms","remote":"127.0.0.1:57782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"info","ts":"2026-04-14T05:15:01.952024Z","caller":"traceutil/trace.go:171","msg":"trace[291611502] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25821; }","duration":"685.079059ms","start":"2026-04-14T05:15:01.266927Z","end":"2026-04-14T05:15:01.952006Z","steps":["trace[291611502] 'agreement among raft nodes before linearized reading' (duration: 684.905354ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:15:01.954055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:15:01.266918Z","time spent":"687.128866ms","remote":"127.0.0.1:44228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-14T05:15:02.495763Z","caller":"traceutil/trace.go:171","msg":"trace[508183855] linearizableReadLoop","detail":"{readStateIndex:26672; appliedIndex:26670; }","duration":"393.988664ms","start":"2026-04-14T05:15:02.101743Z","end":"2026-04-14T05:15:02.495731Z","steps":["trace[508183855] 'read index received' (duration: 393.723137ms)","trace[508183855] 'applied index is now lower than readState.Index' (duration: 264.597µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:15:02.495853Z","caller":"traceutil/trace.go:171","msg":"trace[918810255] transaction","detail":"{read_only:false; response_revision:25824; number_of_response:1; }","duration":"538.337378ms","start":"2026-04-14T05:15:01.957463Z","end":"2026-04-14T05:15:02.495801Z","steps":["trace[918810255] 'process raft request' (duration: 537.965358ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:15:02.495942Z","caller":"traceutil/trace.go:171","msg":"trace[1829767046] transaction","detail":"{read_only:false; response_revision:25825; number_of_response:1; }","duration":"538.056601ms","start":"2026-04-14T05:15:01.957831Z","end":"2026-04-14T05:15:02.495887Z","steps":["trace[1829767046] 'process raft request' (duration: 537.852255ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:15:02.496064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.273802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-04-14T05:15:02.496155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:15:01.957824Z","time spent":"538.223505ms","remote":"127.0.0.1:44228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T05:15:02.496182Z","caller":"traceutil/trace.go:171","msg":"trace[1630525760] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:25825; }","duration":"394.430767ms","start":"2026-04-14T05:15:02.101728Z","end":"2026-04-14T05:15:02.496158Z","steps":["trace[1630525760] 'agreement among raft nodes before linearized reading' (duration: 394.1866ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:15:02.496104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:15:01.957436Z","time spent":"538.521484ms","remote":"127.0.0.1:44228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T05:15:02.496239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:15:02.101669Z","time spent":"394.55837ms","remote":"127.0.0.1:57928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":31,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-04-14T05:15:02.530897Z","caller":"traceutil/trace.go:171","msg":"trace[1894281765] transaction","detail":"{read_only:false; response_revision:25826; number_of_response:1; }","duration":"353.924142ms","start":"2026-04-14T05:15:02.176931Z","end":"2026-04-14T05:15:02.530855Z","steps":["trace[1894281765] 'process raft request' (duration: 352.696697ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:15:02.530994Z","caller":"traceutil/trace.go:171","msg":"trace[379068721] transaction","detail":"{read_only:false; response_revision:25827; number_of_response:1; }","duration":"207.077088ms","start":"2026-04-14T05:15:02.323879Z","end":"2026-04-14T05:15:02.530956Z","steps":["trace[379068721] 'process raft request' (duration: 206.875452ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:15:02.531114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:15:02.176898Z","time spent":"354.095387ms","remote":"127.0.0.1:44228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T05:15:02.531145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.871418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T05:15:02.531224Z","caller":"traceutil/trace.go:171","msg":"trace[2054711221] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25828; }","duration":"326.006771ms","start":"2026-04-14T05:15:02.2052Z","end":"2026-04-14T05:15:02.531207Z","steps":["trace[2054711221] 'agreement among raft nodes before linearized reading' (duration: 325.829616ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:15:02.531253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:15:02.205186Z","time spent":"326.062313ms","remote":"127.0.0.1:43982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-14T05:15:02.531261Z","caller":"traceutil/trace.go:171","msg":"trace[940078568] transaction","detail":"{read_only:false; response_revision:25828; number_of_response:1; }","duration":"195.969384ms","start":"2026-04-14T05:15:02.335278Z","end":"2026-04-14T05:15:02.531247Z","steps":["trace[940078568] 'process raft request' (duration: 195.620204ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:15:03.111906Z","caller":"traceutil/trace.go:171","msg":"trace[1473527046] linearizableReadLoop","detail":"{readStateIndex:26676; appliedIndex:26675; }","duration":"263.996058ms","start":"2026-04-14T05:15:02.84788Z","end":"2026-04-14T05:15:03.111876Z","steps":["trace[1473527046] 'read index received' (duration: 263.849034ms)","trace[1473527046] 'applied index is now lower than readState.Index' (duration: 145.634µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:15:03.112021Z","caller":"traceutil/trace.go:171","msg":"trace[2048732209] transaction","detail":"{read_only:false; response_revision:25829; number_of_response:1; }","duration":"288.150181ms","start":"2026-04-14T05:15:02.823824Z","end":"2026-04-14T05:15:03.111974Z","steps":["trace[2048732209] 'process raft request' (duration: 287.863753ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:15:03.112159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.242234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T05:15:03.112246Z","caller":"traceutil/trace.go:171","msg":"trace[1622894338] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:25829; }","duration":"264.399068ms","start":"2026-04-14T05:15:02.847825Z","end":"2026-04-14T05:15:03.112224Z","steps":["trace[1622894338] 'agreement among raft nodes before linearized reading' (duration: 264.195703ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:15:03.331046Z","caller":"traceutil/trace.go:171","msg":"trace[219318056] linearizableReadLoop","detail":"{readStateIndex:26677; appliedIndex:26676; }","duration":"219.032036ms","start":"2026-04-14T05:15:03.11199Z","end":"2026-04-14T05:15:03.331022Z","steps":["trace[219318056] 'read index received' (duration: 218.797419ms)","trace[219318056] 'applied index is now lower than readState.Index' (duration: 233.617µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:15:03.33135Z","caller":"traceutil/trace.go:171","msg":"trace[528795285] transaction","detail":"{read_only:false; response_revision:25830; number_of_response:1; }","duration":"292.015621ms","start":"2026-04-14T05:15:03.039322Z","end":"2026-04-14T05:15:03.331338Z","steps":["trace[528795285] 'process raft request' (duration: 291.546967ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:15:03.33173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.340936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-04-14T05:15:03.331769Z","caller":"traceutil/trace.go:171","msg":"trace[1492552560] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:25830; }","duration":"312.460609ms","start":"2026-04-14T05:15:03.0193Z","end":"2026-04-14T05:15:03.33176Z","steps":["trace[1492552560] 'agreement among raft nodes before linearized reading' (duration: 312.220322ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:15:03.331796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:15:03.019278Z","time spent":"312.510051ms","remote":"127.0.0.1:44100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":142,"response size":32,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-04-14T05:15:03.331969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.900053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T05:15:03.331999Z","caller":"traceutil/trace.go:171","msg":"trace[927158725] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:25830; }","duration":"212.974885ms","start":"2026-04-14T05:15:03.119015Z","end":"2026-04-14T05:15:03.33199Z","steps":["trace[927158725] 'agreement among raft nodes before linearized reading' (duration: 212.926293ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:15:03.332193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.186995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T05:15:03.332219Z","caller":"traceutil/trace.go:171","msg":"trace[339776111] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25830; }","duration":"123.265067ms","start":"2026-04-14T05:15:03.208948Z","end":"2026-04-14T05:15:03.332213Z","steps":["trace[339776111] 'agreement among raft nodes before linearized reading' (duration: 123.216705ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:15:06.235816Z","caller":"traceutil/trace.go:171","msg":"trace[1900460010] transaction","detail":"{read_only:false; response_revision:25852; number_of_response:1; }","duration":"280.600268ms","start":"2026-04-14T05:15:05.955187Z","end":"2026-04-14T05:15:06.235788Z","steps":["trace[1900460010] 'process raft request' (duration: 280.498115ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:15:06.235863Z","caller":"traceutil/trace.go:171","msg":"trace[1507433865] transaction","detail":"{read_only:false; response_revision:25851; number_of_response:1; }","duration":"280.893236ms","start":"2026-04-14T05:15:05.954928Z","end":"2026-04-14T05:15:06.235821Z","steps":["trace[1507433865] 'process raft request' (duration: 213.18551ms)","trace[1507433865] 'compare' (duration: 67.14279ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:15:06.236228Z","caller":"traceutil/trace.go:171","msg":"trace[1992919325] linearizableReadLoop","detail":"{readStateIndex:26701; appliedIndex:26697; }","duration":"270.206273ms","start":"2026-04-14T05:15:05.966012Z","end":"2026-04-14T05:15:06.236218Z","steps":["trace[1992919325] 'read index received' (duration: 10.866157ms)","trace[1992919325] 'applied index is now lower than readState.Index' (duration: 259.338966ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T05:15:06.236426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.397189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-04-14T05:15:06.236497Z","caller":"traceutil/trace.go:171","msg":"trace[319872352] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25853; }","duration":"270.528713ms","start":"2026-04-14T05:15:05.965958Z","end":"2026-04-14T05:15:06.236487Z","steps":["trace[319872352] 'agreement among raft nodes before linearized reading' (duration: 270.335337ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:15:06.236652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.73908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-14T05:15:06.236673Z","caller":"traceutil/trace.go:171","msg":"trace[406650013] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25853; }","duration":"240.806372ms","start":"2026-04-14T05:15:05.995859Z","end":"2026-04-14T05:15:06.236666Z","steps":["trace[406650013] 'agreement among raft nodes before linearized reading' (duration: 240.743ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:15:06.236783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.706992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T05:15:06.236806Z","caller":"traceutil/trace.go:171","msg":"trace[1559252361] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:0; response_revision:25853; }","duration":"104.768375ms","start":"2026-04-14T05:15:06.132028Z","end":"2026-04-14T05:15:06.236796Z","steps":["trace[1559252361] 'agreement among raft nodes before linearized reading' (duration: 104.700453ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:15:06.237365Z","caller":"traceutil/trace.go:171","msg":"trace[1044007525] transaction","detail":"{read_only:false; response_revision:25853; number_of_response:1; }","duration":"274.476905ms","start":"2026-04-14T05:15:05.962818Z","end":"2026-04-14T05:15:06.237295Z","steps":["trace[1044007525] 'process raft request' (duration: 272.931001ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:15:09.504092Z","caller":"traceutil/trace.go:171","msg":"trace[2034602303] transaction","detail":"{read_only:false; response_revision:25945; number_of_response:1; }","duration":"126.079717ms","start":"2026-04-14T05:15:09.377971Z","end":"2026-04-14T05:15:09.504051Z","steps":["trace[2034602303] 'process raft request' (duration: 125.8539ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:15:11.263042Z","caller":"traceutil/trace.go:171","msg":"trace[1983227151] transaction","detail":"{read_only:false; response_revision:25970; number_of_response:1; }","duration":"104.176836ms","start":"2026-04-14T05:15:11.158825Z","end":"2026-04-14T05:15:11.263002Z","steps":["trace[1983227151] 'process raft request' (duration: 103.94391ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:15:36.479389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.085802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T05:15:36.479617Z","caller":"traceutil/trace.go:171","msg":"trace[629333621] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26201; }","duration":"275.350848ms","start":"2026-04-14T05:15:36.204234Z","end":"2026-04-14T05:15:36.479585Z","steps":["trace[629333621] 'range keys from in-memory index tree' (duration: 274.988009ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:15:36.480015Z","caller":"traceutil/trace.go:171","msg":"trace[85941392] transaction","detail":"{read_only:false; response_revision:26202; number_of_response:1; }","duration":"121.354412ms","start":"2026-04-14T05:15:36.358625Z","end":"2026-04-14T05:15:36.47998Z","steps":["trace[85941392] 'process raft request' (duration: 107.742348ms)","trace[85941392] 'compare' (duration: 12.852083ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:15:36.659569Z","caller":"traceutil/trace.go:171","msg":"trace[1556127881] linearizableReadLoop","detail":"{readStateIndex:27059; appliedIndex:27056; }","duration":"265.15157ms","start":"2026-04-14T05:15:36.394376Z","end":"2026-04-14T05:15:36.659527Z","steps":["trace[1556127881] 'read index received' (duration: 72.13305ms)","trace[1556127881] 'applied index is now lower than readState.Index' (duration: 193.01615ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:15:36.659785Z","caller":"traceutil/trace.go:171","msg":"trace[1298004418] transaction","detail":"{read_only:false; response_revision:26204; number_of_response:1; }","duration":"297.595189ms","start":"2026-04-14T05:15:36.362138Z","end":"2026-04-14T05:15:36.659734Z","steps":["trace[1298004418] 'process raft request' (duration: 297.225178ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:15:36.659917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.518391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3308"} {"level":"info","ts":"2026-04-14T05:15:36.659746Z","caller":"traceutil/trace.go:171","msg":"trace[1950981286] transaction","detail":"{read_only:false; response_revision:26203; number_of_response:1; }","duration":"297.97274ms","start":"2026-04-14T05:15:36.361748Z","end":"2026-04-14T05:15:36.659721Z","steps":["trace[1950981286] 'process raft request' (duration: 249.499018ms)","trace[1950981286] 'compare' (duration: 47.910005ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:15:36.660023Z","caller":"traceutil/trace.go:171","msg":"trace[228994617] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:26204; }","duration":"265.638314ms","start":"2026-04-14T05:15:36.394358Z","end":"2026-04-14T05:15:36.659997Z","steps":["trace[228994617] 'agreement among raft nodes before linearized reading' (duration: 265.366147ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:15:36.660292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.309613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T05:15:36.66044Z","caller":"traceutil/trace.go:171","msg":"trace[685451400] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26204; }","duration":"179.504417ms","start":"2026-04-14T05:15:36.480919Z","end":"2026-04-14T05:15:36.660423Z","steps":["trace[685451400] 'agreement among raft nodes before linearized reading' (duration: 179.288001ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:15:36.660909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.524829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-04-14T05:15:36.66095Z","caller":"traceutil/trace.go:171","msg":"trace[1940699943] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26204; }","duration":"149.655023ms","start":"2026-04-14T05:15:36.511287Z","end":"2026-04-14T05:15:36.660942Z","steps":["trace[1940699943] 'agreement among raft nodes before linearized reading' (duration: 149.441697ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:15:36.660471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.852994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-14T05:15:36.661067Z","caller":"traceutil/trace.go:171","msg":"trace[1461929928] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26204; }","duration":"147.468912ms","start":"2026-04-14T05:15:36.513582Z","end":"2026-04-14T05:15:36.661051Z","steps":["trace[1461929928] 'agreement among raft nodes before linearized reading' (duration: 146.791433ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:15:36.987692Z","caller":"traceutil/trace.go:171","msg":"trace[1723917115] transaction","detail":"{read_only:false; response_revision:26207; number_of_response:1; }","duration":"309.625619ms","start":"2026-04-14T05:15:36.67803Z","end":"2026-04-14T05:15:36.987656Z","steps":["trace[1723917115] 'process raft request' (duration: 309.527016ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:15:36.987747Z","caller":"traceutil/trace.go:171","msg":"trace[973065804] transaction","detail":"{read_only:false; response_revision:26206; number_of_response:1; }","duration":"321.922787ms","start":"2026-04-14T05:15:36.665784Z","end":"2026-04-14T05:15:36.987707Z","steps":["trace[973065804] 'process raft request' (duration: 321.508725ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:15:36.987938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:15:36.678019Z","time spent":"309.784713ms","remote":"127.0.0.1:44228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T05:15:36.987965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:15:36.665764Z","time spent":"322.075511ms","remote":"127.0.0.1:44228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T05:15:36.988065Z","caller":"traceutil/trace.go:171","msg":"trace[1653768181] linearizableReadLoop","detail":"{readStateIndex:27061; appliedIndex:27059; }","duration":"320.836567ms","start":"2026-04-14T05:15:36.667199Z","end":"2026-04-14T05:15:36.988036Z","steps":["trace[1653768181] 'read index received' (duration: 29.861266ms)","trace[1653768181] 'applied index is now lower than readState.Index' (duration: 290.974191ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:15:36.988123Z","caller":"traceutil/trace.go:171","msg":"trace[125320690] transaction","detail":"{read_only:false; response_revision:26208; number_of_response:1; }","duration":"302.020254ms","start":"2026-04-14T05:15:36.686092Z","end":"2026-04-14T05:15:36.988112Z","steps":["trace[125320690] 'process raft request' (duration: 301.51808ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:15:36.988186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:15:36.686066Z","time spent":"302.078815ms","remote":"127.0.0.1:44228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T05:15:36.988181Z","caller":"traceutil/trace.go:171","msg":"trace[374302550] transaction","detail":"{read_only:false; response_revision:26209; number_of_response:1; }","duration":"300.090048ms","start":"2026-04-14T05:15:36.688074Z","end":"2026-04-14T05:15:36.988164Z","steps":["trace[374302550] 'process raft request' (duration: 299.80405ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:15:36.988257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:15:36.688059Z","time spent":"300.155561ms","remote":"127.0.0.1:44228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T05:15:36.988359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.141425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" ","response":"range_response_count:1 size:1312"} {"level":"warn","ts":"2026-04-14T05:15:36.988384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.663221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-04-14T05:15:36.988417Z","caller":"traceutil/trace.go:171","msg":"trace[96638036] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26209; }","duration":"302.732763ms","start":"2026-04-14T05:15:36.685675Z","end":"2026-04-14T05:15:36.988408Z","steps":["trace[96638036] 'agreement among raft nodes before linearized reading' (duration: 302.588979ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:15:36.988437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:15:36.685662Z","time spent":"302.770645ms","remote":"127.0.0.1:44228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-14T05:15:36.98847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.910163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-04-14T05:15:36.988393Z","caller":"traceutil/trace.go:171","msg":"trace[732696239] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:1; response_revision:26209; }","duration":"321.255358ms","start":"2026-04-14T05:15:36.667127Z","end":"2026-04-14T05:15:36.988382Z","steps":["trace[732696239] 'agreement among raft nodes before linearized reading' (duration: 321.077073ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:15:36.988493Z","caller":"traceutil/trace.go:171","msg":"trace[418639120] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26209; }","duration":"259.972064ms","start":"2026-04-14T05:15:36.728514Z","end":"2026-04-14T05:15:36.988486Z","steps":["trace[418639120] 'agreement among raft nodes before linearized reading' (duration: 259.904712ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:15:36.988512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:15:36.667116Z","time spent":"321.387612ms","remote":"127.0.0.1:44090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":1335,"request content":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" "} {"level":"info","ts":"2026-04-14T05:15:37.116931Z","caller":"traceutil/trace.go:171","msg":"trace[1035051223] transaction","detail":"{read_only:false; response_revision:26210; number_of_response:1; }","duration":"123.534284ms","start":"2026-04-14T05:15:36.993373Z","end":"2026-04-14T05:15:37.116907Z","steps":["trace[1035051223] 'process raft request' (duration: 111.076881ms)","trace[1035051223] 'compare' (duration: 12.35636ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:15:37.183134Z","caller":"traceutil/trace.go:171","msg":"trace[298176603] linearizableReadLoop","detail":"{readStateIndex:27066; appliedIndex:27064; }","duration":"188.811751ms","start":"2026-04-14T05:15:36.994281Z","end":"2026-04-14T05:15:37.183093Z","steps":["trace[298176603] 'read index received' (duration: 110.181287ms)","trace[298176603] 'applied index is now lower than readState.Index' (duration: 78.628754ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:15:37.183321Z","caller":"traceutil/trace.go:171","msg":"trace[690056822] transaction","detail":"{read_only:false; response_revision:26211; number_of_response:1; }","duration":"189.324995ms","start":"2026-04-14T05:15:36.993948Z","end":"2026-04-14T05:15:37.183273Z","steps":["trace[690056822] 'process raft request' (duration: 188.970875ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:15:37.183442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.13367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/openstack/\" range_end:\"/registry/resourcequotas/openstack0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T05:15:37.183493Z","caller":"traceutil/trace.go:171","msg":"trace[1713153877] range","detail":"{range_begin:/registry/resourcequotas/openstack/; range_end:/registry/resourcequotas/openstack0; response_count:0; response_revision:26211; }","duration":"189.230924ms","start":"2026-04-14T05:15:36.994249Z","end":"2026-04-14T05:15:37.18348Z","steps":["trace[1713153877] 'agreement among raft nodes before linearized reading' (duration: 188.978986ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:15:37.324092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.978656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9547"} {"level":"info","ts":"2026-04-14T05:15:37.324169Z","caller":"traceutil/trace.go:171","msg":"trace[1308864746] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:26211; }","duration":"107.080389ms","start":"2026-04-14T05:15:37.21707Z","end":"2026-04-14T05:15:37.32415Z","steps":["trace[1308864746] 'range keys from in-memory index tree' (duration: 106.820351ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:15:38.58458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.373956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.115\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-04-14T05:15:38.584652Z","caller":"traceutil/trace.go:171","msg":"trace[663295203] range","detail":"{range_begin:/registry/masterleases/199.204.45.115; range_end:; response_count:1; response_revision:26214; }","duration":"207.49648ms","start":"2026-04-14T05:15:38.377136Z","end":"2026-04-14T05:15:38.584632Z","steps":["trace[663295203] 'range keys from in-memory index tree' (duration: 207.205412ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:15:38.768778Z","caller":"traceutil/trace.go:171","msg":"trace[523724621] transaction","detail":"{read_only:false; response_revision:26215; number_of_response:1; }","duration":"282.426588ms","start":"2026-04-14T05:15:38.486317Z","end":"2026-04-14T05:15:38.768744Z","steps":["trace[523724621] 'process raft request' (duration: 282.196712ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:15:38.936493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.066283ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-04-14T05:15:38.936802Z","caller":"traceutil/trace.go:171","msg":"trace[830117120] linearizableReadLoop","detail":"{readStateIndex:27073; appliedIndex:27069; }","duration":"235.4426ms","start":"2026-04-14T05:15:38.701342Z","end":"2026-04-14T05:15:38.936784Z","steps":["trace[830117120] 'read index received' (duration: 67.197331ms)","trace[830117120] 'applied index is now lower than readState.Index' (duration: 168.244409ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:15:38.936885Z","caller":"traceutil/trace.go:171","msg":"trace[970110040] transaction","detail":"{read_only:false; response_revision:26216; number_of_response:1; }","duration":"270.681226ms","start":"2026-04-14T05:15:38.666197Z","end":"2026-04-14T05:15:38.936878Z","steps":["trace[970110040] 'process raft request' (duration: 270.396008ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:15:38.936882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:15:38.585443Z","time spent":"351.425941ms","remote":"127.0.0.1:44020","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-14T05:15:38.937145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.828159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-14T05:15:38.937166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.807611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-04-14T05:15:38.937167Z","caller":"traceutil/trace.go:171","msg":"trace[1717983088] transaction","detail":"{read_only:false; response_revision:26217; number_of_response:1; }","duration":"270.798121ms","start":"2026-04-14T05:15:38.666308Z","end":"2026-04-14T05:15:38.937106Z","steps":["trace[1717983088] 'process raft request' (duration: 270.401028ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:15:38.937265Z","caller":"traceutil/trace.go:171","msg":"trace[1230722207] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26217; }","duration":"235.950355ms","start":"2026-04-14T05:15:38.701291Z","end":"2026-04-14T05:15:38.937242Z","steps":["trace[1230722207] 'agreement among raft nodes before linearized reading' (duration: 235.664356ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:15:38.937239Z","caller":"traceutil/trace.go:171","msg":"trace[1532128030] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:26217; }","duration":"213.989564ms","start":"2026-04-14T05:15:38.723227Z","end":"2026-04-14T05:15:38.937216Z","steps":["trace[1532128030] 'agreement among raft nodes before linearized reading' (duration: 213.803438ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:15:39.184354Z","caller":"traceutil/trace.go:171","msg":"trace[1734396722] linearizableReadLoop","detail":"{readStateIndex:27076; appliedIndex:27073; }","duration":"189.963454ms","start":"2026-04-14T05:15:38.994363Z","end":"2026-04-14T05:15:39.184327Z","steps":["trace[1734396722] 'read index received' (duration: 13.533433ms)","trace[1734396722] 'applied index is now lower than readState.Index' (duration: 176.428951ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:15:39.18477Z","caller":"traceutil/trace.go:171","msg":"trace[1218458800] transaction","detail":"{read_only:false; response_revision:26219; number_of_response:1; }","duration":"243.1911ms","start":"2026-04-14T05:15:38.941566Z","end":"2026-04-14T05:15:39.184757Z","steps":["trace[1218458800] 'process raft request' (duration: 242.416608ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:15:39.185003Z","caller":"traceutil/trace.go:171","msg":"trace[827653794] transaction","detail":"{read_only:false; response_revision:26220; number_of_response:1; }","duration":"191.248099ms","start":"2026-04-14T05:15:38.993744Z","end":"2026-04-14T05:15:39.184992Z","steps":["trace[827653794] 'process raft request' (duration: 190.435557ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:15:39.185091Z","caller":"traceutil/trace.go:171","msg":"trace[1284787837] transaction","detail":"{read_only:false; response_revision:26221; number_of_response:1; }","duration":"188.687967ms","start":"2026-04-14T05:15:38.996395Z","end":"2026-04-14T05:15:39.185083Z","steps":["trace[1284787837] 'process raft request' (duration: 187.838033ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:15:39.185174Z","caller":"traceutil/trace.go:171","msg":"trace[789475937] transaction","detail":"{read_only:false; response_revision:26222; number_of_response:1; }","duration":"188.721768ms","start":"2026-04-14T05:15:38.996444Z","end":"2026-04-14T05:15:39.185166Z","steps":["trace[789475937] 'process raft request' (duration: 187.831853ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:15:39.185354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.967961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-14T05:15:39.185409Z","caller":"traceutil/trace.go:171","msg":"trace[970802240] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26222; }","duration":"191.080955ms","start":"2026-04-14T05:15:38.99432Z","end":"2026-04-14T05:15:39.1854Z","steps":["trace[970802240] 'agreement among raft nodes before linearized reading' (duration: 190.91145ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:15:39.18563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.186459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"} {"level":"info","ts":"2026-04-14T05:15:39.185662Z","caller":"traceutil/trace.go:171","msg":"trace[771898487] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:26222; }","duration":"173.21849ms","start":"2026-04-14T05:15:39.012434Z","end":"2026-04-14T05:15:39.185652Z","steps":["trace[771898487] 'agreement among raft nodes before linearized reading' (duration: 173.124917ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:15:39.347807Z","caller":"traceutil/trace.go:171","msg":"trace[36168644] linearizableReadLoop","detail":"{readStateIndex:27081; appliedIndex:27078; }","duration":"145.65618ms","start":"2026-04-14T05:15:39.202131Z","end":"2026-04-14T05:15:39.347787Z","steps":["trace[36168644] 'read index received' (duration: 15.342015ms)","trace[36168644] 'applied index is now lower than readState.Index' (duration: 130.313185ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T05:15:39.347928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.781094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T05:15:39.347963Z","caller":"traceutil/trace.go:171","msg":"trace[1906029437] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26225; }","duration":"145.859066ms","start":"2026-04-14T05:15:39.202087Z","end":"2026-04-14T05:15:39.347946Z","steps":["trace[1906029437] 'agreement among raft nodes before linearized reading' (duration: 145.781754ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:15:39.347965Z","caller":"traceutil/trace.go:171","msg":"trace[74842417] transaction","detail":"{read_only:false; response_revision:26224; number_of_response:1; }","duration":"154.012346ms","start":"2026-04-14T05:15:39.193917Z","end":"2026-04-14T05:15:39.34793Z","steps":["trace[74842417] 'process raft request' (duration: 78.211792ms)","trace[74842417] 'compare' (duration: 75.195978ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:15:39.348144Z","caller":"traceutil/trace.go:171","msg":"trace[485323472] transaction","detail":"{read_only:false; response_revision:26225; number_of_response:1; }","duration":"152.535144ms","start":"2026-04-14T05:15:39.1956Z","end":"2026-04-14T05:15:39.348135Z","steps":["trace[485323472] 'process raft request' (duration: 152.082211ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:15:39.594434Z","caller":"traceutil/trace.go:171","msg":"trace[1351643549] transaction","detail":"{read_only:false; response_revision:26226; number_of_response:1; }","duration":"169.68359ms","start":"2026-04-14T05:15:39.424718Z","end":"2026-04-14T05:15:39.594402Z","steps":["trace[1351643549] 'process raft request' (duration: 146.430332ms)","trace[1351643549] 'compare' (duration: 22.527317ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:15:43.327929Z","caller":"traceutil/trace.go:171","msg":"trace[2053731245] linearizableReadLoop","detail":"{readStateIndex:27108; appliedIndex:27105; }","duration":"114.857758ms","start":"2026-04-14T05:15:43.213038Z","end":"2026-04-14T05:15:43.327895Z","steps":["trace[2053731245] 'read index received' (duration: 31.266074ms)","trace[2053731245] 'applied index is now lower than readState.Index' (duration: 83.590514ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:15:43.328322Z","caller":"traceutil/trace.go:171","msg":"trace[1852881983] transaction","detail":"{read_only:false; response_revision:26251; number_of_response:1; }","duration":"119.970174ms","start":"2026-04-14T05:15:43.208336Z","end":"2026-04-14T05:15:43.328306Z","steps":["trace[1852881983] 'process raft request' (duration: 119.320216ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:15:43.32855Z","caller":"traceutil/trace.go:171","msg":"trace[2142167432] transaction","detail":"{read_only:false; response_revision:26252; number_of_response:1; }","duration":"120.144888ms","start":"2026-04-14T05:15:43.208394Z","end":"2026-04-14T05:15:43.328539Z","steps":["trace[2142167432] 'process raft request' (duration: 119.446309ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:15:43.328833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.764885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-04-14T05:15:43.32888Z","caller":"traceutil/trace.go:171","msg":"trace[279363486] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26252; }","duration":"115.876018ms","start":"2026-04-14T05:15:43.212992Z","end":"2026-04-14T05:15:43.328868Z","steps":["trace[279363486] 'agreement among raft nodes before linearized reading' (duration: 115.687052ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:15:43.489918Z","caller":"traceutil/trace.go:171","msg":"trace[1008364970] transaction","detail":"{read_only:false; response_revision:26253; number_of_response:1; }","duration":"155.795007ms","start":"2026-04-14T05:15:43.334093Z","end":"2026-04-14T05:15:43.489888Z","steps":["trace[1008364970] 'process raft request' (duration: 69.933518ms)","trace[1008364970] 'compare' (duration: 85.659684ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:15:43.490772Z","caller":"traceutil/trace.go:171","msg":"trace[1332849569] linearizableReadLoop","detail":"{readStateIndex:27110; appliedIndex:27108; }","duration":"127.223059ms","start":"2026-04-14T05:15:43.363512Z","end":"2026-04-14T05:15:43.490735Z","steps":["trace[1332849569] 'read index received' (duration: 40.549647ms)","trace[1332849569] 'applied index is now lower than readState.Index' (duration: 86.671162ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:15:43.490959Z","caller":"traceutil/trace.go:171","msg":"trace[1370654213] transaction","detail":"{read_only:false; response_revision:26254; number_of_response:1; }","duration":"155.914971ms","start":"2026-04-14T05:15:43.335009Z","end":"2026-04-14T05:15:43.490924Z","steps":["trace[1370654213] 'process raft request' (duration: 154.840251ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:15:43.491202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.029835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-04-14T05:15:43.491293Z","caller":"traceutil/trace.go:171","msg":"trace[1346779408] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26254; }","duration":"126.154869ms","start":"2026-04-14T05:15:43.365111Z","end":"2026-04-14T05:15:43.491266Z","steps":["trace[1346779408] 'agreement among raft nodes before linearized reading' (duration: 125.944433ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:15:43.491802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.279568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-04-14T05:15:43.491927Z","caller":"traceutil/trace.go:171","msg":"trace[622850156] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26254; }","duration":"128.484904ms","start":"2026-04-14T05:15:43.363426Z","end":"2026-04-14T05:15:43.491911Z","steps":["trace[622850156] 'agreement among raft nodes before linearized reading' (duration: 127.540667ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:15:43.771549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.726479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-04-14T05:15:43.771664Z","caller":"traceutil/trace.go:171","msg":"trace[428178294] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26256; }","duration":"158.882354ms","start":"2026-04-14T05:15:43.612759Z","end":"2026-04-14T05:15:43.771641Z","steps":["trace[428178294] 'range keys from in-memory index tree' (duration: 158.507363ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:15:50.700728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.10977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/networks/\" range_end:\"/registry/openstack.k-orc.cloud/networks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T05:15:50.700839Z","caller":"traceutil/trace.go:171","msg":"trace[692526947] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/networks/; range_end:/registry/openstack.k-orc.cloud/networks0; response_count:0; response_revision:26308; }","duration":"171.298195ms","start":"2026-04-14T05:15:50.529511Z","end":"2026-04-14T05:15:50.70081Z","steps":["trace[692526947] 'count revisions from in-memory index tree' (duration: 170.978327ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:15:51.173908Z","caller":"traceutil/trace.go:171","msg":"trace[885395672] transaction","detail":"{read_only:false; response_revision:26310; number_of_response:1; }","duration":"258.255826ms","start":"2026-04-14T05:15:50.915624Z","end":"2026-04-14T05:15:51.17388Z","steps":["trace[885395672] 'process raft request' (duration: 212.245454ms)","trace[885395672] 'compare' (duration: 45.493207ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:15:51.186078Z","caller":"traceutil/trace.go:171","msg":"trace[975298228] transaction","detail":"{read_only:false; response_revision:26311; number_of_response:1; }","duration":"139.509837ms","start":"2026-04-14T05:15:51.046558Z","end":"2026-04-14T05:15:51.186068Z","steps":["trace[975298228] 'process raft request' (duration: 139.25953ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:15:51.18618Z","caller":"traceutil/trace.go:171","msg":"trace[2100061338] transaction","detail":"{read_only:false; response_revision:26312; number_of_response:1; }","duration":"107.987364ms","start":"2026-04-14T05:15:51.078186Z","end":"2026-04-14T05:15:51.186174Z","steps":["trace[2100061338] 'process raft request' (duration: 107.676445ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:15:51.5468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.097212ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-14T05:15:51.546942Z","caller":"traceutil/trace.go:171","msg":"trace[60340596] transaction","detail":"{read_only:false; response_revision:26313; number_of_response:1; }","duration":"274.475264ms","start":"2026-04-14T05:15:51.272447Z","end":"2026-04-14T05:15:51.546922Z","steps":["trace[60340596] 'process raft request' (duration: 63.158296ms)","trace[60340596] 'compare' (duration: 210.935887ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:15:51.54698Z","caller":"traceutil/trace.go:171","msg":"trace[1732034063] transaction","detail":"{read_only:false; response_revision:26314; number_of_response:1; }","duration":"184.018366ms","start":"2026-04-14T05:15:51.362954Z","end":"2026-04-14T05:15:51.546972Z","steps":["trace[1732034063] 'process raft request' (duration: 183.950784ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:15:51.547059Z","caller":"traceutil/trace.go:171","msg":"trace[407604579] linearizableReadLoop","detail":"{readStateIndex:27171; appliedIndex:27170; }","duration":"243.300112ms","start":"2026-04-14T05:15:51.303748Z","end":"2026-04-14T05:15:51.547049Z","steps":["trace[407604579] 'read index received' (duration: 31.902812ms)","trace[407604579] 'applied index is now lower than readState.Index' (duration: 211.39632ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T05:15:51.547176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.425867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T05:15:51.547207Z","caller":"traceutil/trace.go:171","msg":"trace[1063030399] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:26314; }","duration":"243.487107ms","start":"2026-04-14T05:15:51.30371Z","end":"2026-04-14T05:15:51.547197Z","steps":["trace[1063030399] 'agreement among raft nodes before linearized reading' (duration: 243.431946ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:15:51.615073Z","caller":"traceutil/trace.go:171","msg":"trace[478038080] transaction","detail":"{read_only:false; response_revision:26315; number_of_response:1; }","duration":"251.990338ms","start":"2026-04-14T05:15:51.363034Z","end":"2026-04-14T05:15:51.615024Z","steps":["trace[478038080] 'process raft request' (duration: 251.69937ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:15:51.902845Z","caller":"traceutil/trace.go:171","msg":"trace[338591967] transaction","detail":"{read_only:false; response_revision:26316; number_of_response:1; }","duration":"282.575074ms","start":"2026-04-14T05:15:51.620234Z","end":"2026-04-14T05:15:51.90281Z","steps":["trace[338591967] 'process raft request' (duration: 217.242566ms)","trace[338591967] 'compare' (duration: 64.962998ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:15:51.94891Z","caller":"traceutil/trace.go:171","msg":"trace[1284108934] linearizableReadLoop","detail":"{readStateIndex:27176; appliedIndex:27173; }","duration":"327.618028ms","start":"2026-04-14T05:15:51.621237Z","end":"2026-04-14T05:15:51.948855Z","steps":["trace[1284108934] 'read index received' (duration: 216.30754ms)","trace[1284108934] 'applied index is now lower than readState.Index' (duration: 111.309238ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:15:51.949955Z","caller":"traceutil/trace.go:171","msg":"trace[1409754972] transaction","detail":"{read_only:false; response_revision:26317; number_of_response:1; }","duration":"329.45336ms","start":"2026-04-14T05:15:51.620483Z","end":"2026-04-14T05:15:51.949936Z","steps":["trace[1409754972] 'process raft request' (duration: 328.159903ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:15:51.95012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:15:51.620453Z","time spent":"329.554973ms","remote":"127.0.0.1:44228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T05:15:51.950764Z","caller":"traceutil/trace.go:171","msg":"trace[770602554] transaction","detail":"{read_only:false; response_revision:26318; number_of_response:1; }","duration":"329.981395ms","start":"2026-04-14T05:15:51.620737Z","end":"2026-04-14T05:15:51.950718Z","steps":["trace[770602554] 'process raft request' (duration: 328.070311ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:15:51.950897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.625915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-04-14T05:15:51.950991Z","caller":"traceutil/trace.go:171","msg":"trace[353427923] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26318; }","duration":"329.778379ms","start":"2026-04-14T05:15:51.621192Z","end":"2026-04-14T05:15:51.95097Z","steps":["trace[353427923] 'agreement among raft nodes before linearized reading' (duration: 329.46068ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:15:51.950984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:15:51.620693Z","time spent":"330.17144ms","remote":"127.0.0.1:44228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T05:15:51.951061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:15:51.621183Z","time spent":"329.869251ms","remote":"127.0.0.1:44228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-14T05:15:51.962589Z","caller":"traceutil/trace.go:171","msg":"trace[408530511] transaction","detail":"{read_only:false; response_revision:26319; number_of_response:1; }","duration":"110.969838ms","start":"2026-04-14T05:15:51.851538Z","end":"2026-04-14T05:15:51.962508Z","steps":["trace[408530511] 'process raft request' (duration: 110.883376ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:15:51.962627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.094809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-04-14T05:15:51.962669Z","caller":"traceutil/trace.go:171","msg":"trace[1650871333] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26319; }","duration":"113.172571ms","start":"2026-04-14T05:15:51.849486Z","end":"2026-04-14T05:15:51.962658Z","steps":["trace[1650871333] 'agreement among raft nodes before linearized reading' (duration: 113.004536ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:15:52.119798Z","caller":"traceutil/trace.go:171","msg":"trace[352608098] transaction","detail":"{read_only:false; response_revision:26320; number_of_response:1; }","duration":"164.415171ms","start":"2026-04-14T05:15:51.955348Z","end":"2026-04-14T05:15:52.119763Z","steps":["trace[352608098] 'process raft request' (duration: 81.367382ms)","trace[352608098] 'compare' (duration: 82.324189ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:15:52.131151Z","caller":"traceutil/trace.go:171","msg":"trace[271999409] transaction","detail":"{read_only:false; response_revision:26321; number_of_response:1; }","duration":"163.179286ms","start":"2026-04-14T05:15:51.967936Z","end":"2026-04-14T05:15:52.131115Z","steps":["trace[271999409] 'process raft request' (duration: 163.001221ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:15:52.361515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.649629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/magnum-api-7594b95b58-6phlq.18a62144c2b88e0f\" ","response":"range_response_count:1 size:834"} {"level":"info","ts":"2026-04-14T05:15:52.361638Z","caller":"traceutil/trace.go:171","msg":"trace[757057740] range","detail":"{range_begin:/registry/events/openstack/magnum-api-7594b95b58-6phlq.18a62144c2b88e0f; range_end:; response_count:1; response_revision:26321; }","duration":"105.809583ms","start":"2026-04-14T05:15:52.255801Z","end":"2026-04-14T05:15:52.361611Z","steps":["trace[757057740] 'range keys from in-memory index tree' (duration: 105.432103ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:15:55.334698Z","caller":"traceutil/trace.go:171","msg":"trace[247232062] linearizableReadLoop","detail":"{readStateIndex:27202; appliedIndex:27201; }","duration":"129.819872ms","start":"2026-04-14T05:15:55.204847Z","end":"2026-04-14T05:15:55.334666Z","steps":["trace[247232062] 'read index received' (duration: 129.695639ms)","trace[247232062] 'applied index is now lower than readState.Index' (duration: 122.433µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:15:55.334841Z","caller":"traceutil/trace.go:171","msg":"trace[2122325494] transaction","detail":"{read_only:false; response_revision:26343; number_of_response:1; }","duration":"137.280014ms","start":"2026-04-14T05:15:55.197513Z","end":"2026-04-14T05:15:55.334793Z","steps":["trace[2122325494] 'process raft request' (duration: 136.931304ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:15:55.334919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.064189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T05:15:55.334977Z","caller":"traceutil/trace.go:171","msg":"trace[438822061] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26343; }","duration":"130.180092ms","start":"2026-04-14T05:15:55.204779Z","end":"2026-04-14T05:15:55.334959Z","steps":["trace[438822061] 'agreement among raft nodes before linearized reading' (duration: 130.013358ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:15:55.37419Z","caller":"traceutil/trace.go:171","msg":"trace[351819255] transaction","detail":"{read_only:false; response_revision:26344; number_of_response:1; }","duration":"168.62948ms","start":"2026-04-14T05:15:55.20552Z","end":"2026-04-14T05:15:55.374149Z","steps":["trace[351819255] 'process raft request' (duration: 168.464115ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:16:21.132073Z","caller":"traceutil/trace.go:171","msg":"trace[1000326937] transaction","detail":"{read_only:false; response_revision:26690; number_of_response:1; }","duration":"145.100015ms","start":"2026-04-14T05:16:20.986893Z","end":"2026-04-14T05:16:21.131993Z","steps":["trace[1000326937] 'process raft request' (duration: 144.776426ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:16:42.58261Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006c6b.wal"} {"level":"info","ts":"2026-04-14T05:19:25.361293Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25423} {"level":"info","ts":"2026-04-14T05:19:25.501188Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25423,"took":"134.662845ms","hash":3884720926,"current-db-size-bytes":58699776,"current-db-size":"59 MB","current-db-size-in-use-bytes":38645760,"current-db-size-in-use":"39 MB"} {"level":"info","ts":"2026-04-14T05:19:25.501448Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3884720926,"revision":25423,"compact-revision":22170} {"level":"info","ts":"2026-04-14T05:20:31.666832Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"e3aaa5c123e150e0","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-14T05:20:31.675447Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-04-14T05:20:31.675597Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-04-14T05:24:25.368582Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28426} {"level":"info","ts":"2026-04-14T05:24:25.515161Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28426,"took":"140.439246ms","hash":3106622771,"current-db-size-bytes":58699776,"current-db-size":"59 MB","current-db-size-in-use-bytes":32264192,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-14T05:24:25.515249Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3106622771,"revision":28426,"compact-revision":25423} {"level":"info","ts":"2026-04-14T05:29:25.374529Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30783} {"level":"info","ts":"2026-04-14T05:29:25.51175Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30783,"took":"131.360583ms","hash":3956609284,"current-db-size-bytes":58699776,"current-db-size":"59 MB","current-db-size-in-use-bytes":30294016,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-14T05:29:25.511827Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3956609284,"revision":30783,"compact-revision":28426} {"level":"info","ts":"2026-04-14T05:34:25.381051Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33042} {"level":"info","ts":"2026-04-14T05:34:25.533496Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33042,"took":"146.470221ms","hash":322077647,"current-db-size-bytes":59744256,"current-db-size":"60 MB","current-db-size-in-use-bytes":30347264,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-14T05:34:25.533577Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":322077647,"revision":33042,"compact-revision":30783} {"level":"info","ts":"2026-04-14T05:39:25.388048Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35334} {"level":"info","ts":"2026-04-14T05:39:25.536238Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35334,"took":"143.796145ms","hash":3715349575,"current-db-size-bytes":59744256,"current-db-size":"60 MB","current-db-size-in-use-bytes":31703040,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-14T05:39:25.536333Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3715349575,"revision":35334,"compact-revision":33042} {"level":"info","ts":"2026-04-14T05:42:00.630602Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"e3aaa5c123e150e0","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-14T05:42:00.636243Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-04-14T05:42:00.636369Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-04-14T05:44:25.399378Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37575} {"level":"info","ts":"2026-04-14T05:44:25.531158Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37575,"took":"127.187384ms","hash":1933880626,"current-db-size-bytes":59744256,"current-db-size":"60 MB","current-db-size-in-use-bytes":32178176,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-14T05:44:25.531232Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1933880626,"revision":37575,"compact-revision":35334}