{"level":"warn","ts":"2026-04-02T06:06:43.635128Z","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-02T06:06:43.63522Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.195: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.195:2380","--initial-cluster=instance=https://199.19.213.195:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.195:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.195: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-02T06:06:43.635294Z","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-02T06:06:43.635303Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.195:2380"]} {"level":"info","ts":"2026-04-02T06:06:43.635321Z","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-02T06:06:43.63637Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.195:2379"]} {"level":"info","ts":"2026-04-02T06:06:43.636629Z","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.195:2380"],"listen-peer-urls":["https://199.19.213.195:2380"],"advertise-client-urls":["https://199.19.213.195:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.195:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.195: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-02T06:06:43.644651Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.673543ms"} {"level":"info","ts":"2026-04-02T06:06:43.654528Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2808ca5e53a916c4","cluster-id":"b7ef986abcd4d0b8"} {"level":"info","ts":"2026-04-02T06:06:43.654733Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2808ca5e53a916c4 switched to configuration voters=()"} {"level":"info","ts":"2026-04-02T06:06:43.654822Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2808ca5e53a916c4 became follower at term 0"} {"level":"info","ts":"2026-04-02T06:06:43.65487Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2808ca5e53a916c4 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-02T06:06:43.654955Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2808ca5e53a916c4 became follower at term 1"} {"level":"info","ts":"2026-04-02T06:06:43.655056Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2808ca5e53a916c4 switched to configuration voters=(2884778067810129604)"} {"level":"warn","ts":"2026-04-02T06:06:43.662891Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-02T06:06:43.664203Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-02T06:06:43.665336Z","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-02T06:06:43.667746Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2808ca5e53a916c4","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-02T06:06:43.668015Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2808ca5e53a916c4","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-02T06:06:43.668298Z","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-02T06:06:43.668459Z","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-02T06:06:43.668572Z","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-02T06:06:43.668882Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2808ca5e53a916c4 switched to configuration voters=(2884778067810129604)"} {"level":"info","ts":"2026-04-02T06:06:43.669414Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b7ef986abcd4d0b8","local-member-id":"2808ca5e53a916c4","added-peer-id":"2808ca5e53a916c4","added-peer-peer-urls":["https://199.19.213.195:2380"]} {"level":"info","ts":"2026-04-02T06:06:43.673757Z","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-02T06:06:43.674048Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.195:2380"} {"level":"info","ts":"2026-04-02T06:06:43.6741Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.195:2380"} {"level":"info","ts":"2026-04-02T06:06:43.67439Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2808ca5e53a916c4","initial-advertise-peer-urls":["https://199.19.213.195:2380"],"listen-peer-urls":["https://199.19.213.195:2380"],"advertise-client-urls":["https://199.19.213.195:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.195:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-02T06:06:43.674482Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-02T06:06:44.255518Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2808ca5e53a916c4 is starting a new election at term 1"} {"level":"info","ts":"2026-04-02T06:06:44.255577Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2808ca5e53a916c4 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-02T06:06:44.255615Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2808ca5e53a916c4 received MsgPreVoteResp from 2808ca5e53a916c4 at term 1"} {"level":"info","ts":"2026-04-02T06:06:44.25563Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2808ca5e53a916c4 became candidate at term 2"} {"level":"info","ts":"2026-04-02T06:06:44.255638Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2808ca5e53a916c4 received MsgVoteResp from 2808ca5e53a916c4 at term 2"} {"level":"info","ts":"2026-04-02T06:06:44.255648Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2808ca5e53a916c4 became leader at term 2"} {"level":"info","ts":"2026-04-02T06:06:44.255657Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2808ca5e53a916c4 elected leader 2808ca5e53a916c4 at term 2"} {"level":"info","ts":"2026-04-02T06:06:44.256744Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T06:06:44.257428Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2808ca5e53a916c4","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.195:2379]}","request-path":"/0/members/2808ca5e53a916c4/attributes","cluster-id":"b7ef986abcd4d0b8","publish-timeout":"7s"} {"level":"info","ts":"2026-04-02T06:06:44.257652Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-02T06:06:44.257848Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b7ef986abcd4d0b8","local-member-id":"2808ca5e53a916c4","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T06:06:44.257951Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T06:06:44.257997Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T06:06:44.257933Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-02T06:06:44.260169Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-02T06:06:44.260215Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-02T06:06:44.261801Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-02T06:06:44.261823Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.195:2379"} {"level":"info","ts":"2026-04-02T06:06:48.914693Z","caller":"traceutil/trace.go:171","msg":"trace[2114185216] linearizableReadLoop","detail":"{readStateIndex:258; appliedIndex:257; }","duration":"307.695258ms","start":"2026-04-02T06:06:48.606973Z","end":"2026-04-02T06:06:48.914668Z","steps":["trace[2114185216] 'read index received' (duration: 307.529774ms)","trace[2114185216] 'applied index is now lower than readState.Index' (duration: 164.314µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T06:06:48.91476Z","caller":"traceutil/trace.go:171","msg":"trace[1846760532] transaction","detail":"{read_only:false; response_revision:252; number_of_response:1; }","duration":"570.233665ms","start":"2026-04-02T06:06:48.344479Z","end":"2026-04-02T06:06:48.914713Z","steps":["trace[1846760532] 'process raft request' (duration: 570.068871ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:06:48.914908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.927153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3709"} {"level":"info","ts":"2026-04-02T06:06:48.914986Z","caller":"traceutil/trace.go:171","msg":"trace[553124467] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:252; }","duration":"308.075117ms","start":"2026-04-02T06:06:48.606898Z","end":"2026-04-02T06:06:48.914974Z","steps":["trace[553124467] 'agreement among raft nodes before linearized reading' (duration: 307.919694ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:06:48.915027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:06:48.606253Z","time spent":"308.765125ms","remote":"127.0.0.1:36908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3732,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-04-02T06:06:48.915441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:06:48.344464Z","time spent":"570.385089ms","remote":"127.0.0.1:37010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T06:06:49.869717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.59364ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T06:06:49.869835Z","caller":"traceutil/trace.go:171","msg":"trace[124495689] transaction","detail":"{read_only:false; response_revision:253; number_of_response:1; }","duration":"948.58328ms","start":"2026-04-02T06:06:48.921229Z","end":"2026-04-02T06:06:49.869812Z","steps":["trace[124495689] 'process raft request' (duration: 330.557318ms)","trace[124495689] 'compare' (duration: 617.490688ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T06:06:49.869894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:06:48.921208Z","time spent":"948.657702ms","remote":"127.0.0.1:37010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T06:06:49.886848Z","caller":"traceutil/trace.go:171","msg":"trace[1535154084] transaction","detail":"{read_only:false; response_revision:254; number_of_response:1; }","duration":"965.21083ms","start":"2026-04-02T06:06:48.921601Z","end":"2026-04-02T06:06:49.886812Z","steps":["trace[1535154084] 'process raft request' (duration: 964.986674ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:06:49.886975Z","caller":"traceutil/trace.go:171","msg":"trace[1575264962] transaction","detail":"{read_only:false; response_revision:255; number_of_response:1; }","duration":"871.363091ms","start":"2026-04-02T06:06:49.015581Z","end":"2026-04-02T06:06:49.886944Z","steps":["trace[1575264962] 'process raft request' (duration: 871.127994ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:06:49.887035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:06:48.921587Z","time spent":"965.344475ms","remote":"127.0.0.1:36908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4003,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T06:06:49.888814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:06:49.015546Z","time spent":"871.569286ms","remote":"127.0.0.1:36924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5882,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T06:06:49.893598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.522829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T06:06:49.893648Z","caller":"traceutil/trace.go:171","msg":"trace[1470700661] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:256; }","duration":"405.616192ms","start":"2026-04-02T06:06:49.48802Z","end":"2026-04-02T06:06:49.893636Z","steps":["trace[1470700661] 'agreement among raft nodes before linearized reading' (duration: 405.524109ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:06:49.893787Z","caller":"traceutil/trace.go:171","msg":"trace[2049510119] transaction","detail":"{read_only:false; response_revision:256; number_of_response:1; }","duration":"612.749035ms","start":"2026-04-02T06:06:49.281029Z","end":"2026-04-02T06:06:49.893778Z","steps":["trace[2049510119] 'process raft request' (duration: 611.969345ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:06:49.893673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:06:49.487998Z","time spent":"405.670232ms","remote":"127.0.0.1:37010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-02T06:06:49.893925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:06:49.281009Z","time spent":"612.801496ms","remote":"127.0.0.1:36924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T06:06:49.893596Z","caller":"traceutil/trace.go:171","msg":"trace[822124141] linearizableReadLoop","detail":"{readStateIndex:262; appliedIndex:260; }","duration":"405.093237ms","start":"2026-04-02T06:06:49.488081Z","end":"2026-04-02T06:06:49.893175Z","steps":["trace[822124141] 'read index received' (duration: 398.689482ms)","trace[822124141] 'applied index is now lower than readState.Index' (duration: 6.402165ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T06:07:13.605542Z","caller":"traceutil/trace.go:171","msg":"trace[216105329] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"251.063798ms","start":"2026-04-02T06:07:13.354457Z","end":"2026-04-02T06:07:13.605521Z","steps":["trace[216105329] 'process raft request' (duration: 250.870251ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:07:13.68588Z","caller":"traceutil/trace.go:171","msg":"trace[12336874] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"330.349368ms","start":"2026-04-02T06:07:13.355489Z","end":"2026-04-02T06:07:13.685838Z","steps":["trace[12336874] 'process raft request' (duration: 323.467396ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:07:13.685916Z","caller":"traceutil/trace.go:171","msg":"trace[709912857] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"330.354697ms","start":"2026-04-02T06:07:13.355548Z","end":"2026-04-02T06:07:13.685903Z","steps":["trace[709912857] 'process raft request' (duration: 330.239064ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:07:13.686009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:07:13.355545Z","time spent":"330.413278ms","remote":"127.0.0.1:36820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T06:07:13.686053Z","caller":"traceutil/trace.go:171","msg":"trace[2060646422] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"318.376586ms","start":"2026-04-02T06:07:13.367641Z","end":"2026-04-02T06:07:13.686017Z","steps":["trace[2060646422] 'process raft request' (duration: 318.242031ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:07:13.686034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:07:13.355469Z","time spent":"330.486231ms","remote":"127.0.0.1:36820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T06:07:13.68621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:07:13.367632Z","time spent":"318.51251ms","remote":"127.0.0.1:36820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T06:07:13.686179Z","caller":"traceutil/trace.go:171","msg":"trace[1137137623] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"319.181789ms","start":"2026-04-02T06:07:13.366849Z","end":"2026-04-02T06:07:13.68603Z","steps":["trace[1137137623] 'process raft request' (duration: 318.979703ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:07:13.686261Z","caller":"traceutil/trace.go:171","msg":"trace[252852611] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"315.570964ms","start":"2026-04-02T06:07:13.370612Z","end":"2026-04-02T06:07:13.686183Z","steps":["trace[252852611] 'process raft request' (duration: 315.307695ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:07:13.686388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:07:13.370602Z","time spent":"315.727448ms","remote":"127.0.0.1:36820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T06:07:13.686491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:07:13.366827Z","time spent":"319.529579ms","remote":"127.0.0.1:36820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T06:07:13.686654Z","caller":"traceutil/trace.go:171","msg":"trace[487209279] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"313.015328ms","start":"2026-04-02T06:07:13.373622Z","end":"2026-04-02T06:07:13.686637Z","steps":["trace[487209279] 'process raft request' (duration: 312.328228ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:07:13.686716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:07:13.373596Z","time spent":"313.093051ms","remote":"127.0.0.1:36820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T06:07:13.68683Z","caller":"traceutil/trace.go:171","msg":"trace[282815733] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"304.247831ms","start":"2026-04-02T06:07:13.382553Z","end":"2026-04-02T06:07:13.686801Z","steps":["trace[282815733] 'process raft request' (duration: 303.983593ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:07:13.686898Z","caller":"traceutil/trace.go:171","msg":"trace[1228978714] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"299.537602ms","start":"2026-04-02T06:07:13.387348Z","end":"2026-04-02T06:07:13.686885Z","steps":["trace[1228978714] 'process raft request' (duration: 299.332347ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:07:13.686978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:07:13.382534Z","time spent":"304.370694ms","remote":"127.0.0.1:36820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T06:07:13.687049Z","caller":"traceutil/trace.go:171","msg":"trace[893023177] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"284.50031ms","start":"2026-04-02T06:07:13.402535Z","end":"2026-04-02T06:07:13.687036Z","steps":["trace[893023177] 'process raft request' (duration: 284.262393ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:07:13.687063Z","caller":"traceutil/trace.go:171","msg":"trace[1475597585] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"158.887969ms","start":"2026-04-02T06:07:13.528154Z","end":"2026-04-02T06:07:13.687042Z","steps":["trace[1475597585] 'process raft request' (duration: 158.825637ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:07:13.687126Z","caller":"traceutil/trace.go:171","msg":"trace[1429300118] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"251.863782ms","start":"2026-04-02T06:07:13.435251Z","end":"2026-04-02T06:07:13.687115Z","steps":["trace[1429300118] 'process raft request' (duration: 251.651566ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:07:13.725059Z","caller":"traceutil/trace.go:171","msg":"trace[1548052051] transaction","detail":"{read_only:false; number_of_response:1; response_revision:523; }","duration":"113.023281ms","start":"2026-04-02T06:07:13.612013Z","end":"2026-04-02T06:07:13.725037Z","steps":["trace[1548052051] 'process raft request' (duration: 112.98671ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:07:13.725353Z","caller":"traceutil/trace.go:171","msg":"trace[483062397] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"115.690211ms","start":"2026-04-02T06:07:13.609653Z","end":"2026-04-02T06:07:13.725343Z","steps":["trace[483062397] 'process raft request' (duration: 115.194356ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:07:13.725523Z","caller":"traceutil/trace.go:171","msg":"trace[2118725373] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"114.723972ms","start":"2026-04-02T06:07:13.61079Z","end":"2026-04-02T06:07:13.725514Z","steps":["trace[2118725373] 'process raft request' (duration: 114.163815ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:07:13.892709Z","caller":"traceutil/trace.go:171","msg":"trace[1580566053] transaction","detail":"{read_only:false; number_of_response:0; response_revision:533; }","duration":"154.971385ms","start":"2026-04-02T06:07:13.737692Z","end":"2026-04-02T06:07:13.892663Z","steps":["trace[1580566053] 'process raft request' (duration: 154.944444ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:07:13.892758Z","caller":"traceutil/trace.go:171","msg":"trace[695690763] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"155.356105ms","start":"2026-04-02T06:07:13.73736Z","end":"2026-04-02T06:07:13.892716Z","steps":["trace[695690763] 'process raft request' (duration: 103.178191ms)","trace[695690763] 'compare' (duration: 51.935626ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T06:07:13.892935Z","caller":"traceutil/trace.go:171","msg":"trace[1176991250] transaction","detail":"{read_only:false; number_of_response:0; response_revision:533; }","duration":"155.287564ms","start":"2026-04-02T06:07:13.737598Z","end":"2026-04-02T06:07:13.892885Z","steps":["trace[1176991250] 'process raft request' (duration: 155.006266ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:07:13.894845Z","caller":"traceutil/trace.go:171","msg":"trace[97052478] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"129.192807ms","start":"2026-04-02T06:07:13.765627Z","end":"2026-04-02T06:07:13.89482Z","steps":["trace[97052478] 'process raft request' (duration: 128.929609ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:07:13.895129Z","caller":"traceutil/trace.go:171","msg":"trace[2095076764] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"127.565429ms","start":"2026-04-02T06:07:13.767542Z","end":"2026-04-02T06:07:13.895107Z","steps":["trace[2095076764] 'process raft request' (duration: 127.23322ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:07:13.895372Z","caller":"traceutil/trace.go:171","msg":"trace[1119167490] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"124.641993ms","start":"2026-04-02T06:07:13.770718Z","end":"2026-04-02T06:07:13.89536Z","steps":["trace[1119167490] 'process raft request' (duration: 124.321073ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:07:13.89653Z","caller":"traceutil/trace.go:171","msg":"trace[924093021] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"113.457364ms","start":"2026-04-02T06:07:13.783056Z","end":"2026-04-02T06:07:13.896513Z","steps":["trace[924093021] 'process raft request' (duration: 112.149496ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:07:13.89674Z","caller":"traceutil/trace.go:171","msg":"trace[1385496453] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"113.382851ms","start":"2026-04-02T06:07:13.783308Z","end":"2026-04-02T06:07:13.89669Z","steps":["trace[1385496453] 'process raft request' (duration: 113.089653ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:07:19.17464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.922811ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-02T06:07:19.174735Z","caller":"traceutil/trace.go:171","msg":"trace[612527633] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"358.134863ms","start":"2026-04-02T06:07:18.816586Z","end":"2026-04-02T06:07:19.17472Z","steps":["trace[612527633] 'process raft request' (duration: 251.079338ms)","trace[612527633] 'compare' (duration: 106.780348ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T06:07:19.174774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:07:18.816561Z","time spent":"358.197736ms","remote":"127.0.0.1:37100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T06:07:20.24947Z","caller":"traceutil/trace.go:171","msg":"trace[1975392978] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"197.498674ms","start":"2026-04-02T06:07:20.051946Z","end":"2026-04-02T06:07:20.249445Z","steps":["trace[1975392978] 'process raft request' (duration: 197.01137ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:07:20.249624Z","caller":"traceutil/trace.go:171","msg":"trace[2044393397] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"203.982244ms","start":"2026-04-02T06:07:20.045604Z","end":"2026-04-02T06:07:20.249587Z","steps":["trace[2044393397] 'process raft request' (duration: 202.987794ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:07:21.346477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.357023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T06:07:21.346547Z","caller":"traceutil/trace.go:171","msg":"trace[916799227] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:621; }","duration":"615.460766ms","start":"2026-04-02T06:07:20.731069Z","end":"2026-04-02T06:07:21.34653Z","steps":["trace[916799227] 'range keys from in-memory index tree' (duration: 615.24828ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:07:21.346576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:07:20.731051Z","time spent":"615.518157ms","remote":"127.0.0.1:36800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T06:07:21.346735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"913.093701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-wnvgj\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-02T06:07:21.346845Z","caller":"traceutil/trace.go:171","msg":"trace[2029734807] range","detail":"{range_begin:/registry/pods/kube-system/cilium-wnvgj; range_end:; response_count:1; response_revision:621; }","duration":"913.304419ms","start":"2026-04-02T06:07:20.433499Z","end":"2026-04-02T06:07:21.346804Z","steps":["trace[2029734807] 'range keys from in-memory index tree' (duration: 912.983378ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:07:21.346934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:07:20.433481Z","time spent":"913.432962ms","remote":"127.0.0.1:36924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-wnvgj\" "} {"level":"info","ts":"2026-04-02T06:07:21.39253Z","caller":"traceutil/trace.go:171","msg":"trace[869509918] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"637.248936ms","start":"2026-04-02T06:07:20.755242Z","end":"2026-04-02T06:07:21.392491Z","steps":["trace[869509918] 'process raft request' (duration: 635.779622ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:07:21.392687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:07:20.75519Z","time spent":"637.41996ms","remote":"127.0.0.1:36840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T06:07:21.403435Z","caller":"traceutil/trace.go:171","msg":"trace[1004614321] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"629.694364ms","start":"2026-04-02T06:07:20.773719Z","end":"2026-04-02T06:07:21.403413Z","steps":["trace[1004614321] 'process raft request' (duration: 629.514299ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:07:21.403572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:07:20.773684Z","time spent":"629.824378ms","remote":"127.0.0.1:37010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T06:07:21.403689Z","caller":"traceutil/trace.go:171","msg":"trace[595485354] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"443.996257ms","start":"2026-04-02T06:07:20.959683Z","end":"2026-04-02T06:07:21.403679Z","steps":["trace[595485354] 'process raft request' (duration: 443.689838ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:07:21.403735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:07:20.959656Z","time spent":"444.055479ms","remote":"127.0.0.1:36908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T06:07:22.717774Z","caller":"traceutil/trace.go:171","msg":"trace[1138614243] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"148.628378ms","start":"2026-04-02T06:07:22.569124Z","end":"2026-04-02T06:07:22.717752Z","steps":["trace[1138614243] 'process raft request' (duration: 148.564996ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:07:22.71798Z","caller":"traceutil/trace.go:171","msg":"trace[1527012171] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"151.119821ms","start":"2026-04-02T06:07:22.566826Z","end":"2026-04-02T06:07:22.717946Z","steps":["trace[1527012171] 'process raft request' (duration: 149.838803ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:07:32.496276Z","caller":"traceutil/trace.go:171","msg":"trace[617703614] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"304.725595ms","start":"2026-04-02T06:07:32.191527Z","end":"2026-04-02T06:07:32.496253Z","steps":["trace[617703614] 'process raft request' (duration: 304.56084ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:07:32.496443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:07:32.191508Z","time spent":"304.852889ms","remote":"127.0.0.1:37010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T06:07:32.512959Z","caller":"traceutil/trace.go:171","msg":"trace[1573144934] linearizableReadLoop","detail":"{readStateIndex:783; appliedIndex:781; }","duration":"211.446824ms","start":"2026-04-02T06:07:32.301492Z","end":"2026-04-02T06:07:32.512939Z","steps":["trace[1573144934] 'read index received' (duration: 194.523757ms)","trace[1573144934] 'applied index is now lower than readState.Index' (duration: 16.921647ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T06:07:32.513291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.782314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-02T06:07:32.513379Z","caller":"traceutil/trace.go:171","msg":"trace[910897618] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:758; }","duration":"211.916568ms","start":"2026-04-02T06:07:32.301444Z","end":"2026-04-02T06:07:32.513361Z","steps":["trace[910897618] 'agreement among raft nodes before linearized reading' (duration: 211.64942ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:07:32.513531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.238108ms","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-02T06:07:32.513579Z","caller":"traceutil/trace.go:171","msg":"trace[1555054594] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:758; }","duration":"143.336091ms","start":"2026-04-02T06:07:32.370232Z","end":"2026-04-02T06:07:32.513568Z","steps":["trace[1555054594] 'agreement among raft nodes before linearized reading' (duration: 143.127385ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:07:35.856345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.839518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T06:07:35.856421Z","caller":"traceutil/trace.go:171","msg":"trace[1617838764] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:782; }","duration":"125.966331ms","start":"2026-04-02T06:07:35.730439Z","end":"2026-04-02T06:07:35.856405Z","steps":["trace[1617838764] 'range keys from in-memory index tree' (duration: 125.759307ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:07:35.856555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.998914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-zdcj7\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-04-02T06:07:35.856616Z","caller":"traceutil/trace.go:171","msg":"trace[1952472663] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-zdcj7; range_end:; response_count:1; response_revision:782; }","duration":"318.112368ms","start":"2026-04-02T06:07:35.538487Z","end":"2026-04-02T06:07:35.8566Z","steps":["trace[1952472663] 'range keys from in-memory index tree' (duration: 317.862581ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:07:35.856645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:07:35.538469Z","time spent":"318.16796ms","remote":"127.0.0.1:36924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-zdcj7\" "} {"level":"info","ts":"2026-04-02T06:07:36.183841Z","caller":"traceutil/trace.go:171","msg":"trace[583369396] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"313.726129ms","start":"2026-04-02T06:07:35.870091Z","end":"2026-04-02T06:07:36.183817Z","steps":["trace[583369396] 'process raft request' (duration: 313.579585ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:07:36.184007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:07:35.870072Z","time spent":"313.841942ms","remote":"127.0.0.1:36924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T06:07:52.797106Z","caller":"traceutil/trace.go:171","msg":"trace[137397037] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"120.295919ms","start":"2026-04-02T06:07:52.676771Z","end":"2026-04-02T06:07:52.797067Z","steps":["trace[137397037] 'process raft request' (duration: 80.017337ms)","trace[137397037] 'compare' (duration: 40.093067ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T06:08:05.069779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.663947ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T06:08:05.069948Z","caller":"traceutil/trace.go:171","msg":"trace[664008268] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"221.307007ms","start":"2026-04-02T06:08:04.848625Z","end":"2026-04-02T06:08:05.069932Z","steps":["trace[664008268] 'process raft request' (duration: 221.247866ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:08:05.07002Z","caller":"traceutil/trace.go:171","msg":"trace[1307661940] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"221.883432ms","start":"2026-04-02T06:08:04.848092Z","end":"2026-04-02T06:08:05.069976Z","steps":["trace[1307661940] 'process raft request' (duration: 102.945319ms)","trace[1307661940] 'compare' (duration: 118.532503ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T06:08:11.281252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.681866ms","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-02T06:08:11.281318Z","caller":"traceutil/trace.go:171","msg":"trace[498242945] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:996; }","duration":"356.788579ms","start":"2026-04-02T06:08:10.924512Z","end":"2026-04-02T06:08:11.2813Z","steps":["trace[498242945] 'range keys from in-memory index tree' (duration: 356.550973ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:08:11.281353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:08:10.924489Z","time spent":"356.85531ms","remote":"127.0.0.1:37010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-02T06:08:11.281496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.379273ms","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-02T06:08:11.281584Z","caller":"traceutil/trace.go:171","msg":"trace[884768614] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:996; }","duration":"527.512175ms","start":"2026-04-02T06:08:10.754052Z","end":"2026-04-02T06:08:11.281564Z","steps":["trace[884768614] 'range keys from in-memory index tree' (duration: 527.142336ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:08:11.28162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:08:10.754026Z","time spent":"527.585148ms","remote":"127.0.0.1:37010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-02T06:08:11.281636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.192054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-02T06:08:11.281711Z","caller":"traceutil/trace.go:171","msg":"trace[1798479451] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:996; }","duration":"464.348728ms","start":"2026-04-02T06:08:10.817341Z","end":"2026-04-02T06:08:11.281689Z","steps":["trace[1798479451] 'range keys from in-memory index tree' (duration: 463.995969ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:08:11.281747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:08:10.817245Z","time spent":"464.492773ms","remote":"127.0.0.1:37010","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-02T06:08:11.546814Z","caller":"traceutil/trace.go:171","msg":"trace[486942281] linearizableReadLoop","detail":"{readStateIndex:1031; appliedIndex:1030; }","duration":"453.749104ms","start":"2026-04-02T06:08:11.093029Z","end":"2026-04-02T06:08:11.546778Z","steps":["trace[486942281] 'read index received' (duration: 453.420255ms)","trace[486942281] 'applied index is now lower than readState.Index' (duration: 326.769µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T06:08:11.546962Z","caller":"traceutil/trace.go:171","msg":"trace[648572916] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"460.304493ms","start":"2026-04-02T06:08:11.08661Z","end":"2026-04-02T06:08:11.546914Z","steps":["trace[648572916] 'process raft request' (duration: 459.81211ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:08:11.547159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.093262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-04-02T06:08:11.54723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:08:11.086589Z","time spent":"460.506049ms","remote":"127.0.0.1:37010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T06:08:11.547266Z","caller":"traceutil/trace.go:171","msg":"trace[257570583] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:997; }","duration":"454.242705ms","start":"2026-04-02T06:08:11.093001Z","end":"2026-04-02T06:08:11.547244Z","steps":["trace[257570583] 'agreement among raft nodes before linearized reading' (duration: 453.940318ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:08:11.547325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:08:11.092993Z","time spent":"454.323128ms","remote":"127.0.0.1:37010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-02T06:08:11.559768Z","caller":"traceutil/trace.go:171","msg":"trace[1879917960] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"273.047508ms","start":"2026-04-02T06:08:11.286702Z","end":"2026-04-02T06:08:11.55975Z","steps":["trace[1879917960] 'process raft request' (duration: 273.007227ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:08:11.560145Z","caller":"traceutil/trace.go:171","msg":"trace[401322074] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"274.858316ms","start":"2026-04-02T06:08:11.285273Z","end":"2026-04-02T06:08:11.560131Z","steps":["trace[401322074] 'process raft request' (duration: 274.282831ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:08:11.560276Z","caller":"traceutil/trace.go:171","msg":"trace[853252495] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"274.706861ms","start":"2026-04-02T06:08:11.28556Z","end":"2026-04-02T06:08:11.560267Z","steps":["trace[853252495] 'process raft request' (duration: 274.109016ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:08:34.3711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.653239ms","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-02T06:08:34.371197Z","caller":"traceutil/trace.go:171","msg":"trace[1745687571] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1070; }","duration":"316.759782ms","start":"2026-04-02T06:08:34.054424Z","end":"2026-04-02T06:08:34.371184Z","steps":["trace[1745687571] 'range keys from in-memory index tree' (duration: 316.644119ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:08:34.371448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.561635ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T06:08:34.371546Z","caller":"traceutil/trace.go:171","msg":"trace[1385105741] linearizableReadLoop","detail":"{readStateIndex:1110; appliedIndex:1109; }","duration":"712.231024ms","start":"2026-04-02T06:08:33.659295Z","end":"2026-04-02T06:08:34.371526Z","steps":["trace[1385105741] 'read index received' (duration: 355.507145ms)","trace[1385105741] 'applied index is now lower than readState.Index' (duration: 356.722189ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T06:08:34.371587Z","caller":"traceutil/trace.go:171","msg":"trace[619346790] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"713.728513ms","start":"2026-04-02T06:08:33.65784Z","end":"2026-04-02T06:08:34.371569Z","steps":["trace[619346790] 'process raft request' (duration: 356.971064ms)","trace[619346790] 'compare' (duration: 356.201754ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T06:08:34.371641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:08:33.657823Z","time spent":"713.790965ms","remote":"127.0.0.1:37010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T06:08:34.371778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"712.472431ms","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-02T06:08:34.371836Z","caller":"traceutil/trace.go:171","msg":"trace[1237500056] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1071; }","duration":"712.542012ms","start":"2026-04-02T06:08:33.659279Z","end":"2026-04-02T06:08:34.371821Z","steps":["trace[1237500056] 'agreement among raft nodes before linearized reading' (duration: 712.346517ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:08:34.371821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.03861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T06:08:34.371865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"694.70936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-02T06:08:34.371891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:08:33.659273Z","time spent":"712.605344ms","remote":"127.0.0.1:37010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-02T06:08:34.371922Z","caller":"traceutil/trace.go:171","msg":"trace[1223791312] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1071; }","duration":"694.785472ms","start":"2026-04-02T06:08:33.677128Z","end":"2026-04-02T06:08:34.371913Z","steps":["trace[1223791312] 'agreement among raft nodes before linearized reading' (duration: 694.659949ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:08:34.371908Z","caller":"traceutil/trace.go:171","msg":"trace[2012498060] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1071; }","duration":"643.143183ms","start":"2026-04-02T06:08:33.728746Z","end":"2026-04-02T06:08:34.371889Z","steps":["trace[2012498060] 'agreement among raft nodes before linearized reading' (duration: 642.974989ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:08:34.371945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:08:33.677117Z","time spent":"694.821834ms","remote":"127.0.0.1:37010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-02T06:08:34.371968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:08:33.728701Z","time spent":"643.251616ms","remote":"127.0.0.1:36800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T06:08:34.371952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.986694ms","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-02T06:08:34.371956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.237863ms","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":"info","ts":"2026-04-02T06:08:34.372057Z","caller":"traceutil/trace.go:171","msg":"trace[1022283609] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1071; }","duration":"708.280403ms","start":"2026-04-02T06:08:33.663745Z","end":"2026-04-02T06:08:34.372026Z","steps":["trace[1022283609] 'agreement among raft nodes before linearized reading' (duration: 707.920993ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:08:34.372103Z","caller":"traceutil/trace.go:171","msg":"trace[1353092330] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1071; }","duration":"575.465589ms","start":"2026-04-02T06:08:33.796622Z","end":"2026-04-02T06:08:34.372088Z","steps":["trace[1353092330] 'agreement among raft nodes before linearized reading' (duration: 575.1423ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:08:34.372116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:08:33.66374Z","time spent":"708.358905ms","remote":"127.0.0.1:37010","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-02T06:08:34.372139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:08:33.796603Z","time spent":"575.526571ms","remote":"127.0.0.1:43130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-02T06:08:34.63038Z","caller":"traceutil/trace.go:171","msg":"trace[1330115216] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"253.271806ms","start":"2026-04-02T06:08:34.377079Z","end":"2026-04-02T06:08:34.630351Z","steps":["trace[1330115216] 'process raft request' (duration: 253.179693ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:08:34.630419Z","caller":"traceutil/trace.go:171","msg":"trace[1864919856] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"253.189594ms","start":"2026-04-02T06:08:34.377196Z","end":"2026-04-02T06:08:34.630386Z","steps":["trace[1864919856] 'process raft request' (duration: 253.099332ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:08:34.63056Z","caller":"traceutil/trace.go:171","msg":"trace[263725043] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"254.056977ms","start":"2026-04-02T06:08:34.376488Z","end":"2026-04-02T06:08:34.630545Z","steps":["trace[263725043] 'process raft request' (duration: 190.14328ms)","trace[263725043] 'compare' (duration: 63.510236ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T06:08:36.04519Z","caller":"traceutil/trace.go:171","msg":"trace[1373670846] transaction","detail":"{read_only:false; response_revision:1076; number_of_response:1; }","duration":"410.39757ms","start":"2026-04-02T06:08:35.634766Z","end":"2026-04-02T06:08:36.045164Z","steps":["trace[1373670846] 'process raft request' (duration: 410.169094ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:08:36.045357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:08:35.634742Z","time spent":"410.519514ms","remote":"127.0.0.1:37010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T06:08:36.900411Z","caller":"traceutil/trace.go:171","msg":"trace[2074162724] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"261.250343ms","start":"2026-04-02T06:08:36.639128Z","end":"2026-04-02T06:08:36.900379Z","steps":["trace[2074162724] 'process raft request' (duration: 260.999217ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:08:36.90058Z","caller":"traceutil/trace.go:171","msg":"trace[1184250936] linearizableReadLoop","detail":"{readStateIndex:1119; appliedIndex:1117; }","duration":"223.430422ms","start":"2026-04-02T06:08:36.677121Z","end":"2026-04-02T06:08:36.900552Z","steps":["trace[1184250936] 'read index received' (duration: 222.94701ms)","trace[1184250936] 'applied index is now lower than readState.Index' (duration: 481.622µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T06:08:36.900632Z","caller":"traceutil/trace.go:171","msg":"trace[1528460561] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"260.997396ms","start":"2026-04-02T06:08:36.639584Z","end":"2026-04-02T06:08:36.900582Z","steps":["trace[1528460561] 'process raft request' (duration: 260.719709ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:08:36.900827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.687879ms","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-02T06:08:36.900861Z","caller":"traceutil/trace.go:171","msg":"trace[76311615] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1080; }","duration":"223.767211ms","start":"2026-04-02T06:08:36.677083Z","end":"2026-04-02T06:08:36.900851Z","steps":["trace[76311615] 'agreement among raft nodes before linearized reading' (duration: 223.601787ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:08:36.900933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.231733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T06:08:36.901019Z","caller":"traceutil/trace.go:171","msg":"trace[1543142278] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1080; }","duration":"170.365626ms","start":"2026-04-02T06:08:36.730635Z","end":"2026-04-02T06:08:36.901001Z","steps":["trace[1543142278] 'agreement among raft nodes before linearized reading' (duration: 170.231543ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:08:37.056763Z","caller":"traceutil/trace.go:171","msg":"trace[1823708600] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"152.258027ms","start":"2026-04-02T06:08:36.90447Z","end":"2026-04-02T06:08:37.056728Z","steps":["trace[1823708600] 'process raft request' (duration: 79.503181ms)","trace[1823708600] 'compare' (duration: 72.621883ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T06:08:37.412657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.350076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.195\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-02T06:08:37.412735Z","caller":"traceutil/trace.go:171","msg":"trace[299586348] range","detail":"{range_begin:/registry/masterleases/199.19.213.195; range_end:; response_count:1; response_revision:1081; }","duration":"175.474709ms","start":"2026-04-02T06:08:37.237238Z","end":"2026-04-02T06:08:37.412713Z","steps":["trace[299586348] 'range keys from in-memory index tree' (duration: 174.984717ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:08:46.502979Z","caller":"traceutil/trace.go:171","msg":"trace[1649353747] transaction","detail":"{read_only:false; response_revision:1110; number_of_response:1; }","duration":"108.878052ms","start":"2026-04-02T06:08:46.39407Z","end":"2026-04-02T06:08:46.502948Z","steps":["trace[1649353747] 'process raft request' (duration: 108.685057ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:08:46.926968Z","caller":"traceutil/trace.go:171","msg":"trace[1120033542] transaction","detail":"{read_only:false; response_revision:1126; number_of_response:1; }","duration":"101.458071ms","start":"2026-04-02T06:08:46.825476Z","end":"2026-04-02T06:08:46.926934Z","steps":["trace[1120033542] 'process raft request' (duration: 50.816367ms)","trace[1120033542] 'compare' (duration: 50.466449ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T06:08:46.958414Z","caller":"traceutil/trace.go:171","msg":"trace[845481837] transaction","detail":"{read_only:false; response_revision:1128; number_of_response:1; }","duration":"129.949879ms","start":"2026-04-02T06:08:46.828418Z","end":"2026-04-02T06:08:46.958367Z","steps":["trace[845481837] 'process raft request' (duration: 129.858176ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:08:46.958437Z","caller":"traceutil/trace.go:171","msg":"trace[1082567594] transaction","detail":"{read_only:false; response_revision:1127; number_of_response:1; }","duration":"130.570785ms","start":"2026-04-02T06:08:46.827821Z","end":"2026-04-02T06:08:46.958391Z","steps":["trace[1082567594] 'process raft request' (duration: 130.249696ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:08:47.069937Z","caller":"traceutil/trace.go:171","msg":"trace[2071466830] linearizableReadLoop","detail":"{readStateIndex:1176; appliedIndex:1173; }","duration":"100.29972ms","start":"2026-04-02T06:08:46.969615Z","end":"2026-04-02T06:08:47.069914Z","steps":["trace[2071466830] 'read index received' (duration: 2.927326ms)","trace[2071466830] 'applied index is now lower than readState.Index' (duration: 97.371224ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T06:08:47.069951Z","caller":"traceutil/trace.go:171","msg":"trace[356007379] transaction","detail":"{read_only:false; response_revision:1133; number_of_response:1; }","duration":"101.358698ms","start":"2026-04-02T06:08:46.968553Z","end":"2026-04-02T06:08:47.069912Z","steps":["trace[356007379] 'process raft request' (duration: 101.066121ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:08:47.070187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.517325ms","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-02T06:08:47.070211Z","caller":"traceutil/trace.go:171","msg":"trace[1229549331] transaction","detail":"{read_only:false; response_revision:1134; number_of_response:1; }","duration":"101.565762ms","start":"2026-04-02T06:08:46.968604Z","end":"2026-04-02T06:08:47.07017Z","steps":["trace[1229549331] 'process raft request' (duration: 101.172403ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:08:47.070231Z","caller":"traceutil/trace.go:171","msg":"trace[2090773535] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1135; }","duration":"100.627238ms","start":"2026-04-02T06:08:46.969592Z","end":"2026-04-02T06:08:47.07022Z","steps":["trace[2090773535] 'agreement among raft nodes before linearized reading' (duration: 100.409842ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:08:47.223439Z","caller":"traceutil/trace.go:171","msg":"trace[2141163808] linearizableReadLoop","detail":"{readStateIndex:1180; appliedIndex:1177; }","duration":"132.092283ms","start":"2026-04-02T06:08:47.091326Z","end":"2026-04-02T06:08:47.223419Z","steps":["trace[2141163808] 'read index received' (duration: 29.844173ms)","trace[2141163808] 'applied index is now lower than readState.Index' (duration: 102.24708ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T06:08:47.223517Z","caller":"traceutil/trace.go:171","msg":"trace[1203845147] transaction","detail":"{read_only:false; response_revision:1137; number_of_response:1; }","duration":"144.837274ms","start":"2026-04-02T06:08:47.078653Z","end":"2026-04-02T06:08:47.22349Z","steps":["trace[1203845147] 'process raft request' (duration: 89.839249ms)","trace[1203845147] 'compare' (duration: 54.694758ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T06:08:47.223608Z","caller":"traceutil/trace.go:171","msg":"trace[18698547] transaction","detail":"{read_only:false; response_revision:1138; number_of_response:1; }","duration":"144.851176ms","start":"2026-04-02T06:08:47.078739Z","end":"2026-04-02T06:08:47.22359Z","steps":["trace[18698547] 'process raft request' (duration: 144.587898ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:08:47.223764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.422042ms","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-02T06:08:47.223805Z","caller":"traceutil/trace.go:171","msg":"trace[697451208] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1138; }","duration":"132.504515ms","start":"2026-04-02T06:08:47.091289Z","end":"2026-04-02T06:08:47.223793Z","steps":["trace[697451208] 'agreement among raft nodes before linearized reading' (duration: 132.32765ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:08:47.396785Z","caller":"traceutil/trace.go:171","msg":"trace[1461876104] transaction","detail":"{read_only:false; response_revision:1144; number_of_response:1; }","duration":"100.993888ms","start":"2026-04-02T06:08:47.295768Z","end":"2026-04-02T06:08:47.396762Z","steps":["trace[1461876104] 'process raft request' (duration: 37.270666ms)","trace[1461876104] 'compare' (duration: 63.581228ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T06:08:47.396893Z","caller":"traceutil/trace.go:171","msg":"trace[804375608] transaction","detail":"{read_only:false; response_revision:1145; number_of_response:1; }","duration":"100.813514ms","start":"2026-04-02T06:08:47.296039Z","end":"2026-04-02T06:08:47.396852Z","steps":["trace[804375608] 'process raft request' (duration: 100.674021ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:08:47.39725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.03943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T06:08:47.397292Z","caller":"traceutil/trace.go:171","msg":"trace[1452413781] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1146; }","duration":"106.121322ms","start":"2026-04-02T06:08:47.291159Z","end":"2026-04-02T06:08:47.39728Z","steps":["trace[1452413781] 'agreement among raft nodes before linearized reading' (duration: 106.027289ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:08:47.424784Z","caller":"traceutil/trace.go:171","msg":"trace[604741561] transaction","detail":"{read_only:false; response_revision:1147; number_of_response:1; }","duration":"118.581784ms","start":"2026-04-02T06:08:47.306183Z","end":"2026-04-02T06:08:47.424765Z","steps":["trace[604741561] 'process raft request' (duration: 118.370579ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:08:47.425023Z","caller":"traceutil/trace.go:171","msg":"trace[796370551] transaction","detail":"{read_only:false; response_revision:1149; number_of_response:1; }","duration":"114.093788ms","start":"2026-04-02T06:08:47.310918Z","end":"2026-04-02T06:08:47.425012Z","steps":["trace[796370551] 'process raft request' (duration: 113.78611ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:08:47.425166Z","caller":"traceutil/trace.go:171","msg":"trace[900109470] transaction","detail":"{read_only:false; response_revision:1148; number_of_response:1; }","duration":"118.498122ms","start":"2026-04-02T06:08:47.306651Z","end":"2026-04-02T06:08:47.425149Z","steps":["trace[900109470] 'process raft request' (duration: 117.99003ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:08:50.765195Z","caller":"traceutil/trace.go:171","msg":"trace[664208312] transaction","detail":"{read_only:false; response_revision:1180; number_of_response:1; }","duration":"237.684211ms","start":"2026-04-02T06:08:50.52749Z","end":"2026-04-02T06:08:50.765174Z","steps":["trace[664208312] 'process raft request' (duration: 237.567718ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:08:52.266011Z","caller":"traceutil/trace.go:171","msg":"trace[519075753] transaction","detail":"{read_only:false; response_revision:1185; number_of_response:1; }","duration":"126.282913ms","start":"2026-04-02T06:08:52.139704Z","end":"2026-04-02T06:08:52.265987Z","steps":["trace[519075753] 'process raft request' (duration: 126.112439ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:08:53.31483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.719577ms","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-02T06:08:53.314905Z","caller":"traceutil/trace.go:171","msg":"trace[467843967] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1185; }","duration":"545.84488ms","start":"2026-04-02T06:08:52.769043Z","end":"2026-04-02T06:08:53.314888Z","steps":["trace[467843967] 'range keys from in-memory index tree' (duration: 545.512802ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:08:53.31491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.465889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-02T06:08:53.314943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:08:52.769016Z","time spent":"545.916561ms","remote":"127.0.0.1:37010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-02T06:08:53.314983Z","caller":"traceutil/trace.go:171","msg":"trace[517605746] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1185; }","duration":"695.585512ms","start":"2026-04-02T06:08:52.619375Z","end":"2026-04-02T06:08:53.314961Z","steps":["trace[517605746] 'count revisions from in-memory index tree' (duration: 695.374587ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:08:53.315035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:08:52.619354Z","time spent":"695.668494ms","remote":"127.0.0.1:37200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-04-02T06:08:53.315085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.544345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T06:08:53.315115Z","caller":"traceutil/trace.go:171","msg":"trace[1485198990] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1185; }","duration":"586.606488ms","start":"2026-04-02T06:08:52.7285Z","end":"2026-04-02T06:08:53.315106Z","steps":["trace[1485198990] 'range keys from in-memory index tree' (duration: 586.478514ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:08:53.315135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:08:52.728483Z","time spent":"586.646828ms","remote":"127.0.0.1:36798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-02T06:08:53.383738Z","caller":"traceutil/trace.go:171","msg":"trace[779948787] linearizableReadLoop","detail":"{readStateIndex:1231; appliedIndex:1229; }","duration":"403.124371ms","start":"2026-04-02T06:08:52.98059Z","end":"2026-04-02T06:08:53.383715Z","steps":["trace[779948787] 'read index received' (duration: 311.509266ms)","trace[779948787] 'applied index is now lower than readState.Index' (duration: 91.614125ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T06:08:53.383852Z","caller":"traceutil/trace.go:171","msg":"trace[928093272] transaction","detail":"{read_only:false; response_revision:1186; number_of_response:1; }","duration":"433.728975ms","start":"2026-04-02T06:08:52.950097Z","end":"2026-04-02T06:08:53.383826Z","steps":["trace[928093272] 'process raft request' (duration: 433.476698ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:08:53.383925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.325336ms","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-02T06:08:53.383964Z","caller":"traceutil/trace.go:171","msg":"trace[1971858194] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1186; }","duration":"403.412798ms","start":"2026-04-02T06:08:52.980541Z","end":"2026-04-02T06:08:53.383954Z","steps":["trace[1971858194] 'agreement among raft nodes before linearized reading' (duration: 403.278775ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:08:53.383993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:08:52.980522Z","time spent":"403.463489ms","remote":"127.0.0.1:37010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-02T06:08:53.383993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:08:52.950077Z","time spent":"433.850038ms","remote":"127.0.0.1:37010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T06:08:53.479335Z","caller":"traceutil/trace.go:171","msg":"trace[337886205] transaction","detail":"{read_only:false; response_revision:1187; number_of_response:1; }","duration":"161.710542ms","start":"2026-04-02T06:08:53.317595Z","end":"2026-04-02T06:08:53.479305Z","steps":["trace[337886205] 'process raft request' (duration: 157.40276ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:08:53.479623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.511998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T06:08:53.479658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.862565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-04-02T06:08:53.479662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.765001ms","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-02T06:08:53.479676Z","caller":"traceutil/trace.go:171","msg":"trace[1728223416] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1188; }","duration":"163.602812ms","start":"2026-04-02T06:08:53.316056Z","end":"2026-04-02T06:08:53.479659Z","steps":["trace[1728223416] 'agreement among raft nodes before linearized reading' (duration: 163.506578ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:08:53.479709Z","caller":"traceutil/trace.go:171","msg":"trace[712105686] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1188; }","duration":"338.955129ms","start":"2026-04-02T06:08:53.140739Z","end":"2026-04-02T06:08:53.479694Z","steps":["trace[712105686] 'agreement among raft nodes before linearized reading' (duration: 338.841265ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:08:53.479713Z","caller":"traceutil/trace.go:171","msg":"trace[2082406325] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1188; }","duration":"223.859624ms","start":"2026-04-02T06:08:53.255842Z","end":"2026-04-02T06:08:53.479701Z","steps":["trace[2082406325] 'agreement among raft nodes before linearized reading' (duration: 223.709509ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:08:53.479736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:08:53.14072Z","time spent":"339.009289ms","remote":"127.0.0.1:37010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-02T06:08:53.47985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.96787ms","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-02T06:08:53.47988Z","caller":"traceutil/trace.go:171","msg":"trace[433293690] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1188; }","duration":"390.033501ms","start":"2026-04-02T06:08:53.089838Z","end":"2026-04-02T06:08:53.479872Z","steps":["trace[433293690] 'agreement among raft nodes before linearized reading' (duration: 389.511777ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:08:53.479895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:08:53.089818Z","time spent":"390.072532ms","remote":"127.0.0.1:37010","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-02T06:09:05.991344Z","caller":"traceutil/trace.go:171","msg":"trace[482399479] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"397.832824ms","start":"2026-04-02T06:09:05.593482Z","end":"2026-04-02T06:09:05.991315Z","steps":["trace[482399479] 'process raft request' (duration: 397.632049ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:09:05.991497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:09:05.593466Z","time spent":"397.952406ms","remote":"127.0.0.1:37010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T06:09:05.995015Z","caller":"traceutil/trace.go:171","msg":"trace[418416094] linearizableReadLoop","detail":"{readStateIndex:1274; appliedIndex:1272; }","duration":"360.29879ms","start":"2026-04-02T06:09:05.63468Z","end":"2026-04-02T06:09:05.994978Z","steps":["trace[418416094] 'read index received' (duration: 356.591244ms)","trace[418416094] 'applied index is now lower than readState.Index' (duration: 3.705856ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T06:09:05.995238Z","caller":"traceutil/trace.go:171","msg":"trace[1236081876] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"395.467072ms","start":"2026-04-02T06:09:05.599745Z","end":"2026-04-02T06:09:05.995212Z","steps":["trace[1236081876] 'process raft request' (duration: 394.99034ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:09:05.995426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:09:05.59971Z","time spent":"395.596686ms","remote":"127.0.0.1:37010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T06:09:05.995461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.759113ms","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-02T06:09:05.995512Z","caller":"traceutil/trace.go:171","msg":"trace[1415708685] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1227; }","duration":"360.853064ms","start":"2026-04-02T06:09:05.634646Z","end":"2026-04-02T06:09:05.995499Z","steps":["trace[1415708685] 'agreement among raft nodes before linearized reading' (duration: 360.591748ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:09:05.99558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:09:05.634635Z","time spent":"360.930398ms","remote":"127.0.0.1:37010","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-02T06:09:05.996266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.945822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T06:09:05.996314Z","caller":"traceutil/trace.go:171","msg":"trace[878789642] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1227; }","duration":"252.028234ms","start":"2026-04-02T06:09:05.744272Z","end":"2026-04-02T06:09:05.9963Z","steps":["trace[878789642] 'agreement among raft nodes before linearized reading' (duration: 251.949372ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:09:05.996424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.809443ms","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-02T06:09:05.996479Z","caller":"traceutil/trace.go:171","msg":"trace[931330359] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1227; }","duration":"213.911735ms","start":"2026-04-02T06:09:05.782552Z","end":"2026-04-02T06:09:05.996464Z","steps":["trace[931330359] 'agreement among raft nodes before linearized reading' (duration: 213.644128ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:09:05.99687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.565451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-02T06:09:05.996922Z","caller":"traceutil/trace.go:171","msg":"trace[1803355149] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1227; }","duration":"310.643564ms","start":"2026-04-02T06:09:05.686263Z","end":"2026-04-02T06:09:05.996906Z","steps":["trace[1803355149] 'agreement among raft nodes before linearized reading' (duration: 309.908265ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:09:05.996962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:09:05.68625Z","time spent":"310.701775ms","remote":"127.0.0.1:37010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-02T06:09:07.657055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.202607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5331"} {"level":"info","ts":"2026-04-02T06:09:07.657148Z","caller":"traceutil/trace.go:171","msg":"trace[1771981535] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1234; }","duration":"160.351931ms","start":"2026-04-02T06:09:07.496778Z","end":"2026-04-02T06:09:07.65713Z","steps":["trace[1771981535] 'range keys from in-memory index tree' (duration: 159.97772ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:09:10.341437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.1589ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T06:09:10.341635Z","caller":"traceutil/trace.go:171","msg":"trace[755502571] linearizableReadLoop","detail":"{readStateIndex:1307; appliedIndex:1306; }","duration":"338.840064ms","start":"2026-04-02T06:09:10.002777Z","end":"2026-04-02T06:09:10.341617Z","steps":["trace[755502571] 'read index received' (duration: 168.401535ms)","trace[755502571] 'applied index is now lower than readState.Index' (duration: 170.437109ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T06:09:10.341818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.033659ms","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-02T06:09:10.34184Z","caller":"traceutil/trace.go:171","msg":"trace[1669381640] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1260; }","duration":"339.111041ms","start":"2026-04-02T06:09:10.002724Z","end":"2026-04-02T06:09:10.341835Z","steps":["trace[1669381640] 'agreement among raft nodes before linearized reading' (duration: 338.984458ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:09:10.341859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:09:10.002703Z","time spent":"339.151351ms","remote":"127.0.0.1:37010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-02T06:09:10.342042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.462211ms","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-02T06:09:10.342088Z","caller":"traceutil/trace.go:171","msg":"trace[1284173982] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1260; }","duration":"330.534703ms","start":"2026-04-02T06:09:10.01154Z","end":"2026-04-02T06:09:10.342075Z","steps":["trace[1284173982] 'agreement among raft nodes before linearized reading' (duration: 330.380278ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:09:10.342101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.131043ms","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-02T06:09:10.342118Z","caller":"traceutil/trace.go:171","msg":"trace[76331384] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1260; }","duration":"323.186685ms","start":"2026-04-02T06:09:10.018926Z","end":"2026-04-02T06:09:10.342113Z","steps":["trace[76331384] 'agreement among raft nodes before linearized reading' (duration: 323.140103ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:09:10.342118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:09:10.011525Z","time spent":"330.586225ms","remote":"127.0.0.1:37010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-02T06:09:10.342132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:09:10.018902Z","time spent":"323.226596ms","remote":"127.0.0.1:37010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-02T06:09:10.342224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.024027ms","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-02T06:09:10.342253Z","caller":"traceutil/trace.go:171","msg":"trace[1975252015] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1260; }","duration":"326.062359ms","start":"2026-04-02T06:09:10.016184Z","end":"2026-04-02T06:09:10.342247Z","steps":["trace[1975252015] 'agreement among raft nodes before linearized reading' (duration: 325.992507ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:09:10.342273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:09:10.016179Z","time spent":"326.089839ms","remote":"127.0.0.1:37010","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-02T06:09:10.342234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.201916ms","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-02T06:09:10.342361Z","caller":"traceutil/trace.go:171","msg":"trace[958276378] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1260; }","duration":"323.345088ms","start":"2026-04-02T06:09:10.019008Z","end":"2026-04-02T06:09:10.342353Z","steps":["trace[958276378] 'agreement among raft nodes before linearized reading' (duration: 323.195195ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:09:10.342384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:09:10.018996Z","time spent":"323.38211ms","remote":"127.0.0.1:37010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-02T06:09:10.341583Z","caller":"traceutil/trace.go:171","msg":"trace[1983170031] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"382.882977ms","start":"2026-04-02T06:09:09.95867Z","end":"2026-04-02T06:09:10.341553Z","steps":["trace[1983170031] 'process raft request' (duration: 212.534231ms)","trace[1983170031] 'compare' (duration: 170.046618ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T06:09:10.343442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:09:09.958657Z","time spent":"383.916644ms","remote":"127.0.0.1:37252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9375,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T06:09:13.756541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.831826ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-02T06:09:13.756657Z","caller":"traceutil/trace.go:171","msg":"trace[605724267] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"254.613196ms","start":"2026-04-02T06:09:13.502022Z","end":"2026-04-02T06:09:13.756635Z","steps":["trace[605724267] 'process raft request' (duration: 40.624036ms)","trace[605724267] 'compare' (duration: 213.711452ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T06:09:13.773254Z","caller":"traceutil/trace.go:171","msg":"trace[645458484] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"260.367111ms","start":"2026-04-02T06:09:13.512859Z","end":"2026-04-02T06:09:13.773226Z","steps":["trace[645458484] 'process raft request' (duration: 260.218728ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:09:14.322143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.373399ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T06:09:14.32225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.471027ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T06:09:14.322342Z","caller":"traceutil/trace.go:171","msg":"trace[1346834735] linearizableReadLoop","detail":"{readStateIndex:1342; appliedIndex:1340; }","duration":"591.654322ms","start":"2026-04-02T06:09:13.730655Z","end":"2026-04-02T06:09:14.322309Z","steps":["trace[1346834735] 'read index received' (duration: 42.424372ms)","trace[1346834735] 'applied index is now lower than readState.Index' (duration: 549.22914ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T06:09:14.322309Z","caller":"traceutil/trace.go:171","msg":"trace[54065989] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1292; }","duration":"267.572155ms","start":"2026-04-02T06:09:14.054713Z","end":"2026-04-02T06:09:14.322285Z","steps":["trace[54065989] 'range keys from in-memory index tree' (duration: 267.354049ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:09:14.322431Z","caller":"traceutil/trace.go:171","msg":"trace[1784732014] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"653.511391ms","start":"2026-04-02T06:09:13.668911Z","end":"2026-04-02T06:09:14.322422Z","steps":["trace[1784732014] 'process raft request' (duration: 357.796588ms)","trace[1784732014] 'compare' (duration: 295.362044ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T06:09:14.322481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:09:13.668884Z","time spent":"653.572923ms","remote":"127.0.0.1:37010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T06:09:14.322669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.020222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T06:09:14.322695Z","caller":"traceutil/trace.go:171","msg":"trace[2118459744] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1293; }","duration":"592.086203ms","start":"2026-04-02T06:09:13.730602Z","end":"2026-04-02T06:09:14.322688Z","steps":["trace[2118459744] 'agreement among raft nodes before linearized reading' (duration: 592.042732ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:09:14.322713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:09:13.73058Z","time spent":"592.129124ms","remote":"127.0.0.1:36798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T06:09:14.322919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.599622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T06:09:14.322983Z","caller":"traceutil/trace.go:171","msg":"trace[1692556561] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1293; }","duration":"513.713434ms","start":"2026-04-02T06:09:13.809255Z","end":"2026-04-02T06:09:14.322968Z","steps":["trace[1692556561] 'agreement among raft nodes before linearized reading' (duration: 513.595761ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:09:14.323016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:09:13.809232Z","time spent":"513.775866ms","remote":"127.0.0.1:43026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"info","ts":"2026-04-02T06:09:21.162997Z","caller":"traceutil/trace.go:171","msg":"trace[84844061] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"591.089488ms","start":"2026-04-02T06:09:20.571875Z","end":"2026-04-02T06:09:21.162965Z","steps":["trace[84844061] 'process raft request' (duration: 590.830691ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:09:21.163225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:09:20.571849Z","time spent":"591.274023ms","remote":"127.0.0.1:37010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T06:09:21.163536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.10694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-04-02T06:09:21.16293Z","caller":"traceutil/trace.go:171","msg":"trace[1930953960] linearizableReadLoop","detail":"{readStateIndex:1391; appliedIndex:1390; }","duration":"454.507164ms","start":"2026-04-02T06:09:20.708383Z","end":"2026-04-02T06:09:21.16289Z","steps":["trace[1930953960] 'read index received' (duration: 454.208186ms)","trace[1930953960] 'applied index is now lower than readState.Index' (duration: 297.728µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T06:09:21.163692Z","caller":"traceutil/trace.go:171","msg":"trace[1970302664] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1341; }","duration":"455.324466ms","start":"2026-04-02T06:09:20.708337Z","end":"2026-04-02T06:09:21.163661Z","steps":["trace[1970302664] 'agreement among raft nodes before linearized reading' (duration: 454.731781ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:09:21.164064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.54547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T06:09:21.164078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:09:20.708315Z","time spent":"455.693305ms","remote":"127.0.0.1:37010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-04-02T06:09:21.164158Z","caller":"traceutil/trace.go:171","msg":"trace[1157934004] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1341; }","duration":"433.690484ms","start":"2026-04-02T06:09:20.730444Z","end":"2026-04-02T06:09:21.164134Z","steps":["trace[1157934004] 'agreement among raft nodes before linearized reading' (duration: 433.444357ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:09:21.164205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:09:20.730427Z","time spent":"433.769656ms","remote":"127.0.0.1:36798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-02T06:09:28.918096Z","caller":"traceutil/trace.go:171","msg":"trace[81853850] linearizableReadLoop","detail":"{readStateIndex:1431; appliedIndex:1430; }","duration":"124.155777ms","start":"2026-04-02T06:09:28.793915Z","end":"2026-04-02T06:09:28.918071Z","steps":["trace[81853850] 'read index received' (duration: 123.961212ms)","trace[81853850] 'applied index is now lower than readState.Index' (duration: 193.695µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T06:09:28.918162Z","caller":"traceutil/trace.go:171","msg":"trace[2091921019] transaction","detail":"{read_only:false; response_revision:1379; number_of_response:1; }","duration":"176.558054ms","start":"2026-04-02T06:09:28.741567Z","end":"2026-04-02T06:09:28.918125Z","steps":["trace[2091921019] 'process raft request' (duration: 176.353649ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:09:28.918305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.354132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-04-02T06:09:28.91838Z","caller":"traceutil/trace.go:171","msg":"trace[1991883194] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1379; }","duration":"124.469206ms","start":"2026-04-02T06:09:28.793898Z","end":"2026-04-02T06:09:28.918367Z","steps":["trace[1991883194] 'agreement among raft nodes before linearized reading' (duration: 124.26541ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:10:51.504109Z","caller":"traceutil/trace.go:171","msg":"trace[940808531] transaction","detail":"{read_only:false; response_revision:1754; number_of_response:1; }","duration":"306.650841ms","start":"2026-04-02T06:10:51.197434Z","end":"2026-04-02T06:10:51.504085Z","steps":["trace[940808531] 'process raft request' (duration: 306.605179ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:10:51.50424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:10:51.197416Z","time spent":"306.759064ms","remote":"127.0.0.1:37010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T06:10:51.504607Z","caller":"traceutil/trace.go:171","msg":"trace[686085310] transaction","detail":"{read_only:false; response_revision:1753; number_of_response:1; }","duration":"307.175455ms","start":"2026-04-02T06:10:51.197417Z","end":"2026-04-02T06:10:51.504592Z","steps":["trace[686085310] 'process raft request' (duration: 305.385228ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:10:51.504672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:10:51.197396Z","time spent":"307.245206ms","remote":"127.0.0.1:37010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T06:10:51.506973Z","caller":"traceutil/trace.go:171","msg":"trace[434066326] linearizableReadLoop","detail":"{readStateIndex:1823; appliedIndex:1820; }","duration":"217.49731ms","start":"2026-04-02T06:10:51.289462Z","end":"2026-04-02T06:10:51.506959Z","steps":["trace[434066326] 'read index received' (duration: 213.27265ms)","trace[434066326] 'applied index is now lower than readState.Index' (duration: 4.22332ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T06:10:51.507026Z","caller":"traceutil/trace.go:171","msg":"trace[1959026046] transaction","detail":"{read_only:false; response_revision:1755; number_of_response:1; }","duration":"308.54889ms","start":"2026-04-02T06:10:51.198447Z","end":"2026-04-02T06:10:51.506996Z","steps":["trace[1959026046] 'process raft request' (duration: 308.374416ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:10:51.507178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:10:51.198434Z","time spent":"308.671114ms","remote":"127.0.0.1:37010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T06:10:51.507184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.721156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T06:10:51.507247Z","caller":"traceutil/trace.go:171","msg":"trace[1644390189] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1755; }","duration":"217.836169ms","start":"2026-04-02T06:10:51.289395Z","end":"2026-04-02T06:10:51.507231Z","steps":["trace[1644390189] 'agreement among raft nodes before linearized reading' (duration: 217.740967ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:10:51.508501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.607305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-04-02T06:10:51.508716Z","caller":"traceutil/trace.go:171","msg":"trace[169792448] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1755; }","duration":"131.727589ms","start":"2026-04-02T06:10:51.376833Z","end":"2026-04-02T06:10:51.50856Z","steps":["trace[169792448] 'agreement among raft nodes before linearized reading' (duration: 130.526527ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:10:51.958176Z","caller":"traceutil/trace.go:171","msg":"trace[233779897] linearizableReadLoop","detail":"{readStateIndex:1825; appliedIndex:1824; }","duration":"122.245152ms","start":"2026-04-02T06:10:51.835914Z","end":"2026-04-02T06:10:51.958159Z","steps":["trace[233779897] 'read index received' (duration: 122.16339ms)","trace[233779897] 'applied index is now lower than readState.Index' (duration: 80.972µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T06:10:51.958309Z","caller":"traceutil/trace.go:171","msg":"trace[1519318104] transaction","detail":"{read_only:false; response_revision:1757; number_of_response:1; }","duration":"263.388505ms","start":"2026-04-02T06:10:51.694888Z","end":"2026-04-02T06:10:51.958276Z","steps":["trace[1519318104] 'process raft request' (duration: 263.153489ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:10:51.958412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.494258ms","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:526"} {"level":"info","ts":"2026-04-02T06:10:51.958435Z","caller":"traceutil/trace.go:171","msg":"trace[682186641] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1757; }","duration":"122.6006ms","start":"2026-04-02T06:10:51.835826Z","end":"2026-04-02T06:10:51.958426Z","steps":["trace[682186641] 'agreement among raft nodes before linearized reading' (duration: 122.435006ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:10:51.958554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.945811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-04-02T06:10:51.958614Z","caller":"traceutil/trace.go:171","msg":"trace[1810158995] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1757; }","duration":"120.057054ms","start":"2026-04-02T06:10:51.838539Z","end":"2026-04-02T06:10:51.958596Z","steps":["trace[1810158995] 'agreement among raft nodes before linearized reading' (duration: 119.844038ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:10:52.100245Z","caller":"traceutil/trace.go:171","msg":"trace[749277667] transaction","detail":"{read_only:false; response_revision:1759; number_of_response:1; }","duration":"138.583127ms","start":"2026-04-02T06:10:51.961635Z","end":"2026-04-02T06:10:52.100218Z","steps":["trace[749277667] 'process raft request' (duration: 110.841655ms)","trace[749277667] 'compare' (duration: 27.624149ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T06:10:52.627173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.42868ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-04-02T06:11:02.494384Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1640609117453932231,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T06:11:02.994842Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1640609117453932231,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T06:11:03.370673Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.378480097s","expected-duration":"1s"} {"level":"info","ts":"2026-04-02T06:11:03.371125Z","caller":"traceutil/trace.go:171","msg":"trace[1688496846] transaction","detail":"{read_only:false; response_revision:1803; number_of_response:1; }","duration":"1.379054052s","start":"2026-04-02T06:11:01.992052Z","end":"2026-04-02T06:11:03.371106Z","steps":["trace[1688496846] 'process raft request' (duration: 1.378898058s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:11:03.37143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:11:01.992024Z","time spent":"1.379349039s","remote":"127.0.0.1:37010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T06:11:03.395716Z","caller":"traceutil/trace.go:171","msg":"trace[1245389779] transaction","detail":"{read_only:false; response_revision:1804; number_of_response:1; }","duration":"482.257511ms","start":"2026-04-02T06:11:02.913433Z","end":"2026-04-02T06:11:03.39569Z","steps":["trace[1245389779] 'process raft request' (duration: 482.047986ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:11:03.395876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:11:02.913381Z","time spent":"482.414935ms","remote":"127.0.0.1:37010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T06:11:03.398742Z","caller":"traceutil/trace.go:171","msg":"trace[1723308753] linearizableReadLoop","detail":"{readStateIndex:1874; appliedIndex:1872; }","duration":"1.404781151s","start":"2026-04-02T06:11:01.993945Z","end":"2026-04-02T06:11:03.398726Z","steps":["trace[1723308753] 'read index received' (duration: 1.377125002s)","trace[1723308753] 'applied index is now lower than readState.Index' (duration: 27.654969ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T06:11:03.398915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.404970646s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-04-02T06:11:03.398926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.14880441s","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:525"} {"level":"info","ts":"2026-04-02T06:11:03.398945Z","caller":"traceutil/trace.go:171","msg":"trace[532924243] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1804; }","duration":"1.405027638s","start":"2026-04-02T06:11:01.993908Z","end":"2026-04-02T06:11:03.398936Z","steps":["trace[532924243] 'agreement among raft nodes before linearized reading' (duration: 1.404918215s)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:11:03.398954Z","caller":"traceutil/trace.go:171","msg":"trace[1633229932] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1804; }","duration":"1.148875491s","start":"2026-04-02T06:11:02.250071Z","end":"2026-04-02T06:11:03.398946Z","steps":["trace[1633229932] 'agreement among raft nodes before linearized reading' (duration: 1.148775278s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:11:03.398966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:11:01.993894Z","time spent":"1.405066769s","remote":"127.0.0.1:37010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-04-02T06:11:03.398976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:11:02.25005Z","time spent":"1.148920252s","remote":"127.0.0.1:37010","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-02T06:11:03.39906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.396836ms","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-02T06:11:03.399076Z","caller":"traceutil/trace.go:171","msg":"trace[1667793578] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1804; }","duration":"172.453939ms","start":"2026-04-02T06:11:03.226617Z","end":"2026-04-02T06:11:03.399071Z","steps":["trace[1667793578] 'agreement among raft nodes before linearized reading' (duration: 172.406327ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:11:03.399144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.873197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T06:11:03.39916Z","caller":"traceutil/trace.go:171","msg":"trace[1612088331] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1804; }","duration":"389.933259ms","start":"2026-04-02T06:11:03.009222Z","end":"2026-04-02T06:11:03.399155Z","steps":["trace[1612088331] 'agreement among raft nodes before linearized reading' (duration: 389.894818ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:11:03.399176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:11:03.009201Z","time spent":"389.971629ms","remote":"127.0.0.1:37088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":17,"response size":30,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-04-02T06:11:03.39919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.721701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T06:11:03.399207Z","caller":"traceutil/trace.go:171","msg":"trace[1279517509] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1804; }","duration":"611.773882ms","start":"2026-04-02T06:11:02.787429Z","end":"2026-04-02T06:11:03.399202Z","steps":["trace[1279517509] 'agreement among raft nodes before linearized reading' (duration: 611.745671ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:11:03.399224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:11:02.787409Z","time spent":"611.811123ms","remote":"127.0.0.1:36798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-02T06:11:09.581363Z","caller":"traceutil/trace.go:171","msg":"trace[171172622] linearizableReadLoop","detail":"{readStateIndex:1905; appliedIndex:1904; }","duration":"139.160441ms","start":"2026-04-02T06:11:09.442187Z","end":"2026-04-02T06:11:09.581347Z","steps":["trace[171172622] 'read index received' (duration: 139.016438ms)","trace[171172622] 'applied index is now lower than readState.Index' (duration: 142.973µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T06:11:09.581417Z","caller":"traceutil/trace.go:171","msg":"trace[766721832] transaction","detail":"{read_only:false; response_revision:1833; number_of_response:1; }","duration":"139.723395ms","start":"2026-04-02T06:11:09.44167Z","end":"2026-04-02T06:11:09.581393Z","steps":["trace[766721832] 'process raft request' (duration: 139.539241ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:11:09.58158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.371137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-04-02T06:11:09.581655Z","caller":"traceutil/trace.go:171","msg":"trace[1179443750] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1833; }","duration":"139.48982ms","start":"2026-04-02T06:11:09.442146Z","end":"2026-04-02T06:11:09.581636Z","steps":["trace[1179443750] 'agreement among raft nodes before linearized reading' (duration: 139.270455ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:11:09.621702Z","caller":"traceutil/trace.go:171","msg":"trace[306181948] transaction","detail":"{read_only:false; response_revision:1835; number_of_response:1; }","duration":"170.61509ms","start":"2026-04-02T06:11:09.451063Z","end":"2026-04-02T06:11:09.621679Z","steps":["trace[306181948] 'process raft request' (duration: 170.575049ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:11:09.621745Z","caller":"traceutil/trace.go:171","msg":"trace[1567495970] transaction","detail":"{read_only:false; response_revision:1834; number_of_response:1; }","duration":"176.125344ms","start":"2026-04-02T06:11:09.445594Z","end":"2026-04-02T06:11:09.621719Z","steps":["trace[1567495970] 'process raft request' (duration: 175.922269ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:11:09.712554Z","caller":"traceutil/trace.go:171","msg":"trace[2014328525] linearizableReadLoop","detail":"{readStateIndex:1908; appliedIndex:1905; }","duration":"124.637224ms","start":"2026-04-02T06:11:09.58789Z","end":"2026-04-02T06:11:09.712528Z","steps":["trace[2014328525] 'read index received' (duration: 33.616215ms)","trace[2014328525] 'applied index is now lower than readState.Index' (duration: 91.019839ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T06:11:09.712665Z","caller":"traceutil/trace.go:171","msg":"trace[491346701] transaction","detail":"{read_only:false; response_revision:1836; number_of_response:1; }","duration":"128.012402ms","start":"2026-04-02T06:11:09.584626Z","end":"2026-04-02T06:11:09.712638Z","steps":["trace[491346701] 'process raft request' (duration: 87.131018ms)","trace[491346701] 'compare' (duration: 40.653528ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T06:11:09.712801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.88912ms","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-02T06:11:09.71284Z","caller":"traceutil/trace.go:171","msg":"trace[1663430970] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1836; }","duration":"124.971152ms","start":"2026-04-02T06:11:09.587856Z","end":"2026-04-02T06:11:09.712827Z","steps":["trace[1663430970] 'agreement among raft nodes before linearized reading' (duration: 124.820129ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:11:09.712887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.920041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-02T06:11:09.712942Z","caller":"traceutil/trace.go:171","msg":"trace[2028654841] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1836; }","duration":"124.998233ms","start":"2026-04-02T06:11:09.58793Z","end":"2026-04-02T06:11:09.712928Z","steps":["trace[2028654841] 'agreement among raft nodes before linearized reading' (duration: 124.850899ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:11:09.713032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.587224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-02T06:11:09.713064Z","caller":"traceutil/trace.go:171","msg":"trace[1438196663] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1836; }","duration":"121.630866ms","start":"2026-04-02T06:11:09.591424Z","end":"2026-04-02T06:11:09.713055Z","steps":["trace[1438196663] 'agreement among raft nodes before linearized reading' (duration: 121.519892ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:11:09.890617Z","caller":"traceutil/trace.go:171","msg":"trace[2082295327] transaction","detail":"{read_only:false; response_revision:1839; number_of_response:1; }","duration":"173.458785ms","start":"2026-04-02T06:11:09.717134Z","end":"2026-04-02T06:11:09.890592Z","steps":["trace[2082295327] 'process raft request' (duration: 173.401354ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:11:09.890679Z","caller":"traceutil/trace.go:171","msg":"trace[1727571317] transaction","detail":"{read_only:false; response_revision:1838; number_of_response:1; }","duration":"173.580857ms","start":"2026-04-02T06:11:09.717061Z","end":"2026-04-02T06:11:09.890641Z","steps":["trace[1727571317] 'process raft request' (duration: 171.095193ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:11:35.945287Z","caller":"traceutil/trace.go:171","msg":"trace[292444649] linearizableReadLoop","detail":"{readStateIndex:2028; appliedIndex:2027; }","duration":"211.842704ms","start":"2026-04-02T06:11:35.733422Z","end":"2026-04-02T06:11:35.945265Z","steps":["trace[292444649] 'read index received' (duration: 211.707381ms)","trace[292444649] 'applied index is now lower than readState.Index' (duration: 134.413µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T06:11:35.945575Z","caller":"traceutil/trace.go:171","msg":"trace[1162029035] transaction","detail":"{read_only:false; response_revision:1951; number_of_response:1; }","duration":"212.292545ms","start":"2026-04-02T06:11:35.733269Z","end":"2026-04-02T06:11:35.945561Z","steps":["trace[1162029035] 'process raft request' (duration: 211.872905ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:11:35.945871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.767771ms","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-02T06:11:35.945905Z","caller":"traceutil/trace.go:171","msg":"trace[1247608635] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1951; }","duration":"206.833253ms","start":"2026-04-02T06:11:35.739063Z","end":"2026-04-02T06:11:35.945897Z","steps":["trace[1247608635] 'agreement among raft nodes before linearized reading' (duration: 206.73053ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:11:35.945871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.813618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-02T06:11:35.94595Z","caller":"traceutil/trace.go:171","msg":"trace[1605314749] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1951; }","duration":"207.944912ms","start":"2026-04-02T06:11:35.737987Z","end":"2026-04-02T06:11:35.945932Z","steps":["trace[1605314749] 'agreement among raft nodes before linearized reading' (duration: 207.725397ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:11:35.945978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.561872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T06:11:35.945998Z","caller":"traceutil/trace.go:171","msg":"trace[1641216708] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1951; }","duration":"212.611874ms","start":"2026-04-02T06:11:35.73338Z","end":"2026-04-02T06:11:35.945992Z","steps":["trace[1641216708] 'agreement among raft nodes before linearized reading' (duration: 212.581653ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:11:35.946244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.526414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T06:11:35.946269Z","caller":"traceutil/trace.go:171","msg":"trace[1261579837] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1951; }","duration":"196.591656ms","start":"2026-04-02T06:11:35.749671Z","end":"2026-04-02T06:11:35.946262Z","steps":["trace[1261579837] 'agreement among raft nodes before linearized reading' (duration: 196.095683ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:11:36.08497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.68582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-04-02T06:11:36.085093Z","caller":"traceutil/trace.go:171","msg":"trace[1651103806] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1953; }","duration":"133.850234ms","start":"2026-04-02T06:11:35.951218Z","end":"2026-04-02T06:11:36.085069Z","steps":["trace[1651103806] 'agreement among raft nodes before linearized reading' (duration: 76.826849ms)","trace[1651103806] 'range keys from in-memory index tree' (duration: 56.842381ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T06:11:36.355296Z","caller":"traceutil/trace.go:171","msg":"trace[870123499] transaction","detail":"{read_only:false; response_revision:1955; number_of_response:1; }","duration":"265.476169ms","start":"2026-04-02T06:11:36.089797Z","end":"2026-04-02T06:11:36.355273Z","steps":["trace[870123499] 'process raft request' (duration: 265.184191ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:11:36.355907Z","caller":"traceutil/trace.go:171","msg":"trace[181718002] transaction","detail":"{read_only:false; response_revision:1956; number_of_response:1; }","duration":"265.974893ms","start":"2026-04-02T06:11:36.08992Z","end":"2026-04-02T06:11:36.355895Z","steps":["trace[181718002] 'process raft request' (duration: 265.313355ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:11:36.355954Z","caller":"traceutil/trace.go:171","msg":"trace[1559852670] transaction","detail":"{read_only:false; response_revision:1957; number_of_response:1; }","duration":"265.357967ms","start":"2026-04-02T06:11:36.090584Z","end":"2026-04-02T06:11:36.355942Z","steps":["trace[1559852670] 'process raft request' (duration: 265.077169ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:11:36.355788Z","caller":"traceutil/trace.go:171","msg":"trace[1016082190] linearizableReadLoop","detail":"{readStateIndex:2034; appliedIndex:2031; }","duration":"152.064397ms","start":"2026-04-02T06:11:36.203701Z","end":"2026-04-02T06:11:36.355766Z","steps":["trace[1016082190] 'read index received' (duration: 151.226406ms)","trace[1016082190] 'applied index is now lower than readState.Index' (duration: 836.951µs)"],"step_count":2} {"level":"warn","ts":"2026-04-02T06:11:36.356239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.529569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4219"} {"level":"info","ts":"2026-04-02T06:11:36.356288Z","caller":"traceutil/trace.go:171","msg":"trace[2131978902] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:1957; }","duration":"152.632473ms","start":"2026-04-02T06:11:36.203634Z","end":"2026-04-02T06:11:36.356267Z","steps":["trace[2131978902] 'agreement among raft nodes before linearized reading' (duration: 152.450828ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:12:02.263807Z","caller":"traceutil/trace.go:171","msg":"trace[1915696582] linearizableReadLoop","detail":"{readStateIndex:2154; appliedIndex:2153; }","duration":"120.994739ms","start":"2026-04-02T06:12:02.142793Z","end":"2026-04-02T06:12:02.263788Z","steps":["trace[1915696582] 'read index received' (duration: 120.817414ms)","trace[1915696582] 'applied index is now lower than readState.Index' (duration: 176.385µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T06:12:02.263933Z","caller":"traceutil/trace.go:171","msg":"trace[2135855994] transaction","detail":"{read_only:false; response_revision:2072; number_of_response:1; }","duration":"228.888217ms","start":"2026-04-02T06:12:02.035014Z","end":"2026-04-02T06:12:02.263903Z","steps":["trace[2135855994] 'process raft request' (duration: 228.650852ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:12:02.263997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.191584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-02T06:12:02.264036Z","caller":"traceutil/trace.go:171","msg":"trace[1334691712] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2072; }","duration":"121.299896ms","start":"2026-04-02T06:12:02.142726Z","end":"2026-04-02T06:12:02.264026Z","steps":["trace[1334691712] 'agreement among raft nodes before linearized reading' (duration: 121.172973ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:12:02.264269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.104933ms","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-02T06:12:02.264986Z","caller":"traceutil/trace.go:171","msg":"trace[727961018] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2072; }","duration":"118.899514ms","start":"2026-04-02T06:12:02.146056Z","end":"2026-04-02T06:12:02.264955Z","steps":["trace[727961018] 'agreement among raft nodes before linearized reading' (duration: 117.929558ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:12:02.67793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.918311ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-02T06:12:02.678143Z","caller":"traceutil/trace.go:171","msg":"trace[1883827441] transaction","detail":"{read_only:false; response_revision:2076; number_of_response:1; }","duration":"142.343807ms","start":"2026-04-02T06:12:02.535784Z","end":"2026-04-02T06:12:02.678128Z","steps":["trace[1883827441] 'process raft request' (duration: 142.232114ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:12:02.678216Z","caller":"traceutil/trace.go:171","msg":"trace[138166950] linearizableReadLoop","detail":"{readStateIndex:2158; appliedIndex:2157; }","duration":"184.875963ms","start":"2026-04-02T06:12:02.493312Z","end":"2026-04-02T06:12:02.678188Z","steps":["trace[138166950] 'read index received' (duration: 36.711485ms)","trace[138166950] 'applied index is now lower than readState.Index' (duration: 148.160308ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T06:12:02.678533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.938076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-04-02T06:12:02.678564Z","caller":"traceutil/trace.go:171","msg":"trace[1781730418] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2076; }","duration":"182.016418ms","start":"2026-04-02T06:12:02.49654Z","end":"2026-04-02T06:12:02.678556Z","steps":["trace[1781730418] 'agreement among raft nodes before linearized reading' (duration: 181.832044ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:12:02.678654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.320914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-02T06:12:02.678767Z","caller":"traceutil/trace.go:171","msg":"trace[746074922] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2076; }","duration":"185.492659ms","start":"2026-04-02T06:12:02.493252Z","end":"2026-04-02T06:12:02.678745Z","steps":["trace[746074922] 'agreement among raft nodes before linearized reading' (duration: 185.055357ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:12:02.67871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.200544ms","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-02T06:12:02.679111Z","caller":"traceutil/trace.go:171","msg":"trace[1435227169] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2076; }","duration":"179.645147ms","start":"2026-04-02T06:12:02.499436Z","end":"2026-04-02T06:12:02.679081Z","steps":["trace[1435227169] 'agreement among raft nodes before linearized reading' (duration: 178.948669ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:12:36.839296Z","caller":"traceutil/trace.go:171","msg":"trace[1254130330] linearizableReadLoop","detail":"{readStateIndex:2348; appliedIndex:2347; }","duration":"342.075184ms","start":"2026-04-02T06:12:36.497195Z","end":"2026-04-02T06:12:36.83927Z","steps":["trace[1254130330] 'read index received' (duration: 341.943541ms)","trace[1254130330] 'applied index is now lower than readState.Index' (duration: 130.403µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T06:12:36.839411Z","caller":"traceutil/trace.go:171","msg":"trace[1296736926] transaction","detail":"{read_only:false; response_revision:2258; number_of_response:1; }","duration":"353.422308ms","start":"2026-04-02T06:12:36.48596Z","end":"2026-04-02T06:12:36.839382Z","steps":["trace[1296736926] 'process raft request' (duration: 353.148111ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:12:36.839553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.73148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-02T06:12:36.839566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:12:36.48594Z","time spent":"353.53445ms","remote":"127.0.0.1:37010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T06:12:36.839596Z","caller":"traceutil/trace.go:171","msg":"trace[1448246390] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2258; }","duration":"340.803631ms","start":"2026-04-02T06:12:36.498782Z","end":"2026-04-02T06:12:36.839586Z","steps":["trace[1448246390] 'agreement among raft nodes before linearized reading' (duration: 340.645487ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:12:36.839639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:12:36.498776Z","time spent":"340.853812ms","remote":"127.0.0.1:37010","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-02T06:12:36.839649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.065176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T06:12:36.839765Z","caller":"traceutil/trace.go:171","msg":"trace[981301233] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2258; }","duration":"108.242871ms","start":"2026-04-02T06:12:36.731494Z","end":"2026-04-02T06:12:36.839737Z","steps":["trace[981301233] 'agreement among raft nodes before linearized reading' (duration: 108.032196ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:12:36.839993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.743982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-02T06:12:36.840135Z","caller":"traceutil/trace.go:171","msg":"trace[2048017963] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2258; }","duration":"342.930187ms","start":"2026-04-02T06:12:36.497177Z","end":"2026-04-02T06:12:36.840107Z","steps":["trace[2048017963] 'agreement among raft nodes before linearized reading' (duration: 342.246869ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:12:36.8402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:12:36.497168Z","time spent":"343.020509ms","remote":"127.0.0.1:37010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-02T06:12:37.051527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.089167ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T06:12:37.051825Z","caller":"traceutil/trace.go:171","msg":"trace[281822609] linearizableReadLoop","detail":"{readStateIndex:2350; appliedIndex:2348; }","duration":"196.989588ms","start":"2026-04-02T06:12:36.854815Z","end":"2026-04-02T06:12:37.051804Z","steps":["trace[281822609] 'read index received' (duration: 7.955205ms)","trace[281822609] 'applied index is now lower than readState.Index' (duration: 189.032633ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T06:12:37.052089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.262216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-04-02T06:12:37.052175Z","caller":"traceutil/trace.go:171","msg":"trace[127331251] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2260; }","duration":"197.40071ms","start":"2026-04-02T06:12:36.854757Z","end":"2026-04-02T06:12:37.052158Z","steps":["trace[127331251] 'agreement among raft nodes before linearized reading' (duration: 197.103601ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:12:37.052432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.349715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-02T06:12:37.052462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.101147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-04-02T06:12:37.052538Z","caller":"traceutil/trace.go:171","msg":"trace[1469299657] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2260; }","duration":"154.476318ms","start":"2026-04-02T06:12:36.898023Z","end":"2026-04-02T06:12:37.052499Z","steps":["trace[1469299657] 'agreement among raft nodes before linearized reading' (duration: 154.290273ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:12:37.052574Z","caller":"traceutil/trace.go:171","msg":"trace[1955492078] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2260; }","duration":"152.231399ms","start":"2026-04-02T06:12:36.900321Z","end":"2026-04-02T06:12:37.052552Z","steps":["trace[1955492078] 'agreement among raft nodes before linearized reading' (duration: 152.025054ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:12:37.052608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.135823ms","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-02T06:12:37.052679Z","caller":"traceutil/trace.go:171","msg":"trace[2130224054] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2260; }","duration":"153.276527ms","start":"2026-04-02T06:12:36.899386Z","end":"2026-04-02T06:12:37.052663Z","steps":["trace[2130224054] 'agreement among raft nodes before linearized reading' (duration: 153.03912ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:12:37.052787Z","caller":"traceutil/trace.go:171","msg":"trace[1772291452] transaction","detail":"{read_only:false; response_revision:2260; number_of_response:1; }","duration":"206.661959ms","start":"2026-04-02T06:12:36.845178Z","end":"2026-04-02T06:12:37.05184Z","steps":["trace[1772291452] 'process raft request' (duration: 76.147921ms)","trace[1772291452] 'compare' (duration: 129.944164ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T06:12:37.364487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.359308ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T06:12:37.364571Z","caller":"traceutil/trace.go:171","msg":"trace[1530199226] transaction","detail":"{read_only:false; response_revision:2261; number_of_response:1; }","duration":"307.619002ms","start":"2026-04-02T06:12:37.056937Z","end":"2026-04-02T06:12:37.364556Z","steps":["trace[1530199226] 'process raft request' (duration: 144.12147ms)","trace[1530199226] 'compare' (duration: 163.256006ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T06:12:37.364615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:12:37.056918Z","time spent":"307.677524ms","remote":"127.0.0.1:37010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T06:12:37.368708Z","caller":"traceutil/trace.go:171","msg":"trace[1302648510] transaction","detail":"{read_only:false; response_revision:2262; number_of_response:1; }","duration":"311.677388ms","start":"2026-04-02T06:12:37.057007Z","end":"2026-04-02T06:12:37.368684Z","steps":["trace[1302648510] 'process raft request' (duration: 311.555985ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:12:37.368824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:12:37.05698Z","time spent":"311.78945ms","remote":"127.0.0.1:37010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T06:12:37.368978Z","caller":"traceutil/trace.go:171","msg":"trace[661651207] transaction","detail":"{read_only:false; response_revision:2264; number_of_response:1; }","duration":"311.489893ms","start":"2026-04-02T06:12:37.057463Z","end":"2026-04-02T06:12:37.368953Z","steps":["trace[661651207] 'process raft request' (duration: 311.38442ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:12:37.368976Z","caller":"traceutil/trace.go:171","msg":"trace[1158427126] transaction","detail":"{read_only:false; response_revision:2263; number_of_response:1; }","duration":"311.915873ms","start":"2026-04-02T06:12:37.05703Z","end":"2026-04-02T06:12:37.368946Z","steps":["trace[1158427126] 'process raft request' (duration: 311.617156ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:12:37.369204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:12:37.057454Z","time spent":"311.596847ms","remote":"127.0.0.1:37010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T06:12:37.369317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:12:37.05702Z","time spent":"312.199341ms","remote":"127.0.0.1:37010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T06:12:37.369486Z","caller":"traceutil/trace.go:171","msg":"trace[1537150324] linearizableReadLoop","detail":"{readStateIndex:2354; appliedIndex:2354; }","duration":"119.7686ms","start":"2026-04-02T06:12:37.249702Z","end":"2026-04-02T06:12:37.369471Z","steps":["trace[1537150324] 'read index received' (duration: 119.76353ms)","trace[1537150324] 'applied index is now lower than readState.Index' (duration: 4.2µs)"],"step_count":2} {"level":"warn","ts":"2026-04-02T06:12:37.369617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.914334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.195\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-02T06:12:37.369667Z","caller":"traceutil/trace.go:171","msg":"trace[812822195] range","detail":"{range_begin:/registry/masterleases/199.19.213.195; range_end:; response_count:1; response_revision:2264; }","duration":"120.008266ms","start":"2026-04-02T06:12:37.249651Z","end":"2026-04-02T06:12:37.36966Z","steps":["trace[812822195] 'agreement among raft nodes before linearized reading' (duration: 119.887023ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:12:37.893265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.823684ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-04-02T06:12:37.893363Z","caller":"traceutil/trace.go:171","msg":"trace[1892782901] linearizableReadLoop","detail":"{readStateIndex:2355; appliedIndex:2354; }","duration":"473.134006ms","start":"2026-04-02T06:12:37.420211Z","end":"2026-04-02T06:12:37.893345Z","steps":["trace[1892782901] 'read index received' (duration: 169.120917ms)","trace[1892782901] 'applied index is now lower than readState.Index' (duration: 304.011519ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T06:12:37.893447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.234379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T06:12:37.893475Z","caller":"traceutil/trace.go:171","msg":"trace[649034883] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:2264; }","duration":"473.310071ms","start":"2026-04-02T06:12:37.420156Z","end":"2026-04-02T06:12:37.893466Z","steps":["trace[649034883] 'agreement among raft nodes before linearized reading' (duration: 473.239598ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:12:37.893691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:12:37.420135Z","time spent":"473.547538ms","remote":"127.0.0.1:36980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-04-02T06:12:37.89385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:12:37.370714Z","time spent":"523.12962ms","remote":"127.0.0.1:36814","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-02T06:12:38.378075Z","caller":"traceutil/trace.go:171","msg":"trace[1288375899] transaction","detail":"{read_only:false; response_revision:2266; number_of_response:1; }","duration":"144.713646ms","start":"2026-04-02T06:12:38.23334Z","end":"2026-04-02T06:12:38.378054Z","steps":["trace[1288375899] 'process raft request' (duration: 144.596123ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:12:38.596937Z","caller":"traceutil/trace.go:171","msg":"trace[44160235] transaction","detail":"{read_only:false; response_revision:2267; number_of_response:1; }","duration":"152.366494ms","start":"2026-04-02T06:12:38.444504Z","end":"2026-04-02T06:12:38.59687Z","steps":["trace[44160235] 'process raft request' (duration: 146.84093ms)"],"step_count":1}