{"level":"warn","ts":"2026-06-18T03:00:09.685035Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-18T03:00:09.685133Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.65: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.65:2380","--initial-cluster=instance=https://199.204.45.65:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.65:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.65: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-06-18T03:00:09.685226Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-18T03:00:09.685247Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.65:2380"]} {"level":"info","ts":"2026-06-18T03:00:09.685268Z","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-06-18T03:00:09.685896Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.65:2379"]} {"level":"info","ts":"2026-06-18T03:00:09.685987Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.65:2380"],"listen-peer-urls":["https://199.204.45.65:2380"],"advertise-client-urls":["https://199.204.45.65:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.65:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.65: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-06-18T03:00:10.285938Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"599.723425ms"} {"level":"info","ts":"2026-06-18T03:00:10.731032Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"cf8c3a138c9a77a2","cluster-id":"c278183e1f634dff"} {"level":"info","ts":"2026-06-18T03:00:10.731168Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 switched to configuration voters=()"} {"level":"info","ts":"2026-06-18T03:00:10.731198Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became follower at term 0"} {"level":"info","ts":"2026-06-18T03:00:10.731223Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft cf8c3a138c9a77a2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-18T03:00:10.731403Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became follower at term 1"} {"level":"info","ts":"2026-06-18T03:00:10.731629Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 switched to configuration voters=(14955392318228297634)"} {"level":"warn","ts":"2026-06-18T03:00:10.83728Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-18T03:00:10.874352Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-18T03:00:10.920632Z","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-06-18T03:00:11.05361Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"cf8c3a138c9a77a2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-18T03:00:11.053823Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"cf8c3a138c9a77a2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-18T03:00:11.054018Z","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-06-18T03:00:11.05407Z","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-06-18T03:00:11.054079Z","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-06-18T03:00:11.057724Z","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-06-18T03:00:11.057803Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.65:2380"} {"level":"info","ts":"2026-06-18T03:00:11.057957Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.65:2380"} {"level":"info","ts":"2026-06-18T03:00:11.057978Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"cf8c3a138c9a77a2","initial-advertise-peer-urls":["https://199.204.45.65:2380"],"listen-peer-urls":["https://199.204.45.65:2380"],"advertise-client-urls":["https://199.204.45.65:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.65:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-18T03:00:11.058045Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-18T03:00:11.10789Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 switched to configuration voters=(14955392318228297634)"} {"level":"info","ts":"2026-06-18T03:00:11.108161Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c278183e1f634dff","local-member-id":"cf8c3a138c9a77a2","added-peer-id":"cf8c3a138c9a77a2","added-peer-peer-urls":["https://199.204.45.65:2380"]} {"level":"info","ts":"2026-06-18T03:00:11.531966Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 is starting a new election at term 1"} {"level":"info","ts":"2026-06-18T03:00:11.532035Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-18T03:00:11.532121Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 received MsgPreVoteResp from cf8c3a138c9a77a2 at term 1"} {"level":"info","ts":"2026-06-18T03:00:11.532151Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became candidate at term 2"} {"level":"info","ts":"2026-06-18T03:00:11.532163Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 received MsgVoteResp from cf8c3a138c9a77a2 at term 2"} {"level":"info","ts":"2026-06-18T03:00:11.532221Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became leader at term 2"} {"level":"info","ts":"2026-06-18T03:00:11.532264Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: cf8c3a138c9a77a2 elected leader cf8c3a138c9a77a2 at term 2"} {"level":"info","ts":"2026-06-18T03:00:11.571598Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-18T03:00:11.577927Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-18T03:00:11.577935Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"cf8c3a138c9a77a2","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.65:2379]}","request-path":"/0/members/cf8c3a138c9a77a2/attributes","cluster-id":"c278183e1f634dff","publish-timeout":"7s"} {"level":"info","ts":"2026-06-18T03:00:11.578086Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-18T03:00:11.580168Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-18T03:00:11.580304Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-18T03:00:11.580349Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-18T03:00:11.582392Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c278183e1f634dff","local-member-id":"cf8c3a138c9a77a2","cluster-version":"3.5"} {"level":"info","ts":"2026-06-18T03:00:11.582466Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-18T03:00:11.582497Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-18T03:00:11.582682Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.65:2379"} {"level":"warn","ts":"2026-06-18T03:00:13.5413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.559903ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-06-18T03:00:13.541607Z","caller":"traceutil/trace.go:171","msg":"trace[565873496] linearizableReadLoop","detail":"{readStateIndex:50; appliedIndex:49; }","duration":"233.310419ms","start":"2026-06-18T03:00:13.308221Z","end":"2026-06-18T03:00:13.541532Z","steps":["trace[565873496] 'read index received' (duration: 15.200003ms)","trace[565873496] 'applied index is now lower than readState.Index' (duration: 218.098135ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T03:00:13.541468Z","caller":"traceutil/trace.go:171","msg":"trace[142255738] transaction","detail":"{read_only:false; response_revision:47; number_of_response:1; }","duration":"212.55837ms","start":"2026-06-18T03:00:13.328896Z","end":"2026-06-18T03:00:13.541455Z","steps":["trace[142255738] 'process raft request' (duration: 212.510539ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:00:13.541679Z","caller":"traceutil/trace.go:171","msg":"trace[1723473801] transaction","detail":"{read_only:false; response_revision:46; number_of_response:1; }","duration":"242.159419ms","start":"2026-06-18T03:00:13.299476Z","end":"2026-06-18T03:00:13.541636Z","steps":["trace[1723473801] 'process raft request' (duration: 82.895039ms)","trace[1723473801] 'compare' (duration: 158.427281ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T03:00:13.54183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.544464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-18T03:00:13.541871Z","caller":"traceutil/trace.go:171","msg":"trace[770279621] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:47; }","duration":"233.657387ms","start":"2026-06-18T03:00:13.3082Z","end":"2026-06-18T03:00:13.541857Z","steps":["trace[770279621] 'agreement among raft nodes before linearized reading' (duration: 233.481223ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:00:13.943967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.031769ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-06-18T03:00:13.94413Z","caller":"traceutil/trace.go:171","msg":"trace[1085673434] linearizableReadLoop","detail":"{readStateIndex:53; appliedIndex:51; }","duration":"386.251416ms","start":"2026-06-18T03:00:13.557862Z","end":"2026-06-18T03:00:13.944113Z","steps":["trace[1085673434] 'read index received' (duration: 67.881471ms)","trace[1085673434] 'applied index is now lower than readState.Index' (duration: 318.368365ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T03:00:13.944244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.376509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-18T03:00:13.944295Z","caller":"traceutil/trace.go:171","msg":"trace[2138267883] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:49; }","duration":"386.45865ms","start":"2026-06-18T03:00:13.557828Z","end":"2026-06-18T03:00:13.944287Z","steps":["trace[2138267883] 'agreement among raft nodes before linearized reading' (duration: 386.378878ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:00:13.94437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:00:13.557814Z","time spent":"386.541242ms","remote":"127.0.0.1:38136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":28,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-06-18T03:00:13.944406Z","caller":"traceutil/trace.go:171","msg":"trace[1664416691] transaction","detail":"{read_only:false; response_revision:49; number_of_response:1; }","duration":"396.66949ms","start":"2026-06-18T03:00:13.547474Z","end":"2026-06-18T03:00:13.944143Z","steps":["trace[1664416691] 'process raft request' (duration: 251.254362ms)","trace[1664416691] 'compare' (duration: 144.929547ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T03:00:13.944775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:00:13.547456Z","time spent":"397.068129ms","remote":"127.0.0.1:38186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1199,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-18T03:00:13.945012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.578283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-18T03:00:13.945067Z","caller":"traceutil/trace.go:171","msg":"trace[276754133] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:51; }","duration":"315.674935ms","start":"2026-06-18T03:00:13.629384Z","end":"2026-06-18T03:00:13.945059Z","steps":["trace[276754133] 'agreement among raft nodes before linearized reading' (duration: 315.535911ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:00:13.945106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:00:13.629363Z","time spent":"315.736757ms","remote":"127.0.0.1:38034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"info","ts":"2026-06-18T03:00:13.945145Z","caller":"traceutil/trace.go:171","msg":"trace[1022282709] transaction","detail":"{read_only:false; response_revision:50; number_of_response:1; }","duration":"315.213895ms","start":"2026-06-18T03:00:13.629905Z","end":"2026-06-18T03:00:13.945119Z","steps":["trace[1022282709] 'process raft request' (duration: 314.888527ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:00:13.945277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:00:13.629834Z","time spent":"315.380378ms","remote":"127.0.0.1:38186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":671,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-18T03:00:13.945386Z","caller":"traceutil/trace.go:171","msg":"trace[980735240] transaction","detail":"{read_only:false; response_revision:51; number_of_response:1; }","duration":"194.410702ms","start":"2026-06-18T03:00:13.750924Z","end":"2026-06-18T03:00:13.945335Z","steps":["trace[980735240] 'process raft request' (duration: 193.91741ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:00:13.945514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.190683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-06-18T03:00:13.94562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.373737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-18T03:00:13.945667Z","caller":"traceutil/trace.go:171","msg":"trace[1529876653] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:51; }","duration":"149.463529ms","start":"2026-06-18T03:00:13.796195Z","end":"2026-06-18T03:00:13.945658Z","steps":["trace[1529876653] 'agreement among raft nodes before linearized reading' (duration: 149.351287ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:00:13.945653Z","caller":"traceutil/trace.go:171","msg":"trace[1476670726] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:51; }","duration":"149.355347ms","start":"2026-06-18T03:00:13.796208Z","end":"2026-06-18T03:00:13.945563Z","steps":["trace[1476670726] 'agreement among raft nodes before linearized reading' (duration: 149.200734ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:00:14.283946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.554059ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-06-18T03:00:14.284158Z","caller":"traceutil/trace.go:171","msg":"trace[1594426587] linearizableReadLoop","detail":"{readStateIndex:64; appliedIndex:61; }","duration":"270.903949ms","start":"2026-06-18T03:00:14.013238Z","end":"2026-06-18T03:00:14.284142Z","steps":["trace[1594426587] 'read index received' (duration: 18.304328ms)","trace[1594426587] 'applied index is now lower than readState.Index' (duration: 252.598721ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T03:00:14.284282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.039193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-18T03:00:14.284355Z","caller":"traceutil/trace.go:171","msg":"trace[106321942] range","detail":"{range_begin:/registry/clusterroles/admin; range_end:; response_count:0; response_revision:60; }","duration":"271.135425ms","start":"2026-06-18T03:00:14.013208Z","end":"2026-06-18T03:00:14.284344Z","steps":["trace[106321942] 'agreement among raft nodes before linearized reading' (duration: 270.988412ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:00:14.284424Z","caller":"traceutil/trace.go:171","msg":"trace[1925012140] transaction","detail":"{read_only:false; response_revision:59; number_of_response:1; }","duration":"274.49973ms","start":"2026-06-18T03:00:14.009891Z","end":"2026-06-18T03:00:14.28439Z","steps":["trace[1925012140] 'process raft request' (duration: 77.419548ms)","trace[1925012140] 'compare' (duration: 196.396856ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T03:00:14.284666Z","caller":"traceutil/trace.go:171","msg":"trace[867968473] transaction","detail":"{read_only:false; response_revision:60; number_of_response:1; }","duration":"274.764416ms","start":"2026-06-18T03:00:14.00989Z","end":"2026-06-18T03:00:14.284654Z","steps":["trace[867968473] 'process raft request' (duration: 274.191463ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:00:14.770456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.208802ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-06-18T03:00:14.770793Z","caller":"traceutil/trace.go:171","msg":"trace[2020578599] transaction","detail":"{read_only:false; response_revision:61; number_of_response:1; }","duration":"482.730799ms","start":"2026-06-18T03:00:14.287952Z","end":"2026-06-18T03:00:14.770683Z","steps":["trace[2020578599] 'process raft request' (duration: 350.21541ms)","trace[2020578599] 'compare' (duration: 132.044739ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T03:00:14.774415Z","caller":"traceutil/trace.go:171","msg":"trace[1049593096] linearizableReadLoop","detail":"{readStateIndex:66; appliedIndex:64; }","duration":"485.471582ms","start":"2026-06-18T03:00:14.288887Z","end":"2026-06-18T03:00:14.774359Z","steps":["trace[1049593096] 'read index received' (duration: 349.30039ms)","trace[1049593096] 'applied index is now lower than readState.Index' (duration: 136.169192ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T03:00:14.774437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:00:14.287924Z","time spent":"486.371962ms","remote":"127.0.0.1:38194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":622,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-18T03:00:14.774479Z","caller":"traceutil/trace.go:171","msg":"trace[1181243702] transaction","detail":"{read_only:false; response_revision:62; number_of_response:1; }","duration":"485.665846ms","start":"2026-06-18T03:00:14.288771Z","end":"2026-06-18T03:00:14.774437Z","steps":["trace[1181243702] 'process raft request' (duration: 481.85636ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:00:14.774559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.642666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-18T03:00:14.774612Z","caller":"traceutil/trace.go:171","msg":"trace[644660782] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:0; response_revision:62; }","duration":"485.737697ms","start":"2026-06-18T03:00:14.288859Z","end":"2026-06-18T03:00:14.774597Z","steps":["trace[644660782] 'agreement among raft nodes before linearized reading' (duration: 485.635345ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:00:14.774654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:00:14.288852Z","time spent":"485.792208ms","remote":"127.0.0.1:38102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":28,"request content":"key:\"/registry/clusterroles/edit\" "} {"level":"warn","ts":"2026-06-18T03:00:14.77537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:00:14.28875Z","time spent":"485.787569ms","remote":"127.0.0.1:38186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1089,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-18T03:00:15.163199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.496507ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-06-18T03:00:15.163433Z","caller":"traceutil/trace.go:171","msg":"trace[654146514] linearizableReadLoop","detail":"{readStateIndex:70; appliedIndex:67; }","duration":"170.043343ms","start":"2026-06-18T03:00:14.993373Z","end":"2026-06-18T03:00:15.163416Z","steps":["trace[654146514] 'read index received' (duration: 66.082µs)","trace[654146514] 'applied index is now lower than readState.Index' (duration: 169.976421ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T03:00:15.163455Z","caller":"traceutil/trace.go:171","msg":"trace[1673181205] transaction","detail":"{read_only:false; response_revision:64; number_of_response:1; }","duration":"383.526559ms","start":"2026-06-18T03:00:14.779898Z","end":"2026-06-18T03:00:15.163425Z","steps":["trace[1673181205] 'process raft request' (duration: 142.698735ms)","trace[1673181205] 'compare' (duration: 240.305193ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T03:00:15.163535Z","caller":"traceutil/trace.go:171","msg":"trace[1104035434] transaction","detail":"{read_only:false; response_revision:65; number_of_response:1; }","duration":"364.860644ms","start":"2026-06-18T03:00:14.798665Z","end":"2026-06-18T03:00:15.163526Z","steps":["trace[1104035434] 'process raft request' (duration: 364.643719ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:00:15.163593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:00:14.798643Z","time spent":"364.924145ms","remote":"127.0.0.1:38186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":581,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-18T03:00:15.163615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:00:14.779879Z","time spent":"383.657574ms","remote":"127.0.0.1:38186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T03:00:15.163775Z","caller":"traceutil/trace.go:171","msg":"trace[701476308] transaction","detail":"{read_only:false; response_revision:66; number_of_response:1; }","duration":"364.983677ms","start":"2026-06-18T03:00:14.798781Z","end":"2026-06-18T03:00:15.163765Z","steps":["trace[701476308] 'process raft request' (duration: 364.598177ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:00:15.163775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.404271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-06-18T03:00:15.163831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:00:14.798773Z","time spent":"365.032407ms","remote":"127.0.0.1:38102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-18T03:00:15.163845Z","caller":"traceutil/trace.go:171","msg":"trace[686887304] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:66; }","duration":"170.539255ms","start":"2026-06-18T03:00:14.993287Z","end":"2026-06-18T03:00:15.163826Z","steps":["trace[686887304] 'agreement among raft nodes before linearized reading' (duration: 170.32737ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:00:15.933564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.468126ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-06-18T03:00:15.933682Z","caller":"traceutil/trace.go:171","msg":"trace[1961558001] transaction","detail":"{read_only:false; response_revision:67; number_of_response:1; }","duration":"766.15478ms","start":"2026-06-18T03:00:15.167493Z","end":"2026-06-18T03:00:15.933648Z","steps":["trace[1961558001] 'process raft request' (duration: 221.545041ms)","trace[1961558001] 'compare' (duration: 544.341613ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T03:00:15.933778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:00:15.167473Z","time spent":"766.270483ms","remote":"127.0.0.1:38186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":687,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-18T03:00:16.504159Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620627290176187354,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-18T03:00:17.005321Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620627290176187354,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-18T03:00:17.50819Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620627290176187354,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-18T03:00:17.774043Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.333373743s","expected-duration":"1s"} {"level":"warn","ts":"2026-06-18T03:00:18.004045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000565156s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-06-18T03:00:18.004126Z","caller":"traceutil/trace.go:171","msg":"trace[1470538630] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000694229s","start":"2026-06-18T03:00:16.003412Z","end":"2026-06-18T03:00:18.004106Z","steps":["trace[1470538630] 'agreement among raft nodes before linearized reading' (duration: 2.000595387s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:00:18.004173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:00:16.003391Z","time spent":"2.000772272s","remote":"127.0.0.1:37772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/06/18 03:00:18 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-18T03:00:19.49368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00009891s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-06-18T03:00:19.493803Z","caller":"traceutil/trace.go:171","msg":"trace[1834638518] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000280804s","start":"2026-06-18T03:00:17.493504Z","end":"2026-06-18T03:00:19.493784Z","steps":["trace[1834638518] 'agreement among raft nodes before linearized reading' (duration: 2.000127481s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:00:19.493857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:00:17.493484Z","time spent":"2.000361496s","remote":"127.0.0.1:37772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-18T03:00:19.687349Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.893411787s","expected-duration":"1s"} {"level":"warn","ts":"2026-06-18T03:00:20.004901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000508321s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-06-18T03:00:20.004985Z","caller":"traceutil/trace.go:171","msg":"trace[567867419] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000654833s","start":"2026-06-18T03:00:18.004313Z","end":"2026-06-18T03:00:20.004968Z","steps":["trace[567867419] 'agreement among raft nodes before linearized reading' (duration: 2.000532951s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:00:20.005033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:00:18.004298Z","time spent":"2.000725126s","remote":"127.0.0.1:37764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/06/18 03:00:20 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-18T03:00:20.119061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.34466356s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-06-18T03:00:20.119429Z","caller":"traceutil/trace.go:171","msg":"trace[199531841] transaction","detail":"{read_only:false; response_revision:68; number_of_response:1; }","duration":"4.95089842s","start":"2026-06-18T03:00:15.168502Z","end":"2026-06-18T03:00:20.1194Z","steps":["trace[199531841] 'process raft request' (duration: 2.605741259s)","trace[199531841] 'compare' (duration: 2.343828243s)"],"step_count":2} {"level":"warn","ts":"2026-06-18T03:00:20.119534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:00:15.168485Z","time spent":"4.951004562s","remote":"127.0.0.1:38186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1088,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T03:00:20.120213Z","caller":"traceutil/trace.go:171","msg":"trace[1183978279] transaction","detail":"{read_only:false; response_revision:69; number_of_response:1; }","duration":"4.949827975s","start":"2026-06-18T03:00:15.170363Z","end":"2026-06-18T03:00:20.120191Z","steps":["trace[1183978279] 'process raft request' (duration: 4.948837304s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:00:20.120301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:00:15.170346Z","time spent":"4.949925276s","remote":"127.0.0.1:38102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":634,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-18T03:00:20.120951Z","caller":"traceutil/trace.go:171","msg":"trace[452948846] linearizableReadLoop","detail":"{readStateIndex:75; appliedIndex:71; }","duration":"4.117467829s","start":"2026-06-18T03:00:16.003453Z","end":"2026-06-18T03:00:20.120921Z","steps":["trace[452948846] 'read index received' (duration: 1.770768126s)","trace[452948846] 'applied index is now lower than readState.Index' (duration: 2.346698573s)"],"step_count":2} {"level":"warn","ts":"2026-06-18T03:00:20.121089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:00:15.65557Z","time spent":"4.465512518s","remote":"127.0.0.1:37832","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-06-18T03:00:20.121254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.107539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-18T03:00:20.12132Z","caller":"traceutil/trace.go:171","msg":"trace[1364310343] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:70; }","duration":"127.194521ms","start":"2026-06-18T03:00:19.99411Z","end":"2026-06-18T03:00:20.121304Z","steps":["trace[1364310343] 'agreement among raft nodes before linearized reading' (duration: 127.046728ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:00:20.12112Z","caller":"traceutil/trace.go:171","msg":"trace[1006489383] transaction","detail":"{read_only:false; response_revision:70; number_of_response:1; }","duration":"4.260891892s","start":"2026-06-18T03:00:15.860212Z","end":"2026-06-18T03:00:20.121104Z","steps":["trace[1006489383] 'process raft request' (duration: 4.260578005s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:00:20.121546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:00:15.860193Z","time spent":"4.261272709s","remote":"127.0.0.1:38020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-18T03:00:22.256658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.937604466s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-06-18T03:00:22.256837Z","caller":"traceutil/trace.go:171","msg":"trace[1047735928] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"2.131827293s","start":"2026-06-18T03:00:20.124996Z","end":"2026-06-18T03:00:22.256823Z","steps":["trace[1047735928] 'process raft request' (duration: 2.131749321s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:00:22.256894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:00:20.124984Z","time spent":"2.131878994s","remote":"127.0.0.1:38186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1121,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T03:00:22.257026Z","caller":"traceutil/trace.go:171","msg":"trace[1216582914] transaction","detail":"{read_only:false; response_revision:71; number_of_response:1; }","duration":"2.134402738s","start":"2026-06-18T03:00:20.122615Z","end":"2026-06-18T03:00:22.257018Z","steps":["trace[1216582914] 'process raft request' (duration: 196.332842ms)","trace[1216582914] 'compare' (duration: 1.937095645s)"],"step_count":2} {"level":"warn","ts":"2026-06-18T03:00:22.257065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:00:20.122588Z","time spent":"2.134463279s","remote":"127.0.0.1:37832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-18T03:00:22.257151Z","caller":"traceutil/trace.go:171","msg":"trace[540254037] linearizableReadLoop","detail":"{readStateIndex:76; appliedIndex:75; }","duration":"2.133247644s","start":"2026-06-18T03:00:20.123896Z","end":"2026-06-18T03:00:22.257144Z","steps":["trace[540254037] 'read index received' (duration: 195.062155ms)","trace[540254037] 'applied index is now lower than readState.Index' (duration: 1.938184659s)"],"step_count":2} {"level":"warn","ts":"2026-06-18T03:00:22.25735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.133451888s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:monitoring\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-18T03:00:22.257377Z","caller":"traceutil/trace.go:171","msg":"trace[116702856] range","detail":"{range_begin:/registry/clusterroles/system:monitoring; range_end:; response_count:0; response_revision:72; }","duration":"2.133518719s","start":"2026-06-18T03:00:20.123851Z","end":"2026-06-18T03:00:22.25737Z","steps":["trace[116702856] 'agreement among raft nodes before linearized reading' (duration: 2.133475808s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:00:22.257397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:00:20.123832Z","time spent":"2.13356068s","remote":"127.0.0.1:38102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/clusterroles/system:monitoring\" "} {"level":"warn","ts":"2026-06-18T03:00:22.257401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"742.145986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-18T03:00:22.257447Z","caller":"traceutil/trace.go:171","msg":"trace[1224437426] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:72; }","duration":"742.236719ms","start":"2026-06-18T03:00:21.515199Z","end":"2026-06-18T03:00:22.257435Z","steps":["trace[1224437426] 'agreement among raft nodes before linearized reading' (duration: 742.168467ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:00:22.257478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:00:21.515173Z","time spent":"742.299239ms","remote":"127.0.0.1:37884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" "} {"level":"warn","ts":"2026-06-18T03:00:22.257401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.132332664s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-tgtmv\" ","response":"range_response_count:1 size:861"} {"level":"info","ts":"2026-06-18T03:00:22.257738Z","caller":"traceutil/trace.go:171","msg":"trace[1896607167] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-tgtmv; range_end:; response_count:1; response_revision:72; }","duration":"2.13267176s","start":"2026-06-18T03:00:20.125029Z","end":"2026-06-18T03:00:22.2577Z","steps":["trace[1896607167] 'agreement among raft nodes before linearized reading' (duration: 2.132316853s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:00:22.257837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:00:20.125015Z","time spent":"2.132798503s","remote":"127.0.0.1:38020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":885,"request content":"key:\"/registry/certificatesigningrequests/csr-tgtmv\" "} {"level":"warn","ts":"2026-06-18T03:00:22.257457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.25384809s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-18T03:00:22.258148Z","caller":"traceutil/trace.go:171","msg":"trace[1478717732] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:72; }","duration":"1.254580835s","start":"2026-06-18T03:00:21.003556Z","end":"2026-06-18T03:00:22.258137Z","steps":["trace[1478717732] 'agreement among raft nodes before linearized reading' (duration: 1.25382931s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:00:22.25818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:00:21.003542Z","time spent":"1.254628656s","remote":"127.0.0.1:37772","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-06-18T03:00:22.804081Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620627290176187367,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-18T03:00:22.870792Z","caller":"traceutil/trace.go:171","msg":"trace[517984834] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"610.449341ms","start":"2026-06-18T03:00:22.260314Z","end":"2026-06-18T03:00:22.870764Z","steps":["trace[517984834] 'process raft request' (duration: 600.498394ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:00:22.871092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:00:22.260288Z","time spent":"610.576973ms","remote":"127.0.0.1:37944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-18T03:00:22.899438Z","caller":"traceutil/trace.go:171","msg":"trace[1545662146] linearizableReadLoop","detail":"{readStateIndex:81; appliedIndex:77; }","duration":"595.45603ms","start":"2026-06-18T03:00:22.303953Z","end":"2026-06-18T03:00:22.899409Z","steps":["trace[1545662146] 'read index received' (duration: 556.873268ms)","trace[1545662146] 'applied index is now lower than readState.Index' (duration: 38.581682ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T03:00:22.899454Z","caller":"traceutil/trace.go:171","msg":"trace[1757143165] transaction","detail":"{read_only:false; response_revision:75; number_of_response:1; }","duration":"637.680917ms","start":"2026-06-18T03:00:22.261743Z","end":"2026-06-18T03:00:22.899424Z","steps":["trace[1757143165] 'process raft request' (duration: 637.566815ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:00:22.899522Z","caller":"traceutil/trace.go:171","msg":"trace[1232422547] transaction","detail":"{read_only:false; response_revision:74; number_of_response:1; }","duration":"638.852741ms","start":"2026-06-18T03:00:22.260655Z","end":"2026-06-18T03:00:22.899507Z","steps":["trace[1232422547] 'process raft request' (duration: 638.569495ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:00:22.899624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:00:22.260644Z","time spent":"638.922542ms","remote":"127.0.0.1:38102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":621,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-18T03:00:22.899823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.866938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-18T03:00:22.899621Z","caller":"traceutil/trace.go:171","msg":"trace[925660003] transaction","detail":"{read_only:false; response_revision:76; number_of_response:1; }","duration":"637.200727ms","start":"2026-06-18T03:00:22.262385Z","end":"2026-06-18T03:00:22.899585Z","steps":["trace[925660003] 'process raft request' (duration: 636.960042ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:00:22.899989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:00:22.262363Z","time spent":"637.560014ms","remote":"127.0.0.1:38186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1043,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T03:00:22.899876Z","caller":"traceutil/trace.go:171","msg":"trace[682300484] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:76; }","duration":"595.95618ms","start":"2026-06-18T03:00:22.303905Z","end":"2026-06-18T03:00:22.899862Z","steps":["trace[682300484] 'agreement among raft nodes before linearized reading' (duration: 595.703085ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:00:22.900089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:00:22.303894Z","time spent":"596.152534ms","remote":"127.0.0.1:37772","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-06-18T03:00:22.900443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:00:22.261719Z","time spent":"637.81327ms","remote":"127.0.0.1:37832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":625,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-18T03:00:23.593085Z","caller":"traceutil/trace.go:171","msg":"trace[555967771] transaction","detail":"{read_only:false; response_revision:118; number_of_response:1; }","duration":"127.064257ms","start":"2026-06-18T03:00:23.465996Z","end":"2026-06-18T03:00:23.59306Z","steps":["trace[555967771] 'process raft request' (duration: 126.997576ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:00:23.593077Z","caller":"traceutil/trace.go:171","msg":"trace[1372343601] transaction","detail":"{read_only:false; response_revision:116; number_of_response:1; }","duration":"185.915899ms","start":"2026-06-18T03:00:23.407123Z","end":"2026-06-18T03:00:23.593039Z","steps":["trace[1372343601] 'process raft request' (duration: 178.063086ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:00:23.593154Z","caller":"traceutil/trace.go:171","msg":"trace[734889424] transaction","detail":"{read_only:false; response_revision:117; number_of_response:1; }","duration":"185.97395ms","start":"2026-06-18T03:00:23.407141Z","end":"2026-06-18T03:00:23.593115Z","steps":["trace[734889424] 'process raft request' (duration: 185.783356ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:00:26.863815Z","caller":"traceutil/trace.go:171","msg":"trace[742996837] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"107.381684ms","start":"2026-06-18T03:00:26.756414Z","end":"2026-06-18T03:00:26.863795Z","steps":["trace[742996837] 'process raft request' (duration: 107.216881ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:00:26.863806Z","caller":"traceutil/trace.go:171","msg":"trace[910783898] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"119.720512ms","start":"2026-06-18T03:00:26.744056Z","end":"2026-06-18T03:00:26.863776Z","steps":["trace[910783898] 'process raft request' (duration: 69.26485ms)","trace[910783898] 'compare' (duration: 50.165196ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T03:00:28.21311Z","caller":"traceutil/trace.go:171","msg":"trace[1564980684] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"406.156135ms","start":"2026-06-18T03:00:27.806914Z","end":"2026-06-18T03:00:28.213071Z","steps":["trace[1564980684] 'process raft request' (duration: 406.000832ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:00:28.213299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:00:27.806888Z","time spent":"406.331099ms","remote":"127.0.0.1:37964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":209,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-18T03:00:32.807223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.906468ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-18T03:00:32.807371Z","caller":"traceutil/trace.go:171","msg":"trace[745283564] linearizableReadLoop","detail":"{readStateIndex:403; appliedIndex:401; }","duration":"193.294189ms","start":"2026-06-18T03:00:32.614066Z","end":"2026-06-18T03:00:32.807361Z","steps":["trace[745283564] 'read index received' (duration: 86.741115ms)","trace[745283564] 'applied index is now lower than readState.Index' (duration: 106.552464ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T03:00:32.807439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T03:00:32.807456Z","caller":"traceutil/trace.go:171","msg":"trace[1896762071] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:389; }","duration":"193.433371ms","start":"2026-06-18T03:00:32.614016Z","end":"2026-06-18T03:00:32.807449Z","steps":["trace[1896762071] 'agreement among raft nodes before linearized reading' (duration: 193.3755ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:00:32.807567Z","caller":"traceutil/trace.go:171","msg":"trace[60396517] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"501.585531ms","start":"2026-06-18T03:00:32.305975Z","end":"2026-06-18T03:00:32.807561Z","steps":["trace[60396517] 'process raft request' (duration: 98.272806ms)","trace[60396517] 'compare' (duration: 402.585962ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T03:00:32.807598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:00:32.305947Z","time spent":"501.635912ms","remote":"127.0.0.1:38034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T03:00:32.807678Z","caller":"traceutil/trace.go:171","msg":"trace[2144933542] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"500.339528ms","start":"2026-06-18T03:00:32.307302Z","end":"2026-06-18T03:00:32.807642Z","steps":["trace[2144933542] 'process raft request' (duration: 500.017802ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:00:32.80781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:00:32.307286Z","time spent":"500.46964ms","remote":"127.0.0.1:38034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T03:00:37.09195Z","caller":"traceutil/trace.go:171","msg":"trace[195266534] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"257.647798ms","start":"2026-06-18T03:00:36.834269Z","end":"2026-06-18T03:00:37.091916Z","steps":["trace[195266534] 'process raft request' (duration: 228.86304ms)","trace[195266534] 'compare' (duration: 28.585264ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T03:00:37.145013Z","caller":"traceutil/trace.go:171","msg":"trace[1108370810] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"310.474537ms","start":"2026-06-18T03:00:36.834516Z","end":"2026-06-18T03:00:37.14499Z","steps":["trace[1108370810] 'process raft request' (duration: 310.369585ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:00:37.145164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:00:36.834509Z","time spent":"310.573309ms","remote":"127.0.0.1:38034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-18T03:00:59.014015Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620627290176188243,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-18T03:00:59.075207Z","caller":"traceutil/trace.go:171","msg":"trace[51591528] linearizableReadLoop","detail":"{readStateIndex:510; appliedIndex:509; }","duration":"561.792535ms","start":"2026-06-18T03:00:58.513383Z","end":"2026-06-18T03:00:59.075176Z","steps":["trace[51591528] 'read index received' (duration: 561.629842ms)","trace[51591528] 'applied index is now lower than readState.Index' (duration: 161.493µs)"],"step_count":2} {"level":"info","ts":"2026-06-18T03:00:59.075382Z","caller":"traceutil/trace.go:171","msg":"trace[1608573742] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"571.970984ms","start":"2026-06-18T03:00:58.503397Z","end":"2026-06-18T03:00:59.075368Z","steps":["trace[1608573742] 'process raft request' (duration: 571.624788ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:00:59.075558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:00:58.503368Z","time spent":"572.055867ms","remote":"127.0.0.1:38034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-18T03:00:59.075558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.94223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T03:00:59.075629Z","caller":"traceutil/trace.go:171","msg":"trace[326706772] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:489; }","duration":"462.055132ms","start":"2026-06-18T03:00:58.613554Z","end":"2026-06-18T03:00:59.075609Z","steps":["trace[326706772] 'agreement among raft nodes before linearized reading' (duration: 461.949341ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:00:59.075668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:00:58.613533Z","time spent":"462.127313ms","remote":"127.0.0.1:37772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-18T03:00:59.075728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.324423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T03:00:59.075816Z","caller":"traceutil/trace.go:171","msg":"trace[742944710] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:0; response_revision:489; }","duration":"562.449314ms","start":"2026-06-18T03:00:58.513345Z","end":"2026-06-18T03:00:59.075795Z","steps":["trace[742944710] 'agreement among raft nodes before linearized reading' (duration: 562.291852ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:00:59.075866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:00:58.513333Z","time spent":"562.524535ms","remote":"127.0.0.1:37964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":29,"request content":"key:\"/registry/serviceaccounts/kube-system/cilium\" "} {"level":"warn","ts":"2026-06-18T03:00:59.075878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.773831ms","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-06-18T03:00:59.075908Z","caller":"traceutil/trace.go:171","msg":"trace[1721276454] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:489; }","duration":"147.838292ms","start":"2026-06-18T03:00:58.92806Z","end":"2026-06-18T03:00:59.075899Z","steps":["trace[1721276454] 'agreement among raft nodes before linearized reading' (duration: 147.785271ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:00:59.571614Z","caller":"traceutil/trace.go:171","msg":"trace[1947931604] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"101.388586ms","start":"2026-06-18T03:00:59.470203Z","end":"2026-06-18T03:00:59.571591Z","steps":["trace[1947931604] 'process raft request' (duration: 101.272315ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:00:59.658129Z","caller":"traceutil/trace.go:171","msg":"trace[396212610] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"133.810215ms","start":"2026-06-18T03:00:59.52428Z","end":"2026-06-18T03:00:59.65809Z","steps":["trace[396212610] 'process raft request' (duration: 110.758923ms)","trace[396212610] 'compare' (duration: 22.94589ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T03:01:01.358392Z","caller":"traceutil/trace.go:171","msg":"trace[1002344772] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"105.034573ms","start":"2026-06-18T03:01:01.253331Z","end":"2026-06-18T03:01:01.358366Z","steps":["trace[1002344772] 'process raft request' (duration: 104.469494ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:01:01.358753Z","caller":"traceutil/trace.go:171","msg":"trace[599907911] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"100.516234ms","start":"2026-06-18T03:01:01.258212Z","end":"2026-06-18T03:01:01.358728Z","steps":["trace[599907911] 'process raft request' (duration: 99.70156ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:01:01.754701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.817464ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-18T03:01:01.755086Z","caller":"traceutil/trace.go:171","msg":"trace[800268288] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"212.892118ms","start":"2026-06-18T03:01:01.542183Z","end":"2026-06-18T03:01:01.755075Z","steps":["trace[800268288] 'process raft request' (duration: 212.710426ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:01:01.755234Z","caller":"traceutil/trace.go:171","msg":"trace[1923740149] linearizableReadLoop","detail":"{readStateIndex:596; appliedIndex:593; }","duration":"142.4247ms","start":"2026-06-18T03:01:01.612783Z","end":"2026-06-18T03:01:01.755208Z","steps":["trace[1923740149] 'read index received' (duration: 15.063533ms)","trace[1923740149] 'applied index is now lower than readState.Index' (duration: 127.359497ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T03:01:01.755267Z","caller":"traceutil/trace.go:171","msg":"trace[179955106] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"181.339521ms","start":"2026-06-18T03:01:01.573888Z","end":"2026-06-18T03:01:01.755228Z","steps":["trace[179955106] 'process raft request' (duration: 181.183218ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:01:01.755359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.569793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T03:01:01.755401Z","caller":"traceutil/trace.go:171","msg":"trace[1184563436] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:568; }","duration":"142.642143ms","start":"2026-06-18T03:01:01.612746Z","end":"2026-06-18T03:01:01.755388Z","steps":["trace[1184563436] 'agreement among raft nodes before linearized reading' (duration: 142.539362ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:01:01.755027Z","caller":"traceutil/trace.go:171","msg":"trace[126590018] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"229.14854ms","start":"2026-06-18T03:01:01.525833Z","end":"2026-06-18T03:01:01.754981Z","steps":["trace[126590018] 'process raft request' (duration: 102.882259ms)","trace[126590018] 'compare' (duration: 125.580441ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T03:01:02.09565Z","caller":"traceutil/trace.go:171","msg":"trace[2146117820] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"326.13182ms","start":"2026-06-18T03:01:01.769486Z","end":"2026-06-18T03:01:02.095618Z","steps":["trace[2146117820] 'process raft request' (duration: 288.103676ms)","trace[2146117820] 'compare' (duration: 37.838361ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T03:01:02.095815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:01:01.769474Z","time spent":"326.257942ms","remote":"127.0.0.1:37812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29452,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T03:01:02.552507Z","caller":"traceutil/trace.go:171","msg":"trace[1046700281] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"737.501811ms","start":"2026-06-18T03:01:01.814977Z","end":"2026-06-18T03:01:02.552479Z","steps":["trace[1046700281] 'process raft request' (duration: 732.739028ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:01:02.5527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:01:01.814927Z","time spent":"737.705054ms","remote":"127.0.0.1:37812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64392,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-18T03:01:02.561531Z","caller":"traceutil/trace.go:171","msg":"trace[602211915] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"455.31032ms","start":"2026-06-18T03:01:02.106198Z","end":"2026-06-18T03:01:02.561509Z","steps":["trace[602211915] 'process raft request' (duration: 454.478678ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:01:02.561656Z","caller":"traceutil/trace.go:171","msg":"trace[871172665] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"452.772861ms","start":"2026-06-18T03:01:02.108866Z","end":"2026-06-18T03:01:02.561639Z","steps":["trace[871172665] 'process raft request' (duration: 452.504037ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:01:02.561794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:01:02.108851Z","time spent":"452.852502ms","remote":"127.0.0.1:37812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29461,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-18T03:01:02.561793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:01:02.106178Z","time spent":"455.437642ms","remote":"127.0.0.1:37812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31121,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T03:01:02.970721Z","caller":"traceutil/trace.go:171","msg":"trace[2022517189] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"237.930513ms","start":"2026-06-18T03:01:02.732772Z","end":"2026-06-18T03:01:02.970703Z","steps":["trace[2022517189] 'process raft request' (duration: 225.865968ms)","trace[2022517189] 'compare' (duration: 11.965114ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T03:01:02.995483Z","caller":"traceutil/trace.go:171","msg":"trace[1234601053] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"257.116997ms","start":"2026-06-18T03:01:02.738349Z","end":"2026-06-18T03:01:02.995466Z","steps":["trace[1234601053] 'process raft request' (duration: 256.969575ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:01:03.649911Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620627290176188371,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-18T03:01:04.150537Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620627290176188371,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-18T03:01:04.650852Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620627290176188371,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-18T03:01:04.862147Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.722877031s","expected-duration":"1s"} {"level":"info","ts":"2026-06-18T03:01:04.877499Z","caller":"traceutil/trace.go:171","msg":"trace[221250701] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"1.738304966s","start":"2026-06-18T03:01:03.139168Z","end":"2026-06-18T03:01:04.877473Z","steps":["trace[221250701] 'process raft request' (duration: 1.723306509s)","trace[221250701] 'compare' (duration: 14.575361ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T03:01:04.877604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:01:03.139149Z","time spent":"1.738399648s","remote":"127.0.0.1:37812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18032,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T03:01:04.892014Z","caller":"traceutil/trace.go:171","msg":"trace[1307854004] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"1.693675834s","start":"2026-06-18T03:01:03.198296Z","end":"2026-06-18T03:01:04.891972Z","steps":["trace[1307854004] 'process raft request' (duration: 1.693307599s)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:01:04.892031Z","caller":"traceutil/trace.go:171","msg":"trace[311218020] linearizableReadLoop","detail":"{readStateIndex:618; appliedIndex:615; }","duration":"1.742972016s","start":"2026-06-18T03:01:03.149028Z","end":"2026-06-18T03:01:04.892Z","steps":["trace[311218020] 'read index received' (duration: 1.713422007s)","trace[311218020] 'applied index is now lower than readState.Index' (duration: 29.549059ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T03:01:04.892155Z","caller":"traceutil/trace.go:171","msg":"trace[1064933259] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"1.616123951s","start":"2026-06-18T03:01:03.276017Z","end":"2026-06-18T03:01:04.892141Z","steps":["trace[1064933259] 'process raft request' (duration: 1.615854707s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:01:04.89217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:01:03.198276Z","time spent":"1.693818486s","remote":"127.0.0.1:37812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":151221,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-18T03:01:04.892274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:01:03.27599Z","time spent":"1.616204333s","remote":"127.0.0.1:37812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":207462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-18T03:01:04.892392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.743357511s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-06-18T03:01:04.892434Z","caller":"traceutil/trace.go:171","msg":"trace[1628207513] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:591; }","duration":"1.743425582s","start":"2026-06-18T03:01:03.148997Z","end":"2026-06-18T03:01:04.892423Z","steps":["trace[1628207513] 'agreement among raft nodes before linearized reading' (duration: 1.74330079s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:01:04.892462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:01:03.148977Z","time spent":"1.743480093s","remote":"127.0.0.1:38034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-18T03:01:04.892472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.278943806s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-18T03:01:04.892482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.696932314s","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-06-18T03:01:04.892506Z","caller":"traceutil/trace.go:171","msg":"trace[383058170] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:591; }","duration":"1.279017727s","start":"2026-06-18T03:01:03.613478Z","end":"2026-06-18T03:01:04.892495Z","steps":["trace[383058170] 'agreement among raft nodes before linearized reading' (duration: 1.278964296s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:01:04.892527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:01:03.61345Z","time spent":"1.279072368s","remote":"127.0.0.1:37772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-18T03:01:04.892525Z","caller":"traceutil/trace.go:171","msg":"trace[1763613296] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:591; }","duration":"1.697024415s","start":"2026-06-18T03:01:03.195489Z","end":"2026-06-18T03:01:04.892513Z","steps":["trace[1763613296] 'agreement among raft nodes before linearized reading' (duration: 1.696951064s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:01:04.892644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.204942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.65\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-06-18T03:01:04.892692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:01:03.195475Z","time spent":"1.697206188s","remote":"127.0.0.1:38034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-18T03:01:04.892712Z","caller":"traceutil/trace.go:171","msg":"trace[278354960] range","detail":"{range_begin:/registry/masterleases/199.204.45.65; range_end:; response_count:1; response_revision:591; }","duration":"186.343735ms","start":"2026-06-18T03:01:04.706349Z","end":"2026-06-18T03:01:04.892693Z","steps":["trace[278354960] 'agreement among raft nodes before linearized reading' (duration: 186.193903ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:01:04.892745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.48631009s","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-06-18T03:01:04.89278Z","caller":"traceutil/trace.go:171","msg":"trace[1772241045] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:591; }","duration":"1.48637296s","start":"2026-06-18T03:01:03.406398Z","end":"2026-06-18T03:01:04.892771Z","steps":["trace[1772241045] 'agreement among raft nodes before linearized reading' (duration: 1.486291279s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:01:04.892809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:01:03.406376Z","time spent":"1.486426252s","remote":"127.0.0.1:38034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-18T03:01:04.89267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.480550541s","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-06-18T03:01:04.892902Z","caller":"traceutil/trace.go:171","msg":"trace[250273876] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:591; }","duration":"1.480799905s","start":"2026-06-18T03:01:03.41209Z","end":"2026-06-18T03:01:04.89289Z","steps":["trace[250273876] 'agreement among raft nodes before linearized reading' (duration: 1.48045445s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:01:04.892943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:01:03.412081Z","time spent":"1.480850636s","remote":"127.0.0.1:38034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-18T03:01:05.263224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.303254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/udproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:31126"} {"level":"info","ts":"2026-06-18T03:01:05.263309Z","caller":"traceutil/trace.go:171","msg":"trace[692493635] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/udproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:605; }","duration":"142.425036ms","start":"2026-06-18T03:01:05.120865Z","end":"2026-06-18T03:01:05.26329Z","steps":["trace[692493635] 'range keys from in-memory index tree' (duration: 142.126402ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:01:05.811831Z","caller":"traceutil/trace.go:171","msg":"trace[758246303] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"115.970456ms","start":"2026-06-18T03:01:05.695837Z","end":"2026-06-18T03:01:05.811808Z","steps":["trace[758246303] 'process raft request' (duration: 115.643602ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:01:07.755975Z","caller":"traceutil/trace.go:171","msg":"trace[1611919199] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:644; }","duration":"113.263856ms","start":"2026-06-18T03:01:07.642691Z","end":"2026-06-18T03:01:07.755955Z","steps":["trace[1611919199] 'read index received' (duration: 113.100233ms)","trace[1611919199] 'applied index is now lower than readState.Index' (duration: 162.883µs)"],"step_count":2} {"level":"warn","ts":"2026-06-18T03:01:07.756179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.456798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:7708"} {"level":"info","ts":"2026-06-18T03:01:07.756216Z","caller":"traceutil/trace.go:171","msg":"trace[1997387979] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:616; }","duration":"113.553669ms","start":"2026-06-18T03:01:07.642651Z","end":"2026-06-18T03:01:07.756204Z","steps":["trace[1997387979] 'agreement among raft nodes before linearized reading' (duration: 113.390897ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:01:07.756233Z","caller":"traceutil/trace.go:171","msg":"trace[256434786] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"141.780659ms","start":"2026-06-18T03:01:07.614426Z","end":"2026-06-18T03:01:07.756207Z","steps":["trace[256434786] 'process raft request' (duration: 141.414924ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:01:07.939228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.769944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/udproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:31126"} {"level":"info","ts":"2026-06-18T03:01:07.939431Z","caller":"traceutil/trace.go:171","msg":"trace[84294956] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/udproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:616; }","duration":"157.015767ms","start":"2026-06-18T03:01:07.782383Z","end":"2026-06-18T03:01:07.939398Z","steps":["trace[84294956] 'range keys from in-memory index tree' (duration: 156.53331ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:01:09.444677Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620627290176188477,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-18T03:01:09.945455Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620627290176188477,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-18T03:01:10.371149Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.452609709s","expected-duration":"1s"} {"level":"info","ts":"2026-06-18T03:01:10.371773Z","caller":"traceutil/trace.go:171","msg":"trace[236167423] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"1.453292438s","start":"2026-06-18T03:01:08.918455Z","end":"2026-06-18T03:01:10.371747Z","steps":["trace[236167423] 'process raft request' (duration: 1.453036305s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:01:10.372235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:01:08.918442Z","time spent":"1.45341003s","remote":"127.0.0.1:38034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T03:01:10.585605Z","caller":"traceutil/trace.go:171","msg":"trace[1088517909] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"1.664422214s","start":"2026-06-18T03:01:08.921163Z","end":"2026-06-18T03:01:10.585585Z","steps":["trace[1088517909] 'process raft request' (duration: 1.631232759s)","trace[1088517909] 'compare' (duration: 33.027662ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T03:01:10.585709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:01:08.921151Z","time spent":"1.664505126s","remote":"127.0.0.1:38034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T03:01:10.585783Z","caller":"traceutil/trace.go:171","msg":"trace[602752857] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"1.664446944s","start":"2026-06-18T03:01:08.921256Z","end":"2026-06-18T03:01:10.585703Z","steps":["trace[602752857] 'process raft request' (duration: 1.664281121s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:01:10.58596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:01:08.921247Z","time spent":"1.664639717s","remote":"127.0.0.1:38034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T03:01:10.590176Z","caller":"traceutil/trace.go:171","msg":"trace[1306133025] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:645; }","duration":"1.646353495s","start":"2026-06-18T03:01:08.943789Z","end":"2026-06-18T03:01:10.590143Z","steps":["trace[1306133025] 'read index received' (duration: 1.427652079s)","trace[1306133025] 'applied index is now lower than readState.Index' (duration: 218.700106ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T03:01:10.590393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.646598109s","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-06-18T03:01:10.590432Z","caller":"traceutil/trace.go:171","msg":"trace[1326743931] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:619; }","duration":"1.64667321s","start":"2026-06-18T03:01:08.943748Z","end":"2026-06-18T03:01:10.590422Z","steps":["trace[1326743931] 'agreement among raft nodes before linearized reading' (duration: 1.646541219s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:01:10.590458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:01:08.943731Z","time spent":"1.646719201s","remote":"127.0.0.1:38034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-18T03:01:14.463408Z","caller":"traceutil/trace.go:171","msg":"trace[1203282853] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"114.287853ms","start":"2026-06-18T03:01:14.34909Z","end":"2026-06-18T03:01:14.463378Z","steps":["trace[1203282853] 'process raft request' (duration: 113.767566ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:01:15.832455Z","caller":"traceutil/trace.go:171","msg":"trace[1618073279] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"906.380295ms","start":"2026-06-18T03:01:14.926047Z","end":"2026-06-18T03:01:15.832427Z","steps":["trace[1618073279] 'process raft request' (duration: 894.367034ms)","trace[1618073279] 'compare' (duration: 11.826638ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T03:01:15.832945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:01:14.926028Z","time spent":"906.505356ms","remote":"127.0.0.1:38034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T03:01:16.023288Z","caller":"traceutil/trace.go:171","msg":"trace[182924900] linearizableReadLoop","detail":"{readStateIndex:678; appliedIndex:676; }","duration":"596.633926ms","start":"2026-06-18T03:01:15.426637Z","end":"2026-06-18T03:01:16.023271Z","steps":["trace[182924900] 'read index received' (duration: 393.870156ms)","trace[182924900] 'applied index is now lower than readState.Index' (duration: 202.76294ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T03:01:16.023349Z","caller":"traceutil/trace.go:171","msg":"trace[677710062] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"1.060648532s","start":"2026-06-18T03:01:14.962678Z","end":"2026-06-18T03:01:16.023327Z","steps":["trace[677710062] 'process raft request' (duration: 1.060193065s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:01:16.023436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.215149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-18T03:01:16.023461Z","caller":"traceutil/trace.go:171","msg":"trace[277160171] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:647; }","duration":"477.291171ms","start":"2026-06-18T03:01:15.546162Z","end":"2026-06-18T03:01:16.023453Z","steps":["trace[277160171] 'agreement among raft nodes before linearized reading' (duration: 477.20549ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:01:16.023434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.776018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-18T03:01:16.023484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:01:15.546142Z","time spent":"477.336661ms","remote":"127.0.0.1:38102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":67,"response size":31,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-06-18T03:01:16.023482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:01:14.962644Z","time spent":"1.060759694s","remote":"127.0.0.1:37844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036257,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T03:01:16.023511Z","caller":"traceutil/trace.go:171","msg":"trace[1705041090] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:647; }","duration":"596.912529ms","start":"2026-06-18T03:01:15.426577Z","end":"2026-06-18T03:01:16.023489Z","steps":["trace[1705041090] 'agreement among raft nodes before linearized reading' (duration: 596.761237ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:01:16.023563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:01:15.426553Z","time spent":"597.001771ms","remote":"127.0.0.1:37982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-06-18T03:01:16.023774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.090691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T03:01:16.023814Z","caller":"traceutil/trace.go:171","msg":"trace[1034003961] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:647; }","duration":"409.170142ms","start":"2026-06-18T03:01:15.614633Z","end":"2026-06-18T03:01:16.023803Z","steps":["trace[1034003961] 'agreement among raft nodes before linearized reading' (duration: 409.108681ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:01:16.023853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:01:15.614609Z","time spent":"409.237173ms","remote":"127.0.0.1:37764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-18T03:01:16.754349Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620627290176188555,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-18T03:01:17.255012Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620627290176188555,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-18T03:01:17.524783Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.437552223s","expected-duration":"1s"} {"level":"warn","ts":"2026-06-18T03:01:18.614364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000118431s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-06-18T03:01:18.614455Z","caller":"traceutil/trace.go:171","msg":"trace[2089575174] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000268864s","start":"2026-06-18T03:01:16.614161Z","end":"2026-06-18T03:01:18.61443Z","steps":["trace[2089575174] 'agreement among raft nodes before linearized reading' (duration: 2.000155232s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:01:18.614689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:01:16.614137Z","time spent":"2.000530967s","remote":"127.0.0.1:37772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-18T03:01:20.030426Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.928769205s","expected-duration":"1s"} {"level":"warn","ts":"2026-06-18T03:01:20.09581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.217457374s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T03:01:20.095933Z","caller":"traceutil/trace.go:171","msg":"trace[1196178398] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:647; }","duration":"3.217594236s","start":"2026-06-18T03:01:16.878325Z","end":"2026-06-18T03:01:20.095919Z","steps":["trace[1196178398] 'range keys from in-memory index tree' (duration: 3.217442264s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:01:20.096458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.571310394s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-18T03:01:20.096623Z","caller":"traceutil/trace.go:171","msg":"trace[1828561964] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"1.995059184s","start":"2026-06-18T03:01:18.101526Z","end":"2026-06-18T03:01:20.096586Z","steps":["trace[1828561964] 'process raft request' (duration: 1.994983973s)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:01:20.096558Z","caller":"traceutil/trace.go:171","msg":"trace[1159460159] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"4.009393694s","start":"2026-06-18T03:01:16.087138Z","end":"2026-06-18T03:01:20.096531Z","steps":["trace[1159460159] 'process raft request' (duration: 1.437971698s)","trace[1159460159] 'compare' (duration: 2.57104467s)"],"step_count":2} {"level":"info","ts":"2026-06-18T03:01:20.096647Z","caller":"traceutil/trace.go:171","msg":"trace[1617531615] linearizableReadLoop","detail":"{readStateIndex:679; appliedIndex:678; }","duration":"3.843013652s","start":"2026-06-18T03:01:16.253613Z","end":"2026-06-18T03:01:20.096627Z","steps":["trace[1617531615] 'read index received' (duration: 1.271418294s)","trace[1617531615] 'applied index is now lower than readState.Index' (duration: 2.571593458s)"],"step_count":2} {"level":"warn","ts":"2026-06-18T03:01:20.096767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.843134364s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-18T03:01:20.096795Z","caller":"traceutil/trace.go:171","msg":"trace[1452974807] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:649; }","duration":"3.843180054s","start":"2026-06-18T03:01:16.253607Z","end":"2026-06-18T03:01:20.096788Z","steps":["trace[1452974807] 'agreement among raft nodes before linearized reading' (duration: 3.843111593s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:01:20.096676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:01:18.101492Z","time spent":"1.995157885s","remote":"127.0.0.1:38034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-18T03:01:20.09682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:01:16.253556Z","time spent":"3.843257786s","remote":"127.0.0.1:37844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":5,"response size":31,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-06-18T03:01:20.097271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:01:16.087115Z","time spent":"4.010080053s","remote":"127.0.0.1:38114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-18T03:01:20.604498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.613171ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-06-18T03:01:20.604648Z","caller":"traceutil/trace.go:171","msg":"trace[1354194274] linearizableReadLoop","detail":"{readStateIndex:681; appliedIndex:680; }","duration":"507.927563ms","start":"2026-06-18T03:01:20.096698Z","end":"2026-06-18T03:01:20.604626Z","steps":["trace[1354194274] 'read index received' (duration: 57.1472ms)","trace[1354194274] 'applied index is now lower than readState.Index' (duration: 450.778073ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T03:01:20.604731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.600411318s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-18T03:01:20.604777Z","caller":"traceutil/trace.go:171","msg":"trace[1840469834] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:649; }","duration":"2.60053398s","start":"2026-06-18T03:01:18.004229Z","end":"2026-06-18T03:01:20.604764Z","steps":["trace[1840469834] 'agreement among raft nodes before linearized reading' (duration: 2.600419909s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:01:20.604815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:01:18.004209Z","time spent":"2.600595671s","remote":"127.0.0.1:38246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":31,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-06-18T03:01:20.604871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.831434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-18T03:01:20.604877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.112571257s","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-06-18T03:01:20.604905Z","caller":"traceutil/trace.go:171","msg":"trace[2113903091] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system; range_end:; response_count:0; response_revision:649; }","duration":"497.912525ms","start":"2026-06-18T03:01:20.106983Z","end":"2026-06-18T03:01:20.604896Z","steps":["trace[2113903091] 'agreement among raft nodes before linearized reading' (duration: 497.845984ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:01:20.604926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:01:20.106966Z","time spent":"497.954465ms","remote":"127.0.0.1:38288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" "} {"level":"info","ts":"2026-06-18T03:01:20.604924Z","caller":"traceutil/trace.go:171","msg":"trace[415780141] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:649; }","duration":"4.112646569s","start":"2026-06-18T03:01:16.492263Z","end":"2026-06-18T03:01:20.60491Z","steps":["trace[415780141] 'agreement among raft nodes before linearized reading' (duration: 4.112484127s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:01:20.604964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:01:16.492237Z","time spent":"4.11271666s","remote":"127.0.0.1:38034","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-06-18T03:01:20.605014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.9896415s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T03:01:20.605051Z","caller":"traceutil/trace.go:171","msg":"trace[701418018] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:649; }","duration":"1.989709432s","start":"2026-06-18T03:01:18.615331Z","end":"2026-06-18T03:01:20.605041Z","steps":["trace[701418018] 'agreement among raft nodes before linearized reading' (duration: 1.989655871s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:01:20.60508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.21045161s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-18T03:01:20.605105Z","caller":"traceutil/trace.go:171","msg":"trace[1320859234] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:649; }","duration":"4.210511232s","start":"2026-06-18T03:01:16.394587Z","end":"2026-06-18T03:01:20.605098Z","steps":["trace[1320859234] 'agreement among raft nodes before linearized reading' (duration: 4.210440221s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:01:20.605123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:01:16.394568Z","time spent":"4.210550472s","remote":"127.0.0.1:38304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":59,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-06-18T03:01:20.605191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.962239433s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-06-18T03:01:20.605079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:01:18.615312Z","time spent":"1.989756902s","remote":"127.0.0.1:37764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-18T03:01:20.605326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.851750432s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-06-18T03:01:20.605362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.885624481s","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-06-18T03:01:20.605393Z","caller":"traceutil/trace.go:171","msg":"trace[1780496664] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:649; }","duration":"3.851832463s","start":"2026-06-18T03:01:16.753545Z","end":"2026-06-18T03:01:20.605377Z","steps":["trace[1780496664] 'agreement among raft nodes before linearized reading' (duration: 3.85161368s)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:01:20.605396Z","caller":"traceutil/trace.go:171","msg":"trace[583364720] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:649; }","duration":"3.885695262s","start":"2026-06-18T03:01:16.719691Z","end":"2026-06-18T03:01:20.605387Z","steps":["trace[583364720] 'agreement among raft nodes before linearized reading' (duration: 3.885600061s)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:01:20.605292Z","caller":"traceutil/trace.go:171","msg":"trace[1243173212] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:649; }","duration":"3.962420606s","start":"2026-06-18T03:01:16.642854Z","end":"2026-06-18T03:01:20.605275Z","steps":["trace[1243173212] 'agreement among raft nodes before linearized reading' (duration: 3.961879389s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:01:20.60549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:01:16.642834Z","time spent":"3.962639079s","remote":"127.0.0.1:38034","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-06-18T03:01:20.605427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:01:16.719672Z","time spent":"3.885746973s","remote":"127.0.0.1:38034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-18T03:01:20.605429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:01:16.753454Z","time spent":"3.851967336s","remote":"127.0.0.1:38034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-18T03:01:21.116186Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620627290176188561,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-18T03:01:21.144977Z","caller":"traceutil/trace.go:171","msg":"trace[1724863027] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"536.500438ms","start":"2026-06-18T03:01:20.608452Z","end":"2026-06-18T03:01:21.144952Z","steps":["trace[1724863027] 'process raft request' (duration: 536.266645ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:01:21.145348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:01:20.608435Z","time spent":"536.614309ms","remote":"127.0.0.1:38034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-18T03:01:21.352226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:01:20.610268Z","time spent":"741.942114ms","remote":"127.0.0.1:37832","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-06-18T03:01:21.35258Z","caller":"traceutil/trace.go:171","msg":"trace[1149601562] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"742.157117ms","start":"2026-06-18T03:01:20.610389Z","end":"2026-06-18T03:01:21.352546Z","steps":["trace[1149601562] 'process raft request' (duration: 741.70294ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:01:21.352775Z","caller":"traceutil/trace.go:171","msg":"trace[612079864] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"742.047415ms","start":"2026-06-18T03:01:20.610671Z","end":"2026-06-18T03:01:21.352718Z","steps":["trace[612079864] 'process raft request' (duration: 741.531268ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:01:21.352851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:01:20.610382Z","time spent":"742.287278ms","remote":"127.0.0.1:38034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T03:01:21.352967Z","caller":"traceutil/trace.go:171","msg":"trace[518881425] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"741.70496ms","start":"2026-06-18T03:01:20.611201Z","end":"2026-06-18T03:01:21.352906Z","steps":["trace[518881425] 'process raft request' (duration: 741.051152ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:01:21.3531Z","caller":"traceutil/trace.go:171","msg":"trace[1602388465] linearizableReadLoop","detail":"{readStateIndex:687; appliedIndex:681; }","duration":"737.962058ms","start":"2026-06-18T03:01:20.615115Z","end":"2026-06-18T03:01:21.353077Z","steps":["trace[1602388465] 'read index received' (duration: 529.642673ms)","trace[1602388465] 'applied index is now lower than readState.Index' (duration: 208.318515ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T03:01:21.353137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:01:20.611192Z","time spent":"741.867092ms","remote":"127.0.0.1:38034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T03:01:21.353233Z","caller":"traceutil/trace.go:171","msg":"trace[1902253309] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"366.448322ms","start":"2026-06-18T03:01:20.986766Z","end":"2026-06-18T03:01:21.353215Z","steps":["trace[1902253309] 'process raft request' (duration: 365.735632ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:01:21.353318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:01:20.986737Z","time spent":"366.537363ms","remote":"127.0.0.1:37844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-18T03:01:21.35334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"738.215622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T03:01:21.35338Z","caller":"traceutil/trace.go:171","msg":"trace[1860222370] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:654; }","duration":"738.320463ms","start":"2026-06-18T03:01:20.615046Z","end":"2026-06-18T03:01:21.353367Z","steps":["trace[1860222370] 'agreement among raft nodes before linearized reading' (duration: 738.244402ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:01:21.353365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.439693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-18T03:01:21.353399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.809232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-qhf84\" ","response":"range_response_count:1 size:17145"} {"level":"warn","ts":"2026-06-18T03:01:21.353423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:01:20.615015Z","time spent":"738.400044ms","remote":"127.0.0.1:37764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-18T03:01:21.353434Z","caller":"traceutil/trace.go:171","msg":"trace[1923180840] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:654; }","duration":"351.517304ms","start":"2026-06-18T03:01:21.001901Z","end":"2026-06-18T03:01:21.353418Z","steps":["trace[1923180840] 'agreement among raft nodes before linearized reading' (duration: 351.412233ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:01:21.353475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:01:21.001889Z","time spent":"351.575735ms","remote":"127.0.0.1:38062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"info","ts":"2026-06-18T03:01:21.353431Z","caller":"traceutil/trace.go:171","msg":"trace[488680853] range","detail":"{range_begin:/registry/pods/kube-system/cilium-qhf84; range_end:; response_count:1; response_revision:654; }","duration":"527.877013ms","start":"2026-06-18T03:01:20.825544Z","end":"2026-06-18T03:01:21.353421Z","steps":["trace[488680853] 'agreement among raft nodes before linearized reading' (duration: 527.737011ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:01:21.353682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:01:20.825523Z","time spent":"528.149217ms","remote":"127.0.0.1:37944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17169,"request content":"key:\"/registry/pods/kube-system/cilium-qhf84\" "} {"level":"warn","ts":"2026-06-18T03:01:21.352952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:01:20.610665Z","time spent":"742.190347ms","remote":"127.0.0.1:38034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-18T03:01:21.890709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.716147ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-18T03:01:21.891018Z","caller":"traceutil/trace.go:171","msg":"trace[1920749369] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"536.357147ms","start":"2026-06-18T03:01:21.354631Z","end":"2026-06-18T03:01:21.890988Z","steps":["trace[1920749369] 'process raft request' (duration: 232.300935ms)","trace[1920749369] 'compare' (duration: 303.576635ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T03:01:21.891126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:01:21.354609Z","time spent":"536.468309ms","remote":"127.0.0.1:37832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-18T03:01:21.891222Z","caller":"traceutil/trace.go:171","msg":"trace[1499734023] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"489.356711ms","start":"2026-06-18T03:01:21.401839Z","end":"2026-06-18T03:01:21.891196Z","steps":["trace[1499734023] 'process raft request' (duration: 489.191588ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:01:21.891224Z","caller":"traceutil/trace.go:171","msg":"trace[518792052] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"519.357463ms","start":"2026-06-18T03:01:21.371806Z","end":"2026-06-18T03:01:21.891163Z","steps":["trace[518792052] 'process raft request' (duration: 519.047049ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:01:21.891347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:01:21.401813Z","time spent":"489.472322ms","remote":"127.0.0.1:38288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-18T03:01:21.891259Z","caller":"traceutil/trace.go:171","msg":"trace[1756613439] linearizableReadLoop","detail":"{readStateIndex:690; appliedIndex:687; }","duration":"279.676557ms","start":"2026-06-18T03:01:21.611399Z","end":"2026-06-18T03:01:21.891076Z","steps":["trace[1756613439] 'read index received' (duration: 222.527621ms)","trace[1756613439] 'applied index is now lower than readState.Index' (duration: 57.147816ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T03:01:21.891408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:01:21.37178Z","time spent":"519.529666ms","remote":"127.0.0.1:37944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17228,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-18T03:01:21.891429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.021272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T03:01:21.891466Z","caller":"traceutil/trace.go:171","msg":"trace[639087544] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:657; }","duration":"280.086842ms","start":"2026-06-18T03:01:21.611372Z","end":"2026-06-18T03:01:21.891458Z","steps":["trace[639087544] 'agreement among raft nodes before linearized reading' (duration: 279.991591ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:01:22.522847Z","caller":"traceutil/trace.go:171","msg":"trace[580405523] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"175.37361ms","start":"2026-06-18T03:01:22.347382Z","end":"2026-06-18T03:01:22.522755Z","steps":["trace[580405523] 'process raft request' (duration: 172.43521ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:01:22.897463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.511595ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-18T03:01:22.897768Z","caller":"traceutil/trace.go:171","msg":"trace[1310322883] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"253.952945ms","start":"2026-06-18T03:01:22.643785Z","end":"2026-06-18T03:01:22.897738Z","steps":["trace[1310322883] 'process raft request' (duration: 77.112265ms)","trace[1310322883] 'compare' (duration: 176.405594ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T03:01:23.242472Z","caller":"traceutil/trace.go:171","msg":"trace[2037080287] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"340.27196ms","start":"2026-06-18T03:01:22.902173Z","end":"2026-06-18T03:01:23.242445Z","steps":["trace[2037080287] 'process raft request' (duration: 340.085437ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:01:23.242559Z","caller":"traceutil/trace.go:171","msg":"trace[551680334] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"339.056413ms","start":"2026-06-18T03:01:22.903473Z","end":"2026-06-18T03:01:23.24253Z","steps":["trace[551680334] 'process raft request' (duration: 338.83508ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:01:23.242623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:01:22.902162Z","time spent":"340.379801ms","remote":"127.0.0.1:38010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T03:01:23.242482Z","caller":"traceutil/trace.go:171","msg":"trace[906808270] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"340.584254ms","start":"2026-06-18T03:01:22.901882Z","end":"2026-06-18T03:01:23.242466Z","steps":["trace[906808270] 'process raft request' (duration: 307.199789ms)","trace[906808270] 'compare' (duration: 32.702515ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T03:01:23.24272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:01:22.90346Z","time spent":"339.163394ms","remote":"127.0.0.1:37944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T03:01:23.242586Z","caller":"traceutil/trace.go:171","msg":"trace[665973946] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"284.082491ms","start":"2026-06-18T03:01:22.958484Z","end":"2026-06-18T03:01:23.242567Z","steps":["trace[665973946] 'process raft request' (duration: 283.857308ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:01:23.242821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:01:22.901839Z","time spent":"340.916419ms","remote":"127.0.0.1:37832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-18T03:01:25.511914Z","caller":"traceutil/trace.go:171","msg":"trace[1749627337] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"201.632607ms","start":"2026-06-18T03:01:25.310255Z","end":"2026-06-18T03:01:25.511888Z","steps":["trace[1749627337] 'process raft request' (duration: 200.626923ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:01:26.155297Z","caller":"traceutil/trace.go:171","msg":"trace[1449928775] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"265.160272ms","start":"2026-06-18T03:01:25.89012Z","end":"2026-06-18T03:01:26.15528Z","steps":["trace[1449928775] 'process raft request' (duration: 265.05657ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:01:27.027209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.815063ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-18T03:01:27.027235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.343779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-qhf84\" ","response":"range_response_count:1 size:17520"} {"level":"info","ts":"2026-06-18T03:01:27.027289Z","caller":"traceutil/trace.go:171","msg":"trace[509050474] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:681; }","duration":"148.904494ms","start":"2026-06-18T03:01:26.878371Z","end":"2026-06-18T03:01:27.027276Z","steps":["trace[509050474] 'range keys from in-memory index tree' (duration: 148.803393ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:01:27.027299Z","caller":"traceutil/trace.go:171","msg":"trace[127767999] range","detail":"{range_begin:/registry/pods/kube-system/cilium-qhf84; range_end:; response_count:1; response_revision:681; }","duration":"181.459101ms","start":"2026-06-18T03:01:26.845827Z","end":"2026-06-18T03:01:27.027286Z","steps":["trace[127767999] 'range keys from in-memory index tree' (duration: 181.172217ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:01:27.354603Z","caller":"traceutil/trace.go:171","msg":"trace[1662026736] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"313.504302ms","start":"2026-06-18T03:01:27.041075Z","end":"2026-06-18T03:01:27.35458Z","steps":["trace[1662026736] 'process raft request' (duration: 313.357699ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:01:27.354749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:01:27.041054Z","time spent":"313.623264ms","remote":"127.0.0.1:37944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17689,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-18T03:01:27.716879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.247391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T03:01:27.717025Z","caller":"traceutil/trace.go:171","msg":"trace[247957029] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:682; }","duration":"101.468764ms","start":"2026-06-18T03:01:27.615542Z","end":"2026-06-18T03:01:27.717011Z","steps":["trace[247957029] 'range keys from in-memory index tree' (duration: 101.124479ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:01:27.717354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.841853ms","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-06-18T03:01:27.717416Z","caller":"traceutil/trace.go:171","msg":"trace[1819316675] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:682; }","duration":"201.939434ms","start":"2026-06-18T03:01:27.515471Z","end":"2026-06-18T03:01:27.71741Z","steps":["trace[1819316675] 'range keys from in-memory index tree' (duration: 201.692831ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:01:27.717468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.390599ms","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-06-18T03:01:27.717547Z","caller":"traceutil/trace.go:171","msg":"trace[2116606116] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:682; }","duration":"115.51325ms","start":"2026-06-18T03:01:27.602011Z","end":"2026-06-18T03:01:27.717525Z","steps":["trace[2116606116] 'range keys from in-memory index tree' (duration: 115.147016ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:01:27.7176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.456993ms","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-06-18T03:01:27.717644Z","caller":"traceutil/trace.go:171","msg":"trace[2018061177] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:682; }","duration":"116.531594ms","start":"2026-06-18T03:01:27.6011Z","end":"2026-06-18T03:01:27.717631Z","steps":["trace[2018061177] 'range keys from in-memory index tree' (duration: 116.326801ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:01:27.717885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.785834ms","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-06-18T03:01:27.718116Z","caller":"traceutil/trace.go:171","msg":"trace[1217166978] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:682; }","duration":"116.110348ms","start":"2026-06-18T03:01:27.601999Z","end":"2026-06-18T03:01:27.71811Z","steps":["trace[1217166978] 'range keys from in-memory index tree' (duration: 115.659573ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:01:27.966845Z","caller":"traceutil/trace.go:171","msg":"trace[532813147] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"244.996109ms","start":"2026-06-18T03:01:27.721823Z","end":"2026-06-18T03:01:27.966819Z","steps":["trace[532813147] 'process raft request' (duration: 244.852967ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:01:28.017254Z","caller":"traceutil/trace.go:171","msg":"trace[666954321] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"294.637821ms","start":"2026-06-18T03:01:27.722595Z","end":"2026-06-18T03:01:28.017233Z","steps":["trace[666954321] 'process raft request' (duration: 294.533269ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:01:28.149542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.229495ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-18T03:01:28.149793Z","caller":"traceutil/trace.go:171","msg":"trace[232188494] linearizableReadLoop","detail":"{readStateIndex:720; appliedIndex:716; }","duration":"424.923409ms","start":"2026-06-18T03:01:27.724853Z","end":"2026-06-18T03:01:28.149777Z","steps":["trace[232188494] 'read index received' (duration: 241.810236ms)","trace[232188494] 'applied index is now lower than readState.Index' (duration: 183.111953ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T03:01:28.149779Z","caller":"traceutil/trace.go:171","msg":"trace[241046070] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"427.091049ms","start":"2026-06-18T03:01:27.722658Z","end":"2026-06-18T03:01:28.149749Z","steps":["trace[241046070] 'process raft request' (duration: 294.56512ms)","trace[241046070] 'compare' (duration: 131.982402ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T03:01:28.14992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:01:27.722652Z","time spent":"427.18958ms","remote":"127.0.0.1:38034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T03:01:28.149986Z","caller":"traceutil/trace.go:171","msg":"trace[1612816206] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"427.295372ms","start":"2026-06-18T03:01:27.72268Z","end":"2026-06-18T03:01:28.149975Z","steps":["trace[1612816206] 'process raft request' (duration: 426.964447ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:01:28.150051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:01:27.722654Z","time spent":"427.360212ms","remote":"127.0.0.1:38034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-18T03:01:28.15029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.028244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-qhf84\" ","response":"range_response_count:1 size:17704"} {"level":"info","ts":"2026-06-18T03:01:28.150314Z","caller":"traceutil/trace.go:171","msg":"trace[4693386] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"296.71584ms","start":"2026-06-18T03:01:27.853588Z","end":"2026-06-18T03:01:28.150304Z","steps":["trace[4693386] 'process raft request' (duration: 296.137661ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:01:28.150326Z","caller":"traceutil/trace.go:171","msg":"trace[1814685739] range","detail":"{range_begin:/registry/pods/kube-system/cilium-qhf84; range_end:; response_count:1; response_revision:687; }","duration":"297.099754ms","start":"2026-06-18T03:01:27.853216Z","end":"2026-06-18T03:01:28.150315Z","steps":["trace[1814685739] 'agreement among raft nodes before linearized reading' (duration: 296.969492ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:01:28.150411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.526698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-18T03:01:28.150504Z","caller":"traceutil/trace.go:171","msg":"trace[1842955851] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:687; }","duration":"425.65578ms","start":"2026-06-18T03:01:27.724823Z","end":"2026-06-18T03:01:28.150478Z","steps":["trace[1842955851] 'agreement among raft nodes before linearized reading' (duration: 425.498708ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:01:28.150564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:01:27.724814Z","time spent":"425.733991ms","remote":"127.0.0.1:38088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":14,"response size":31,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-06-18T03:01:29.041015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.273358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-18T03:01:29.041089Z","caller":"traceutil/trace.go:171","msg":"trace[1412836726] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:691; }","duration":"322.411449ms","start":"2026-06-18T03:01:28.718663Z","end":"2026-06-18T03:01:29.041074Z","steps":["trace[1412836726] 'count revisions from in-memory index tree' (duration: 322.192615ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:01:29.041125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:01:28.718644Z","time spent":"322.47263ms","remote":"127.0.0.1:38210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":2,"response size":31,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-06-18T03:01:29.041137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.160736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-qhf84\" ","response":"range_response_count:1 size:17786"} {"level":"info","ts":"2026-06-18T03:01:29.041204Z","caller":"traceutil/trace.go:171","msg":"trace[2021724778] range","detail":"{range_begin:/registry/pods/kube-system/cilium-qhf84; range_end:; response_count:1; response_revision:691; }","duration":"179.244027ms","start":"2026-06-18T03:01:28.861944Z","end":"2026-06-18T03:01:29.041188Z","steps":["trace[2021724778] 'range keys from in-memory index tree' (duration: 178.992564ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:01:30.118342Z","caller":"traceutil/trace.go:171","msg":"trace[1815799343] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"100.087886ms","start":"2026-06-18T03:01:30.018202Z","end":"2026-06-18T03:01:30.11829Z","steps":["trace[1815799343] 'process raft request' (duration: 87.578622ms)","trace[1815799343] 'compare' (duration: 12.394593ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T03:01:31.385736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.819395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T03:01:31.385842Z","caller":"traceutil/trace.go:171","msg":"trace[472581725] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:742; }","duration":"283.979047ms","start":"2026-06-18T03:01:31.10184Z","end":"2026-06-18T03:01:31.385819Z","steps":["trace[472581725] 'range keys from in-memory index tree' (duration: 283.677323ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:01:32.804251Z","caller":"traceutil/trace.go:171","msg":"trace[1617079889] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"101.860287ms","start":"2026-06-18T03:01:32.702366Z","end":"2026-06-18T03:01:32.804226Z","steps":["trace[1617079889] 'process raft request' (duration: 93.869312ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:01:32.811327Z","caller":"traceutil/trace.go:171","msg":"trace[1256864065] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"107.52744ms","start":"2026-06-18T03:01:32.703778Z","end":"2026-06-18T03:01:32.811305Z","steps":["trace[1256864065] 'process raft request' (duration: 107.317827ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:01:39.900112Z","caller":"traceutil/trace.go:171","msg":"trace[1591537136] linearizableReadLoop","detail":"{readStateIndex:844; appliedIndex:843; }","duration":"251.119471ms","start":"2026-06-18T03:01:39.648964Z","end":"2026-06-18T03:01:39.900084Z","steps":["trace[1591537136] 'read index received' (duration: 250.889878ms)","trace[1591537136] 'applied index is now lower than readState.Index' (duration: 228.513µs)"],"step_count":2} {"level":"info","ts":"2026-06-18T03:01:39.900187Z","caller":"traceutil/trace.go:171","msg":"trace[524791315] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"423.932681ms","start":"2026-06-18T03:01:39.476226Z","end":"2026-06-18T03:01:39.900159Z","steps":["trace[524791315] 'process raft request' (duration: 423.691228ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:01:39.900305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.332633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T03:01:39.90046Z","caller":"traceutil/trace.go:171","msg":"trace[1310447440] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:807; }","duration":"251.546126ms","start":"2026-06-18T03:01:39.648899Z","end":"2026-06-18T03:01:39.900445Z","steps":["trace[1310447440] 'agreement among raft nodes before linearized reading' (duration: 251.348874ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:01:39.900429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:01:39.476194Z","time spent":"424.159194ms","remote":"127.0.0.1:38034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-18T03:01:44.112066Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620627290176188982,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-18T03:01:44.613087Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620627290176188982,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-18T03:01:45.113845Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620627290176188982,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-18T03:01:45.19902Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.078604593s","expected-duration":"1s"} {"level":"info","ts":"2026-06-18T03:01:45.199405Z","caller":"traceutil/trace.go:171","msg":"trace[434525781] transaction","detail":"{read_only:false; response_revision:827; number_of_response:1; }","duration":"2.07908086s","start":"2026-06-18T03:01:43.1203Z","end":"2026-06-18T03:01:45.199381Z","steps":["trace[434525781] 'process raft request' (duration: 2.078937858s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:01:45.199547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:01:43.120279Z","time spent":"2.079197212s","remote":"127.0.0.1:37944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4856,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T03:01:45.206881Z","caller":"traceutil/trace.go:171","msg":"trace[560459067] linearizableReadLoop","detail":"{readStateIndex:866; appliedIndex:864; }","duration":"1.594892397s","start":"2026-06-18T03:01:43.611968Z","end":"2026-06-18T03:01:45.20686Z","steps":["trace[560459067] 'read index received' (duration: 1.587343005s)","trace[560459067] 'applied index is now lower than readState.Index' (duration: 7.548672ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T03:01:45.207058Z","caller":"traceutil/trace.go:171","msg":"trace[809278455] transaction","detail":"{read_only:false; response_revision:828; number_of_response:1; }","duration":"1.253571791s","start":"2026-06-18T03:01:43.953469Z","end":"2026-06-18T03:01:45.207041Z","steps":["trace[809278455] 'process raft request' (duration: 1.253267707s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:01:45.20718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:01:43.953444Z","time spent":"1.253670561s","remote":"127.0.0.1:38010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-18T03:01:45.207304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.595164691s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T03:01:45.207932Z","caller":"traceutil/trace.go:171","msg":"trace[658348118] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:828; }","duration":"1.595976512s","start":"2026-06-18T03:01:43.611921Z","end":"2026-06-18T03:01:45.207898Z","steps":["trace[658348118] 'agreement among raft nodes before linearized reading' (duration: 1.595161761s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:01:45.207994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:01:43.611903Z","time spent":"1.596069483s","remote":"127.0.0.1:37772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-18T03:01:45.213812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"970.64523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-06-18T03:01:45.213835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"963.115178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-06-18T03:01:45.213893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"964.612686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-06-18T03:01:45.213905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.128597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.65\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-06-18T03:01:45.213925Z","caller":"traceutil/trace.go:171","msg":"trace[2123477007] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:830; }","duration":"964.677947ms","start":"2026-06-18T03:01:44.249237Z","end":"2026-06-18T03:01:45.213915Z","steps":["trace[2123477007] 'agreement among raft nodes before linearized reading' (duration: 964.603316ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:01:45.213912Z","caller":"traceutil/trace.go:171","msg":"trace[683872158] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:830; }","duration":"963.208728ms","start":"2026-06-18T03:01:44.250684Z","end":"2026-06-18T03:01:45.213893Z","steps":["trace[683872158] 'agreement among raft nodes before linearized reading' (duration: 963.042086ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:01:45.214029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:01:44.250674Z","time spent":"963.33961ms","remote":"127.0.0.1:38034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-18T03:01:45.213955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:01:44.249218Z","time spent":"964.731507ms","remote":"127.0.0.1:38034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-18T03:01:45.214032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.243723278s","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-06-18T03:01:45.213886Z","caller":"traceutil/trace.go:171","msg":"trace[44174384] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:830; }","duration":"970.750921ms","start":"2026-06-18T03:01:44.243125Z","end":"2026-06-18T03:01:45.213876Z","steps":["trace[44174384] 'agreement among raft nodes before linearized reading' (duration: 970.61449ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:01:45.214122Z","caller":"traceutil/trace.go:171","msg":"trace[139792936] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:830; }","duration":"1.243846589s","start":"2026-06-18T03:01:43.970267Z","end":"2026-06-18T03:01:45.214113Z","steps":["trace[139792936] 'agreement among raft nodes before linearized reading' (duration: 1.243726858s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:01:45.2141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.093814114s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-06-18T03:01:45.214138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:01:44.243107Z","time spent":"971.023005ms","remote":"127.0.0.1:38034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-18T03:01:45.214149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:01:43.970244Z","time spent":"1.243897539s","remote":"127.0.0.1:38034","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-06-18T03:01:45.214186Z","caller":"traceutil/trace.go:171","msg":"trace[1061346009] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:830; }","duration":"1.093943155s","start":"2026-06-18T03:01:44.120219Z","end":"2026-06-18T03:01:45.214162Z","steps":["trace[1061346009] 'agreement among raft nodes before linearized reading' (duration: 1.093637831s)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:01:45.213971Z","caller":"traceutil/trace.go:171","msg":"trace[52742714] range","detail":"{range_begin:/registry/masterleases/199.204.45.65; range_end:; response_count:1; response_revision:830; }","duration":"506.206259ms","start":"2026-06-18T03:01:44.707724Z","end":"2026-06-18T03:01:45.21393Z","steps":["trace[52742714] 'agreement among raft nodes before linearized reading' (duration: 506.076987ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:01:45.214225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:01:44.707705Z","time spent":"506.515152ms","remote":"127.0.0.1:37788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.65\" "} {"level":"warn","ts":"2026-06-18T03:01:45.215016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:01:44.1202Z","time spent":"1.094791876s","remote":"127.0.0.1:38034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-18T03:01:51.393018Z","caller":"traceutil/trace.go:171","msg":"trace[874275982] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"142.952719ms","start":"2026-06-18T03:01:51.250046Z","end":"2026-06-18T03:01:51.392999Z","steps":["trace[874275982] 'process raft request' (duration: 142.632054ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:01:51.401176Z","caller":"traceutil/trace.go:171","msg":"trace[145638722] linearizableReadLoop","detail":"{readStateIndex:939; appliedIndex:937; }","duration":"146.174998ms","start":"2026-06-18T03:01:51.254981Z","end":"2026-06-18T03:01:51.401156Z","steps":["trace[145638722] 'read index received' (duration: 137.735117ms)","trace[145638722] 'applied index is now lower than readState.Index' (duration: 8.438381ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T03:01:51.401199Z","caller":"traceutil/trace.go:171","msg":"trace[1246697778] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"150.778073ms","start":"2026-06-18T03:01:51.250392Z","end":"2026-06-18T03:01:51.401171Z","steps":["trace[1246697778] 'process raft request' (duration: 150.613541ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:01:51.401476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.467233ms","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-06-18T03:01:51.40156Z","caller":"traceutil/trace.go:171","msg":"trace[21384841] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:899; }","duration":"146.571874ms","start":"2026-06-18T03:01:51.254962Z","end":"2026-06-18T03:01:51.401534Z","steps":["trace[21384841] 'agreement among raft nodes before linearized reading' (duration: 146.326221ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:01:51.402283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.685343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T03:01:51.402312Z","caller":"traceutil/trace.go:171","msg":"trace[1433423318] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:899; }","duration":"135.748554ms","start":"2026-06-18T03:01:51.266557Z","end":"2026-06-18T03:01:51.402305Z","steps":["trace[1433423318] 'agreement among raft nodes before linearized reading' (duration: 135.698773ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:01:51.402466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.03857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-06-18T03:01:51.402548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.163952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-06-18T03:01:51.402576Z","caller":"traceutil/trace.go:171","msg":"trace[137401648] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:899; }","duration":"143.221953ms","start":"2026-06-18T03:01:51.259348Z","end":"2026-06-18T03:01:51.40257Z","steps":["trace[137401648] 'agreement among raft nodes before linearized reading' (duration: 143.153272ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:01:51.402556Z","caller":"traceutil/trace.go:171","msg":"trace[1780930196] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:899; }","duration":"147.147231ms","start":"2026-06-18T03:01:51.255395Z","end":"2026-06-18T03:01:51.402542Z","steps":["trace[1780930196] 'agreement among raft nodes before linearized reading' (duration: 146.848837ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:01:52.667944Z","caller":"traceutil/trace.go:171","msg":"trace[814786937] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"204.354799ms","start":"2026-06-18T03:01:52.463565Z","end":"2026-06-18T03:01:52.667919Z","steps":["trace[814786937] 'process raft request' (duration: 151.755609ms)","trace[814786937] 'compare' (duration: 52.416678ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T03:01:52.668154Z","caller":"traceutil/trace.go:171","msg":"trace[1312351476] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"143.261007ms","start":"2026-06-18T03:01:52.524867Z","end":"2026-06-18T03:01:52.668128Z","steps":["trace[1312351476] 'process raft request' (duration: 142.999854ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:01:54.048073Z","caller":"traceutil/trace.go:171","msg":"trace[727859206] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"123.739176ms","start":"2026-06-18T03:01:53.924308Z","end":"2026-06-18T03:01:54.048047Z","steps":["trace[727859206] 'process raft request' (duration: 123.584054ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:01:54.20627Z","caller":"traceutil/trace.go:171","msg":"trace[104832585] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"148.155182ms","start":"2026-06-18T03:01:54.058092Z","end":"2026-06-18T03:01:54.206248Z","steps":["trace[104832585] 'process raft request' (duration: 88.600624ms)","trace[104832585] 'compare' (duration: 59.449477ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T03:01:54.387819Z","caller":"traceutil/trace.go:171","msg":"trace[1809530058] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"176.177707ms","start":"2026-06-18T03:01:54.211609Z","end":"2026-06-18T03:01:54.387787Z","steps":["trace[1809530058] 'process raft request' (duration: 121.07857ms)","trace[1809530058] 'compare' (duration: 54.954475ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T03:01:54.388337Z","caller":"traceutil/trace.go:171","msg":"trace[585262197] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"176.665582ms","start":"2026-06-18T03:01:54.211651Z","end":"2026-06-18T03:01:54.388316Z","steps":["trace[585262197] 'process raft request' (duration: 176.5396ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:01:54.388444Z","caller":"traceutil/trace.go:171","msg":"trace[123165323] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"171.261737ms","start":"2026-06-18T03:01:54.21711Z","end":"2026-06-18T03:01:54.388371Z","steps":["trace[123165323] 'process raft request' (duration: 171.163996ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:01:54.38882Z","caller":"traceutil/trace.go:171","msg":"trace[2079126955] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"125.762297ms","start":"2026-06-18T03:01:54.263023Z","end":"2026-06-18T03:01:54.388786Z","steps":["trace[2079126955] 'process raft request' (duration: 125.294172ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:01:55.291724Z","caller":"traceutil/trace.go:171","msg":"trace[1085209356] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"173.517987ms","start":"2026-06-18T03:01:55.118179Z","end":"2026-06-18T03:01:55.291697Z","steps":["trace[1085209356] 'process raft request' (duration: 173.125332ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:01:55.492654Z","caller":"traceutil/trace.go:171","msg":"trace[151670740] linearizableReadLoop","detail":"{readStateIndex:987; appliedIndex:985; }","duration":"136.511968ms","start":"2026-06-18T03:01:55.356113Z","end":"2026-06-18T03:01:55.492625Z","steps":["trace[151670740] 'read index received' (duration: 134.022839ms)","trace[151670740] 'applied index is now lower than readState.Index' (duration: 2.487579ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T03:01:55.492791Z","caller":"traceutil/trace.go:171","msg":"trace[927627884] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"370.427382ms","start":"2026-06-18T03:01:55.122333Z","end":"2026-06-18T03:01:55.49276Z","steps":["trace[927627884] 'process raft request' (duration: 370.186599ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:01:55.492757Z","caller":"traceutil/trace.go:171","msg":"trace[1802492477] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"373.281936ms","start":"2026-06-18T03:01:55.119442Z","end":"2026-06-18T03:01:55.492724Z","steps":["trace[1802492477] 'process raft request' (duration: 370.754456ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:01:55.492834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.70153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-18T03:01:55.49293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:01:55.122307Z","time spent":"370.547263ms","remote":"127.0.0.1:38210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4213,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-18T03:01:55.492907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.046681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T03:01:55.493003Z","caller":"traceutil/trace.go:171","msg":"trace[1891467699] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:0; response_revision:946; }","duration":"130.183033ms","start":"2026-06-18T03:01:55.362797Z","end":"2026-06-18T03:01:55.49298Z","steps":["trace[1891467699] 'agreement among raft nodes before linearized reading' (duration: 130.015082ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:01:55.493165Z","caller":"traceutil/trace.go:171","msg":"trace[1311156646] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:946; }","duration":"136.801672ms","start":"2026-06-18T03:01:55.356065Z","end":"2026-06-18T03:01:55.492867Z","steps":["trace[1311156646] 'agreement among raft nodes before linearized reading' (duration: 136.706641ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:01:55.493663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:01:55.119434Z","time spent":"373.438807ms","remote":"127.0.0.1:37944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4868,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T03:01:55.792895Z","caller":"traceutil/trace.go:171","msg":"trace[1485310254] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"293.083774ms","start":"2026-06-18T03:01:55.499768Z","end":"2026-06-18T03:01:55.792851Z","steps":["trace[1485310254] 'process raft request' (duration: 291.611647ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:01:55.793086Z","caller":"traceutil/trace.go:171","msg":"trace[824640617] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"292.878512ms","start":"2026-06-18T03:01:55.500148Z","end":"2026-06-18T03:01:55.793027Z","steps":["trace[824640617] 'process raft request' (duration: 292.646599ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:01:55.793196Z","caller":"traceutil/trace.go:171","msg":"trace[1258176130] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"292.954362ms","start":"2026-06-18T03:01:55.50022Z","end":"2026-06-18T03:01:55.793175Z","steps":["trace[1258176130] 'process raft request' (duration: 292.71217ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:01:55.793427Z","caller":"traceutil/trace.go:171","msg":"trace[1922139277] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"292.948782ms","start":"2026-06-18T03:01:55.500466Z","end":"2026-06-18T03:01:55.793415Z","steps":["trace[1922139277] 'process raft request' (duration: 292.661119ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:01:55.793477Z","caller":"traceutil/trace.go:171","msg":"trace[1921455361] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"292.621089ms","start":"2026-06-18T03:01:55.50084Z","end":"2026-06-18T03:01:55.793462Z","steps":["trace[1921455361] 'process raft request' (duration: 292.450237ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:01:55.793506Z","caller":"traceutil/trace.go:171","msg":"trace[1729208829] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"291.910921ms","start":"2026-06-18T03:01:55.501578Z","end":"2026-06-18T03:01:55.793489Z","steps":["trace[1729208829] 'process raft request' (duration: 291.78627ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:01:55.793766Z","caller":"traceutil/trace.go:171","msg":"trace[33577230] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"291.580578ms","start":"2026-06-18T03:01:55.502166Z","end":"2026-06-18T03:01:55.793746Z","steps":["trace[33577230] 'process raft request' (duration: 291.259333ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:01:55.793847Z","caller":"traceutil/trace.go:171","msg":"trace[2099725773] linearizableReadLoop","detail":"{readStateIndex:995; appliedIndex:987; }","duration":"290.97838ms","start":"2026-06-18T03:01:55.502856Z","end":"2026-06-18T03:01:55.793834Z","steps":["trace[2099725773] 'read index received' (duration: 20.157199ms)","trace[2099725773] 'applied index is now lower than readState.Index' (duration: 270.820231ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T03:01:55.793911Z","caller":"traceutil/trace.go:171","msg":"trace[46977112] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"290.909459ms","start":"2026-06-18T03:01:55.502989Z","end":"2026-06-18T03:01:55.793898Z","steps":["trace[46977112] 'process raft request' (duration: 290.626794ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:01:55.794025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.148112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-06-18T03:01:55.794045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.034015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T03:01:55.794062Z","caller":"traceutil/trace.go:171","msg":"trace[792493457] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:955; }","duration":"291.217062ms","start":"2026-06-18T03:01:55.502833Z","end":"2026-06-18T03:01:55.79405Z","steps":["trace[792493457] 'agreement among raft nodes before linearized reading' (duration: 291.080771ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:01:55.7941Z","caller":"traceutil/trace.go:171","msg":"trace[2001782204] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:0; response_revision:955; }","duration":"191.122867ms","start":"2026-06-18T03:01:55.602958Z","end":"2026-06-18T03:01:55.794081Z","steps":["trace[2001782204] 'agreement among raft nodes before linearized reading' (duration: 191.044456ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:01:55.794114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.677086ms","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-06-18T03:01:55.794162Z","caller":"traceutil/trace.go:171","msg":"trace[1995799354] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:955; }","duration":"290.744937ms","start":"2026-06-18T03:01:55.503402Z","end":"2026-06-18T03:01:55.794147Z","steps":["trace[1995799354] 'agreement among raft nodes before linearized reading' (duration: 290.588675ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:01:55.922639Z","caller":"traceutil/trace.go:171","msg":"trace[1742379783] linearizableReadLoop","detail":"{readStateIndex:999; appliedIndex:997; }","duration":"119.993103ms","start":"2026-06-18T03:01:55.802617Z","end":"2026-06-18T03:01:55.92261Z","steps":["trace[1742379783] 'read index received' (duration: 74.361491ms)","trace[1742379783] 'applied index is now lower than readState.Index' (duration: 45.630002ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T03:01:55.92268Z","caller":"traceutil/trace.go:171","msg":"trace[1407457363] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"123.092309ms","start":"2026-06-18T03:01:55.799563Z","end":"2026-06-18T03:01:55.922655Z","steps":["trace[1407457363] 'process raft request' (duration: 122.042316ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:01:55.9228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.152405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T03:01:55.922857Z","caller":"traceutil/trace.go:171","msg":"trace[1212877200] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:0; response_revision:958; }","duration":"120.250625ms","start":"2026-06-18T03:01:55.802591Z","end":"2026-06-18T03:01:55.922842Z","steps":["trace[1212877200] 'agreement among raft nodes before linearized reading' (duration: 120.139384ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:01:55.923036Z","caller":"traceutil/trace.go:171","msg":"trace[1793789784] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"121.924666ms","start":"2026-06-18T03:01:55.80109Z","end":"2026-06-18T03:01:55.923014Z","steps":["trace[1793789784] 'process raft request' (duration: 121.43241ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:01:57.395474Z","caller":"traceutil/trace.go:171","msg":"trace[1429856328] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"206.197877ms","start":"2026-06-18T03:01:57.189252Z","end":"2026-06-18T03:01:57.39545Z","steps":["trace[1429856328] 'process raft request' (duration: 148.218596ms)","trace[1429856328] 'compare' (duration: 57.85134ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T03:01:58.090581Z","caller":"traceutil/trace.go:171","msg":"trace[22787657] linearizableReadLoop","detail":"{readStateIndex:1028; appliedIndex:1025; }","duration":"209.055277ms","start":"2026-06-18T03:01:57.881508Z","end":"2026-06-18T03:01:58.090563Z","steps":["trace[22787657] 'read index received' (duration: 93.576631ms)","trace[22787657] 'applied index is now lower than readState.Index' (duration: 115.477836ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T03:01:58.090862Z","caller":"traceutil/trace.go:171","msg":"trace[2036110635] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"211.495797ms","start":"2026-06-18T03:01:57.879359Z","end":"2026-06-18T03:01:58.090855Z","steps":["trace[2036110635] 'process raft request' (duration: 209.310361ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:01:58.090994Z","caller":"traceutil/trace.go:171","msg":"trace[297200284] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"211.575048ms","start":"2026-06-18T03:01:57.879413Z","end":"2026-06-18T03:01:58.090988Z","steps":["trace[297200284] 'process raft request' (duration: 211.117862ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:01:58.091262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.741446ms","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-06-18T03:01:58.091333Z","caller":"traceutil/trace.go:171","msg":"trace[1893293068] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:987; }","duration":"209.845787ms","start":"2026-06-18T03:01:57.881463Z","end":"2026-06-18T03:01:58.091309Z","steps":["trace[1893293068] 'agreement among raft nodes before linearized reading' (duration: 209.645495ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:01:58.091414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.866596ms","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-06-18T03:01:58.09211Z","caller":"traceutil/trace.go:171","msg":"trace[1604492233] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:987; }","duration":"141.685215ms","start":"2026-06-18T03:01:57.950393Z","end":"2026-06-18T03:01:58.092078Z","steps":["trace[1604492233] 'agreement among raft nodes before linearized reading' (duration: 140.737274ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:02:04.133613Z","caller":"traceutil/trace.go:171","msg":"trace[888512689] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"134.016032ms","start":"2026-06-18T03:02:03.999568Z","end":"2026-06-18T03:02:04.133584Z","steps":["trace[888512689] 'process raft request' (duration: 133.773469ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:02:04.657771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.831804ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-18T03:02:04.657875Z","caller":"traceutil/trace.go:171","msg":"trace[1471446143] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"520.273674ms","start":"2026-06-18T03:02:04.137581Z","end":"2026-06-18T03:02:04.657855Z","steps":["trace[1471446143] 'process raft request' (duration: 363.268478ms)","trace[1471446143] 'compare' (duration: 156.354958ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T03:02:04.658019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:02:04.137554Z","time spent":"520.403406ms","remote":"127.0.0.1:38034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T03:02:04.712297Z","caller":"traceutil/trace.go:171","msg":"trace[1230981997] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"574.199624ms","start":"2026-06-18T03:02:04.138075Z","end":"2026-06-18T03:02:04.712275Z","steps":["trace[1230981997] 'process raft request' (duration: 574.013682ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:02:04.712451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:02:04.138054Z","time spent":"574.310875ms","remote":"127.0.0.1:38034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T03:02:04.712547Z","caller":"traceutil/trace.go:171","msg":"trace[2034594105] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"574.074413ms","start":"2026-06-18T03:02:04.138448Z","end":"2026-06-18T03:02:04.712522Z","steps":["trace[2034594105] 'process raft request' (duration: 573.78816ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:02:04.712643Z","caller":"traceutil/trace.go:171","msg":"trace[352628434] linearizableReadLoop","detail":"{readStateIndex:1069; appliedIndex:1066; }","duration":"573.722339ms","start":"2026-06-18T03:02:04.138897Z","end":"2026-06-18T03:02:04.712619Z","steps":["trace[352628434] 'read index received' (duration: 361.985584ms)","trace[352628434] 'applied index is now lower than readState.Index' (duration: 211.735455ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T03:02:04.712685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:02:04.13844Z","time spent":"574.183234ms","remote":"127.0.0.1:38034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-18T03:02:04.712849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.91484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-18T03:02:04.712889Z","caller":"traceutil/trace.go:171","msg":"trace[370112955] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1028; }","duration":"407.004521ms","start":"2026-06-18T03:02:04.305875Z","end":"2026-06-18T03:02:04.712879Z","steps":["trace[370112955] 'agreement among raft nodes before linearized reading' (duration: 406.852479ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:02:04.712903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.969822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-06-18T03:02:04.712921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:02:04.305854Z","time spent":"407.059761ms","remote":"127.0.0.1:37832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":122,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-06-18T03:02:04.712957Z","caller":"traceutil/trace.go:171","msg":"trace[1975011551] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1028; }","duration":"574.069653ms","start":"2026-06-18T03:02:04.138877Z","end":"2026-06-18T03:02:04.712946Z","steps":["trace[1975011551] 'agreement among raft nodes before linearized reading' (duration: 573.83716ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:02:04.712994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:02:04.13887Z","time spent":"574.118223ms","remote":"127.0.0.1:38034","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":"info","ts":"2026-06-18T03:02:04.712678Z","caller":"traceutil/trace.go:171","msg":"trace[873411988] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"249.182726ms","start":"2026-06-18T03:02:04.463455Z","end":"2026-06-18T03:02:04.712638Z","steps":["trace[873411988] 'process raft request' (duration: 249.023244ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:02:04.905806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.244309ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-18T03:02:04.906015Z","caller":"traceutil/trace.go:171","msg":"trace[1954933559] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"189.009894ms","start":"2026-06-18T03:02:04.716981Z","end":"2026-06-18T03:02:04.905991Z","steps":["trace[1954933559] 'process raft request' (duration: 77.496152ms)","trace[1954933559] 'compare' (duration: 111.051206ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T03:02:04.906315Z","caller":"traceutil/trace.go:171","msg":"trace[562541504] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"179.365473ms","start":"2026-06-18T03:02:04.726915Z","end":"2026-06-18T03:02:04.90628Z","steps":["trace[562541504] 'process raft request' (duration: 178.996039ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:02:06.302695Z","caller":"traceutil/trace.go:171","msg":"trace[1132119640] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"159.891477ms","start":"2026-06-18T03:02:06.142779Z","end":"2026-06-18T03:02:06.302671Z","steps":["trace[1132119640] 'process raft request' (duration: 159.734875ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:02:25.193751Z","caller":"traceutil/trace.go:171","msg":"trace[1385435941] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"148.118405ms","start":"2026-06-18T03:02:25.045607Z","end":"2026-06-18T03:02:25.193726Z","steps":["trace[1385435941] 'process raft request' (duration: 147.963964ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:02:26.912189Z","caller":"traceutil/trace.go:171","msg":"trace[2007846250] linearizableReadLoop","detail":"{readStateIndex:1149; appliedIndex:1148; }","duration":"278.359542ms","start":"2026-06-18T03:02:26.633809Z","end":"2026-06-18T03:02:26.912169Z","steps":["trace[2007846250] 'read index received' (duration: 278.315961ms)","trace[2007846250] 'applied index is now lower than readState.Index' (duration: 42.781µs)"],"step_count":2} {"level":"info","ts":"2026-06-18T03:02:26.912286Z","caller":"traceutil/trace.go:171","msg":"trace[1624023023] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"461.889178ms","start":"2026-06-18T03:02:26.450369Z","end":"2026-06-18T03:02:26.912258Z","steps":["trace[1624023023] 'process raft request' (duration: 461.699456ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:02:26.91241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.598744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-18T03:02:26.912441Z","caller":"traceutil/trace.go:171","msg":"trace[1883511031] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1101; }","duration":"278.708526ms","start":"2026-06-18T03:02:26.633726Z","end":"2026-06-18T03:02:26.912434Z","steps":["trace[1883511031] 'agreement among raft nodes before linearized reading' (duration: 278.544964ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:02:26.912466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:02:26.450358Z","time spent":"462.030549ms","remote":"127.0.0.1:38034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-18T03:02:26.94128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.163852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-18T03:02:26.941367Z","caller":"traceutil/trace.go:171","msg":"trace[609228784] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1102; }","duration":"183.294323ms","start":"2026-06-18T03:02:26.758053Z","end":"2026-06-18T03:02:26.941348Z","steps":["trace[609228784] 'agreement among raft nodes before linearized reading' (duration: 183.133551ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:02:26.941384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.952661ms","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-06-18T03:02:26.941432Z","caller":"traceutil/trace.go:171","msg":"trace[1321884887] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1102; }","duration":"112.041963ms","start":"2026-06-18T03:02:26.829379Z","end":"2026-06-18T03:02:26.941421Z","steps":["trace[1321884887] 'agreement among raft nodes before linearized reading' (duration: 111.882751ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:02:31.349683Z","caller":"traceutil/trace.go:171","msg":"trace[1486603565] linearizableReadLoop","detail":"{readStateIndex:1165; appliedIndex:1164; }","duration":"334.601108ms","start":"2026-06-18T03:02:31.015054Z","end":"2026-06-18T03:02:31.349655Z","steps":["trace[1486603565] 'read index received' (duration: 310.48914ms)","trace[1486603565] 'applied index is now lower than readState.Index' (duration: 24.110748ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T03:02:31.349758Z","caller":"traceutil/trace.go:171","msg":"trace[692886450] transaction","detail":"{read_only:false; response_revision:1116; number_of_response:1; }","duration":"385.270322ms","start":"2026-06-18T03:02:30.964441Z","end":"2026-06-18T03:02:31.349711Z","steps":["trace[692886450] 'process raft request' (duration: 361.249895ms)","trace[692886450] 'compare' (duration: 23.780384ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T03:02:31.349952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.880371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"warn","ts":"2026-06-18T03:02:31.349959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.877154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-18T03:02:31.349991Z","caller":"traceutil/trace.go:171","msg":"trace[736226579] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1116; }","duration":"334.970852ms","start":"2026-06-18T03:02:31.015007Z","end":"2026-06-18T03:02:31.349978Z","steps":["trace[736226579] 'agreement among raft nodes before linearized reading' (duration: 334.79954ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:02:31.349983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:02:30.964412Z","time spent":"385.436234ms","remote":"127.0.0.1:38034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-18T03:02:31.350029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:02:31.014989Z","time spent":"335.031492ms","remote":"127.0.0.1:38034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-18T03:02:31.350082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.571573ms","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-06-18T03:02:31.350107Z","caller":"traceutil/trace.go:171","msg":"trace[795931289] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1116; }","duration":"312.614723ms","start":"2026-06-18T03:02:31.037485Z","end":"2026-06-18T03:02:31.3501Z","steps":["trace[795931289] 'agreement among raft nodes before linearized reading' (duration: 312.543282ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:02:31.350007Z","caller":"traceutil/trace.go:171","msg":"trace[1704145605] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1116; }","duration":"323.950555ms","start":"2026-06-18T03:02:31.026043Z","end":"2026-06-18T03:02:31.349994Z","steps":["trace[1704145605] 'agreement among raft nodes before linearized reading' (duration: 323.816354ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:02:31.350136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:02:31.037476Z","time spent":"312.653433ms","remote":"127.0.0.1:38034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-18T03:02:31.350177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:02:31.02603Z","time spent":"324.129957ms","remote":"127.0.0.1:38034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-18T03:02:31.504818Z","caller":"traceutil/trace.go:171","msg":"trace[445211742] transaction","detail":"{read_only:false; response_revision:1119; number_of_response:1; }","duration":"149.966433ms","start":"2026-06-18T03:02:31.354832Z","end":"2026-06-18T03:02:31.504799Z","steps":["trace[445211742] 'process raft request' (duration: 149.910293ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:02:31.504844Z","caller":"traceutil/trace.go:171","msg":"trace[1498515145] transaction","detail":"{read_only:false; response_revision:1118; number_of_response:1; }","duration":"150.916164ms","start":"2026-06-18T03:02:31.353896Z","end":"2026-06-18T03:02:31.504812Z","steps":["trace[1498515145] 'process raft request' (duration: 127.394553ms)","trace[1498515145] 'compare' (duration: 23.34869ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T03:02:31.505011Z","caller":"traceutil/trace.go:171","msg":"trace[1760709824] transaction","detail":"{read_only:false; response_revision:1120; number_of_response:1; }","duration":"150.098634ms","start":"2026-06-18T03:02:31.35489Z","end":"2026-06-18T03:02:31.504988Z","steps":["trace[1760709824] 'process raft request' (duration: 149.882592ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:02:33.672935Z","caller":"traceutil/trace.go:171","msg":"trace[1474542962] transaction","detail":"{read_only:false; response_revision:1126; number_of_response:1; }","duration":"126.661698ms","start":"2026-06-18T03:02:33.546256Z","end":"2026-06-18T03:02:33.672918Z","steps":["trace[1474542962] 'process raft request' (duration: 107.818549ms)","trace[1474542962] 'compare' (duration: 18.729818ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T03:02:33.673343Z","caller":"traceutil/trace.go:171","msg":"trace[144473850] transaction","detail":"{read_only:false; response_revision:1127; number_of_response:1; }","duration":"126.937661ms","start":"2026-06-18T03:02:33.546369Z","end":"2026-06-18T03:02:33.673307Z","steps":["trace[144473850] 'process raft request' (duration: 126.525217ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:02:37.863825Z","caller":"traceutil/trace.go:171","msg":"trace[2069304520] transaction","detail":"{read_only:false; response_revision:1139; number_of_response:1; }","duration":"151.683892ms","start":"2026-06-18T03:02:37.712108Z","end":"2026-06-18T03:02:37.863792Z","steps":["trace[2069304520] 'process raft request' (duration: 64.095657ms)","trace[2069304520] 'compare' (duration: 87.430983ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T03:02:37.948775Z","caller":"traceutil/trace.go:171","msg":"trace[1402545438] transaction","detail":"{read_only:false; response_revision:1141; number_of_response:1; }","duration":"196.832439ms","start":"2026-06-18T03:02:37.751912Z","end":"2026-06-18T03:02:37.948744Z","steps":["trace[1402545438] 'process raft request' (duration: 196.780189ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:02:37.949051Z","caller":"traceutil/trace.go:171","msg":"trace[893500535] transaction","detail":"{read_only:false; response_revision:1140; number_of_response:1; }","duration":"236.892211ms","start":"2026-06-18T03:02:37.712149Z","end":"2026-06-18T03:02:37.949041Z","steps":["trace[893500535] 'process raft request' (duration: 236.357986ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:02:47.69719Z","caller":"traceutil/trace.go:171","msg":"trace[1037175171] transaction","detail":"{read_only:false; response_revision:1169; number_of_response:1; }","duration":"104.023029ms","start":"2026-06-18T03:02:47.593139Z","end":"2026-06-18T03:02:47.697162Z","steps":["trace[1037175171] 'process raft request' (duration: 103.841507ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:02:47.953731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.453917ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-18T03:02:47.953811Z","caller":"traceutil/trace.go:171","msg":"trace[1022200350] linearizableReadLoop","detail":"{readStateIndex:1222; appliedIndex:1221; }","duration":"182.171866ms","start":"2026-06-18T03:02:47.771623Z","end":"2026-06-18T03:02:47.953795Z","steps":["trace[1022200350] 'read index received' (duration: 38.941µs)","trace[1022200350] 'applied index is now lower than readState.Index' (duration: 182.132245ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T03:02:47.953925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.303298ms","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-06-18T03:02:47.953948Z","caller":"traceutil/trace.go:171","msg":"trace[1549858284] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1170; }","duration":"182.361708ms","start":"2026-06-18T03:02:47.771579Z","end":"2026-06-18T03:02:47.953941Z","steps":["trace[1549858284] 'agreement among raft nodes before linearized reading' (duration: 182.250687ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:02:47.954285Z","caller":"traceutil/trace.go:171","msg":"trace[379575292] transaction","detail":"{read_only:false; response_revision:1170; number_of_response:1; }","duration":"253.123511ms","start":"2026-06-18T03:02:47.701143Z","end":"2026-06-18T03:02:47.954267Z","steps":["trace[379575292] 'process raft request' (duration: 48.063328ms)","trace[379575292] 'compare' (duration: 204.186024ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T03:02:48.352Z","caller":"traceutil/trace.go:171","msg":"trace[1317751944] transaction","detail":"{read_only:false; response_revision:1174; number_of_response:1; }","duration":"162.893374ms","start":"2026-06-18T03:02:48.189084Z","end":"2026-06-18T03:02:48.351977Z","steps":["trace[1317751944] 'process raft request' (duration: 162.49865ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:03:06.34695Z","caller":"traceutil/trace.go:171","msg":"trace[2139750184] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"104.963085ms","start":"2026-06-18T03:03:06.241944Z","end":"2026-06-18T03:03:06.346907Z","steps":["trace[2139750184] 'process raft request' (duration: 95.905724ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:03:12.394565Z","caller":"traceutil/trace.go:171","msg":"trace[262033290] linearizableReadLoop","detail":"{readStateIndex:1306; appliedIndex:1305; }","duration":"126.494881ms","start":"2026-06-18T03:03:12.268042Z","end":"2026-06-18T03:03:12.394537Z","steps":["trace[262033290] 'read index received' (duration: 126.299229ms)","trace[262033290] 'applied index is now lower than readState.Index' (duration: 194.362µs)"],"step_count":2} {"level":"info","ts":"2026-06-18T03:03:12.394667Z","caller":"traceutil/trace.go:171","msg":"trace[149935767] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"136.569692ms","start":"2026-06-18T03:03:12.258064Z","end":"2026-06-18T03:03:12.394633Z","steps":["trace[149935767] 'process raft request' (duration: 136.331279ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:03:12.394876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.938346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-18T03:03:12.394926Z","caller":"traceutil/trace.go:171","msg":"trace[1296125475] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1249; }","duration":"123.038947ms","start":"2026-06-18T03:03:12.271871Z","end":"2026-06-18T03:03:12.39491Z","steps":["trace[1296125475] 'agreement among raft nodes before linearized reading' (duration: 122.798165ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:03:12.39595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.905795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:5 size:1050268"} {"level":"info","ts":"2026-06-18T03:03:12.396024Z","caller":"traceutil/trace.go:171","msg":"trace[1074795196] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:5; response_revision:1249; }","duration":"128.030806ms","start":"2026-06-18T03:03:12.267975Z","end":"2026-06-18T03:03:12.396006Z","steps":["trace[1074795196] 'agreement among raft nodes before linearized reading' (duration: 126.663153ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:03:13.644137Z","caller":"traceutil/trace.go:171","msg":"trace[1926106513] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"233.667277ms","start":"2026-06-18T03:03:13.410443Z","end":"2026-06-18T03:03:13.64411Z","steps":["trace[1926106513] 'process raft request' (duration: 233.430624ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:03:14.021831Z","caller":"traceutil/trace.go:171","msg":"trace[1273261418] linearizableReadLoop","detail":"{readStateIndex:1312; appliedIndex:1311; }","duration":"123.712711ms","start":"2026-06-18T03:03:13.898095Z","end":"2026-06-18T03:03:14.021808Z","steps":["trace[1273261418] 'read index received' (duration: 123.496069ms)","trace[1273261418] 'applied index is now lower than readState.Index' (duration: 215.752µs)"],"step_count":2} {"level":"info","ts":"2026-06-18T03:03:14.021895Z","caller":"traceutil/trace.go:171","msg":"trace[904006638] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"128.474009ms","start":"2026-06-18T03:03:13.893384Z","end":"2026-06-18T03:03:14.021858Z","steps":["trace[904006638] 'process raft request' (duration: 128.271447ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:03:14.023134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.010763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:5 size:1050268"} {"level":"info","ts":"2026-06-18T03:03:14.023249Z","caller":"traceutil/trace.go:171","msg":"trace[2100301085] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:5; response_revision:1255; }","duration":"125.141885ms","start":"2026-06-18T03:03:13.89808Z","end":"2026-06-18T03:03:14.023222Z","steps":["trace[2100301085] 'agreement among raft nodes before linearized reading' (duration: 123.850742ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:03:15.866036Z","caller":"traceutil/trace.go:171","msg":"trace[2103592850] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"179.492884ms","start":"2026-06-18T03:03:15.686518Z","end":"2026-06-18T03:03:15.866011Z","steps":["trace[2103592850] 'process raft request' (duration: 179.340902ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:03:16.612679Z","caller":"traceutil/trace.go:171","msg":"trace[674883098] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"123.510956ms","start":"2026-06-18T03:03:16.489098Z","end":"2026-06-18T03:03:16.612609Z","steps":["trace[674883098] 'process raft request' (duration: 73.609581ms)","trace[674883098] 'compare' (duration: 49.708284ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T03:03:18.004737Z","caller":"traceutil/trace.go:171","msg":"trace[1723104567] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"131.073979ms","start":"2026-06-18T03:03:17.873631Z","end":"2026-06-18T03:03:18.004705Z","steps":["trace[1723104567] 'process raft request' (duration: 130.896607ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:03:19.025381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.105777ms","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-06-18T03:03:19.025453Z","caller":"traceutil/trace.go:171","msg":"trace[379470273] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1270; }","duration":"227.221809ms","start":"2026-06-18T03:03:18.798213Z","end":"2026-06-18T03:03:19.025435Z","steps":["trace[379470273] 'range keys from in-memory index tree' (duration: 226.937686ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:03:19.025472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.869477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T03:03:19.025579Z","caller":"traceutil/trace.go:171","msg":"trace[729172476] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1270; }","duration":"413.017988ms","start":"2026-06-18T03:03:18.612542Z","end":"2026-06-18T03:03:19.025559Z","steps":["trace[729172476] 'range keys from in-memory index tree' (duration: 412.786036ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:03:19.025619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:03:18.612524Z","time spent":"413.085909ms","remote":"127.0.0.1:37772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-18T03:03:19.025727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.085364ms","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-06-18T03:03:19.025785Z","caller":"traceutil/trace.go:171","msg":"trace[2109264207] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1270; }","duration":"365.197554ms","start":"2026-06-18T03:03:18.660572Z","end":"2026-06-18T03:03:19.025769Z","steps":["trace[2109264207] 'range keys from in-memory index tree' (duration: 364.968822ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:03:19.025856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:03:18.66056Z","time spent":"365.257136ms","remote":"127.0.0.1:38034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-18T03:03:19.02628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.006099ms","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-06-18T03:03:19.026392Z","caller":"traceutil/trace.go:171","msg":"trace[1215230805] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1270; }","duration":"410.18551ms","start":"2026-06-18T03:03:18.616176Z","end":"2026-06-18T03:03:19.026362Z","steps":["trace[1215230805] 'range keys from in-memory index tree' (duration: 409.503864ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:03:19.026513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:03:18.61616Z","time spent":"410.327491ms","remote":"127.0.0.1:38034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-18T03:03:19.997228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.353313ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-06-18T03:03:20.30432Z","caller":"traceutil/trace.go:171","msg":"trace[1383389960] linearizableReadLoop","detail":"{readStateIndex:1334; appliedIndex:1333; }","duration":"113.864034ms","start":"2026-06-18T03:03:20.190424Z","end":"2026-06-18T03:03:20.304288Z","steps":["trace[1383389960] 'read index received' (duration: 110.41265ms)","trace[1383389960] 'applied index is now lower than readState.Index' (duration: 3.449804ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T03:03:20.304357Z","caller":"traceutil/trace.go:171","msg":"trace[260665398] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"228.85427ms","start":"2026-06-18T03:03:20.075475Z","end":"2026-06-18T03:03:20.30433Z","steps":["trace[260665398] 'process raft request' (duration: 225.406486ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:03:20.304497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.061696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-18T03:03:20.30454Z","caller":"traceutil/trace.go:171","msg":"trace[1184074177] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1275; }","duration":"114.159937ms","start":"2026-06-18T03:03:20.190358Z","end":"2026-06-18T03:03:20.304518Z","steps":["trace[1184074177] 'agreement among raft nodes before linearized reading' (duration: 114.050416ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:03:20.647931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.872424ms","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-06-18T03:03:20.648001Z","caller":"traceutil/trace.go:171","msg":"trace[1929715528] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1275; }","duration":"118.988995ms","start":"2026-06-18T03:03:20.528995Z","end":"2026-06-18T03:03:20.647984Z","steps":["trace[1929715528] 'range keys from in-memory index tree' (duration: 118.652492ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:03:21.41935Z","caller":"traceutil/trace.go:171","msg":"trace[1972120015] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"291.92321ms","start":"2026-06-18T03:03:21.127404Z","end":"2026-06-18T03:03:21.419327Z","steps":["trace[1972120015] 'process raft request' (duration: 291.789789ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:03:21.443356Z","caller":"traceutil/trace.go:171","msg":"trace[1701306383] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"314.564043ms","start":"2026-06-18T03:03:21.128767Z","end":"2026-06-18T03:03:21.443331Z","steps":["trace[1701306383] 'process raft request' (duration: 314.411572ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:03:21.443503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:03:21.128745Z","time spent":"314.682624ms","remote":"127.0.0.1:38034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T03:03:34.797742Z","caller":"traceutil/trace.go:171","msg":"trace[2036744875] transaction","detail":"{read_only:false; response_revision:1354; number_of_response:1; }","duration":"677.328936ms","start":"2026-06-18T03:03:34.120392Z","end":"2026-06-18T03:03:34.797721Z","steps":["trace[2036744875] 'process raft request' (duration: 677.184615ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:03:34.798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:03:34.12038Z","time spent":"677.514778ms","remote":"127.0.0.1:38034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T03:03:34.862897Z","caller":"traceutil/trace.go:171","msg":"trace[1163048614] linearizableReadLoop","detail":"{readStateIndex:1418; appliedIndex:1416; }","duration":"148.689068ms","start":"2026-06-18T03:03:34.714184Z","end":"2026-06-18T03:03:34.862873Z","steps":["trace[1163048614] 'read index received' (duration: 83.378712ms)","trace[1163048614] 'applied index is now lower than readState.Index' (duration: 65.309226ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T03:03:34.863354Z","caller":"traceutil/trace.go:171","msg":"trace[763146636] transaction","detail":"{read_only:false; response_revision:1355; number_of_response:1; }","duration":"471.825775ms","start":"2026-06-18T03:03:34.391466Z","end":"2026-06-18T03:03:34.863292Z","steps":["trace[763146636] 'process raft request' (duration: 471.276909ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:03:34.863522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.321824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.65\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-06-18T03:03:34.863613Z","caller":"traceutil/trace.go:171","msg":"trace[182393140] range","detail":"{range_begin:/registry/masterleases/199.204.45.65; range_end:; response_count:1; response_revision:1355; }","duration":"149.450436ms","start":"2026-06-18T03:03:34.714144Z","end":"2026-06-18T03:03:34.863595Z","steps":["trace[182393140] 'agreement among raft nodes before linearized reading' (duration: 149.171894ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T03:03:34.863566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T03:03:34.391443Z","time spent":"471.998917ms","remote":"127.0.0.1:38034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T03:03:42.976103Z","caller":"traceutil/trace.go:171","msg":"trace[1061021644] transaction","detail":"{read_only:false; response_revision:1386; number_of_response:1; }","duration":"134.536864ms","start":"2026-06-18T03:03:42.841548Z","end":"2026-06-18T03:03:42.976085Z","steps":["trace[1061021644] 'process raft request' (duration: 134.428749ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:03:43.223129Z","caller":"traceutil/trace.go:171","msg":"trace[1510250158] transaction","detail":"{read_only:false; response_revision:1388; number_of_response:1; }","duration":"212.920605ms","start":"2026-06-18T03:03:43.01018Z","end":"2026-06-18T03:03:43.2231Z","steps":["trace[1510250158] 'process raft request' (duration: 197.096117ms)","trace[1510250158] 'compare' (duration: 15.366118ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T03:03:43.523205Z","caller":"traceutil/trace.go:171","msg":"trace[1306899846] transaction","detail":"{read_only:false; response_revision:1389; number_of_response:1; }","duration":"157.08665ms","start":"2026-06-18T03:03:43.366092Z","end":"2026-06-18T03:03:43.523179Z","steps":["trace[1306899846] 'process raft request' (duration: 156.949014ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T03:03:43.55017Z","caller":"traceutil/trace.go:171","msg":"trace[902092493] transaction","detail":"{read_only:false; response_revision:1390; number_of_response:1; }","duration":"120.676611ms","start":"2026-06-18T03:03:43.429472Z","end":"2026-06-18T03:03:43.550148Z","steps":["trace[902092493] 'process raft request' (duration: 110.946038ms)"],"step_count":1}