{"level":"warn","ts":"2026-04-28T03:21:40.602498Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-28T03:21:40.602581Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.219: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.19.213.219:2380","--initial-cluster=instance=https://199.19.213.219:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.219:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.219:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-28T03:21:40.602638Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-28T03:21:40.602649Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.219:2380"]} {"level":"info","ts":"2026-04-28T03:21:40.602666Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-28T03:21:40.603421Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.219:2379"]} {"level":"info","ts":"2026-04-28T03:21:40.603505Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.219:2380"],"listen-peer-urls":["https://199.19.213.219:2380"],"advertise-client-urls":["https://199.19.213.219:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.219:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.219:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-28T03:21:40.60937Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.603462ms"} {"level":"info","ts":"2026-04-28T03:21:40.618004Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"7e142186203fa167","cluster-id":"e7586aea7e662ee6"} {"level":"info","ts":"2026-04-28T03:21:40.618115Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 switched to configuration voters=()"} {"level":"info","ts":"2026-04-28T03:21:40.618159Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 became follower at term 0"} {"level":"info","ts":"2026-04-28T03:21:40.61818Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 7e142186203fa167 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-28T03:21:40.618193Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 became follower at term 1"} {"level":"info","ts":"2026-04-28T03:21:40.618262Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 switched to configuration voters=(9084923208263508327)"} {"level":"warn","ts":"2026-04-28T03:21:40.628007Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-28T03:21:40.630219Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-28T03:21:40.633917Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-28T03:21:40.636143Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"7e142186203fa167","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-28T03:21:40.636337Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"7e142186203fa167","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-28T03:21:40.636528Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-28T03:21:40.636627Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-28T03:21:40.636643Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-28T03:21:40.638982Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 switched to configuration voters=(9084923208263508327)"} {"level":"info","ts":"2026-04-28T03:21:40.63946Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e7586aea7e662ee6","local-member-id":"7e142186203fa167","added-peer-id":"7e142186203fa167","added-peer-peer-urls":["https://199.19.213.219:2380"]} {"level":"info","ts":"2026-04-28T03:21:40.640869Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-28T03:21:40.640981Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.219:2380"} {"level":"info","ts":"2026-04-28T03:21:40.641043Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.219:2380"} {"level":"info","ts":"2026-04-28T03:21:40.641235Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"7e142186203fa167","initial-advertise-peer-urls":["https://199.19.213.219:2380"],"listen-peer-urls":["https://199.19.213.219:2380"],"advertise-client-urls":["https://199.19.213.219:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.219:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-28T03:21:40.641254Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-28T03:21:41.318538Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 is starting a new election at term 1"} {"level":"info","ts":"2026-04-28T03:21:41.318696Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-28T03:21:41.318753Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 received MsgPreVoteResp from 7e142186203fa167 at term 1"} {"level":"info","ts":"2026-04-28T03:21:41.318798Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 became candidate at term 2"} {"level":"info","ts":"2026-04-28T03:21:41.318843Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 received MsgVoteResp from 7e142186203fa167 at term 2"} {"level":"info","ts":"2026-04-28T03:21:41.318911Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 became leader at term 2"} {"level":"info","ts":"2026-04-28T03:21:41.318961Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 7e142186203fa167 elected leader 7e142186203fa167 at term 2"} {"level":"info","ts":"2026-04-28T03:21:41.319897Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"7e142186203fa167","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.219:2379]}","request-path":"/0/members/7e142186203fa167/attributes","cluster-id":"e7586aea7e662ee6","publish-timeout":"7s"} {"level":"info","ts":"2026-04-28T03:21:41.320268Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T03:21:41.321122Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T03:21:41.32156Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T03:21:41.321768Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-28T03:21:41.321794Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-28T03:21:41.321849Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e7586aea7e662ee6","local-member-id":"7e142186203fa167","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T03:21:41.321935Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T03:21:41.321968Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T03:21:41.324503Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-28T03:21:41.326247Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.219:2379"} {"level":"info","ts":"2026-04-28T03:21:43.825632Z","caller":"traceutil/trace.go:171","msg":"trace[1147351036] transaction","detail":"{read_only:false; response_revision:74; number_of_response:1; }","duration":"217.44296ms","start":"2026-04-28T03:21:43.60816Z","end":"2026-04-28T03:21:43.825603Z","steps":["trace[1147351036] 'process raft request' (duration: 133.214007ms)","trace[1147351036] 'compare' (duration: 84.034589ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:21:43.825606Z","caller":"traceutil/trace.go:171","msg":"trace[466784317] linearizableReadLoop","detail":"{readStateIndex:78; appliedIndex:77; }","duration":"205.490468ms","start":"2026-04-28T03:21:43.620081Z","end":"2026-04-28T03:21:43.825572Z","steps":["trace[466784317] 'read index received' (duration: 121.280096ms)","trace[466784317] 'applied index is now lower than readState.Index' (duration: 84.208912ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:21:43.826085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.947099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T03:21:43.826281Z","caller":"traceutil/trace.go:171","msg":"trace[1662112466] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:74; }","duration":"206.187585ms","start":"2026-04-28T03:21:43.620077Z","end":"2026-04-28T03:21:43.826265Z","steps":["trace[1662112466] 'agreement among raft nodes before linearized reading' (duration: 205.605501ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:21:44.214888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.286608ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-28T03:21:44.215029Z","caller":"traceutil/trace.go:171","msg":"trace[1452953390] linearizableReadLoop","detail":"{readStateIndex:105; appliedIndex:104; }","duration":"221.205939ms","start":"2026-04-28T03:21:43.9938Z","end":"2026-04-28T03:21:44.215006Z","steps":["trace[1452953390] 'read index received' (duration: 93.0331ms)","trace[1452953390] 'applied index is now lower than readState.Index' (duration: 128.171598ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:21:44.215129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.330702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:kubelet-serving-approver\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T03:21:44.215163Z","caller":"traceutil/trace.go:171","msg":"trace[1011970370] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:kubelet-serving-approver; range_end:; response_count:0; response_revision:100; }","duration":"221.409824ms","start":"2026-04-28T03:21:43.993743Z","end":"2026-04-28T03:21:44.215152Z","steps":["trace[1011970370] 'agreement among raft nodes before linearized reading' (duration: 221.316872ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:21:44.215177Z","caller":"traceutil/trace.go:171","msg":"trace[828669888] transaction","detail":"{read_only:false; response_revision:100; number_of_response:1; }","duration":"279.204794ms","start":"2026-04-28T03:21:43.935877Z","end":"2026-04-28T03:21:44.215082Z","steps":["trace[828669888] 'process raft request' (duration: 151.046786ms)","trace[828669888] 'compare' (duration: 127.139074ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:21:44.353405Z","caller":"traceutil/trace.go:171","msg":"trace[1434544173] transaction","detail":"{read_only:false; response_revision:102; number_of_response:1; }","duration":"133.506664ms","start":"2026-04-28T03:21:44.219874Z","end":"2026-04-28T03:21:44.353381Z","steps":["trace[1434544173] 'process raft request' (duration: 133.463453ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:21:44.353496Z","caller":"traceutil/trace.go:171","msg":"trace[1244267180] transaction","detail":"{read_only:false; response_revision:101; number_of_response:1; }","duration":"134.944187ms","start":"2026-04-28T03:21:44.218488Z","end":"2026-04-28T03:21:44.353433Z","steps":["trace[1244267180] 'process raft request' (duration: 131.458605ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:21:47.866529Z","caller":"traceutil/trace.go:171","msg":"trace[851929876] transaction","detail":"{read_only:false; number_of_response:0; response_revision:308; }","duration":"132.940671ms","start":"2026-04-28T03:21:47.733566Z","end":"2026-04-28T03:21:47.866507Z","steps":["trace[851929876] 'process raft request' (duration: 132.746896ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:21:47.866641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.985304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/persistent-volume-binder\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:21:47.866682Z","caller":"traceutil/trace.go:171","msg":"trace[176192552] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/persistent-volume-binder; range_end:; response_count:0; response_revision:309; }","duration":"128.101667ms","start":"2026-04-28T03:21:47.738568Z","end":"2026-04-28T03:21:47.86667Z","steps":["trace[176192552] 'agreement among raft nodes before linearized reading' (duration: 128.015625ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:21:47.866528Z","caller":"traceutil/trace.go:171","msg":"trace[1911644817] linearizableReadLoop","detail":"{readStateIndex:319; appliedIndex:317; }","duration":"127.863381ms","start":"2026-04-28T03:21:47.738638Z","end":"2026-04-28T03:21:47.866502Z","steps":["trace[1911644817] 'read index received' (duration: 71.493243ms)","trace[1911644817] 'applied index is now lower than readState.Index' (duration: 56.369078ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:21:47.866677Z","caller":"traceutil/trace.go:171","msg":"trace[1735564744] transaction","detail":"{read_only:false; response_revision:309; number_of_response:1; }","duration":"117.987979ms","start":"2026-04-28T03:21:47.748649Z","end":"2026-04-28T03:21:47.866637Z","steps":["trace[1735564744] 'process raft request' (duration: 117.757553ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:22:09.557952Z","caller":"traceutil/trace.go:171","msg":"trace[250077458] linearizableReadLoop","detail":"{readStateIndex:458; appliedIndex:457; }","duration":"111.701577ms","start":"2026-04-28T03:22:09.446225Z","end":"2026-04-28T03:22:09.557926Z","steps":["trace[250077458] 'read index received' (duration: 111.619385ms)","trace[250077458] 'applied index is now lower than readState.Index' (duration: 80.862µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:22:09.558101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.861381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-28T03:22:09.558131Z","caller":"traceutil/trace.go:171","msg":"trace[1026812431] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:443; }","duration":"111.968453ms","start":"2026-04-28T03:22:09.446156Z","end":"2026-04-28T03:22:09.558124Z","steps":["trace[1026812431] 'agreement among raft nodes before linearized reading' (duration: 111.877661ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:22:09.558255Z","caller":"traceutil/trace.go:171","msg":"trace[2047260814] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"337.087517ms","start":"2026-04-28T03:22:09.22116Z","end":"2026-04-28T03:22:09.558247Z","steps":["trace[2047260814] 'process raft request' (duration: 336.609085ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:22:09.558508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:22:09.221133Z","time spent":"337.142677ms","remote":"127.0.0.1:42850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T03:22:09.691813Z","caller":"traceutil/trace.go:171","msg":"trace[951526157] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"129.091539ms","start":"2026-04-28T03:22:09.562694Z","end":"2026-04-28T03:22:09.691786Z","steps":["trace[951526157] 'process raft request' (duration: 59.031339ms)","trace[951526157] 'compare' (duration: 69.940857ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:22:31.508767Z","caller":"traceutil/trace.go:171","msg":"trace[1903145172] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"233.958763ms","start":"2026-04-28T03:22:31.274784Z","end":"2026-04-28T03:22:31.508743Z","steps":["trace[1903145172] 'process raft request' (duration: 233.806099ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:22:31.522917Z","caller":"traceutil/trace.go:171","msg":"trace[1834282336] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"239.096515ms","start":"2026-04-28T03:22:31.283801Z","end":"2026-04-28T03:22:31.522898Z","steps":["trace[1834282336] 'process raft request' (duration: 238.962261ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:22:31.523087Z","caller":"traceutil/trace.go:171","msg":"trace[1421414390] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"200.602593ms","start":"2026-04-28T03:22:31.322428Z","end":"2026-04-28T03:22:31.523031Z","steps":["trace[1421414390] 'process raft request' (duration: 200.50616ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:22:31.523255Z","caller":"traceutil/trace.go:171","msg":"trace[63312612] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"182.215898ms","start":"2026-04-28T03:22:31.341024Z","end":"2026-04-28T03:22:31.52324Z","steps":["trace[63312612] 'process raft request' (duration: 182.028533ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:22:31.522983Z","caller":"traceutil/trace.go:171","msg":"trace[299957787] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"222.368179ms","start":"2026-04-28T03:22:31.300587Z","end":"2026-04-28T03:22:31.522955Z","steps":["trace[299957787] 'process raft request' (duration: 222.287287ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:22:31.522965Z","caller":"traceutil/trace.go:171","msg":"trace[1765192593] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"229.739773ms","start":"2026-04-28T03:22:31.293196Z","end":"2026-04-28T03:22:31.522936Z","steps":["trace[1765192593] 'process raft request' (duration: 229.650551ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:22:31.523033Z","caller":"traceutil/trace.go:171","msg":"trace[1731867404] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"193.792982ms","start":"2026-04-28T03:22:31.329232Z","end":"2026-04-28T03:22:31.523025Z","steps":["trace[1731867404] 'process raft request' (duration: 193.72983ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:22:31.523049Z","caller":"traceutil/trace.go:171","msg":"trace[21722334] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"206.77374ms","start":"2026-04-28T03:22:31.316207Z","end":"2026-04-28T03:22:31.522981Z","steps":["trace[21722334] 'process raft request' (duration: 206.699388ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:22:31.523128Z","caller":"traceutil/trace.go:171","msg":"trace[654928047] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"190.617786ms","start":"2026-04-28T03:22:31.332502Z","end":"2026-04-28T03:22:31.52312Z","steps":["trace[654928047] 'process raft request' (duration: 190.487113ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:22:31.523369Z","caller":"traceutil/trace.go:171","msg":"trace[1534429432] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"179.518743ms","start":"2026-04-28T03:22:31.343829Z","end":"2026-04-28T03:22:31.523348Z","steps":["trace[1534429432] 'process raft request' (duration: 179.299508ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:22:31.523821Z","caller":"traceutil/trace.go:171","msg":"trace[1467836273] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"160.188335ms","start":"2026-04-28T03:22:31.363616Z","end":"2026-04-28T03:22:31.523805Z","steps":["trace[1467836273] 'process raft request' (duration: 159.865807ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:22:37.848786Z","caller":"traceutil/trace.go:171","msg":"trace[949842326] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"147.634638ms","start":"2026-04-28T03:22:37.701124Z","end":"2026-04-28T03:22:37.848759Z","steps":["trace[949842326] 'process raft request' (duration: 147.447204ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:22:37.988123Z","caller":"traceutil/trace.go:171","msg":"trace[301627799] linearizableReadLoop","detail":"{readStateIndex:692; appliedIndex:691; }","duration":"146.825439ms","start":"2026-04-28T03:22:37.84128Z","end":"2026-04-28T03:22:37.988105Z","steps":["trace[301627799] 'read index received' (duration: 8.216915ms)","trace[301627799] 'applied index is now lower than readState.Index' (duration: 138.607974ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:22:37.98823Z","caller":"traceutil/trace.go:171","msg":"trace[1763233198] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"260.963333ms","start":"2026-04-28T03:22:37.72723Z","end":"2026-04-28T03:22:37.988193Z","steps":["trace[1763233198] 'process raft request' (duration: 240.287703ms)","trace[1763233198] 'compare' (duration: 20.490955ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:22:37.988289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.991042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-28T03:22:37.988341Z","caller":"traceutil/trace.go:171","msg":"trace[62536151] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:666; }","duration":"147.094475ms","start":"2026-04-28T03:22:37.841235Z","end":"2026-04-28T03:22:37.988329Z","steps":["trace[62536151] 'agreement among raft nodes before linearized reading' (duration: 146.956222ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:22:38.273535Z","caller":"traceutil/trace.go:171","msg":"trace[361811535] linearizableReadLoop","detail":"{readStateIndex:693; appliedIndex:692; }","duration":"285.337151ms","start":"2026-04-28T03:22:37.988174Z","end":"2026-04-28T03:22:38.273511Z","steps":["trace[361811535] 'read index received' (duration: 284.553342ms)","trace[361811535] 'applied index is now lower than readState.Index' (duration: 782.899µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:22:38.27365Z","caller":"traceutil/trace.go:171","msg":"trace[790132860] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"289.628992ms","start":"2026-04-28T03:22:37.983975Z","end":"2026-04-28T03:22:38.273604Z","steps":["trace[790132860] 'process raft request' (duration: 288.966506ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:22:38.273756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.941436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-57rmx\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-28T03:22:38.273804Z","caller":"traceutil/trace.go:171","msg":"trace[1313977491] range","detail":"{range_begin:/registry/pods/kube-system/cilium-57rmx; range_end:; response_count:1; response_revision:667; }","duration":"419.018878ms","start":"2026-04-28T03:22:37.854762Z","end":"2026-04-28T03:22:38.27378Z","steps":["trace[1313977491] 'agreement among raft nodes before linearized reading' (duration: 418.855374ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:22:38.273839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:22:37.854745Z","time spent":"419.087029ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-57rmx\" "} {"level":"info","ts":"2026-04-28T03:22:38.499447Z","caller":"traceutil/trace.go:171","msg":"trace[2035602865] linearizableReadLoop","detail":"{readStateIndex:694; appliedIndex:693; }","duration":"225.824641ms","start":"2026-04-28T03:22:38.273597Z","end":"2026-04-28T03:22:38.499421Z","steps":["trace[2035602865] 'read index received' (duration: 172.143829ms)","trace[2035602865] 'applied index is now lower than readState.Index' (duration: 53.679772ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:22:38.499641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.690675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:22:38.499681Z","caller":"traceutil/trace.go:171","msg":"trace[249612203] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:668; }","duration":"330.778098ms","start":"2026-04-28T03:22:38.16889Z","end":"2026-04-28T03:22:38.499668Z","steps":["trace[249612203] 'agreement among raft nodes before linearized reading' (duration: 330.697666ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:22:38.499723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:22:38.168872Z","time spent":"330.843069ms","remote":"127.0.0.1:42538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-28T03:22:38.499866Z","caller":"traceutil/trace.go:171","msg":"trace[1361440948] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"507.682839ms","start":"2026-04-28T03:22:37.992154Z","end":"2026-04-28T03:22:38.499837Z","steps":["trace[1361440948] 'process raft request' (duration: 453.646128ms)","trace[1361440948] 'compare' (duration: 53.517248ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:22:38.500164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:22:37.992135Z","time spent":"507.833063ms","remote":"127.0.0.1:42850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T03:22:38.530802Z","caller":"traceutil/trace.go:171","msg":"trace[779740965] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"239.697279ms","start":"2026-04-28T03:22:38.291067Z","end":"2026-04-28T03:22:38.530765Z","steps":["trace[779740965] 'process raft request' (duration: 239.546696ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:22:38.530897Z","caller":"traceutil/trace.go:171","msg":"trace[253100298] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"199.099677ms","start":"2026-04-28T03:22:38.331778Z","end":"2026-04-28T03:22:38.530878Z","steps":["trace[253100298] 'process raft request' (duration: 198.932923ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:22:39.144933Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11630438088435044247,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-28T03:22:39.224184Z","caller":"traceutil/trace.go:171","msg":"trace[2083475954] linearizableReadLoop","detail":"{readStateIndex:698; appliedIndex:697; }","duration":"579.991731ms","start":"2026-04-28T03:22:38.64417Z","end":"2026-04-28T03:22:39.224161Z","steps":["trace[2083475954] 'read index received' (duration: 579.799867ms)","trace[2083475954] 'applied index is now lower than readState.Index' (duration: 190.914µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:22:39.224277Z","caller":"traceutil/trace.go:171","msg":"trace[1541725479] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"669.655965ms","start":"2026-04-28T03:22:38.554591Z","end":"2026-04-28T03:22:39.224247Z","steps":["trace[1541725479] 'process raft request' (duration: 669.454721ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:22:39.224358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.168215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-28T03:22:39.224402Z","caller":"traceutil/trace.go:171","msg":"trace[1863027586] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:672; }","duration":"580.257498ms","start":"2026-04-28T03:22:38.644134Z","end":"2026-04-28T03:22:39.224391Z","steps":["trace[1863027586] 'agreement among raft nodes before linearized reading' (duration: 580.123055ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:22:39.224412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:22:38.554571Z","time spent":"669.781089ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T03:22:39.224442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:22:38.644113Z","time spent":"580.321869ms","remote":"127.0.0.1:42850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-28T03:22:39.410229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.839533ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T03:22:39.410342Z","caller":"traceutil/trace.go:171","msg":"trace[733980906] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"182.238818ms","start":"2026-04-28T03:22:39.228084Z","end":"2026-04-28T03:22:39.410322Z","steps":["trace[733980906] 'process raft request' (duration: 53.222861ms)","trace[733980906] 'compare' (duration: 128.69854ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:22:39.429083Z","caller":"traceutil/trace.go:171","msg":"trace[1983656457] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"200.374378ms","start":"2026-04-28T03:22:39.228682Z","end":"2026-04-28T03:22:39.429057Z","steps":["trace[1983656457] 'process raft request' (duration: 200.210644ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:22:39.429499Z","caller":"traceutil/trace.go:171","msg":"trace[149708898] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"200.348877ms","start":"2026-04-28T03:22:39.229109Z","end":"2026-04-28T03:22:39.429458Z","steps":["trace[149708898] 'process raft request' (duration: 199.901356ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:22:39.429623Z","caller":"traceutil/trace.go:171","msg":"trace[1539069378] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"199.777323ms","start":"2026-04-28T03:22:39.229833Z","end":"2026-04-28T03:22:39.42961Z","steps":["trace[1539069378] 'process raft request' (duration: 199.540218ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:22:39.429842Z","caller":"traceutil/trace.go:171","msg":"trace[508774019] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"199.407495ms","start":"2026-04-28T03:22:39.230415Z","end":"2026-04-28T03:22:39.429823Z","steps":["trace[508774019] 'process raft request' (duration: 199.067347ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:22:42.194565Z","caller":"traceutil/trace.go:171","msg":"trace[954303046] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"181.589843ms","start":"2026-04-28T03:22:42.012948Z","end":"2026-04-28T03:22:42.194537Z","steps":["trace[954303046] 'process raft request' (duration: 97.49479ms)","trace[954303046] 'compare' (duration: 83.96695ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:22:42.198122Z","caller":"traceutil/trace.go:171","msg":"trace[2128318404] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"132.662513ms","start":"2026-04-28T03:22:42.065427Z","end":"2026-04-28T03:22:42.198089Z","steps":["trace[2128318404] 'process raft request' (duration: 132.515969ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:22:51.664318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.981557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:22:51.66439Z","caller":"traceutil/trace.go:171","msg":"trace[469506441] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:819; }","duration":"105.09482ms","start":"2026-04-28T03:22:51.559275Z","end":"2026-04-28T03:22:51.66437Z","steps":["trace[469506441] 'range keys from in-memory index tree' (duration: 104.903355ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:22:51.664679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.202942ms","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-04-28T03:22:51.664764Z","caller":"traceutil/trace.go:171","msg":"trace[268991629] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:819; }","duration":"105.326285ms","start":"2026-04-28T03:22:51.559421Z","end":"2026-04-28T03:22:51.664747Z","steps":["trace[268991629] 'count revisions from in-memory index tree' (duration: 105.07927ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:22:54.151217Z","caller":"traceutil/trace.go:171","msg":"trace[217658498] linearizableReadLoop","detail":"{readStateIndex:865; appliedIndex:864; }","duration":"187.723487ms","start":"2026-04-28T03:22:53.963468Z","end":"2026-04-28T03:22:54.151192Z","steps":["trace[217658498] 'read index received' (duration: 187.589434ms)","trace[217658498] 'applied index is now lower than readState.Index' (duration: 133.183µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:22:54.151314Z","caller":"traceutil/trace.go:171","msg":"trace[275624792] transaction","detail":"{read_only:false; response_revision:833; number_of_response:1; }","duration":"198.612206ms","start":"2026-04-28T03:22:53.952669Z","end":"2026-04-28T03:22:54.151281Z","steps":["trace[275624792] 'process raft request' (duration: 198.37099ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:22:54.151525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.047406ms","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-04-28T03:22:54.15156Z","caller":"traceutil/trace.go:171","msg":"trace[1058627879] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:833; }","duration":"188.126567ms","start":"2026-04-28T03:22:53.963425Z","end":"2026-04-28T03:22:54.151551Z","steps":["trace[1058627879] 'agreement among raft nodes before linearized reading' (duration: 187.84801ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:23:28.431456Z","caller":"traceutil/trace.go:171","msg":"trace[516896233] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"128.831841ms","start":"2026-04-28T03:23:28.302605Z","end":"2026-04-28T03:23:28.431437Z","steps":["trace[516896233] 'process raft request' (duration: 128.708118ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:23:29.746446Z","caller":"traceutil/trace.go:171","msg":"trace[113495933] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"833.718543ms","start":"2026-04-28T03:23:28.912703Z","end":"2026-04-28T03:23:29.746422Z","steps":["trace[113495933] 'process raft request' (duration: 833.458287ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:23:29.746629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:23:28.912683Z","time spent":"833.841326ms","remote":"127.0.0.1:42850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T03:23:49.167451Z","caller":"traceutil/trace.go:171","msg":"trace[312535633] linearizableReadLoop","detail":"{readStateIndex:1158; appliedIndex:1157; }","duration":"293.321426ms","start":"2026-04-28T03:23:48.874109Z","end":"2026-04-28T03:23:49.16743Z","steps":["trace[312535633] 'read index received' (duration: 293.209294ms)","trace[312535633] 'applied index is now lower than readState.Index' (duration: 111.322µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:23:49.167601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.47351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-28T03:23:49.167638Z","caller":"traceutil/trace.go:171","msg":"trace[397458693] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1112; }","duration":"293.541492ms","start":"2026-04-28T03:23:48.874085Z","end":"2026-04-28T03:23:49.167626Z","steps":["trace[397458693] 'agreement among raft nodes before linearized reading' (duration: 293.426199ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:23:49.167643Z","caller":"traceutil/trace.go:171","msg":"trace[2108922630] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"313.32393ms","start":"2026-04-28T03:23:48.854306Z","end":"2026-04-28T03:23:49.16763Z","steps":["trace[2108922630] 'process raft request' (duration: 313.004573ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:23:49.167727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:23:48.854286Z","time spent":"313.390252ms","remote":"127.0.0.1:42850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T03:23:49.540615Z","caller":"traceutil/trace.go:171","msg":"trace[1557825200] transaction","detail":"{read_only:false; response_revision:1114; number_of_response:1; }","duration":"369.0245ms","start":"2026-04-28T03:23:49.171566Z","end":"2026-04-28T03:23:49.54059Z","steps":["trace[1557825200] 'process raft request' (duration: 368.967868ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:23:49.540674Z","caller":"traceutil/trace.go:171","msg":"trace[701526228] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"495.74295ms","start":"2026-04-28T03:23:49.044898Z","end":"2026-04-28T03:23:49.540641Z","steps":["trace[701526228] 'process raft request' (duration: 410.175333ms)","trace[701526228] 'compare' (duration: 84.839349ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:23:49.540753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:23:49.171549Z","time spent":"369.133542ms","remote":"127.0.0.1:42850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T03:23:49.54083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:23:49.044873Z","time spent":"495.871363ms","remote":"127.0.0.1:42850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T03:23:49.540876Z","caller":"traceutil/trace.go:171","msg":"trace[1551764370] linearizableReadLoop","detail":"{readStateIndex:1159; appliedIndex:1158; }","duration":"372.756928ms","start":"2026-04-28T03:23:49.168108Z","end":"2026-04-28T03:23:49.540865Z","steps":["trace[1551764370] 'read index received' (duration: 287.043158ms)","trace[1551764370] 'applied index is now lower than readState.Index' (duration: 85.71288ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:23:49.541021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.914712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:23:49.541143Z","caller":"traceutil/trace.go:171","msg":"trace[1014427613] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1114; }","duration":"372.969542ms","start":"2026-04-28T03:23:49.168079Z","end":"2026-04-28T03:23:49.541049Z","steps":["trace[1014427613] 'agreement among raft nodes before linearized reading' (duration: 372.827199ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:23:49.541215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:23:49.168064Z","time spent":"373.137267ms","remote":"127.0.0.1:42538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-28T03:23:50.64203Z","caller":"traceutil/trace.go:171","msg":"trace[1874232637] linearizableReadLoop","detail":"{readStateIndex:1164; appliedIndex:1163; }","duration":"119.864568ms","start":"2026-04-28T03:23:50.522126Z","end":"2026-04-28T03:23:50.64199Z","steps":["trace[1874232637] 'read index received' (duration: 58.384032ms)","trace[1874232637] 'applied index is now lower than readState.Index' (duration: 61.478596ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:23:50.642239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.088153ms","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-04-28T03:23:50.64233Z","caller":"traceutil/trace.go:171","msg":"trace[1722239171] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1117; }","duration":"120.222416ms","start":"2026-04-28T03:23:50.522089Z","end":"2026-04-28T03:23:50.642311Z","steps":["trace[1722239171] 'agreement among raft nodes before linearized reading' (duration: 119.97345ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:23:52.017653Z","caller":"traceutil/trace.go:171","msg":"trace[1437750823] linearizableReadLoop","detail":"{readStateIndex:1168; appliedIndex:1167; }","duration":"139.311319ms","start":"2026-04-28T03:23:51.878314Z","end":"2026-04-28T03:23:52.017625Z","steps":["trace[1437750823] 'read index received' (duration: 139.082154ms)","trace[1437750823] 'applied index is now lower than readState.Index' (duration: 228.155µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:23:52.017735Z","caller":"traceutil/trace.go:171","msg":"trace[1547559781] transaction","detail":"{read_only:false; response_revision:1121; number_of_response:1; }","duration":"185.319619ms","start":"2026-04-28T03:23:51.832379Z","end":"2026-04-28T03:23:52.017699Z","steps":["trace[1547559781] 'process raft request' (duration: 185.076193ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:23:52.017902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.573535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-28T03:23:52.017986Z","caller":"traceutil/trace.go:171","msg":"trace[1362331814] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1121; }","duration":"139.704508ms","start":"2026-04-28T03:23:51.878258Z","end":"2026-04-28T03:23:52.017962Z","steps":["trace[1362331814] 'agreement among raft nodes before linearized reading' (duration: 139.476483ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:23:52.018129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.865532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-28T03:23:52.018186Z","caller":"traceutil/trace.go:171","msg":"trace[953531239] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1121; }","duration":"128.975174ms","start":"2026-04-28T03:23:51.889196Z","end":"2026-04-28T03:23:52.018171Z","steps":["trace[953531239] 'agreement among raft nodes before linearized reading' (duration: 128.574395ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:23:52.268882Z","caller":"traceutil/trace.go:171","msg":"trace[517407672] transaction","detail":"{read_only:false; response_revision:1122; number_of_response:1; }","duration":"247.00412ms","start":"2026-04-28T03:23:52.021808Z","end":"2026-04-28T03:23:52.268812Z","steps":["trace[517407672] 'process raft request' (duration: 149.800128ms)","trace[517407672] 'compare' (duration: 96.885534ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:23:52.268914Z","caller":"traceutil/trace.go:171","msg":"trace[1396740082] transaction","detail":"{read_only:false; response_revision:1123; number_of_response:1; }","duration":"246.409736ms","start":"2026-04-28T03:23:52.022476Z","end":"2026-04-28T03:23:52.268886Z","steps":["trace[1396740082] 'process raft request' (duration: 246.135559ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:24:05.069344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.067629ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T03:24:05.06946Z","caller":"traceutil/trace.go:171","msg":"trace[284077313] linearizableReadLoop","detail":"{readStateIndex:1265; appliedIndex:1264; }","duration":"222.77046ms","start":"2026-04-28T03:24:04.846673Z","end":"2026-04-28T03:24:05.069443Z","steps":["trace[284077313] 'read index received' (duration: 47.581µs)","trace[284077313] 'applied index is now lower than readState.Index' (duration: 222.721699ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:24:05.069576Z","caller":"traceutil/trace.go:171","msg":"trace[1057592234] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"747.298397ms","start":"2026-04-28T03:24:04.322263Z","end":"2026-04-28T03:24:05.069561Z","steps":["trace[1057592234] 'process raft request' (duration: 445.931011ms)","trace[1057592234] 'compare' (duration: 300.935756ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:24:05.069643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:24:04.322247Z","time spent":"747.362038ms","remote":"127.0.0.1:42850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T03:24:05.069962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.627677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.219\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-28T03:24:05.070035Z","caller":"traceutil/trace.go:171","msg":"trace[779104491] range","detail":"{range_begin:/registry/masterleases/199.19.213.219; range_end:; response_count:1; response_revision:1213; }","duration":"139.74472ms","start":"2026-04-28T03:24:04.930271Z","end":"2026-04-28T03:24:05.070015Z","steps":["trace[779104491] 'agreement among raft nodes before linearized reading' (duration: 139.545785ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:24:05.070243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.558659ms","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-04-28T03:24:05.070316Z","caller":"traceutil/trace.go:171","msg":"trace[1653244528] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1213; }","duration":"223.640201ms","start":"2026-04-28T03:24:04.846657Z","end":"2026-04-28T03:24:05.070297Z","steps":["trace[1653244528] 'agreement among raft nodes before linearized reading' (duration: 223.415115ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:24:20.257027Z","caller":"traceutil/trace.go:171","msg":"trace[89036750] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"130.333088ms","start":"2026-04-28T03:24:20.126668Z","end":"2026-04-28T03:24:20.257001Z","steps":["trace[89036750] 'process raft request' (duration: 130.190575ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:24:20.399105Z","caller":"traceutil/trace.go:171","msg":"trace[1712026024] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"120.980967ms","start":"2026-04-28T03:24:20.278107Z","end":"2026-04-28T03:24:20.399088Z","steps":["trace[1712026024] 'process raft request' (duration: 45.75018ms)","trace[1712026024] 'compare' (duration: 74.851188ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:24:22.164111Z","caller":"traceutil/trace.go:171","msg":"trace[1821477201] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"407.740899ms","start":"2026-04-28T03:24:21.756346Z","end":"2026-04-28T03:24:22.164087Z","steps":["trace[1821477201] 'process raft request' (duration: 407.546195ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:24:22.164262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:24:21.756328Z","time spent":"407.856852ms","remote":"127.0.0.1:42850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T03:24:23.319656Z","caller":"traceutil/trace.go:171","msg":"trace[1622943841] linearizableReadLoop","detail":"{readStateIndex:1357; appliedIndex:1355; }","duration":"154.289424ms","start":"2026-04-28T03:24:23.165341Z","end":"2026-04-28T03:24:23.31963Z","steps":["trace[1622943841] 'read index received' (duration: 78.466473ms)","trace[1622943841] 'applied index is now lower than readState.Index' (duration: 75.820941ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:24:23.319709Z","caller":"traceutil/trace.go:171","msg":"trace[1448674738] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"157.636253ms","start":"2026-04-28T03:24:23.162043Z","end":"2026-04-28T03:24:23.319679Z","steps":["trace[1448674738] 'process raft request' (duration: 109.24181ms)","trace[1448674738] 'compare' (duration: 48.073465ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:24:23.319799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.430957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:24:23.319857Z","caller":"traceutil/trace.go:171","msg":"trace[885968813] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1301; }","duration":"154.5191ms","start":"2026-04-28T03:24:23.165322Z","end":"2026-04-28T03:24:23.319841Z","steps":["trace[885968813] 'agreement among raft nodes before linearized reading' (duration: 154.404257ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:24:24.218296Z","caller":"traceutil/trace.go:171","msg":"trace[496417354] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"228.912406ms","start":"2026-04-28T03:24:23.989357Z","end":"2026-04-28T03:24:24.218269Z","steps":["trace[496417354] 'process raft request' (duration: 228.773962ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:24:34.45305Z","caller":"traceutil/trace.go:171","msg":"trace[230616944] transaction","detail":"{read_only:false; response_revision:1377; number_of_response:1; }","duration":"165.673413ms","start":"2026-04-28T03:24:34.287348Z","end":"2026-04-28T03:24:34.453021Z","steps":["trace[230616944] 'process raft request' (duration: 165.486228ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:24:35.004099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.224317ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T03:24:35.004218Z","caller":"traceutil/trace.go:171","msg":"trace[1014687421] linearizableReadLoop","detail":"{readStateIndex:1436; appliedIndex:1435; }","duration":"532.950806ms","start":"2026-04-28T03:24:34.471249Z","end":"2026-04-28T03:24:35.0042Z","steps":["trace[1014687421] 'read index received' (duration: 114.432553ms)","trace[1014687421] 'applied index is now lower than readState.Index' (duration: 418.517033ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:24:35.004447Z","caller":"traceutil/trace.go:171","msg":"trace[884576731] transaction","detail":"{read_only:false; response_revision:1378; number_of_response:1; }","duration":"602.498909ms","start":"2026-04-28T03:24:34.401934Z","end":"2026-04-28T03:24:35.004433Z","steps":["trace[884576731] 'process raft request' (duration: 183.811971ms)","trace[884576731] 'compare' (duration: 417.285435ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:24:35.004533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:24:34.401761Z","time spent":"602.725814ms","remote":"127.0.0.1:42850","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-04-28T03:24:35.004709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.023112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-28T03:24:35.004831Z","caller":"traceutil/trace.go:171","msg":"trace[700250618] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1378; }","duration":"454.199046ms","start":"2026-04-28T03:24:34.550608Z","end":"2026-04-28T03:24:35.004807Z","steps":["trace[700250618] 'agreement among raft nodes before linearized reading' (duration: 453.842748ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:24:35.004906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:24:34.550585Z","time spent":"454.309468ms","remote":"127.0.0.1:42850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-28T03:24:35.005144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.864084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:24:35.005328Z","caller":"traceutil/trace.go:171","msg":"trace[1326439289] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1378; }","duration":"381.11718ms","start":"2026-04-28T03:24:34.624184Z","end":"2026-04-28T03:24:35.005302Z","steps":["trace[1326439289] 'agreement among raft nodes before linearized reading' (duration: 380.852914ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:24:35.0054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:24:34.624159Z","time spent":"381.225373ms","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-04-28T03:24:35.005176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.93369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-04-28T03:24:35.005675Z","caller":"traceutil/trace.go:171","msg":"trace[1934497863] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1378; }","duration":"534.482672ms","start":"2026-04-28T03:24:34.471173Z","end":"2026-04-28T03:24:35.005655Z","steps":["trace[1934497863] 'agreement among raft nodes before linearized reading' (duration: 533.885708ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:24:35.00576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:24:34.471143Z","time spent":"534.600636ms","remote":"127.0.0.1:42850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-04-28T03:26:30.088502Z","caller":"traceutil/trace.go:171","msg":"trace[2062161488] transaction","detail":"{read_only:false; response_revision:1908; number_of_response:1; }","duration":"123.116002ms","start":"2026-04-28T03:26:29.965365Z","end":"2026-04-28T03:26:30.088481Z","steps":["trace[2062161488] 'process raft request' (duration: 122.997809ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:26:30.227735Z","caller":"traceutil/trace.go:171","msg":"trace[2077789077] linearizableReadLoop","detail":"{readStateIndex:1990; appliedIndex:1989; }","duration":"252.894432ms","start":"2026-04-28T03:26:29.974816Z","end":"2026-04-28T03:26:30.22771Z","steps":["trace[2077789077] 'read index received' (duration: 113.668179ms)","trace[2077789077] 'applied index is now lower than readState.Index' (duration: 139.225013ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:26:30.227947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.109137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-28T03:26:30.227982Z","caller":"traceutil/trace.go:171","msg":"trace[324925009] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1908; }","duration":"253.182349ms","start":"2026-04-28T03:26:29.974791Z","end":"2026-04-28T03:26:30.227973Z","steps":["trace[324925009] 'agreement among raft nodes before linearized reading' (duration: 253.040445ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:26:30.228076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.385354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-28T03:26:30.228156Z","caller":"traceutil/trace.go:171","msg":"trace[517034169] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1908; }","duration":"212.503676ms","start":"2026-04-28T03:26:30.015634Z","end":"2026-04-28T03:26:30.228138Z","steps":["trace[517034169] 'agreement among raft nodes before linearized reading' (duration: 212.291431ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:26:57.6797Z","caller":"traceutil/trace.go:171","msg":"trace[1400466985] transaction","detail":"{read_only:false; response_revision:2036; number_of_response:1; }","duration":"143.181157ms","start":"2026-04-28T03:26:57.536498Z","end":"2026-04-28T03:26:57.67968Z","steps":["trace[1400466985] 'process raft request' (duration: 143.046204ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:26:57.836805Z","caller":"traceutil/trace.go:171","msg":"trace[258462359] transaction","detail":"{read_only:false; response_revision:2037; number_of_response:1; }","duration":"127.752372ms","start":"2026-04-28T03:26:57.709022Z","end":"2026-04-28T03:26:57.836775Z","steps":["trace[258462359] 'process raft request' (duration: 124.412833ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:01.981801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.078715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-28T03:27:01.981927Z","caller":"traceutil/trace.go:171","msg":"trace[656800843] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2054; }","duration":"132.251958ms","start":"2026-04-28T03:27:01.849657Z","end":"2026-04-28T03:27:01.981909Z","steps":["trace[656800843] 'range keys from in-memory index tree' (duration: 131.906711ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:27:08.442989Z","caller":"traceutil/trace.go:171","msg":"trace[86951322] transaction","detail":"{read_only:false; response_revision:2085; number_of_response:1; }","duration":"181.406511ms","start":"2026-04-28T03:27:08.261565Z","end":"2026-04-28T03:27:08.442972Z","steps":["trace[86951322] 'process raft request' (duration: 181.312159ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:27:52.205476Z","caller":"traceutil/trace.go:171","msg":"trace[312058409] linearizableReadLoop","detail":"{readStateIndex:2414; appliedIndex:2413; }","duration":"201.279192ms","start":"2026-04-28T03:27:52.004177Z","end":"2026-04-28T03:27:52.205456Z","steps":["trace[312058409] 'read index received' (duration: 200.988805ms)","trace[312058409] 'applied index is now lower than readState.Index' (duration: 288.837µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:27:52.205753Z","caller":"traceutil/trace.go:171","msg":"trace[1160453559] transaction","detail":"{read_only:false; response_revision:2315; number_of_response:1; }","duration":"264.493557ms","start":"2026-04-28T03:27:51.941222Z","end":"2026-04-28T03:27:52.205716Z","steps":["trace[1160453559] 'process raft request' (duration: 264.025346ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:52.206139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.702892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:27:52.206231Z","caller":"traceutil/trace.go:171","msg":"trace[741356043] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:2315; }","duration":"202.06865ms","start":"2026-04-28T03:27:52.004144Z","end":"2026-04-28T03:27:52.206213Z","steps":["trace[741356043] 'agreement among raft nodes before linearized reading' (duration: 201.564538ms)"],"step_count":1}