{"level":"warn","ts":"2026-08-13T18:22:04.554451Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-13T18:22:04.554546Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.74: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.74:2380","--initial-cluster=instance=https://199.204.45.74:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.74:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.74:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-08-13T18:22:04.554606Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-13T18:22:04.554616Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.74:2380"]} {"level":"info","ts":"2026-08-13T18:22:04.554635Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-13T18:22:04.555231Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"]} {"level":"info","ts":"2026-08-13T18:22:04.555301Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.74:2380"],"listen-peer-urls":["https://199.204.45.74:2380"],"advertise-client-urls":["https://199.204.45.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.74:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-08-13T18:22:04.559696Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.17774ms"} {"level":"info","ts":"2026-08-13T18:22:04.582114Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"89ba38f964b95aa0","cluster-id":"54492f71bf65c400"} {"level":"info","ts":"2026-08-13T18:22:04.582188Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=()"} {"level":"info","ts":"2026-08-13T18:22:04.582207Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became follower at term 0"} {"level":"info","ts":"2026-08-13T18:22:04.582221Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 89ba38f964b95aa0 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-13T18:22:04.582227Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became follower at term 1"} {"level":"info","ts":"2026-08-13T18:22:04.582293Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=(9924307372652190368)"} {"level":"warn","ts":"2026-08-13T18:22:04.597763Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-13T18:22:04.606192Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-13T18:22:04.608005Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-08-13T18:22:04.610262Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"89ba38f964b95aa0","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-13T18:22:04.610506Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"89ba38f964b95aa0","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-13T18:22:04.610764Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-13T18:22:04.610836Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-13T18:22:04.610958Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-13T18:22:04.611633Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=(9924307372652190368)"} {"level":"info","ts":"2026-08-13T18:22:04.61185Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"54492f71bf65c400","local-member-id":"89ba38f964b95aa0","added-peer-id":"89ba38f964b95aa0","added-peer-peer-urls":["https://199.204.45.74:2380"]} {"level":"info","ts":"2026-08-13T18:22:04.613143Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-13T18:22:04.613323Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.74:2380"} {"level":"info","ts":"2026-08-13T18:22:04.613386Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.74:2380"} {"level":"info","ts":"2026-08-13T18:22:04.613413Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"89ba38f964b95aa0","initial-advertise-peer-urls":["https://199.204.45.74:2380"],"listen-peer-urls":["https://199.204.45.74:2380"],"advertise-client-urls":["https://199.204.45.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-13T18:22:04.613463Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-13T18:22:04.883377Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 is starting a new election at term 1"} {"level":"info","ts":"2026-08-13T18:22:04.883447Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-13T18:22:04.88347Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 received MsgPreVoteResp from 89ba38f964b95aa0 at term 1"} {"level":"info","ts":"2026-08-13T18:22:04.883485Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became candidate at term 2"} {"level":"info","ts":"2026-08-13T18:22:04.883496Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 received MsgVoteResp from 89ba38f964b95aa0 at term 2"} {"level":"info","ts":"2026-08-13T18:22:04.883507Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became leader at term 2"} {"level":"info","ts":"2026-08-13T18:22:04.88352Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 89ba38f964b95aa0 elected leader 89ba38f964b95aa0 at term 2"} {"level":"info","ts":"2026-08-13T18:22:04.88456Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-13T18:22:04.885261Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"89ba38f964b95aa0","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.74:2379]}","request-path":"/0/members/89ba38f964b95aa0/attributes","cluster-id":"54492f71bf65c400","publish-timeout":"7s"} {"level":"info","ts":"2026-08-13T18:22:04.885267Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-13T18:22:04.88582Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"54492f71bf65c400","local-member-id":"89ba38f964b95aa0","cluster-version":"3.5"} {"level":"info","ts":"2026-08-13T18:22:04.885887Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-13T18:22:04.885908Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-13T18:22:04.885923Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-13T18:22:04.888142Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.74:2379"} {"level":"info","ts":"2026-08-13T18:22:04.888251Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-13T18:22:04.888316Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-13T18:22:04.888458Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-08-13T18:22:16.965063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.374991ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-13T18:22:16.9652Z","caller":"traceutil/trace.go:171","msg":"trace[1252527939] linearizableReadLoop","detail":"{readStateIndex:405; appliedIndex:404; }","duration":"185.312232ms","start":"2026-08-13T18:22:16.779871Z","end":"2026-08-13T18:22:16.965183Z","steps":["trace[1252527939] 'read index received' (duration: 3.109864ms)","trace[1252527939] 'applied index is now lower than readState.Index' (duration: 182.200928ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T18:22:16.965322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.441044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4322"} {"level":"info","ts":"2026-08-13T18:22:16.965352Z","caller":"traceutil/trace.go:171","msg":"trace[780322739] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:396; }","duration":"185.509776ms","start":"2026-08-13T18:22:16.779831Z","end":"2026-08-13T18:22:16.965341Z","steps":["trace[780322739] 'agreement among raft nodes before linearized reading' (duration: 185.414874ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:22:16.965536Z","caller":"traceutil/trace.go:171","msg":"trace[193943684] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"190.989519ms","start":"2026-08-13T18:22:16.774531Z","end":"2026-08-13T18:22:16.96552Z","steps":["trace[193943684] 'compare' (duration: 181.259789ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:22:16.965626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.695832ms","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-08-13T18:22:16.965681Z","caller":"traceutil/trace.go:171","msg":"trace[2137040908] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:395; }","duration":"284.791484ms","start":"2026-08-13T18:22:16.680873Z","end":"2026-08-13T18:22:16.965665Z","steps":["trace[2137040908] 'range keys from in-memory index tree' (duration: 283.249373ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:22:16.965784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.548105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-13T18:22:16.965885Z","caller":"traceutil/trace.go:171","msg":"trace[1400615522] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:395; }","duration":"229.717748ms","start":"2026-08-13T18:22:16.736136Z","end":"2026-08-13T18:22:16.965854Z","steps":["trace[1400615522] 'range keys from in-memory index tree' (duration: 228.018363ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:22:17.138564Z","caller":"traceutil/trace.go:171","msg":"trace[184216795] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"167.421203ms","start":"2026-08-13T18:22:16.971121Z","end":"2026-08-13T18:22:17.138543Z","steps":["trace[184216795] 'process raft request' (duration: 167.282671ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:22:17.29538Z","caller":"traceutil/trace.go:171","msg":"trace[1472570716] linearizableReadLoop","detail":"{readStateIndex:407; appliedIndex:405; }","duration":"322.845532ms","start":"2026-08-13T18:22:16.972516Z","end":"2026-08-13T18:22:17.295361Z","steps":["trace[1472570716] 'read index received' (duration: 165.973443ms)","trace[1472570716] 'applied index is now lower than readState.Index' (duration: 156.871069ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T18:22:17.295521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.983595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T18:22:17.295491Z","caller":"traceutil/trace.go:171","msg":"trace[1349786512] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"319.635156ms","start":"2026-08-13T18:22:16.975838Z","end":"2026-08-13T18:22:17.295473Z","steps":["trace[1349786512] 'process raft request' (duration: 319.484693ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:22:17.295549Z","caller":"traceutil/trace.go:171","msg":"trace[1323803818] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:399; }","duration":"323.060277ms","start":"2026-08-13T18:22:16.972481Z","end":"2026-08-13T18:22:17.295542Z","steps":["trace[1323803818] 'agreement among raft nodes before linearized reading' (duration: 322.952715ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:22:17.295575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T18:22:16.972466Z","time spent":"323.103908ms","remote":"127.0.0.1:44878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-13T18:22:17.29559Z","caller":"traceutil/trace.go:171","msg":"trace[1070965912] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"324.20458ms","start":"2026-08-13T18:22:16.971347Z","end":"2026-08-13T18:22:17.295552Z","steps":["trace[1070965912] 'process raft request' (duration: 323.868343ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:22:17.295668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T18:22:16.975829Z","time spent":"319.744529ms","remote":"127.0.0.1:45034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4115,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T18:22:17.295771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T18:22:16.971341Z","time spent":"324.335373ms","remote":"127.0.0.1:45098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T18:22:39.666851Z","caller":"traceutil/trace.go:171","msg":"trace[1219502176] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"104.578336ms","start":"2026-08-13T18:22:39.56225Z","end":"2026-08-13T18:22:39.666828Z","steps":["trace[1219502176] 'process raft request' (duration: 104.427404ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:22:46.107506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.646793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-mqflz\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-08-13T18:22:46.107668Z","caller":"traceutil/trace.go:171","msg":"trace[1892794617] range","detail":"{range_begin:/registry/pods/kube-system/cilium-mqflz; range_end:; response_count:1; response_revision:630; }","duration":"255.855167ms","start":"2026-08-13T18:22:45.851792Z","end":"2026-08-13T18:22:46.107647Z","steps":["trace[1892794617] 'range keys from in-memory index tree' (duration: 255.525641ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:22:46.288299Z","caller":"traceutil/trace.go:171","msg":"trace[1792549145] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"224.154709ms","start":"2026-08-13T18:22:46.064121Z","end":"2026-08-13T18:22:46.288276Z","steps":["trace[1792549145] 'process raft request' (duration: 223.5461ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:22:46.720995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.213797ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-13T18:22:46.721117Z","caller":"traceutil/trace.go:171","msg":"trace[728986815] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"599.472106ms","start":"2026-08-13T18:22:46.121624Z","end":"2026-08-13T18:22:46.721096Z","steps":["trace[728986815] 'process raft request' (duration: 193.076546ms)","trace[728986815] 'compare' (duration: 406.093616ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T18:22:46.721185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T18:22:46.121579Z","time spent":"599.579708ms","remote":"127.0.0.1:45034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T18:22:47.002591Z","caller":"traceutil/trace.go:171","msg":"trace[517169970] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:650; }","duration":"236.806595ms","start":"2026-08-13T18:22:46.765755Z","end":"2026-08-13T18:22:47.002562Z","steps":["trace[517169970] 'read index received' (duration: 145.293023ms)","trace[517169970] 'applied index is now lower than readState.Index' (duration: 91.502982ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T18:22:47.002628Z","caller":"traceutil/trace.go:171","msg":"trace[495869529] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"653.602583ms","start":"2026-08-13T18:22:46.348991Z","end":"2026-08-13T18:22:47.002593Z","steps":["trace[495869529] 'process raft request' (duration: 562.01061ms)","trace[495869529] 'compare' (duration: 91.41596ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T18:22:47.002817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.053538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-13T18:22:47.00288Z","caller":"traceutil/trace.go:171","msg":"trace[660858844] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:633; }","duration":"237.174121ms","start":"2026-08-13T18:22:46.76569Z","end":"2026-08-13T18:22:47.002864Z","steps":["trace[660858844] 'agreement among raft nodes before linearized reading' (duration: 236.966827ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:22:47.002915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T18:22:46.348963Z","time spent":"653.802446ms","remote":"127.0.0.1:45084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-13T18:22:48.098583Z","caller":"traceutil/trace.go:171","msg":"trace[602175511] linearizableReadLoop","detail":"{readStateIndex:668; appliedIndex:667; }","duration":"103.286815ms","start":"2026-08-13T18:22:47.995271Z","end":"2026-08-13T18:22:48.098558Z","steps":["trace[602175511] 'read index received' (duration: 94.462384ms)","trace[602175511] 'applied index is now lower than readState.Index' (duration: 8.823501ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T18:22:48.098811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.516809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.74\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-08-13T18:22:48.098955Z","caller":"traceutil/trace.go:171","msg":"trace[1893429356] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"135.356381ms","start":"2026-08-13T18:22:47.963587Z","end":"2026-08-13T18:22:48.098943Z","steps":["trace[1893429356] 'process raft request' (duration: 126.090333ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:22:48.099067Z","caller":"traceutil/trace.go:171","msg":"trace[1474577596] range","detail":"{range_begin:/registry/masterleases/199.204.45.74; range_end:; response_count:1; response_revision:650; }","duration":"103.672792ms","start":"2026-08-13T18:22:47.995237Z","end":"2026-08-13T18:22:48.098909Z","steps":["trace[1474577596] 'agreement among raft nodes before linearized reading' (duration: 103.434408ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:23:02.092196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.650092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-13T18:23:02.092306Z","caller":"traceutil/trace.go:171","msg":"trace[1120030220] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:794; }","duration":"321.807978ms","start":"2026-08-13T18:23:01.770479Z","end":"2026-08-13T18:23:02.092287Z","steps":["trace[1120030220] 'range keys from in-memory index tree' (duration: 321.198835ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:23:02.092348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T18:23:01.770457Z","time spent":"321.88104ms","remote":"127.0.0.1:45098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-13T18:23:02.092462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.334873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-6flgp\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-08-13T18:23:02.092532Z","caller":"traceutil/trace.go:171","msg":"trace[1692088735] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-6flgp; range_end:; response_count:1; response_revision:794; }","duration":"145.451977ms","start":"2026-08-13T18:23:01.947066Z","end":"2026-08-13T18:23:02.092518Z","steps":["trace[1692088735] 'range keys from in-memory index tree' (duration: 144.698939ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:23:40.71076Z","caller":"traceutil/trace.go:171","msg":"trace[1334215587] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"287.493663ms","start":"2026-08-13T18:23:40.423238Z","end":"2026-08-13T18:23:40.710731Z","steps":["trace[1334215587] 'process raft request' (duration: 228.572857ms)","trace[1334215587] 'compare' (duration: 58.806532ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T18:23:48.51854Z","caller":"traceutil/trace.go:171","msg":"trace[2143713064] linearizableReadLoop","detail":"{readStateIndex:1338; appliedIndex:1337; }","duration":"120.39356ms","start":"2026-08-13T18:23:48.398128Z","end":"2026-08-13T18:23:48.518521Z","steps":["trace[2143713064] 'read index received' (duration: 120.185315ms)","trace[2143713064] 'applied index is now lower than readState.Index' (duration: 207.485µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T18:23:48.518658Z","caller":"traceutil/trace.go:171","msg":"trace[459037332] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"129.228549ms","start":"2026-08-13T18:23:48.389398Z","end":"2026-08-13T18:23:48.518627Z","steps":["trace[459037332] 'process raft request' (duration: 128.954832ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:23:48.518734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.587965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-13T18:23:48.51877Z","caller":"traceutil/trace.go:171","msg":"trace[2064959106] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1301; }","duration":"120.658227ms","start":"2026-08-13T18:23:48.398101Z","end":"2026-08-13T18:23:48.518759Z","steps":["trace[2064959106] 'agreement among raft nodes before linearized reading' (duration: 120.539574ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:23:55.896184Z","caller":"traceutil/trace.go:171","msg":"trace[1362572328] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"123.435721ms","start":"2026-08-13T18:23:55.772723Z","end":"2026-08-13T18:23:55.896159Z","steps":["trace[1362572328] 'process raft request' (duration: 122.496128ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:24:11.489539Z","caller":"traceutil/trace.go:171","msg":"trace[401154970] transaction","detail":"{read_only:false; response_revision:1620; number_of_response:1; }","duration":"104.342131ms","start":"2026-08-13T18:24:11.38516Z","end":"2026-08-13T18:24:11.489503Z","steps":["trace[401154970] 'process raft request' (duration: 29.835624ms)","trace[401154970] 'compare' (duration: 74.269112ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T18:24:11.489583Z","caller":"traceutil/trace.go:171","msg":"trace[160079646] transaction","detail":"{read_only:false; response_revision:1621; number_of_response:1; }","duration":"103.258577ms","start":"2026-08-13T18:24:11.386295Z","end":"2026-08-13T18:24:11.489553Z","steps":["trace[160079646] 'process raft request' (duration: 103.113763ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:24:11.489752Z","caller":"traceutil/trace.go:171","msg":"trace[1389186069] transaction","detail":"{read_only:false; response_revision:1622; number_of_response:1; }","duration":"100.253098ms","start":"2026-08-13T18:24:11.38947Z","end":"2026-08-13T18:24:11.489723Z","steps":["trace[1389186069] 'process raft request' (duration: 100.003772ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:24:11.490545Z","caller":"traceutil/trace.go:171","msg":"trace[886125528] transaction","detail":"{read_only:false; response_revision:1623; number_of_response:1; }","duration":"100.310359ms","start":"2026-08-13T18:24:11.390208Z","end":"2026-08-13T18:24:11.490518Z","steps":["trace[886125528] 'process raft request' (duration: 100.132005ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:24:27.237298Z","caller":"traceutil/trace.go:171","msg":"trace[1834892747] linearizableReadLoop","detail":"{readStateIndex:1964; appliedIndex:1963; }","duration":"220.533076ms","start":"2026-08-13T18:24:27.016742Z","end":"2026-08-13T18:24:27.237275Z","steps":["trace[1834892747] 'read index received' (duration: 220.369922ms)","trace[1834892747] 'applied index is now lower than readState.Index' (duration: 162.044µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T18:24:27.237435Z","caller":"traceutil/trace.go:171","msg":"trace[1984053332] transaction","detail":"{read_only:false; response_revision:1897; number_of_response:1; }","duration":"234.708849ms","start":"2026-08-13T18:24:27.002673Z","end":"2026-08-13T18:24:27.237382Z","steps":["trace[1984053332] 'process raft request' (duration: 234.441143ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:24:27.237533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.772581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T18:24:27.237721Z","caller":"traceutil/trace.go:171","msg":"trace[337561069] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1897; }","duration":"221.015326ms","start":"2026-08-13T18:24:27.016686Z","end":"2026-08-13T18:24:27.237702Z","steps":["trace[337561069] 'agreement among raft nodes before linearized reading' (duration: 220.790781ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:24:27.38732Z","caller":"traceutil/trace.go:171","msg":"trace[460962957] linearizableReadLoop","detail":"{readStateIndex:1965; appliedIndex:1964; }","duration":"148.395203ms","start":"2026-08-13T18:24:27.238904Z","end":"2026-08-13T18:24:27.387299Z","steps":["trace[460962957] 'read index received' (duration: 148.329541ms)","trace[460962957] 'applied index is now lower than readState.Index' (duration: 64.712µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T18:24:27.387649Z","caller":"traceutil/trace.go:171","msg":"trace[1706180962] transaction","detail":"{read_only:false; response_revision:1898; number_of_response:1; }","duration":"354.521951ms","start":"2026-08-13T18:24:27.033096Z","end":"2026-08-13T18:24:27.387618Z","steps":["trace[1706180962] 'process raft request' (duration: 354.04796ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:24:27.387698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.785302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-13T18:24:27.387798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T18:24:27.03308Z","time spent":"354.641583ms","remote":"127.0.0.1:45098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T18:24:27.38781Z","caller":"traceutil/trace.go:171","msg":"trace[939428944] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1898; }","duration":"148.891923ms","start":"2026-08-13T18:24:27.238854Z","end":"2026-08-13T18:24:27.387746Z","steps":["trace[939428944] 'agreement among raft nodes before linearized reading' (duration: 148.796201ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:24:27.668044Z","caller":"traceutil/trace.go:171","msg":"trace[1969154529] linearizableReadLoop","detail":"{readStateIndex:1968; appliedIndex:1967; }","duration":"127.431435ms","start":"2026-08-13T18:24:27.540588Z","end":"2026-08-13T18:24:27.668019Z","steps":["trace[1969154529] 'read index received' (duration: 127.256661ms)","trace[1969154529] 'applied index is now lower than readState.Index' (duration: 173.713µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T18:24:27.668224Z","caller":"traceutil/trace.go:171","msg":"trace[1382823672] transaction","detail":"{read_only:false; response_revision:1901; number_of_response:1; }","duration":"267.96204ms","start":"2026-08-13T18:24:27.400246Z","end":"2026-08-13T18:24:27.668208Z","steps":["trace[1382823672] 'process raft request' (duration: 267.628822ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:24:27.66851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.477895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-08-13T18:24:27.668595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.989627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-13T18:24:27.668677Z","caller":"traceutil/trace.go:171","msg":"trace[1036447868] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1901; }","duration":"128.12764ms","start":"2026-08-13T18:24:27.540528Z","end":"2026-08-13T18:24:27.668656Z","steps":["trace[1036447868] 'agreement among raft nodes before linearized reading' (duration: 127.871435ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:24:27.668584Z","caller":"traceutil/trace.go:171","msg":"trace[2056661766] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1901; }","duration":"112.601098ms","start":"2026-08-13T18:24:27.555966Z","end":"2026-08-13T18:24:27.668567Z","steps":["trace[2056661766] 'agreement among raft nodes before linearized reading' (duration: 112.388904ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:24:27.796577Z","caller":"traceutil/trace.go:171","msg":"trace[616365264] transaction","detail":"{read_only:false; response_revision:1902; number_of_response:1; }","duration":"122.358957ms","start":"2026-08-13T18:24:27.674195Z","end":"2026-08-13T18:24:27.796553Z","steps":["trace[616365264] 'process raft request' (duration: 112.596118ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:24:55.059262Z","caller":"traceutil/trace.go:171","msg":"trace[366316013] transaction","detail":"{read_only:false; response_revision:2103; number_of_response:1; }","duration":"136.378731ms","start":"2026-08-13T18:24:54.92286Z","end":"2026-08-13T18:24:55.059238Z","steps":["trace[366316013] 'process raft request' (duration: 82.366169ms)","trace[366316013] 'compare' (duration: 53.266388ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T18:25:02.021076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.081746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-13T18:25:02.021199Z","caller":"traceutil/trace.go:171","msg":"trace[1440216422] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2139; }","duration":"126.266559ms","start":"2026-08-13T18:25:01.89491Z","end":"2026-08-13T18:25:02.021177Z","steps":["trace[1440216422] 'range keys from in-memory index tree' (duration: 125.847681ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:25:02.398679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.535099ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-13T18:25:02.399003Z","caller":"traceutil/trace.go:171","msg":"trace[912069224] transaction","detail":"{read_only:false; response_revision:2142; number_of_response:1; }","duration":"261.971919ms","start":"2026-08-13T18:25:02.136996Z","end":"2026-08-13T18:25:02.398968Z","steps":["trace[912069224] 'process raft request' (duration: 261.788176ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:25:02.399018Z","caller":"traceutil/trace.go:171","msg":"trace[820354713] transaction","detail":"{read_only:false; response_revision:2141; number_of_response:1; }","duration":"374.075042ms","start":"2026-08-13T18:25:02.02484Z","end":"2026-08-13T18:25:02.398915Z","steps":["trace[820354713] 'process raft request' (duration: 178.202287ms)","trace[820354713] 'compare' (duration: 195.412126ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T18:25:02.399202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T18:25:02.024832Z","time spent":"374.272846ms","remote":"127.0.0.1:45098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T18:25:02.646663Z","caller":"traceutil/trace.go:171","msg":"trace[2035591621] transaction","detail":"{read_only:false; response_revision:2143; number_of_response:1; }","duration":"242.902534ms","start":"2026-08-13T18:25:02.403731Z","end":"2026-08-13T18:25:02.646633Z","steps":["trace[2035591621] 'process raft request' (duration: 202.036663ms)","trace[2035591621] 'compare' (duration: 40.753019ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T18:26:09.383927Z","caller":"traceutil/trace.go:171","msg":"trace[1413720056] transaction","detail":"{read_only:false; response_revision:2605; number_of_response:1; }","duration":"148.168725ms","start":"2026-08-13T18:26:09.235715Z","end":"2026-08-13T18:26:09.383884Z","steps":["trace[1413720056] 'process raft request' (duration: 147.90447ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:26:42.016261Z","caller":"traceutil/trace.go:171","msg":"trace[1715183286] transaction","detail":"{read_only:false; response_revision:2783; number_of_response:1; }","duration":"114.630052ms","start":"2026-08-13T18:26:41.90161Z","end":"2026-08-13T18:26:42.01624Z","steps":["trace[1715183286] 'process raft request' (duration: 114.50242ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:26:42.016679Z","caller":"traceutil/trace.go:171","msg":"trace[2000839922] linearizableReadLoop","detail":"{readStateIndex:2881; appliedIndex:2881; }","duration":"105.204856ms","start":"2026-08-13T18:26:41.911444Z","end":"2026-08-13T18:26:42.016649Z","steps":["trace[2000839922] 'read index received' (duration: 105.192365ms)","trace[2000839922] 'applied index is now lower than readState.Index' (duration: 9.841µs)"],"step_count":2} {"level":"warn","ts":"2026-08-13T18:26:42.016966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.49584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-13T18:26:42.01703Z","caller":"traceutil/trace.go:171","msg":"trace[247983062] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2783; }","duration":"105.583122ms","start":"2026-08-13T18:26:41.911437Z","end":"2026-08-13T18:26:42.01702Z","steps":["trace[247983062] 'agreement among raft nodes before linearized reading' (duration: 105.319497ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:26:42.261989Z","caller":"traceutil/trace.go:171","msg":"trace[1521250349] transaction","detail":"{read_only:false; response_revision:2784; number_of_response:1; }","duration":"324.088644ms","start":"2026-08-13T18:26:41.937877Z","end":"2026-08-13T18:26:42.261965Z","steps":["trace[1521250349] 'process raft request' (duration: 323.895651ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:26:42.262061Z","caller":"traceutil/trace.go:171","msg":"trace[951417323] transaction","detail":"{read_only:false; response_revision:2785; number_of_response:1; }","duration":"323.527945ms","start":"2026-08-13T18:26:41.938478Z","end":"2026-08-13T18:26:42.262006Z","steps":["trace[951417323] 'process raft request' (duration: 323.430413ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:26:42.262116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T18:26:41.937852Z","time spent":"324.199136ms","remote":"127.0.0.1:45098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T18:26:42.262282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.552237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T18:26:42.262343Z","caller":"traceutil/trace.go:171","msg":"trace[79661623] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2785; }","duration":"244.73636ms","start":"2026-08-13T18:26:42.017594Z","end":"2026-08-13T18:26:42.262331Z","steps":["trace[79661623] 'agreement among raft nodes before linearized reading' (duration: 244.466776ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:26:42.261968Z","caller":"traceutil/trace.go:171","msg":"trace[1010471392] linearizableReadLoop","detail":"{readStateIndex:2883; appliedIndex:2881; }","duration":"244.319093ms","start":"2026-08-13T18:26:42.017628Z","end":"2026-08-13T18:26:42.261947Z","steps":["trace[1010471392] 'read index received' (duration: 244.12868ms)","trace[1010471392] 'applied index is now lower than readState.Index' (duration: 189.353µs)"],"step_count":2} {"level":"warn","ts":"2026-08-13T18:26:42.263081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T18:26:41.938452Z","time spent":"323.698777ms","remote":"127.0.0.1:45098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T18:26:42.269728Z","caller":"traceutil/trace.go:171","msg":"trace[1362180618] transaction","detail":"{read_only:false; response_revision:2786; number_of_response:1; }","duration":"249.080232ms","start":"2026-08-13T18:26:42.020628Z","end":"2026-08-13T18:26:42.269708Z","steps":["trace[1362180618] 'process raft request' (duration: 248.889419ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:30:24.12984Z","caller":"traceutil/trace.go:171","msg":"trace[1449053691] transaction","detail":"{read_only:false; response_revision:4650; number_of_response:1; }","duration":"109.424163ms","start":"2026-08-13T18:30:24.020386Z","end":"2026-08-13T18:30:24.12981Z","steps":["trace[1449053691] 'process raft request' (duration: 109.187197ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:30:24.49661Z","caller":"traceutil/trace.go:171","msg":"trace[788496081] transaction","detail":"{read_only:false; response_revision:4651; number_of_response:1; }","duration":"399.423923ms","start":"2026-08-13T18:30:24.097143Z","end":"2026-08-13T18:30:24.496567Z","steps":["trace[788496081] 'process raft request' (duration: 365.660946ms)","trace[788496081] 'compare' (duration: 32.530877ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T18:30:24.4968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T18:30:24.097115Z","time spent":"399.582547ms","remote":"127.0.0.1:44912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":947,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-13T18:30:24.518714Z","caller":"traceutil/trace.go:171","msg":"trace[392553234] linearizableReadLoop","detail":"{readStateIndex:4822; appliedIndex:4820; }","duration":"329.54545ms","start":"2026-08-13T18:30:24.189149Z","end":"2026-08-13T18:30:24.518695Z","steps":["trace[392553234] 'read index received' (duration: 273.735386ms)","trace[392553234] 'applied index is now lower than readState.Index' (duration: 55.808924ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T18:30:24.51885Z","caller":"traceutil/trace.go:171","msg":"trace[1024918843] transaction","detail":"{read_only:false; response_revision:4652; number_of_response:1; }","duration":"377.392066ms","start":"2026-08-13T18:30:24.141399Z","end":"2026-08-13T18:30:24.518791Z","steps":["trace[1024918843] 'process raft request' (duration: 377.051608ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:30:24.518907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.740886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-13T18:30:24.518952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.543881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T18:30:24.518989Z","caller":"traceutil/trace.go:171","msg":"trace[915946984] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:4652; }","duration":"329.888949ms","start":"2026-08-13T18:30:24.189082Z","end":"2026-08-13T18:30:24.518971Z","steps":["trace[915946984] 'agreement among raft nodes before linearized reading' (duration: 329.713935ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:30:24.519005Z","caller":"traceutil/trace.go:171","msg":"trace[223585382] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:4652; }","duration":"181.630153ms","start":"2026-08-13T18:30:24.33736Z","end":"2026-08-13T18:30:24.51899Z","steps":["trace[223585382] 'agreement among raft nodes before linearized reading' (duration: 181.516301ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:30:24.519037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T18:30:24.189055Z","time spent":"329.971261ms","remote":"127.0.0.1:45004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":35,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-08-13T18:30:24.519063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T18:30:24.141385Z","time spent":"377.564021ms","remote":"127.0.0.1:45034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9852,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T18:30:24.519529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.379313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-13T18:30:24.519567Z","caller":"traceutil/trace.go:171","msg":"trace[396419865] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4652; }","duration":"324.472234ms","start":"2026-08-13T18:30:24.195085Z","end":"2026-08-13T18:30:24.519557Z","steps":["trace[396419865] 'agreement among raft nodes before linearized reading' (duration: 323.739686ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:30:24.519597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T18:30:24.195062Z","time spent":"324.527676ms","remote":"127.0.0.1:45098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-13T18:30:29.089901Z","caller":"traceutil/trace.go:171","msg":"trace[644335895] linearizableReadLoop","detail":"{readStateIndex:4912; appliedIndex:4911; }","duration":"114.782372ms","start":"2026-08-13T18:30:28.975096Z","end":"2026-08-13T18:30:29.089878Z","steps":["trace[644335895] 'read index received' (duration: 114.586207ms)","trace[644335895] 'applied index is now lower than readState.Index' (duration: 195.285µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T18:30:29.089969Z","caller":"traceutil/trace.go:171","msg":"trace[972616052] transaction","detail":"{read_only:false; response_revision:4740; number_of_response:1; }","duration":"187.041662ms","start":"2026-08-13T18:30:28.90289Z","end":"2026-08-13T18:30:29.089931Z","steps":["trace[972616052] 'process raft request' (duration: 186.845587ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:30:29.09024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.128591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e336ec18-180b-48b7-9b77-a9276c466a1a\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-08-13T18:30:29.090289Z","caller":"traceutil/trace.go:171","msg":"trace[836372458] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e336ec18-180b-48b7-9b77-a9276c466a1a; range_end:; response_count:1; response_revision:4740; }","duration":"115.230283ms","start":"2026-08-13T18:30:28.975047Z","end":"2026-08-13T18:30:29.090277Z","steps":["trace[836372458] 'agreement among raft nodes before linearized reading' (duration: 114.957916ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:30:29.238565Z","caller":"traceutil/trace.go:171","msg":"trace[628769574] linearizableReadLoop","detail":"{readStateIndex:4914; appliedIndex:4912; }","duration":"109.969663ms","start":"2026-08-13T18:30:29.128581Z","end":"2026-08-13T18:30:29.238551Z","steps":["trace[628769574] 'read index received' (duration: 69.126469ms)","trace[628769574] 'applied index is now lower than readState.Index' (duration: 40.841854ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T18:30:29.238866Z","caller":"traceutil/trace.go:171","msg":"trace[1223339247] transaction","detail":"{read_only:false; response_revision:4742; number_of_response:1; }","duration":"135.921191ms","start":"2026-08-13T18:30:29.102908Z","end":"2026-08-13T18:30:29.238829Z","steps":["trace[1223339247] 'process raft request' (duration: 135.335506ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:30:29.238882Z","caller":"traceutil/trace.go:171","msg":"trace[15425368] transaction","detail":"{read_only:false; response_revision:4743; number_of_response:1; }","duration":"105.443411ms","start":"2026-08-13T18:30:29.133411Z","end":"2026-08-13T18:30:29.238855Z","steps":["trace[15425368] 'process raft request' (duration: 105.060542ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:30:29.238413Z","caller":"traceutil/trace.go:171","msg":"trace[194403659] transaction","detail":"{read_only:false; response_revision:4741; number_of_response:1; }","duration":"145.565607ms","start":"2026-08-13T18:30:29.092782Z","end":"2026-08-13T18:30:29.238348Z","steps":["trace[194403659] 'process raft request' (duration: 104.906598ms)","trace[194403659] 'compare' (duration: 40.333371ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T18:30:29.23993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.315386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e336ec18-180b-48b7-9b77-a9276c466a1a\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-08-13T18:30:29.24005Z","caller":"traceutil/trace.go:171","msg":"trace[422805378] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e336ec18-180b-48b7-9b77-a9276c466a1a; range_end:; response_count:1; response_revision:4743; }","duration":"111.430289ms","start":"2026-08-13T18:30:29.128553Z","end":"2026-08-13T18:30:29.239983Z","steps":["trace[422805378] 'agreement among raft nodes before linearized reading' (duration: 110.101767ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:30:43.138668Z","caller":"traceutil/trace.go:171","msg":"trace[1843134709] linearizableReadLoop","detail":"{readStateIndex:5109; appliedIndex:5108; }","duration":"420.504378ms","start":"2026-08-13T18:30:42.718146Z","end":"2026-08-13T18:30:43.138651Z","steps":["trace[1843134709] 'read index received' (duration: 414.115254ms)","trace[1843134709] 'applied index is now lower than readState.Index' (duration: 6.388404ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T18:30:43.138738Z","caller":"traceutil/trace.go:171","msg":"trace[1355794798] transaction","detail":"{read_only:false; response_revision:4932; number_of_response:1; }","duration":"432.417384ms","start":"2026-08-13T18:30:42.706297Z","end":"2026-08-13T18:30:43.138714Z","steps":["trace[1355794798] 'process raft request' (duration: 426.051701ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:30:43.13883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.677782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"info","ts":"2026-08-13T18:30:43.13886Z","caller":"traceutil/trace.go:171","msg":"trace[1118456568] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4932; }","duration":"420.744413ms","start":"2026-08-13T18:30:42.718106Z","end":"2026-08-13T18:30:43.13885Z","steps":["trace[1118456568] 'agreement among raft nodes before linearized reading' (duration: 420.629101ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:30:43.138926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T18:30:42.71809Z","time spent":"420.828236ms","remote":"127.0.0.1:38914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3339,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"warn","ts":"2026-08-13T18:30:43.139676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T18:30:42.706272Z","time spent":"432.553078ms","remote":"127.0.0.1:45098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T18:30:43.141723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.066007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T18:30:43.141817Z","caller":"traceutil/trace.go:171","msg":"trace[2030983099] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:4933; }","duration":"336.152399ms","start":"2026-08-13T18:30:42.805613Z","end":"2026-08-13T18:30:43.141765Z","steps":["trace[2030983099] 'agreement among raft nodes before linearized reading' (duration: 336.023546ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:30:43.141894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T18:30:42.805599Z","time spent":"336.253831ms","remote":"127.0.0.1:40210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"info","ts":"2026-08-13T18:30:43.761493Z","caller":"traceutil/trace.go:171","msg":"trace[1372222416] transaction","detail":"{read_only:false; response_revision:4942; number_of_response:1; }","duration":"102.955462ms","start":"2026-08-13T18:30:43.658507Z","end":"2026-08-13T18:30:43.761462Z","steps":["trace[1372222416] 'process raft request' (duration: 102.892991ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:30:43.762067Z","caller":"traceutil/trace.go:171","msg":"trace[1553277212] transaction","detail":"{read_only:false; response_revision:4941; number_of_response:1; }","duration":"107.745088ms","start":"2026-08-13T18:30:43.65427Z","end":"2026-08-13T18:30:43.762015Z","steps":["trace[1553277212] 'process raft request' (duration: 107.02522ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:31:39.883871Z","caller":"traceutil/trace.go:171","msg":"trace[1868438987] transaction","detail":"{read_only:false; response_revision:5419; number_of_response:1; }","duration":"178.330802ms","start":"2026-08-13T18:31:39.705498Z","end":"2026-08-13T18:31:39.883829Z","steps":["trace[1868438987] 'process raft request' (duration: 178.009365ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:31:40.159697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.234719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-13T18:31:40.159788Z","caller":"traceutil/trace.go:171","msg":"trace[1639559675] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5419; }","duration":"166.373842ms","start":"2026-08-13T18:31:39.993394Z","end":"2026-08-13T18:31:40.159767Z","steps":["trace[1639559675] 'range keys from in-memory index tree' (duration: 166.057085ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:31:40.16029Z","caller":"traceutil/trace.go:171","msg":"trace[1662930524] linearizableReadLoop","detail":"{readStateIndex:5611; appliedIndex:5610; }","duration":"142.590072ms","start":"2026-08-13T18:31:40.017673Z","end":"2026-08-13T18:31:40.160263Z","steps":["trace[1662930524] 'read index received' (duration: 75.543551ms)","trace[1662930524] 'applied index is now lower than readState.Index' (duration: 67.045231ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T18:31:40.160479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.787548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T18:31:40.160521Z","caller":"traceutil/trace.go:171","msg":"trace[1674888503] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5420; }","duration":"142.864629ms","start":"2026-08-13T18:31:40.017646Z","end":"2026-08-13T18:31:40.160511Z","steps":["trace[1674888503] 'agreement among raft nodes before linearized reading' (duration: 142.730306ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:31:40.160771Z","caller":"traceutil/trace.go:171","msg":"trace[2017066607] transaction","detail":"{read_only:false; response_revision:5420; number_of_response:1; }","duration":"165.533713ms","start":"2026-08-13T18:31:39.99518Z","end":"2026-08-13T18:31:40.160714Z","steps":["trace[2017066607] 'process raft request' (duration: 98.088703ms)","trace[2017066607] 'compare' (duration: 66.894038ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T18:31:46.405556Z","caller":"traceutil/trace.go:171","msg":"trace[1816127954] transaction","detail":"{read_only:false; response_revision:5473; number_of_response:1; }","duration":"117.410126ms","start":"2026-08-13T18:31:46.288117Z","end":"2026-08-13T18:31:46.405527Z","steps":["trace[1816127954] 'process raft request' (duration: 117.265693ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:31:57.595776Z","caller":"traceutil/trace.go:171","msg":"trace[1618637097] linearizableReadLoop","detail":"{readStateIndex:5732; appliedIndex:5731; }","duration":"126.206459ms","start":"2026-08-13T18:31:57.469551Z","end":"2026-08-13T18:31:57.595757Z","steps":["trace[1618637097] 'read index received' (duration: 125.050814ms)","trace[1618637097] 'applied index is now lower than readState.Index' (duration: 1.154685ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T18:31:57.595857Z","caller":"traceutil/trace.go:171","msg":"trace[327192012] transaction","detail":"{read_only:false; response_revision:5538; number_of_response:1; }","duration":"137.842804ms","start":"2026-08-13T18:31:57.458007Z","end":"2026-08-13T18:31:57.59585Z","steps":["trace[327192012] 'process raft request' (duration: 137.594059ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:31:57.596068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.495835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T18:31:57.596135Z","caller":"traceutil/trace.go:171","msg":"trace[1771243780] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:5538; }","duration":"126.579607ms","start":"2026-08-13T18:31:57.469539Z","end":"2026-08-13T18:31:57.596119Z","steps":["trace[1771243780] 'agreement among raft nodes before linearized reading' (duration: 126.456864ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:31:57.711508Z","caller":"traceutil/trace.go:171","msg":"trace[402043291] transaction","detail":"{read_only:false; response_revision:5539; number_of_response:1; }","duration":"109.772819ms","start":"2026-08-13T18:31:57.601698Z","end":"2026-08-13T18:31:57.71147Z","steps":["trace[402043291] 'process raft request' (duration: 29.659508ms)","trace[402043291] 'compare' (duration: 79.934418ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T18:31:57.909941Z","caller":"traceutil/trace.go:171","msg":"trace[326212291] transaction","detail":"{read_only:false; response_revision:5541; number_of_response:1; }","duration":"113.518572ms","start":"2026-08-13T18:31:57.796397Z","end":"2026-08-13T18:31:57.909915Z","steps":["trace[326212291] 'process raft request' (duration: 113.460791ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:31:57.910283Z","caller":"traceutil/trace.go:171","msg":"trace[793456697] transaction","detail":"{read_only:false; response_revision:5540; number_of_response:1; }","duration":"248.311029ms","start":"2026-08-13T18:31:57.661953Z","end":"2026-08-13T18:31:57.910264Z","steps":["trace[793456697] 'process raft request' (duration: 194.913641ms)","trace[793456697] 'compare' (duration: 52.60589ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T18:31:58.172815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.597806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T18:31:58.172894Z","caller":"traceutil/trace.go:171","msg":"trace[2075596358] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5541; }","duration":"154.731249ms","start":"2026-08-13T18:31:58.018146Z","end":"2026-08-13T18:31:58.172877Z","steps":["trace[2075596358] 'range keys from in-memory index tree' (duration: 154.512575ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:31:58.173125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.885121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.74\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-08-13T18:31:58.174252Z","caller":"traceutil/trace.go:171","msg":"trace[328869497] range","detail":"{range_begin:/registry/masterleases/199.204.45.74; range_end:; response_count:1; response_revision:5541; }","duration":"144.186159ms","start":"2026-08-13T18:31:58.030015Z","end":"2026-08-13T18:31:58.174201Z","steps":["trace[328869497] 'range keys from in-memory index tree' (duration: 142.648036ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:31:58.477795Z","caller":"traceutil/trace.go:171","msg":"trace[699960208] transaction","detail":"{read_only:false; response_revision:5545; number_of_response:1; }","duration":"159.540924ms","start":"2026-08-13T18:31:58.318225Z","end":"2026-08-13T18:31:58.477766Z","steps":["trace[699960208] 'process raft request' (duration: 159.473732ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:31:58.477798Z","caller":"traceutil/trace.go:171","msg":"trace[1595541731] linearizableReadLoop","detail":"{readStateIndex:5739; appliedIndex:5738; }","duration":"160.009415ms","start":"2026-08-13T18:31:58.317761Z","end":"2026-08-13T18:31:58.47777Z","steps":["trace[1595541731] 'read index received' (duration: 111.099317ms)","trace[1595541731] 'applied index is now lower than readState.Index' (duration: 48.907578ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T18:31:58.478113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.27758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-13T18:31:58.478244Z","caller":"traceutil/trace.go:171","msg":"trace[1503417020] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:5545; }","duration":"160.513046ms","start":"2026-08-13T18:31:58.317713Z","end":"2026-08-13T18:31:58.478226Z","steps":["trace[1503417020] 'agreement among raft nodes before linearized reading' (duration: 160.182089ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:31:58.478563Z","caller":"traceutil/trace.go:171","msg":"trace[36809669] transaction","detail":"{read_only:false; response_revision:5544; number_of_response:1; }","duration":"160.981996ms","start":"2026-08-13T18:31:58.316843Z","end":"2026-08-13T18:31:58.477825Z","steps":["trace[36809669] 'process raft request' (duration: 111.965526ms)","trace[36809669] 'compare' (duration: 48.756944ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T18:32:05.09483Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2907} {"level":"info","ts":"2026-08-13T18:32:05.216069Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2907,"took":"119.83892ms","hash":4083375655,"current-db-size-bytes":54050816,"current-db-size":"54 MB","current-db-size-in-use-bytes":29573120,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-13T18:32:05.216127Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4083375655,"revision":2907,"compact-revision":-1} {"level":"info","ts":"2026-08-13T18:33:18.329618Z","caller":"traceutil/trace.go:171","msg":"trace[1008957614] linearizableReadLoop","detail":"{readStateIndex:6216; appliedIndex:6214; }","duration":"130.550316ms","start":"2026-08-13T18:33:18.199019Z","end":"2026-08-13T18:33:18.329569Z","steps":["trace[1008957614] 'read index received' (duration: 97.631399ms)","trace[1008957614] 'applied index is now lower than readState.Index' (duration: 32.917987ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T18:33:18.329652Z","caller":"traceutil/trace.go:171","msg":"trace[2079339825] transaction","detail":"{read_only:false; response_revision:6003; number_of_response:1; }","duration":"155.591172ms","start":"2026-08-13T18:33:18.174015Z","end":"2026-08-13T18:33:18.329607Z","steps":["trace[2079339825] 'process raft request' (duration: 122.624543ms)","trace[2079339825] 'compare' (duration: 32.722484ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T18:33:18.329827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.574668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T18:33:18.32987Z","caller":"traceutil/trace.go:171","msg":"trace[1499002722] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6004; }","duration":"132.67792ms","start":"2026-08-13T18:33:18.19718Z","end":"2026-08-13T18:33:18.329858Z","steps":["trace[1499002722] 'agreement among raft nodes before linearized reading' (duration: 132.564258ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:33:18.329844Z","caller":"traceutil/trace.go:171","msg":"trace[1997104678] transaction","detail":"{read_only:false; response_revision:6004; number_of_response:1; }","duration":"154.599191ms","start":"2026-08-13T18:33:18.175054Z","end":"2026-08-13T18:33:18.329653Z","steps":["trace[1997104678] 'process raft request' (duration: 154.446478ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:33:18.330177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.75241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:12190"} {"level":"warn","ts":"2026-08-13T18:33:18.329975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.72882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-13T18:33:18.330255Z","caller":"traceutil/trace.go:171","msg":"trace[333698857] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:6004; }","duration":"129.858092ms","start":"2026-08-13T18:33:18.200378Z","end":"2026-08-13T18:33:18.330237Z","steps":["trace[333698857] 'agreement among raft nodes before linearized reading' (duration: 129.534845ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:33:18.330279Z","caller":"traceutil/trace.go:171","msg":"trace[1931042904] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6004; }","duration":"148.049606ms","start":"2026-08-13T18:33:18.182212Z","end":"2026-08-13T18:33:18.330262Z","steps":["trace[1931042904] 'agreement among raft nodes before linearized reading' (duration: 147.579587ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:33:18.330523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.469964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-08-13T18:33:18.330579Z","caller":"traceutil/trace.go:171","msg":"trace[1386306962] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:6004; }","duration":"129.564257ms","start":"2026-08-13T18:33:18.200999Z","end":"2026-08-13T18:33:18.330563Z","steps":["trace[1386306962] 'agreement among raft nodes before linearized reading' (duration: 128.840052ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:33:52.782085Z","caller":"traceutil/trace.go:171","msg":"trace[599249927] linearizableReadLoop","detail":"{readStateIndex:6439; appliedIndex:6438; }","duration":"147.194749ms","start":"2026-08-13T18:33:52.634815Z","end":"2026-08-13T18:33:52.78201Z","steps":["trace[599249927] 'read index received' (duration: 146.971924ms)","trace[599249927] 'applied index is now lower than readState.Index' (duration: 218.115µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T18:33:52.782117Z","caller":"traceutil/trace.go:171","msg":"trace[619847869] transaction","detail":"{read_only:false; response_revision:6220; number_of_response:1; }","duration":"159.256623ms","start":"2026-08-13T18:33:52.62282Z","end":"2026-08-13T18:33:52.782076Z","steps":["trace[619847869] 'process raft request' (duration: 158.946217ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:33:52.78228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.424613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T18:33:52.782355Z","caller":"traceutil/trace.go:171","msg":"trace[925524374] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:6220; }","duration":"147.564217ms","start":"2026-08-13T18:33:52.63477Z","end":"2026-08-13T18:33:52.782334Z","steps":["trace[925524374] 'agreement among raft nodes before linearized reading' (duration: 147.405543ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:33:52.801816Z","caller":"traceutil/trace.go:171","msg":"trace[1008761308] transaction","detail":"{read_only:false; response_revision:6221; number_of_response:1; }","duration":"108.17317ms","start":"2026-08-13T18:33:52.693629Z","end":"2026-08-13T18:33:52.801802Z","steps":["trace[1008761308] 'process raft request' (duration: 108.102188ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:33:54.21371Z","caller":"traceutil/trace.go:171","msg":"trace[1318454452] linearizableReadLoop","detail":"{readStateIndex:6457; appliedIndex:6456; }","duration":"101.962362ms","start":"2026-08-13T18:33:54.111722Z","end":"2026-08-13T18:33:54.213685Z","steps":["trace[1318454452] 'read index received' (duration: 79.669401ms)","trace[1318454452] 'applied index is now lower than readState.Index' (duration: 22.292121ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T18:33:54.213807Z","caller":"traceutil/trace.go:171","msg":"trace[493725183] transaction","detail":"{read_only:false; response_revision:6236; number_of_response:1; }","duration":"102.117125ms","start":"2026-08-13T18:33:54.111638Z","end":"2026-08-13T18:33:54.213755Z","steps":["trace[493725183] 'process raft request' (duration: 79.741383ms)","trace[493725183] 'compare' (duration: 22.012435ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T18:33:54.214067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.302779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-08-13T18:33:54.214108Z","caller":"traceutil/trace.go:171","msg":"trace[1218778338] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:6236; }","duration":"102.421261ms","start":"2026-08-13T18:33:54.111673Z","end":"2026-08-13T18:33:54.214095Z","steps":["trace[1218778338] 'agreement among raft nodes before linearized reading' (duration: 102.122805ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:33:54.221031Z","caller":"traceutil/trace.go:171","msg":"trace[1493000906] transaction","detail":"{read_only:false; response_revision:6237; number_of_response:1; }","duration":"106.091645ms","start":"2026-08-13T18:33:54.114921Z","end":"2026-08-13T18:33:54.221012Z","steps":["trace[1493000906] 'process raft request' (duration: 105.644586ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:33:54.502461Z","caller":"traceutil/trace.go:171","msg":"trace[1378879689] linearizableReadLoop","detail":"{readStateIndex:6474; appliedIndex:6473; }","duration":"114.480495ms","start":"2026-08-13T18:33:54.387949Z","end":"2026-08-13T18:33:54.502429Z","steps":["trace[1378879689] 'read index received' (duration: 114.393573ms)","trace[1378879689] 'applied index is now lower than readState.Index' (duration: 85.542µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T18:33:54.502843Z","caller":"traceutil/trace.go:171","msg":"trace[192646628] transaction","detail":"{read_only:false; response_revision:6252; number_of_response:1; }","duration":"116.103188ms","start":"2026-08-13T18:33:54.386688Z","end":"2026-08-13T18:33:54.502791Z","steps":["trace[192646628] 'process raft request' (duration: 115.325022ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:33:54.502927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.950414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-08-13T18:33:54.502976Z","caller":"traceutil/trace.go:171","msg":"trace[1931284041] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:6252; }","duration":"115.044866ms","start":"2026-08-13T18:33:54.387914Z","end":"2026-08-13T18:33:54.502959Z","steps":["trace[1931284041] 'agreement among raft nodes before linearized reading' (duration: 114.857303ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:33:54.531418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.658983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"info","ts":"2026-08-13T18:33:54.531493Z","caller":"traceutil/trace.go:171","msg":"trace[1774947341] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:6254; }","duration":"133.770855ms","start":"2026-08-13T18:33:54.3977Z","end":"2026-08-13T18:33:54.531471Z","steps":["trace[1774947341] 'agreement among raft nodes before linearized reading' (duration: 133.471869ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:34:51.415265Z","caller":"traceutil/trace.go:171","msg":"trace[535956263] transaction","detail":"{read_only:false; response_revision:6727; number_of_response:1; }","duration":"174.347217ms","start":"2026-08-13T18:34:51.240898Z","end":"2026-08-13T18:34:51.415245Z","steps":["trace[535956263] 'process raft request' (duration: 130.679711ms)","trace[535956263] 'compare' (duration: 43.204327ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T18:34:51.415414Z","caller":"traceutil/trace.go:171","msg":"trace[654437987] transaction","detail":"{read_only:false; response_revision:6728; number_of_response:1; }","duration":"109.535971ms","start":"2026-08-13T18:34:51.305858Z","end":"2026-08-13T18:34:51.415394Z","steps":["trace[654437987] 'process raft request' (duration: 109.288996ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:37:05.101138Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5593} {"level":"info","ts":"2026-08-13T18:37:05.209267Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5593,"took":"106.645241ms","hash":2671608665,"current-db-size-bytes":54050816,"current-db-size":"54 MB","current-db-size-in-use-bytes":18542592,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-08-13T18:37:05.209334Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2671608665,"revision":5593,"compact-revision":2907}