{"level":"warn","ts":"2026-04-14T04:08:03.773905Z","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-14T04:08:03.774021Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.116:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.116:2380","--initial-cluster=instance=https://199.204.45.116:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.116:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.116: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-14T04:08:03.774113Z","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-14T04:08:03.774127Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.116:2380"]} {"level":"info","ts":"2026-04-14T04:08:03.774159Z","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-14T04:08:03.774855Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"]} {"level":"info","ts":"2026-04-14T04:08:03.774947Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.116:2380"],"listen-peer-urls":["https://199.204.45.116:2380"],"advertise-client-urls":["https://199.204.45.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.116: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-14T04:08:03.785736Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"10.543243ms"} {"level":"info","ts":"2026-04-14T04:08:03.807775Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5caa70ef1dd6dc04","cluster-id":"45afccab143d2c82"} {"level":"info","ts":"2026-04-14T04:08:03.807875Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=()"} {"level":"info","ts":"2026-04-14T04:08:03.807908Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became follower at term 0"} {"level":"info","ts":"2026-04-14T04:08:03.807923Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5caa70ef1dd6dc04 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-14T04:08:03.807939Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became follower at term 1"} {"level":"info","ts":"2026-04-14T04:08:03.807988Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=(6677273569830296580)"} {"level":"warn","ts":"2026-04-14T04:08:03.814695Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-14T04:08:03.818094Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-14T04:08:03.820251Z","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-14T04:08:03.823833Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5caa70ef1dd6dc04","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-14T04:08:03.82404Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5caa70ef1dd6dc04","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-14T04:08:03.82411Z","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-14T04:08:03.8243Z","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-14T04:08:03.824312Z","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-14T04:08:03.828046Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=(6677273569830296580)"} {"level":"info","ts":"2026-04-14T04:08:03.828827Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"45afccab143d2c82","local-member-id":"5caa70ef1dd6dc04","added-peer-id":"5caa70ef1dd6dc04","added-peer-peer-urls":["https://199.204.45.116:2380"]} {"level":"info","ts":"2026-04-14T04:08:03.830505Z","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-14T04:08:03.830919Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5caa70ef1dd6dc04","initial-advertise-peer-urls":["https://199.204.45.116:2380"],"listen-peer-urls":["https://199.204.45.116:2380"],"advertise-client-urls":["https://199.204.45.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-14T04:08:03.831356Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.116:2380"} {"level":"info","ts":"2026-04-14T04:08:03.831398Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.116:2380"} {"level":"info","ts":"2026-04-14T04:08:03.830998Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-14T04:08:04.1082Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 is starting a new election at term 1"} {"level":"info","ts":"2026-04-14T04:08:04.108273Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-14T04:08:04.108294Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 received MsgPreVoteResp from 5caa70ef1dd6dc04 at term 1"} {"level":"info","ts":"2026-04-14T04:08:04.108357Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became candidate at term 2"} {"level":"info","ts":"2026-04-14T04:08:04.108372Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 received MsgVoteResp from 5caa70ef1dd6dc04 at term 2"} {"level":"info","ts":"2026-04-14T04:08:04.108397Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became leader at term 2"} {"level":"info","ts":"2026-04-14T04:08:04.10843Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5caa70ef1dd6dc04 elected leader 5caa70ef1dd6dc04 at term 2"} {"level":"info","ts":"2026-04-14T04:08:04.117241Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5caa70ef1dd6dc04","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.116:2379]}","request-path":"/0/members/5caa70ef1dd6dc04/attributes","cluster-id":"45afccab143d2c82","publish-timeout":"7s"} {"level":"info","ts":"2026-04-14T04:08:04.117324Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-14T04:08:04.117756Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T04:08:04.117831Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-14T04:08:04.120381Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-14T04:08:04.120575Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-14T04:08:04.120639Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-14T04:08:04.120829Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.116:2379"} {"level":"info","ts":"2026-04-14T04:08:04.122566Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"45afccab143d2c82","local-member-id":"5caa70ef1dd6dc04","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T04:08:04.122952Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T04:08:04.125715Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T04:08:05.611648Z","caller":"traceutil/trace.go:171","msg":"trace[555232912] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"109.795316ms","start":"2026-04-14T04:08:05.501818Z","end":"2026-04-14T04:08:05.611613Z","steps":["trace[555232912] 'process raft request' (duration: 101.338885ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:08:06.733275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.125992ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-14T04:08:06.733709Z","caller":"traceutil/trace.go:171","msg":"trace[1923586197] linearizableReadLoop","detail":"{readStateIndex:8; appliedIndex:6; }","duration":"1.229599581s","start":"2026-04-14T04:08:05.504086Z","end":"2026-04-14T04:08:06.733685Z","steps":["trace[1923586197] 'read index received' (duration: 99.082453ms)","trace[1923586197] 'applied index is now lower than readState.Index' (duration: 1.130515948s)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:08:06.733733Z","caller":"traceutil/trace.go:171","msg":"trace[96567482] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"1.231296265s","start":"2026-04-14T04:08:05.502407Z","end":"2026-04-14T04:08:06.733703Z","steps":["trace[96567482] 'process raft request' (duration: 647.100567ms)","trace[96567482] 'compare' (duration: 582.933779ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:08:06.73386Z","caller":"traceutil/trace.go:171","msg":"trace[690656000] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"1.228213541s","start":"2026-04-14T04:08:05.505626Z","end":"2026-04-14T04:08:06.73384Z","steps":["trace[690656000] 'process raft request' (duration: 1.227901906s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:08:06.733897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:08:05.502396Z","time spent":"1.231411696s","remote":"127.0.0.1:35080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T04:08:06.733951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:08:05.505608Z","time spent":"1.228293612s","remote":"127.0.0.1:35080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T04:08:06.734045Z","caller":"traceutil/trace.go:171","msg":"trace[1161808121] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"1.228339503s","start":"2026-04-14T04:08:05.505669Z","end":"2026-04-14T04:08:06.734009Z","steps":["trace[1161808121] 'process raft request' (duration: 1.227881037s)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:08:06.734099Z","caller":"traceutil/trace.go:171","msg":"trace[1197494290] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"1.228479865s","start":"2026-04-14T04:08:05.505558Z","end":"2026-04-14T04:08:06.734038Z","steps":["trace[1197494290] 'process raft request' (duration: 1.227936727s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:08:06.734208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:08:05.505647Z","time spent":"1.228483905s","remote":"127.0.0.1:35080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T04:08:06.734243Z","caller":"traceutil/trace.go:171","msg":"trace[1691735085] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"1.221384512s","start":"2026-04-14T04:08:05.512841Z","end":"2026-04-14T04:08:06.734226Z","steps":["trace[1691735085] 'process raft request' (duration: 1.220734143s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:08:06.734277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:08:05.50555Z","time spent":"1.228638297s","remote":"127.0.0.1:35080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T04:08:06.734297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:08:05.512831Z","time spent":"1.221441373s","remote":"127.0.0.1:34816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":845,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T04:08:06.734373Z","caller":"traceutil/trace.go:171","msg":"trace[1950286876] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"1.214157168s","start":"2026-04-14T04:08:05.520204Z","end":"2026-04-14T04:08:06.734361Z","steps":["trace[1950286876] 'process raft request' (duration: 1.213429168s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:08:06.734428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:08:05.520185Z","time spent":"1.214217339s","remote":"127.0.0.1:34708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3159,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T04:08:06.734459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.230360141s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-14T04:08:06.734498Z","caller":"traceutil/trace.go:171","msg":"trace[956265449] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"1.230415192s","start":"2026-04-14T04:08:05.50407Z","end":"2026-04-14T04:08:06.734485Z","steps":["trace[956265449] 'agreement among raft nodes before linearized reading' (duration: 1.23029636s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:08:06.734527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:08:05.504059Z","time spent":"1.230461082s","remote":"127.0.0.1:34624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-04-14T04:08:06.734587Z","caller":"traceutil/trace.go:171","msg":"trace[735008165] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"1.228384673s","start":"2026-04-14T04:08:05.505414Z","end":"2026-04-14T04:08:06.733799Z","steps":["trace[735008165] 'process raft request' (duration: 1.228022708s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:08:06.734691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:08:05.505401Z","time spent":"1.229255755s","remote":"127.0.0.1:35080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T04:08:07.234757Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853969805033332871,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-14T04:08:07.424609Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.275140981s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-14T04:08:08.507091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999878354s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-14T04:08:08.5072Z","caller":"traceutil/trace.go:171","msg":"trace[2140841379] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000067627s","start":"2026-04-14T04:08:06.507107Z","end":"2026-04-14T04:08:08.507175Z","steps":["trace[2140841379] 'agreement among raft nodes before linearized reading' (duration: 1.999937915s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:08:08.50726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:08:06.507074Z","time spent":"2.000175008s","remote":"127.0.0.1:34506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-14T04:08:08.548315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.123022492s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-14T04:08:08.548801Z","caller":"traceutil/trace.go:171","msg":"trace[1455304616] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"2.933658797s","start":"2026-04-14T04:08:05.615084Z","end":"2026-04-14T04:08:08.548742Z","steps":["trace[1455304616] 'process raft request' (duration: 1.809970576s)","trace[1455304616] 'compare' (duration: 1.122821398s)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:08:08.548868Z","caller":"traceutil/trace.go:171","msg":"trace[16863247] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"1.809297458s","start":"2026-04-14T04:08:06.739539Z","end":"2026-04-14T04:08:08.548837Z","steps":["trace[16863247] 'process raft request' (duration: 1.808924203s)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:08:08.548958Z","caller":"traceutil/trace.go:171","msg":"trace[357229335] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"1.809351179s","start":"2026-04-14T04:08:06.739595Z","end":"2026-04-14T04:08:08.548946Z","steps":["trace[357229335] 'process raft request' (duration: 1.809185887s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:08:08.54898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:08:05.615054Z","time spent":"2.93385756s","remote":"127.0.0.1:35016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T04:08:08.549002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:08:06.739567Z","time spent":"1.809412759s","remote":"127.0.0.1:35080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T04:08:08.549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:08:06.739525Z","time spent":"1.80941244s","remote":"127.0.0.1:35080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":923,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T04:08:08.548994Z","caller":"traceutil/trace.go:171","msg":"trace[1681428711] linearizableReadLoop","detail":"{readStateIndex:21; appliedIndex:14; }","duration":"1.815216402s","start":"2026-04-14T04:08:06.733755Z","end":"2026-04-14T04:08:08.548972Z","steps":["trace[1681428711] 'read index received' (duration: 691.39805ms)","trace[1681428711] 'applied index is now lower than readState.Index' (duration: 1.123817312s)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:08:08.549117Z","caller":"traceutil/trace.go:171","msg":"trace[1762436501] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"1.8094101s","start":"2026-04-14T04:08:06.739695Z","end":"2026-04-14T04:08:08.549105Z","steps":["trace[1762436501] 'process raft request' (duration: 1.809132246s)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:08:08.549124Z","caller":"traceutil/trace.go:171","msg":"trace[922823301] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"1.809521391s","start":"2026-04-14T04:08:06.739578Z","end":"2026-04-14T04:08:08.549099Z","steps":["trace[922823301] 'process raft request' (duration: 1.809161206s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:08:08.54918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:08:06.739684Z","time spent":"1.80945781s","remote":"127.0.0.1:35080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T04:08:08.549217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:08:06.739555Z","time spent":"1.809640183s","remote":"127.0.0.1:35080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T04:08:08.549314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.851635619s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-14T04:08:08.549356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.987221316s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-14T04:08:08.549359Z","caller":"traceutil/trace.go:171","msg":"trace[2061703980] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:18; }","duration":"2.851718709s","start":"2026-04-14T04:08:05.697626Z","end":"2026-04-14T04:08:08.549344Z","steps":["trace[2061703980] 'agreement among raft nodes before linearized reading' (duration: 2.851610998s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:08:08.54937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.809975818s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-qpqqb\" ","response":"range_response_count:1 size:857"} {"level":"info","ts":"2026-04-14T04:08:08.549385Z","caller":"traceutil/trace.go:171","msg":"trace[1151356505] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:18; }","duration":"2.987261967s","start":"2026-04-14T04:08:05.562116Z","end":"2026-04-14T04:08:08.549378Z","steps":["trace[1151356505] 'agreement among raft nodes before linearized reading' (duration: 2.987227356s)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:08:08.549393Z","caller":"traceutil/trace.go:171","msg":"trace[1703318535] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-qpqqb; range_end:; response_count:1; response_revision:18; }","duration":"1.810060119s","start":"2026-04-14T04:08:06.739327Z","end":"2026-04-14T04:08:08.549387Z","steps":["trace[1703318535] 'agreement among raft nodes before linearized reading' (duration: 1.810003688s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:08:08.549394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:08:05.697606Z","time spent":"2.85178149s","remote":"127.0.0.1:34826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":26,"request content":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" "} {"level":"warn","ts":"2026-04-14T04:08:08.549408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:08:06.739302Z","time spent":"1.810101699s","remote":"127.0.0.1:34816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":879,"request content":"key:\"/registry/certificatesigningrequests/csr-qpqqb\" "} {"level":"warn","ts":"2026-04-14T04:08:08.549432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.997862548s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-14T04:08:08.549448Z","caller":"traceutil/trace.go:171","msg":"trace[292716576] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:18; }","duration":"2.997899139s","start":"2026-04-14T04:08:05.551542Z","end":"2026-04-14T04:08:08.549441Z","steps":["trace[292716576] 'agreement among raft nodes before linearized reading' (duration: 2.997873388s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:08:08.54946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:08:05.55153Z","time spent":"2.997927509s","remote":"127.0.0.1:34958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":26,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-04-14T04:08:08.549511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.135306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-14T04:08:08.549524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.310304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-14T04:08:08.549545Z","caller":"traceutil/trace.go:171","msg":"trace[249443199] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18; }","duration":"730.410535ms","start":"2026-04-14T04:08:07.819128Z","end":"2026-04-14T04:08:08.549538Z","steps":["trace[249443199] 'agreement among raft nodes before linearized reading' (duration: 730.372454ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:08:08.549547Z","caller":"traceutil/trace.go:171","msg":"trace[1021341333] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:18; }","duration":"309.239568ms","start":"2026-04-14T04:08:08.240297Z","end":"2026-04-14T04:08:08.549536Z","steps":["trace[1021341333] 'agreement among raft nodes before linearized reading' (duration: 309.174607ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:08:08.549578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:08:08.240258Z","time spent":"309.31371ms","remote":"127.0.0.1:34624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-14T04:08:08.549585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.675137712s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-14T04:08:08.549605Z","caller":"traceutil/trace.go:171","msg":"trace[841090287] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:18; }","duration":"1.675193844s","start":"2026-04-14T04:08:06.874404Z","end":"2026-04-14T04:08:08.549598Z","steps":["trace[841090287] 'agreement among raft nodes before linearized reading' (duration: 1.675163543s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:08:08.549619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:08:06.87438Z","time spent":"1.675235944s","remote":"127.0.0.1:34624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-14T04:08:08.549559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:08:07.819094Z","time spent":"730.461605ms","remote":"127.0.0.1:34506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-14T04:08:08.549406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:08:05.562105Z","time spent":"2.987294217s","remote":"127.0.0.1:34624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/default\" "} {"level":"info","ts":"2026-04-14T04:08:08.54961Z","caller":"traceutil/trace.go:171","msg":"trace[546396146] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"1.808553798s","start":"2026-04-14T04:08:06.741038Z","end":"2026-04-14T04:08:08.549592Z","steps":["trace[546396146] 'process raft request' (duration: 1.807831498s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:08:08.55166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:08:06.741029Z","time spent":"1.810578086s","remote":"127.0.0.1:35080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T04:08:08.549326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.998483978s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-14T04:08:08.551834Z","caller":"traceutil/trace.go:171","msg":"trace[835225845] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:18; }","duration":"3.001016033s","start":"2026-04-14T04:08:05.550806Z","end":"2026-04-14T04:08:08.551822Z","steps":["trace[835225845] 'agreement among raft nodes before linearized reading' (duration: 2.998478317s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:08:08.55187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:08:05.550792Z","time spent":"3.001067625s","remote":"127.0.0.1:34624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-14T04:08:08.549612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.045374401s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-14T04:08:08.552005Z","caller":"traceutil/trace.go:171","msg":"trace[1763313352] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:18; }","duration":"3.047785795s","start":"2026-04-14T04:08:05.504208Z","end":"2026-04-14T04:08:08.551993Z","steps":["trace[1763313352] 'agreement among raft nodes before linearized reading' (duration: 3.04533279s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:08:08.552036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:08:05.504202Z","time spent":"3.047825535s","remote":"127.0.0.1:34600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":26,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-04-14T04:08:08.549644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.152955613s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-14T04:08:08.552224Z","caller":"traceutil/trace.go:171","msg":"trace[139657194] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:18; }","duration":"2.155596251s","start":"2026-04-14T04:08:06.396618Z","end":"2026-04-14T04:08:08.552214Z","steps":["trace[139657194] 'agreement among raft nodes before linearized reading' (duration: 2.153000164s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:08:08.552263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:08:06.396587Z","time spent":"2.155659541s","remote":"127.0.0.1:34928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":26,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"info","ts":"2026-04-14T04:08:08.549692Z","caller":"traceutil/trace.go:171","msg":"trace[489138841] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"1.804878804s","start":"2026-04-14T04:08:06.744798Z","end":"2026-04-14T04:08:08.549676Z","steps":["trace[489138841] 'process raft request' (duration: 1.804108443s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:08:08.552695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:08:06.744789Z","time spent":"1.807613833s","remote":"127.0.0.1:34708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3479,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T04:08:08.549736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.149372691s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-14T04:08:08.552871Z","caller":"traceutil/trace.go:171","msg":"trace[1953786865] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:18; }","duration":"2.152600676s","start":"2026-04-14T04:08:06.400258Z","end":"2026-04-14T04:08:08.552859Z","steps":["trace[1953786865] 'agreement among raft nodes before linearized reading' (duration: 2.149403492s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:08:08.552907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:08:06.400215Z","time spent":"2.152680027s","remote":"127.0.0.1:34898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"info","ts":"2026-04-14T04:08:08.869637Z","caller":"traceutil/trace.go:171","msg":"trace[1578759339] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"313.122674ms","start":"2026-04-14T04:08:08.556474Z","end":"2026-04-14T04:08:08.869597Z","steps":["trace[1578759339] 'process raft request' (duration: 227.737048ms)","trace[1578759339] 'compare' (duration: 85.133543ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T04:08:08.869953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:08:08.556463Z","time spent":"313.283106ms","remote":"127.0.0.1:35016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T04:08:08.874336Z","caller":"traceutil/trace.go:171","msg":"trace[484410741] linearizableReadLoop","detail":"{readStateIndex:26; appliedIndex:21; }","duration":"317.070429ms","start":"2026-04-14T04:08:08.557237Z","end":"2026-04-14T04:08:08.874307Z","steps":["trace[484410741] 'read index received' (duration: 226.991776ms)","trace[484410741] 'applied index is now lower than readState.Index' (duration: 90.077403ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:08:08.874484Z","caller":"traceutil/trace.go:171","msg":"trace[1352043816] transaction","detail":"{read_only:false; response_revision:24; number_of_response:1; }","duration":"317.197961ms","start":"2026-04-14T04:08:08.55726Z","end":"2026-04-14T04:08:08.874458Z","steps":["trace[1352043816] 'process raft request' (duration: 316.873637ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:08:08.874521Z","caller":"traceutil/trace.go:171","msg":"trace[345700339] transaction","detail":"{read_only:false; response_revision:22; number_of_response:1; }","duration":"317.473744ms","start":"2026-04-14T04:08:08.557016Z","end":"2026-04-14T04:08:08.87449Z","steps":["trace[345700339] 'process raft request' (duration: 317.050819ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:08:08.874573Z","caller":"traceutil/trace.go:171","msg":"trace[972679366] transaction","detail":"{read_only:false; response_revision:27; number_of_response:1; }","duration":"314.588133ms","start":"2026-04-14T04:08:08.559972Z","end":"2026-04-14T04:08:08.87456Z","steps":["trace[972679366] 'process raft request' (duration: 314.299059ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:08:08.874608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:08:08.55701Z","time spent":"317.551375ms","remote":"127.0.0.1:35080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":947,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T04:08:08.874638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:08:08.557231Z","time spent":"317.324022ms","remote":"127.0.0.1:34928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":429,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T04:08:08.874673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:08:08.559967Z","time spent":"314.638394ms","remote":"127.0.0.1:34958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":651,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T04:08:08.874686Z","caller":"traceutil/trace.go:171","msg":"trace[1010950132] transaction","detail":"{read_only:false; response_revision:23; number_of_response:1; }","duration":"317.522155ms","start":"2026-04-14T04:08:08.557154Z","end":"2026-04-14T04:08:08.874676Z","steps":["trace[1010950132] 'process raft request' (duration: 316.945597ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:08:08.874652Z","caller":"traceutil/trace.go:171","msg":"trace[1437418073] transaction","detail":"{read_only:false; number_of_response:0; response_revision:24; }","duration":"316.848076ms","start":"2026-04-14T04:08:08.557762Z","end":"2026-04-14T04:08:08.87461Z","steps":["trace[1437418073] 'process raft request' (duration: 316.40079ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:08:08.87476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:08:08.557144Z","time spent":"317.581026ms","remote":"127.0.0.1:34624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":338,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T04:08:08.874775Z","caller":"traceutil/trace.go:171","msg":"trace[1591413823] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"317.9163ms","start":"2026-04-14T04:08:08.556847Z","end":"2026-04-14T04:08:08.874763Z","steps":["trace[1591413823] 'process raft request' (duration: 317.063449ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:08:08.874823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:08:08.556828Z","time spent":"317.972993ms","remote":"127.0.0.1:35080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":956,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T04:08:08.874845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:08:08.557739Z","time spent":"317.018348ms","remote":"127.0.0.1:34624","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":26,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T04:08:08.874638Z","caller":"traceutil/trace.go:171","msg":"trace[714859188] transaction","detail":"{read_only:false; response_revision:26; number_of_response:1; }","duration":"316.230307ms","start":"2026-04-14T04:08:08.558389Z","end":"2026-04-14T04:08:08.874619Z","steps":["trace[714859188] 'process raft request' (duration: 315.849502ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:08:08.874951Z","caller":"traceutil/trace.go:171","msg":"trace[959583052] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"318.012932ms","start":"2026-04-14T04:08:08.556928Z","end":"2026-04-14T04:08:08.874941Z","steps":["trace[959583052] 'process raft request' (duration: 317.101599ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:08:08.874951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:08:08.558379Z","time spent":"316.543271ms","remote":"127.0.0.1:35080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1007,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T04:08:08.874996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:08:08.556922Z","time spent":"318.051752ms","remote":"127.0.0.1:35080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T04:08:08.874951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.698258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-14T04:08:08.87512Z","caller":"traceutil/trace.go:171","msg":"trace[1490800638] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:27; }","duration":"317.911471ms","start":"2026-04-14T04:08:08.557197Z","end":"2026-04-14T04:08:08.875108Z","steps":["trace[1490800638] 'agreement among raft nodes before linearized reading' (duration: 317.662278ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:08:08.875205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:08:08.557189Z","time spent":"317.989522ms","remote":"127.0.0.1:34914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":26,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" "} {"level":"info","ts":"2026-04-14T04:08:08.875303Z","caller":"traceutil/trace.go:171","msg":"trace[468668116] transaction","detail":"{read_only:false; response_revision:25; number_of_response:1; }","duration":"317.113329ms","start":"2026-04-14T04:08:08.558173Z","end":"2026-04-14T04:08:08.875287Z","steps":["trace[468668116] 'process raft request' (duration: 316.013774ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:08:08.875385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:08:08.558158Z","time spent":"317.17768ms","remote":"127.0.0.1:35080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1007,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T04:08:08.882293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.289457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-14T04:08:08.882333Z","caller":"traceutil/trace.go:171","msg":"trace[2009441732] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:28; }","duration":"250.355408ms","start":"2026-04-14T04:08:08.631968Z","end":"2026-04-14T04:08:08.882323Z","steps":["trace[2009441732] 'agreement among raft nodes before linearized reading' (duration: 250.258347ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:08:08.882348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.432378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-14T04:08:08.882381Z","caller":"traceutil/trace.go:171","msg":"trace[598585184] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:28; }","duration":"274.502828ms","start":"2026-04-14T04:08:08.607868Z","end":"2026-04-14T04:08:08.882371Z","steps":["trace[598585184] 'agreement among raft nodes before linearized reading' (duration: 274.435247ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:08:08.882564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.251984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"warn","ts":"2026-04-14T04:08:08.882448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.897287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-04-14T04:08:08.882848Z","caller":"traceutil/trace.go:171","msg":"trace[2146814773] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:28; }","duration":"271.443756ms","start":"2026-04-14T04:08:08.611392Z","end":"2026-04-14T04:08:08.882836Z","steps":["trace[2146814773] 'agreement among raft nodes before linearized reading' (duration: 270.844477ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:08:08.882685Z","caller":"traceutil/trace.go:171","msg":"trace[688449552] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:28; }","duration":"276.348694ms","start":"2026-04-14T04:08:08.606263Z","end":"2026-04-14T04:08:08.882612Z","steps":["trace[688449552] 'agreement among raft nodes before linearized reading' (duration: 276.199882ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:08:34.373385Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853969805033333823,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-14T04:08:34.552377Z","caller":"traceutil/trace.go:171","msg":"trace[2108738323] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"843.443133ms","start":"2026-04-14T04:08:33.708908Z","end":"2026-04-14T04:08:34.552351Z","steps":["trace[2108738323] 'process raft request' (duration: 843.291971ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:08:34.552555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:08:33.708883Z","time spent":"843.575914ms","remote":"127.0.0.1:34558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T04:08:34.61548Z","caller":"traceutil/trace.go:171","msg":"trace[477723183] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"906.283593ms","start":"2026-04-14T04:08:33.709171Z","end":"2026-04-14T04:08:34.615454Z","steps":["trace[477723183] 'process raft request' (duration: 906.126661ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:08:34.615567Z","caller":"traceutil/trace.go:171","msg":"trace[1394438384] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"901.550133ms","start":"2026-04-14T04:08:33.713991Z","end":"2026-04-14T04:08:34.615541Z","steps":["trace[1394438384] 'process raft request' (duration: 901.415751ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:08:34.615673Z","caller":"traceutil/trace.go:171","msg":"trace[2099654291] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"894.12705ms","start":"2026-04-14T04:08:33.721536Z","end":"2026-04-14T04:08:34.615663Z","steps":["trace[2099654291] 'process raft request' (duration: 893.910597ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:08:34.615683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:08:33.713978Z","time spent":"901.651844ms","remote":"127.0.0.1:34558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T04:08:34.615737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:08:33.721516Z","time spent":"894.1808ms","remote":"127.0.0.1:34558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T04:08:34.61578Z","caller":"traceutil/trace.go:171","msg":"trace[1893894311] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"888.941305ms","start":"2026-04-14T04:08:33.726816Z","end":"2026-04-14T04:08:34.615757Z","steps":["trace[1893894311] 'process raft request' (duration: 888.672091ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:08:34.615828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:08:33.726808Z","time spent":"888.997655ms","remote":"127.0.0.1:34558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T04:08:34.615584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:08:33.70916Z","time spent":"906.378054ms","remote":"127.0.0.1:34558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T04:08:34.616154Z","caller":"traceutil/trace.go:171","msg":"trace[821144407] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"888.170134ms","start":"2026-04-14T04:08:33.727955Z","end":"2026-04-14T04:08:34.616125Z","steps":["trace[821144407] 'process raft request' (duration: 887.79951ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:08:34.616213Z","caller":"traceutil/trace.go:171","msg":"trace[125429628] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"889.049576ms","start":"2026-04-14T04:08:33.726858Z","end":"2026-04-14T04:08:34.615907Z","steps":["trace[125429628] 'process raft request' (duration: 888.669322ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:08:34.616279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:08:33.72795Z","time spent":"888.267495ms","remote":"127.0.0.1:34558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T04:08:34.616331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:08:33.726854Z","time spent":"889.433721ms","remote":"127.0.0.1:34558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T04:08:34.616572Z","caller":"traceutil/trace.go:171","msg":"trace[512801845] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"888.207354ms","start":"2026-04-14T04:08:33.728351Z","end":"2026-04-14T04:08:34.616558Z","steps":["trace[512801845] 'process raft request' (duration: 887.730839ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:08:34.616643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:08:33.728345Z","time spent":"888.257845ms","remote":"127.0.0.1:34558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T04:08:34.617196Z","caller":"traceutil/trace.go:171","msg":"trace[1699180818] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"886.955008ms","start":"2026-04-14T04:08:33.730218Z","end":"2026-04-14T04:08:34.617173Z","steps":["trace[1699180818] 'process raft request' (duration: 886.29074ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:08:34.617323Z","caller":"traceutil/trace.go:171","msg":"trace[57260816] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"881.51289ms","start":"2026-04-14T04:08:33.735801Z","end":"2026-04-14T04:08:34.617314Z","steps":["trace[57260816] 'process raft request' (duration: 881.118755ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:08:34.617385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:08:33.735792Z","time spent":"881.56788ms","remote":"127.0.0.1:34558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T04:08:34.618275Z","caller":"traceutil/trace.go:171","msg":"trace[693711191] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"748.077168ms","start":"2026-04-14T04:08:33.870182Z","end":"2026-04-14T04:08:34.618259Z","steps":["trace[693711191] 'process raft request' (duration: 746.961524ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:08:34.618342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:08:33.870145Z","time spent":"748.16837ms","remote":"127.0.0.1:34558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T04:08:34.619548Z","caller":"traceutil/trace.go:171","msg":"trace[333231010] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"864.438425ms","start":"2026-04-14T04:08:33.753395Z","end":"2026-04-14T04:08:34.617833Z","steps":["trace[333231010] 'process raft request' (duration: 863.652674ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:08:34.619613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:08:33.753365Z","time spent":"866.221937ms","remote":"127.0.0.1:34558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T04:08:34.617211Z","caller":"traceutil/trace.go:171","msg":"trace[1212571369] linearizableReadLoop","detail":"{readStateIndex:527; appliedIndex:515; }","duration":"743.862275ms","start":"2026-04-14T04:08:33.873333Z","end":"2026-04-14T04:08:34.617195Z","steps":["trace[1212571369] 'read index received' (duration: 678.98026ms)","trace[1212571369] 'applied index is now lower than readState.Index' (duration: 64.881155ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T04:08:34.617291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.950286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T04:08:34.619794Z","caller":"traceutil/trace.go:171","msg":"trace[1679210692] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:511; }","duration":"746.492318ms","start":"2026-04-14T04:08:33.873294Z","end":"2026-04-14T04:08:34.619786Z","steps":["trace[1679210692] 'agreement among raft nodes before linearized reading' (duration: 743.947906ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:08:34.619821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:08:33.873275Z","time spent":"746.539659ms","remote":"127.0.0.1:34506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-14T04:08:34.620411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:08:33.730211Z","time spent":"887.111401ms","remote":"127.0.0.1:34558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T04:08:34.624651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.725949ms","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-14T04:08:34.62469Z","caller":"traceutil/trace.go:171","msg":"trace[1031199414] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:513; }","duration":"645.788131ms","start":"2026-04-14T04:08:33.978893Z","end":"2026-04-14T04:08:34.624681Z","steps":["trace[1031199414] 'agreement among raft nodes before linearized reading' (duration: 645.70951ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:08:34.627614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:08:33.978881Z","time spent":"648.725157ms","remote":"127.0.0.1:34826","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-14T04:08:34.625886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"705.971518ms","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-14T04:08:34.627761Z","caller":"traceutil/trace.go:171","msg":"trace[623641907] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:513; }","duration":"707.893722ms","start":"2026-04-14T04:08:33.919857Z","end":"2026-04-14T04:08:34.627751Z","steps":["trace[623641907] 'agreement among raft nodes before linearized reading' (duration: 705.964918ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:08:34.629327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:08:33.91983Z","time spent":"709.485033ms","remote":"127.0.0.1:34826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-14T04:08:39.586001Z","caller":"traceutil/trace.go:171","msg":"trace[1419924544] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"129.686257ms","start":"2026-04-14T04:08:39.456293Z","end":"2026-04-14T04:08:39.585979Z","steps":["trace[1419924544] 'process raft request' (duration: 129.503645ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:08:41.152856Z","caller":"traceutil/trace.go:171","msg":"trace[281202274] linearizableReadLoop","detail":"{readStateIndex:628; appliedIndex:627; }","duration":"450.647144ms","start":"2026-04-14T04:08:40.70218Z","end":"2026-04-14T04:08:41.152827Z","steps":["trace[281202274] 'read index received' (duration: 449.576411ms)","trace[281202274] 'applied index is now lower than readState.Index' (duration: 1.069403ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:08:41.153005Z","caller":"traceutil/trace.go:171","msg":"trace[1333683073] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"502.208767ms","start":"2026-04-14T04:08:40.650735Z","end":"2026-04-14T04:08:41.152944Z","steps":["trace[1333683073] 'process raft request' (duration: 501.338407ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:08:41.153241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.044429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-14T04:08:41.153286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:08:40.650699Z","time spent":"502.430509ms","remote":"127.0.0.1:34606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036297,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T04:08:41.153341Z","caller":"traceutil/trace.go:171","msg":"trace[1574859401] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:605; }","duration":"451.204581ms","start":"2026-04-14T04:08:40.702113Z","end":"2026-04-14T04:08:41.153318Z","steps":["trace[1574859401] 'agreement among raft nodes before linearized reading' (duration: 450.847136ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:08:41.153339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.811376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-14T04:08:41.15339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:08:40.702089Z","time spent":"451.291312ms","remote":"127.0.0.1:34826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-14T04:08:41.153413Z","caller":"traceutil/trace.go:171","msg":"trace[1364825310] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:605; }","duration":"278.868476ms","start":"2026-04-14T04:08:40.874521Z","end":"2026-04-14T04:08:41.15339Z","steps":["trace[1364825310] 'agreement among raft nodes before linearized reading' (duration: 278.796535ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:08:41.153254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.73201ms","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-14T04:08:41.153656Z","caller":"traceutil/trace.go:171","msg":"trace[1294891431] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:605; }","duration":"449.178256ms","start":"2026-04-14T04:08:40.70446Z","end":"2026-04-14T04:08:41.153638Z","steps":["trace[1294891431] 'agreement among raft nodes before linearized reading' (duration: 448.602968ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:08:41.153698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:08:40.704449Z","time spent":"449.236647ms","remote":"127.0.0.1:34826","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-14T04:08:41.153777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.757087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.116\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-14T04:08:41.153827Z","caller":"traceutil/trace.go:171","msg":"trace[2129108526] range","detail":"{range_begin:/registry/masterleases/199.204.45.116; range_end:; response_count:1; response_revision:605; }","duration":"442.824658ms","start":"2026-04-14T04:08:40.710991Z","end":"2026-04-14T04:08:41.153815Z","steps":["trace[2129108526] 'agreement among raft nodes before linearized reading' (duration: 442.733967ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:08:41.153858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:08:40.71098Z","time spent":"442.869839ms","remote":"127.0.0.1:34542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.116\" "} {"level":"warn","ts":"2026-04-14T04:08:41.716678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.501836ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"info","ts":"2026-04-14T04:08:41.716794Z","caller":"traceutil/trace.go:171","msg":"trace[185458113] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:628; }","duration":"560.546874ms","start":"2026-04-14T04:08:41.156228Z","end":"2026-04-14T04:08:41.716775Z","steps":["trace[185458113] 'read index received' (duration: 429.848336ms)","trace[185458113] 'applied index is now lower than readState.Index' (duration: 130.696828ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T04:08:41.716877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:08:41.155953Z","time spent":"560.918599ms","remote":"127.0.0.1:34542","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-14T04:08:41.71711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.834258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T04:08:41.717156Z","caller":"traceutil/trace.go:171","msg":"trace[1414604672] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:605; }","duration":"560.947279ms","start":"2026-04-14T04:08:41.156196Z","end":"2026-04-14T04:08:41.717143Z","steps":["trace[1414604672] 'agreement among raft nodes before linearized reading' (duration: 560.827977ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:08:41.717189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:08:41.15618Z","time spent":"561.002139ms","remote":"127.0.0.1:34514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-14T04:08:41.757295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.27792ms","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-14T04:08:41.757355Z","caller":"traceutil/trace.go:171","msg":"trace[1730093512] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:608; }","duration":"151.392061ms","start":"2026-04-14T04:08:41.605948Z","end":"2026-04-14T04:08:41.75734Z","steps":["trace[1730093512] 'agreement among raft nodes before linearized reading' (duration: 151.154658ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:08:41.75739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.927318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-66qpk\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-04-14T04:08:41.756938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.690494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-14T04:08:41.757503Z","caller":"traceutil/trace.go:171","msg":"trace[1855025245] range","detail":"{range_begin:/registry/pods/kube-system/cilium-66qpk; range_end:; response_count:1; response_revision:608; }","duration":"242.134311ms","start":"2026-04-14T04:08:41.515347Z","end":"2026-04-14T04:08:41.757481Z","steps":["trace[1855025245] 'agreement among raft nodes before linearized reading' (duration: 241.789786ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:08:41.757572Z","caller":"traceutil/trace.go:171","msg":"trace[1744215657] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"545.208576ms","start":"2026-04-14T04:08:41.212349Z","end":"2026-04-14T04:08:41.757558Z","steps":["trace[1744215657] 'process raft request' (duration: 544.328836ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:08:41.757564Z","caller":"traceutil/trace.go:171","msg":"trace[640481477] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:608; }","duration":"542.354762ms","start":"2026-04-14T04:08:41.215159Z","end":"2026-04-14T04:08:41.757514Z","steps":["trace[640481477] 'agreement among raft nodes before linearized reading' (duration: 541.578323ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:08:41.757377Z","caller":"traceutil/trace.go:171","msg":"trace[1178789696] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"596.260382ms","start":"2026-04-14T04:08:41.161075Z","end":"2026-04-14T04:08:41.757335Z","steps":["trace[1178789696] 'process raft request' (duration: 595.349001ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:08:41.757687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:08:41.212317Z","time spent":"545.294917ms","remote":"127.0.0.1:35062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T04:08:41.757758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:08:41.21513Z","time spent":"542.506523ms","remote":"127.0.0.1:34826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-14T04:08:41.75794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:08:41.161054Z","time spent":"596.660356ms","remote":"127.0.0.1:34826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T04:08:41.758122Z","caller":"traceutil/trace.go:171","msg":"trace[2065625319] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"596.714566ms","start":"2026-04-14T04:08:41.16135Z","end":"2026-04-14T04:08:41.758065Z","steps":["trace[2065625319] 'process raft request' (duration: 595.275859ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:08:41.758322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:08:41.161321Z","time spent":"596.903899ms","remote":"127.0.0.1:34826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T04:08:43.214236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.367161965s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T04:08:43.21436Z","caller":"traceutil/trace.go:171","msg":"trace[1412974776] linearizableReadLoop","detail":"{readStateIndex:636; appliedIndex:635; }","duration":"1.419132731s","start":"2026-04-14T04:08:41.795209Z","end":"2026-04-14T04:08:43.214342Z","steps":["trace[1412974776] 'read index received' (duration: 51.684142ms)","trace[1412974776] 'applied index is now lower than readState.Index' (duration: 1.367447139s)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:08:43.214454Z","caller":"traceutil/trace.go:171","msg":"trace[399747186] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"1.439969406s","start":"2026-04-14T04:08:41.774462Z","end":"2026-04-14T04:08:43.214432Z","steps":["trace[399747186] 'process raft request' (duration: 72.487207ms)","trace[399747186] 'compare' (duration: 1.366932533s)"],"step_count":2} {"level":"warn","ts":"2026-04-14T04:08:43.21451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:08:41.774451Z","time spent":"1.440028277s","remote":"127.0.0.1:34724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T04:08:43.214635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.417762884s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-14T04:08:43.214787Z","caller":"traceutil/trace.go:171","msg":"trace[670395431] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:612; }","duration":"1.417871335s","start":"2026-04-14T04:08:41.796818Z","end":"2026-04-14T04:08:43.214689Z","steps":["trace[670395431] 'agreement among raft nodes before linearized reading' (duration: 1.417683533s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:08:43.214797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.444349649s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T04:08:43.214875Z","caller":"traceutil/trace.go:171","msg":"trace[2031770342] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:612; }","duration":"1.44445751s","start":"2026-04-14T04:08:41.7704Z","end":"2026-04-14T04:08:43.214857Z","steps":["trace[2031770342] 'agreement among raft nodes before linearized reading' (duration: 1.444330289s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:08:43.214884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:08:41.796807Z","time spent":"1.418063038s","remote":"127.0.0.1:34542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"warn","ts":"2026-04-14T04:08:43.214927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:08:41.770387Z","time spent":"1.444530871s","remote":"127.0.0.1:34788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-04-14T04:08:43.215237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.111351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T04:08:43.216559Z","caller":"traceutil/trace.go:171","msg":"trace[957521606] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:612; }","duration":"343.170293ms","start":"2026-04-14T04:08:42.873111Z","end":"2026-04-14T04:08:43.216281Z","steps":["trace[957521606] 'agreement among raft nodes before linearized reading' (duration: 341.780026ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:08:43.216755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:08:42.87305Z","time spent":"343.690369ms","remote":"127.0.0.1:34506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-14T04:08:44.974652Z","caller":"traceutil/trace.go:171","msg":"trace[136473616] linearizableReadLoop","detail":"{readStateIndex:672; appliedIndex:671; }","duration":"102.168207ms","start":"2026-04-14T04:08:44.872464Z","end":"2026-04-14T04:08:44.974632Z","steps":["trace[136473616] 'read index received' (duration: 102.151957ms)","trace[136473616] 'applied index is now lower than readState.Index' (duration: 15.46µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:08:44.97471Z","caller":"traceutil/trace.go:171","msg":"trace[366638003] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"214.999002ms","start":"2026-04-14T04:08:44.759674Z","end":"2026-04-14T04:08:44.974673Z","steps":["trace[366638003] 'process raft request' (duration: 214.857661ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:08:44.974774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.289658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T04:08:44.974815Z","caller":"traceutil/trace.go:171","msg":"trace[1013901060] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:648; }","duration":"102.38705ms","start":"2026-04-14T04:08:44.872418Z","end":"2026-04-14T04:08:44.974805Z","steps":["trace[1013901060] 'agreement among raft nodes before linearized reading' (duration: 102.291929ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:08:58.758227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"924.04856ms","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-14T04:08:58.758317Z","caller":"traceutil/trace.go:171","msg":"trace[1915517029] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:772; }","duration":"924.167582ms","start":"2026-04-14T04:08:57.834131Z","end":"2026-04-14T04:08:58.758299Z","steps":["trace[1915517029] 'range keys from in-memory index tree' (duration: 923.905439ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:08:58.75853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"931.141942ms","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-14T04:08:58.758622Z","caller":"traceutil/trace.go:171","msg":"trace[885143867] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:772; }","duration":"931.277693ms","start":"2026-04-14T04:08:57.827326Z","end":"2026-04-14T04:08:58.758604Z","steps":["trace[885143867] 'range keys from in-memory index tree' (duration: 930.95658ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:08:58.758669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:08:57.827306Z","time spent":"931.353724ms","remote":"127.0.0.1:34826","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-14T04:08:58.759282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"897.902556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-14T04:08:58.759434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"897.020686ms","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-14T04:08:58.759511Z","caller":"traceutil/trace.go:171","msg":"trace[1404714486] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:772; }","duration":"897.096186ms","start":"2026-04-14T04:08:57.862404Z","end":"2026-04-14T04:08:58.7595Z","steps":["trace[1404714486] 'range keys from in-memory index tree' (duration: 896.932565ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:08:58.759545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:08:57.86237Z","time spent":"897.162888ms","remote":"127.0.0.1:34826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-14T04:08:58.759428Z","caller":"traceutil/trace.go:171","msg":"trace[471405562] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:772; }","duration":"898.104678ms","start":"2026-04-14T04:08:57.861297Z","end":"2026-04-14T04:08:58.759402Z","steps":["trace[471405562] 'range keys from in-memory index tree' (duration: 897.662713ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:08:58.759622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"887.05904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T04:08:58.759716Z","caller":"traceutil/trace.go:171","msg":"trace[906087932] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:772; }","duration":"887.220912ms","start":"2026-04-14T04:08:57.872484Z","end":"2026-04-14T04:08:58.759704Z","steps":["trace[906087932] 'range keys from in-memory index tree' (duration: 886.957129ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:08:58.759725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:08:57.861273Z","time spent":"898.403241ms","remote":"127.0.0.1:34826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-14T04:08:58.758383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:08:57.834121Z","time spent":"924.252423ms","remote":"127.0.0.1:34826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-14T04:08:58.759787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:08:57.872477Z","time spent":"887.296994ms","remote":"127.0.0.1:34506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-14T04:08:58.76895Z","caller":"traceutil/trace.go:171","msg":"trace[1235247289] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"148.720561ms","start":"2026-04-14T04:08:58.620209Z","end":"2026-04-14T04:08:58.768929Z","steps":["trace[1235247289] 'process raft request' (duration: 148.58713ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:08:58.769304Z","caller":"traceutil/trace.go:171","msg":"trace[1774945351] linearizableReadLoop","detail":"{readStateIndex:801; appliedIndex:801; }","duration":"111.427919ms","start":"2026-04-14T04:08:58.657866Z","end":"2026-04-14T04:08:58.769294Z","steps":["trace[1774945351] 'read index received' (duration: 111.423429ms)","trace[1774945351] 'applied index is now lower than readState.Index' (duration: 3.8µs)"],"step_count":2} {"level":"warn","ts":"2026-04-14T04:08:58.76945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.542611ms","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-14T04:08:58.76948Z","caller":"traceutil/trace.go:171","msg":"trace[1919686175] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:773; }","duration":"111.648322ms","start":"2026-04-14T04:08:58.657818Z","end":"2026-04-14T04:08:58.769466Z","steps":["trace[1919686175] 'agreement among raft nodes before linearized reading' (duration: 111.524721ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:09:55.021653Z","caller":"traceutil/trace.go:171","msg":"trace[414565622] linearizableReadLoop","detail":"{readStateIndex:1104; appliedIndex:1103; }","duration":"131.810657ms","start":"2026-04-14T04:09:54.889823Z","end":"2026-04-14T04:09:55.021633Z","steps":["trace[414565622] 'read index received' (duration: 131.68206ms)","trace[414565622] 'applied index is now lower than readState.Index' (duration: 127.887µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:09:55.021709Z","caller":"traceutil/trace.go:171","msg":"trace[573232388] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"228.127871ms","start":"2026-04-14T04:09:54.79355Z","end":"2026-04-14T04:09:55.021678Z","steps":["trace[573232388] 'process raft request' (duration: 227.952655ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:09:55.021773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.929043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T04:09:55.021801Z","caller":"traceutil/trace.go:171","msg":"trace[155066927] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1062; }","duration":"132.015792ms","start":"2026-04-14T04:09:54.889776Z","end":"2026-04-14T04:09:55.021792Z","steps":["trace[155066927] 'agreement among raft nodes before linearized reading' (duration: 131.938184ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:10:00.440627Z","caller":"traceutil/trace.go:171","msg":"trace[1050708173] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"125.305614ms","start":"2026-04-14T04:10:00.315294Z","end":"2026-04-14T04:10:00.440599Z","steps":["trace[1050708173] 'process raft request' (duration: 124.801438ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:10:01.280286Z","caller":"traceutil/trace.go:171","msg":"trace[621777959] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"166.790764ms","start":"2026-04-14T04:10:01.113464Z","end":"2026-04-14T04:10:01.280255Z","steps":["trace[621777959] 'process raft request' (duration: 166.582852ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:10:02.483065Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.202993996s","expected-duration":"1s"} {"level":"info","ts":"2026-04-14T04:10:02.489629Z","caller":"traceutil/trace.go:171","msg":"trace[510084786] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"1.375977868s","start":"2026-04-14T04:10:01.113627Z","end":"2026-04-14T04:10:02.489605Z","steps":["trace[510084786] 'process raft request' (duration: 1.369623998s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:10:02.489769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:10:01.11361Z","time spent":"1.376084119s","remote":"127.0.0.1:34826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T04:10:02.489756Z","caller":"traceutil/trace.go:171","msg":"trace[513099524] linearizableReadLoop","detail":"{readStateIndex:1128; appliedIndex:1125; }","duration":"1.317700397s","start":"2026-04-14T04:10:01.172033Z","end":"2026-04-14T04:10:02.489734Z","steps":["trace[513099524] 'read index received' (duration: 108.039039ms)","trace[513099524] 'applied index is now lower than readState.Index' (duration: 1.209660338s)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:10:02.489846Z","caller":"traceutil/trace.go:171","msg":"trace[426319996] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"1.37623392s","start":"2026-04-14T04:10:01.113602Z","end":"2026-04-14T04:10:02.489836Z","steps":["trace[426319996] 'process raft request' (duration: 1.375935837s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:10:02.48993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:10:01.113564Z","time spent":"1.3762999s","remote":"127.0.0.1:34826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T04:10:02.490104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.31805414s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-14T04:10:02.490201Z","caller":"traceutil/trace.go:171","msg":"trace[354336662] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1084; }","duration":"1.318201812s","start":"2026-04-14T04:10:01.171974Z","end":"2026-04-14T04:10:02.490176Z","steps":["trace[354336662] 'agreement among raft nodes before linearized reading' (duration: 1.31795682s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:10:02.490241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:10:01.171959Z","time spent":"1.318274453s","remote":"127.0.0.1:34826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-14T04:10:02.502952Z","caller":"traceutil/trace.go:171","msg":"trace[2095963512] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"1.044848682s","start":"2026-04-14T04:10:01.458073Z","end":"2026-04-14T04:10:02.502922Z","steps":["trace[2095963512] 'process raft request' (duration: 1.044692811s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:10:02.50302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.979727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T04:10:02.503082Z","caller":"traceutil/trace.go:171","msg":"trace[732692170] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1086; }","duration":"630.082357ms","start":"2026-04-14T04:10:01.872985Z","end":"2026-04-14T04:10:02.503067Z","steps":["trace[732692170] 'agreement among raft nodes before linearized reading' (duration: 629.968886ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:10:02.503081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:10:01.458052Z","time spent":"1.044962404s","remote":"127.0.0.1:34826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T04:10:02.503126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:10:01.87296Z","time spent":"630.157868ms","remote":"127.0.0.1:34506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-14T04:10:02.50313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.328133836s","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-14T04:10:02.503186Z","caller":"traceutil/trace.go:171","msg":"trace[1801364857] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1086; }","duration":"1.328284907s","start":"2026-04-14T04:10:01.174884Z","end":"2026-04-14T04:10:02.503168Z","steps":["trace[1801364857] 'agreement among raft nodes before linearized reading' (duration: 1.328021585s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:10:02.503232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:10:01.174873Z","time spent":"1.328347048s","remote":"127.0.0.1:34826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-14T04:10:22.791794Z","caller":"traceutil/trace.go:171","msg":"trace[373232339] transaction","detail":"{read_only:false; response_revision:1150; number_of_response:1; }","duration":"147.958275ms","start":"2026-04-14T04:10:22.64381Z","end":"2026-04-14T04:10:22.791768Z","steps":["trace[373232339] 'process raft request' (duration: 147.796704ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:10:22.80121Z","caller":"traceutil/trace.go:171","msg":"trace[1172702353] linearizableReadLoop","detail":"{readStateIndex:1199; appliedIndex:1197; }","duration":"126.09591ms","start":"2026-04-14T04:10:22.675074Z","end":"2026-04-14T04:10:22.80117Z","steps":["trace[1172702353] 'read index received' (duration: 116.454359ms)","trace[1172702353] 'applied index is now lower than readState.Index' (duration: 9.639931ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:10:22.801283Z","caller":"traceutil/trace.go:171","msg":"trace[1146153676] transaction","detail":"{read_only:false; response_revision:1151; number_of_response:1; }","duration":"131.047207ms","start":"2026-04-14T04:10:22.670185Z","end":"2026-04-14T04:10:22.801232Z","steps":["trace[1146153676] 'process raft request' (duration: 130.779384ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:10:22.801452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.351812ms","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-14T04:10:22.801539Z","caller":"traceutil/trace.go:171","msg":"trace[1234259450] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1151; }","duration":"126.499463ms","start":"2026-04-14T04:10:22.675008Z","end":"2026-04-14T04:10:22.801507Z","steps":["trace[1234259450] 'agreement among raft nodes before linearized reading' (duration: 126.291541ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:10:22.801753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.756849ms","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-14T04:10:22.801817Z","caller":"traceutil/trace.go:171","msg":"trace[1999047344] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1151; }","duration":"122.88448ms","start":"2026-04-14T04:10:22.678918Z","end":"2026-04-14T04:10:22.801802Z","steps":["trace[1999047344] 'agreement among raft nodes before linearized reading' (duration: 122.611888ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:10:23.902973Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853969805033335285,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-14T04:10:24.062165Z","caller":"traceutil/trace.go:171","msg":"trace[902626106] linearizableReadLoop","detail":"{readStateIndex:1202; appliedIndex:1201; }","duration":"659.230452ms","start":"2026-04-14T04:10:23.402911Z","end":"2026-04-14T04:10:24.062142Z","steps":["trace[902626106] 'read index received' (duration: 659.02795ms)","trace[902626106] 'applied index is now lower than readState.Index' (duration: 201.692µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:10:24.062311Z","caller":"traceutil/trace.go:171","msg":"trace[1607978385] transaction","detail":"{read_only:false; response_revision:1154; number_of_response:1; }","duration":"1.00010231s","start":"2026-04-14T04:10:23.062161Z","end":"2026-04-14T04:10:24.062263Z","steps":["trace[1607978385] 'process raft request' (duration: 999.833577ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:10:24.062401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.476654ms","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-14T04:10:24.062439Z","caller":"traceutil/trace.go:171","msg":"trace[768929979] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1154; }","duration":"659.567505ms","start":"2026-04-14T04:10:23.402859Z","end":"2026-04-14T04:10:24.062427Z","steps":["trace[768929979] 'agreement among raft nodes before linearized reading' (duration: 659.419614ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:10:24.062476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:10:23.402838Z","time spent":"659.628896ms","remote":"127.0.0.1:34826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-14T04:10:24.062561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:10:23.062116Z","time spent":"1.000307882s","remote":"127.0.0.1:34826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T04:10:24.062613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.317448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T04:10:24.062694Z","caller":"traceutil/trace.go:171","msg":"trace[493592920] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1154; }","duration":"188.446088ms","start":"2026-04-14T04:10:23.874227Z","end":"2026-04-14T04:10:24.062673Z","steps":["trace[493592920] 'agreement among raft nodes before linearized reading' (duration: 188.316137ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:10:24.364758Z","caller":"traceutil/trace.go:171","msg":"trace[1328404579] transaction","detail":"{read_only:false; response_revision:1155; number_of_response:1; }","duration":"296.373638ms","start":"2026-04-14T04:10:24.068349Z","end":"2026-04-14T04:10:24.364723Z","steps":["trace[1328404579] 'process raft request' (duration: 296.146686ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:10:26.233041Z","caller":"traceutil/trace.go:171","msg":"trace[570816290] linearizableReadLoop","detail":"{readStateIndex:1209; appliedIndex:1208; }","duration":"362.001317ms","start":"2026-04-14T04:10:25.87101Z","end":"2026-04-14T04:10:26.233011Z","steps":["trace[570816290] 'read index received' (duration: 361.795275ms)","trace[570816290] 'applied index is now lower than readState.Index' (duration: 204.472µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:10:26.23316Z","caller":"traceutil/trace.go:171","msg":"trace[17952504] transaction","detail":"{read_only:false; response_revision:1161; number_of_response:1; }","duration":"531.977362ms","start":"2026-04-14T04:10:25.701146Z","end":"2026-04-14T04:10:26.233123Z","steps":["trace[17952504] 'process raft request' (duration: 531.656169ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:10:26.233263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.235039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T04:10:26.233346Z","caller":"traceutil/trace.go:171","msg":"trace[1871454206] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1161; }","duration":"362.372861ms","start":"2026-04-14T04:10:25.870955Z","end":"2026-04-14T04:10:26.233328Z","steps":["trace[1871454206] 'agreement among raft nodes before linearized reading' (duration: 362.178279ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:10:26.233395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:10:25.87093Z","time spent":"362.457021ms","remote":"127.0.0.1:34514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-14T04:10:26.2335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:10:25.701117Z","time spent":"532.191713ms","remote":"127.0.0.1:34826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T04:11:37.488051Z","caller":"traceutil/trace.go:171","msg":"trace[101033206] transaction","detail":"{read_only:false; response_revision:1397; number_of_response:1; }","duration":"118.165349ms","start":"2026-04-14T04:11:37.36985Z","end":"2026-04-14T04:11:37.488015Z","steps":["trace[101033206] 'process raft request' (duration: 117.881553ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:14:39.429398Z","caller":"traceutil/trace.go:171","msg":"trace[863370139] transaction","detail":"{read_only:false; response_revision:1993; number_of_response:1; }","duration":"218.445502ms","start":"2026-04-14T04:14:39.210924Z","end":"2026-04-14T04:14:39.42937Z","steps":["trace[863370139] 'process raft request' (duration: 218.294118ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:14:39.99982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.422309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-14T04:14:39.999857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.520456ms","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-14T04:14:39.999893Z","caller":"traceutil/trace.go:171","msg":"trace[1782778816] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1993; }","duration":"126.543302ms","start":"2026-04-14T04:14:39.873333Z","end":"2026-04-14T04:14:39.999876Z","steps":["trace[1782778816] 'range keys from in-memory index tree' (duration: 126.350388ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:14:39.999917Z","caller":"traceutil/trace.go:171","msg":"trace[1190466883] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1993; }","duration":"179.625889ms","start":"2026-04-14T04:14:39.820277Z","end":"2026-04-14T04:14:39.999903Z","steps":["trace[1190466883] 'range keys from in-memory index tree' (duration: 179.351622ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:14:39.999933Z","caller":"traceutil/trace.go:171","msg":"trace[493774132] transaction","detail":"{read_only:false; response_revision:1994; number_of_response:1; }","duration":"114.601825ms","start":"2026-04-14T04:14:39.885283Z","end":"2026-04-14T04:14:39.999885Z","steps":["trace[493774132] 'process raft request' (duration: 33.194028ms)","trace[493774132] 'compare' (duration: 81.242773ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T04:14:40.988382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.550204ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T04:14:40.988485Z","caller":"traceutil/trace.go:171","msg":"trace[544241802] linearizableReadLoop","detail":"{readStateIndex:2094; appliedIndex:2093; }","duration":"857.734291ms","start":"2026-04-14T04:14:40.130734Z","end":"2026-04-14T04:14:40.988468Z","steps":["trace[544241802] 'read index received' (duration: 408.915562ms)","trace[544241802] 'applied index is now lower than readState.Index' (duration: 448.816899ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:14:40.988509Z","caller":"traceutil/trace.go:171","msg":"trace[612661110] transaction","detail":"{read_only:false; response_revision:1995; number_of_response:1; }","duration":"984.933478ms","start":"2026-04-14T04:14:40.003545Z","end":"2026-04-14T04:14:40.988479Z","steps":["trace[612661110] 'process raft request' (duration: 536.188581ms)","trace[612661110] 'compare' (duration: 448.342628ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T04:14:40.988694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:14:40.003526Z","time spent":"985.094222ms","remote":"127.0.0.1:34826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T04:14:40.988776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"858.035778ms","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-14T04:14:40.988824Z","caller":"traceutil/trace.go:171","msg":"trace[959130577] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1995; }","duration":"858.13354ms","start":"2026-04-14T04:14:40.130678Z","end":"2026-04-14T04:14:40.988812Z","steps":["trace[959130577] 'agreement among raft nodes before linearized reading' (duration: 857.939186ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:14:40.988858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:14:40.130655Z","time spent":"858.194701ms","remote":"127.0.0.1:34826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-14T04:14:40.98903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.705509ms","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-14T04:14:40.989056Z","caller":"traceutil/trace.go:171","msg":"trace[457155563] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1995; }","duration":"691.761591ms","start":"2026-04-14T04:14:40.297286Z","end":"2026-04-14T04:14:40.989048Z","steps":["trace[457155563] 'agreement among raft nodes before linearized reading' (duration: 691.681919ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:14:40.989082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:14:40.297267Z","time spent":"691.809302ms","remote":"127.0.0.1:34826","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-14T04:14:40.989109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.374243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T04:14:40.989189Z","caller":"traceutil/trace.go:171","msg":"trace[1700083348] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1995; }","duration":"330.497726ms","start":"2026-04-14T04:14:40.658667Z","end":"2026-04-14T04:14:40.989164Z","steps":["trace[1700083348] 'agreement among raft nodes before linearized reading' (duration: 330.364093ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:14:40.98923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:14:40.658648Z","time spent":"330.572327ms","remote":"127.0.0.1:47922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-04-14T04:14:40.989255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.47683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.116\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-14T04:14:40.989295Z","caller":"traceutil/trace.go:171","msg":"trace[1468213787] range","detail":"{range_begin:/registry/masterleases/199.204.45.116; range_end:; response_count:1; response_revision:1995; }","duration":"257.536332ms","start":"2026-04-14T04:14:40.731747Z","end":"2026-04-14T04:14:40.989284Z","steps":["trace[1468213787] 'agreement among raft nodes before linearized reading' (duration: 257.380308ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:14:40.989296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.070441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-14T04:14:40.989327Z","caller":"traceutil/trace.go:171","msg":"trace[515857935] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1995; }","duration":"517.134402ms","start":"2026-04-14T04:14:40.472184Z","end":"2026-04-14T04:14:40.989319Z","steps":["trace[515857935] 'agreement among raft nodes before linearized reading' (duration: 517.04652ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:14:40.989353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:14:40.472167Z","time spent":"517.180373ms","remote":"127.0.0.1:34826","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-14T04:14:40.989507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.36159ms","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-14T04:14:40.989545Z","caller":"traceutil/trace.go:171","msg":"trace[1440722256] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1995; }","duration":"542.432472ms","start":"2026-04-14T04:14:40.447103Z","end":"2026-04-14T04:14:40.989535Z","steps":["trace[1440722256] 'agreement among raft nodes before linearized reading' (duration: 542.348319ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:14:40.989571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:14:40.447082Z","time spent":"542.483272ms","remote":"127.0.0.1:34826","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-14T04:15:02.663821Z","caller":"traceutil/trace.go:171","msg":"trace[1601060704] transaction","detail":"{read_only:false; response_revision:2067; number_of_response:1; }","duration":"582.813473ms","start":"2026-04-14T04:15:02.080974Z","end":"2026-04-14T04:15:02.663787Z","steps":["trace[1601060704] 'process raft request' (duration: 582.684749ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:15:02.663985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:15:02.080942Z","time spent":"582.959236ms","remote":"127.0.0.1:34826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T04:15:02.940831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.261223ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T04:15:02.94097Z","caller":"traceutil/trace.go:171","msg":"trace[650098712] linearizableReadLoop","detail":"{readStateIndex:2172; appliedIndex:2170; }","duration":"450.842748ms","start":"2026-04-14T04:15:02.490106Z","end":"2026-04-14T04:15:02.940949Z","steps":["trace[650098712] 'read index received' (duration: 173.574112ms)","trace[650098712] 'applied index is now lower than readState.Index' (duration: 277.267406ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:15:02.941121Z","caller":"traceutil/trace.go:171","msg":"trace[1863347584] transaction","detail":"{read_only:false; response_revision:2068; number_of_response:1; }","duration":"731.651651ms","start":"2026-04-14T04:15:02.209436Z","end":"2026-04-14T04:15:02.941088Z","steps":["trace[1863347584] 'process raft request' (duration: 566.038838ms)","trace[1863347584] 'compare' (duration: 165.124ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T04:15:02.941166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.025114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-14T04:15:02.941262Z","caller":"traceutil/trace.go:171","msg":"trace[1263171538] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:2068; }","duration":"451.153397ms","start":"2026-04-14T04:15:02.490063Z","end":"2026-04-14T04:15:02.941216Z","steps":["trace[1263171538] 'agreement among raft nodes before linearized reading' (duration: 451.021334ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:15:02.941287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:15:02.209407Z","time spent":"731.797585ms","remote":"127.0.0.1:34826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T04:15:02.941306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:15:02.490039Z","time spent":"451.25752ms","remote":"127.0.0.1:47884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-14T04:15:03.593904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.143754ms","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-14T04:15:03.593978Z","caller":"traceutil/trace.go:171","msg":"trace[1545430911] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2068; }","duration":"465.251667ms","start":"2026-04-14T04:15:03.12871Z","end":"2026-04-14T04:15:03.593962Z","steps":["trace[1545430911] 'range keys from in-memory index tree' (duration: 464.980278ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:15:03.594183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.865501ms","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-14T04:15:03.594015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:15:03.128696Z","time spent":"465.309138ms","remote":"127.0.0.1:34826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-14T04:15:03.594316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.257023ms","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-14T04:15:03.594351Z","caller":"traceutil/trace.go:171","msg":"trace[499568921] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2068; }","duration":"462.331655ms","start":"2026-04-14T04:15:03.132012Z","end":"2026-04-14T04:15:03.594344Z","steps":["trace[499568921] 'range keys from in-memory index tree' (duration: 462.150191ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:15:03.594397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:15:03.13199Z","time spent":"462.399407ms","remote":"127.0.0.1:34826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-14T04:15:03.59424Z","caller":"traceutil/trace.go:171","msg":"trace[1245871415] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2068; }","duration":"460.943312ms","start":"2026-04-14T04:15:03.133284Z","end":"2026-04-14T04:15:03.594228Z","steps":["trace[1245871415] 'range keys from in-memory index tree' (duration: 460.767118ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:15:03.594382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.932422ms","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-14T04:15:03.594541Z","caller":"traceutil/trace.go:171","msg":"trace[373516933] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2068; }","duration":"461.176968ms","start":"2026-04-14T04:15:03.133346Z","end":"2026-04-14T04:15:03.594523Z","steps":["trace[373516933] 'range keys from in-memory index tree' (duration: 460.731837ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:15:03.594619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:15:03.133324Z","time spent":"461.28259ms","remote":"127.0.0.1:34826","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-14T04:15:03.594463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:15:03.133276Z","time spent":"461.170867ms","remote":"127.0.0.1:34826","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-14T04:15:03.594262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.868919ms","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-14T04:15:03.59501Z","caller":"traceutil/trace.go:171","msg":"trace[1266421841] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2068; }","duration":"473.674167ms","start":"2026-04-14T04:15:03.121322Z","end":"2026-04-14T04:15:03.594996Z","steps":["trace[1266421841] 'range keys from in-memory index tree' (duration: 472.685954ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:15:03.595084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:15:03.121303Z","time spent":"473.76708ms","remote":"127.0.0.1:34826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-14T04:15:03.841638Z","caller":"traceutil/trace.go:171","msg":"trace[447703571] transaction","detail":"{read_only:false; response_revision:2070; number_of_response:1; }","duration":"242.213323ms","start":"2026-04-14T04:15:03.599397Z","end":"2026-04-14T04:15:03.84161Z","steps":["trace[447703571] 'process raft request' (duration: 241.888245ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:15:03.841755Z","caller":"traceutil/trace.go:171","msg":"trace[934871901] transaction","detail":"{read_only:false; response_revision:2071; number_of_response:1; }","duration":"242.271664ms","start":"2026-04-14T04:15:03.599456Z","end":"2026-04-14T04:15:03.841727Z","steps":["trace[934871901] 'process raft request' (duration: 242.059239ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:15:03.84196Z","caller":"traceutil/trace.go:171","msg":"trace[242331677] transaction","detail":"{read_only:false; response_revision:2072; number_of_response:1; }","duration":"242.283534ms","start":"2026-04-14T04:15:03.599664Z","end":"2026-04-14T04:15:03.841947Z","steps":["trace[242331677] 'process raft request' (duration: 241.894735ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:15:03.842021Z","caller":"traceutil/trace.go:171","msg":"trace[614123014] transaction","detail":"{read_only:false; response_revision:2073; number_of_response:1; }","duration":"242.345926ms","start":"2026-04-14T04:15:03.599663Z","end":"2026-04-14T04:15:03.842009Z","steps":["trace[614123014] 'process raft request' (duration: 242.253063ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:15:05.81747Z","caller":"traceutil/trace.go:171","msg":"trace[1040576183] transaction","detail":"{read_only:false; response_revision:2075; number_of_response:1; }","duration":"142.760962ms","start":"2026-04-14T04:15:05.674684Z","end":"2026-04-14T04:15:05.817445Z","steps":["trace[1040576183] 'process raft request' (duration: 142.611668ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:15:06.321504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.491944ms","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-14T04:15:06.321633Z","caller":"traceutil/trace.go:171","msg":"trace[1521290700] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2075; }","duration":"206.650128ms","start":"2026-04-14T04:15:06.114963Z","end":"2026-04-14T04:15:06.321613Z","steps":["trace[1521290700] 'range keys from in-memory index tree' (duration: 206.477414ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:15:06.36366Z","caller":"traceutil/trace.go:171","msg":"trace[966638432] linearizableReadLoop","detail":"{readStateIndex:2180; appliedIndex:2179; }","duration":"518.177838ms","start":"2026-04-14T04:15:05.845451Z","end":"2026-04-14T04:15:06.363629Z","steps":["trace[966638432] 'read index received' (duration: 469.809766ms)","trace[966638432] 'applied index is now lower than readState.Index' (duration: 48.366602ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T04:15:06.363932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.471145ms","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-14T04:15:06.36399Z","caller":"traceutil/trace.go:171","msg":"trace[1689976523] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2075; }","duration":"518.570467ms","start":"2026-04-14T04:15:05.845406Z","end":"2026-04-14T04:15:06.363977Z","steps":["trace[1689976523] 'agreement among raft nodes before linearized reading' (duration: 518.355112ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:15:06.364019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.048655ms","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-14T04:15:06.36403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:15:05.845382Z","time spent":"518.638428ms","remote":"127.0.0.1:34826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-14T04:15:06.364067Z","caller":"traceutil/trace.go:171","msg":"trace[1708463795] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2075; }","duration":"518.114986ms","start":"2026-04-14T04:15:05.845938Z","end":"2026-04-14T04:15:06.364053Z","steps":["trace[1708463795] 'agreement among raft nodes before linearized reading' (duration: 517.953982ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:15:06.364111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:15:05.845931Z","time spent":"518.172037ms","remote":"127.0.0.1:34826","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-14T04:15:06.364132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.307871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-14T04:15:06.364137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.020934ms","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-14T04:15:06.364259Z","caller":"traceutil/trace.go:171","msg":"trace[600500411] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2075; }","duration":"518.208418ms","start":"2026-04-14T04:15:05.846025Z","end":"2026-04-14T04:15:06.364233Z","steps":["trace[600500411] 'agreement among raft nodes before linearized reading' (duration: 517.762188ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:15:06.36434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:15:05.846016Z","time spent":"518.310051ms","remote":"127.0.0.1:34826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-14T04:15:06.36418Z","caller":"traceutil/trace.go:171","msg":"trace[1044249074] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2075; }","duration":"518.400663ms","start":"2026-04-14T04:15:05.845767Z","end":"2026-04-14T04:15:06.364168Z","steps":["trace[1044249074] 'agreement among raft nodes before linearized reading' (duration: 518.021044ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:15:06.364685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:15:05.84576Z","time spent":"518.804453ms","remote":"127.0.0.1:34826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-14T04:15:07.935774Z","caller":"traceutil/trace.go:171","msg":"trace[2012355014] transaction","detail":"{read_only:false; response_revision:2081; number_of_response:1; }","duration":"108.241921ms","start":"2026-04-14T04:15:07.827507Z","end":"2026-04-14T04:15:07.935749Z","steps":["trace[2012355014] 'process raft request' (duration: 108.080937ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:15:08.373678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.004293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-14T04:15:08.373733Z","caller":"traceutil/trace.go:171","msg":"trace[649861487] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:2081; }","duration":"191.075725ms","start":"2026-04-14T04:15:08.182644Z","end":"2026-04-14T04:15:08.37372Z","steps":["trace[649861487] 'count revisions from in-memory index tree' (duration: 190.862249ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:15:08.661419Z","caller":"traceutil/trace.go:171","msg":"trace[1915736991] transaction","detail":"{read_only:false; response_revision:2083; number_of_response:1; }","duration":"235.315397ms","start":"2026-04-14T04:15:08.42608Z","end":"2026-04-14T04:15:08.661395Z","steps":["trace[1915736991] 'process raft request' (duration: 203.653326ms)","trace[1915736991] 'compare' (duration: 31.561519ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:15:08.72544Z","caller":"traceutil/trace.go:171","msg":"trace[1240695649] linearizableReadLoop","detail":"{readStateIndex:2190; appliedIndex:2187; }","duration":"297.529342ms","start":"2026-04-14T04:15:08.427878Z","end":"2026-04-14T04:15:08.725407Z","steps":["trace[1240695649] 'read index received' (duration: 201.866593ms)","trace[1240695649] 'applied index is now lower than readState.Index' (duration: 95.661379ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:15:08.725482Z","caller":"traceutil/trace.go:171","msg":"trace[1835057448] transaction","detail":"{read_only:false; response_revision:2084; number_of_response:1; }","duration":"299.038038ms","start":"2026-04-14T04:15:08.426412Z","end":"2026-04-14T04:15:08.72545Z","steps":["trace[1835057448] 'process raft request' (duration: 298.788342ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:15:08.725534Z","caller":"traceutil/trace.go:171","msg":"trace[915667383] transaction","detail":"{read_only:false; response_revision:2085; number_of_response:1; }","duration":"297.965802ms","start":"2026-04-14T04:15:08.427533Z","end":"2026-04-14T04:15:08.725499Z","steps":["trace[915667383] 'process raft request' (duration: 297.831949ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:15:08.725634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.733776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T04:15:08.725698Z","caller":"traceutil/trace.go:171","msg":"trace[1460072195] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:2085; }","duration":"297.840449ms","start":"2026-04-14T04:15:08.427843Z","end":"2026-04-14T04:15:08.725684Z","steps":["trace[1460072195] 'agreement among raft nodes before linearized reading' (duration: 297.674196ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:15:08.961543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.4696ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T04:15:08.961749Z","caller":"traceutil/trace.go:171","msg":"trace[925956717] linearizableReadLoop","detail":"{readStateIndex:2191; appliedIndex:2190; }","duration":"133.506761ms","start":"2026-04-14T04:15:08.828194Z","end":"2026-04-14T04:15:08.9617Z","steps":["trace[925956717] 'read index received' (duration: 58.031µs)","trace[925956717] 'applied index is now lower than readState.Index' (duration: 133.44723ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:15:08.961816Z","caller":"traceutil/trace.go:171","msg":"trace[966046367] transaction","detail":"{read_only:false; response_revision:2086; number_of_response:1; }","duration":"237.131482ms","start":"2026-04-14T04:15:08.724643Z","end":"2026-04-14T04:15:08.961774Z","steps":["trace[966046367] 'process raft request' (duration: 56.270332ms)","trace[966046367] 'compare' (duration: 180.273585ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T04:15:08.961997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.783027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-14T04:15:08.962076Z","caller":"traceutil/trace.go:171","msg":"trace[2067286325] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:2086; }","duration":"133.92103ms","start":"2026-04-14T04:15:08.828137Z","end":"2026-04-14T04:15:08.962058Z","steps":["trace[2067286325] 'agreement among raft nodes before linearized reading' (duration: 133.774087ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:15:22.962806Z","caller":"traceutil/trace.go:171","msg":"trace[2012597991] transaction","detail":"{read_only:false; response_revision:2182; number_of_response:1; }","duration":"144.80347ms","start":"2026-04-14T04:15:22.817971Z","end":"2026-04-14T04:15:22.962774Z","steps":["trace[2012597991] 'process raft request' (duration: 79.49209ms)","trace[2012597991] 'compare' (duration: 65.195197ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:15:22.96496Z","caller":"traceutil/trace.go:171","msg":"trace[1866815734] transaction","detail":"{read_only:false; response_revision:2183; number_of_response:1; }","duration":"146.16912ms","start":"2026-04-14T04:15:22.818774Z","end":"2026-04-14T04:15:22.964943Z","steps":["trace[1866815734] 'process raft request' (duration: 145.880594ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:15:25.373152Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853969805033337966,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-14T04:15:25.68898Z","caller":"traceutil/trace.go:171","msg":"trace[265133363] transaction","detail":"{read_only:false; response_revision:2188; number_of_response:1; }","duration":"897.055501ms","start":"2026-04-14T04:15:24.791899Z","end":"2026-04-14T04:15:25.688954Z","steps":["trace[265133363] 'process raft request' (duration: 896.905007ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:15:25.689128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:15:24.791882Z","time spent":"897.168853ms","remote":"127.0.0.1:34826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T04:15:26.872759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000106095s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-14T04:15:26.872864Z","caller":"traceutil/trace.go:171","msg":"trace[2006932663] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000224507s","start":"2026-04-14T04:15:24.872618Z","end":"2026-04-14T04:15:26.872842Z","steps":["trace[2006932663] 'agreement among raft nodes before linearized reading' (duration: 2.000098365s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:15:26.872915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:15:24.872574Z","time spent":"2.00033102s","remote":"127.0.0.1:34514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/14 04:15:26 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-14T04:15:27.143891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000731029s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-14T04:15:27.143975Z","caller":"traceutil/trace.go:171","msg":"trace[784283111] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000930404s","start":"2026-04-14T04:15:25.143025Z","end":"2026-04-14T04:15:27.143955Z","steps":["trace[784283111] 'agreement among raft nodes before linearized reading' (duration: 2.00077077s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:15:27.144024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:15:25.142995Z","time spent":"2.001019526s","remote":"127.0.0.1:34506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/14 04:15:27 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-14T04:15:27.232139Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.543057712s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-14T04:15:27.241468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.125760082s","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-14T04:15:27.241562Z","caller":"traceutil/trace.go:171","msg":"trace[832490248] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2188; }","duration":"1.125873685s","start":"2026-04-14T04:15:26.115674Z","end":"2026-04-14T04:15:27.241547Z","steps":["trace[832490248] 'range keys from in-memory index tree' (duration: 1.125738032s)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:15:27.241941Z","caller":"traceutil/trace.go:171","msg":"trace[662966755] linearizableReadLoop","detail":"{readStateIndex:2299; appliedIndex:2297; }","duration":"2.369286974s","start":"2026-04-14T04:15:24.872627Z","end":"2026-04-14T04:15:27.241914Z","steps":["trace[662966755] 'read index received' (duration: 816.292452ms)","trace[662966755] 'applied index is now lower than readState.Index' (duration: 1.552993342s)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:15:27.241976Z","caller":"traceutil/trace.go:171","msg":"trace[683576626] transaction","detail":"{read_only:false; response_revision:2189; number_of_response:1; }","duration":"2.126071716s","start":"2026-04-14T04:15:25.115879Z","end":"2026-04-14T04:15:27.241951Z","steps":["trace[683576626] 'process raft request' (duration: 2.116381161s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:15:27.242161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:15:25.115846Z","time spent":"2.126189179s","remote":"127.0.0.1:34826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T04:15:27.3342Z","caller":"traceutil/trace.go:171","msg":"trace[132825132] transaction","detail":"{read_only:false; response_revision:2190; number_of_response:1; }","duration":"458.418756ms","start":"2026-04-14T04:15:26.875746Z","end":"2026-04-14T04:15:27.334164Z","steps":["trace[132825132] 'process raft request' (duration: 458.194691ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:15:27.33431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.196194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-14T04:15:27.334332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:15:26.875725Z","time spent":"458.543899ms","remote":"127.0.0.1:34586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T04:15:27.334346Z","caller":"traceutil/trace.go:171","msg":"trace[1912785924] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2190; }","duration":"453.279436ms","start":"2026-04-14T04:15:26.881057Z","end":"2026-04-14T04:15:27.334336Z","steps":["trace[1912785924] 'agreement among raft nodes before linearized reading' (duration: 453.213855ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:15:27.334372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:15:26.881036Z","time spent":"453.332248ms","remote":"127.0.0.1:34506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-14T04:15:27.334378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.258193074s","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-14T04:15:27.33439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.367320659s","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-14T04:15:27.334433Z","caller":"traceutil/trace.go:171","msg":"trace[1840049517] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2190; }","duration":"1.258288795s","start":"2026-04-14T04:15:26.076131Z","end":"2026-04-14T04:15:27.334419Z","steps":["trace[1840049517] 'agreement among raft nodes before linearized reading' (duration: 1.258081891s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:15:27.334462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:15:26.07611Z","time spent":"1.258347727s","remote":"127.0.0.1:34826","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-14T04:15:27.334466Z","caller":"traceutil/trace.go:171","msg":"trace[1442411870] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2190; }","duration":"2.367444582s","start":"2026-04-14T04:15:24.967Z","end":"2026-04-14T04:15:27.334445Z","steps":["trace[1442411870] 'agreement among raft nodes before linearized reading' (duration: 2.367169126s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:15:27.334506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:15:24.966978Z","time spent":"2.367521063s","remote":"127.0.0.1:34826","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-14T04:15:27.334606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"762.208683ms","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-14T04:15:27.334624Z","caller":"traceutil/trace.go:171","msg":"trace[482312668] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2190; }","duration":"762.265825ms","start":"2026-04-14T04:15:26.572353Z","end":"2026-04-14T04:15:27.334619Z","steps":["trace[482312668] 'agreement among raft nodes before linearized reading' (duration: 762.191293ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:15:27.334618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.160528857s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-14T04:15:27.334638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:15:26.57233Z","time spent":"762.304116ms","remote":"127.0.0.1:34826","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-14T04:15:27.33466Z","caller":"traceutil/trace.go:171","msg":"trace[1179946447] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:2190; }","duration":"2.160631229s","start":"2026-04-14T04:15:25.174015Z","end":"2026-04-14T04:15:27.334646Z","steps":["trace[1179946447] 'agreement among raft nodes before linearized reading' (duration: 2.160543977s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:15:27.334693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:15:25.173991Z","time spent":"2.160692811s","remote":"127.0.0.1:47920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-04-14T04:15:27.334727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.366924149s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-14T04:15:27.334734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.536691882s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T04:15:27.334757Z","caller":"traceutil/trace.go:171","msg":"trace[1615668234] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:2190; }","duration":"1.536774585s","start":"2026-04-14T04:15:25.797977Z","end":"2026-04-14T04:15:27.334752Z","steps":["trace[1615668234] 'agreement among raft nodes before linearized reading' (duration: 1.536736034s)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:15:27.334758Z","caller":"traceutil/trace.go:171","msg":"trace[1098385582] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2190; }","duration":"2.367003042s","start":"2026-04-14T04:15:24.967746Z","end":"2026-04-14T04:15:27.334749Z","steps":["trace[1098385582] 'agreement among raft nodes before linearized reading' (duration: 2.36691364s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:15:27.334771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:15:25.797947Z","time spent":"1.536820355s","remote":"127.0.0.1:34944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":27,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-04-14T04:15:27.334787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:15:24.967717Z","time spent":"2.367064673s","remote":"127.0.0.1:34826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-14T04:15:41.076373Z","caller":"traceutil/trace.go:171","msg":"trace[2023308662] linearizableReadLoop","detail":"{readStateIndex:2349; appliedIndex:2348; }","duration":"340.277878ms","start":"2026-04-14T04:15:40.736059Z","end":"2026-04-14T04:15:41.076337Z","steps":["trace[2023308662] 'read index received' (duration: 340.013071ms)","trace[2023308662] 'applied index is now lower than readState.Index' (duration: 263.207µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:15:41.076599Z","caller":"traceutil/trace.go:171","msg":"trace[248964928] transaction","detail":"{read_only:false; response_revision:2236; number_of_response:1; }","duration":"408.073854ms","start":"2026-04-14T04:15:40.668507Z","end":"2026-04-14T04:15:41.076581Z","steps":["trace[248964928] 'process raft request' (duration: 407.572792ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:15:41.076763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:15:40.668476Z","time spent":"408.172667ms","remote":"127.0.0.1:34826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T04:15:41.076833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.765832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T04:15:41.076921Z","caller":"traceutil/trace.go:171","msg":"trace[1413800500] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2236; }","duration":"203.861654ms","start":"2026-04-14T04:15:40.873036Z","end":"2026-04-14T04:15:41.076898Z","steps":["trace[1413800500] 'agreement among raft nodes before linearized reading' (duration: 203.725551ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:15:41.077311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.24305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.116\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-14T04:15:41.077412Z","caller":"traceutil/trace.go:171","msg":"trace[1569158746] range","detail":"{range_begin:/registry/masterleases/199.204.45.116; range_end:; response_count:1; response_revision:2236; }","duration":"341.342272ms","start":"2026-04-14T04:15:40.736023Z","end":"2026-04-14T04:15:41.077365Z","steps":["trace[1569158746] 'agreement among raft nodes before linearized reading' (duration: 341.150628ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:15:41.077464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:15:40.736006Z","time spent":"341.443854ms","remote":"127.0.0.1:34542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.116\" "} {"level":"info","ts":"2026-04-14T04:15:44.044986Z","caller":"traceutil/trace.go:171","msg":"trace[338031807] linearizableReadLoop","detail":"{readStateIndex:2375; appliedIndex:2374; }","duration":"172.012931ms","start":"2026-04-14T04:15:43.872939Z","end":"2026-04-14T04:15:44.044952Z","steps":["trace[338031807] 'read index received' (duration: 171.614122ms)","trace[338031807] 'applied index is now lower than readState.Index' (duration: 397.279µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:15:44.045033Z","caller":"traceutil/trace.go:171","msg":"trace[1847194753] transaction","detail":"{read_only:false; response_revision:2261; number_of_response:1; }","duration":"196.873ms","start":"2026-04-14T04:15:43.848127Z","end":"2026-04-14T04:15:44.045Z","steps":["trace[1847194753] 'process raft request' (duration: 196.540633ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:15:44.045157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.187256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T04:15:44.045199Z","caller":"traceutil/trace.go:171","msg":"trace[319132241] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2261; }","duration":"172.283228ms","start":"2026-04-14T04:15:43.872904Z","end":"2026-04-14T04:15:44.045187Z","steps":["trace[319132241] 'agreement among raft nodes before linearized reading' (duration: 172.178255ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:15:44.680336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.608899ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-14T04:15:44.680444Z","caller":"traceutil/trace.go:171","msg":"trace[2105775631] transaction","detail":"{read_only:false; response_revision:2262; number_of_response:1; }","duration":"572.924489ms","start":"2026-04-14T04:15:44.107498Z","end":"2026-04-14T04:15:44.680423Z","steps":["trace[2105775631] 'process raft request' (duration: 203.144866ms)","trace[2105775631] 'compare' (duration: 368.941244ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T04:15:44.680502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:15:44.10747Z","time spent":"573.009701ms","remote":"127.0.0.1:34724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1475,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T04:15:45.047177Z","caller":"traceutil/trace.go:171","msg":"trace[1951411761] linearizableReadLoop","detail":"{readStateIndex:2377; appliedIndex:2376; }","duration":"361.112942ms","start":"2026-04-14T04:15:44.686036Z","end":"2026-04-14T04:15:45.047149Z","steps":["trace[1951411761] 'read index received' (duration: 361.00179ms)","trace[1951411761] 'applied index is now lower than readState.Index' (duration: 110.102µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:15:45.047241Z","caller":"traceutil/trace.go:171","msg":"trace[524170984] transaction","detail":"{read_only:false; response_revision:2263; number_of_response:1; }","duration":"361.873559ms","start":"2026-04-14T04:15:44.685324Z","end":"2026-04-14T04:15:45.047198Z","steps":["trace[524170984] 'process raft request' (duration: 361.650324ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:15:45.047493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:15:44.68529Z","time spent":"362.033423ms","remote":"127.0.0.1:34586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":767,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T04:15:45.047589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.523681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:1490"} {"level":"info","ts":"2026-04-14T04:15:45.047632Z","caller":"traceutil/trace.go:171","msg":"trace[1906041643] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:2263; }","duration":"361.632994ms","start":"2026-04-14T04:15:44.685987Z","end":"2026-04-14T04:15:45.04762Z","steps":["trace[1906041643] 'agreement among raft nodes before linearized reading' (duration: 361.270796ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:15:45.047725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:15:44.685966Z","time spent":"361.750626ms","remote":"127.0.0.1:34724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":1512,"request content":"key:\"/registry/pods/default/test-pvc-pod\" "} {"level":"info","ts":"2026-04-14T04:15:45.104899Z","caller":"traceutil/trace.go:171","msg":"trace[119830750] transaction","detail":"{read_only:false; response_revision:2264; number_of_response:1; }","duration":"418.58195ms","start":"2026-04-14T04:15:44.686289Z","end":"2026-04-14T04:15:45.104871Z","steps":["trace[119830750] 'process raft request' (duration: 406.073988ms)","trace[119830750] 'compare' (duration: 12.246556ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T04:15:45.10503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:15:44.686274Z","time spent":"418.682382ms","remote":"127.0.0.1:34724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1933,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T04:15:49.092188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.067977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T04:15:49.092269Z","caller":"traceutil/trace.go:171","msg":"trace[761733244] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2298; }","duration":"219.232581ms","start":"2026-04-14T04:15:48.873018Z","end":"2026-04-14T04:15:49.092251Z","steps":["trace[761733244] 'range keys from in-memory index tree' (duration: 218.923503ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:15:49.816339Z","caller":"traceutil/trace.go:171","msg":"trace[597090736] linearizableReadLoop","detail":"{readStateIndex:2414; appliedIndex:2413; }","duration":"205.763147ms","start":"2026-04-14T04:15:49.610554Z","end":"2026-04-14T04:15:49.816317Z","steps":["trace[597090736] 'read index received' (duration: 205.483811ms)","trace[597090736] 'applied index is now lower than readState.Index' (duration: 278.546µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:15:49.816398Z","caller":"traceutil/trace.go:171","msg":"trace[463784191] transaction","detail":"{read_only:false; response_revision:2299; number_of_response:1; }","duration":"351.61827ms","start":"2026-04-14T04:15:49.464751Z","end":"2026-04-14T04:15:49.81637Z","steps":["trace[463784191] 'process raft request' (duration: 351.405675ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:15:49.816606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:15:49.464721Z","time spent":"351.725603ms","remote":"127.0.0.1:34826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T04:15:49.816632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.066675ms","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-14T04:15:49.816761Z","caller":"traceutil/trace.go:171","msg":"trace[243256774] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2299; }","duration":"206.187338ms","start":"2026-04-14T04:15:49.610508Z","end":"2026-04-14T04:15:49.816696Z","steps":["trace[243256774] 'agreement among raft nodes before linearized reading' (duration: 205.924501ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:15:49.816766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.321348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-04-14T04:15:49.816849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.173458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-14T04:15:49.816875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.663136ms","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-14T04:15:49.816956Z","caller":"traceutil/trace.go:171","msg":"trace[138070849] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2299; }","duration":"206.341901ms","start":"2026-04-14T04:15:49.610595Z","end":"2026-04-14T04:15:49.816936Z","steps":["trace[138070849] 'agreement among raft nodes before linearized reading' (duration: 205.912281ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:15:49.816981Z","caller":"traceutil/trace.go:171","msg":"trace[1841354890] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2299; }","duration":"205.807139ms","start":"2026-04-14T04:15:49.611155Z","end":"2026-04-14T04:15:49.816962Z","steps":["trace[1841354890] 'agreement among raft nodes before linearized reading' (duration: 205.484712ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:15:49.816877Z","caller":"traceutil/trace.go:171","msg":"trace[1261963992] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2299; }","duration":"205.499272ms","start":"2026-04-14T04:15:49.611336Z","end":"2026-04-14T04:15:49.816835Z","steps":["trace[1261963992] 'agreement among raft nodes before linearized reading' (duration: 205.218546ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:15:50.018365Z","caller":"traceutil/trace.go:171","msg":"trace[1528380095] linearizableReadLoop","detail":"{readStateIndex:2418; appliedIndex:2414; }","duration":"160.319381ms","start":"2026-04-14T04:15:49.858009Z","end":"2026-04-14T04:15:50.018329Z","steps":["trace[1528380095] 'read index received' (duration: 19.202997ms)","trace[1528380095] 'applied index is now lower than readState.Index' (duration: 141.115034ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:15:50.01846Z","caller":"traceutil/trace.go:171","msg":"trace[2139280167] transaction","detail":"{read_only:false; response_revision:2301; number_of_response:1; }","duration":"196.658386ms","start":"2026-04-14T04:15:49.821765Z","end":"2026-04-14T04:15:50.018423Z","steps":["trace[2139280167] 'process raft request' (duration: 130.398834ms)","trace[2139280167] 'compare' (duration: 65.659027ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:15:50.018592Z","caller":"traceutil/trace.go:171","msg":"trace[759747287] transaction","detail":"{read_only:false; response_revision:2302; number_of_response:1; }","duration":"196.726698ms","start":"2026-04-14T04:15:49.821845Z","end":"2026-04-14T04:15:50.018572Z","steps":["trace[759747287] 'process raft request' (duration: 196.291357ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:15:50.018761Z","caller":"traceutil/trace.go:171","msg":"trace[1784135308] transaction","detail":"{read_only:false; response_revision:2303; number_of_response:1; }","duration":"197.216169ms","start":"2026-04-14T04:15:49.821508Z","end":"2026-04-14T04:15:50.018724Z","steps":["trace[1784135308] 'process raft request' (duration: 196.758989ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:15:50.018717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.754961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T04:15:50.018873Z","caller":"traceutil/trace.go:171","msg":"trace[1066967668] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2303; }","duration":"145.948026ms","start":"2026-04-14T04:15:49.872905Z","end":"2026-04-14T04:15:50.018853Z","steps":["trace[1066967668] 'agreement among raft nodes before linearized reading' (duration: 145.737121ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:15:50.019452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.694169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T04:15:50.019633Z","caller":"traceutil/trace.go:171","msg":"trace[942315581] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:2303; }","duration":"161.561279ms","start":"2026-04-14T04:15:49.857957Z","end":"2026-04-14T04:15:50.019518Z","steps":["trace[942315581] 'agreement among raft nodes before linearized reading' (duration: 160.650898ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:15:50.277784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.603753ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T04:15:50.278122Z","caller":"traceutil/trace.go:171","msg":"trace[1195810084] transaction","detail":"{read_only:false; response_revision:2304; number_of_response:1; }","duration":"204.326184ms","start":"2026-04-14T04:15:50.073774Z","end":"2026-04-14T04:15:50.2781Z","steps":["trace[1195810084] 'process raft request' (duration: 78.320132ms)","trace[1195810084] 'compare' (duration: 125.47188ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T04:15:51.029841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.484291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-14T04:15:51.029901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.748332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.116\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-14T04:15:51.02996Z","caller":"traceutil/trace.go:171","msg":"trace[1331354373] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2304; }","duration":"156.662235ms","start":"2026-04-14T04:15:50.873269Z","end":"2026-04-14T04:15:51.029931Z","steps":["trace[1331354373] 'range keys from in-memory index tree' (duration: 156.384478ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:15:51.029987Z","caller":"traceutil/trace.go:171","msg":"trace[1398673919] range","detail":"{range_begin:/registry/masterleases/199.204.45.116; range_end:; response_count:1; response_revision:2304; }","duration":"292.907446ms","start":"2026-04-14T04:15:50.737057Z","end":"2026-04-14T04:15:51.029965Z","steps":["trace[1398673919] 'range keys from in-memory index tree' (duration: 292.499447ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:15:51.41237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:15:51.031091Z","time spent":"381.274221ms","remote":"127.0.0.1:34542","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-14T04:15:51.41239Z","caller":"traceutil/trace.go:171","msg":"trace[1187316027] linearizableReadLoop","detail":"{readStateIndex:2420; appliedIndex:2420; }","duration":"380.958864ms","start":"2026-04-14T04:15:51.031398Z","end":"2026-04-14T04:15:51.412357Z","steps":["trace[1187316027] 'read index received' (duration: 380.944693ms)","trace[1187316027] 'applied index is now lower than readState.Index' (duration: 11.921µs)"],"step_count":2} {"level":"warn","ts":"2026-04-14T04:15:51.412568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.116897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T04:15:51.412612Z","caller":"traceutil/trace.go:171","msg":"trace[1850419203] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2304; }","duration":"381.23235ms","start":"2026-04-14T04:15:51.031368Z","end":"2026-04-14T04:15:51.4126Z","steps":["trace[1850419203] 'agreement among raft nodes before linearized reading' (duration: 381.095257ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:15:51.41265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:15:51.031356Z","time spent":"381.285781ms","remote":"127.0.0.1:34514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-14T04:15:52.024829Z","caller":"traceutil/trace.go:171","msg":"trace[832639291] transaction","detail":"{read_only:false; response_revision:2305; number_of_response:1; }","duration":"788.733671ms","start":"2026-04-14T04:15:51.236074Z","end":"2026-04-14T04:15:52.024808Z","steps":["trace[832639291] 'process raft request' (duration: 788.572828ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:15:52.024979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:15:51.236045Z","time spent":"788.862455ms","remote":"127.0.0.1:34826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T04:15:52.03327Z","caller":"traceutil/trace.go:171","msg":"trace[1664973560] linearizableReadLoop","detail":"{readStateIndex:2423; appliedIndex:2420; }","duration":"159.694256ms","start":"2026-04-14T04:15:51.87356Z","end":"2026-04-14T04:15:52.033255Z","steps":["trace[1664973560] 'read index received' (duration: 151.200818ms)","trace[1664973560] 'applied index is now lower than readState.Index' (duration: 8.492468ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:15:52.033361Z","caller":"traceutil/trace.go:171","msg":"trace[1347866534] transaction","detail":"{read_only:false; response_revision:2306; number_of_response:1; }","duration":"619.215817ms","start":"2026-04-14T04:15:51.414133Z","end":"2026-04-14T04:15:52.033349Z","steps":["trace[1347866534] 'process raft request' (duration: 618.963412ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:15:52.033448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:15:51.414111Z","time spent":"619.290669ms","remote":"127.0.0.1:34542","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T04:15:52.033828Z","caller":"traceutil/trace.go:171","msg":"trace[294253289] transaction","detail":"{read_only:false; response_revision:2307; number_of_response:1; }","duration":"212.040064ms","start":"2026-04-14T04:15:51.821774Z","end":"2026-04-14T04:15:52.033814Z","steps":["trace[294253289] 'process raft request' (duration: 211.43834ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:15:52.034017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.463553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T04:15:52.034046Z","caller":"traceutil/trace.go:171","msg":"trace[898681599] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2307; }","duration":"160.533365ms","start":"2026-04-14T04:15:51.873504Z","end":"2026-04-14T04:15:52.034037Z","steps":["trace[898681599] 'agreement among raft nodes before linearized reading' (duration: 160.463943ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:15:52.034187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.750453ms","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-14T04:15:52.034235Z","caller":"traceutil/trace.go:171","msg":"trace[1025969104] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2307; }","duration":"152.844995ms","start":"2026-04-14T04:15:51.881378Z","end":"2026-04-14T04:15:52.034223Z","steps":["trace[1025969104] 'agreement among raft nodes before linearized reading' (duration: 152.715922ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:15:52.144141Z","caller":"traceutil/trace.go:171","msg":"trace[229589283] transaction","detail":"{read_only:false; response_revision:2309; number_of_response:1; }","duration":"103.504498ms","start":"2026-04-14T04:15:52.040612Z","end":"2026-04-14T04:15:52.144117Z","steps":["trace[229589283] 'process raft request' (duration: 92.224905ms)","trace[229589283] 'compare' (duration: 11.054758ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:15:52.144426Z","caller":"traceutil/trace.go:171","msg":"trace[411705718] transaction","detail":"{read_only:false; response_revision:2311; number_of_response:1; }","duration":"103.335874ms","start":"2026-04-14T04:15:52.04107Z","end":"2026-04-14T04:15:52.144406Z","steps":["trace[411705718] 'process raft request' (duration: 103.237241ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:15:52.144558Z","caller":"traceutil/trace.go:171","msg":"trace[202118964] transaction","detail":"{read_only:false; response_revision:2310; number_of_response:1; }","duration":"103.716773ms","start":"2026-04-14T04:15:52.040793Z","end":"2026-04-14T04:15:52.144509Z","steps":["trace[202118964] 'process raft request' (duration: 103.267152ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:15:54.481853Z","caller":"traceutil/trace.go:171","msg":"trace[82955848] transaction","detail":"{read_only:false; response_revision:2320; number_of_response:1; }","duration":"137.588701ms","start":"2026-04-14T04:15:54.344228Z","end":"2026-04-14T04:15:54.481817Z","steps":["trace[82955848] 'process raft request' (duration: 137.365336ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:16:11.565973Z","caller":"traceutil/trace.go:171","msg":"trace[1356838082] transaction","detail":"{read_only:false; response_revision:2445; number_of_response:1; }","duration":"194.801402ms","start":"2026-04-14T04:16:11.37115Z","end":"2026-04-14T04:16:11.565952Z","steps":["trace[1356838082] 'process raft request' (duration: 194.669809ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:16:12.676166Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853969805033338546,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-14T04:16:13.031664Z","caller":"traceutil/trace.go:171","msg":"trace[780979577] transaction","detail":"{read_only:false; response_revision:2446; number_of_response:1; }","duration":"926.18448ms","start":"2026-04-14T04:16:12.105455Z","end":"2026-04-14T04:16:13.031639Z","steps":["trace[780979577] 'process raft request' (duration: 926.030826ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:16:13.031825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:16:12.105436Z","time spent":"926.299182ms","remote":"127.0.0.1:34826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T04:16:14.184117Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.152619207s","expected-duration":"1s"} {"level":"info","ts":"2026-04-14T04:16:14.184706Z","caller":"traceutil/trace.go:171","msg":"trace[583610260] transaction","detail":"{read_only:false; response_revision:2447; number_of_response:1; }","duration":"2.064914163s","start":"2026-04-14T04:16:12.119767Z","end":"2026-04-14T04:16:14.184681Z","steps":["trace[583610260] 'process raft request' (duration: 2.064659637s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:16:14.184821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:16:12.119745Z","time spent":"2.065006706s","remote":"127.0.0.1:34826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T04:16:14.184947Z","caller":"traceutil/trace.go:171","msg":"trace[640006787] linearizableReadLoop","detail":"{readStateIndex:2568; appliedIndex:2565; }","duration":"2.009194557s","start":"2026-04-14T04:16:12.175711Z","end":"2026-04-14T04:16:14.184906Z","steps":["trace[640006787] 'read index received' (duration: 855.767482ms)","trace[640006787] 'applied index is now lower than readState.Index' (duration: 1.153422725s)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:16:14.185144Z","caller":"traceutil/trace.go:171","msg":"trace[1424116732] transaction","detail":"{read_only:false; response_revision:2448; number_of_response:1; }","duration":"1.830916779s","start":"2026-04-14T04:16:12.354214Z","end":"2026-04-14T04:16:14.185131Z","steps":["trace[1424116732] 'process raft request' (duration: 1.830429177s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:16:14.185221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:16:12.35419Z","time spent":"1.830987021s","remote":"127.0.0.1:34826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T04:16:14.185413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.255676835s","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-14T04:16:14.185447Z","caller":"traceutil/trace.go:171","msg":"trace[809313773] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2448; }","duration":"1.255745857s","start":"2026-04-14T04:16:12.929689Z","end":"2026-04-14T04:16:14.185434Z","steps":["trace[809313773] 'agreement among raft nodes before linearized reading' (duration: 1.255626224s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:16:14.185433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.928555501s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-14T04:16:14.185479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:16:12.929668Z","time spent":"1.255803778s","remote":"127.0.0.1:34826","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":"info","ts":"2026-04-14T04:16:14.185503Z","caller":"traceutil/trace.go:171","msg":"trace[1554509531] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2448; }","duration":"1.928672113s","start":"2026-04-14T04:16:12.256813Z","end":"2026-04-14T04:16:14.185486Z","steps":["trace[1554509531] 'agreement among raft nodes before linearized reading' (duration: 1.928464998s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:16:14.18559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:16:12.256795Z","time spent":"1.928739575s","remote":"127.0.0.1:34826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-14T04:16:14.1856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.308437003s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T04:16:14.185653Z","caller":"traceutil/trace.go:171","msg":"trace[733111590] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2448; }","duration":"1.308496695s","start":"2026-04-14T04:16:12.877125Z","end":"2026-04-14T04:16:14.185622Z","steps":["trace[733111590] 'agreement among raft nodes before linearized reading' (duration: 1.308441404s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:16:14.18568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:16:12.877105Z","time spent":"1.308568387s","remote":"127.0.0.1:34506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-14T04:16:14.185812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.010089919s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-14T04:16:14.1858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"829.616763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"warn","ts":"2026-04-14T04:16:14.185836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.579539781s","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-14T04:16:14.185861Z","caller":"traceutil/trace.go:171","msg":"trace[2106642651] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2448; }","duration":"2.01017194s","start":"2026-04-14T04:16:12.175676Z","end":"2026-04-14T04:16:14.185848Z","steps":["trace[2106642651] 'agreement among raft nodes before linearized reading' (duration: 2.010023737s)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:16:14.185877Z","caller":"traceutil/trace.go:171","msg":"trace[1913319083] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2448; }","duration":"829.748026ms","start":"2026-04-14T04:16:13.356112Z","end":"2026-04-14T04:16:14.18586Z","steps":["trace[1913319083] 'agreement among raft nodes before linearized reading' (duration: 829.50009ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:16:14.185908Z","caller":"traceutil/trace.go:171","msg":"trace[1076374543] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2448; }","duration":"1.579622453s","start":"2026-04-14T04:16:12.606268Z","end":"2026-04-14T04:16:14.18589Z","steps":["trace[1076374543] 'agreement among raft nodes before linearized reading' (duration: 1.579306805s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:16:14.185918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:16:12.175663Z","time spent":"2.010235592s","remote":"127.0.0.1:34826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-14T04:16:14.185923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:16:13.356088Z","time spent":"829.826658ms","remote":"127.0.0.1:34826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":494,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-14T04:16:14.185932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.926480803s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-14T04:16:14.185952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:16:12.606207Z","time spent":"1.579736285s","remote":"127.0.0.1:34826","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-14T04:16:14.185858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.926155236s","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-14T04:16:14.185966Z","caller":"traceutil/trace.go:171","msg":"trace[935899472] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2448; }","duration":"1.926539284s","start":"2026-04-14T04:16:12.259417Z","end":"2026-04-14T04:16:14.185956Z","steps":["trace[935899472] 'agreement among raft nodes before linearized reading' (duration: 1.926464243s)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:16:14.186017Z","caller":"traceutil/trace.go:171","msg":"trace[334416611] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2448; }","duration":"1.92632967s","start":"2026-04-14T04:16:12.259671Z","end":"2026-04-14T04:16:14.186001Z","steps":["trace[334416611] 'agreement among raft nodes before linearized reading' (duration: 1.926119645s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:16:14.186042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:16:12.259397Z","time spent":"1.926638286s","remote":"127.0.0.1:34826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-14T04:16:14.186047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:16:12.259661Z","time spent":"1.92637804s","remote":"127.0.0.1:34826","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-14T04:16:14.18616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.255109012s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-14T04:16:14.186187Z","caller":"traceutil/trace.go:171","msg":"trace[636186198] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:2448; }","duration":"1.255156934s","start":"2026-04-14T04:16:12.93102Z","end":"2026-04-14T04:16:14.186177Z","steps":["trace[636186198] 'agreement among raft nodes before linearized reading' (duration: 1.255083062s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:16:14.186213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T04:16:12.931008Z","time spent":"1.255198194s","remote":"127.0.0.1:34914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":55,"response size":29,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"info","ts":"2026-04-14T04:16:14.336476Z","caller":"traceutil/trace.go:171","msg":"trace[260163883] transaction","detail":"{read_only:false; response_revision:2449; number_of_response:1; }","duration":"145.327828ms","start":"2026-04-14T04:16:14.191124Z","end":"2026-04-14T04:16:14.336452Z","steps":["trace[260163883] 'process raft request' (duration: 73.87909ms)","trace[260163883] 'compare' (duration: 71.352546ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T04:16:14.41445Z","caller":"traceutil/trace.go:171","msg":"trace[1205118635] transaction","detail":"{read_only:false; response_revision:2451; number_of_response:1; }","duration":"222.89877ms","start":"2026-04-14T04:16:14.191532Z","end":"2026-04-14T04:16:14.414431Z","steps":["trace[1205118635] 'process raft request' (duration: 222.821888ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:16:14.414736Z","caller":"traceutil/trace.go:171","msg":"trace[1967458608] transaction","detail":"{read_only:false; response_revision:2450; number_of_response:1; }","duration":"223.267917ms","start":"2026-04-14T04:16:14.191455Z","end":"2026-04-14T04:16:14.414723Z","steps":["trace[1967458608] 'process raft request' (duration: 222.792137ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:16:14.414738Z","caller":"traceutil/trace.go:171","msg":"trace[1453683738] transaction","detail":"{read_only:false; response_revision:2452; number_of_response:1; }","duration":"222.815138ms","start":"2026-04-14T04:16:14.191875Z","end":"2026-04-14T04:16:14.41469Z","steps":["trace[1453683738] 'process raft request' (duration: 222.516141ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:16:14.415204Z","caller":"traceutil/trace.go:171","msg":"trace[1235850646] transaction","detail":"{read_only:false; response_revision:2453; number_of_response:1; }","duration":"222.875229ms","start":"2026-04-14T04:16:14.192312Z","end":"2026-04-14T04:16:14.415188Z","steps":["trace[1235850646] 'process raft request' (duration: 222.321656ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:16:14.415247Z","caller":"traceutil/trace.go:171","msg":"trace[1270416571] transaction","detail":"{read_only:false; response_revision:2454; number_of_response:1; }","duration":"222.529211ms","start":"2026-04-14T04:16:14.192698Z","end":"2026-04-14T04:16:14.415227Z","steps":["trace[1270416571] 'process raft request' (duration: 222.393358ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:16:14.4153Z","caller":"traceutil/trace.go:171","msg":"trace[1371871777] linearizableReadLoop","detail":"{readStateIndex:2575; appliedIndex:2568; }","duration":"220.235677ms","start":"2026-04-14T04:16:14.195049Z","end":"2026-04-14T04:16:14.415284Z","steps":["trace[1371871777] 'read index received' (duration: 69.969178ms)","trace[1371871777] 'applied index is now lower than readState.Index' (duration: 150.265229ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T04:16:14.415404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.33182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T04:16:14.415439Z","caller":"traceutil/trace.go:171","msg":"trace[1266201281] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2455; }","duration":"220.411012ms","start":"2026-04-14T04:16:14.195018Z","end":"2026-04-14T04:16:14.415429Z","steps":["trace[1266201281] 'agreement among raft nodes before linearized reading' (duration: 220.324599ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:16:14.415375Z","caller":"traceutil/trace.go:171","msg":"trace[457754666] transaction","detail":"{read_only:false; response_revision:2455; number_of_response:1; }","duration":"222.421828ms","start":"2026-04-14T04:16:14.192918Z","end":"2026-04-14T04:16:14.41534Z","steps":["trace[457754666] 'process raft request' (duration: 222.265385ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T04:16:14.998875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.268787ms","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-14T04:16:14.999005Z","caller":"traceutil/trace.go:171","msg":"trace[344687252] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2456; }","duration":"236.431071ms","start":"2026-04-14T04:16:14.762524Z","end":"2026-04-14T04:16:14.998955Z","steps":["trace[344687252] 'range keys from in-memory index tree' (duration: 236.045413ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T04:16:15.20882Z","caller":"traceutil/trace.go:171","msg":"trace[1616141481] transaction","detail":"{read_only:false; response_revision:2458; number_of_response:1; }","duration":"162.222297ms","start":"2026-04-14T04:16:15.046561Z","end":"2026-04-14T04:16:15.208784Z","steps":["trace[1616141481] 'process raft request' (duration: 114.587587ms)","trace[1616141481] 'compare' (duration: 47.493647ms)"],"step_count":2}