{"level":"warn","ts":"2026-04-28T06:52:55.603922Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-28T06:52:55.60409Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.156: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.156:2380","--initial-cluster=instance=https://199.204.45.156:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.156:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.156:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-28T06:52:55.604634Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-28T06:52:55.604667Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-04-28T06:52:55.60478Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-28T06:52:55.605814Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"]} {"level":"info","ts":"2026-04-28T06:52:55.605987Z","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.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.156:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-28T06:52:55.626454Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"20.136697ms"} {"level":"info","ts":"2026-04-28T06:52:55.634057Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5ac240a6daec2148","cluster-id":"62163637a46c33c2"} {"level":"info","ts":"2026-04-28T06:52:55.634266Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=()"} {"level":"info","ts":"2026-04-28T06:52:55.634301Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 0"} {"level":"info","ts":"2026-04-28T06:52:55.634389Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5ac240a6daec2148 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-28T06:52:55.634409Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 1"} {"level":"info","ts":"2026-04-28T06:52:55.634518Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"warn","ts":"2026-04-28T06:52:55.67773Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-28T06:52:55.68485Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-28T06:52:55.686559Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-28T06:52:55.691516Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5ac240a6daec2148","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-28T06:52:55.691744Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5ac240a6daec2148","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-28T06:52:55.691711Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-28T06:52:55.691901Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-28T06:52:55.691923Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-28T06:52:55.692621Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"info","ts":"2026-04-28T06:52:55.692748Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","added-peer-id":"5ac240a6daec2148","added-peer-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-04-28T06:52:55.698943Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-28T06:52:55.699142Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-04-28T06:52:55.699204Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-04-28T06:52:55.700154Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5ac240a6daec2148","initial-advertise-peer-urls":["https://199.204.45.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-28T06:52:55.700211Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-28T06:52:56.335689Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 is starting a new election at term 1"} {"level":"info","ts":"2026-04-28T06:52:56.335749Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-28T06:52:56.335798Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgPreVoteResp from 5ac240a6daec2148 at term 1"} {"level":"info","ts":"2026-04-28T06:52:56.335825Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became candidate at term 2"} {"level":"info","ts":"2026-04-28T06:52:56.335843Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgVoteResp from 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-04-28T06:52:56.335862Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became leader at term 2"} {"level":"info","ts":"2026-04-28T06:52:56.335882Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5ac240a6daec2148 elected leader 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-04-28T06:52:56.33676Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5ac240a6daec2148","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.156:2379]}","request-path":"/0/members/5ac240a6daec2148/attributes","cluster-id":"62163637a46c33c2","publish-timeout":"7s"} {"level":"info","ts":"2026-04-28T06:52:56.336808Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T06:52:56.336877Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T06:52:56.336795Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T06:52:56.33714Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-28T06:52:56.337188Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-28T06:52:56.338033Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T06:52:56.338139Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T06:52:56.338175Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T06:52:56.340827Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-28T06:52:56.348318Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.156:2379"} {"level":"info","ts":"2026-04-28T06:53:26.74178Z","caller":"traceutil/trace.go:171","msg":"trace[1627622602] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"167.446929ms","start":"2026-04-28T06:53:26.574309Z","end":"2026-04-28T06:53:26.741756Z","steps":["trace[1627622602] 'process raft request' (duration: 167.396338ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:53:26.742195Z","caller":"traceutil/trace.go:171","msg":"trace[1449607102] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"176.326156ms","start":"2026-04-28T06:53:26.565856Z","end":"2026-04-28T06:53:26.742182Z","steps":["trace[1449607102] 'process raft request' (duration: 175.762543ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:53:26.742464Z","caller":"traceutil/trace.go:171","msg":"trace[333617473] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"176.463159ms","start":"2026-04-28T06:53:26.565988Z","end":"2026-04-28T06:53:26.742451Z","steps":["trace[333617473] 'process raft request' (duration: 175.670351ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:53:26.742966Z","caller":"traceutil/trace.go:171","msg":"trace[1325922990] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"182.933891ms","start":"2026-04-28T06:53:26.560001Z","end":"2026-04-28T06:53:26.742935Z","steps":["trace[1325922990] 'process raft request' (duration: 181.488747ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:53:26.754274Z","caller":"traceutil/trace.go:171","msg":"trace[847289831] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"170.777377ms","start":"2026-04-28T06:53:26.583466Z","end":"2026-04-28T06:53:26.754243Z","steps":["trace[847289831] 'process raft request' (duration: 170.679694ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:53:26.754403Z","caller":"traceutil/trace.go:171","msg":"trace[1056158007] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"157.790854ms","start":"2026-04-28T06:53:26.596598Z","end":"2026-04-28T06:53:26.754389Z","steps":["trace[1056158007] 'process raft request' (duration: 157.61438ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:53:26.75451Z","caller":"traceutil/trace.go:171","msg":"trace[1088259057] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"176.90885ms","start":"2026-04-28T06:53:26.577595Z","end":"2026-04-28T06:53:26.754504Z","steps":["trace[1088259057] 'process raft request' (duration: 176.4717ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:53:26.754536Z","caller":"traceutil/trace.go:171","msg":"trace[175244909] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"164.638093ms","start":"2026-04-28T06:53:26.589892Z","end":"2026-04-28T06:53:26.75453Z","steps":["trace[175244909] 'process raft request' (duration: 164.283015ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:53:26.754597Z","caller":"traceutil/trace.go:171","msg":"trace[574167687] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"149.90355ms","start":"2026-04-28T06:53:26.604689Z","end":"2026-04-28T06:53:26.754592Z","steps":["trace[574167687] 'process raft request' (duration: 149.554132ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:53:26.754639Z","caller":"traceutil/trace.go:171","msg":"trace[1441388729] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"133.517757ms","start":"2026-04-28T06:53:26.621117Z","end":"2026-04-28T06:53:26.754634Z","steps":["trace[1441388729] 'process raft request' (duration: 133.159138ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:53:26.75462Z","caller":"traceutil/trace.go:171","msg":"trace[1010616677] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"119.671493ms","start":"2026-04-28T06:53:26.63494Z","end":"2026-04-28T06:53:26.754612Z","steps":["trace[1010616677] 'process raft request' (duration: 119.52659ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:53:33.245297Z","caller":"traceutil/trace.go:171","msg":"trace[535136064] linearizableReadLoop","detail":"{readStateIndex:600; appliedIndex:599; }","duration":"216.095575ms","start":"2026-04-28T06:53:33.029178Z","end":"2026-04-28T06:53:33.245274Z","steps":["trace[535136064] 'read index received' (duration: 214.221501ms)","trace[535136064] 'applied index is now lower than readState.Index' (duration: 1.873134ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:53:33.245398Z","caller":"traceutil/trace.go:171","msg":"trace[955503510] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"337.253614ms","start":"2026-04-28T06:53:32.90809Z","end":"2026-04-28T06:53:33.245344Z","steps":["trace[955503510] 'process raft request' (duration: 335.600805ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:33.245623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.394802ms","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-04-28T06:53:33.245922Z","caller":"traceutil/trace.go:171","msg":"trace[1421639427] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:579; }","duration":"216.774781ms","start":"2026-04-28T06:53:33.02912Z","end":"2026-04-28T06:53:33.245895Z","steps":["trace[1421639427] 'agreement among raft nodes before linearized reading' (duration: 216.28518ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:33.246084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.282429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-28T06:53:33.246189Z","caller":"traceutil/trace.go:171","msg":"trace[1562418630] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:579; }","duration":"126.467253ms","start":"2026-04-28T06:53:33.119698Z","end":"2026-04-28T06:53:33.246165Z","steps":["trace[1562418630] 'agreement among raft nodes before linearized reading' (duration: 125.757376ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:33.246715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:32.908063Z","time spent":"337.463158ms","remote":"127.0.0.1:58774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:53:33.37389Z","caller":"traceutil/trace.go:171","msg":"trace[1828226784] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"120.361819ms","start":"2026-04-28T06:53:33.253496Z","end":"2026-04-28T06:53:33.373857Z","steps":["trace[1828226784] 'process raft request' (duration: 33.024471ms)","trace[1828226784] 'compare' (duration: 86.863997ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:53:33.628945Z","caller":"traceutil/trace.go:171","msg":"trace[376061192] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"309.202557ms","start":"2026-04-28T06:53:33.319714Z","end":"2026-04-28T06:53:33.628916Z","steps":["trace[376061192] 'process raft request' (duration: 293.417059ms)","trace[376061192] 'compare' (duration: 15.399189ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:53:33.629155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:33.319683Z","time spent":"309.411043ms","remote":"127.0.0.1:59190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:53:34.133269Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398340330547042003,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T06:53:34.722501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.665317ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:53:34.722658Z","caller":"traceutil/trace.go:171","msg":"trace[1398470145] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"1.334601553s","start":"2026-04-28T06:53:33.388013Z","end":"2026-04-28T06:53:34.722615Z","steps":["trace[1398470145] 'process raft request' (duration: 914.535137ms)","trace[1398470145] 'compare' (duration: 419.568264ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:53:34.722863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:33.387969Z","time spent":"1.334784787s","remote":"127.0.0.1:58852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:53:34.722757Z","caller":"traceutil/trace.go:171","msg":"trace[619198019] linearizableReadLoop","detail":"{readStateIndex:604; appliedIndex:603; }","duration":"1.089537903s","start":"2026-04-28T06:53:33.633186Z","end":"2026-04-28T06:53:34.722724Z","steps":["trace[619198019] 'read index received' (duration: 669.372204ms)","trace[619198019] 'applied index is now lower than readState.Index' (duration: 420.118098ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:53:35.22167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.04336ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-28T06:53:35.221817Z","caller":"traceutil/trace.go:171","msg":"trace[2035994862] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:604; }","duration":"498.788725ms","start":"2026-04-28T06:53:34.723004Z","end":"2026-04-28T06:53:35.221793Z","steps":["trace[2035994862] 'read index received' (duration: 92.182µs)","trace[2035994862] 'applied index is now lower than readState.Index' (duration: 498.690592ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:53:35.221852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.588626324s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T06:53:35.22192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.580278529s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:53:35.221958Z","caller":"traceutil/trace.go:171","msg":"trace[549965795] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:583; }","duration":"1.580413002s","start":"2026-04-28T06:53:33.641532Z","end":"2026-04-28T06:53:35.221945Z","steps":["trace[549965795] 'agreement among raft nodes before linearized reading' (duration: 1.58032293s)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:53:35.22199Z","caller":"traceutil/trace.go:171","msg":"trace[1832151077] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:583; }","duration":"1.588804668s","start":"2026-04-28T06:53:33.633133Z","end":"2026-04-28T06:53:35.221937Z","steps":["trace[1832151077] 'agreement among raft nodes before linearized reading' (duration: 1.089678816s)","trace[1832151077] 'range keys from in-memory index tree' (duration: 498.910697ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:53:35.222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:33.641505Z","time spent":"1.580485554s","remote":"127.0.0.1:58916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-04-28T06:53:35.222048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:33.63311Z","time spent":"1.588927211s","remote":"127.0.0.1:58678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T06:53:35.222055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"906.903501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:53:35.222124Z","caller":"traceutil/trace.go:171","msg":"trace[14715413] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:583; }","duration":"907.014934ms","start":"2026-04-28T06:53:34.315092Z","end":"2026-04-28T06:53:35.222107Z","steps":["trace[14715413] 'agreement among raft nodes before linearized reading' (duration: 906.900391ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:35.222162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:34.315071Z","time spent":"907.083155ms","remote":"127.0.0.1:58664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T06:53:35.22227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.443582059s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-28T06:53:35.222378Z","caller":"traceutil/trace.go:171","msg":"trace[1905979468] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:583; }","duration":"1.443698651s","start":"2026-04-28T06:53:33.778646Z","end":"2026-04-28T06:53:35.222344Z","steps":["trace[1905979468] 'agreement among raft nodes before linearized reading' (duration: 1.443365274s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:35.222426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:33.77858Z","time spent":"1.443836875s","remote":"127.0.0.1:58934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-28T06:53:35.222528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.490765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-28T06:53:35.222579Z","caller":"traceutil/trace.go:171","msg":"trace[597095559] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:583; }","duration":"602.571587ms","start":"2026-04-28T06:53:34.619993Z","end":"2026-04-28T06:53:35.222565Z","steps":["trace[597095559] 'agreement among raft nodes before linearized reading' (duration: 602.383102ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:35.222612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:34.619972Z","time spent":"602.632128ms","remote":"127.0.0.1:58934","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-04-28T06:53:36.654433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.048347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:53:36.65452Z","caller":"traceutil/trace.go:171","msg":"trace[896094740] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:605; }","duration":"142.170109ms","start":"2026-04-28T06:53:36.512329Z","end":"2026-04-28T06:53:36.654499Z","steps":["trace[896094740] 'range keys from in-memory index tree' (duration: 142.007485ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:36.654571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.702887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T06:53:36.65453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.400738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T06:53:36.654693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.519223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:53:36.654724Z","caller":"traceutil/trace.go:171","msg":"trace[1033318554] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:605; }","duration":"120.565305ms","start":"2026-04-28T06:53:36.534149Z","end":"2026-04-28T06:53:36.654714Z","steps":["trace[1033318554] 'count revisions from in-memory index tree' (duration: 120.484082ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:53:36.65473Z","caller":"traceutil/trace.go:171","msg":"trace[1163874080] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:605; }","duration":"185.639454ms","start":"2026-04-28T06:53:36.46906Z","end":"2026-04-28T06:53:36.654699Z","steps":["trace[1163874080] 'range keys from in-memory index tree' (duration: 185.355727ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:36.654756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.584922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:53:36.654938Z","caller":"traceutil/trace.go:171","msg":"trace[1732312868] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:605; }","duration":"186.011442ms","start":"2026-04-28T06:53:36.468869Z","end":"2026-04-28T06:53:36.65488Z","steps":["trace[1732312868] 'count revisions from in-memory index tree' (duration: 185.49486ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:36.655095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.123218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T06:53:36.655136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.552924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:53:36.655191Z","caller":"traceutil/trace.go:171","msg":"trace[1699818569] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:605; }","duration":"120.657317ms","start":"2026-04-28T06:53:36.534518Z","end":"2026-04-28T06:53:36.655175Z","steps":["trace[1699818569] 'range keys from in-memory index tree' (duration: 120.459622ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:36.65522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.909879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:53:36.655277Z","caller":"traceutil/trace.go:171","msg":"trace[1189386485] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:605; }","duration":"102.187116ms","start":"2026-04-28T06:53:36.553078Z","end":"2026-04-28T06:53:36.655265Z","steps":["trace[1189386485] 'count revisions from in-memory index tree' (duration: 101.775956ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:53:36.655332Z","caller":"traceutil/trace.go:171","msg":"trace[287005834] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:0; response_revision:605; }","duration":"169.378294ms","start":"2026-04-28T06:53:36.485793Z","end":"2026-04-28T06:53:36.655171Z","steps":["trace[287005834] 'range keys from in-memory index tree' (duration: 169.048217ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:53:36.654644Z","caller":"traceutil/trace.go:171","msg":"trace[2069109499] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:605; }","duration":"141.77058ms","start":"2026-04-28T06:53:36.512846Z","end":"2026-04-28T06:53:36.654616Z","steps":["trace[2069109499] 'range keys from in-memory index tree' (duration: 141.654556ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:36.654483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.112468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:53:36.655537Z","caller":"traceutil/trace.go:171","msg":"trace[625628489] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:605; }","duration":"143.249454ms","start":"2026-04-28T06:53:36.512276Z","end":"2026-04-28T06:53:36.655526Z","steps":["trace[625628489] 'count revisions from in-memory index tree' (duration: 141.967154ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:36.655555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.143069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:53:36.655615Z","caller":"traceutil/trace.go:171","msg":"trace[1159101809] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:605; }","duration":"187.338994ms","start":"2026-04-28T06:53:36.468258Z","end":"2026-04-28T06:53:36.655597Z","steps":["trace[1159101809] 'range keys from in-memory index tree' (duration: 186.34801ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:36.655758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.566474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:53:36.655802Z","caller":"traceutil/trace.go:171","msg":"trace[894444402] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:605; }","duration":"102.622206ms","start":"2026-04-28T06:53:36.553168Z","end":"2026-04-28T06:53:36.655791Z","steps":["trace[894444402] 'range keys from in-memory index tree' (duration: 102.538424ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:36.655258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.509057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T06:53:36.656047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.162789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:53:36.656085Z","caller":"traceutil/trace.go:171","msg":"trace[739133094] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:605; }","duration":"187.220111ms","start":"2026-04-28T06:53:36.468855Z","end":"2026-04-28T06:53:36.656075Z","steps":["trace[739133094] 'range keys from in-memory index tree' (duration: 187.090718ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:53:36.656044Z","caller":"traceutil/trace.go:171","msg":"trace[334120037] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:605; }","duration":"164.319566ms","start":"2026-04-28T06:53:36.491705Z","end":"2026-04-28T06:53:36.656024Z","steps":["trace[334120037] 'range keys from in-memory index tree' (duration: 163.407934ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:36.656354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.751399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T06:53:36.656356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.948041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:53:36.656443Z","caller":"traceutil/trace.go:171","msg":"trace[1061381338] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:605; }","duration":"102.876932ms","start":"2026-04-28T06:53:36.553555Z","end":"2026-04-28T06:53:36.656432Z","steps":["trace[1061381338] 'range keys from in-memory index tree' (duration: 102.664997ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:53:36.656453Z","caller":"traceutil/trace.go:171","msg":"trace[693351438] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:605; }","duration":"165.061103ms","start":"2026-04-28T06:53:36.491378Z","end":"2026-04-28T06:53:36.656439Z","steps":["trace[693351438] 'range keys from in-memory index tree' (duration: 164.88631ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:36.656484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.93041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:53:36.657655Z","caller":"traceutil/trace.go:171","msg":"trace[1328133104] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:605; }","duration":"166.088537ms","start":"2026-04-28T06:53:36.491524Z","end":"2026-04-28T06:53:36.657612Z","steps":["trace[1328133104] 'count revisions from in-memory index tree' (duration: 164.876469ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:49.877927Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398340330547042383,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T06:53:50.412112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.577387ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:53:50.412217Z","caller":"traceutil/trace.go:171","msg":"trace[578337873] linearizableReadLoop","detail":"{readStateIndex:771; appliedIndex:770; }","duration":"1.034661291s","start":"2026-04-28T06:53:49.37754Z","end":"2026-04-28T06:53:50.412201Z","steps":["trace[578337873] 'read index received' (duration: 565.854049ms)","trace[578337873] 'applied index is now lower than readState.Index' (duration: 468.806092ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:53:50.412244Z","caller":"traceutil/trace.go:171","msg":"trace[1969723968] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"1.045404853s","start":"2026-04-28T06:53:49.366815Z","end":"2026-04-28T06:53:50.41222Z","steps":["trace[1969723968] 'process raft request' (duration: 576.633281ms)","trace[1969723968] 'compare' (duration: 468.459405ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:53:50.412402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:49.36679Z","time spent":"1.045514895s","remote":"127.0.0.1:58934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:53:50.412418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.034881436s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-nngsg\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-04-28T06:53:50.412451Z","caller":"traceutil/trace.go:171","msg":"trace[629166490] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-nngsg; range_end:; response_count:1; response_revision:745; }","duration":"1.034953928s","start":"2026-04-28T06:53:49.377486Z","end":"2026-04-28T06:53:50.41244Z","steps":["trace[629166490] 'agreement among raft nodes before linearized reading' (duration: 1.034796784s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:50.412488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:49.377461Z","time spent":"1.03501882s","remote":"127.0.0.1:58852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-nngsg\" "} {"level":"warn","ts":"2026-04-28T06:53:50.412737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"968.395124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-28T06:53:50.412822Z","caller":"traceutil/trace.go:171","msg":"trace[1658348075] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:745; }","duration":"968.523918ms","start":"2026-04-28T06:53:49.44428Z","end":"2026-04-28T06:53:50.412804Z","steps":["trace[1658348075] 'agreement among raft nodes before linearized reading' (duration: 968.244511ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:50.412865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:49.44426Z","time spent":"968.59612ms","remote":"127.0.0.1:58934","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-04-28T06:54:06.621834Z","caller":"traceutil/trace.go:171","msg":"trace[43455] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"134.024468ms","start":"2026-04-28T06:54:06.487786Z","end":"2026-04-28T06:54:06.62181Z","steps":["trace[43455] 'process raft request' (duration: 133.900635ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:54:21.110751Z","caller":"traceutil/trace.go:171","msg":"trace[1826274775] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"141.765767ms","start":"2026-04-28T06:54:20.968961Z","end":"2026-04-28T06:54:21.110727Z","steps":["trace[1826274775] 'process raft request' (duration: 141.619564ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:25.396623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.578209ms","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-04-28T06:54:25.396677Z","caller":"traceutil/trace.go:171","msg":"trace[1658882553] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:961; }","duration":"272.66784ms","start":"2026-04-28T06:54:25.123997Z","end":"2026-04-28T06:54:25.396664Z","steps":["trace[1658882553] 'range keys from in-memory index tree' (duration: 272.451685ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:54:25.987428Z","caller":"traceutil/trace.go:171","msg":"trace[479251090] linearizableReadLoop","detail":"{readStateIndex:996; appliedIndex:995; }","duration":"471.83686ms","start":"2026-04-28T06:54:25.515564Z","end":"2026-04-28T06:54:25.987401Z","steps":["trace[479251090] 'read index received' (duration: 471.700217ms)","trace[479251090] 'applied index is now lower than readState.Index' (duration: 135.603µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:54:25.987472Z","caller":"traceutil/trace.go:171","msg":"trace[392497027] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"587.013275ms","start":"2026-04-28T06:54:25.400426Z","end":"2026-04-28T06:54:25.987439Z","steps":["trace[392497027] 'process raft request' (duration: 586.81432ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:25.987639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:54:25.400415Z","time spent":"587.127948ms","remote":"127.0.0.1:58934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:54:25.987695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.110506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-28T06:54:25.987732Z","caller":"traceutil/trace.go:171","msg":"trace[1733804284] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:962; }","duration":"472.207679ms","start":"2026-04-28T06:54:25.515515Z","end":"2026-04-28T06:54:25.987722Z","steps":["trace[1733804284] 'agreement among raft nodes before linearized reading' (duration: 472.015754ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:25.987776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:54:25.515494Z","time spent":"472.273941ms","remote":"127.0.0.1:58934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-28T06:54:26.025293Z","caller":"traceutil/trace.go:171","msg":"trace[259878500] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"289.915023ms","start":"2026-04-28T06:54:25.735349Z","end":"2026-04-28T06:54:26.025264Z","steps":["trace[259878500] 'process raft request' (duration: 283.347349ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:26.025337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.863992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-28T06:54:26.025394Z","caller":"traceutil/trace.go:171","msg":"trace[596232571] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:963; }","duration":"395.963345ms","start":"2026-04-28T06:54:25.629419Z","end":"2026-04-28T06:54:26.025382Z","steps":["trace[596232571] 'agreement among raft nodes before linearized reading' (duration: 395.783881ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:26.025423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:54:25.629403Z","time spent":"396.014197ms","remote":"127.0.0.1:58934","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-04-28T06:54:32.166683Z","caller":"traceutil/trace.go:171","msg":"trace[74654808] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"112.681046ms","start":"2026-04-28T06:54:32.053969Z","end":"2026-04-28T06:54:32.16665Z","steps":["trace[74654808] 'process raft request' (duration: 112.329188ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:54:38.18484Z","caller":"traceutil/trace.go:171","msg":"trace[130641688] linearizableReadLoop","detail":"{readStateIndex:1038; appliedIndex:1036; }","duration":"100.375829ms","start":"2026-04-28T06:54:38.084434Z","end":"2026-04-28T06:54:38.184809Z","steps":["trace[130641688] 'read index received' (duration: 24.76849ms)","trace[130641688] 'applied index is now lower than readState.Index' (duration: 75.605719ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:54:38.184915Z","caller":"traceutil/trace.go:171","msg":"trace[954322733] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"118.912333ms","start":"2026-04-28T06:54:38.065974Z","end":"2026-04-28T06:54:38.184886Z","steps":["trace[954322733] 'process raft request' (duration: 117.920769ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:38.185115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.647035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-28T06:54:38.185161Z","caller":"traceutil/trace.go:171","msg":"trace[1665514693] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1002; }","duration":"100.752458ms","start":"2026-04-28T06:54:38.084397Z","end":"2026-04-28T06:54:38.18515Z","steps":["trace[1665514693] 'agreement among raft nodes before linearized reading' (duration: 100.545533ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:54:44.382425Z","caller":"traceutil/trace.go:171","msg":"trace[99750451] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"116.157208ms","start":"2026-04-28T06:54:44.266249Z","end":"2026-04-28T06:54:44.382406Z","steps":["trace[99750451] 'process raft request' (duration: 95.180317ms)","trace[99750451] 'compare' (duration: 20.835858ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:54:44.432732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.876022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:54:44.432812Z","caller":"traceutil/trace.go:171","msg":"trace[388959727] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1024; }","duration":"119.005825ms","start":"2026-04-28T06:54:44.313789Z","end":"2026-04-28T06:54:44.432795Z","steps":["trace[388959727] 'agreement among raft nodes before linearized reading' (duration: 118.873692ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:44.432838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.247282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-28T06:54:44.432886Z","caller":"traceutil/trace.go:171","msg":"trace[1941152058] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1024; }","duration":"146.308393ms","start":"2026-04-28T06:54:44.286558Z","end":"2026-04-28T06:54:44.432866Z","steps":["trace[1941152058] 'agreement among raft nodes before linearized reading' (duration: 95.956095ms)","trace[1941152058] 'range keys from in-memory index tree' (duration: 50.120132ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:54:44.432735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.461178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:54:44.433003Z","caller":"traceutil/trace.go:171","msg":"trace[1006747558] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1024; }","duration":"105.740894ms","start":"2026-04-28T06:54:44.327224Z","end":"2026-04-28T06:54:44.432965Z","steps":["trace[1006747558] 'agreement among raft nodes before linearized reading' (duration: 105.448497ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:54:48.654537Z","caller":"traceutil/trace.go:171","msg":"trace[721386458] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"497.183803ms","start":"2026-04-28T06:54:48.157332Z","end":"2026-04-28T06:54:48.654516Z","steps":["trace[721386458] 'process raft request' (duration: 497.03362ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:48.654687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:54:48.157305Z","time spent":"497.297266ms","remote":"127.0.0.1:58934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:54:48.891935Z","caller":"traceutil/trace.go:171","msg":"trace[895704011] linearizableReadLoop","detail":"{readStateIndex:1073; appliedIndex:1071; }","duration":"578.065396ms","start":"2026-04-28T06:54:48.313848Z","end":"2026-04-28T06:54:48.891913Z","steps":["trace[895704011] 'read index received' (duration: 340.514128ms)","trace[895704011] 'applied index is now lower than readState.Index' (duration: 237.550488ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:54:48.892343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.485306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T06:54:48.892428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.671979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-28T06:54:48.892485Z","caller":"traceutil/trace.go:171","msg":"trace[38408208] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1035; }","duration":"457.772971ms","start":"2026-04-28T06:54:48.434698Z","end":"2026-04-28T06:54:48.892471Z","steps":["trace[38408208] 'agreement among raft nodes before linearized reading' (duration: 457.564816ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:54:48.892445Z","caller":"traceutil/trace.go:171","msg":"trace[799820685] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1035; }","duration":"578.620639ms","start":"2026-04-28T06:54:48.313809Z","end":"2026-04-28T06:54:48.89243Z","steps":["trace[799820685] 'agreement among raft nodes before linearized reading' (duration: 578.482156ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:48.892568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:54:48.313795Z","time spent":"578.763852ms","remote":"127.0.0.1:58678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-28T06:54:48.892322Z","caller":"traceutil/trace.go:171","msg":"trace[485906627] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"652.279763ms","start":"2026-04-28T06:54:48.240015Z","end":"2026-04-28T06:54:48.892295Z","steps":["trace[485906627] 'process raft request' (duration: 578.516847ms)","trace[485906627] 'compare' (duration: 73.264254ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:54:48.89273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:54:48.239992Z","time spent":"652.675832ms","remote":"127.0.0.1:58934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:54:48.892755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.050628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:54:48.892848Z","caller":"traceutil/trace.go:171","msg":"trace[1153211444] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1035; }","duration":"211.57805ms","start":"2026-04-28T06:54:48.681251Z","end":"2026-04-28T06:54:48.892829Z","steps":["trace[1153211444] 'agreement among raft nodes before linearized reading' (duration: 211.048438ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:48.892829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.815356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-28T06:54:48.892967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:54:48.434679Z","time spent":"457.834073ms","remote":"127.0.0.1:58934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-28T06:54:48.892987Z","caller":"traceutil/trace.go:171","msg":"trace[1026181217] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1035; }","duration":"408.97454ms","start":"2026-04-28T06:54:48.483947Z","end":"2026-04-28T06:54:48.892921Z","steps":["trace[1026181217] 'agreement among raft nodes before linearized reading' (duration: 408.365185ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:48.894286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:54:48.483931Z","time spent":"410.340151ms","remote":"127.0.0.1:58934","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-04-28T06:54:49.124607Z","caller":"traceutil/trace.go:171","msg":"trace[1330122612] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"227.056453ms","start":"2026-04-28T06:54:48.897526Z","end":"2026-04-28T06:54:49.124583Z","steps":["trace[1330122612] 'process raft request' (duration: 226.976061ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:54:49.12463Z","caller":"traceutil/trace.go:171","msg":"trace[1216991205] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"228.187969ms","start":"2026-04-28T06:54:48.896407Z","end":"2026-04-28T06:54:49.124594Z","steps":["trace[1216991205] 'process raft request' (duration: 145.451323ms)","trace[1216991205] 'compare' (duration: 82.48909ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:54:49.124886Z","caller":"traceutil/trace.go:171","msg":"trace[475048835] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"227.030512ms","start":"2026-04-28T06:54:48.897841Z","end":"2026-04-28T06:54:49.124872Z","steps":["trace[475048835] 'process raft request' (duration: 226.706334ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:54:49.124746Z","caller":"traceutil/trace.go:171","msg":"trace[1902428067] linearizableReadLoop","detail":"{readStateIndex:1076; appliedIndex:1073; }","duration":"192.145176ms","start":"2026-04-28T06:54:48.932582Z","end":"2026-04-28T06:54:49.124727Z","steps":["trace[1902428067] 'read index received' (duration: 109.291228ms)","trace[1902428067] 'applied index is now lower than readState.Index' (duration: 82.852158ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:54:49.124964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.3135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:54:49.12509Z","caller":"traceutil/trace.go:171","msg":"trace[449019576] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1038; }","duration":"192.530845ms","start":"2026-04-28T06:54:48.932548Z","end":"2026-04-28T06:54:49.125079Z","steps":["trace[449019576] 'agreement among raft nodes before linearized reading' (duration: 192.234239ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:49.125312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.87265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-28T06:54:49.125385Z","caller":"traceutil/trace.go:171","msg":"trace[254416732] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1038; }","duration":"150.992632ms","start":"2026-04-28T06:54:48.97438Z","end":"2026-04-28T06:54:49.125373Z","steps":["trace[254416732] 'agreement among raft nodes before linearized reading' (duration: 150.686065ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:05.005738Z","caller":"traceutil/trace.go:171","msg":"trace[601355700] transaction","detail":"{read_only:false; response_revision:1139; number_of_response:1; }","duration":"106.87203ms","start":"2026-04-28T06:55:04.898845Z","end":"2026-04-28T06:55:05.005717Z","steps":["trace[601355700] 'process raft request' (duration: 106.750908ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:05.34627Z","caller":"traceutil/trace.go:171","msg":"trace[1816928903] transaction","detail":"{read_only:false; response_revision:1140; number_of_response:1; }","duration":"226.53861ms","start":"2026-04-28T06:55:05.119709Z","end":"2026-04-28T06:55:05.346248Z","steps":["trace[1816928903] 'process raft request' (duration: 226.074909ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:17.209933Z","caller":"traceutil/trace.go:171","msg":"trace[182842413] transaction","detail":"{read_only:false; response_revision:1178; number_of_response:1; }","duration":"148.975733ms","start":"2026-04-28T06:55:17.06094Z","end":"2026-04-28T06:55:17.209916Z","steps":["trace[182842413] 'process raft request' (duration: 148.87337ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:25.029873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.476416ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-28T06:55:25.030646Z","caller":"traceutil/trace.go:171","msg":"trace[592688611] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"382.901364ms","start":"2026-04-28T06:55:24.647711Z","end":"2026-04-28T06:55:25.030612Z","steps":["trace[592688611] 'process raft request' (duration: 382.262139ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:25.030777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:24.647688Z","time spent":"383.009776ms","remote":"127.0.0.1:58934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:55:25.59301Z","caller":"traceutil/trace.go:171","msg":"trace[887799504] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"133.333242ms","start":"2026-04-28T06:55:25.459652Z","end":"2026-04-28T06:55:25.592985Z","steps":["trace[887799504] 'process raft request' (duration: 133.278041ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:25.593042Z","caller":"traceutil/trace.go:171","msg":"trace[1227845809] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"134.292404ms","start":"2026-04-28T06:55:25.458704Z","end":"2026-04-28T06:55:25.592996Z","steps":["trace[1227845809] 'process raft request' (duration: 101.878667ms)","trace[1227845809] 'compare' (duration: 32.165822ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:55:25.593237Z","caller":"traceutil/trace.go:171","msg":"trace[65878345] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"134.139171ms","start":"2026-04-28T06:55:25.459083Z","end":"2026-04-28T06:55:25.593222Z","steps":["trace[65878345] 'process raft request' (duration: 133.805884ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:26.217776Z","caller":"traceutil/trace.go:171","msg":"trace[1249835436] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"214.190016ms","start":"2026-04-28T06:55:26.003558Z","end":"2026-04-28T06:55:26.217748Z","steps":["trace[1249835436] 'process raft request' (duration: 214.015122ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:27.428811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.050868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:55:27.428925Z","caller":"traceutil/trace.go:171","msg":"trace[355958222] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1239; }","duration":"114.213451ms","start":"2026-04-28T06:55:27.314673Z","end":"2026-04-28T06:55:27.428886Z","steps":["trace[355958222] 'range keys from in-memory index tree' (duration: 113.969906ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:27.971129Z","caller":"traceutil/trace.go:171","msg":"trace[1579831701] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"538.894577ms","start":"2026-04-28T06:55:27.432199Z","end":"2026-04-28T06:55:27.971093Z","steps":["trace[1579831701] 'process raft request' (duration: 538.506738ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:27.971503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:27.432186Z","time spent":"539.099602ms","remote":"127.0.0.1:58934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:55:28.005158Z","caller":"traceutil/trace.go:171","msg":"trace[2027844689] linearizableReadLoop","detail":"{readStateIndex:1289; appliedIndex:1287; }","duration":"517.73931ms","start":"2026-04-28T06:55:27.487388Z","end":"2026-04-28T06:55:28.005127Z","steps":["trace[2027844689] 'read index received' (duration: 483.43869ms)","trace[2027844689] 'applied index is now lower than readState.Index' (duration: 34.29932ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:55:28.005608Z","caller":"traceutil/trace.go:171","msg":"trace[1898841062] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"545.662603ms","start":"2026-04-28T06:55:27.459869Z","end":"2026-04-28T06:55:28.005532Z","steps":["trace[1898841062] 'process raft request' (duration: 544.964957ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:28.006084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:27.459842Z","time spent":"545.873838ms","remote":"127.0.0.1:58934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:55:28.00663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.241694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-28T06:55:28.00668Z","caller":"traceutil/trace.go:171","msg":"trace[1730222259] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1241; }","duration":"519.329867ms","start":"2026-04-28T06:55:27.487338Z","end":"2026-04-28T06:55:28.006668Z","steps":["trace[1730222259] 'agreement among raft nodes before linearized reading' (duration: 519.157943ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:28.006711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:27.487319Z","time spent":"519.384377ms","remote":"127.0.0.1:58934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-28T06:55:28.019369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.383802ms","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-04-28T06:55:28.01943Z","caller":"traceutil/trace.go:171","msg":"trace[1053073806] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1242; }","duration":"517.488254ms","start":"2026-04-28T06:55:27.501926Z","end":"2026-04-28T06:55:28.019414Z","steps":["trace[1053073806] 'agreement among raft nodes before linearized reading' (duration: 517.335391ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:28.019421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.583081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-28T06:55:28.019428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.790499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-28T06:55:28.019462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:27.501912Z","time spent":"517.543385ms","remote":"127.0.0.1:59054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":29,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"info","ts":"2026-04-28T06:55:28.019473Z","caller":"traceutil/trace.go:171","msg":"trace[1122531588] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1242; }","duration":"420.663684ms","start":"2026-04-28T06:55:27.598798Z","end":"2026-04-28T06:55:28.019462Z","steps":["trace[1122531588] 'agreement among raft nodes before linearized reading' (duration: 420.483139ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:28.019485Z","caller":"traceutil/trace.go:171","msg":"trace[889186680] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1242; }","duration":"421.862761ms","start":"2026-04-28T06:55:27.597609Z","end":"2026-04-28T06:55:28.019472Z","steps":["trace[889186680] 'agreement among raft nodes before linearized reading' (duration: 421.711397ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:28.019502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:27.598789Z","time spent":"420.707354ms","remote":"127.0.0.1:58934","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-04-28T06:55:28.019522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:27.597603Z","time spent":"421.911132ms","remote":"127.0.0.1:58934","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-04-28T06:55:28.019664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.395403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-28T06:55:28.01969Z","caller":"traceutil/trace.go:171","msg":"trace[1469287751] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1242; }","duration":"422.460665ms","start":"2026-04-28T06:55:27.59722Z","end":"2026-04-28T06:55:28.019681Z","steps":["trace[1469287751] 'agreement among raft nodes before linearized reading' (duration: 422.379103ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:28.019728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:27.597196Z","time spent":"422.524716ms","remote":"127.0.0.1:58934","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":"info","ts":"2026-04-28T06:55:28.124393Z","caller":"traceutil/trace.go:171","msg":"trace[2084972173] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"113.749711ms","start":"2026-04-28T06:55:28.01059Z","end":"2026-04-28T06:55:28.12434Z","steps":["trace[2084972173] 'process raft request' (duration: 53.156435ms)","trace[2084972173] 'compare' (duration: 60.485263ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:55:28.401099Z","caller":"traceutil/trace.go:171","msg":"trace[1789590013] linearizableReadLoop","detail":"{readStateIndex:1294; appliedIndex:1291; }","duration":"337.110148ms","start":"2026-04-28T06:55:28.063965Z","end":"2026-04-28T06:55:28.401075Z","steps":["trace[1789590013] 'read index received' (duration: 336.884213ms)","trace[1789590013] 'applied index is now lower than readState.Index' (duration: 224.545µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:55:28.401155Z","caller":"traceutil/trace.go:171","msg":"trace[368270034] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"376.131617ms","start":"2026-04-28T06:55:28.024993Z","end":"2026-04-28T06:55:28.401124Z","steps":["trace[368270034] 'process raft request' (duration: 376.044855ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:28.401209Z","caller":"traceutil/trace.go:171","msg":"trace[1109913813] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"378.308257ms","start":"2026-04-28T06:55:28.022872Z","end":"2026-04-28T06:55:28.40118Z","steps":["trace[1109913813] 'process raft request' (duration: 377.958079ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:28.401324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:28.024975Z","time spent":"376.25904ms","remote":"127.0.0.1:58934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:55:28.401386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:28.022854Z","time spent":"378.43672ms","remote":"127.0.0.1:58934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:55:28.401682Z","caller":"traceutil/trace.go:171","msg":"trace[143765645] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"378.090992ms","start":"2026-04-28T06:55:28.023011Z","end":"2026-04-28T06:55:28.401102Z","steps":["trace[143765645] 'process raft request' (duration: 377.98299ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:28.401817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.820744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:55:28.401873Z","caller":"traceutil/trace.go:171","msg":"trace[1973117321] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1246; }","duration":"337.937047ms","start":"2026-04-28T06:55:28.063921Z","end":"2026-04-28T06:55:28.401858Z","steps":["trace[1973117321] 'agreement among raft nodes before linearized reading' (duration: 337.274211ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:28.401918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:28.063896Z","time spent":"338.012809ms","remote":"127.0.0.1:58934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-28T06:55:28.401927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:28.023004Z","time spent":"378.842979ms","remote":"127.0.0.1:58934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:55:28.500483Z","caller":"traceutil/trace.go:171","msg":"trace[1194543827] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"213.182083ms","start":"2026-04-28T06:55:28.287282Z","end":"2026-04-28T06:55:28.500464Z","steps":["trace[1194543827] 'process raft request' (duration: 212.474886ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:28.500536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.546896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:55:28.500601Z","caller":"traceutil/trace.go:171","msg":"trace[324359436] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1247; }","duration":"185.652607ms","start":"2026-04-28T06:55:28.314933Z","end":"2026-04-28T06:55:28.500586Z","steps":["trace[324359436] 'agreement among raft nodes before linearized reading' (duration: 185.510644ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:33.740721Z","caller":"traceutil/trace.go:171","msg":"trace[1992686244] linearizableReadLoop","detail":"{readStateIndex:1343; appliedIndex:1342; }","duration":"110.355743ms","start":"2026-04-28T06:55:33.630346Z","end":"2026-04-28T06:55:33.740701Z","steps":["trace[1992686244] 'read index received' (duration: 110.248421ms)","trace[1992686244] 'applied index is now lower than readState.Index' (duration: 106.522µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:55:33.740832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.465396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:55:33.740835Z","caller":"traceutil/trace.go:171","msg":"trace[675931864] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"219.860226ms","start":"2026-04-28T06:55:33.520951Z","end":"2026-04-28T06:55:33.740811Z","steps":["trace[675931864] 'process raft request' (duration: 219.633961ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:33.740881Z","caller":"traceutil/trace.go:171","msg":"trace[2136382557] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1294; }","duration":"110.558447ms","start":"2026-04-28T06:55:33.630312Z","end":"2026-04-28T06:55:33.740871Z","steps":["trace[2136382557] 'agreement among raft nodes before linearized reading' (duration: 110.474085ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:34.578269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.407863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-28T06:55:34.57834Z","caller":"traceutil/trace.go:171","msg":"trace[1929722296] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1294; }","duration":"583.491325ms","start":"2026-04-28T06:55:33.994833Z","end":"2026-04-28T06:55:34.578325Z","steps":["trace[1929722296] 'range keys from in-memory index tree' (duration: 583.233749ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:34.578423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:33.994774Z","time spent":"583.636988ms","remote":"127.0.0.1:58934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-28T06:55:34.645423Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398340330547043594,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T06:55:35.14595Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398340330547043594,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T06:55:35.620724Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.600246763s","expected-duration":"1s"} {"level":"info","ts":"2026-04-28T06:55:35.621362Z","caller":"traceutil/trace.go:171","msg":"trace[1602034403] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"1.601008211s","start":"2026-04-28T06:55:34.020328Z","end":"2026-04-28T06:55:35.621336Z","steps":["trace[1602034403] 'process raft request' (duration: 1.600762215s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:35.621685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:34.020301Z","time spent":"1.601142834s","remote":"127.0.0.1:58934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:55:35.717798Z","caller":"traceutil/trace.go:171","msg":"trace[587256039] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"1.134628205s","start":"2026-04-28T06:55:34.583152Z","end":"2026-04-28T06:55:35.71778Z","steps":["trace[587256039] 'process raft request' (duration: 1.134517692s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:35.717916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:34.583135Z","time spent":"1.134716026s","remote":"127.0.0.1:58934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:55:35.718456Z","caller":"traceutil/trace.go:171","msg":"trace[1781737320] linearizableReadLoop","detail":"{readStateIndex:1346; appliedIndex:1343; }","duration":"1.573236521s","start":"2026-04-28T06:55:34.145116Z","end":"2026-04-28T06:55:35.718353Z","steps":["trace[1781737320] 'read index received' (duration: 1.47596176s)","trace[1781737320] 'applied index is now lower than readState.Index' (duration: 97.273811ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:55:35.718609Z","caller":"traceutil/trace.go:171","msg":"trace[441706138] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"272.95696ms","start":"2026-04-28T06:55:35.445432Z","end":"2026-04-28T06:55:35.718388Z","steps":["trace[441706138] 'process raft request' (duration: 272.754165ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:35.718674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.573526957s","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-04-28T06:55:35.718727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.404370789s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:55:35.718766Z","caller":"traceutil/trace.go:171","msg":"trace[30919803] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1298; }","duration":"1.404448121s","start":"2026-04-28T06:55:34.314309Z","end":"2026-04-28T06:55:35.718757Z","steps":["trace[30919803] 'agreement among raft nodes before linearized reading' (duration: 1.40438956s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:35.718786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:34.314286Z","time spent":"1.404495973s","remote":"127.0.0.1:58664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-28T06:55:35.718809Z","caller":"traceutil/trace.go:171","msg":"trace[1571227008] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1298; }","duration":"1.57365132s","start":"2026-04-28T06:55:34.145071Z","end":"2026-04-28T06:55:35.718722Z","steps":["trace[1571227008] 'agreement among raft nodes before linearized reading' (duration: 1.573428595s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:35.718891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:34.145049Z","time spent":"1.573828315s","remote":"127.0.0.1:58934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-28T06:55:35.718952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.282104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-04-28T06:55:35.718973Z","caller":"traceutil/trace.go:171","msg":"trace[912730578] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1298; }","duration":"469.334304ms","start":"2026-04-28T06:55:35.249631Z","end":"2026-04-28T06:55:35.718965Z","steps":["trace[912730578] 'agreement among raft nodes before linearized reading' (duration: 469.253772ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:35.718992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:35.249613Z","time spent":"469.375486ms","remote":"127.0.0.1:58934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":552,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-04-28T06:55:35.719034Z","caller":"traceutil/trace.go:171","msg":"trace[2075609504] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"397.98299ms","start":"2026-04-28T06:55:35.320427Z","end":"2026-04-28T06:55:35.71841Z","steps":["trace[2075609504] 'process raft request' (duration: 397.321675ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:35.719131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"905.378312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-04-28T06:55:35.719156Z","caller":"traceutil/trace.go:171","msg":"trace[920738837] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1298; }","duration":"905.427863ms","start":"2026-04-28T06:55:34.813721Z","end":"2026-04-28T06:55:35.719149Z","steps":["trace[920738837] 'agreement among raft nodes before linearized reading' (duration: 905.373632ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:35.719173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:34.813705Z","time spent":"905.463824ms","remote":"127.0.0.1:58934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-28T06:55:35.719164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:35.320351Z","time spent":"398.755948ms","remote":"127.0.0.1:58934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:55:35.719248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.21387835s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-28T06:55:35.719082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"786.82905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:55:35.719268Z","caller":"traceutil/trace.go:171","msg":"trace[1227880810] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1298; }","duration":"1.213931442s","start":"2026-04-28T06:55:34.505332Z","end":"2026-04-28T06:55:35.719263Z","steps":["trace[1227880810] 'agreement among raft nodes before linearized reading' (duration: 1.213883711s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:35.719284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:34.505313Z","time spent":"1.213967342s","remote":"127.0.0.1:58934","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-04-28T06:55:35.719288Z","caller":"traceutil/trace.go:171","msg":"trace[785959901] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1298; }","duration":"787.058205ms","start":"2026-04-28T06:55:34.932217Z","end":"2026-04-28T06:55:35.719276Z","steps":["trace[785959901] 'agreement among raft nodes before linearized reading' (duration: 786.83906ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:35.719337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:34.932203Z","time spent":"787.119676ms","remote":"127.0.0.1:52950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-28T06:55:35.719387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.215218142s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-28T06:55:35.719408Z","caller":"traceutil/trace.go:171","msg":"trace[383414274] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1298; }","duration":"1.215280003s","start":"2026-04-28T06:55:34.504123Z","end":"2026-04-28T06:55:35.719403Z","steps":["trace[383414274] 'agreement among raft nodes before linearized reading' (duration: 1.215236192s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:35.719424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:34.5041Z","time spent":"1.215320494s","remote":"127.0.0.1:58934","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-04-28T06:55:35.71935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.21429716s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-28T06:55:35.719528Z","caller":"traceutil/trace.go:171","msg":"trace[1984049891] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1298; }","duration":"1.214464044s","start":"2026-04-28T06:55:34.505026Z","end":"2026-04-28T06:55:35.71949Z","steps":["trace[1984049891] 'agreement among raft nodes before linearized reading' (duration: 1.214293s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:35.719606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:34.505015Z","time spent":"1.214552146s","remote":"127.0.0.1:58934","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":"info","ts":"2026-04-28T06:56:36.23267Z","caller":"traceutil/trace.go:171","msg":"trace[735728894] transaction","detail":"{read_only:false; response_revision:1577; number_of_response:1; }","duration":"123.600186ms","start":"2026-04-28T06:56:36.109048Z","end":"2026-04-28T06:56:36.232648Z","steps":["trace[735728894] 'process raft request' (duration: 123.465083ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:58:50.767015Z","caller":"traceutil/trace.go:171","msg":"trace[911953773] transaction","detail":"{read_only:false; response_revision:2222; number_of_response:1; }","duration":"272.960169ms","start":"2026-04-28T06:58:50.494031Z","end":"2026-04-28T06:58:50.766991Z","steps":["trace[911953773] 'process raft request' (duration: 272.543159ms)"],"step_count":1}