{"level":"warn","ts":"2026-05-04T18:55:24.557055Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-04T18:55:24.557149Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.240: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.240:2380","--initial-cluster=instance=https://199.204.45.240:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.240:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.240: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-05-04T18:55:24.557235Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-04T18:55:24.557249Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.240:2380"]} {"level":"info","ts":"2026-05-04T18:55:24.557274Z","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-05-04T18:55:24.557933Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"]} {"level":"info","ts":"2026-05-04T18:55:24.558004Z","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.240:2380"],"listen-peer-urls":["https://199.204.45.240:2380"],"advertise-client-urls":["https://199.204.45.240:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.240: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-05-04T18:55:24.562511Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.29586ms"} {"level":"info","ts":"2026-05-04T18:55:24.568691Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e46287fb5aa4785","cluster-id":"cae704afa9813e87"} {"level":"info","ts":"2026-05-04T18:55:24.568771Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=()"} {"level":"info","ts":"2026-05-04T18:55:24.568797Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became follower at term 0"} {"level":"info","ts":"2026-05-04T18:55:24.568808Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e46287fb5aa4785 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-04T18:55:24.568821Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became follower at term 1"} {"level":"info","ts":"2026-05-04T18:55:24.568873Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=(5640240112301918085)"} {"level":"warn","ts":"2026-05-04T18:55:24.580905Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-04T18:55:24.583716Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-04T18:55:24.585694Z","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-05-04T18:55:24.588222Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e46287fb5aa4785","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-04T18:55:24.588418Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e46287fb5aa4785","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-04T18:55:24.588421Z","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-05-04T18:55:24.590584Z","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-05-04T18:55:24.590759Z","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-05-04T18:55:24.591952Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=(5640240112301918085)"} {"level":"info","ts":"2026-05-04T18:55:24.592679Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cae704afa9813e87","local-member-id":"4e46287fb5aa4785","added-peer-id":"4e46287fb5aa4785","added-peer-peer-urls":["https://199.204.45.240:2380"]} {"level":"info","ts":"2026-05-04T18:55:24.594448Z","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-05-04T18:55:24.594547Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.240:2380"} {"level":"info","ts":"2026-05-04T18:55:24.594624Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.240:2380"} {"level":"info","ts":"2026-05-04T18:55:24.594688Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e46287fb5aa4785","initial-advertise-peer-urls":["https://199.204.45.240:2380"],"listen-peer-urls":["https://199.204.45.240:2380"],"advertise-client-urls":["https://199.204.45.240:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-04T18:55:24.594727Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-04T18:55:25.269201Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 is starting a new election at term 1"} {"level":"info","ts":"2026-05-04T18:55:25.269265Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-04T18:55:25.269286Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 received MsgPreVoteResp from 4e46287fb5aa4785 at term 1"} {"level":"info","ts":"2026-05-04T18:55:25.269303Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became candidate at term 2"} {"level":"info","ts":"2026-05-04T18:55:25.269312Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 received MsgVoteResp from 4e46287fb5aa4785 at term 2"} {"level":"info","ts":"2026-05-04T18:55:25.269337Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became leader at term 2"} {"level":"info","ts":"2026-05-04T18:55:25.269349Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e46287fb5aa4785 elected leader 4e46287fb5aa4785 at term 2"} {"level":"info","ts":"2026-05-04T18:55:25.271155Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-04T18:55:25.271407Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e46287fb5aa4785","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.240:2379]}","request-path":"/0/members/4e46287fb5aa4785/attributes","cluster-id":"cae704afa9813e87","publish-timeout":"7s"} {"level":"info","ts":"2026-05-04T18:55:25.271573Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-04T18:55:25.271643Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-04T18:55:25.27173Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-04T18:55:25.271805Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-04T18:55:25.272289Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cae704afa9813e87","local-member-id":"4e46287fb5aa4785","cluster-version":"3.5"} {"level":"info","ts":"2026-05-04T18:55:25.272419Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-04T18:55:25.272459Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-04T18:55:25.273738Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-04T18:55:25.287586Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.240:2379"} {"level":"info","ts":"2026-05-04T18:55:42.841532Z","caller":"traceutil/trace.go:171","msg":"trace[1756199555] transaction","detail":"{read_only:false; response_revision:441; number_of_response:1; }","duration":"278.599873ms","start":"2026-05-04T18:55:42.562889Z","end":"2026-05-04T18:55:42.841489Z","steps":["trace[1756199555] 'process raft request' (duration: 278.375018ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:55:54.828404Z","caller":"traceutil/trace.go:171","msg":"trace[1234331777] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"134.254115ms","start":"2026-05-04T18:55:54.694121Z","end":"2026-05-04T18:55:54.828375Z","steps":["trace[1234331777] 'process raft request' (duration: 130.879466ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:55:54.832281Z","caller":"traceutil/trace.go:171","msg":"trace[301585712] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"100.899149ms","start":"2026-05-04T18:55:54.731358Z","end":"2026-05-04T18:55:54.832257Z","steps":["trace[301585712] 'process raft request' (duration: 100.755835ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:55:59.972491Z","caller":"traceutil/trace.go:171","msg":"trace[1847121637] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"129.024042ms","start":"2026-05-04T18:55:59.843433Z","end":"2026-05-04T18:55:59.972457Z","steps":["trace[1847121637] 'process raft request' (duration: 128.483359ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:00.433887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.689897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-w67x2\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-05-04T18:56:00.433932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.116546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T18:56:00.433978Z","caller":"traceutil/trace.go:171","msg":"trace[927611475] range","detail":"{range_begin:/registry/pods/kube-system/cilium-w67x2; range_end:; response_count:1; response_revision:611; }","duration":"147.82516ms","start":"2026-05-04T18:56:00.286136Z","end":"2026-05-04T18:56:00.433961Z","steps":["trace[927611475] 'range keys from in-memory index tree' (duration: 147.575804ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:56:00.434027Z","caller":"traceutil/trace.go:171","msg":"trace[1748615222] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:611; }","duration":"310.28408ms","start":"2026-05-04T18:56:00.123721Z","end":"2026-05-04T18:56:00.434005Z","steps":["trace[1748615222] 'range keys from in-memory index tree' (duration: 310.005644ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:00.434083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:56:00.1237Z","time spent":"310.374423ms","remote":"127.0.0.1:35546","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-05-04T18:56:00.850792Z","caller":"traceutil/trace.go:171","msg":"trace[676084760] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"417.520156ms","start":"2026-05-04T18:56:00.433238Z","end":"2026-05-04T18:56:00.850758Z","steps":["trace[676084760] 'process raft request' (duration: 416.467392ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:00.851481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:56:00.433203Z","time spent":"417.67673ms","remote":"127.0.0.1:35670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036297,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-04T18:56:01.07226Z","caller":"traceutil/trace.go:171","msg":"trace[1600390493] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"623.232784ms","start":"2026-05-04T18:56:00.448994Z","end":"2026-05-04T18:56:01.072227Z","steps":["trace[1600390493] 'process raft request' (duration: 622.046886ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:01.072436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:56:00.448951Z","time spent":"623.387337ms","remote":"127.0.0.1:35782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-04T18:56:01.075754Z","caller":"traceutil/trace.go:171","msg":"trace[1115407338] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"198.890799ms","start":"2026-05-04T18:56:00.876831Z","end":"2026-05-04T18:56:01.075722Z","steps":["trace[1115407338] 'process raft request' (duration: 198.654482ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:56:01.076114Z","caller":"traceutil/trace.go:171","msg":"trace[1261649542] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:634; }","duration":"109.597801ms","start":"2026-05-04T18:56:00.966493Z","end":"2026-05-04T18:56:01.07609Z","steps":["trace[1261649542] 'read index received' (duration: 104.634225ms)","trace[1261649542] 'applied index is now lower than readState.Index' (duration: 4.962316ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T18:56:01.07623Z","caller":"traceutil/trace.go:171","msg":"trace[1591204483] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"175.803472ms","start":"2026-05-04T18:56:00.900413Z","end":"2026-05-04T18:56:01.076216Z","steps":["trace[1591204483] 'process raft request' (duration: 175.236518ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:56:01.076311Z","caller":"traceutil/trace.go:171","msg":"trace[154563917] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"164.1187ms","start":"2026-05-04T18:56:00.912156Z","end":"2026-05-04T18:56:01.076274Z","steps":["trace[154563917] 'process raft request' (duration: 163.845534ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:01.076741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.234585ms","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-05-04T18:56:01.076795Z","caller":"traceutil/trace.go:171","msg":"trace[801011190] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:616; }","duration":"110.349258ms","start":"2026-05-04T18:56:00.966431Z","end":"2026-05-04T18:56:01.07678Z","steps":["trace[801011190] 'agreement among raft nodes before linearized reading' (duration: 109.754524ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:15.478865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.422322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-wqrgl\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-05-04T18:56:15.479083Z","caller":"traceutil/trace.go:171","msg":"trace[1687556122] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-wqrgl; range_end:; response_count:1; response_revision:772; }","duration":"103.688658ms","start":"2026-05-04T18:56:15.375371Z","end":"2026-05-04T18:56:15.47906Z","steps":["trace[1687556122] 'range keys from in-memory index tree' (duration: 103.251498ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:15.479159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.841521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T18:56:15.479214Z","caller":"traceutil/trace.go:171","msg":"trace[852076894] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:772; }","duration":"355.933563ms","start":"2026-05-04T18:56:15.123266Z","end":"2026-05-04T18:56:15.4792Z","steps":["trace[852076894] 'range keys from in-memory index tree' (duration: 355.763299ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:15.479302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:56:15.123247Z","time spent":"355.995364ms","remote":"127.0.0.1:35530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-04T18:56:15.479603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.983846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-04T18:56:15.479691Z","caller":"traceutil/trace.go:171","msg":"trace[470577191] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:772; }","duration":"226.108589ms","start":"2026-05-04T18:56:15.253571Z","end":"2026-05-04T18:56:15.479679Z","steps":["trace[470577191] 'range keys from in-memory index tree' (duration: 225.796502ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:15.479593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.582938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-04T18:56:15.479945Z","caller":"traceutil/trace.go:171","msg":"trace[551450262] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:772; }","duration":"324.984147ms","start":"2026-05-04T18:56:15.154948Z","end":"2026-05-04T18:56:15.479932Z","steps":["trace[551450262] 'range keys from in-memory index tree' (duration: 324.408374ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:56:15.480004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:56:15.154929Z","time spent":"325.061868ms","remote":"127.0.0.1:35856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-04T18:56:15.482907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.053863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-04T18:56:15.484792Z","caller":"traceutil/trace.go:171","msg":"trace[1993076559] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:772; }","duration":"202.850772ms","start":"2026-05-04T18:56:15.280522Z","end":"2026-05-04T18:56:15.483346Z","steps":["trace[1993076559] 'range keys from in-memory index tree' (duration: 200.229782ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:56:29.651479Z","caller":"traceutil/trace.go:171","msg":"trace[470395824] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"103.727679ms","start":"2026-05-04T18:56:29.547697Z","end":"2026-05-04T18:56:29.651425Z","steps":["trace[470395824] 'process raft request' (duration: 103.425383ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:57:27.255616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.583039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6c2454cf-5ad4-4499-830d-0b66bd955338\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-04T18:57:27.255712Z","caller":"traceutil/trace.go:171","msg":"trace[526738913] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6c2454cf-5ad4-4499-830d-0b66bd955338; range_end:; response_count:1; response_revision:1825; }","duration":"616.740192ms","start":"2026-05-04T18:57:26.63895Z","end":"2026-05-04T18:57:27.25569Z","steps":["trace[526738913] 'range keys from in-memory index tree' (duration: 616.330872ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:57:27.255758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:57:26.638923Z","time spent":"616.824154ms","remote":"127.0.0.1:35782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6c2454cf-5ad4-4499-830d-0b66bd955338\" "} {"level":"warn","ts":"2026-05-04T18:57:27.256074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.403048ms","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-05-04T18:57:27.256171Z","caller":"traceutil/trace.go:171","msg":"trace[535363752] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1825; }","duration":"569.545411ms","start":"2026-05-04T18:57:26.686606Z","end":"2026-05-04T18:57:27.256152Z","steps":["trace[535363752] 'range keys from in-memory index tree' (duration: 568.61723ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:57:27.256224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:57:26.686594Z","time spent":"569.619692ms","remote":"127.0.0.1:35856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-04T18:57:27.256281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.269305ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-04T18:57:27.256446Z","caller":"traceutil/trace.go:171","msg":"trace[1859585965] linearizableReadLoop","detail":"{readStateIndex:1896; appliedIndex:1895; }","duration":"549.821217ms","start":"2026-05-04T18:57:26.706604Z","end":"2026-05-04T18:57:27.256425Z","steps":["trace[1859585965] 'read index received' (duration: 82.01593ms)","trace[1859585965] 'applied index is now lower than readState.Index' (duration: 467.803267ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T18:57:27.256558Z","caller":"traceutil/trace.go:171","msg":"trace[1422168157] transaction","detail":"{read_only:false; response_revision:1826; number_of_response:1; }","duration":"568.369354ms","start":"2026-05-04T18:57:26.688161Z","end":"2026-05-04T18:57:27.25653Z","steps":["trace[1422168157] 'process raft request' (duration: 100.520705ms)","trace[1422168157] 'compare' (duration: 466.874236ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T18:57:27.256635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:57:26.688142Z","time spent":"568.457526ms","remote":"127.0.0.1:35856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T18:57:27.256646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.033192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6c2454cf-5ad4-4499-830d-0b66bd955338\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-04T18:57:27.256696Z","caller":"traceutil/trace.go:171","msg":"trace[1896889845] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6c2454cf-5ad4-4499-830d-0b66bd955338; range_end:; response_count:1; response_revision:1826; }","duration":"550.114754ms","start":"2026-05-04T18:57:26.706568Z","end":"2026-05-04T18:57:27.256683Z","steps":["trace[1896889845] 'agreement among raft nodes before linearized reading' (duration: 549.988871ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:57:27.256734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:57:26.706552Z","time spent":"550.174555ms","remote":"127.0.0.1:35782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6c2454cf-5ad4-4499-830d-0b66bd955338\" "} {"level":"info","ts":"2026-05-04T18:57:27.298655Z","caller":"traceutil/trace.go:171","msg":"trace[1096553296] transaction","detail":"{read_only:false; response_revision:1827; number_of_response:1; }","duration":"509.797426ms","start":"2026-05-04T18:57:26.788834Z","end":"2026-05-04T18:57:27.298631Z","steps":["trace[1096553296] 'process raft request' (duration: 509.701383ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:57:27.298824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:57:26.788815Z","time spent":"509.915579ms","remote":"127.0.0.1:35856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-04T18:57:27.343254Z","caller":"traceutil/trace.go:171","msg":"trace[2130186280] transaction","detail":"{read_only:false; response_revision:1828; number_of_response:1; }","duration":"489.560129ms","start":"2026-05-04T18:57:26.853677Z","end":"2026-05-04T18:57:27.343237Z","steps":["trace[2130186280] 'process raft request' (duration: 489.424776ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:57:27.343365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:57:26.853632Z","time spent":"489.677232ms","remote":"127.0.0.1:35856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T18:57:27.343635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.24113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T18:57:27.343688Z","caller":"traceutil/trace.go:171","msg":"trace[488024554] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1830; }","duration":"551.320242ms","start":"2026-05-04T18:57:26.792353Z","end":"2026-05-04T18:57:27.343673Z","steps":["trace[488024554] 'agreement among raft nodes before linearized reading' (duration: 551.23229ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:57:27.343724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:57:26.792341Z","time spent":"551.372003ms","remote":"127.0.0.1:48858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-05-04T18:57:27.343833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.709881ms","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-05-04T18:57:27.343894Z","caller":"traceutil/trace.go:171","msg":"trace[215582116] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1830; }","duration":"531.799793ms","start":"2026-05-04T18:57:26.812079Z","end":"2026-05-04T18:57:27.343879Z","steps":["trace[215582116] 'agreement among raft nodes before linearized reading' (duration: 531.609349ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:57:27.343933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:57:26.812068Z","time spent":"531.857154ms","remote":"127.0.0.1:57720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-05-04T18:57:27.711961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.378341ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-04T18:57:27.71207Z","caller":"traceutil/trace.go:171","msg":"trace[574348886] transaction","detail":"{read_only:false; response_revision:1831; number_of_response:1; }","duration":"355.123387ms","start":"2026-05-04T18:57:27.356928Z","end":"2026-05-04T18:57:27.712051Z","steps":["trace[574348886] 'process raft request' (duration: 22.575902ms)","trace[574348886] 'compare' (duration: 332.248738ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T18:57:27.712127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:57:27.356907Z","time spent":"355.194058ms","remote":"127.0.0.1:35782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10970,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-04T18:58:17.604669Z","caller":"traceutil/trace.go:171","msg":"trace[1049537424] transaction","detail":"{read_only:false; response_revision:2153; number_of_response:1; }","duration":"146.052086ms","start":"2026-05-04T18:58:17.458586Z","end":"2026-05-04T18:58:17.604638Z","steps":["trace[1049537424] 'process raft request' (duration: 145.870582ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:58:17.853252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.304491ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-04T18:58:17.853522Z","caller":"traceutil/trace.go:171","msg":"trace[1878692983] linearizableReadLoop","detail":"{readStateIndex:2237; appliedIndex:2234; }","duration":"269.075067ms","start":"2026-05-04T18:58:17.584405Z","end":"2026-05-04T18:58:17.85348Z","steps":["trace[1878692983] 'read index received' (duration: 20.068728ms)","trace[1878692983] 'applied index is now lower than readState.Index' (duration: 249.005089ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T18:58:17.853623Z","caller":"traceutil/trace.go:171","msg":"trace[1480545156] transaction","detail":"{read_only:false; response_revision:2155; number_of_response:1; }","duration":"327.784809ms","start":"2026-05-04T18:58:17.525808Z","end":"2026-05-04T18:58:17.853592Z","steps":["trace[1480545156] 'process raft request' (duration: 327.599664ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T18:58:17.853609Z","caller":"traceutil/trace.go:171","msg":"trace[388010861] transaction","detail":"{read_only:false; response_revision:2154; number_of_response:1; }","duration":"394.904573ms","start":"2026-05-04T18:58:17.458645Z","end":"2026-05-04T18:58:17.853549Z","steps":["trace[388010861] 'process raft request' (duration: 256.170182ms)","trace[388010861] 'compare' (duration: 138.068956ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T18:58:17.853768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:58:17.525762Z","time spent":"327.929852ms","remote":"127.0.0.1:35856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T18:58:17.853766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.347664ms","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-05-04T18:58:17.853824Z","caller":"traceutil/trace.go:171","msg":"trace[658221872] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2155; }","duration":"269.555908ms","start":"2026-05-04T18:58:17.584252Z","end":"2026-05-04T18:58:17.853808Z","steps":["trace[658221872] 'agreement among raft nodes before linearized reading' (duration: 269.343674ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:58:17.853815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.011126ms","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-05-04T18:58:17.853961Z","caller":"traceutil/trace.go:171","msg":"trace[1076201303] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2155; }","duration":"153.20872ms","start":"2026-05-04T18:58:17.700729Z","end":"2026-05-04T18:58:17.853938Z","steps":["trace[1076201303] 'agreement among raft nodes before linearized reading' (duration: 152.921794ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T18:58:17.853828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T18:58:17.458616Z","time spent":"395.098926ms","remote":"127.0.0.1:35856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-04T19:01:37.177085Z","caller":"traceutil/trace.go:171","msg":"trace[1800223206] transaction","detail":"{read_only:false; response_revision:3539; number_of_response:1; }","duration":"117.023808ms","start":"2026-05-04T19:01:37.060023Z","end":"2026-05-04T19:01:37.177046Z","steps":["trace[1800223206] 'process raft request' (duration: 116.823773ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:02:04.825526Z","caller":"traceutil/trace.go:171","msg":"trace[2400585] transaction","detail":"{read_only:false; response_revision:3758; number_of_response:1; }","duration":"191.499567ms","start":"2026-05-04T19:02:04.633993Z","end":"2026-05-04T19:02:04.825493Z","steps":["trace[2400585] 'process raft request' (duration: 191.345043ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:02:05.001345Z","caller":"traceutil/trace.go:171","msg":"trace[104869259] transaction","detail":"{read_only:false; response_revision:3759; number_of_response:1; }","duration":"363.456018ms","start":"2026-05-04T19:02:04.637824Z","end":"2026-05-04T19:02:05.00128Z","steps":["trace[104869259] 'process raft request' (duration: 363.114161ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:02:05.001529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T19:02:04.637801Z","time spent":"363.623021ms","remote":"127.0.0.1:35856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-04T19:03:24.578309Z","caller":"traceutil/trace.go:171","msg":"trace[1099929515] transaction","detail":"{read_only:false; response_revision:4647; number_of_response:1; }","duration":"113.340264ms","start":"2026-05-04T19:03:24.464686Z","end":"2026-05-04T19:03:24.578026Z","steps":["trace[1099929515] 'process raft request' (duration: 111.497733ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:03:24.580582Z","caller":"traceutil/trace.go:171","msg":"trace[1833525049] linearizableReadLoop","detail":"{readStateIndex:4827; appliedIndex:4825; }","duration":"113.891825ms","start":"2026-05-04T19:03:24.466663Z","end":"2026-05-04T19:03:24.580555Z","steps":["trace[1833525049] 'read index received' (duration: 109.533849ms)","trace[1833525049] 'applied index is now lower than readState.Index' (duration: 4.356866ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T19:03:24.580706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.026168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T19:03:24.580731Z","caller":"traceutil/trace.go:171","msg":"trace[1867545628] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:0; response_revision:4648; }","duration":"114.0833ms","start":"2026-05-04T19:03:24.466638Z","end":"2026-05-04T19:03:24.580721Z","steps":["trace[1867545628] 'agreement among raft nodes before linearized reading' (duration: 113.999758ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:03:24.581023Z","caller":"traceutil/trace.go:171","msg":"trace[1509059237] transaction","detail":"{read_only:false; response_revision:4648; number_of_response:1; }","duration":"115.212156ms","start":"2026-05-04T19:03:24.465783Z","end":"2026-05-04T19:03:24.580995Z","steps":["trace[1509059237] 'process raft request' (duration: 114.618852ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:03:30.201447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.192568ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-04T19:03:30.201609Z","caller":"traceutil/trace.go:171","msg":"trace[2096528755] linearizableReadLoop","detail":"{readStateIndex:4994; appliedIndex:4993; }","duration":"448.948886ms","start":"2026-05-04T19:03:29.752637Z","end":"2026-05-04T19:03:30.201585Z","steps":["trace[2096528755] 'read index received' (duration: 94.436262ms)","trace[2096528755] 'applied index is now lower than readState.Index' (duration: 354.510394ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T19:03:30.201725Z","caller":"traceutil/trace.go:171","msg":"trace[21346759] transaction","detail":"{read_only:false; response_revision:4810; number_of_response:1; }","duration":"459.230066ms","start":"2026-05-04T19:03:29.742477Z","end":"2026-05-04T19:03:30.201707Z","steps":["trace[21346759] 'process raft request' (duration: 104.65689ms)","trace[21346759] 'compare' (duration: 354.037534ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T19:03:30.20182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T19:03:29.742444Z","time spent":"459.329468ms","remote":"127.0.0.1:35782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12960,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T19:03:30.201906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.230632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-05-04T19:03:30.20195Z","caller":"traceutil/trace.go:171","msg":"trace[1449221597] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4810; }","duration":"449.336835ms","start":"2026-05-04T19:03:29.752601Z","end":"2026-05-04T19:03:30.201938Z","steps":["trace[1449221597] 'agreement among raft nodes before linearized reading' (duration: 449.147541ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:03:30.201973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T19:03:29.75259Z","time spent":"449.379046ms","remote":"127.0.0.1:35746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1958,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"info","ts":"2026-05-04T19:03:30.373193Z","caller":"traceutil/trace.go:171","msg":"trace[1320048279] linearizableReadLoop","detail":"{readStateIndex:4995; appliedIndex:4994; }","duration":"171.523209ms","start":"2026-05-04T19:03:30.201647Z","end":"2026-05-04T19:03:30.373171Z","steps":["trace[1320048279] 'read index received' (duration: 168.863179ms)","trace[1320048279] 'applied index is now lower than readState.Index' (duration: 2.65927ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T19:03:30.373296Z","caller":"traceutil/trace.go:171","msg":"trace[565440725] transaction","detail":"{read_only:false; response_revision:4811; number_of_response:1; }","duration":"410.327006ms","start":"2026-05-04T19:03:29.96294Z","end":"2026-05-04T19:03:30.373267Z","steps":["trace[565440725] 'process raft request' (duration: 407.568064ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:03:30.37334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.811287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-04T19:03:30.373421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.750181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-04T19:03:30.373431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T19:03:29.96292Z","time spent":"410.441349ms","remote":"127.0.0.1:35782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14482,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T19:03:30.373456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.974432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-52542e8d-30bb-4919-9378-d41994807679\" ","response":"range_response_count:1 size:1450"} {"level":"warn","ts":"2026-05-04T19:03:30.373484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.80427ms","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-05-04T19:03:30.373481Z","caller":"traceutil/trace.go:171","msg":"trace[1056599496] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4811; }","duration":"527.847953ms","start":"2026-05-04T19:03:29.845617Z","end":"2026-05-04T19:03:30.373465Z","steps":["trace[1056599496] 'agreement among raft nodes before linearized reading' (duration: 527.68206ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:03:30.373495Z","caller":"traceutil/trace.go:171","msg":"trace[860849778] range","detail":"{range_begin:/registry/persistentvolumes/pvc-52542e8d-30bb-4919-9378-d41994807679; range_end:; response_count:1; response_revision:4811; }","duration":"169.052874ms","start":"2026-05-04T19:03:30.204431Z","end":"2026-05-04T19:03:30.373484Z","steps":["trace[860849778] 'agreement among raft nodes before linearized reading' (duration: 168.934681ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:03:30.37351Z","caller":"traceutil/trace.go:171","msg":"trace[451540638] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4811; }","duration":"522.892552ms","start":"2026-05-04T19:03:29.850608Z","end":"2026-05-04T19:03:30.373501Z","steps":["trace[451540638] 'agreement among raft nodes before linearized reading' (duration: 522.831891ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:03:30.373443Z","caller":"traceutil/trace.go:171","msg":"trace[1940081328] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4811; }","duration":"251.9795ms","start":"2026-05-04T19:03:30.121446Z","end":"2026-05-04T19:03:30.373425Z","steps":["trace[1940081328] 'agreement among raft nodes before linearized reading' (duration: 251.833647ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:03:30.373524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T19:03:29.845596Z","time spent":"527.919334ms","remote":"127.0.0.1:35856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-04T19:03:30.373539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T19:03:29.850536Z","time spent":"522.997105ms","remote":"127.0.0.1:35856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-04T19:03:30.373548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.894056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12975"} {"level":"info","ts":"2026-05-04T19:03:30.373578Z","caller":"traceutil/trace.go:171","msg":"trace[1121262537] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4811; }","duration":"166.950927ms","start":"2026-05-04T19:03:30.206618Z","end":"2026-05-04T19:03:30.373569Z","steps":["trace[1121262537] 'agreement among raft nodes before linearized reading' (duration: 166.850735ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:03:30.373393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.715558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-04T19:03:30.373625Z","caller":"traceutil/trace.go:171","msg":"trace[1927073762] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4811; }","duration":"517.986774ms","start":"2026-05-04T19:03:29.855632Z","end":"2026-05-04T19:03:30.373619Z","steps":["trace[1927073762] 'agreement among raft nodes before linearized reading' (duration: 517.649166ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:03:30.373642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T19:03:29.855621Z","time spent":"518.016954ms","remote":"127.0.0.1:35856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-04T19:03:30.37367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.187088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-04T19:03:30.373765Z","caller":"traceutil/trace.go:171","msg":"trace[1033461919] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4811; }","duration":"518.334131ms","start":"2026-05-04T19:03:29.855407Z","end":"2026-05-04T19:03:30.373742Z","steps":["trace[1033461919] 'agreement among raft nodes before linearized reading' (duration: 518.043165ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:03:30.373817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T19:03:29.855388Z","time spent":"518.419524ms","remote":"127.0.0.1:35856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-04T19:03:30.373838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.845684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-04T19:03:30.373885Z","caller":"traceutil/trace.go:171","msg":"trace[1815599946] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:4811; }","duration":"387.492638ms","start":"2026-05-04T19:03:29.986377Z","end":"2026-05-04T19:03:30.37387Z","steps":["trace[1815599946] 'agreement among raft nodes before linearized reading' (duration: 386.840164ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:03:30.373929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T19:03:29.986351Z","time spent":"387.568419ms","remote":"127.0.0.1:36092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":4,"response size":30,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-05-04T19:03:32.333177Z","caller":"traceutil/trace.go:171","msg":"trace[979026603] linearizableReadLoop","detail":"{readStateIndex:5122; appliedIndex:5121; }","duration":"192.581888ms","start":"2026-05-04T19:03:32.140556Z","end":"2026-05-04T19:03:32.333138Z","steps":["trace[979026603] 'read index received' (duration: 107.279988ms)","trace[979026603] 'applied index is now lower than readState.Index' (duration: 85.30033ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T19:03:32.333276Z","caller":"traceutil/trace.go:171","msg":"trace[281653524] transaction","detail":"{read_only:false; response_revision:4936; number_of_response:1; }","duration":"197.729023ms","start":"2026-05-04T19:03:32.135512Z","end":"2026-05-04T19:03:32.333241Z","steps":["trace[281653524] 'process raft request' (duration: 112.337651ms)","trace[281653524] 'compare' (duration: 85.002554ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T19:03:32.333462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.878945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-48fbdbb9-c6aa-4169-a006-25fa7def7da0\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-05-04T19:03:32.333511Z","caller":"traceutil/trace.go:171","msg":"trace[19829286] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-48fbdbb9-c6aa-4169-a006-25fa7def7da0; range_end:; response_count:1; response_revision:4936; }","duration":"192.946296ms","start":"2026-05-04T19:03:32.140553Z","end":"2026-05-04T19:03:32.333499Z","steps":["trace[19829286] 'agreement among raft nodes before linearized reading' (duration: 192.707291ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:03:32.60232Z","caller":"traceutil/trace.go:171","msg":"trace[1962539881] transaction","detail":"{read_only:false; response_revision:4937; number_of_response:1; }","duration":"194.290646ms","start":"2026-05-04T19:03:32.407978Z","end":"2026-05-04T19:03:32.602268Z","steps":["trace[1962539881] 'process raft request' (duration: 192.72149ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:03:32.610631Z","caller":"traceutil/trace.go:171","msg":"trace[1106174393] transaction","detail":"{read_only:false; response_revision:4938; number_of_response:1; }","duration":"200.178528ms","start":"2026-05-04T19:03:32.410416Z","end":"2026-05-04T19:03:32.610594Z","steps":["trace[1106174393] 'process raft request' (duration: 199.874051ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:03:32.610655Z","caller":"traceutil/trace.go:171","msg":"trace[415959872] linearizableReadLoop","detail":"{readStateIndex:5126; appliedIndex:5122; }","duration":"178.029244ms","start":"2026-05-04T19:03:32.432598Z","end":"2026-05-04T19:03:32.610628Z","steps":["trace[415959872] 'read index received' (duration: 168.093933ms)","trace[415959872] 'applied index is now lower than readState.Index' (duration: 9.933971ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T19:03:32.610784Z","caller":"traceutil/trace.go:171","msg":"trace[1123792972] transaction","detail":"{read_only:false; response_revision:4939; number_of_response:1; }","duration":"200.316301ms","start":"2026-05-04T19:03:32.410454Z","end":"2026-05-04T19:03:32.61077Z","steps":["trace[1123792972] 'process raft request' (duration: 199.990123ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:03:32.610977Z","caller":"traceutil/trace.go:171","msg":"trace[1091715637] transaction","detail":"{read_only:false; response_revision:4940; number_of_response:1; }","duration":"200.445094ms","start":"2026-05-04T19:03:32.410505Z","end":"2026-05-04T19:03:32.61095Z","steps":["trace[1091715637] 'process raft request' (duration: 199.988704ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:03:32.611051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.450488ms","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:5"} {"level":"info","ts":"2026-05-04T19:03:32.611093Z","caller":"traceutil/trace.go:171","msg":"trace[72009021] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:4940; }","duration":"114.55777ms","start":"2026-05-04T19:03:32.496523Z","end":"2026-05-04T19:03:32.611081Z","steps":["trace[72009021] 'agreement among raft nodes before linearized reading' (duration: 114.427398ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:03:32.611117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.317414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-48fbdbb9-c6aa-4169-a006-25fa7def7da0\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-05-04T19:03:32.61117Z","caller":"traceutil/trace.go:171","msg":"trace[474491212] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-48fbdbb9-c6aa-4169-a006-25fa7def7da0; range_end:; response_count:1; response_revision:4940; }","duration":"118.446447ms","start":"2026-05-04T19:03:32.492708Z","end":"2026-05-04T19:03:32.611155Z","steps":["trace[474491212] 'agreement among raft nodes before linearized reading' (duration: 118.174661ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:03:32.61125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.150878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-5vpqh.18ac721a5ce79407\" ","response":"range_response_count:1 size:962"} {"level":"warn","ts":"2026-05-04T19:03:32.611279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.668908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:720280"} {"level":"info","ts":"2026-05-04T19:03:32.611308Z","caller":"traceutil/trace.go:171","msg":"trace[571384811] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-5vpqh.18ac721a5ce79407; range_end:; response_count:1; response_revision:4940; }","duration":"117.25258ms","start":"2026-05-04T19:03:32.494041Z","end":"2026-05-04T19:03:32.611294Z","steps":["trace[571384811] 'agreement among raft nodes before linearized reading' (duration: 117.024295ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:03:32.611359Z","caller":"traceutil/trace.go:171","msg":"trace[1318300577] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:4940; }","duration":"178.783261ms","start":"2026-05-04T19:03:32.432553Z","end":"2026-05-04T19:03:32.611337Z","steps":["trace[1318300577] 'agreement among raft nodes before linearized reading' (duration: 178.26442ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:03:51.580484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.268463ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-04T19:03:51.580623Z","caller":"traceutil/trace.go:171","msg":"trace[1754342006] linearizableReadLoop","detail":"{readStateIndex:5537; appliedIndex:5536; }","duration":"173.330141ms","start":"2026-05-04T19:03:51.407273Z","end":"2026-05-04T19:03:51.580603Z","steps":["trace[1754342006] 'read index received' (duration: 53.784131ms)","trace[1754342006] 'applied index is now lower than readState.Index' (duration: 119.54464ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T19:03:51.580627Z","caller":"traceutil/trace.go:171","msg":"trace[1718126966] transaction","detail":"{read_only:false; response_revision:5338; number_of_response:1; }","duration":"218.856388ms","start":"2026-05-04T19:03:51.361745Z","end":"2026-05-04T19:03:51.580602Z","steps":["trace[1718126966] 'process raft request' (duration: 99.362969ms)","trace[1718126966] 'compare' (duration: 118.947876ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T19:03:51.581527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.2422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2212891"} {"level":"info","ts":"2026-05-04T19:03:51.581722Z","caller":"traceutil/trace.go:171","msg":"trace[227206719] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:5338; }","duration":"174.496726ms","start":"2026-05-04T19:03:51.407209Z","end":"2026-05-04T19:03:51.581706Z","steps":["trace[227206719] 'agreement among raft nodes before linearized reading' (duration: 173.462353ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:03:51.891527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.800389ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-04T19:03:51.891615Z","caller":"traceutil/trace.go:171","msg":"trace[2033106366] linearizableReadLoop","detail":"{readStateIndex:5538; appliedIndex:5537; }","duration":"285.113507ms","start":"2026-05-04T19:03:51.606487Z","end":"2026-05-04T19:03:51.891601Z","steps":["trace[2033106366] 'read index received' (duration: 86.025221ms)","trace[2033106366] 'applied index is now lower than readState.Index' (duration: 199.087386ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T19:03:51.891659Z","caller":"traceutil/trace.go:171","msg":"trace[356682281] transaction","detail":"{read_only:false; response_revision:5339; number_of_response:1; }","duration":"306.591346ms","start":"2026-05-04T19:03:51.585029Z","end":"2026-05-04T19:03:51.891621Z","steps":["trace[356682281] 'process raft request' (duration: 107.601233ms)","trace[356682281] 'compare' (duration: 198.690015ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T19:03:51.891751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.2742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-05-04T19:03:51.891784Z","caller":"traceutil/trace.go:171","msg":"trace[768688227] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:5339; }","duration":"285.332691ms","start":"2026-05-04T19:03:51.606436Z","end":"2026-05-04T19:03:51.891769Z","steps":["trace[768688227] 'agreement among raft nodes before linearized reading' (duration: 285.222259ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:03:51.891852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T19:03:51.585007Z","time spent":"306.74122ms","remote":"127.0.0.1:35856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-04T19:03:52.146204Z","caller":"traceutil/trace.go:171","msg":"trace[891002199] linearizableReadLoop","detail":"{readStateIndex:5539; appliedIndex:5538; }","duration":"254.543683ms","start":"2026-05-04T19:03:51.891634Z","end":"2026-05-04T19:03:52.146177Z","steps":["trace[891002199] 'read index received' (duration: 254.40708ms)","trace[891002199] 'applied index is now lower than readState.Index' (duration: 135.493µs)"],"step_count":2} {"level":"info","ts":"2026-05-04T19:03:52.146284Z","caller":"traceutil/trace.go:171","msg":"trace[705082500] transaction","detail":"{read_only:false; response_revision:5340; number_of_response:1; }","duration":"351.065858ms","start":"2026-05-04T19:03:51.795189Z","end":"2026-05-04T19:03:52.146255Z","steps":["trace[705082500] 'process raft request' (duration: 350.837873ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:03:52.146449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T19:03:51.795153Z","time spent":"351.202142ms","remote":"127.0.0.1:35856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T19:03:52.146479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.731832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-07d5a5f4-7e5f-48df-bf71-326df224a905\" ","response":"range_response_count:1 size:3646"} {"level":"warn","ts":"2026-05-04T19:03:52.146605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.596782ms","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-05-04T19:03:52.146624Z","caller":"traceutil/trace.go:171","msg":"trace[1212992707] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-07d5a5f4-7e5f-48df-bf71-326df224a905; range_end:; response_count:1; response_revision:5340; }","duration":"419.976417ms","start":"2026-05-04T19:03:51.726627Z","end":"2026-05-04T19:03:52.146603Z","steps":["trace[1212992707] 'agreement among raft nodes before linearized reading' (duration: 419.67352ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:03:52.146684Z","caller":"traceutil/trace.go:171","msg":"trace[2140018270] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5340; }","duration":"532.698134ms","start":"2026-05-04T19:03:51.613968Z","end":"2026-05-04T19:03:52.146666Z","steps":["trace[2140018270] 'agreement among raft nodes before linearized reading' (duration: 532.49317ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:03:52.146726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T19:03:51.613961Z","time spent":"532.756406ms","remote":"127.0.0.1:35670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":1840,"request content":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" "} {"level":"warn","ts":"2026-05-04T19:03:52.146776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T19:03:51.726603Z","time spent":"420.11352ms","remote":"127.0.0.1:35782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3669,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-07d5a5f4-7e5f-48df-bf71-326df224a905\" "} {"level":"info","ts":"2026-05-04T19:03:52.189116Z","caller":"traceutil/trace.go:171","msg":"trace[1425501098] transaction","detail":"{read_only:false; response_revision:5341; number_of_response:1; }","duration":"292.597413ms","start":"2026-05-04T19:03:51.89648Z","end":"2026-05-04T19:03:52.189077Z","steps":["trace[1425501098] 'process raft request' (duration: 292.370978ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:05:25.305342Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3046} {"level":"info","ts":"2026-05-04T19:05:25.391118Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3046,"took":"84.058695ms","hash":1335417288,"current-db-size-bytes":57593856,"current-db-size":"58 MB","current-db-size-in-use-bytes":31903744,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-04T19:05:25.391191Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1335417288,"revision":3046,"compact-revision":-1} {"level":"info","ts":"2026-05-04T19:10:02.158637Z","caller":"traceutil/trace.go:171","msg":"trace[1794053336] linearizableReadLoop","detail":"{readStateIndex:8481; appliedIndex:8480; }","duration":"332.651175ms","start":"2026-05-04T19:10:01.825962Z","end":"2026-05-04T19:10:02.158613Z","steps":["trace[1794053336] 'read index received' (duration: 332.479651ms)","trace[1794053336] 'applied index is now lower than readState.Index' (duration: 170.274µs)"],"step_count":2} {"level":"info","ts":"2026-05-04T19:10:02.158685Z","caller":"traceutil/trace.go:171","msg":"trace[13492973] transaction","detail":"{read_only:false; response_revision:8193; number_of_response:1; }","duration":"605.943961ms","start":"2026-05-04T19:10:01.552708Z","end":"2026-05-04T19:10:02.158652Z","steps":["trace[13492973] 'process raft request' (duration: 605.765657ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:10:02.158811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T19:10:01.552686Z","time spent":"606.052302ms","remote":"127.0.0.1:35856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T19:10:02.158993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.020004ms","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-05-04T19:10:02.159062Z","caller":"traceutil/trace.go:171","msg":"trace[1329151634] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8193; }","duration":"333.120035ms","start":"2026-05-04T19:10:01.82593Z","end":"2026-05-04T19:10:02.15905Z","steps":["trace[1329151634] 'agreement among raft nodes before linearized reading' (duration: 332.804548ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:10:02.159125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T19:10:01.825918Z","time spent":"333.168486ms","remote":"127.0.0.1:35856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-04T19:10:12.690941Z","caller":"traceutil/trace.go:171","msg":"trace[539329142] transaction","detail":"{read_only:false; response_revision:8251; number_of_response:1; }","duration":"121.021351ms","start":"2026-05-04T19:10:12.569885Z","end":"2026-05-04T19:10:12.690906Z","steps":["trace[539329142] 'process raft request' (duration: 120.820806ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:10:12.94465Z","caller":"traceutil/trace.go:171","msg":"trace[1996157355] transaction","detail":"{read_only:false; response_revision:8255; number_of_response:1; }","duration":"103.27111ms","start":"2026-05-04T19:10:12.841306Z","end":"2026-05-04T19:10:12.944577Z","steps":["trace[1996157355] 'process raft request' (duration: 69.11233ms)","trace[1996157355] 'compare' (duration: 33.842523ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T19:10:13.226315Z","caller":"traceutil/trace.go:171","msg":"trace[320320900] linearizableReadLoop","detail":"{readStateIndex:8547; appliedIndex:8546; }","duration":"105.125212ms","start":"2026-05-04T19:10:13.121155Z","end":"2026-05-04T19:10:13.226281Z","steps":["trace[320320900] 'read index received' (duration: 99.347752ms)","trace[320320900] 'applied index is now lower than readState.Index' (duration: 5.77612ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T19:10:13.22652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.347367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T19:10:13.226583Z","caller":"traceutil/trace.go:171","msg":"trace[2026216736] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8256; }","duration":"105.46106ms","start":"2026-05-04T19:10:13.121103Z","end":"2026-05-04T19:10:13.226564Z","steps":["trace[2026216736] 'agreement among raft nodes before linearized reading' (duration: 105.313166ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:10:13.226764Z","caller":"traceutil/trace.go:171","msg":"trace[1409343447] transaction","detail":"{read_only:false; response_revision:8256; number_of_response:1; }","duration":"170.916216ms","start":"2026-05-04T19:10:13.055813Z","end":"2026-05-04T19:10:13.226729Z","steps":["trace[1409343447] 'process raft request' (duration: 164.812439ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:10:14.460381Z","caller":"traceutil/trace.go:171","msg":"trace[1534861263] transaction","detail":"{read_only:false; response_revision:8260; number_of_response:1; }","duration":"124.046238ms","start":"2026-05-04T19:10:14.336294Z","end":"2026-05-04T19:10:14.46034Z","steps":["trace[1534861263] 'process raft request' (duration: 111.098626ms)","trace[1534861263] 'compare' (duration: 12.699436ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T19:10:16.222674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.910271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T19:10:16.222765Z","caller":"traceutil/trace.go:171","msg":"trace[350982911] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8268; }","duration":"173.040574ms","start":"2026-05-04T19:10:16.049703Z","end":"2026-05-04T19:10:16.222744Z","steps":["trace[350982911] 'range keys from in-memory index tree' (duration: 172.825018ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:10:16.222942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.288344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-778457575c-hc6qd.18ac72742526177d\" ","response":"range_response_count:1 size:1135"} {"level":"info","ts":"2026-05-04T19:10:16.223062Z","caller":"traceutil/trace.go:171","msg":"trace[1801687835] range","detail":"{range_begin:/registry/events/openstack/barbican-api-778457575c-hc6qd.18ac72742526177d; range_end:; response_count:1; response_revision:8268; }","duration":"151.409697ms","start":"2026-05-04T19:10:16.071592Z","end":"2026-05-04T19:10:16.223002Z","steps":["trace[1801687835] 'range keys from in-memory index tree' (duration: 151.103948ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:10:25.312249Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6153} {"level":"info","ts":"2026-05-04T19:10:25.410133Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6153,"took":"95.74528ms","hash":3133573625,"current-db-size-bytes":57593856,"current-db-size":"58 MB","current-db-size-in-use-bytes":23089152,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-05-04T19:10:25.410197Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3133573625,"revision":6153,"compact-revision":3046} {"level":"info","ts":"2026-05-04T19:10:44.262779Z","caller":"traceutil/trace.go:171","msg":"trace[613363691] transaction","detail":"{read_only:false; response_revision:8475; number_of_response:1; }","duration":"132.147271ms","start":"2026-05-04T19:10:44.130594Z","end":"2026-05-04T19:10:44.262741Z","steps":["trace[613363691] 'process raft request' (duration: 131.917126ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:10:56.055124Z","caller":"traceutil/trace.go:171","msg":"trace[999987480] transaction","detail":"{read_only:false; response_revision:8579; number_of_response:1; }","duration":"136.788746ms","start":"2026-05-04T19:10:55.91831Z","end":"2026-05-04T19:10:56.055099Z","steps":["trace[999987480] 'process raft request' (duration: 136.100871ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:10:56.579433Z","caller":"traceutil/trace.go:171","msg":"trace[723367831] transaction","detail":"{read_only:false; response_revision:8583; number_of_response:1; }","duration":"150.692699ms","start":"2026-05-04T19:10:56.428714Z","end":"2026-05-04T19:10:56.579407Z","steps":["trace[723367831] 'process raft request' (duration: 150.536216ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:10:56.755662Z","caller":"traceutil/trace.go:171","msg":"trace[883522781] transaction","detail":"{read_only:false; response_revision:8584; number_of_response:1; }","duration":"155.953908ms","start":"2026-05-04T19:10:56.599684Z","end":"2026-05-04T19:10:56.755638Z","steps":["trace[883522781] 'process raft request' (duration: 136.289704ms)","trace[883522781] 'compare' (duration: 19.537381ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T19:10:56.755673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.202426ms","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-05-04T19:10:56.75576Z","caller":"traceutil/trace.go:171","msg":"trace[820024917] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8583; }","duration":"157.334999ms","start":"2026-05-04T19:10:56.598408Z","end":"2026-05-04T19:10:56.755743Z","steps":["trace[820024917] 'range keys from in-memory index tree' (duration: 156.982642ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:10:56.995292Z","caller":"traceutil/trace.go:171","msg":"trace[1275756577] transaction","detail":"{read_only:false; response_revision:8585; number_of_response:1; }","duration":"235.716608ms","start":"2026-05-04T19:10:56.759548Z","end":"2026-05-04T19:10:56.995265Z","steps":["trace[1275756577] 'process raft request' (duration: 160.94672ms)","trace[1275756577] 'compare' (duration: 74.570093ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T19:10:57.074983Z","caller":"traceutil/trace.go:171","msg":"trace[580766938] linearizableReadLoop","detail":"{readStateIndex:8893; appliedIndex:8890; }","duration":"160.675014ms","start":"2026-05-04T19:10:56.914287Z","end":"2026-05-04T19:10:57.074962Z","steps":["trace[580766938] 'read index received' (duration: 6.207829ms)","trace[580766938] 'applied index is now lower than readState.Index' (duration: 154.466545ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T19:10:57.075244Z","caller":"traceutil/trace.go:171","msg":"trace[685701539] transaction","detail":"{read_only:false; response_revision:8587; number_of_response:1; }","duration":"178.338533ms","start":"2026-05-04T19:10:56.896864Z","end":"2026-05-04T19:10:57.075203Z","steps":["trace[685701539] 'process raft request' (duration: 178.060226ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:10:57.075385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.091583ms","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-05-04T19:10:57.075718Z","caller":"traceutil/trace.go:171","msg":"trace[1122384619] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8587; }","duration":"161.443852ms","start":"2026-05-04T19:10:56.914236Z","end":"2026-05-04T19:10:57.07568Z","steps":["trace[1122384619] 'agreement among raft nodes before linearized reading' (duration: 161.056023ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:10:57.075366Z","caller":"traceutil/trace.go:171","msg":"trace[1255107496] transaction","detail":"{read_only:false; response_revision:8586; number_of_response:1; }","duration":"222.776315ms","start":"2026-05-04T19:10:56.852503Z","end":"2026-05-04T19:10:57.075279Z","steps":["trace[1255107496] 'process raft request' (duration: 222.311595ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:11:38.472269Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002443.wal"} {"level":"info","ts":"2026-05-04T19:12:59.223884Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4e46287fb5aa4785","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-04T19:12:59.230611Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-04T19:12:59.230738Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"warn","ts":"2026-05-04T19:14:33.625746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.582117ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-04T19:14:33.626037Z","caller":"traceutil/trace.go:171","msg":"trace[1212021624] linearizableReadLoop","detail":"{readStateIndex:10752; appliedIndex:10751; }","duration":"133.829694ms","start":"2026-05-04T19:14:33.492182Z","end":"2026-05-04T19:14:33.626011Z","steps":["trace[1212021624] 'read index received' (duration: 24.987241ms)","trace[1212021624] 'applied index is now lower than readState.Index' (duration: 108.840852ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T19:14:33.626238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.025028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T19:14:33.626335Z","caller":"traceutil/trace.go:171","msg":"trace[1891139007] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:10390; }","duration":"134.188431ms","start":"2026-05-04T19:14:33.492124Z","end":"2026-05-04T19:14:33.626313Z","steps":["trace[1891139007] 'agreement among raft nodes before linearized reading' (duration: 133.993857ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:15:25.322511Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8326} {"level":"info","ts":"2026-05-04T19:15:25.398556Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8326,"took":"74.110073ms","hash":1351668324,"current-db-size-bytes":57593856,"current-db-size":"58 MB","current-db-size-in-use-bytes":26353664,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-04T19:15:25.398606Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1351668324,"revision":8326,"compact-revision":6153} {"level":"info","ts":"2026-05-04T19:15:38.236314Z","caller":"traceutil/trace.go:171","msg":"trace[860640061] linearizableReadLoop","detail":"{readStateIndex:11450; appliedIndex:11449; }","duration":"110.801168ms","start":"2026-05-04T19:15:38.125485Z","end":"2026-05-04T19:15:38.236286Z","steps":["trace[860640061] 'read index received' (duration: 110.46421ms)","trace[860640061] 'applied index is now lower than readState.Index' (duration: 335.968µs)"],"step_count":2} {"level":"info","ts":"2026-05-04T19:15:38.236418Z","caller":"traceutil/trace.go:171","msg":"trace[1118514687] transaction","detail":"{read_only:false; response_revision:11072; number_of_response:1; }","duration":"111.507033ms","start":"2026-05-04T19:15:38.124887Z","end":"2026-05-04T19:15:38.236394Z","steps":["trace[1118514687] 'process raft request' (duration: 111.170306ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:15:38.236463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.965021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T19:15:38.236504Z","caller":"traceutil/trace.go:171","msg":"trace[1215634315] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11072; }","duration":"111.069763ms","start":"2026-05-04T19:15:38.125423Z","end":"2026-05-04T19:15:38.236492Z","steps":["trace[1215634315] 'agreement among raft nodes before linearized reading' (duration: 110.981981ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:15:56.64111Z","caller":"traceutil/trace.go:171","msg":"trace[2093330703] linearizableReadLoop","detail":"{readStateIndex:11555; appliedIndex:11554; }","duration":"184.533179ms","start":"2026-05-04T19:15:56.456547Z","end":"2026-05-04T19:15:56.64108Z","steps":["trace[2093330703] 'read index received' (duration: 168.839039ms)","trace[2093330703] 'applied index is now lower than readState.Index' (duration: 15.6925ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T19:15:56.641249Z","caller":"traceutil/trace.go:171","msg":"trace[1480485769] transaction","detail":"{read_only:false; response_revision:11173; number_of_response:1; }","duration":"200.193248ms","start":"2026-05-04T19:15:56.441033Z","end":"2026-05-04T19:15:56.641226Z","steps":["trace[1480485769] 'process raft request' (duration: 184.323514ms)","trace[1480485769] 'compare' (duration: 15.124087ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T19:15:56.641515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.944687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-05-04T19:15:56.641599Z","caller":"traceutil/trace.go:171","msg":"trace[1189179898] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:11173; }","duration":"185.07932ms","start":"2026-05-04T19:15:56.456501Z","end":"2026-05-04T19:15:56.64158Z","steps":["trace[1189179898] 'agreement among raft nodes before linearized reading' (duration: 184.845935ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:15:56.641862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.558909ms","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-05-04T19:15:56.641902Z","caller":"traceutil/trace.go:171","msg":"trace[1862332423] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:11173; }","duration":"122.6391ms","start":"2026-05-04T19:15:56.519253Z","end":"2026-05-04T19:15:56.641892Z","steps":["trace[1862332423] 'agreement among raft nodes before linearized reading' (duration: 122.539238ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:15:56.641872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.904166ms","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-05-04T19:15:56.641979Z","caller":"traceutil/trace.go:171","msg":"trace[850846311] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:11173; }","duration":"145.04405ms","start":"2026-05-04T19:15:56.496908Z","end":"2026-05-04T19:15:56.641952Z","steps":["trace[850846311] 'agreement among raft nodes before linearized reading' (duration: 144.747513ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:20:25.3291Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10949} {"level":"info","ts":"2026-05-04T19:20:25.407622Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10949,"took":"75.883567ms","hash":1080614203,"current-db-size-bytes":57593856,"current-db-size":"58 MB","current-db-size-in-use-bytes":27934720,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-04T19:20:25.407674Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1080614203,"revision":10949,"compact-revision":8326} {"level":"warn","ts":"2026-05-04T19:21:51.340519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.079209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T19:21:51.340616Z","caller":"traceutil/trace.go:171","msg":"trace[742335403] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13589; }","duration":"218.211141ms","start":"2026-05-04T19:21:51.122384Z","end":"2026-05-04T19:21:51.340595Z","steps":["trace[742335403] 'range keys from in-memory index tree' (duration: 218.016967ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:21:51.34102Z","caller":"traceutil/trace.go:171","msg":"trace[563731811] transaction","detail":"{read_only:false; response_revision:13590; number_of_response:1; }","duration":"160.118498ms","start":"2026-05-04T19:21:51.180888Z","end":"2026-05-04T19:21:51.341006Z","steps":["trace[563731811] 'process raft request' (duration: 159.225199ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:25:25.33684Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13010} {"level":"info","ts":"2026-05-04T19:25:25.415252Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13010,"took":"76.709158ms","hash":2077636730,"current-db-size-bytes":57593856,"current-db-size":"58 MB","current-db-size-in-use-bytes":24625152,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-05-04T19:25:25.415317Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2077636730,"revision":13010,"compact-revision":10949} {"level":"info","ts":"2026-05-04T19:30:25.343555Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14683} {"level":"info","ts":"2026-05-04T19:30:25.418627Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14683,"took":"72.534777ms","hash":826493354,"current-db-size-bytes":57593856,"current-db-size":"58 MB","current-db-size-in-use-bytes":20963328,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-05-04T19:30:25.418721Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":826493354,"revision":14683,"compact-revision":13010} {"level":"info","ts":"2026-05-04T19:34:20.729246Z","caller":"traceutil/trace.go:171","msg":"trace[333750779] transaction","detail":"{read_only:false; response_revision:17402; number_of_response:1; }","duration":"187.99481ms","start":"2026-05-04T19:34:20.541239Z","end":"2026-05-04T19:34:20.729234Z","steps":["trace[333750779] 'process raft request' (duration: 187.859027ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T19:34:20.729356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.409081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-05-04T19:34:20.72942Z","caller":"traceutil/trace.go:171","msg":"trace[1699257119] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:17402; }","duration":"182.492183ms","start":"2026-05-04T19:34:20.546897Z","end":"2026-05-04T19:34:20.72939Z","steps":["trace[1699257119] 'agreement among raft nodes before linearized reading' (duration: 182.36485ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:34:20.729249Z","caller":"traceutil/trace.go:171","msg":"trace[1111548975] linearizableReadLoop","detail":"{readStateIndex:18027; appliedIndex:18026; }","duration":"182.277098ms","start":"2026-05-04T19:34:20.546943Z","end":"2026-05-04T19:34:20.72922Z","steps":["trace[1111548975] 'read index received' (duration: 182.084073ms)","trace[1111548975] 'applied index is now lower than readState.Index' (duration: 192.275µs)"],"step_count":2} {"level":"info","ts":"2026-05-04T19:35:25.350191Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16210} {"level":"info","ts":"2026-05-04T19:35:25.423182Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16210,"took":"70.28604ms","hash":228131510,"current-db-size-bytes":57593856,"current-db-size":"58 MB","current-db-size-in-use-bytes":20471808,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-05-04T19:35:25.423251Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":228131510,"revision":16210,"compact-revision":14683} {"level":"info","ts":"2026-05-04T19:37:24.4756Z","caller":"traceutil/trace.go:171","msg":"trace[1574533837] transaction","detail":"{read_only:false; response_revision:18334; number_of_response:1; }","duration":"154.6081ms","start":"2026-05-04T19:37:24.320962Z","end":"2026-05-04T19:37:24.47557Z","steps":["trace[1574533837] 'process raft request' (duration: 154.429606ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:40:25.357835Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17730} {"level":"info","ts":"2026-05-04T19:40:25.425199Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17730,"took":"64.436747ms","hash":3537716012,"current-db-size-bytes":57593856,"current-db-size":"58 MB","current-db-size-in-use-bytes":20471808,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-05-04T19:40:25.425318Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3537716012,"revision":17730,"compact-revision":16210} {"level":"info","ts":"2026-05-04T19:40:33.922329Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4e46287fb5aa4785","local-member-applied-index":20003,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-04T19:40:33.927119Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20003} {"level":"info","ts":"2026-05-04T19:40:33.927303Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15003} {"level":"info","ts":"2026-05-04T19:45:25.365271Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19252} {"level":"info","ts":"2026-05-04T19:45:25.43003Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19252,"took":"62.675ms","hash":294348163,"current-db-size-bytes":57593856,"current-db-size":"58 MB","current-db-size-in-use-bytes":20574208,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-05-04T19:45:25.430104Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":294348163,"revision":19252,"compact-revision":17730} {"level":"info","ts":"2026-05-04T19:47:46.825181Z","caller":"traceutil/trace.go:171","msg":"trace[74941682] transaction","detail":"{read_only:false; response_revision:21516; number_of_response:1; }","duration":"124.863839ms","start":"2026-05-04T19:47:46.70029Z","end":"2026-05-04T19:47:46.825154Z","steps":["trace[74941682] 'process raft request' (duration: 124.685165ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:47:46.825928Z","caller":"traceutil/trace.go:171","msg":"trace[1588484848] transaction","detail":"{read_only:false; response_revision:21517; number_of_response:1; }","duration":"124.113811ms","start":"2026-05-04T19:47:46.701783Z","end":"2026-05-04T19:47:46.825897Z","steps":["trace[1588484848] 'process raft request' (duration: 123.930907ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T19:50:25.372161Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20795} {"level":"info","ts":"2026-05-04T19:50:25.451806Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20795,"took":"76.797984ms","hash":1678464877,"current-db-size-bytes":57593856,"current-db-size":"58 MB","current-db-size-in-use-bytes":20520960,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-05-04T19:50:25.451895Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1678464877,"revision":20795,"compact-revision":19252} {"level":"info","ts":"2026-05-04T19:55:25.379351Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22321} {"level":"info","ts":"2026-05-04T19:55:25.445479Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22321,"took":"63.287998ms","hash":4258533721,"current-db-size-bytes":57593856,"current-db-size":"58 MB","current-db-size-in-use-bytes":20537344,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-05-04T19:55:25.445553Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4258533721,"revision":22321,"compact-revision":20795} {"level":"info","ts":"2026-05-04T20:00:25.38632Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23844} {"level":"info","ts":"2026-05-04T20:00:25.453702Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23844,"took":"64.758238ms","hash":2507666970,"current-db-size-bytes":57593856,"current-db-size":"58 MB","current-db-size-in-use-bytes":20680704,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-05-04T20:00:25.453775Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2507666970,"revision":23844,"compact-revision":22321}