{"level":"warn","ts":"2026-04-23T07:45:07.506058Z","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-23T07:45:07.506188Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.43:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.43:2380","--initial-cluster=instance=https://199.19.213.43:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.43:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.43: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-23T07:45:07.506293Z","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-23T07:45:07.506316Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.43:2380"]} {"level":"info","ts":"2026-04-23T07:45:07.506352Z","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-23T07:45:07.507521Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.43:2379"]} {"level":"info","ts":"2026-04-23T07:45:07.50764Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.43:2380"],"listen-peer-urls":["https://199.19.213.43:2380"],"advertise-client-urls":["https://199.19.213.43:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.43:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.43: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-23T07:45:07.512169Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.223864ms"} {"level":"info","ts":"2026-04-23T07:45:07.518843Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a8d0274548ffaee4","cluster-id":"858b677d1d9175b1"} {"level":"info","ts":"2026-04-23T07:45:07.518946Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a8d0274548ffaee4 switched to configuration voters=()"} {"level":"info","ts":"2026-04-23T07:45:07.518968Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a8d0274548ffaee4 became follower at term 0"} {"level":"info","ts":"2026-04-23T07:45:07.51899Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a8d0274548ffaee4 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-23T07:45:07.519001Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a8d0274548ffaee4 became follower at term 1"} {"level":"info","ts":"2026-04-23T07:45:07.519046Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a8d0274548ffaee4 switched to configuration voters=(12164265772058652388)"} {"level":"warn","ts":"2026-04-23T07:45:07.525469Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-23T07:45:07.527131Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-23T07:45:07.528189Z","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-23T07:45:07.53054Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a8d0274548ffaee4","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-23T07:45:07.530666Z","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-23T07:45:07.530708Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a8d0274548ffaee4","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-23T07:45:07.530803Z","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-23T07:45:07.531093Z","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-23T07:45:07.533292Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a8d0274548ffaee4 switched to configuration voters=(12164265772058652388)"} {"level":"info","ts":"2026-04-23T07:45:07.533877Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"858b677d1d9175b1","local-member-id":"a8d0274548ffaee4","added-peer-id":"a8d0274548ffaee4","added-peer-peer-urls":["https://199.19.213.43:2380"]} {"level":"info","ts":"2026-04-23T07:45:07.53592Z","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-23T07:45:07.536209Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a8d0274548ffaee4","initial-advertise-peer-urls":["https://199.19.213.43:2380"],"listen-peer-urls":["https://199.19.213.43:2380"],"advertise-client-urls":["https://199.19.213.43:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.43:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-23T07:45:07.53626Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-23T07:45:07.536328Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.43:2380"} {"level":"info","ts":"2026-04-23T07:45:07.536406Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.43:2380"} {"level":"info","ts":"2026-04-23T07:45:08.419523Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a8d0274548ffaee4 is starting a new election at term 1"} {"level":"info","ts":"2026-04-23T07:45:08.419605Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a8d0274548ffaee4 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-23T07:45:08.419631Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a8d0274548ffaee4 received MsgPreVoteResp from a8d0274548ffaee4 at term 1"} {"level":"info","ts":"2026-04-23T07:45:08.419653Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a8d0274548ffaee4 became candidate at term 2"} {"level":"info","ts":"2026-04-23T07:45:08.419664Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a8d0274548ffaee4 received MsgVoteResp from a8d0274548ffaee4 at term 2"} {"level":"info","ts":"2026-04-23T07:45:08.419682Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a8d0274548ffaee4 became leader at term 2"} {"level":"info","ts":"2026-04-23T07:45:08.419697Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a8d0274548ffaee4 elected leader a8d0274548ffaee4 at term 2"} {"level":"info","ts":"2026-04-23T07:45:08.420944Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a8d0274548ffaee4","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.43:2379]}","request-path":"/0/members/a8d0274548ffaee4/attributes","cluster-id":"858b677d1d9175b1","publish-timeout":"7s"} {"level":"info","ts":"2026-04-23T07:45:08.420965Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T07:45:08.421177Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-23T07:45:08.421337Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-23T07:45:08.421858Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-23T07:45:08.421892Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-23T07:45:08.422053Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"858b677d1d9175b1","local-member-id":"a8d0274548ffaee4","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T07:45:08.422272Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T07:45:08.422315Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T07:45:08.425293Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-23T07:45:08.425538Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.43:2379"} {"level":"info","ts":"2026-04-23T07:46:04.118489Z","caller":"traceutil/trace.go:171","msg":"trace[442370805] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"315.606992ms","start":"2026-04-23T07:46:03.802854Z","end":"2026-04-23T07:46:04.118461Z","steps":["trace[442370805] 'process raft request' (duration: 315.474999ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:04.118582Z","caller":"traceutil/trace.go:171","msg":"trace[367875067] linearizableReadLoop","detail":"{readStateIndex:585; appliedIndex:585; }","duration":"315.601472ms","start":"2026-04-23T07:46:03.802932Z","end":"2026-04-23T07:46:04.118534Z","steps":["trace[367875067] 'read index received' (duration: 315.589182ms)","trace[367875067] 'applied index is now lower than readState.Index' (duration: 9.52µs)"],"step_count":2} {"level":"warn","ts":"2026-04-23T07:46:04.118773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.811707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T07:46:04.118831Z","caller":"traceutil/trace.go:171","msg":"trace[480223949] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:564; }","duration":"315.93219ms","start":"2026-04-23T07:46:03.802889Z","end":"2026-04-23T07:46:04.118821Z","steps":["trace[480223949] 'agreement among raft nodes before linearized reading' (duration: 315.786586ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:04.118893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:03.802866Z","time spent":"316.018491ms","remote":"127.0.0.1:58924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T07:46:04.11948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:03.802839Z","time spent":"315.771406ms","remote":"127.0.0.1:59216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T07:46:04.200815Z","caller":"traceutil/trace.go:171","msg":"trace[661171625] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"273.761212ms","start":"2026-04-23T07:46:03.927025Z","end":"2026-04-23T07:46:04.200786Z","steps":["trace[661171625] 'process raft request' (duration: 273.620239ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:04.200752Z","caller":"traceutil/trace.go:171","msg":"trace[2101095864] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"274.817095ms","start":"2026-04-23T07:46:03.9259Z","end":"2026-04-23T07:46:04.200717Z","steps":["trace[2101095864] 'process raft request' (duration: 274.537749ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:04.200976Z","caller":"traceutil/trace.go:171","msg":"trace[443482582] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"260.248024ms","start":"2026-04-23T07:46:03.940718Z","end":"2026-04-23T07:46:04.200966Z","steps":["trace[443482582] 'process raft request' (duration: 260.027989ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:04.201058Z","caller":"traceutil/trace.go:171","msg":"trace[680780606] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"257.823171ms","start":"2026-04-23T07:46:03.943226Z","end":"2026-04-23T07:46:04.201049Z","steps":["trace[680780606] 'process raft request' (duration: 257.616386ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:04.201706Z","caller":"traceutil/trace.go:171","msg":"trace[838099479] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"248.969816ms","start":"2026-04-23T07:46:03.95217Z","end":"2026-04-23T07:46:04.20114Z","steps":["trace[838099479] 'process raft request' (duration: 248.825523ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:04.201765Z","caller":"traceutil/trace.go:171","msg":"trace[120940765] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"239.061668ms","start":"2026-04-23T07:46:03.962415Z","end":"2026-04-23T07:46:04.201477Z","steps":["trace[120940765] 'process raft request' (duration: 238.651049ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:04.201113Z","caller":"traceutil/trace.go:171","msg":"trace[21136341] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"256.196535ms","start":"2026-04-23T07:46:03.944888Z","end":"2026-04-23T07:46:04.201084Z","steps":["trace[21136341] 'process raft request' (duration: 256.038561ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:04.202199Z","caller":"traceutil/trace.go:171","msg":"trace[895016542] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"271.723597ms","start":"2026-04-23T07:46:03.929191Z","end":"2026-04-23T07:46:04.200915Z","steps":["trace[895016542] 'process raft request' (duration: 271.509222ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:04.205549Z","caller":"traceutil/trace.go:171","msg":"trace[1589995110] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"213.084907ms","start":"2026-04-23T07:46:03.992443Z","end":"2026-04-23T07:46:04.205528Z","steps":["trace[1589995110] 'process raft request' (duration: 210.191943ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:04.205551Z","caller":"traceutil/trace.go:171","msg":"trace[1867977112] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"237.540985ms","start":"2026-04-23T07:46:03.967986Z","end":"2026-04-23T07:46:04.205527Z","steps":["trace[1867977112] 'process raft request' (duration: 233.964186ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:04.20568Z","caller":"traceutil/trace.go:171","msg":"trace[881458649] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"208.262801ms","start":"2026-04-23T07:46:03.997357Z","end":"2026-04-23T07:46:04.205619Z","steps":["trace[881458649] 'process raft request' (duration: 205.700654ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:04.205978Z","caller":"traceutil/trace.go:171","msg":"trace[908162698] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"106.657576ms","start":"2026-04-23T07:46:04.099289Z","end":"2026-04-23T07:46:04.205947Z","steps":["trace[908162698] 'process raft request' (duration: 106.424221ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:04.206216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.312536ms","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-23T07:46:04.212976Z","caller":"traceutil/trace.go:171","msg":"trace[1418285690] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:576; }","duration":"272.083444ms","start":"2026-04-23T07:46:03.940863Z","end":"2026-04-23T07:46:04.212946Z","steps":["trace[1418285690] 'agreement among raft nodes before linearized reading' (duration: 265.142372ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:10.909379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.584639ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-23T07:46:10.909571Z","caller":"traceutil/trace.go:171","msg":"trace[683153472] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"404.951238ms","start":"2026-04-23T07:46:10.504574Z","end":"2026-04-23T07:46:10.909526Z","steps":["trace[683153472] 'process raft request' (duration: 250.89566ms)","trace[683153472] 'compare' (duration: 153.368883ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T07:46:10.909695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:10.504547Z","time spent":"405.079191ms","remote":"127.0.0.1:59010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":688,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T07:46:11.296713Z","caller":"traceutil/trace.go:171","msg":"trace[310422987] linearizableReadLoop","detail":"{readStateIndex:702; appliedIndex:701; }","duration":"493.577985ms","start":"2026-04-23T07:46:10.80311Z","end":"2026-04-23T07:46:11.296688Z","steps":["trace[310422987] 'read index received' (duration: 492.8774ms)","trace[310422987] 'applied index is now lower than readState.Index' (duration: 699.365µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:46:11.296807Z","caller":"traceutil/trace.go:171","msg":"trace[746502114] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"736.089651ms","start":"2026-04-23T07:46:10.560643Z","end":"2026-04-23T07:46:11.296732Z","steps":["trace[746502114] 'process raft request' (duration: 735.455598ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:11.296989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:10.560617Z","time spent":"736.279135ms","remote":"127.0.0.1:59034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T07:46:11.297072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.897733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T07:46:11.297203Z","caller":"traceutil/trace.go:171","msg":"trace[1401823967] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:674; }","duration":"494.138358ms","start":"2026-04-23T07:46:10.803041Z","end":"2026-04-23T07:46:11.297179Z","steps":["trace[1401823967] 'agreement among raft nodes before linearized reading' (duration: 493.76168ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:11.297271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:10.803009Z","time spent":"494.25186ms","remote":"127.0.0.1:58918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-23T07:46:11.423115Z","caller":"traceutil/trace.go:171","msg":"trace[1660464867] linearizableReadLoop","detail":"{readStateIndex:703; appliedIndex:702; }","duration":"126.249457ms","start":"2026-04-23T07:46:11.296823Z","end":"2026-04-23T07:46:11.423073Z","steps":["trace[1660464867] 'read index received' (duration: 120.326847ms)","trace[1660464867] 'applied index is now lower than readState.Index' (duration: 5.92179ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T07:46:11.423241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.024338ms","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-23T07:46:11.423325Z","caller":"traceutil/trace.go:171","msg":"trace[2008204072] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:675; }","duration":"515.15882ms","start":"2026-04-23T07:46:10.908148Z","end":"2026-04-23T07:46:11.423307Z","steps":["trace[2008204072] 'agreement among raft nodes before linearized reading' (duration: 514.932495ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:11.42334Z","caller":"traceutil/trace.go:171","msg":"trace[700151109] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"238.143648ms","start":"2026-04-23T07:46:11.185169Z","end":"2026-04-23T07:46:11.423312Z","steps":["trace[700151109] 'process raft request' (duration: 231.969823ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:11.42337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:10.908124Z","time spent":"515.237413ms","remote":"127.0.0.1:59216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-23T07:46:11.423395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.958185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-mwfsh\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-23T07:46:11.423435Z","caller":"traceutil/trace.go:171","msg":"trace[466859429] range","detail":"{range_begin:/registry/pods/kube-system/cilium-mwfsh; range_end:; response_count:1; response_revision:675; }","duration":"299.058767ms","start":"2026-04-23T07:46:11.124364Z","end":"2026-04-23T07:46:11.423423Z","steps":["trace[466859429] 'agreement among raft nodes before linearized reading' (duration: 298.859783ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:11.423515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.120946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-23T07:46:11.423637Z","caller":"traceutil/trace.go:171","msg":"trace[1307034700] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:675; }","duration":"368.28349ms","start":"2026-04-23T07:46:11.055331Z","end":"2026-04-23T07:46:11.423614Z","steps":["trace[1307034700] 'agreement among raft nodes before linearized reading' (duration: 368.013084ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:11.423815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:11.055262Z","time spent":"368.535506ms","remote":"127.0.0.1:59280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":67,"response size":31,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-04-23T07:46:12.490003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.594213ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T07:46:12.490126Z","caller":"traceutil/trace.go:171","msg":"trace[409144894] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:677; }","duration":"446.725686ms","start":"2026-04-23T07:46:12.04338Z","end":"2026-04-23T07:46:12.490106Z","steps":["trace[409144894] 'range keys from in-memory index tree' (duration: 446.579313ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:12.490379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.884194ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-04-23T07:46:12.490576Z","caller":"traceutil/trace.go:171","msg":"trace[708035476] linearizableReadLoop","detail":"{readStateIndex:706; appliedIndex:704; }","duration":"1.04933956s","start":"2026-04-23T07:46:11.441223Z","end":"2026-04-23T07:46:12.490563Z","steps":["trace[708035476] 'read index received' (duration: 4.611401ms)","trace[708035476] 'applied index is now lower than readState.Index' (duration: 1.044727459s)"],"step_count":2} {"level":"warn","ts":"2026-04-23T07:46:12.490666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:11.43663Z","time spent":"1.054032864s","remote":"127.0.0.1:58954","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-23T07:46:12.491008Z","caller":"traceutil/trace.go:171","msg":"trace[1515301074] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"1.048780208s","start":"2026-04-23T07:46:11.442194Z","end":"2026-04-23T07:46:12.490974Z","steps":["trace[1515301074] 'process raft request' (duration: 1.048248446s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:12.491076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.049856591s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-23T07:46:12.491103Z","caller":"traceutil/trace.go:171","msg":"trace[411051533] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:678; }","duration":"1.049927794s","start":"2026-04-23T07:46:11.441167Z","end":"2026-04-23T07:46:12.491095Z","steps":["trace[411051533] 'agreement among raft nodes before linearized reading' (duration: 1.049879952s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:12.491138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:11.441144Z","time spent":"1.049988945s","remote":"127.0.0.1:59158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":217,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-04-23T07:46:12.491151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:11.44216Z","time spent":"1.048918221s","remote":"127.0.0.1:59144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T07:46:12.491696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"688.065515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T07:46:12.491961Z","caller":"traceutil/trace.go:171","msg":"trace[1912859720] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:678; }","duration":"688.31518ms","start":"2026-04-23T07:46:11.803583Z","end":"2026-04-23T07:46:12.491898Z","steps":["trace[1912859720] 'agreement among raft nodes before linearized reading' (duration: 688.023234ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:12.492211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:11.803519Z","time spent":"688.633617ms","remote":"127.0.0.1:58924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T07:46:12.491704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.273549ms","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-23T07:46:12.492694Z","caller":"traceutil/trace.go:171","msg":"trace[904950976] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:678; }","duration":"167.301961ms","start":"2026-04-23T07:46:12.325376Z","end":"2026-04-23T07:46:12.492678Z","steps":["trace[904950976] 'agreement among raft nodes before linearized reading' (duration: 166.218677ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:12.491782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.060541ms","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-23T07:46:12.492948Z","caller":"traceutil/trace.go:171","msg":"trace[1744373924] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:678; }","duration":"256.222307ms","start":"2026-04-23T07:46:12.236713Z","end":"2026-04-23T07:46:12.492936Z","steps":["trace[1744373924] 'agreement among raft nodes before linearized reading' (duration: 255.023151ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:12.747112Z","caller":"traceutil/trace.go:171","msg":"trace[2130868233] linearizableReadLoop","detail":"{readStateIndex:708; appliedIndex:707; }","duration":"252.646508ms","start":"2026-04-23T07:46:12.494431Z","end":"2026-04-23T07:46:12.747078Z","steps":["trace[2130868233] 'read index received' (duration: 197.588876ms)","trace[2130868233] 'applied index is now lower than readState.Index' (duration: 55.056462ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:46:12.747216Z","caller":"traceutil/trace.go:171","msg":"trace[635909451] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"254.447917ms","start":"2026-04-23T07:46:12.492757Z","end":"2026-04-23T07:46:12.747205Z","steps":["trace[635909451] 'process raft request' (duration: 199.247573ms)","trace[635909451] 'compare' (duration: 54.973099ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T07:46:12.747364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.911223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T07:46:12.747463Z","caller":"traceutil/trace.go:171","msg":"trace[591414060] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:679; }","duration":"253.038056ms","start":"2026-04-23T07:46:12.494394Z","end":"2026-04-23T07:46:12.747432Z","steps":["trace[591414060] 'agreement among raft nodes before linearized reading' (duration: 252.859052ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:12.75975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.581019ms","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-23T07:46:12.759819Z","caller":"traceutil/trace.go:171","msg":"trace[227997203] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:681; }","duration":"259.664202ms","start":"2026-04-23T07:46:12.500141Z","end":"2026-04-23T07:46:12.759805Z","steps":["trace[227997203] 'agreement among raft nodes before linearized reading' (duration: 259.485158ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:12.759831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.59398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-23T07:46:12.759864Z","caller":"traceutil/trace.go:171","msg":"trace[1083590427] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:681; }","duration":"264.654932ms","start":"2026-04-23T07:46:12.495199Z","end":"2026-04-23T07:46:12.759854Z","steps":["trace[1083590427] 'agreement among raft nodes before linearized reading' (duration: 264.601461ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:12.760048Z","caller":"traceutil/trace.go:171","msg":"trace[396176104] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"262.604217ms","start":"2026-04-23T07:46:12.497436Z","end":"2026-04-23T07:46:12.760041Z","steps":["trace[396176104] 'process raft request' (duration: 262.127796ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:12.75995Z","caller":"traceutil/trace.go:171","msg":"trace[1105245935] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"262.592936ms","start":"2026-04-23T07:46:12.497341Z","end":"2026-04-23T07:46:12.759934Z","steps":["trace[1105245935] 'process raft request' (duration: 262.122436ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:13.266379Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12602371076471506128,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-23T07:46:13.764499Z","caller":"traceutil/trace.go:171","msg":"trace[226124378] linearizableReadLoop","detail":"{readStateIndex:711; appliedIndex:710; }","duration":"998.333576ms","start":"2026-04-23T07:46:12.766138Z","end":"2026-04-23T07:46:13.764471Z","steps":["trace[226124378] 'read index received' (duration: 965.959465ms)","trace[226124378] 'applied index is now lower than readState.Index' (duration: 32.372701ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:46:13.764817Z","caller":"traceutil/trace.go:171","msg":"trace[245106926] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"999.561403ms","start":"2026-04-23T07:46:12.765241Z","end":"2026-04-23T07:46:13.764803Z","steps":["trace[245106926] 'process raft request' (duration: 966.862145ms)","trace[245106926] 'compare' (duration: 31.82886ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T07:46:13.764999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:12.765221Z","time spent":"999.634636ms","remote":"127.0.0.1:59216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T07:46:13.765348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.099762ms","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-23T07:46:13.7655Z","caller":"traceutil/trace.go:171","msg":"trace[311247143] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:682; }","duration":"316.323008ms","start":"2026-04-23T07:46:13.449138Z","end":"2026-04-23T07:46:13.765461Z","steps":["trace[311247143] 'agreement among raft nodes before linearized reading' (duration: 315.861348ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:13.765557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:13.449118Z","time spent":"316.427961ms","remote":"127.0.0.1:59216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-23T07:46:13.76559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"999.44902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/envoy-gateway-system/\" range_end:\"/registry/limitranges/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T07:46:13.765648Z","caller":"traceutil/trace.go:171","msg":"trace[36150939] range","detail":"{range_begin:/registry/limitranges/envoy-gateway-system/; range_end:/registry/limitranges/envoy-gateway-system0; response_count:0; response_revision:682; }","duration":"999.548292ms","start":"2026-04-23T07:46:12.766079Z","end":"2026-04-23T07:46:13.765627Z","steps":["trace[36150939] 'agreement among raft nodes before linearized reading' (duration: 999.467471ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:13.7657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:12.766053Z","time spent":"999.634845ms","remote":"127.0.0.1:59080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/limitranges/envoy-gateway-system/\" range_end:\"/registry/limitranges/envoy-gateway-system0\" "} {"level":"warn","ts":"2026-04-23T07:46:14.304676Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12602371076471506131,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-23T07:46:14.47241Z","caller":"traceutil/trace.go:171","msg":"trace[234512547] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"703.506445ms","start":"2026-04-23T07:46:13.768874Z","end":"2026-04-23T07:46:14.472381Z","steps":["trace[234512547] 'process raft request' (duration: 703.359342ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:14.472574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:13.768843Z","time spent":"703.657639ms","remote":"127.0.0.1:59144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T07:46:14.481963Z","caller":"traceutil/trace.go:171","msg":"trace[930266762] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"710.180622ms","start":"2026-04-23T07:46:13.771763Z","end":"2026-04-23T07:46:14.481944Z","steps":["trace[930266762] 'process raft request' (duration: 710.033809ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:14.482081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:13.771735Z","time spent":"710.281824ms","remote":"127.0.0.1:59216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T07:46:14.482061Z","caller":"traceutil/trace.go:171","msg":"trace[1370420444] linearizableReadLoop","detail":"{readStateIndex:714; appliedIndex:711; }","duration":"677.82261ms","start":"2026-04-23T07:46:13.804218Z","end":"2026-04-23T07:46:14.482041Z","steps":["trace[1370420444] 'read index received' (duration: 667.999875ms)","trace[1370420444] 'applied index is now lower than readState.Index' (duration: 9.821335ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:46:14.482077Z","caller":"traceutil/trace.go:171","msg":"trace[289134813] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"449.57579ms","start":"2026-04-23T07:46:14.032483Z","end":"2026-04-23T07:46:14.482059Z","steps":["trace[289134813] 'process raft request' (duration: 449.420927ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:14.482181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:14.03246Z","time spent":"449.665282ms","remote":"127.0.0.1:59130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4538,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T07:46:14.482176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"677.959004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T07:46:14.482237Z","caller":"traceutil/trace.go:171","msg":"trace[829864170] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:685; }","duration":"678.076026ms","start":"2026-04-23T07:46:13.804147Z","end":"2026-04-23T07:46:14.482223Z","steps":["trace[829864170] 'agreement among raft nodes before linearized reading' (duration: 677.978954ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:14.482263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:13.804121Z","time spent":"678.137417ms","remote":"127.0.0.1:58924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T07:46:14.490074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.630557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T07:46:14.490154Z","caller":"traceutil/trace.go:171","msg":"trace[1068987759] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:689; }","duration":"507.753439ms","start":"2026-04-23T07:46:13.982386Z","end":"2026-04-23T07:46:14.490139Z","steps":["trace[1068987759] 'agreement among raft nodes before linearized reading' (duration: 507.635807ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:14.490195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:13.982354Z","time spent":"507.829791ms","remote":"127.0.0.1:59236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":29,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"info","ts":"2026-04-23T07:46:16.364569Z","caller":"traceutil/trace.go:171","msg":"trace[743389117] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"121.445991ms","start":"2026-04-23T07:46:16.243102Z","end":"2026-04-23T07:46:16.364548Z","steps":["trace[743389117] 'process raft request' (duration: 121.327918ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:16.73757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.677801ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-23T07:46:16.738314Z","caller":"traceutil/trace.go:171","msg":"trace[1024818322] linearizableReadLoop","detail":"{readStateIndex:758; appliedIndex:756; }","duration":"220.569582ms","start":"2026-04-23T07:46:16.517707Z","end":"2026-04-23T07:46:16.738276Z","steps":["trace[1024818322] 'read index received' (duration: 116.194625ms)","trace[1024818322] 'applied index is now lower than readState.Index' (duration: 104.373497ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:46:16.738678Z","caller":"traceutil/trace.go:171","msg":"trace[1260196648] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"237.197308ms","start":"2026-04-23T07:46:16.501455Z","end":"2026-04-23T07:46:16.738652Z","steps":["trace[1260196648] 'process raft request' (duration: 236.246176ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:16.738764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.988391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T07:46:16.738843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.992041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T07:46:16.738903Z","caller":"traceutil/trace.go:171","msg":"trace[1606222337] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:728; }","duration":"221.102373ms","start":"2026-04-23T07:46:16.517783Z","end":"2026-04-23T07:46:16.738885Z","steps":["trace[1606222337] 'agreement among raft nodes before linearized reading' (duration: 220.935439ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:16.739082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.188818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T07:46:16.739177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.473644ms","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-23T07:46:16.739243Z","caller":"traceutil/trace.go:171","msg":"trace[1658769628] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:728; }","duration":"102.568716ms","start":"2026-04-23T07:46:16.636658Z","end":"2026-04-23T07:46:16.739227Z","steps":["trace[1658769628] 'agreement among raft nodes before linearized reading' (duration: 102.470634ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:16.739386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.823843ms","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-23T07:46:16.73945Z","caller":"traceutil/trace.go:171","msg":"trace[2010360273] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:728; }","duration":"128.902596ms","start":"2026-04-23T07:46:16.610531Z","end":"2026-04-23T07:46:16.739434Z","steps":["trace[2010360273] 'agreement among raft nodes before linearized reading' (duration: 128.818434ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:16.739632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.285284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T07:46:16.739697Z","caller":"traceutil/trace.go:171","msg":"trace[980362874] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:728; }","duration":"164.355705ms","start":"2026-04-23T07:46:16.575327Z","end":"2026-04-23T07:46:16.739682Z","steps":["trace[980362874] 'agreement among raft nodes before linearized reading' (duration: 164.256463ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:16.740317Z","caller":"traceutil/trace.go:171","msg":"trace[971079112] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:728; }","duration":"221.185765ms","start":"2026-04-23T07:46:16.517675Z","end":"2026-04-23T07:46:16.73886Z","steps":["trace[971079112] 'agreement among raft nodes before linearized reading' (duration: 220.901179ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:16.740664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.921953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T07:46:16.740825Z","caller":"traceutil/trace.go:171","msg":"trace[1473270693] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:728; }","duration":"103.026255ms","start":"2026-04-23T07:46:16.637724Z","end":"2026-04-23T07:46:16.740751Z","steps":["trace[1473270693] 'agreement among raft nodes before linearized reading' (duration: 102.884822ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:16.739246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.809633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T07:46:16.741559Z","caller":"traceutil/trace.go:171","msg":"trace[437043367] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:728; }","duration":"166.150305ms","start":"2026-04-23T07:46:16.575362Z","end":"2026-04-23T07:46:16.741512Z","steps":["trace[437043367] 'agreement among raft nodes before linearized reading' (duration: 163.830484ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:16.739714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.910791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T07:46:16.741877Z","caller":"traceutil/trace.go:171","msg":"trace[1485233477] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:728; }","duration":"164.092549ms","start":"2026-04-23T07:46:16.577763Z","end":"2026-04-23T07:46:16.741856Z","steps":["trace[1485233477] 'agreement among raft nodes before linearized reading' (duration: 161.905861ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:16.739319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.729352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T07:46:16.742043Z","caller":"traceutil/trace.go:171","msg":"trace[369834778] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:728; }","duration":"131.501013ms","start":"2026-04-23T07:46:16.610526Z","end":"2026-04-23T07:46:16.742027Z","steps":["trace[369834778] 'agreement among raft nodes before linearized reading' (duration: 128.754313ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:16.739324Z","caller":"traceutil/trace.go:171","msg":"trace[797415557] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:728; }","duration":"102.390503ms","start":"2026-04-23T07:46:16.636811Z","end":"2026-04-23T07:46:16.739201Z","steps":["trace[797415557] 'agreement among raft nodes before linearized reading' (duration: 102.020685ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:16.740973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.227876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T07:46:16.743892Z","caller":"traceutil/trace.go:171","msg":"trace[714704314] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:728; }","duration":"224.235512ms","start":"2026-04-23T07:46:16.519632Z","end":"2026-04-23T07:46:16.743867Z","steps":["trace[714704314] 'agreement among raft nodes before linearized reading' (duration: 221.243606ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:20.869292Z","caller":"traceutil/trace.go:171","msg":"trace[2048541481] linearizableReadLoop","detail":"{readStateIndex:802; appliedIndex:801; }","duration":"138.346782ms","start":"2026-04-23T07:46:20.730924Z","end":"2026-04-23T07:46:20.869271Z","steps":["trace[2048541481] 'read index received' (duration: 138.139728ms)","trace[2048541481] 'applied index is now lower than readState.Index' (duration: 206.064µs)"],"step_count":2} {"level":"warn","ts":"2026-04-23T07:46:20.869457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.360483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T07:46:20.869492Z","caller":"traceutil/trace.go:171","msg":"trace[1017822219] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:772; }","duration":"138.467965ms","start":"2026-04-23T07:46:20.731014Z","end":"2026-04-23T07:46:20.869482Z","steps":["trace[1017822219] 'agreement among raft nodes before linearized reading' (duration: 138.379163ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:20.869649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.452303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T07:46:20.869747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.656717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T07:46:20.869777Z","caller":"traceutil/trace.go:171","msg":"trace[1183920844] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:772; }","duration":"107.725459ms","start":"2026-04-23T07:46:20.762044Z","end":"2026-04-23T07:46:20.869769Z","steps":["trace[1183920844] 'agreement among raft nodes before linearized reading' (duration: 107.676058ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:20.869778Z","caller":"traceutil/trace.go:171","msg":"trace[112817178] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:772; }","duration":"107.618827ms","start":"2026-04-23T07:46:20.762127Z","end":"2026-04-23T07:46:20.869746Z","steps":["trace[112817178] 'agreement among raft nodes before linearized reading' (duration: 107.350821ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:20.86935Z","caller":"traceutil/trace.go:171","msg":"trace[4322447] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"149.110599ms","start":"2026-04-23T07:46:20.720214Z","end":"2026-04-23T07:46:20.869325Z","steps":["trace[4322447] 'process raft request' (duration: 148.917015ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:20.870012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.606108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T07:46:20.870372Z","caller":"traceutil/trace.go:171","msg":"trace[1620548077] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:772; }","duration":"139.143151ms","start":"2026-04-23T07:46:20.731219Z","end":"2026-04-23T07:46:20.870362Z","steps":["trace[1620548077] 'agreement among raft nodes before linearized reading' (duration: 138.551247ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:20.870129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.944939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T07:46:20.870524Z","caller":"traceutil/trace.go:171","msg":"trace[1739646466] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:772; }","duration":"105.361788ms","start":"2026-04-23T07:46:20.765132Z","end":"2026-04-23T07:46:20.870494Z","steps":["trace[1739646466] 'agreement among raft nodes before linearized reading' (duration: 104.957359ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:20.870729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.781285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T07:46:20.87079Z","caller":"traceutil/trace.go:171","msg":"trace[105048198] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:772; }","duration":"139.892167ms","start":"2026-04-23T07:46:20.730879Z","end":"2026-04-23T07:46:20.870771Z","steps":["trace[105048198] 'agreement among raft nodes before linearized reading' (duration: 138.485235ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:21.029439Z","caller":"traceutil/trace.go:171","msg":"trace[583748239] linearizableReadLoop","detail":"{readStateIndex:805; appliedIndex:803; }","duration":"116.235706ms","start":"2026-04-23T07:46:20.913182Z","end":"2026-04-23T07:46:21.029417Z","steps":["trace[583748239] 'read index received' (duration: 113.702961ms)","trace[583748239] 'applied index is now lower than readState.Index' (duration: 2.532005ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T07:46:21.029589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.39271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-23T07:46:21.029622Z","caller":"traceutil/trace.go:171","msg":"trace[386407215] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:775; }","duration":"116.487372ms","start":"2026-04-23T07:46:20.913126Z","end":"2026-04-23T07:46:21.029613Z","steps":["trace[386407215] 'agreement among raft nodes before linearized reading' (duration: 116.3819ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:21.029753Z","caller":"traceutil/trace.go:171","msg":"trace[1905693667] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"154.056869ms","start":"2026-04-23T07:46:20.875647Z","end":"2026-04-23T07:46:21.029704Z","steps":["trace[1905693667] 'process raft request' (duration: 151.298309ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:21.029831Z","caller":"traceutil/trace.go:171","msg":"trace[325926498] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"152.627508ms","start":"2026-04-23T07:46:20.877195Z","end":"2026-04-23T07:46:21.029822Z","steps":["trace[325926498] 'process raft request' (duration: 152.191357ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:21.31137Z","caller":"traceutil/trace.go:171","msg":"trace[956865693] linearizableReadLoop","detail":"{readStateIndex:807; appliedIndex:805; }","duration":"110.979461ms","start":"2026-04-23T07:46:21.200361Z","end":"2026-04-23T07:46:21.31134Z","steps":["trace[956865693] 'read index received' (duration: 8.838095ms)","trace[956865693] 'applied index is now lower than readState.Index' (duration: 102.139906ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:46:21.311491Z","caller":"traceutil/trace.go:171","msg":"trace[528576753] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"131.114144ms","start":"2026-04-23T07:46:21.180316Z","end":"2026-04-23T07:46:21.31143Z","steps":["trace[528576753] 'process raft request' (duration: 104.280835ms)","trace[528576753] 'compare' (duration: 26.613175ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T07:46:21.311641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.259717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-mwfsh\" ","response":"range_response_count:1 size:18512"} {"level":"info","ts":"2026-04-23T07:46:21.311691Z","caller":"traceutil/trace.go:171","msg":"trace[1083799302] range","detail":"{range_begin:/registry/pods/kube-system/cilium-mwfsh; range_end:; response_count:1; response_revision:777; }","duration":"111.37146ms","start":"2026-04-23T07:46:21.200308Z","end":"2026-04-23T07:46:21.31168Z","steps":["trace[1083799302] 'agreement among raft nodes before linearized reading' (duration: 111.160085ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:28.000821Z","caller":"traceutil/trace.go:171","msg":"trace[1186243329] transaction","detail":"{read_only:false; response_revision:830; number_of_response:1; }","duration":"151.595744ms","start":"2026-04-23T07:46:27.849163Z","end":"2026-04-23T07:46:28.000759Z","steps":["trace[1186243329] 'process raft request' (duration: 151.264297ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:28.117056Z","caller":"traceutil/trace.go:171","msg":"trace[1991005074] transaction","detail":"{read_only:false; response_revision:831; number_of_response:1; }","duration":"110.737596ms","start":"2026-04-23T07:46:28.006272Z","end":"2026-04-23T07:46:28.11701Z","steps":["trace[1991005074] 'process raft request' (duration: 104.484188ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:31.128181Z","caller":"traceutil/trace.go:171","msg":"trace[1764877942] transaction","detail":"{read_only:false; response_revision:837; number_of_response:1; }","duration":"166.512542ms","start":"2026-04-23T07:46:30.961585Z","end":"2026-04-23T07:46:31.128098Z","steps":["trace[1764877942] 'process raft request' (duration: 166.129974ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:31.299887Z","caller":"traceutil/trace.go:171","msg":"trace[1357977319] transaction","detail":"{read_only:false; response_revision:838; number_of_response:1; }","duration":"163.394774ms","start":"2026-04-23T07:46:31.13643Z","end":"2026-04-23T07:46:31.299824Z","steps":["trace[1357977319] 'process raft request' (duration: 145.983421ms)","trace[1357977319] 'compare' (duration: 16.987423ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:46:31.311229Z","caller":"traceutil/trace.go:171","msg":"trace[991585149] transaction","detail":"{read_only:false; response_revision:839; number_of_response:1; }","duration":"174.735274ms","start":"2026-04-23T07:46:31.136454Z","end":"2026-04-23T07:46:31.311189Z","steps":["trace[991585149] 'process raft request' (duration: 174.477729ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:32.513241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.958082ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T07:46:32.513355Z","caller":"traceutil/trace.go:171","msg":"trace[1757313240] linearizableReadLoop","detail":"{readStateIndex:884; appliedIndex:883; }","duration":"320.04592ms","start":"2026-04-23T07:46:32.193293Z","end":"2026-04-23T07:46:32.513339Z","steps":["trace[1757313240] 'read index received' (duration: 187.872583ms)","trace[1757313240] 'applied index is now lower than readState.Index' (duration: 132.172007ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:46:32.513711Z","caller":"traceutil/trace.go:171","msg":"trace[238978373] transaction","detail":"{read_only:false; response_revision:850; number_of_response:1; }","duration":"462.682427ms","start":"2026-04-23T07:46:32.050747Z","end":"2026-04-23T07:46:32.513429Z","steps":["trace[238978373] 'process raft request' (duration: 330.442528ms)","trace[238978373] 'compare' (duration: 131.764428ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T07:46:32.513962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.616562ms","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-23T07:46:32.51404Z","caller":"traceutil/trace.go:171","msg":"trace[770618932] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:850; }","duration":"320.770555ms","start":"2026-04-23T07:46:32.193253Z","end":"2026-04-23T07:46:32.514024Z","steps":["trace[770618932] 'agreement among raft nodes before linearized reading' (duration: 320.55553ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:32.514055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.603582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-frddh\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-04-23T07:46:32.514153Z","caller":"traceutil/trace.go:171","msg":"trace[1201338032] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-frddh; range_end:; response_count:1; response_revision:850; }","duration":"265.770286ms","start":"2026-04-23T07:46:32.24836Z","end":"2026-04-23T07:46:32.514131Z","steps":["trace[1201338032] 'agreement among raft nodes before linearized reading' (duration: 265.392758ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:32.514106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:32.193235Z","time spent":"320.859047ms","remote":"127.0.0.1:59216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-23T07:46:32.514375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:32.050728Z","time spent":"463.025284ms","remote":"127.0.0.1:59216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T07:46:33.234844Z","caller":"traceutil/trace.go:171","msg":"trace[520632544] transaction","detail":"{read_only:false; response_revision:851; number_of_response:1; }","duration":"716.487568ms","start":"2026-04-23T07:46:32.518318Z","end":"2026-04-23T07:46:33.234805Z","steps":["trace[520632544] 'process raft request' (duration: 714.628078ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:33.23498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:32.51829Z","time spent":"716.622501ms","remote":"127.0.0.1:59216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T07:46:33.243913Z","caller":"traceutil/trace.go:171","msg":"trace[1008345590] linearizableReadLoop","detail":"{readStateIndex:886; appliedIndex:884; }","duration":"441.220694ms","start":"2026-04-23T07:46:32.802673Z","end":"2026-04-23T07:46:33.243894Z","steps":["trace[1008345590] 'read index received' (duration: 430.337655ms)","trace[1008345590] 'applied index is now lower than readState.Index' (duration: 10.882149ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T07:46:33.244116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.433299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T07:46:33.244189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.359385ms","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-23T07:46:33.24423Z","caller":"traceutil/trace.go:171","msg":"trace[258392212] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:852; }","duration":"109.448177ms","start":"2026-04-23T07:46:33.134772Z","end":"2026-04-23T07:46:33.24422Z","steps":["trace[258392212] 'agreement among raft nodes before linearized reading' (duration: 109.302544ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:33.244215Z","caller":"traceutil/trace.go:171","msg":"trace[1960815024] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:852; }","duration":"441.588023ms","start":"2026-04-23T07:46:32.802614Z","end":"2026-04-23T07:46:33.244203Z","steps":["trace[1960815024] 'agreement among raft nodes before linearized reading' (duration: 441.427559ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:33.244156Z","caller":"traceutil/trace.go:171","msg":"trace[2007721682] transaction","detail":"{read_only:false; response_revision:852; number_of_response:1; }","duration":"721.195992ms","start":"2026-04-23T07:46:32.522935Z","end":"2026-04-23T07:46:33.244131Z","steps":["trace[2007721682] 'process raft request' (duration: 720.861845ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:46:33.244561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:32.522909Z","time spent":"721.584691ms","remote":"127.0.0.1:59144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4024,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T07:46:33.244888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:46:32.802595Z","time spent":"442.274407ms","remote":"127.0.0.1:58924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-23T07:46:47.490628Z","caller":"traceutil/trace.go:171","msg":"trace[1787841314] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"107.110035ms","start":"2026-04-23T07:46:47.383474Z","end":"2026-04-23T07:46:47.490584Z","steps":["trace[1787841314] 'process raft request' (duration: 106.665646ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:46:47.501365Z","caller":"traceutil/trace.go:171","msg":"trace[1928189224] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"116.86488ms","start":"2026-04-23T07:46:47.384481Z","end":"2026-04-23T07:46:47.501346Z","steps":["trace[1928189224] 'process raft request' (duration: 116.765988ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:47:09.3047Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12602371076471506976,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-23T07:47:09.540205Z","caller":"traceutil/trace.go:171","msg":"trace[547378159] linearizableReadLoop","detail":"{readStateIndex:1112; appliedIndex:1111; }","duration":"736.389157ms","start":"2026-04-23T07:47:08.80377Z","end":"2026-04-23T07:47:09.540159Z","steps":["trace[547378159] 'read index received' (duration: 735.491627ms)","trace[547378159] 'applied index is now lower than readState.Index' (duration: 895.85µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:47:09.540251Z","caller":"traceutil/trace.go:171","msg":"trace[1043004963] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"761.550617ms","start":"2026-04-23T07:47:08.778641Z","end":"2026-04-23T07:47:09.540192Z","steps":["trace[1043004963] 'process raft request' (duration: 760.610946ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:47:09.540556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"736.703134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T07:47:09.540745Z","caller":"traceutil/trace.go:171","msg":"trace[987531832] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1069; }","duration":"736.95746ms","start":"2026-04-23T07:47:08.803725Z","end":"2026-04-23T07:47:09.540683Z","steps":["trace[987531832] 'agreement among raft nodes before linearized reading' (duration: 736.623633ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:47:09.540802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:47:08.778612Z","time spent":"762.065929ms","remote":"127.0.0.1:59216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T07:47:09.540876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:47:08.803703Z","time spent":"737.140554ms","remote":"127.0.0.1:58924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T07:47:10.531686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.778774ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T07:47:10.531936Z","caller":"traceutil/trace.go:171","msg":"trace[116941260] linearizableReadLoop","detail":"{readStateIndex:1114; appliedIndex:1112; }","duration":"928.974341ms","start":"2026-04-23T07:47:09.602937Z","end":"2026-04-23T07:47:10.531911Z","steps":["trace[116941260] 'read index received' (duration: 482.035803ms)","trace[116941260] 'applied index is now lower than readState.Index' (duration: 446.937438ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:47:10.53193Z","caller":"traceutil/trace.go:171","msg":"trace[1336694344] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"985.693196ms","start":"2026-04-23T07:47:09.546202Z","end":"2026-04-23T07:47:10.531896Z","steps":["trace[1336694344] 'process raft request' (duration: 538.551365ms)","trace[1336694344] 'compare' (duration: 446.495457ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T07:47:10.532051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:47:09.546176Z","time spent":"985.819379ms","remote":"127.0.0.1:59216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T07:47:10.532306Z","caller":"traceutil/trace.go:171","msg":"trace[2113964294] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"985.241535ms","start":"2026-04-23T07:47:09.547047Z","end":"2026-04-23T07:47:10.532288Z","steps":["trace[2113964294] 'process raft request' (duration: 984.760184ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:47:10.532397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:47:09.54697Z","time spent":"985.375118ms","remote":"127.0.0.1:59216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T07:47:10.532532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"929.585824ms","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-23T07:47:10.53261Z","caller":"traceutil/trace.go:171","msg":"trace[1453412494] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1071; }","duration":"929.710947ms","start":"2026-04-23T07:47:09.602874Z","end":"2026-04-23T07:47:10.532585Z","steps":["trace[1453412494] 'agreement among raft nodes before linearized reading' (duration: 929.533053ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:47:10.53264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:47:09.602854Z","time spent":"929.780638ms","remote":"127.0.0.1:59216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-23T07:47:10.532832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"923.20205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-23T07:47:10.532898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.151716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-23T07:47:10.532913Z","caller":"traceutil/trace.go:171","msg":"trace[859312131] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1071; }","duration":"923.334003ms","start":"2026-04-23T07:47:09.609549Z","end":"2026-04-23T07:47:10.532884Z","steps":["trace[859312131] 'agreement among raft nodes before linearized reading' (duration: 923.069096ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:47:10.532926Z","caller":"traceutil/trace.go:171","msg":"trace[1680856495] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1071; }","duration":"610.209558ms","start":"2026-04-23T07:47:09.922709Z","end":"2026-04-23T07:47:10.532918Z","steps":["trace[1680856495] 'agreement among raft nodes before linearized reading' (duration: 610.152097ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:47:10.53295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:47:09.922691Z","time spent":"610.253839ms","remote":"127.0.0.1:59414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":2,"response size":31,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-04-23T07:47:10.532935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.053135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-23T07:47:10.532989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"728.788166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T07:47:10.532988Z","caller":"traceutil/trace.go:171","msg":"trace[1255515327] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1071; }","duration":"565.163067ms","start":"2026-04-23T07:47:09.96781Z","end":"2026-04-23T07:47:10.532973Z","steps":["trace[1255515327] 'agreement among raft nodes before linearized reading' (duration: 564.989273ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:47:10.53301Z","caller":"traceutil/trace.go:171","msg":"trace[1454056575] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1071; }","duration":"728.834726ms","start":"2026-04-23T07:47:09.804168Z","end":"2026-04-23T07:47:10.533003Z","steps":["trace[1454056575] 'agreement among raft nodes before linearized reading' (duration: 728.800025ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:47:10.533027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:47:09.804149Z","time spent":"728.874087ms","remote":"127.0.0.1:58924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T07:47:10.533033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:47:09.967787Z","time spent":"565.233258ms","remote":"127.0.0.1:59468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":113,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-04-23T07:47:10.532951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:47:09.609524Z","time spent":"923.420536ms","remote":"127.0.0.1:59216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-23T07:47:10.533297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.239598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-23T07:47:10.533423Z","caller":"traceutil/trace.go:171","msg":"trace[2043414259] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1071; }","duration":"234.392091ms","start":"2026-04-23T07:47:10.298983Z","end":"2026-04-23T07:47:10.533375Z","steps":["trace[2043414259] 'agreement among raft nodes before linearized reading' (duration: 234.087185ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:47:35.983076Z","caller":"traceutil/trace.go:171","msg":"trace[1491687089] linearizableReadLoop","detail":"{readStateIndex:1203; appliedIndex:1202; }","duration":"179.680271ms","start":"2026-04-23T07:47:35.803369Z","end":"2026-04-23T07:47:35.983049Z","steps":["trace[1491687089] 'read index received' (duration: 179.489207ms)","trace[1491687089] 'applied index is now lower than readState.Index' (duration: 190.164µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:47:35.9832Z","caller":"traceutil/trace.go:171","msg":"trace[1866733306] transaction","detail":"{read_only:false; response_revision:1155; number_of_response:1; }","duration":"356.414938ms","start":"2026-04-23T07:47:35.62676Z","end":"2026-04-23T07:47:35.983175Z","steps":["trace[1866733306] 'process raft request' (duration: 356.12045ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:47:35.983255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.850326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T07:47:35.983312Z","caller":"traceutil/trace.go:171","msg":"trace[2098362737] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1155; }","duration":"179.989669ms","start":"2026-04-23T07:47:35.803307Z","end":"2026-04-23T07:47:35.983296Z","steps":["trace[2098362737] 'agreement among raft nodes before linearized reading' (duration: 179.855055ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:47:35.983335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:47:35.626731Z","time spent":"356.522749ms","remote":"127.0.0.1:59216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T07:47:36.311911Z","caller":"traceutil/trace.go:171","msg":"trace[153611048] transaction","detail":"{read_only:false; response_revision:1156; number_of_response:1; }","duration":"199.551852ms","start":"2026-04-23T07:47:36.112328Z","end":"2026-04-23T07:47:36.31188Z","steps":["trace[153611048] 'process raft request' (duration: 173.225135ms)","trace[153611048] 'compare' (duration: 25.967199ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T07:47:37.366225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.294989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T07:47:37.366353Z","caller":"traceutil/trace.go:171","msg":"trace[855335165] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1161; }","duration":"418.476563ms","start":"2026-04-23T07:47:36.947851Z","end":"2026-04-23T07:47:37.366328Z","steps":["trace[855335165] 'count revisions from in-memory index tree' (duration: 418.096524ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:47:37.367198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:47:36.947825Z","time spent":"418.948503ms","remote":"127.0.0.1:53876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"info","ts":"2026-04-23T07:47:38.956124Z","caller":"traceutil/trace.go:171","msg":"trace[632351753] transaction","detail":"{read_only:false; response_revision:1167; number_of_response:1; }","duration":"101.084651ms","start":"2026-04-23T07:47:38.855016Z","end":"2026-04-23T07:47:38.9561Z","steps":["trace[632351753] 'process raft request' (duration: 101.04143ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:47:38.956566Z","caller":"traceutil/trace.go:171","msg":"trace[2146120403] transaction","detail":"{read_only:false; response_revision:1166; number_of_response:1; }","duration":"101.888559ms","start":"2026-04-23T07:47:38.854633Z","end":"2026-04-23T07:47:38.956522Z","steps":["trace[2146120403] 'process raft request' (duration: 76.499283ms)","trace[2146120403] 'compare' (duration: 24.779172ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:47:40.388104Z","caller":"traceutil/trace.go:171","msg":"trace[1867940897] transaction","detail":"{read_only:false; response_revision:1168; number_of_response:1; }","duration":"294.066524ms","start":"2026-04-23T07:47:40.094013Z","end":"2026-04-23T07:47:40.388079Z","steps":["trace[1867940897] 'process raft request' (duration: 293.500771ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:47:53.419025Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12602371076471507445,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T07:47:53.919961Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12602371076471507445,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T07:47:53.961418Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.133338611s","expected-duration":"1s"} {"level":"info","ts":"2026-04-23T07:47:53.961922Z","caller":"traceutil/trace.go:171","msg":"trace[762714317] linearizableReadLoop","detail":"{readStateIndex:1314; appliedIndex:1313; }","duration":"1.042984914s","start":"2026-04-23T07:47:52.918902Z","end":"2026-04-23T07:47:53.961887Z","steps":["trace[762714317] 'read index received' (duration: 1.042734278s)","trace[762714317] 'applied index is now lower than readState.Index' (duration: 249.286µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:47:53.961952Z","caller":"traceutil/trace.go:171","msg":"trace[582918685] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"1.133944605s","start":"2026-04-23T07:47:52.827986Z","end":"2026-04-23T07:47:53.961931Z","steps":["trace[582918685] 'process raft request' (duration: 1.13372176s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:47:53.962148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"948.982793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-23T07:47:53.962177Z","caller":"traceutil/trace.go:171","msg":"trace[1862008859] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1260; }","duration":"949.044855ms","start":"2026-04-23T07:47:53.013123Z","end":"2026-04-23T07:47:53.962168Z","steps":["trace[1862008859] 'agreement among raft nodes before linearized reading' (duration: 948.932203ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:47:53.962197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:47:53.013104Z","time spent":"949.088246ms","remote":"127.0.0.1:59216","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-23T07:47:53.962172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.043241939s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-23T07:47:53.962188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.029051578s","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-23T07:47:53.962245Z","caller":"traceutil/trace.go:171","msg":"trace[809138175] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1260; }","duration":"1.043374032s","start":"2026-04-23T07:47:52.918859Z","end":"2026-04-23T07:47:53.962233Z","steps":["trace[809138175] 'agreement among raft nodes before linearized reading' (duration: 1.043154878s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:47:53.962243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"948.604315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-23T07:47:53.962272Z","caller":"traceutil/trace.go:171","msg":"trace[1923405080] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1260; }","duration":"1.02913688s","start":"2026-04-23T07:47:52.933119Z","end":"2026-04-23T07:47:53.962256Z","steps":["trace[1923405080] 'agreement among raft nodes before linearized reading' (duration: 1.028906935s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:47:53.962335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:47:52.93307Z","time spent":"1.029253943s","remote":"127.0.0.1:59216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-23T07:47:53.962333Z","caller":"traceutil/trace.go:171","msg":"trace[858761272] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1260; }","duration":"948.724987ms","start":"2026-04-23T07:47:53.013587Z","end":"2026-04-23T07:47:53.962312Z","steps":["trace[858761272] 'agreement among raft nodes before linearized reading' (duration: 948.451011ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:47:53.962478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:47:53.013583Z","time spent":"948.882482ms","remote":"127.0.0.1:59216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-23T07:47:53.962286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:47:52.918838Z","time spent":"1.043441635s","remote":"127.0.0.1:59216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-23T07:47:53.962651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:47:52.827966Z","time spent":"1.134026367s","remote":"127.0.0.1:59216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T07:48:06.670687Z","caller":"traceutil/trace.go:171","msg":"trace[563421983] linearizableReadLoop","detail":"{readStateIndex:1361; appliedIndex:1360; }","duration":"107.491423ms","start":"2026-04-23T07:48:06.563171Z","end":"2026-04-23T07:48:06.670663Z","steps":["trace[563421983] 'read index received' (duration: 107.34431ms)","trace[563421983] 'applied index is now lower than readState.Index' (duration: 145.473µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:48:06.671044Z","caller":"traceutil/trace.go:171","msg":"trace[1973415969] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"155.836098ms","start":"2026-04-23T07:48:06.515179Z","end":"2026-04-23T07:48:06.671015Z","steps":["trace[1973415969] 'process raft request' (duration: 155.317357ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:48:06.671074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.889742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-23T07:48:06.671122Z","caller":"traceutil/trace.go:171","msg":"trace[286176546] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1305; }","duration":"107.993374ms","start":"2026-04-23T07:48:06.563116Z","end":"2026-04-23T07:48:06.67111Z","steps":["trace[286176546] 'agreement among raft nodes before linearized reading' (duration: 107.895172ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:48:09.272798Z","caller":"traceutil/trace.go:171","msg":"trace[2048078834] transaction","detail":"{read_only:false; response_revision:1331; number_of_response:1; }","duration":"118.136115ms","start":"2026-04-23T07:48:09.154613Z","end":"2026-04-23T07:48:09.272749Z","steps":["trace[2048078834] 'process raft request' (duration: 117.91792ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:48:10.103761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.015167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-04-23T07:48:10.103834Z","caller":"traceutil/trace.go:171","msg":"trace[1558006022] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1332; }","duration":"370.131769ms","start":"2026-04-23T07:48:09.733685Z","end":"2026-04-23T07:48:10.103817Z","steps":["trace[1558006022] 'range keys from in-memory index tree' (duration: 369.849903ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:48:10.103867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:48:09.733666Z","time spent":"370.19281ms","remote":"127.0.0.1:59006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":142,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"warn","ts":"2026-04-23T07:48:10.103941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.318716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:114235"} {"level":"info","ts":"2026-04-23T07:48:10.103974Z","caller":"traceutil/trace.go:171","msg":"trace[1507246571] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1332; }","duration":"371.411799ms","start":"2026-04-23T07:48:09.732554Z","end":"2026-04-23T07:48:10.103966Z","steps":["trace[1507246571] 'range keys from in-memory index tree' (duration: 371.032949ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:48:10.103996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:48:09.732523Z","time spent":"371.467459ms","remote":"127.0.0.1:58992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":114259,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-04-23T07:48:10.10397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.300173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T07:48:10.107226Z","caller":"traceutil/trace.go:171","msg":"trace[398297254] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1332; }","duration":"301.65096ms","start":"2026-04-23T07:48:09.805544Z","end":"2026-04-23T07:48:10.107195Z","steps":["trace[398297254] 'range keys from in-memory index tree' (duration: 298.19366ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:48:10.107489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:48:09.805506Z","time spent":"301.960436ms","remote":"127.0.0.1:58924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-23T07:48:10.464057Z","caller":"traceutil/trace.go:171","msg":"trace[392095923] transaction","detail":"{read_only:false; response_revision:1333; number_of_response:1; }","duration":"355.048758ms","start":"2026-04-23T07:48:10.108987Z","end":"2026-04-23T07:48:10.464036Z","steps":["trace[392095923] 'process raft request' (duration: 354.915685ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:48:10.464182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:48:10.108974Z","time spent":"355.14069ms","remote":"127.0.0.1:59216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T07:48:10.464572Z","caller":"traceutil/trace.go:171","msg":"trace[707069365] linearizableReadLoop","detail":"{readStateIndex:1390; appliedIndex:1390; }","duration":"355.319704ms","start":"2026-04-23T07:48:10.109245Z","end":"2026-04-23T07:48:10.464564Z","steps":["trace[707069365] 'read index received' (duration: 355.315534ms)","trace[707069365] 'applied index is now lower than readState.Index' (duration: 3.47µs)"],"step_count":2} {"level":"warn","ts":"2026-04-23T07:48:10.464634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.391086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T07:48:10.464673Z","caller":"traceutil/trace.go:171","msg":"trace[1867343190] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1333; }","duration":"355.477248ms","start":"2026-04-23T07:48:10.10919Z","end":"2026-04-23T07:48:10.464667Z","steps":["trace[1867343190] 'agreement among raft nodes before linearized reading' (duration: 355.418787ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:48:10.464694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:48:10.109166Z","time spent":"355.523059ms","remote":"127.0.0.1:58918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-23T07:48:10.585387Z","caller":"traceutil/trace.go:171","msg":"trace[1157699932] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"475.639687ms","start":"2026-04-23T07:48:10.109723Z","end":"2026-04-23T07:48:10.585363Z","steps":["trace[1157699932] 'process raft request' (duration: 475.441573ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:48:10.585692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:48:10.109695Z","time spent":"475.887262ms","remote":"127.0.0.1:59216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T07:48:10.585902Z","caller":"traceutil/trace.go:171","msg":"trace[621204787] linearizableReadLoop","detail":"{readStateIndex:1394; appliedIndex:1390; }","duration":"121.270236ms","start":"2026-04-23T07:48:10.464597Z","end":"2026-04-23T07:48:10.585867Z","steps":["trace[621204787] 'read index received' (duration: 120.505357ms)","trace[621204787] 'applied index is now lower than readState.Index' (duration: 763.428µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:48:10.585946Z","caller":"traceutil/trace.go:171","msg":"trace[1365297698] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"476.19189ms","start":"2026-04-23T07:48:10.109737Z","end":"2026-04-23T07:48:10.585929Z","steps":["trace[1365297698] 'process raft request' (duration: 475.589455ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:48:10.586505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:48:10.109727Z","time spent":"476.693652ms","remote":"127.0.0.1:59216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T07:48:10.586008Z","caller":"traceutil/trace.go:171","msg":"trace[549598721] transaction","detail":"{read_only:false; response_revision:1337; number_of_response:1; }","duration":"476.14788ms","start":"2026-04-23T07:48:10.109854Z","end":"2026-04-23T07:48:10.586002Z","steps":["trace[549598721] 'process raft request' (duration: 475.943885ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:48:10.587227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:48:10.109836Z","time spent":"477.292475ms","remote":"127.0.0.1:59216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T07:48:10.58603Z","caller":"traceutil/trace.go:171","msg":"trace[1879347862] transaction","detail":"{read_only:false; response_revision:1336; number_of_response:1; }","duration":"476.233241ms","start":"2026-04-23T07:48:10.109791Z","end":"2026-04-23T07:48:10.586024Z","steps":["trace[1879347862] 'process raft request' (duration: 475.821291ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:48:10.587469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:48:10.109783Z","time spent":"477.644152ms","remote":"127.0.0.1:59216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T07:48:10.58606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.714973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-04-23T07:48:10.587584Z","caller":"traceutil/trace.go:171","msg":"trace[756981766] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1337; }","duration":"478.274167ms","start":"2026-04-23T07:48:10.10929Z","end":"2026-04-23T07:48:10.587564Z","steps":["trace[756981766] 'agreement among raft nodes before linearized reading' (duration: 476.681482ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:48:10.587629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:48:10.109282Z","time spent":"478.33648ms","remote":"127.0.0.1:59006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":142,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"warn","ts":"2026-04-23T07:48:10.586094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.615928ms","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-23T07:48:10.587801Z","caller":"traceutil/trace.go:171","msg":"trace[1414051258] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1337; }","duration":"462.356057ms","start":"2026-04-23T07:48:10.125433Z","end":"2026-04-23T07:48:10.587789Z","steps":["trace[1414051258] 'agreement among raft nodes before linearized reading' (duration: 460.597558ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:48:10.587847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:48:10.125416Z","time spent":"462.417878ms","remote":"127.0.0.1:53606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"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-23T07:48:10.586141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.746893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:114235"} {"level":"info","ts":"2026-04-23T07:48:10.588008Z","caller":"traceutil/trace.go:171","msg":"trace[2011724091] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1337; }","duration":"478.622846ms","start":"2026-04-23T07:48:10.109375Z","end":"2026-04-23T07:48:10.587998Z","steps":["trace[2011724091] 'agreement among raft nodes before linearized reading' (duration: 476.671903ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:48:10.58804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:48:10.109372Z","time spent":"478.659157ms","remote":"127.0.0.1:58992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":114259,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-04-23T07:48:11.760122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:48:11.414885Z","time spent":"345.232556ms","remote":"127.0.0.1:58954","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-23T07:48:12.324117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.012421ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T07:48:12.324271Z","caller":"traceutil/trace.go:171","msg":"trace[1518305366] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1338; }","duration":"281.178355ms","start":"2026-04-23T07:48:12.043068Z","end":"2026-04-23T07:48:12.324246Z","steps":["trace[1518305366] 'range keys from in-memory index tree' (duration: 280.992881ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:48:12.324892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.816533ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T07:48:12.325225Z","caller":"traceutil/trace.go:171","msg":"trace[1461898827] linearizableReadLoop","detail":"{readStateIndex:1397; appliedIndex:1396; }","duration":"523.348008ms","start":"2026-04-23T07:48:11.801857Z","end":"2026-04-23T07:48:12.325205Z","steps":["trace[1461898827] 'read index received' (duration: 152.791169ms)","trace[1461898827] 'applied index is now lower than readState.Index' (duration: 370.554198ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:48:12.325263Z","caller":"traceutil/trace.go:171","msg":"trace[1496574426] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"563.961597ms","start":"2026-04-23T07:48:11.761272Z","end":"2026-04-23T07:48:12.325233Z","steps":["trace[1496574426] 'process raft request' (duration: 193.317156ms)","trace[1496574426] 'compare' (duration: 369.009985ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T07:48:12.325383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.522901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T07:48:12.325442Z","caller":"traceutil/trace.go:171","msg":"trace[2045583676] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1339; }","duration":"523.618484ms","start":"2026-04-23T07:48:11.80181Z","end":"2026-04-23T07:48:12.325428Z","steps":["trace[2045583676] 'agreement among raft nodes before linearized reading' (duration: 523.48033ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:48:12.325432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:48:11.761244Z","time spent":"564.09349ms","remote":"127.0.0.1:58954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T07:48:12.325487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:48:11.80179Z","time spent":"523.690177ms","remote":"127.0.0.1:58924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T07:48:12.325552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.559465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-23T07:48:12.325627Z","caller":"traceutil/trace.go:171","msg":"trace[1480610416] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1339; }","duration":"103.685378ms","start":"2026-04-23T07:48:12.22193Z","end":"2026-04-23T07:48:12.325615Z","steps":["trace[1480610416] 'agreement among raft nodes before linearized reading' (duration: 103.468503ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:48:12.964736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.070924ms","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-23T07:48:12.964843Z","caller":"traceutil/trace.go:171","msg":"trace[2078257773] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1341; }","duration":"372.245748ms","start":"2026-04-23T07:48:12.59258Z","end":"2026-04-23T07:48:12.964826Z","steps":["trace[2078257773] 'range keys from in-memory index tree' (duration: 371.866859ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:48:12.9649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:48:12.59255Z","time spent":"372.33654ms","remote":"127.0.0.1:59216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-23T07:48:12.965004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.753189ms","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-23T07:48:12.965073Z","caller":"traceutil/trace.go:171","msg":"trace[383959344] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1341; }","duration":"372.852462ms","start":"2026-04-23T07:48:12.592201Z","end":"2026-04-23T07:48:12.965053Z","steps":["trace[383959344] 'range keys from in-memory index tree' (duration: 372.574575ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:48:12.965103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:48:12.592188Z","time spent":"372.909353ms","remote":"127.0.0.1:59216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-23T07:48:12.965209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.130207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-23T07:48:12.965224Z","caller":"traceutil/trace.go:171","msg":"trace[967119574] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1341; }","duration":"373.171268ms","start":"2026-04-23T07:48:12.592048Z","end":"2026-04-23T07:48:12.965219Z","steps":["trace[967119574] 'range keys from in-memory index tree' (duration: 373.040685ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:48:12.96524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:48:12.592028Z","time spent":"373.208269ms","remote":"127.0.0.1:59216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-23T07:48:12.965392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.130337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-23T07:48:12.965459Z","caller":"traceutil/trace.go:171","msg":"trace[1331702473] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1341; }","duration":"373.25119ms","start":"2026-04-23T07:48:12.592191Z","end":"2026-04-23T07:48:12.965442Z","steps":["trace[1331702473] 'range keys from in-memory index tree' (duration: 372.938793ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:48:12.9655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:48:12.592157Z","time spent":"373.335452ms","remote":"127.0.0.1:59216","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-23T07:48:13.026828Z","caller":"traceutil/trace.go:171","msg":"trace[751585403] linearizableReadLoop","detail":"{readStateIndex:1400; appliedIndex:1400; }","duration":"224.783248ms","start":"2026-04-23T07:48:12.802019Z","end":"2026-04-23T07:48:13.026802Z","steps":["trace[751585403] 'read index received' (duration: 224.769788ms)","trace[751585403] 'applied index is now lower than readState.Index' (duration: 11.2µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:48:13.026834Z","caller":"traceutil/trace.go:171","msg":"trace[607201171] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"283.094909ms","start":"2026-04-23T07:48:12.743714Z","end":"2026-04-23T07:48:13.026809Z","steps":["trace[607201171] 'process raft request' (duration: 282.948856ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:48:13.026948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.912192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T07:48:13.027194Z","caller":"traceutil/trace.go:171","msg":"trace[323885579] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1342; }","duration":"225.166417ms","start":"2026-04-23T07:48:12.801962Z","end":"2026-04-23T07:48:13.027129Z","steps":["trace[323885579] 'agreement among raft nodes before linearized reading' (duration: 224.935192ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:48:13.299041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.302141ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T07:48:13.299288Z","caller":"traceutil/trace.go:171","msg":"trace[1161081051] transaction","detail":"{read_only:false; response_revision:1346; number_of_response:1; }","duration":"323.854321ms","start":"2026-04-23T07:48:12.975414Z","end":"2026-04-23T07:48:13.299268Z","steps":["trace[1161081051] 'process raft request' (duration: 323.814811ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:48:13.299364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:48:12.975391Z","time spent":"323.937943ms","remote":"127.0.0.1:59216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T07:48:13.299428Z","caller":"traceutil/trace.go:171","msg":"trace[1769614946] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"328.807443ms","start":"2026-04-23T07:48:12.970584Z","end":"2026-04-23T07:48:13.299391Z","steps":["trace[1769614946] 'process raft request' (duration: 186.028021ms)","trace[1769614946] 'compare' (duration: 142.109737ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:48:13.299481Z","caller":"traceutil/trace.go:171","msg":"trace[2092821648] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"328.752542ms","start":"2026-04-23T07:48:12.970667Z","end":"2026-04-23T07:48:13.29942Z","steps":["trace[2092821648] 'process raft request' (duration: 328.462545ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:48:13.299534Z","caller":"traceutil/trace.go:171","msg":"trace[1846074843] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"328.614929ms","start":"2026-04-23T07:48:12.970897Z","end":"2026-04-23T07:48:13.299512Z","steps":["trace[1846074843] 'process raft request' (duration: 328.292412ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:48:13.299588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:48:12.970562Z","time spent":"328.937337ms","remote":"127.0.0.1:59216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T07:48:13.299679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:48:12.970886Z","time spent":"328.707561ms","remote":"127.0.0.1:59216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T07:48:13.299667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:48:12.970626Z","time spent":"328.932916ms","remote":"127.0.0.1:59216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T07:48:13.760524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.65309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T07:48:13.760614Z","caller":"traceutil/trace.go:171","msg":"trace[1429368786] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1346; }","duration":"184.796573ms","start":"2026-04-23T07:48:13.575795Z","end":"2026-04-23T07:48:13.760592Z","steps":["trace[1429368786] 'count revisions from in-memory index tree' (duration: 184.541608ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:48:14.151569Z","caller":"traceutil/trace.go:171","msg":"trace[1650385574] linearizableReadLoop","detail":"{readStateIndex:1405; appliedIndex:1404; }","duration":"262.293847ms","start":"2026-04-23T07:48:13.889257Z","end":"2026-04-23T07:48:14.151551Z","steps":["trace[1650385574] 'read index received' (duration: 262.153474ms)","trace[1650385574] 'applied index is now lower than readState.Index' (duration: 139.863µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:48:14.15165Z","caller":"traceutil/trace.go:171","msg":"trace[316612264] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"325.484829ms","start":"2026-04-23T07:48:13.826158Z","end":"2026-04-23T07:48:14.151642Z","steps":["trace[316612264] 'process raft request' (duration: 325.304185ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:48:14.15172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:48:13.826114Z","time spent":"325.55453ms","remote":"127.0.0.1:59010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T07:48:14.152363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.099686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T07:48:14.152462Z","caller":"traceutil/trace.go:171","msg":"trace[345128848] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1347; }","duration":"263.221608ms","start":"2026-04-23T07:48:13.889218Z","end":"2026-04-23T07:48:14.15244Z","steps":["trace[345128848] 'agreement among raft nodes before linearized reading' (duration: 262.752938ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:48:14.166259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.279366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T07:48:14.166312Z","caller":"traceutil/trace.go:171","msg":"trace[599803884] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1347; }","duration":"208.365038ms","start":"2026-04-23T07:48:13.957933Z","end":"2026-04-23T07:48:14.166298Z","steps":["trace[599803884] 'agreement among raft nodes before linearized reading' (duration: 208.281826ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:48:14.915118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.173056ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-23T07:48:14.915253Z","caller":"traceutil/trace.go:171","msg":"trace[424215905] transaction","detail":"{read_only:false; response_revision:1348; number_of_response:1; }","duration":"744.128027ms","start":"2026-04-23T07:48:14.1711Z","end":"2026-04-23T07:48:14.915228Z","steps":["trace[424215905] 'process raft request' (duration: 371.764677ms)","trace[424215905] 'compare' (duration: 371.089502ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T07:48:14.915324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:48:14.171081Z","time spent":"744.211519ms","remote":"127.0.0.1:59216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T07:48:14.922968Z","caller":"traceutil/trace.go:171","msg":"trace[428596139] linearizableReadLoop","detail":"{readStateIndex:1407; appliedIndex:1405; }","duration":"404.502998ms","start":"2026-04-23T07:48:14.518441Z","end":"2026-04-23T07:48:14.922944Z","steps":["trace[428596139] 'read index received' (duration: 24.437173ms)","trace[428596139] 'applied index is now lower than readState.Index' (duration: 380.064835ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T07:48:14.923173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.713033ms","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-23T07:48:14.923213Z","caller":"traceutil/trace.go:171","msg":"trace[539200036] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1349; }","duration":"404.846216ms","start":"2026-04-23T07:48:14.518356Z","end":"2026-04-23T07:48:14.923202Z","steps":["trace[539200036] 'agreement among raft nodes before linearized reading' (duration: 404.691332ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:48:14.923254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:48:14.518333Z","time spent":"404.913128ms","remote":"127.0.0.1:59216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-23T07:48:14.923461Z","caller":"traceutil/trace.go:171","msg":"trace[571381897] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"618.605665ms","start":"2026-04-23T07:48:14.304845Z","end":"2026-04-23T07:48:14.923451Z","steps":["trace[571381897] 'process raft request' (duration: 617.968401ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:48:14.923476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.305672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T07:48:14.923552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:48:14.304818Z","time spent":"618.678816ms","remote":"127.0.0.1:59216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T07:48:14.923555Z","caller":"traceutil/trace.go:171","msg":"trace[2069168730] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1349; }","duration":"119.427184ms","start":"2026-04-23T07:48:14.804109Z","end":"2026-04-23T07:48:14.923537Z","steps":["trace[2069168730] 'agreement among raft nodes before linearized reading' (duration: 119.306102ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:48:15.566216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.421216ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-23T07:48:15.566296Z","caller":"traceutil/trace.go:171","msg":"trace[1761019037] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"641.338939ms","start":"2026-04-23T07:48:14.924945Z","end":"2026-04-23T07:48:15.566283Z","steps":["trace[1761019037] 'process raft request' (duration: 444.79462ms)","trace[1761019037] 'compare' (duration: 196.178451ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T07:48:15.566327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:48:14.924917Z","time spent":"641.398151ms","remote":"127.0.0.1:59010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":773,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T07:48:15.651894Z","caller":"traceutil/trace.go:171","msg":"trace[1440528975] linearizableReadLoop","detail":"{readStateIndex:1409; appliedIndex:1407; }","duration":"726.63077ms","start":"2026-04-23T07:48:14.925242Z","end":"2026-04-23T07:48:15.651873Z","steps":["trace[1440528975] 'read index received' (duration: 444.574404ms)","trace[1440528975] 'applied index is now lower than readState.Index' (duration: 282.055186ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:48:15.652004Z","caller":"traceutil/trace.go:171","msg":"trace[706847651] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"726.925076ms","start":"2026-04-23T07:48:14.925068Z","end":"2026-04-23T07:48:15.651993Z","steps":["trace[706847651] 'process raft request' (duration: 726.602549ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:48:15.65208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:48:14.925037Z","time spent":"726.992569ms","remote":"127.0.0.1:59010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T07:48:15.652417Z","caller":"traceutil/trace.go:171","msg":"trace[836924903] transaction","detail":"{read_only:false; response_revision:1352; number_of_response:1; }","duration":"724.86225ms","start":"2026-04-23T07:48:14.927543Z","end":"2026-04-23T07:48:15.652406Z","steps":["trace[836924903] 'process raft request' (duration: 724.227136ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:48:15.652494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:48:14.92753Z","time spent":"724.923592ms","remote":"127.0.0.1:59216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T07:48:15.652643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"727.246513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T07:48:15.652758Z","caller":"traceutil/trace.go:171","msg":"trace[1700277263] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1353; }","duration":"727.389397ms","start":"2026-04-23T07:48:14.925336Z","end":"2026-04-23T07:48:15.652725Z","steps":["trace[1700277263] 'agreement among raft nodes before linearized reading' (duration: 727.217873ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:48:15.652855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:48:14.925322Z","time spent":"727.51986ms","remote":"127.0.0.1:58918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-23T07:48:15.653048Z","caller":"traceutil/trace.go:171","msg":"trace[1619914774] transaction","detail":"{read_only:false; response_revision:1353; number_of_response:1; }","duration":"619.829813ms","start":"2026-04-23T07:48:15.033188Z","end":"2026-04-23T07:48:15.653017Z","steps":["trace[1619914774] 'process raft request' (duration: 618.644176ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:48:15.653103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.029835ms","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-23T07:48:15.653145Z","caller":"traceutil/trace.go:171","msg":"trace[775338649] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1353; }","duration":"347.085377ms","start":"2026-04-23T07:48:15.306048Z","end":"2026-04-23T07:48:15.653133Z","steps":["trace[775338649] 'agreement among raft nodes before linearized reading' (duration: 346.940024ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:48:15.653173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:48:15.033168Z","time spent":"619.946735ms","remote":"127.0.0.1:59216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T07:48:15.65318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.185032ms","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":"warn","ts":"2026-04-23T07:48:15.653265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.173879ms","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-23T07:48:15.653216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.998495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-23T07:48:15.653286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"728.038621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-04-23T07:48:15.6533Z","caller":"traceutil/trace.go:171","msg":"trace[1676575913] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1353; }","duration":"347.21563ms","start":"2026-04-23T07:48:15.306077Z","end":"2026-04-23T07:48:15.653292Z","steps":["trace[1676575913] 'agreement among raft nodes before linearized reading' (duration: 347.129938ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:48:15.653332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:48:15.306073Z","time spent":"347.25072ms","remote":"127.0.0.1:59216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-23T07:48:15.653335Z","caller":"traceutil/trace.go:171","msg":"trace[2079361415] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1353; }","duration":"728.100872ms","start":"2026-04-23T07:48:14.925218Z","end":"2026-04-23T07:48:15.653319Z","steps":["trace[2079361415] 'agreement among raft nodes before linearized reading' (duration: 727.98419ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:48:15.653338Z","caller":"traceutil/trace.go:171","msg":"trace[1909732927] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1353; }","duration":"347.141958ms","start":"2026-04-23T07:48:15.306172Z","end":"2026-04-23T07:48:15.653314Z","steps":["trace[1909732927] 'agreement among raft nodes before linearized reading' (duration: 346.78991ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:48:15.653366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:48:14.925211Z","time spent":"728.148083ms","remote":"127.0.0.1:59216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":498,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-23T07:48:15.653386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:48:15.306167Z","time spent":"347.20828ms","remote":"127.0.0.1:59216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-23T07:48:15.653256Z","caller":"traceutil/trace.go:171","msg":"trace[886094357] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1353; }","duration":"348.282354ms","start":"2026-04-23T07:48:15.304955Z","end":"2026-04-23T07:48:15.653237Z","steps":["trace[886094357] 'agreement among raft nodes before linearized reading' (duration: 348.08196ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:48:15.653465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:48:15.304937Z","time spent":"348.50864ms","remote":"127.0.0.1:59216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-23T07:48:15.653186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:48:15.306041Z","time spent":"347.137378ms","remote":"127.0.0.1:59216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-23T07:48:15.767833Z","caller":"traceutil/trace.go:171","msg":"trace[1229106] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"105.998129ms","start":"2026-04-23T07:48:15.66181Z","end":"2026-04-23T07:48:15.767808Z","steps":["trace[1229106] 'process raft request' (duration: 105.961118ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:48:15.768094Z","caller":"traceutil/trace.go:171","msg":"trace[575113239] transaction","detail":"{read_only:false; response_revision:1357; number_of_response:1; }","duration":"106.615783ms","start":"2026-04-23T07:48:15.661465Z","end":"2026-04-23T07:48:15.768081Z","steps":["trace[575113239] 'process raft request' (duration: 106.238895ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:48:15.768123Z","caller":"traceutil/trace.go:171","msg":"trace[1213130063] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"106.607963ms","start":"2026-04-23T07:48:15.661496Z","end":"2026-04-23T07:48:15.768104Z","steps":["trace[1213130063] 'process raft request' (duration: 106.240304ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:48:15.768188Z","caller":"traceutil/trace.go:171","msg":"trace[851422132] transaction","detail":"{read_only:false; response_revision:1355; number_of_response:1; }","duration":"106.891019ms","start":"2026-04-23T07:48:15.661292Z","end":"2026-04-23T07:48:15.768183Z","steps":["trace[851422132] 'process raft request' (duration: 106.226465ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:48:15.768293Z","caller":"traceutil/trace.go:171","msg":"trace[460087423] transaction","detail":"{read_only:false; response_revision:1356; number_of_response:1; }","duration":"106.768117ms","start":"2026-04-23T07:48:15.661429Z","end":"2026-04-23T07:48:15.768197Z","steps":["trace[460087423] 'process raft request' (duration: 106.185994ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:48:16.081711Z","caller":"traceutil/trace.go:171","msg":"trace[1655833388] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"102.907829ms","start":"2026-04-23T07:48:15.97878Z","end":"2026-04-23T07:48:16.081688Z","steps":["trace[1655833388] 'process raft request' (duration: 102.176783ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:48:25.56466Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12602371076471507797,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-23T07:48:25.65767Z","caller":"traceutil/trace.go:171","msg":"trace[1698130746] linearizableReadLoop","detail":"{readStateIndex:1485; appliedIndex:1484; }","duration":"593.872795ms","start":"2026-04-23T07:48:25.063764Z","end":"2026-04-23T07:48:25.657637Z","steps":["trace[1698130746] 'read index received' (duration: 593.737952ms)","trace[1698130746] 'applied index is now lower than readState.Index' (duration: 133.233µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:48:25.657805Z","caller":"traceutil/trace.go:171","msg":"trace[756491844] transaction","detail":"{read_only:false; response_revision:1425; number_of_response:1; }","duration":"638.399063ms","start":"2026-04-23T07:48:25.019365Z","end":"2026-04-23T07:48:25.657764Z","steps":["trace[756491844] 'process raft request' (duration: 638.116516ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:48:25.657973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.196422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T07:48:25.657995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:48:25.019348Z","time spent":"638.547006ms","remote":"127.0.0.1:59216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T07:48:25.658032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.202636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:524"} {"level":"info","ts":"2026-04-23T07:48:25.65808Z","caller":"traceutil/trace.go:171","msg":"trace[129905923] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1425; }","duration":"594.323605ms","start":"2026-04-23T07:48:25.063701Z","end":"2026-04-23T07:48:25.658025Z","steps":["trace[129905923] 'agreement among raft nodes before linearized reading' (duration: 594.13106ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:48:25.658276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:48:25.063575Z","time spent":"594.678973ms","remote":"127.0.0.1:53684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"info","ts":"2026-04-23T07:48:25.658119Z","caller":"traceutil/trace.go:171","msg":"trace[1553369427] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1425; }","duration":"535.340599ms","start":"2026-04-23T07:48:25.122761Z","end":"2026-04-23T07:48:25.658101Z","steps":["trace[1553369427] 'agreement among raft nodes before linearized reading' (duration: 535.074513ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:48:25.658422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T07:48:25.122741Z","time spent":"535.655867ms","remote":"127.0.0.1:59216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":548,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-23T07:48:25.938658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.14246ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T07:48:25.939364Z","caller":"traceutil/trace.go:171","msg":"trace[2109151253] linearizableReadLoop","detail":"{readStateIndex:1487; appliedIndex:1485; }","duration":"175.261477ms","start":"2026-04-23T07:48:25.764077Z","end":"2026-04-23T07:48:25.939338Z","steps":["trace[2109151253] 'read index received' (duration: 50.37741ms)","trace[2109151253] 'applied index is now lower than readState.Index' (duration: 124.883017ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:48:25.93946Z","caller":"traceutil/trace.go:171","msg":"trace[330191028] transaction","detail":"{read_only:false; response_revision:1427; number_of_response:1; }","duration":"186.745397ms","start":"2026-04-23T07:48:25.75269Z","end":"2026-04-23T07:48:25.939435Z","steps":["trace[330191028] 'process raft request' (duration: 186.519172ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:48:25.939611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.526243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-04-23T07:48:25.939655Z","caller":"traceutil/trace.go:171","msg":"trace[413126278] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1427; }","duration":"175.627135ms","start":"2026-04-23T07:48:25.764015Z","end":"2026-04-23T07:48:25.939643Z","steps":["trace[413126278] 'agreement among raft nodes before linearized reading' (duration: 175.460092ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:48:25.93974Z","caller":"traceutil/trace.go:171","msg":"trace[1959602964] transaction","detail":"{read_only:false; response_revision:1426; number_of_response:1; }","duration":"276.404086ms","start":"2026-04-23T07:48:25.663227Z","end":"2026-04-23T07:48:25.939631Z","steps":["trace[1959602964] 'process raft request' (duration: 151.202462ms)","trace[1959602964] 'compare' (duration: 123.961227ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T07:48:25.940432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.956484ms","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-23T07:48:25.940505Z","caller":"traceutil/trace.go:171","msg":"trace[818935932] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1427; }","duration":"157.074336ms","start":"2026-04-23T07:48:25.783415Z","end":"2026-04-23T07:48:25.940489Z","steps":["trace[818935932] 'agreement among raft nodes before linearized reading' (duration: 156.903883ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:48:25.94064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.130582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T07:48:25.940689Z","caller":"traceutil/trace.go:171","msg":"trace[1028613152] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1427; }","duration":"136.211643ms","start":"2026-04-23T07:48:25.804464Z","end":"2026-04-23T07:48:25.940676Z","steps":["trace[1028613152] 'agreement among raft nodes before linearized reading' (duration: 136.142142ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:48:25.940696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.240841ms","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-23T07:48:25.940746Z","caller":"traceutil/trace.go:171","msg":"trace[1986765549] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1427; }","duration":"112.323243ms","start":"2026-04-23T07:48:25.828408Z","end":"2026-04-23T07:48:25.940731Z","steps":["trace[1986765549] 'agreement among raft nodes before linearized reading' (duration: 112.19005ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:48:30.144165Z","caller":"traceutil/trace.go:171","msg":"trace[778068919] linearizableReadLoop","detail":"{readStateIndex:1506; appliedIndex:1505; }","duration":"177.740924ms","start":"2026-04-23T07:48:29.966398Z","end":"2026-04-23T07:48:30.144139Z","steps":["trace[778068919] 'read index received' (duration: 177.56503ms)","trace[778068919] 'applied index is now lower than readState.Index' (duration: 175.324µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T07:48:30.144266Z","caller":"traceutil/trace.go:171","msg":"trace[1445508787] transaction","detail":"{read_only:false; response_revision:1445; number_of_response:1; }","duration":"191.091576ms","start":"2026-04-23T07:48:29.953146Z","end":"2026-04-23T07:48:30.144237Z","steps":["trace[1445508787] 'process raft request' (duration: 190.873571ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:48:30.144354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.838656ms","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-23T07:48:30.144381Z","caller":"traceutil/trace.go:171","msg":"trace[784672859] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1445; }","duration":"177.917627ms","start":"2026-04-23T07:48:29.966454Z","end":"2026-04-23T07:48:30.144371Z","steps":["trace[784672859] 'agreement among raft nodes before linearized reading' (duration: 177.797504ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:48:30.144462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.02971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-23T07:48:30.144524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.907634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-23T07:48:30.14457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.993637ms","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-23T07:48:30.144563Z","caller":"traceutil/trace.go:171","msg":"trace[618481222] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1445; }","duration":"178.184383ms","start":"2026-04-23T07:48:29.966355Z","end":"2026-04-23T07:48:30.14454Z","steps":["trace[618481222] 'agreement among raft nodes before linearized reading' (duration: 177.883597ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:48:30.144614Z","caller":"traceutil/trace.go:171","msg":"trace[146543148] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1445; }","duration":"177.052738ms","start":"2026-04-23T07:48:29.967543Z","end":"2026-04-23T07:48:30.144596Z","steps":["trace[146543148] 'agreement among raft nodes before linearized reading' (duration: 176.783922ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T07:48:30.144623Z","caller":"traceutil/trace.go:171","msg":"trace[1783723449] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1445; }","duration":"177.062198ms","start":"2026-04-23T07:48:29.967547Z","end":"2026-04-23T07:48:30.144609Z","steps":["trace[1783723449] 'agreement among raft nodes before linearized reading' (duration: 176.911175ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T07:48:30.144865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.876954ms","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-23T07:48:30.144991Z","caller":"traceutil/trace.go:171","msg":"trace[1855511942] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1445; }","duration":"177.019178ms","start":"2026-04-23T07:48:29.967961Z","end":"2026-04-23T07:48:30.14498Z","steps":["trace[1855511942] 'agreement among raft nodes before linearized reading' (duration: 176.832793ms)"],"step_count":1}