{"level":"warn","ts":"2026-04-16T16:30:16.328775Z","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-16T16:30:16.328885Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.65:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.65:2380","--initial-cluster=instance=https://199.204.45.65:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.65:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.65:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-16T16:30:16.328959Z","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-16T16:30:16.328972Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.65:2380"]} {"level":"info","ts":"2026-04-16T16:30:16.328999Z","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-16T16:30:16.329811Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.65:2379"]} {"level":"info","ts":"2026-04-16T16:30:16.3299Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.65:2380"],"listen-peer-urls":["https://199.204.45.65:2380"],"advertise-client-urls":["https://199.204.45.65:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.65:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.65:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-16T16:30:16.34278Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"12.563076ms"} {"level":"info","ts":"2026-04-16T16:30:16.355253Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"cf8c3a138c9a77a2","cluster-id":"c278183e1f634dff"} {"level":"info","ts":"2026-04-16T16:30:16.355378Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 switched to configuration voters=()"} {"level":"info","ts":"2026-04-16T16:30:16.355409Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became follower at term 0"} {"level":"info","ts":"2026-04-16T16:30:16.355427Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft cf8c3a138c9a77a2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-16T16:30:16.355443Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became follower at term 1"} {"level":"info","ts":"2026-04-16T16:30:16.355491Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 switched to configuration voters=(14955392318228297634)"} {"level":"warn","ts":"2026-04-16T16:30:16.367052Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-16T16:30:16.369405Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-16T16:30:16.370774Z","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-16T16:30:16.37283Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"cf8c3a138c9a77a2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-16T16:30:16.373999Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"cf8c3a138c9a77a2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-16T16:30:16.37415Z","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-16T16:30:16.374269Z","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-16T16:30:16.374289Z","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-16T16:30:16.382427Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 switched to configuration voters=(14955392318228297634)"} {"level":"info","ts":"2026-04-16T16:30:16.382801Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c278183e1f634dff","local-member-id":"cf8c3a138c9a77a2","added-peer-id":"cf8c3a138c9a77a2","added-peer-peer-urls":["https://199.204.45.65:2380"]} {"level":"info","ts":"2026-04-16T16:30:16.38514Z","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-16T16:30:16.385278Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.65:2380"} {"level":"info","ts":"2026-04-16T16:30:16.385333Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.65:2380"} {"level":"info","ts":"2026-04-16T16:30:16.385414Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"cf8c3a138c9a77a2","initial-advertise-peer-urls":["https://199.204.45.65:2380"],"listen-peer-urls":["https://199.204.45.65:2380"],"advertise-client-urls":["https://199.204.45.65:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.65:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-16T16:30:16.385477Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-16T16:30:17.056066Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 is starting a new election at term 1"} {"level":"info","ts":"2026-04-16T16:30:17.056132Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-16T16:30:17.056158Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 received MsgPreVoteResp from cf8c3a138c9a77a2 at term 1"} {"level":"info","ts":"2026-04-16T16:30:17.056178Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became candidate at term 2"} {"level":"info","ts":"2026-04-16T16:30:17.05619Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 received MsgVoteResp from cf8c3a138c9a77a2 at term 2"} {"level":"info","ts":"2026-04-16T16:30:17.056232Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became leader at term 2"} {"level":"info","ts":"2026-04-16T16:30:17.056254Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: cf8c3a138c9a77a2 elected leader cf8c3a138c9a77a2 at term 2"} {"level":"info","ts":"2026-04-16T16:30:17.057423Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T16:30:17.060153Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-16T16:30:17.060174Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"cf8c3a138c9a77a2","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.65:2379]}","request-path":"/0/members/cf8c3a138c9a77a2/attributes","cluster-id":"c278183e1f634dff","publish-timeout":"7s"} {"level":"info","ts":"2026-04-16T16:30:17.06046Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c278183e1f634dff","local-member-id":"cf8c3a138c9a77a2","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T16:30:17.060579Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T16:30:17.060618Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T16:30:17.060527Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-16T16:30:17.062594Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.65:2379"} {"level":"info","ts":"2026-04-16T16:30:17.064201Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-16T16:30:17.065884Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-16T16:30:17.065949Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"warn","ts":"2026-04-16T16:30:27.100656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.961548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4368"} {"level":"info","ts":"2026-04-16T16:30:27.101025Z","caller":"traceutil/trace.go:171","msg":"trace[152156289] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:347; }","duration":"277.216264ms","start":"2026-04-16T16:30:26.823571Z","end":"2026-04-16T16:30:27.100788Z","steps":["trace[152156289] 'range keys from in-memory index tree' (duration: 276.272304ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:30:27.100046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.70835ms","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-16T16:30:27.101578Z","caller":"traceutil/trace.go:171","msg":"trace[2041073593] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:347; }","duration":"233.331072ms","start":"2026-04-16T16:30:26.868215Z","end":"2026-04-16T16:30:27.101546Z","steps":["trace[2041073593] 'range keys from in-memory index tree' (duration: 231.559217ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:30:33.559604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.567431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-16T16:30:33.559681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"826.14662ms","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-16T16:30:33.559738Z","caller":"traceutil/trace.go:171","msg":"trace[1749528852] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:370; }","duration":"409.757926ms","start":"2026-04-16T16:30:33.149941Z","end":"2026-04-16T16:30:33.559699Z","steps":["trace[1749528852] 'range keys from in-memory index tree' (duration: 409.426798ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:30:33.559786Z","caller":"traceutil/trace.go:171","msg":"trace[1665155597] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:370; }","duration":"826.322145ms","start":"2026-04-16T16:30:32.733434Z","end":"2026-04-16T16:30:33.559756Z","steps":["trace[1665155597] 'range keys from in-memory index tree' (duration: 826.003128ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:30:33.559795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:30:33.149914Z","time spent":"409.869447ms","remote":"127.0.0.1:52682","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-16T16:30:33.559832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:30:32.733411Z","time spent":"826.411476ms","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-16T16:30:33.559815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"807.626907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4368"} {"level":"info","ts":"2026-04-16T16:30:33.559941Z","caller":"traceutil/trace.go:171","msg":"trace[1777862472] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:370; }","duration":"807.823731ms","start":"2026-04-16T16:30:32.752094Z","end":"2026-04-16T16:30:33.559917Z","steps":["trace[1777862472] 'range keys from in-memory index tree' (duration: 807.393663ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:30:33.559986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:30:32.752066Z","time spent":"807.908422ms","remote":"127.0.0.1:52590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4392,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-04-16T16:30:33.560471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"811.314828ms","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-16T16:30:33.560515Z","caller":"traceutil/trace.go:171","msg":"trace[1957421446] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:370; }","duration":"811.37705ms","start":"2026-04-16T16:30:32.749125Z","end":"2026-04-16T16:30:33.560502Z","steps":["trace[1957421446] 'range keys from in-memory index tree' (duration: 811.231297ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:30:33.560547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:30:32.749114Z","time spent":"811.426161ms","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-16T16:30:34.762622Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620625909160027755,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T16:30:34.885013Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.318885076s","expected-duration":"1s"} {"level":"info","ts":"2026-04-16T16:30:34.885615Z","caller":"traceutil/trace.go:171","msg":"trace[1152498291] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"1.319581729s","start":"2026-04-16T16:30:33.565986Z","end":"2026-04-16T16:30:34.885567Z","steps":["trace[1152498291] 'process raft request' (duration: 1.319279243s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:30:34.886531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:30:33.56596Z","time spent":"1.319728153s","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:30:35.800426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.129411ms","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-16T16:30:35.800583Z","caller":"traceutil/trace.go:171","msg":"trace[624284866] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:371; }","duration":"111.302514ms","start":"2026-04-16T16:30:35.689256Z","end":"2026-04-16T16:30:35.800559Z","steps":["trace[624284866] 'range keys from in-memory index tree' (duration: 111.110321ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:30:36.262705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000271216s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-16T16:30:36.26282Z","caller":"traceutil/trace.go:171","msg":"trace[413419997] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.0004647s","start":"2026-04-16T16:30:34.262333Z","end":"2026-04-16T16:30:36.262798Z","steps":["trace[413419997] 'agreement among raft nodes before linearized reading' (duration: 2.000320067s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:30:36.26287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:30:34.262309Z","time spent":"2.000552082s","remote":"127.0.0.1:52396","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-16T16:30:36.555101Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.669845652s","expected-duration":"1s"} {"level":"info","ts":"2026-04-16T16:30:36.555634Z","caller":"traceutil/trace.go:171","msg":"trace[2053734927] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"2.988695966s","start":"2026-04-16T16:30:33.566913Z","end":"2026-04-16T16:30:36.555608Z","steps":["trace[2053734927] 'process raft request' (duration: 2.988606264s)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:30:36.555701Z","caller":"traceutil/trace.go:171","msg":"trace[83884734] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"2.989593783s","start":"2026-04-16T16:30:33.566048Z","end":"2026-04-16T16:30:36.555641Z","steps":["trace[83884734] 'process raft request' (duration: 2.989319668s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:30:36.555825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:30:33.566905Z","time spent":"2.988821838s","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:30:36.555972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:30:33.566018Z","time spent":"2.989808057s","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:30:36.556013Z","caller":"traceutil/trace.go:171","msg":"trace[2092318201] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"2.974392476s","start":"2026-04-16T16:30:33.581599Z","end":"2026-04-16T16:30:36.555992Z","steps":["trace[2092318201] 'process raft request' (duration: 2.973960407s)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:30:36.556015Z","caller":"traceutil/trace.go:171","msg":"trace[891333824] linearizableReadLoop","detail":"{readStateIndex:387; appliedIndex:383; }","duration":"2.293561946s","start":"2026-04-16T16:30:34.262396Z","end":"2026-04-16T16:30:36.555958Z","steps":["trace[891333824] 'read index received' (duration: 622.98009ms)","trace[891333824] 'applied index is now lower than readState.Index' (duration: 1.670573166s)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:30:36.556102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:30:33.581562Z","time spent":"2.974503708s","remote":"127.0.0.1:52590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4360,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:30:36.560074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.926487949s","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-16T16:30:36.560139Z","caller":"traceutil/trace.go:171","msg":"trace[767195185] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:374; }","duration":"1.926612332s","start":"2026-04-16T16:30:34.633507Z","end":"2026-04-16T16:30:36.560119Z","steps":["trace[767195185] 'agreement among raft nodes before linearized reading' (duration: 1.926497139s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:30:36.56012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.261678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T16:30:36.56017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:30:34.633434Z","time spent":"1.926728356s","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-16T16:30:36.560226Z","caller":"traceutil/trace.go:171","msg":"trace[1294173377] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:374; }","duration":"296.394981ms","start":"2026-04-16T16:30:36.26379Z","end":"2026-04-16T16:30:36.560185Z","steps":["trace[1294173377] 'agreement among raft nodes before linearized reading' (duration: 296.223157ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:30:36.560391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"694.723613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4322"} {"level":"info","ts":"2026-04-16T16:30:36.56042Z","caller":"traceutil/trace.go:171","msg":"trace[2093173338] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:374; }","duration":"694.779534ms","start":"2026-04-16T16:30:35.865632Z","end":"2026-04-16T16:30:36.560412Z","steps":["trace[2093173338] 'agreement among raft nodes before linearized reading' (duration: 694.718872ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:30:36.560444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:30:35.865616Z","time spent":"694.822435ms","remote":"127.0.0.1:52592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4346,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"info","ts":"2026-04-16T16:30:42.722419Z","caller":"traceutil/trace.go:171","msg":"trace[1169777457] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"132.649695ms","start":"2026-04-16T16:30:42.589745Z","end":"2026-04-16T16:30:42.722395Z","steps":["trace[1169777457] 'process raft request' (duration: 132.495052ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:30:42.826236Z","caller":"traceutil/trace.go:171","msg":"trace[1068172397] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"234.965344ms","start":"2026-04-16T16:30:42.591245Z","end":"2026-04-16T16:30:42.82621Z","steps":["trace[1068172397] 'process raft request' (duration: 233.611057ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:30:42.826597Z","caller":"traceutil/trace.go:171","msg":"trace[1523607643] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"234.017205ms","start":"2026-04-16T16:30:42.592548Z","end":"2026-04-16T16:30:42.826565Z","steps":["trace[1523607643] 'process raft request' (duration: 233.616047ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:30:53.309506Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620625909160027964,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T16:30:53.560081Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.080526251s","expected-duration":"1s"} {"level":"info","ts":"2026-04-16T16:30:53.560747Z","caller":"traceutil/trace.go:171","msg":"trace[1993294630] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"1.081304977s","start":"2026-04-16T16:30:52.479399Z","end":"2026-04-16T16:30:53.560704Z","steps":["trace[1993294630] 'process raft request' (duration: 1.081061801s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:30:53.560946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:30:52.479367Z","time spent":"1.08148673s","remote":"127.0.0.1:52446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T16:30:53.72449Z","caller":"traceutil/trace.go:171","msg":"trace[694405351] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"1.242611809s","start":"2026-04-16T16:30:52.48182Z","end":"2026-04-16T16:30:53.724432Z","steps":["trace[694405351] 'process raft request' (duration: 1.242331273s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:30:53.724709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:30:52.481789Z","time spent":"1.242817143s","remote":"127.0.0.1:52446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T16:30:53.724838Z","caller":"traceutil/trace.go:171","msg":"trace[777949756] linearizableReadLoop","detail":"{readStateIndex:505; appliedIndex:493; }","duration":"915.934212ms","start":"2026-04-16T16:30:52.808875Z","end":"2026-04-16T16:30:53.724809Z","steps":["trace[777949756] 'read index received' (duration: 751.727952ms)","trace[777949756] 'applied index is now lower than readState.Index' (duration: 164.20532ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:30:53.724892Z","caller":"traceutil/trace.go:171","msg":"trace[1673997431] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"1.218968969s","start":"2026-04-16T16:30:52.505911Z","end":"2026-04-16T16:30:53.72488Z","steps":["trace[1673997431] 'process raft request' (duration: 1.218580041s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:30:53.724947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:30:52.505878Z","time spent":"1.219044139s","remote":"127.0.0.1:52446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T16:30:53.724984Z","caller":"traceutil/trace.go:171","msg":"trace[2033344487] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"1.188838987s","start":"2026-04-16T16:30:52.536135Z","end":"2026-04-16T16:30:53.724974Z","steps":["trace[2033344487] 'process raft request' (duration: 1.18846116s)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:30:53.725046Z","caller":"traceutil/trace.go:171","msg":"trace[1254312289] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"1.228472427s","start":"2026-04-16T16:30:52.496563Z","end":"2026-04-16T16:30:53.725036Z","steps":["trace[1254312289] 'process raft request' (duration: 1.227761853s)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:30:53.725052Z","caller":"traceutil/trace.go:171","msg":"trace[859147262] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"1.165364239s","start":"2026-04-16T16:30:52.559666Z","end":"2026-04-16T16:30:53.72503Z","steps":["trace[859147262] 'process raft request' (duration: 1.165008453s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:30:53.725098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:30:52.496534Z","time spent":"1.228543598s","remote":"127.0.0.1:52446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T16:30:53.725113Z","caller":"traceutil/trace.go:171","msg":"trace[1445946412] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"1.046329199s","start":"2026-04-16T16:30:52.678777Z","end":"2026-04-16T16:30:53.725107Z","steps":["trace[1445946412] 'process raft request' (duration: 1.045963582s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:30:53.72514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:30:52.678742Z","time spent":"1.046385s","remote":"127.0.0.1:52446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T16:30:53.725155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:30:52.559637Z","time spent":"1.165480172s","remote":"127.0.0.1:52446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T16:30:53.725185Z","caller":"traceutil/trace.go:171","msg":"trace[2023007693] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"1.228585879s","start":"2026-04-16T16:30:52.496586Z","end":"2026-04-16T16:30:53.725172Z","steps":["trace[2023007693] 'process raft request' (duration: 1.227786264s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:30:53.725243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:30:52.496558Z","time spent":"1.22866373s","remote":"127.0.0.1:52446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T16:30:53.725278Z","caller":"traceutil/trace.go:171","msg":"trace[674694691] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"1.18896579s","start":"2026-04-16T16:30:52.5363Z","end":"2026-04-16T16:30:53.725266Z","steps":["trace[674694691] 'process raft request' (duration: 1.188333908s)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:30:53.724956Z","caller":"traceutil/trace.go:171","msg":"trace[184704521] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"1.218951218s","start":"2026-04-16T16:30:52.505994Z","end":"2026-04-16T16:30:53.724945Z","steps":["trace[184704521] 'process raft request' (duration: 1.218520179s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:30:53.725339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:30:52.536292Z","time spent":"1.189021841s","remote":"127.0.0.1:52446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T16:30:53.725367Z","caller":"traceutil/trace.go:171","msg":"trace[2072702947] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"1.226798793s","start":"2026-04-16T16:30:52.498533Z","end":"2026-04-16T16:30:53.725332Z","steps":["trace[2072702947] 'process raft request' (duration: 1.225925716s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:30:53.725377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:30:52.505987Z","time spent":"1.219349505s","remote":"127.0.0.1:52446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T16:30:53.725479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:30:52.4985Z","time spent":"1.226917016s","remote":"127.0.0.1:52446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T16:30:53.725546Z","caller":"traceutil/trace.go:171","msg":"trace[1802639557] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"1.228864144s","start":"2026-04-16T16:30:52.496669Z","end":"2026-04-16T16:30:53.725533Z","steps":["trace[1802639557] 'process raft request' (duration: 1.227750693s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:30:53.725085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:30:52.536089Z","time spent":"1.188923269s","remote":"127.0.0.1:52446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T16:30:53.725596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"916.718977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-16T16:30:53.725603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:30:52.496663Z","time spent":"1.228917515s","remote":"127.0.0.1:52446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T16:30:53.725632Z","caller":"traceutil/trace.go:171","msg":"trace[750055159] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:488; }","duration":"916.809969ms","start":"2026-04-16T16:30:52.808815Z","end":"2026-04-16T16:30:53.725625Z","steps":["trace[750055159] 'agreement among raft nodes before linearized reading' (duration: 916.626145ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:30:53.72566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:30:52.808789Z","time spent":"916.8663ms","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-16T16:30:53.733303Z","caller":"traceutil/trace.go:171","msg":"trace[2032318092] transaction","detail":"{read_only:false; number_of_response:1; response_revision:490; }","duration":"163.917245ms","start":"2026-04-16T16:30:53.569367Z","end":"2026-04-16T16:30:53.733284Z","steps":["trace[2032318092] 'process raft request' (duration: 163.874294ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:30:53.734114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"860.377355ms","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-16T16:30:53.734196Z","caller":"traceutil/trace.go:171","msg":"trace[653345436] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:490; }","duration":"860.513119ms","start":"2026-04-16T16:30:52.873667Z","end":"2026-04-16T16:30:53.73418Z","steps":["trace[653345436] 'agreement among raft nodes before linearized reading' (duration: 860.263303ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:30:53.734285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:30:52.873647Z","time spent":"860.630241ms","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-16T16:30:53.734466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.884326ms","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-16T16:30:53.734492Z","caller":"traceutil/trace.go:171","msg":"trace[1007348478] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:490; }","duration":"739.968148ms","start":"2026-04-16T16:30:52.994517Z","end":"2026-04-16T16:30:53.734485Z","steps":["trace[1007348478] 'agreement among raft nodes before linearized reading' (duration: 739.908967ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:30:53.734511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:30:52.994477Z","time spent":"740.029969ms","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-16T16:30:53.734608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"860.1266ms","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-16T16:30:53.734635Z","caller":"traceutil/trace.go:171","msg":"trace[199752481] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:490; }","duration":"860.177252ms","start":"2026-04-16T16:30:52.874453Z","end":"2026-04-16T16:30:53.73463Z","steps":["trace[199752481] 'agreement among raft nodes before linearized reading' (duration: 860.134021ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:30:53.734653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:30:52.87444Z","time spent":"860.209442ms","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-16T16:30:53.734468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.375629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:30:53.734833Z","caller":"traceutil/trace.go:171","msg":"trace[460370088] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:490; }","duration":"473.070702ms","start":"2026-04-16T16:30:53.261754Z","end":"2026-04-16T16:30:53.734824Z","steps":["trace[460370088] 'agreement among raft nodes before linearized reading' (duration: 472.262426ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:30:53.734864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:30:53.261733Z","time spent":"473.123724ms","remote":"127.0.0.1:52396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-16T16:30:53.73479Z","caller":"traceutil/trace.go:171","msg":"trace[1501446225] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"167.102038ms","start":"2026-04-16T16:30:53.56765Z","end":"2026-04-16T16:30:53.734752Z","steps":["trace[1501446225] 'process raft request' (duration: 165.436885ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:30:53.735292Z","caller":"traceutil/trace.go:171","msg":"trace[2043696700] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"166.002276ms","start":"2026-04-16T16:30:53.569259Z","end":"2026-04-16T16:30:53.735262Z","steps":["trace[2043696700] 'process raft request' (duration: 163.932895ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:30:59.189598Z","caller":"traceutil/trace.go:171","msg":"trace[259437539] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"642.533928ms","start":"2026-04-16T16:30:58.540576Z","end":"2026-04-16T16:30:59.18311Z","steps":["trace[259437539] 'process raft request' (duration: 642.016238ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:30:59.189872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:30:58.540537Z","time spent":"649.18651ms","remote":"127.0.0.1:52500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036193,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:31:00.377324Z","caller":"traceutil/trace.go:171","msg":"trace[284323160] linearizableReadLoop","detail":"{readStateIndex:606; appliedIndex:606; }","duration":"154.697491ms","start":"2026-04-16T16:31:00.222597Z","end":"2026-04-16T16:31:00.377294Z","steps":["trace[284323160] 'read index received' (duration: 154.689301ms)","trace[284323160] 'applied index is now lower than readState.Index' (duration: 5.79µs)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:31:00.377581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.956126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-2s4sj\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-16T16:31:00.377673Z","caller":"traceutil/trace.go:171","msg":"trace[375744144] range","detail":"{range_begin:/registry/pods/kube-system/cilium-2s4sj; range_end:; response_count:1; response_revision:583; }","duration":"155.113129ms","start":"2026-04-16T16:31:00.222545Z","end":"2026-04-16T16:31:00.377658Z","steps":["trace[375744144] 'agreement among raft nodes before linearized reading' (duration: 154.867264ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:31:01.225829Z","caller":"traceutil/trace.go:171","msg":"trace[848618844] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"985.235142ms","start":"2026-04-16T16:31:00.240561Z","end":"2026-04-16T16:31:01.225796Z","steps":["trace[848618844] 'process raft request' (duration: 983.305794ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:01.226017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:00.240538Z","time spent":"985.384665ms","remote":"127.0.0.1:52500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036257,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:31:01.552275Z","caller":"traceutil/trace.go:171","msg":"trace[1273043161] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"1.173323349s","start":"2026-04-16T16:31:00.378912Z","end":"2026-04-16T16:31:01.552236Z","steps":["trace[1273043161] 'process raft request' (duration: 1.13269315s)","trace[1273043161] 'compare' (duration: 40.104449ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:31:01.552598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:00.378879Z","time spent":"1.173605226s","remote":"127.0.0.1:52426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:31:01.553061Z","caller":"traceutil/trace.go:171","msg":"trace[289038636] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"1.159462443s","start":"2026-04-16T16:31:00.393367Z","end":"2026-04-16T16:31:01.552829Z","steps":["trace[289038636] 'process raft request' (duration: 1.158726358s)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:31:01.553222Z","caller":"traceutil/trace.go:171","msg":"trace[428334162] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"274.319723ms","start":"2026-04-16T16:31:01.278861Z","end":"2026-04-16T16:31:01.553181Z","steps":["trace[428334162] 'process raft request' (duration: 273.410195ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:01.55329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:00.393332Z","time spent":"1.15985353s","remote":"127.0.0.1:52592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:31:01.553289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.836092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:31:01.553425Z","caller":"traceutil/trace.go:171","msg":"trace[403227610] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:587; }","duration":"294.971635ms","start":"2026-04-16T16:31:01.258438Z","end":"2026-04-16T16:31:01.553409Z","steps":["trace[403227610] 'agreement among raft nodes before linearized reading' (duration: 294.71612ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:31:01.553108Z","caller":"traceutil/trace.go:171","msg":"trace[1236854095] linearizableReadLoop","detail":"{readStateIndex:609; appliedIndex:607; }","duration":"294.626147ms","start":"2026-04-16T16:31:01.258453Z","end":"2026-04-16T16:31:01.55308Z","steps":["trace[1236854095] 'read index received' (duration: 253.08287ms)","trace[1236854095] 'applied index is now lower than readState.Index' (duration: 41.540537ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:31:02.762296Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620625909160028221,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T16:31:03.206961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.36866243s","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-16T16:31:03.207064Z","caller":"traceutil/trace.go:171","msg":"trace[1050133900] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:587; }","duration":"1.368803443s","start":"2026-04-16T16:31:01.838236Z","end":"2026-04-16T16:31:03.207039Z","steps":["trace[1050133900] 'range keys from in-memory index tree' (duration: 1.368486027s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:03.207118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:01.838227Z","time spent":"1.368879784s","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-16T16:31:03.207098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.392688898s","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-16T16:31:03.207205Z","caller":"traceutil/trace.go:171","msg":"trace[364888184] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:587; }","duration":"1.392869782s","start":"2026-04-16T16:31:01.814309Z","end":"2026-04-16T16:31:03.207179Z","steps":["trace[364888184] 'range keys from in-memory index tree' (duration: 1.392441253s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:03.207279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:01.814285Z","time spent":"1.392982744s","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-16T16:31:03.207268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.370752372s","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-16T16:31:03.207337Z","caller":"traceutil/trace.go:171","msg":"trace[564822540] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:587; }","duration":"1.370881004s","start":"2026-04-16T16:31:01.836435Z","end":"2026-04-16T16:31:03.207316Z","steps":["trace[564822540] 'range keys from in-memory index tree' (duration: 1.370568279s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:03.207378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:01.836408Z","time spent":"1.370963596s","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-16T16:31:03.20744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.369889945s","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-16T16:31:03.208275Z","caller":"traceutil/trace.go:171","msg":"trace[1669766119] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:587; }","duration":"1.370021278s","start":"2026-04-16T16:31:01.837496Z","end":"2026-04-16T16:31:03.207517Z","steps":["trace[1669766119] 'range keys from in-memory index tree' (duration: 1.369722491s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:03.208435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:01.837488Z","time spent":"1.370926325s","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-16T16:31:03.263505Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620625909160028221,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T16:31:03.764692Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620625909160028221,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T16:31:04.11858Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.106166292s","expected-duration":"1s"} {"level":"info","ts":"2026-04-16T16:31:04.119598Z","caller":"traceutil/trace.go:171","msg":"trace[2031504422] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"2.164518792s","start":"2026-04-16T16:31:01.955049Z","end":"2026-04-16T16:31:04.119568Z","steps":["trace[2031504422] 'process raft request' (duration: 2.163960531s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:04.11975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:01.955014Z","time spent":"2.164644114s","remote":"127.0.0.1:52500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:31:04.146625Z","caller":"traceutil/trace.go:171","msg":"trace[367395616] linearizableReadLoop","detail":"{readStateIndex:616; appliedIndex:610; }","duration":"1.884683618s","start":"2026-04-16T16:31:02.261917Z","end":"2026-04-16T16:31:04.1466Z","steps":["trace[367395616] 'read index received' (duration: 1.856998637s)","trace[367395616] 'applied index is now lower than readState.Index' (duration: 27.683381ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:31:04.146775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.88483918s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:31:04.146768Z","caller":"traceutil/trace.go:171","msg":"trace[1864498968] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"933.675495ms","start":"2026-04-16T16:31:03.213051Z","end":"2026-04-16T16:31:04.146726Z","steps":["trace[1864498968] 'process raft request' (duration: 933.41743ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:31:04.14686Z","caller":"traceutil/trace.go:171","msg":"trace[68332194] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"932.145105ms","start":"2026-04-16T16:31:03.214705Z","end":"2026-04-16T16:31:04.14685Z","steps":["trace[68332194] 'process raft request' (duration: 931.796878ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:04.146964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:03.214694Z","time spent":"932.199375ms","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:31:04.146992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:03.213029Z","time spent":"933.845418ms","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:31:04.147183Z","caller":"traceutil/trace.go:171","msg":"trace[827655378] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"932.176346ms","start":"2026-04-16T16:31:03.214995Z","end":"2026-04-16T16:31:04.147172Z","steps":["trace[827655378] 'process raft request' (duration: 931.538252ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:31:04.146819Z","caller":"traceutil/trace.go:171","msg":"trace[1122256691] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:593; }","duration":"1.884948053s","start":"2026-04-16T16:31:02.261859Z","end":"2026-04-16T16:31:04.146807Z","steps":["trace[1122256691] 'agreement among raft nodes before linearized reading' (duration: 1.884833511s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:04.147286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:03.214926Z","time spent":"932.321779ms","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:31:04.147469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:02.261836Z","time spent":"1.885620226s","remote":"127.0.0.1:52396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-16T16:31:04.147844Z","caller":"traceutil/trace.go:171","msg":"trace[532262391] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"1.670337778s","start":"2026-04-16T16:31:02.477056Z","end":"2026-04-16T16:31:04.147394Z","steps":["trace[532262391] 'process raft request' (duration: 1.669270937s)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:31:04.147929Z","caller":"traceutil/trace.go:171","msg":"trace[198899680] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"932.295348ms","start":"2026-04-16T16:31:03.215118Z","end":"2026-04-16T16:31:04.147414Z","steps":["trace[198899680] 'process raft request' (duration: 931.447431ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:04.147984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:03.21505Z","time spent":"932.9127ms","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:31:04.148407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:02.477004Z","time spent":"1.671121933s","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:31:04.808723Z","caller":"traceutil/trace.go:171","msg":"trace[1139091460] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"133.651521ms","start":"2026-04-16T16:31:04.675045Z","end":"2026-04-16T16:31:04.808696Z","steps":["trace[1139091460] 'process raft request' (duration: 133.520789ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:31:16.501298Z","caller":"traceutil/trace.go:171","msg":"trace[1984965217] linearizableReadLoop","detail":"{readStateIndex:762; appliedIndex:761; }","duration":"295.441144ms","start":"2026-04-16T16:31:16.205824Z","end":"2026-04-16T16:31:16.501265Z","steps":["trace[1984965217] 'read index received' (duration: 295.077767ms)","trace[1984965217] 'applied index is now lower than readState.Index' (duration: 362.117µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:31:16.501418Z","caller":"traceutil/trace.go:171","msg":"trace[1757060008] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"346.47739ms","start":"2026-04-16T16:31:16.154893Z","end":"2026-04-16T16:31:16.501371Z","steps":["trace[1757060008] 'process raft request' (duration: 346.167094ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:16.501653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.517316ms","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-16T16:31:16.50169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:16.15486Z","time spent":"346.703515ms","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:31:16.501703Z","caller":"traceutil/trace.go:171","msg":"trace[481893703] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:735; }","duration":"290.615238ms","start":"2026-04-16T16:31:16.211075Z","end":"2026-04-16T16:31:16.50169Z","steps":["trace[481893703] 'agreement among raft nodes before linearized reading' (duration: 290.369204ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:16.501721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.781891ms","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-16T16:31:16.501731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.280923ms","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-16T16:31:16.501802Z","caller":"traceutil/trace.go:171","msg":"trace[1186986451] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:735; }","duration":"286.917555ms","start":"2026-04-16T16:31:16.214866Z","end":"2026-04-16T16:31:16.501784Z","steps":["trace[1186986451] 'agreement among raft nodes before linearized reading' (duration: 286.68557ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:31:16.501827Z","caller":"traceutil/trace.go:171","msg":"trace[521506278] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:735; }","duration":"286.408715ms","start":"2026-04-16T16:31:16.215396Z","end":"2026-04-16T16:31:16.501804Z","steps":["trace[521506278] 'agreement among raft nodes before linearized reading' (duration: 286.142268ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:16.501912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.081408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-16T16:31:16.502042Z","caller":"traceutil/trace.go:171","msg":"trace[1025813741] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:735; }","duration":"296.268612ms","start":"2026-04-16T16:31:16.205752Z","end":"2026-04-16T16:31:16.502021Z","steps":["trace[1025813741] 'agreement among raft nodes before linearized reading' (duration: 295.777791ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:16.502296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.49058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:31:16.502334Z","caller":"traceutil/trace.go:171","msg":"trace[513431767] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:735; }","duration":"239.556831ms","start":"2026-04-16T16:31:16.262768Z","end":"2026-04-16T16:31:16.502325Z","steps":["trace[513431767] 'agreement among raft nodes before linearized reading' (duration: 238.708473ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:31:20.288521Z","caller":"traceutil/trace.go:171","msg":"trace[1118194345] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"935.594945ms","start":"2026-04-16T16:31:19.352906Z","end":"2026-04-16T16:31:20.288501Z","steps":["trace[1118194345] 'process raft request' (duration: 935.449312ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:20.288667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:19.352886Z","time spent":"935.705466ms","remote":"127.0.0.1:52592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4027,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:31:20.720348Z","caller":"traceutil/trace.go:171","msg":"trace[726739241] linearizableReadLoop","detail":"{readStateIndex:781; appliedIndex:779; }","duration":"502.147902ms","start":"2026-04-16T16:31:20.218162Z","end":"2026-04-16T16:31:20.72031Z","steps":["trace[726739241] 'read index received' (duration: 70.277939ms)","trace[726739241] 'applied index is now lower than readState.Index' (duration: 431.868253ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:31:20.720416Z","caller":"traceutil/trace.go:171","msg":"trace[1839155164] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"743.161362ms","start":"2026-04-16T16:31:19.977222Z","end":"2026-04-16T16:31:20.720384Z","steps":["trace[1839155164] 'process raft request' (duration: 740.125341ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:20.720611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.30209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T16:31:20.720617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.450388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.65\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-16T16:31:20.720743Z","caller":"traceutil/trace.go:171","msg":"trace[1466421811] range","detail":"{range_begin:/registry/masterleases/199.204.45.65; range_end:; response_count:1; response_revision:754; }","duration":"502.605661ms","start":"2026-04-16T16:31:20.218093Z","end":"2026-04-16T16:31:20.720699Z","steps":["trace[1466421811] 'agreement among raft nodes before linearized reading' (duration: 502.388417ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:20.720765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:19.977196Z","time spent":"743.294054ms","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:31:20.720793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:20.218074Z","time spent":"502.708523ms","remote":"127.0.0.1:52426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.65\" "} {"level":"warn","ts":"2026-04-16T16:31:20.720847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.611243ms","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-16T16:31:20.720877Z","caller":"traceutil/trace.go:171","msg":"trace[1370847971] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:754; }","duration":"115.660054ms","start":"2026-04-16T16:31:20.605209Z","end":"2026-04-16T16:31:20.720869Z","steps":["trace[1370847971] 'agreement among raft nodes before linearized reading' (duration: 115.499081ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:20.721058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.633063ms","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-16T16:31:20.721089Z","caller":"traceutil/trace.go:171","msg":"trace[2025806849] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:754; }","duration":"116.664434ms","start":"2026-04-16T16:31:20.604418Z","end":"2026-04-16T16:31:20.721082Z","steps":["trace[2025806849] 'agreement among raft nodes before linearized reading' (duration: 116.584222ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:31:20.720674Z","caller":"traceutil/trace.go:171","msg":"trace[259264723] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:754; }","duration":"443.435482ms","start":"2026-04-16T16:31:20.277226Z","end":"2026-04-16T16:31:20.720662Z","steps":["trace[259264723] 'agreement among raft nodes before linearized reading' (duration: 443.29376ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:20.721401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.220268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-04-16T16:31:20.721516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.371228ms","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-16T16:31:20.721485Z","caller":"traceutil/trace.go:171","msg":"trace[2016745405] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:754; }","duration":"200.32365ms","start":"2026-04-16T16:31:20.521117Z","end":"2026-04-16T16:31:20.721441Z","steps":["trace[2016745405] 'agreement among raft nodes before linearized reading' (duration: 200.188358ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:20.721642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.207364ms","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-16T16:31:20.721713Z","caller":"traceutil/trace.go:171","msg":"trace[1488036779] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:754; }","duration":"118.610122ms","start":"2026-04-16T16:31:20.603088Z","end":"2026-04-16T16:31:20.721698Z","steps":["trace[1488036779] 'agreement among raft nodes before linearized reading' (duration: 118.298346ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:31:20.721749Z","caller":"traceutil/trace.go:171","msg":"trace[1519233684] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:754; }","duration":"119.44528ms","start":"2026-04-16T16:31:20.60228Z","end":"2026-04-16T16:31:20.721725Z","steps":["trace[1519233684] 'agreement among raft nodes before linearized reading' (duration: 118.97586ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:20.722012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:20.277209Z","time spent":"443.982953ms","remote":"127.0.0.1:52644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"info","ts":"2026-04-16T16:31:30.951578Z","caller":"traceutil/trace.go:171","msg":"trace[760295587] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"134.889676ms","start":"2026-04-16T16:31:30.816669Z","end":"2026-04-16T16:31:30.951559Z","steps":["trace[760295587] 'process raft request' (duration: 134.854035ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:31:30.952136Z","caller":"traceutil/trace.go:171","msg":"trace[906518148] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"137.492929ms","start":"2026-04-16T16:31:30.814619Z","end":"2026-04-16T16:31:30.952111Z","steps":["trace[906518148] 'process raft request' (duration: 135.920077ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:31:30.952372Z","caller":"traceutil/trace.go:171","msg":"trace[1576207714] transaction","detail":"{read_only:false; response_revision:877; number_of_response:1; }","duration":"135.733373ms","start":"2026-04-16T16:31:30.816625Z","end":"2026-04-16T16:31:30.952358Z","steps":["trace[1576207714] 'process raft request' (duration: 134.877697ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:57.37011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.681665515s","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-16T16:31:57.370289Z","caller":"traceutil/trace.go:171","msg":"trace[1319792514] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:972; }","duration":"1.6818529s","start":"2026-04-16T16:31:55.68842Z","end":"2026-04-16T16:31:57.370273Z","steps":["trace[1319792514] 'range keys from in-memory index tree' (duration: 1.681650515s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:58.001232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.196866066s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-16T16:31:58.001757Z","caller":"traceutil/trace.go:171","msg":"trace[937796427] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"2.347126689s","start":"2026-04-16T16:31:55.65461Z","end":"2026-04-16T16:31:58.001737Z","steps":["trace[937796427] 'process raft request' (duration: 2.346723151s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:58.001866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:55.654585Z","time spent":"2.347207781s","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:31:58.001982Z","caller":"traceutil/trace.go:171","msg":"trace[763423199] linearizableReadLoop","detail":"{readStateIndex:1010; appliedIndex:1008; }","duration":"1.739762645s","start":"2026-04-16T16:31:56.261975Z","end":"2026-04-16T16:31:58.001737Z","steps":["trace[763423199] 'read index received' (duration: 304.698638ms)","trace[763423199] 'applied index is now lower than readState.Index' (duration: 1.435062327s)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:31:58.008532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.746554128s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T16:31:58.008543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.087356299s","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-16T16:31:58.008571Z","caller":"traceutil/trace.go:171","msg":"trace[1866564928] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:973; }","duration":"1.74663951s","start":"2026-04-16T16:31:56.261921Z","end":"2026-04-16T16:31:58.00856Z","steps":["trace[1866564928] 'agreement among raft nodes before linearized reading' (duration: 1.739821226s)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:31:58.00858Z","caller":"traceutil/trace.go:171","msg":"trace[347889321] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:973; }","duration":"1.087450581s","start":"2026-04-16T16:31:56.921118Z","end":"2026-04-16T16:31:58.008569Z","steps":["trace[347889321] 'agreement among raft nodes before linearized reading' (duration: 1.081002474s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:58.008597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:56.261903Z","time spent":"1.746689181s","remote":"127.0.0.1:52396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T16:31:58.008603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:56.9211Z","time spent":"1.087497861s","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-16T16:31:58.00875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.040586435s","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-16T16:31:58.00881Z","caller":"traceutil/trace.go:171","msg":"trace[1747259097] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:973; }","duration":"1.040696987s","start":"2026-04-16T16:31:56.968098Z","end":"2026-04-16T16:31:58.008795Z","steps":["trace[1747259097] 'agreement among raft nodes before linearized reading' (duration: 1.034059105s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:58.008846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:56.96808Z","time spent":"1.040759229s","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-16T16:31:58.008924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"916.941488ms","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-16T16:31:58.008952Z","caller":"traceutil/trace.go:171","msg":"trace[1596831511] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:973; }","duration":"917.02387ms","start":"2026-04-16T16:31:57.09192Z","end":"2026-04-16T16:31:58.008944Z","steps":["trace[1596831511] 'agreement among raft nodes before linearized reading' (duration: 910.204115ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:58.008972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:57.091906Z","time spent":"917.06144ms","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-16T16:31:58.009061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"916.432569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-16T16:31:58.009084Z","caller":"traceutil/trace.go:171","msg":"trace[2072513076] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:973; }","duration":"916.474649ms","start":"2026-04-16T16:31:57.092602Z","end":"2026-04-16T16:31:58.009077Z","steps":["trace[2072513076] 'agreement among raft nodes before linearized reading' (duration: 909.546802ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:58.009102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:57.092593Z","time spent":"916.50524ms","remote":"127.0.0.1:52682","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-16T16:31:58.009205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"918.056811ms","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-16T16:31:58.009224Z","caller":"traceutil/trace.go:171","msg":"trace[1244835572] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:973; }","duration":"918.103982ms","start":"2026-04-16T16:31:57.091112Z","end":"2026-04-16T16:31:58.009216Z","steps":["trace[1244835572] 'agreement among raft nodes before linearized reading' (duration: 911.021012ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:58.00924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:57.091094Z","time spent":"918.142162ms","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-16T16:32:02.102804Z","caller":"traceutil/trace.go:171","msg":"trace[1135166363] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"240.193272ms","start":"2026-04-16T16:32:01.862592Z","end":"2026-04-16T16:32:02.102786Z","steps":["trace[1135166363] 'process raft request' (duration: 240.053608ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:32:02.116679Z","caller":"traceutil/trace.go:171","msg":"trace[418896149] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"103.019448ms","start":"2026-04-16T16:32:02.013648Z","end":"2026-04-16T16:32:02.116667Z","steps":["trace[418896149] 'process raft request' (duration: 102.883735ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:32:18.303257Z","caller":"traceutil/trace.go:171","msg":"trace[192531479] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"129.655395ms","start":"2026-04-16T16:32:18.17358Z","end":"2026-04-16T16:32:18.303236Z","steps":["trace[192531479] 'process raft request' (duration: 129.330739ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:32:18.918967Z","caller":"traceutil/trace.go:171","msg":"trace[579693355] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"611.146229ms","start":"2026-04-16T16:32:18.307791Z","end":"2026-04-16T16:32:18.918937Z","steps":["trace[579693355] 'process raft request' (duration: 531.871631ms)","trace[579693355] 'compare' (duration: 78.805139ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:32:18.919836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:32:18.307765Z","time spent":"611.270812ms","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:32:19.606487Z","caller":"traceutil/trace.go:171","msg":"trace[1097053124] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"1.298515015s","start":"2026-04-16T16:32:18.307949Z","end":"2026-04-16T16:32:19.606464Z","steps":["trace[1097053124] 'process raft request' (duration: 1.298445684s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:32:19.60665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:32:18.307917Z","time spent":"1.298640378s","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:32:19.606951Z","caller":"traceutil/trace.go:171","msg":"trace[1353234835] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"1.298821712s","start":"2026-04-16T16:32:18.308093Z","end":"2026-04-16T16:32:19.606914Z","steps":["trace[1353234835] 'process raft request' (duration: 1.298341293s)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:32:19.607188Z","caller":"traceutil/trace.go:171","msg":"trace[153747379] linearizableReadLoop","detail":"{readStateIndex:1085; appliedIndex:1080; }","duration":"869.06166ms","start":"2026-04-16T16:32:18.737776Z","end":"2026-04-16T16:32:19.606838Z","steps":["trace[153747379] 'read index received' (duration: 101.913395ms)","trace[153747379] 'applied index is now lower than readState.Index' (duration: 767.147365ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:32:19.607298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:32:18.308078Z","time spent":"1.299110798s","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:32:19.607309Z","caller":"traceutil/trace.go:171","msg":"trace[1600107801] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"1.299194159s","start":"2026-04-16T16:32:18.308103Z","end":"2026-04-16T16:32:19.607297Z","steps":["trace[1600107801] 'process raft request' (duration: 1.298653808s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:32:19.607412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"869.619532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T16:32:19.607448Z","caller":"traceutil/trace.go:171","msg":"trace[1817436611] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1044; }","duration":"869.699794ms","start":"2026-04-16T16:32:18.737737Z","end":"2026-04-16T16:32:19.607437Z","steps":["trace[1817436611] 'agreement among raft nodes before linearized reading' (duration: 869.594032ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:32:19.607467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:32:18.308093Z","time spent":"1.299344413s","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:32:19.607489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:32:18.737721Z","time spent":"869.758215ms","remote":"127.0.0.1:52952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":29,"response size":31,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-04-16T16:32:19.607472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.655298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:32:19.607463Z","caller":"traceutil/trace.go:171","msg":"trace[228154328] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"1.298664628s","start":"2026-04-16T16:32:18.307846Z","end":"2026-04-16T16:32:19.606511Z","steps":["trace[228154328] 'process raft request' (duration: 1.298423234s)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:32:19.607557Z","caller":"traceutil/trace.go:171","msg":"trace[947904060] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1044; }","duration":"345.77817ms","start":"2026-04-16T16:32:19.261754Z","end":"2026-04-16T16:32:19.607532Z","steps":["trace[947904060] 'agreement among raft nodes before linearized reading' (duration: 345.646007ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:32:19.607598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:32:19.261735Z","time spent":"345.856472ms","remote":"127.0.0.1:52396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T16:32:19.608979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:32:18.307822Z","time spent":"1.299781692s","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:32:20.828071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.510251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T16:32:20.828141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.175902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T16:32:20.828167Z","caller":"traceutil/trace.go:171","msg":"trace[487934773] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:1046; }","duration":"178.660015ms","start":"2026-04-16T16:32:20.649485Z","end":"2026-04-16T16:32:20.828145Z","steps":["trace[487934773] 'count revisions from in-memory index tree' (duration: 178.376299ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:32:20.828201Z","caller":"traceutil/trace.go:171","msg":"trace[1792968345] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1046; }","duration":"146.284804ms","start":"2026-04-16T16:32:20.6819Z","end":"2026-04-16T16:32:20.828184Z","steps":["trace[1792968345] 'count revisions from in-memory index tree' (duration: 146.047779ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:32:21.763855Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620625909160029199,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-16T16:32:21.785704Z","caller":"traceutil/trace.go:171","msg":"trace[1191532649] linearizableReadLoop","detail":"{readStateIndex:1089; appliedIndex:1088; }","duration":"522.795491ms","start":"2026-04-16T16:32:21.262874Z","end":"2026-04-16T16:32:21.785669Z","steps":["trace[1191532649] 'read index received' (duration: 522.457025ms)","trace[1191532649] 'applied index is now lower than readState.Index' (duration: 337.486µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:32:21.785861Z","caller":"traceutil/trace.go:171","msg":"trace[450075617] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"855.757158ms","start":"2026-04-16T16:32:20.930089Z","end":"2026-04-16T16:32:21.785846Z","steps":["trace[450075617] 'process raft request' (duration: 855.35309ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:32:21.785996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:32:20.930068Z","time spent":"855.833621ms","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:32:21.786061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.515623ms","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-16T16:32:21.786129Z","caller":"traceutil/trace.go:171","msg":"trace[1494709951] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1047; }","duration":"174.648816ms","start":"2026-04-16T16:32:21.611463Z","end":"2026-04-16T16:32:21.786112Z","steps":["trace[1494709951] 'agreement among raft nodes before linearized reading' (duration: 174.456672ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:32:21.786262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.394874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:32:21.786296Z","caller":"traceutil/trace.go:171","msg":"trace[1058420471] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1047; }","duration":"523.466525ms","start":"2026-04-16T16:32:21.262821Z","end":"2026-04-16T16:32:21.786288Z","steps":["trace[1058420471] 'agreement among raft nodes before linearized reading' (duration: 523.416644ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:32:21.786322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:32:21.262799Z","time spent":"523.516446ms","remote":"127.0.0.1:52396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T16:32:21.786542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.393821ms","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-16T16:32:21.786578Z","caller":"traceutil/trace.go:171","msg":"trace[1180107429] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1047; }","duration":"174.479592ms","start":"2026-04-16T16:32:21.612089Z","end":"2026-04-16T16:32:21.786569Z","steps":["trace[1180107429] 'agreement among raft nodes before linearized reading' (duration: 174.388771ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:32:21.786533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.90296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-16T16:32:21.786686Z","caller":"traceutil/trace.go:171","msg":"trace[654761378] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1047; }","duration":"174.105544ms","start":"2026-04-16T16:32:21.612553Z","end":"2026-04-16T16:32:21.786659Z","steps":["trace[654761378] 'agreement among raft nodes before linearized reading' (duration: 173.768208ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:32:21.786752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.649295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-16T16:32:21.786812Z","caller":"traceutil/trace.go:171","msg":"trace[1850697942] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1047; }","duration":"173.726167ms","start":"2026-04-16T16:32:21.61307Z","end":"2026-04-16T16:32:21.786796Z","steps":["trace[1850697942] 'agreement among raft nodes before linearized reading' (duration: 173.545983ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:32:21.948708Z","caller":"traceutil/trace.go:171","msg":"trace[954446731] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"156.23083ms","start":"2026-04-16T16:32:21.792454Z","end":"2026-04-16T16:32:21.948685Z","steps":["trace[954446731] 'process raft request' (duration: 80.197027ms)","trace[954446731] 'compare' (duration: 75.86814ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:32:21.948948Z","caller":"traceutil/trace.go:171","msg":"trace[1650238268] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"156.16171ms","start":"2026-04-16T16:32:21.792761Z","end":"2026-04-16T16:32:21.948923Z","steps":["trace[1650238268] 'process raft request' (duration: 156.053758ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:32:21.948945Z","caller":"traceutil/trace.go:171","msg":"trace[1132194199] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"156.414155ms","start":"2026-04-16T16:32:21.792503Z","end":"2026-04-16T16:32:21.948918Z","steps":["trace[1132194199] 'process raft request' (duration: 156.14892ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:32:22.635419Z","caller":"traceutil/trace.go:171","msg":"trace[757049439] linearizableReadLoop","detail":"{readStateIndex:1095; appliedIndex:1094; }","duration":"116.149218ms","start":"2026-04-16T16:32:22.519245Z","end":"2026-04-16T16:32:22.635394Z","steps":["trace[757049439] 'read index received' (duration: 116.000265ms)","trace[757049439] 'applied index is now lower than readState.Index' (duration: 147.253µs)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:32:22.635552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.296691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T16:32:22.635584Z","caller":"traceutil/trace.go:171","msg":"trace[663113744] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1053; }","duration":"116.401052ms","start":"2026-04-16T16:32:22.519174Z","end":"2026-04-16T16:32:22.635575Z","steps":["trace[663113744] 'agreement among raft nodes before linearized reading' (duration: 116.314061ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:32:22.635842Z","caller":"traceutil/trace.go:171","msg":"trace[1550282695] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"225.335327ms","start":"2026-04-16T16:32:22.410496Z","end":"2026-04-16T16:32:22.635831Z","steps":["trace[1550282695] 'process raft request' (duration: 224.738665ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:32:25.1393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"766.263388ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T16:32:25.139577Z","caller":"traceutil/trace.go:171","msg":"trace[458496208] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"766.612305ms","start":"2026-04-16T16:32:24.372937Z","end":"2026-04-16T16:32:25.13955Z","steps":["trace[458496208] 'process raft request' (duration: 766.530303ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:32:25.13967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:32:24.372912Z","time spent":"766.719317ms","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:32:25.139677Z","caller":"traceutil/trace.go:171","msg":"trace[1983652774] linearizableReadLoop","detail":"{readStateIndex:1097; appliedIndex:1096; }","duration":"1.185805336s","start":"2026-04-16T16:32:23.95385Z","end":"2026-04-16T16:32:25.139655Z","steps":["trace[1983652774] 'read index received' (duration: 418.973207ms)","trace[1983652774] 'applied index is now lower than readState.Index' (duration: 766.829089ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:32:25.139543Z","caller":"traceutil/trace.go:171","msg":"trace[11099913] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"1.302007475s","start":"2026-04-16T16:32:23.837493Z","end":"2026-04-16T16:32:25.139501Z","steps":["trace[11099913] 'process raft request' (duration: 535.41622ms)","trace[11099913] 'compare' (duration: 766.053204ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:32:25.140067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.186207844s","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-16T16:32:25.140143Z","caller":"traceutil/trace.go:171","msg":"trace[527866827] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1056; }","duration":"1.186330096s","start":"2026-04-16T16:32:23.953796Z","end":"2026-04-16T16:32:25.140126Z","steps":["trace[527866827] 'agreement among raft nodes before linearized reading' (duration: 1.18602457s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:32:25.140203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.186253215s","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-16T16:32:25.140261Z","caller":"traceutil/trace.go:171","msg":"trace[663711809] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1056; }","duration":"1.186333006s","start":"2026-04-16T16:32:23.953913Z","end":"2026-04-16T16:32:25.140246Z","steps":["trace[663711809] 'agreement among raft nodes before linearized reading' (duration: 1.186207674s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:32:25.14024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.598856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T16:32:25.1403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:32:23.953901Z","time spent":"1.186390457s","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-16T16:32:25.14032Z","caller":"traceutil/trace.go:171","msg":"trace[1894220428] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1056; }","duration":"526.737149ms","start":"2026-04-16T16:32:24.613563Z","end":"2026-04-16T16:32:25.1403Z","steps":["trace[1894220428] 'agreement among raft nodes before linearized reading' (duration: 526.595696ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:32:25.140379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:32:24.613539Z","time spent":"526.829351ms","remote":"127.0.0.1:52696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":29,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-16T16:32:25.140525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:32:23.837447Z","time spent":"1.302523985s","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:32:25.140215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:32:23.953735Z","time spent":"1.186467099s","remote":"127.0.0.1:52682","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-16T16:32:25.140707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.185506591s","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-16T16:32:25.140811Z","caller":"traceutil/trace.go:171","msg":"trace[1102117172] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1056; }","duration":"1.185674803s","start":"2026-04-16T16:32:23.955117Z","end":"2026-04-16T16:32:25.140792Z","steps":["trace[1102117172] 'agreement among raft nodes before linearized reading' (duration: 1.185345406s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:32:25.140946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:32:23.955105Z","time spent":"1.185822797s","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-16T16:32:25.350671Z","caller":"traceutil/trace.go:171","msg":"trace[1742073921] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"204.214109ms","start":"2026-04-16T16:32:25.146403Z","end":"2026-04-16T16:32:25.350617Z","steps":["trace[1742073921] 'process raft request' (duration: 174.700606ms)","trace[1742073921] 'compare' (duration: 29.229767ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:32:25.394958Z","caller":"traceutil/trace.go:171","msg":"trace[1117720995] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"247.850053ms","start":"2026-04-16T16:32:25.147079Z","end":"2026-04-16T16:32:25.394929Z","steps":["trace[1117720995] 'process raft request' (duration: 247.644359ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:32:25.395386Z","caller":"traceutil/trace.go:171","msg":"trace[1417996688] linearizableReadLoop","detail":"{readStateIndex:1101; appliedIndex:1098; }","duration":"133.120444ms","start":"2026-04-16T16:32:25.262219Z","end":"2026-04-16T16:32:25.395339Z","steps":["trace[1417996688] 'read index received' (duration: 59.09579ms)","trace[1417996688] 'applied index is now lower than readState.Index' (duration: 74.022074ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:32:25.395587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.327788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:32:25.39563Z","caller":"traceutil/trace.go:171","msg":"trace[1911211039] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1059; }","duration":"133.44735ms","start":"2026-04-16T16:32:25.262171Z","end":"2026-04-16T16:32:25.395618Z","steps":["trace[1911211039] 'agreement among raft nodes before linearized reading' (duration: 133.287027ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:32:25.395735Z","caller":"traceutil/trace.go:171","msg":"trace[818643055] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"248.412294ms","start":"2026-04-16T16:32:25.147153Z","end":"2026-04-16T16:32:25.395566Z","steps":["trace[818643055] 'process raft request' (duration: 247.72668ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:32:36.554186Z","caller":"traceutil/trace.go:171","msg":"trace[227700941] transaction","detail":"{read_only:false; response_revision:1146; number_of_response:1; }","duration":"303.146437ms","start":"2026-04-16T16:32:36.251012Z","end":"2026-04-16T16:32:36.554158Z","steps":["trace[227700941] 'process raft request' (duration: 302.949453ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:32:36.55435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:32:36.250989Z","time spent":"303.277399ms","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:32:39.553385Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.608468956s","expected-duration":"1s"} {"level":"info","ts":"2026-04-16T16:32:39.553768Z","caller":"traceutil/trace.go:171","msg":"trace[509601324] transaction","detail":"{read_only:false; response_revision:1152; number_of_response:1; }","duration":"1.608921376s","start":"2026-04-16T16:32:37.944824Z","end":"2026-04-16T16:32:39.553746Z","steps":["trace[509601324] 'process raft request' (duration: 1.608773604s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:32:39.5539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:32:37.944804Z","time spent":"1.609029638s","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:32:40.332601Z","caller":"traceutil/trace.go:171","msg":"trace[1172018061] linearizableReadLoop","detail":"{readStateIndex:1200; appliedIndex:1198; }","duration":"1.137171294s","start":"2026-04-16T16:32:39.195405Z","end":"2026-04-16T16:32:40.332577Z","steps":["trace[1172018061] 'read index received' (duration: 358.201806ms)","trace[1172018061] 'applied index is now lower than readState.Index' (duration: 778.968508ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:32:40.332721Z","caller":"traceutil/trace.go:171","msg":"trace[793073829] transaction","detail":"{read_only:false; response_revision:1153; number_of_response:1; }","duration":"1.143834616s","start":"2026-04-16T16:32:39.188874Z","end":"2026-04-16T16:32:40.332709Z","steps":["trace[793073829] 'process raft request' (duration: 1.14049558s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:32:40.332837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:32:39.188857Z","time spent":"1.143912998s","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:32:40.332887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.071010136s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:32:40.33298Z","caller":"traceutil/trace.go:171","msg":"trace[725115809] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1153; }","duration":"1.071141409s","start":"2026-04-16T16:32:39.26181Z","end":"2026-04-16T16:32:40.332952Z","steps":["trace[725115809] 'agreement among raft nodes before linearized reading' (duration: 1.071018356s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:32:40.333041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:32:39.261791Z","time spent":"1.07123538s","remote":"127.0.0.1:52396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T16:32:40.333324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"873.017718ms","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-16T16:32:40.333356Z","caller":"traceutil/trace.go:171","msg":"trace[1608246589] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1153; }","duration":"873.083501ms","start":"2026-04-16T16:32:39.460263Z","end":"2026-04-16T16:32:40.333346Z","steps":["trace[1608246589] 'agreement among raft nodes before linearized reading' (duration: 872.962968ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:32:40.333383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:32:39.460245Z","time spent":"873.132022ms","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-16T16:32:40.333545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"868.695974ms","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-16T16:32:40.333592Z","caller":"traceutil/trace.go:171","msg":"trace[564352700] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1153; }","duration":"868.809037ms","start":"2026-04-16T16:32:39.464772Z","end":"2026-04-16T16:32:40.333581Z","steps":["trace[564352700] 'agreement among raft nodes before linearized reading' (duration: 868.354317ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:32:40.333606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"925.677921ms","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-16T16:32:40.333624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:32:39.46475Z","time spent":"868.866997ms","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-16T16:32:40.333637Z","caller":"traceutil/trace.go:171","msg":"trace[1426046108] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1153; }","duration":"925.785903ms","start":"2026-04-16T16:32:39.407842Z","end":"2026-04-16T16:32:40.333628Z","steps":["trace[1426046108] 'agreement among raft nodes before linearized reading' (duration: 925.702262ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:32:40.333667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:32:39.40782Z","time spent":"925.839895ms","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-16T16:32:40.333893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.13849599s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-16T16:32:40.333929Z","caller":"traceutil/trace.go:171","msg":"trace[1656508309] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1153; }","duration":"1.138569632s","start":"2026-04-16T16:32:39.19535Z","end":"2026-04-16T16:32:40.333919Z","steps":["trace[1656508309] 'agreement among raft nodes before linearized reading' (duration: 1.13847014s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:32:40.333958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:32:39.195329Z","time spent":"1.138623783s","remote":"127.0.0.1:52682","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-16T16:32:40.333974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.112287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.65\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-16T16:32:40.334005Z","caller":"traceutil/trace.go:171","msg":"trace[324242588] range","detail":"{range_begin:/registry/masterleases/199.204.45.65; range_end:; response_count:1; response_revision:1153; }","duration":"111.182488ms","start":"2026-04-16T16:32:40.222814Z","end":"2026-04-16T16:32:40.333996Z","steps":["trace[324242588] 'agreement among raft nodes before linearized reading' (duration: 111.089937ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:32:52.615765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.087546ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T16:32:52.615927Z","caller":"traceutil/trace.go:171","msg":"trace[1718987610] linearizableReadLoop","detail":"{readStateIndex:1246; appliedIndex:1243; }","duration":"189.6029ms","start":"2026-04-16T16:32:52.426313Z","end":"2026-04-16T16:32:52.615916Z","steps":["trace[1718987610] 'read index received' (duration: 27.435412ms)","trace[1718987610] 'applied index is now lower than readState.Index' (duration: 162.166698ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:32:52.616037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.736263ms","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-16T16:32:52.616056Z","caller":"traceutil/trace.go:171","msg":"trace[137581413] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1196; }","duration":"189.781015ms","start":"2026-04-16T16:32:52.426269Z","end":"2026-04-16T16:32:52.61605Z","steps":["trace[137581413] 'agreement among raft nodes before linearized reading' (duration: 189.682523ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:32:52.616138Z","caller":"traceutil/trace.go:171","msg":"trace[2115546925] transaction","detail":"{read_only:false; response_revision:1195; number_of_response:1; }","duration":"200.046318ms","start":"2026-04-16T16:32:52.416047Z","end":"2026-04-16T16:32:52.616093Z","steps":["trace[2115546925] 'process raft request' (duration: 199.810613ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:32:52.616232Z","caller":"traceutil/trace.go:171","msg":"trace[992132636] transaction","detail":"{read_only:false; response_revision:1196; number_of_response:1; }","duration":"200.002416ms","start":"2026-04-16T16:32:52.416216Z","end":"2026-04-16T16:32:52.616218Z","steps":["trace[992132636] 'process raft request' (duration: 199.67914ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:32:52.616232Z","caller":"traceutil/trace.go:171","msg":"trace[1399139561] transaction","detail":"{read_only:false; response_revision:1194; number_of_response:1; }","duration":"201.181159ms","start":"2026-04-16T16:32:52.415007Z","end":"2026-04-16T16:32:52.616188Z","steps":["trace[1399139561] 'process raft request' (duration: 38.609444ms)","trace[1399139561] 'compare' (duration: 162.012085ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:32:55.147244Z","caller":"traceutil/trace.go:171","msg":"trace[2069810793] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"519.379444ms","start":"2026-04-16T16:32:54.627836Z","end":"2026-04-16T16:32:55.147215Z","steps":["trace[2069810793] 'process raft request' (duration: 493.207236ms)","trace[2069810793] 'compare' (duration: 25.831472ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:32:55.147438Z","caller":"traceutil/trace.go:171","msg":"trace[1837944685] linearizableReadLoop","detail":"{readStateIndex:1260; appliedIndex:1258; }","duration":"518.673959ms","start":"2026-04-16T16:32:54.62874Z","end":"2026-04-16T16:32:55.147414Z","steps":["trace[1837944685] 'read index received' (duration: 44.226974ms)","trace[1837944685] 'applied index is now lower than readState.Index' (duration: 474.444825ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:32:55.14747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:32:54.627813Z","time spent":"519.568427ms","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:32:55.14757Z","caller":"traceutil/trace.go:171","msg":"trace[1278057578] transaction","detail":"{read_only:false; response_revision:1211; number_of_response:1; }","duration":"517.286691ms","start":"2026-04-16T16:32:54.630257Z","end":"2026-04-16T16:32:55.147544Z","steps":["trace[1278057578] 'process raft request' (duration: 516.853983ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:32:55.147608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.849052ms","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-16T16:32:55.147633Z","caller":"traceutil/trace.go:171","msg":"trace[945757752] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1211; }","duration":"518.917715ms","start":"2026-04-16T16:32:54.628709Z","end":"2026-04-16T16:32:55.147627Z","steps":["trace[945757752] 'agreement among raft nodes before linearized reading' (duration: 518.782252ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:32:55.147653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:32:54.628695Z","time spent":"518.953375ms","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-16T16:32:55.147711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:32:54.630239Z","time spent":"517.394534ms","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:32:55.78975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.832241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T16:32:55.789751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.481738ms","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-16T16:32:55.789852Z","caller":"traceutil/trace.go:171","msg":"trace[1641318382] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1212; }","duration":"527.937893ms","start":"2026-04-16T16:32:55.261889Z","end":"2026-04-16T16:32:55.789826Z","steps":["trace[1641318382] 'range keys from in-memory index tree' (duration: 527.77195ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:32:55.78986Z","caller":"traceutil/trace.go:171","msg":"trace[1708446567] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1212; }","duration":"100.60882ms","start":"2026-04-16T16:32:55.689237Z","end":"2026-04-16T16:32:55.789846Z","steps":["trace[1708446567] 'range keys from in-memory index tree' (duration: 100.460128ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:32:55.789908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:32:55.261851Z","time spent":"528.047625ms","remote":"127.0.0.1:52396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T16:32:57.034977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.060355585s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-16T16:32:57.035546Z","caller":"traceutil/trace.go:171","msg":"trace[923597087] linearizableReadLoop","detail":"{readStateIndex:1263; appliedIndex:1262; }","duration":"1.412239676s","start":"2026-04-16T16:32:55.623276Z","end":"2026-04-16T16:32:57.035516Z","steps":["trace[923597087] 'read index received' (duration: 351.423552ms)","trace[923597087] 'applied index is now lower than readState.Index' (duration: 1.060813134s)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:32:57.035657Z","caller":"traceutil/trace.go:171","msg":"trace[2100556539] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"586.184606ms","start":"2026-04-16T16:32:56.449444Z","end":"2026-04-16T16:32:57.035629Z","steps":["trace[2100556539] 'process raft request' (duration: 585.712616ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:32:57.035857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.412557832s","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-16T16:32:57.03598Z","caller":"traceutil/trace.go:171","msg":"trace[763137184] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1213; }","duration":"1.412707546s","start":"2026-04-16T16:32:55.623225Z","end":"2026-04-16T16:32:57.035932Z","steps":["trace[763137184] 'agreement among raft nodes before linearized reading' (duration: 1.4124246s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:32:57.036033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:32:55.623205Z","time spent":"1.412815497s","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-16T16:32:57.035863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:32:56.449418Z","time spent":"586.287548ms","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:32:57.055796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.264799869s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T16:32:57.055825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.32411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:32:57.055875Z","caller":"traceutil/trace.go:171","msg":"trace[99825932] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1214; }","duration":"1.264912832s","start":"2026-04-16T16:32:55.790944Z","end":"2026-04-16T16:32:57.055857Z","steps":["trace[99825932] 'agreement among raft nodes before linearized reading' (duration: 1.264783239s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:32:57.05592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:32:55.790925Z","time spent":"1.264986303s","remote":"127.0.0.1:52408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-16T16:32:57.055916Z","caller":"traceutil/trace.go:171","msg":"trace[169025146] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"354.602324ms","start":"2026-04-16T16:32:56.701294Z","end":"2026-04-16T16:32:57.055896Z","steps":["trace[169025146] 'process raft request' (duration: 354.260998ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:32:57.055905Z","caller":"traceutil/trace.go:171","msg":"trace[67765369] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1214; }","duration":"257.436243ms","start":"2026-04-16T16:32:56.798435Z","end":"2026-04-16T16:32:57.055871Z","steps":["trace[67765369] 'agreement among raft nodes before linearized reading' (duration: 257.314211ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:32:57.056045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:32:56.70126Z","time spent":"354.712887ms","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:32:57.056225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.112117299s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:32:57.056271Z","caller":"traceutil/trace.go:171","msg":"trace[1912864070] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1214; }","duration":"1.11216146s","start":"2026-04-16T16:32:55.944096Z","end":"2026-04-16T16:32:57.056257Z","steps":["trace[1912864070] 'agreement among raft nodes before linearized reading' (duration: 1.112091219s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:32:57.056301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:32:55.944042Z","time spent":"1.112251252s","remote":"127.0.0.1:52886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":29,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-04-16T16:32:57.056913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.850395ms","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-16T16:32:57.057036Z","caller":"traceutil/trace.go:171","msg":"trace[857364355] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1214; }","duration":"380.012899ms","start":"2026-04-16T16:32:56.676998Z","end":"2026-04-16T16:32:57.057011Z","steps":["trace[857364355] 'agreement among raft nodes before linearized reading' (duration: 378.774344ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:32:57.057262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:32:56.676975Z","time spent":"380.266713ms","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-16T16:32:57.459316Z","caller":"traceutil/trace.go:171","msg":"trace[723540837] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"418.798634ms","start":"2026-04-16T16:32:57.040495Z","end":"2026-04-16T16:32:57.459294Z","steps":["trace[723540837] 'process raft request' (duration: 329.784744ms)","trace[723540837] 'compare' (duration: 88.873297ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:32:57.459434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:32:57.04047Z","time spent":"418.902606ms","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:32:57.522585Z","caller":"traceutil/trace.go:171","msg":"trace[1066458538] linearizableReadLoop","detail":"{readStateIndex:1267; appliedIndex:1265; }","duration":"459.075181ms","start":"2026-04-16T16:32:57.063491Z","end":"2026-04-16T16:32:57.522567Z","steps":["trace[1066458538] 'read index received' (duration: 306.729988ms)","trace[1066458538] 'applied index is now lower than readState.Index' (duration: 152.344493ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:32:57.522858Z","caller":"traceutil/trace.go:171","msg":"trace[531607256] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"460.56963ms","start":"2026-04-16T16:32:57.062262Z","end":"2026-04-16T16:32:57.522832Z","steps":["trace[531607256] 'process raft request' (duration: 460.209872ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:32:57.522915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.403848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:32:57.522971Z","caller":"traceutil/trace.go:171","msg":"trace[2133815423] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1216; }","duration":"459.493311ms","start":"2026-04-16T16:32:57.063463Z","end":"2026-04-16T16:32:57.522957Z","steps":["trace[2133815423] 'agreement among raft nodes before linearized reading' (duration: 459.386639ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:32:57.522981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:32:57.062244Z","time spent":"460.675343ms","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:32:57.523016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:32:57.063455Z","time spent":"459.551622ms","remote":"127.0.0.1:52396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T16:32:57.523504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.861186ms","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-16T16:32:57.523765Z","caller":"traceutil/trace.go:171","msg":"trace[881076107] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1216; }","duration":"373.145811ms","start":"2026-04-16T16:32:57.150605Z","end":"2026-04-16T16:32:57.523751Z","steps":["trace[881076107] 'agreement among raft nodes before linearized reading' (duration: 372.786724ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:32:57.523799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:32:57.150591Z","time spent":"373.199742ms","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-16T16:32:57.523628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.652801ms","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-16T16:32:57.523934Z","caller":"traceutil/trace.go:171","msg":"trace[416440460] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1216; }","duration":"372.968578ms","start":"2026-04-16T16:32:57.150953Z","end":"2026-04-16T16:32:57.523922Z","steps":["trace[416440460] 'agreement among raft nodes before linearized reading' (duration: 372.613231ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:32:57.523967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:32:57.150946Z","time spent":"373.012289ms","remote":"127.0.0.1:52682","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-16T16:32:57.523638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.764706ms","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-16T16:32:57.524124Z","caller":"traceutil/trace.go:171","msg":"trace[980675994] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1216; }","duration":"317.298376ms","start":"2026-04-16T16:32:57.206816Z","end":"2026-04-16T16:32:57.524114Z","steps":["trace[980675994] 'agreement among raft nodes before linearized reading' (duration: 316.607682ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:32:57.523647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.354945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-16T16:32:57.524529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:32:57.206802Z","time spent":"317.710764ms","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-16T16:32:57.524579Z","caller":"traceutil/trace.go:171","msg":"trace[551745690] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1216; }","duration":"235.338875ms","start":"2026-04-16T16:32:57.289212Z","end":"2026-04-16T16:32:57.524551Z","steps":["trace[551745690] 'agreement among raft nodes before linearized reading' (duration: 234.333205ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:32:57.80335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.654882ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T16:32:57.803522Z","caller":"traceutil/trace.go:171","msg":"trace[179387099] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"271.329158ms","start":"2026-04-16T16:32:57.532176Z","end":"2026-04-16T16:32:57.803505Z","steps":["trace[179387099] 'process raft request' (duration: 271.270597ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:32:57.803585Z","caller":"traceutil/trace.go:171","msg":"trace[1184124070] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"273.424849ms","start":"2026-04-16T16:32:57.530099Z","end":"2026-04-16T16:32:57.803523Z","steps":["trace[1184124070] 'process raft request' (duration: 84.515702ms)","trace[1184124070] 'compare' (duration: 188.53199ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:32:59.761883Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620625909160029589,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-16T16:32:59.825697Z","caller":"traceutil/trace.go:171","msg":"trace[493440727] linearizableReadLoop","detail":"{readStateIndex:1272; appliedIndex:1271; }","duration":"564.507453ms","start":"2026-04-16T16:32:59.261173Z","end":"2026-04-16T16:32:59.825681Z","steps":["trace[493440727] 'read index received' (duration: 564.36285ms)","trace[493440727] 'applied index is now lower than readState.Index' (duration: 143.793µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:32:59.825803Z","caller":"traceutil/trace.go:171","msg":"trace[1699312382] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"783.487122ms","start":"2026-04-16T16:32:59.04231Z","end":"2026-04-16T16:32:59.825797Z","steps":["trace[1699312382] 'process raft request' (duration: 783.223627ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:32:59.825887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:32:59.04228Z","time spent":"783.542174ms","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:32:59.826043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.401839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:32:59.82619Z","caller":"traceutil/trace.go:171","msg":"trace[1807147651] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1221; }","duration":"397.637765ms","start":"2026-04-16T16:32:59.42853Z","end":"2026-04-16T16:32:59.826168Z","steps":["trace[1807147651] 'agreement among raft nodes before linearized reading' (duration: 397.339668ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:32:59.826309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.593941ms","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-16T16:32:59.826333Z","caller":"traceutil/trace.go:171","msg":"trace[1951535083] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1221; }","duration":"363.638731ms","start":"2026-04-16T16:32:59.462689Z","end":"2026-04-16T16:32:59.826327Z","steps":["trace[1951535083] 'agreement among raft nodes before linearized reading' (duration: 363.57878ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:32:59.826351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:32:59.462676Z","time spent":"363.671912ms","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-16T16:32:59.826327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.141845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:32:59.826406Z","caller":"traceutil/trace.go:171","msg":"trace[1212341222] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1221; }","duration":"565.264688ms","start":"2026-04-16T16:32:59.261122Z","end":"2026-04-16T16:32:59.826387Z","steps":["trace[1212341222] 'agreement among raft nodes before linearized reading' (duration: 565.150236ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:32:59.826449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:32:59.261103Z","time spent":"565.336929ms","remote":"127.0.0.1:52408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T16:32:59.826459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:32:59.428507Z","time spent":"397.800218ms","remote":"127.0.0.1:53516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-16T16:32:59.826246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.834769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-16T16:32:59.826725Z","caller":"traceutil/trace.go:171","msg":"trace[2102928539] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1221; }","duration":"281.326479ms","start":"2026-04-16T16:32:59.545368Z","end":"2026-04-16T16:32:59.826695Z","steps":["trace[2102928539] 'agreement among raft nodes before linearized reading' (duration: 280.799009ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:32:59.82619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.579211ms","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-16T16:32:59.826839Z","caller":"traceutil/trace.go:171","msg":"trace[2861132] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1221; }","duration":"299.298464ms","start":"2026-04-16T16:32:59.527532Z","end":"2026-04-16T16:32:59.82683Z","steps":["trace[2861132] 'agreement among raft nodes before linearized reading' (duration: 298.437457ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:33:00.301862Z","caller":"traceutil/trace.go:171","msg":"trace[64633725] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"469.613348ms","start":"2026-04-16T16:32:59.832199Z","end":"2026-04-16T16:33:00.301813Z","steps":["trace[64633725] 'process raft request' (duration: 453.316585ms)","trace[64633725] 'compare' (duration: 15.855775ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:33:00.302131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:32:59.832175Z","time spent":"469.800592ms","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:33:00.720917Z","caller":"traceutil/trace.go:171","msg":"trace[1942533359] transaction","detail":"{read_only:false; response_revision:1223; number_of_response:1; }","duration":"888.465781ms","start":"2026-04-16T16:32:59.832422Z","end":"2026-04-16T16:33:00.720888Z","steps":["trace[1942533359] 'process raft request' (duration: 828.535379ms)","trace[1942533359] 'compare' (duration: 59.629456ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:33:00.721274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:32:59.83241Z","time spent":"888.575223ms","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:33:00.722158Z","caller":"traceutil/trace.go:171","msg":"trace[656276524] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"889.695535ms","start":"2026-04-16T16:32:59.832441Z","end":"2026-04-16T16:33:00.722136Z","steps":["trace[656276524] 'process raft request' (duration: 888.38833ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:33:00.722291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:32:59.832412Z","time spent":"889.814377ms","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:33:00.722511Z","caller":"traceutil/trace.go:171","msg":"trace[959412995] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"890.001611ms","start":"2026-04-16T16:32:59.8325Z","end":"2026-04-16T16:33:00.722502Z","steps":["trace[959412995] 'process raft request' (duration: 889.642744ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:33:00.722559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:32:59.832492Z","time spent":"890.044022ms","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:33:00.722962Z","caller":"traceutil/trace.go:171","msg":"trace[727640819] linearizableReadLoop","detail":"{readStateIndex:1277; appliedIndex:1272; }","duration":"499.456812ms","start":"2026-04-16T16:33:00.223496Z","end":"2026-04-16T16:33:00.722953Z","steps":["trace[727640819] 'read index received' (duration: 62.175316ms)","trace[727640819] 'applied index is now lower than readState.Index' (duration: 437.280206ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:33:00.723098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.597245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.65\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-16T16:33:00.723137Z","caller":"traceutil/trace.go:171","msg":"trace[1280447188] range","detail":"{range_begin:/registry/masterleases/199.204.45.65; range_end:; response_count:1; response_revision:1226; }","duration":"499.660207ms","start":"2026-04-16T16:33:00.223469Z","end":"2026-04-16T16:33:00.723129Z","steps":["trace[1280447188] 'agreement among raft nodes before linearized reading' (duration: 499.555025ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:33:00.723163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:33:00.223433Z","time spent":"499.721547ms","remote":"127.0.0.1:52426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.65\" "} {"level":"warn","ts":"2026-04-16T16:33:00.723293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.177526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:33:00.723321Z","caller":"traceutil/trace.go:171","msg":"trace[539275088] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1226; }","duration":"462.243987ms","start":"2026-04-16T16:33:00.261071Z","end":"2026-04-16T16:33:00.723315Z","steps":["trace[539275088] 'agreement among raft nodes before linearized reading' (duration: 462.205006ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:33:00.72334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:33:00.261057Z","time spent":"462.278678ms","remote":"127.0.0.1:52396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-16T16:33:00.722226Z","caller":"traceutil/trace.go:171","msg":"trace[178481180] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"889.721766ms","start":"2026-04-16T16:32:59.832486Z","end":"2026-04-16T16:33:00.722207Z","steps":["trace[178481180] 'process raft request' (duration: 889.558902ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:33:00.724483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:32:59.832469Z","time spent":"891.806785ms","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:33:02.763165Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620625909160029614,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T16:33:02.795594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.12683ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T16:33:02.795709Z","caller":"traceutil/trace.go:171","msg":"trace[265015871] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"1.005491351s","start":"2026-04-16T16:33:01.790198Z","end":"2026-04-16T16:33:02.795689Z","steps":["trace[265015871] 'process raft request' (duration: 291.183258ms)","trace[265015871] 'compare' (duration: 713.947876ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:33:02.795767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:33:01.790183Z","time spent":"1.005555973s","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:33:02.82374Z","caller":"traceutil/trace.go:171","msg":"trace[1091862914] linearizableReadLoop","detail":"{readStateIndex:1285; appliedIndex:1282; }","duration":"561.568048ms","start":"2026-04-16T16:33:02.262154Z","end":"2026-04-16T16:33:02.823722Z","steps":["trace[1091862914] 'read index received' (duration: 561.360833ms)","trace[1091862914] 'applied index is now lower than readState.Index' (duration: 206.325µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:33:02.823844Z","caller":"traceutil/trace.go:171","msg":"trace[626729016] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"991.38987ms","start":"2026-04-16T16:33:01.832443Z","end":"2026-04-16T16:33:02.823833Z","steps":["trace[626729016] 'process raft request' (duration: 991.202845ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:33:02.823845Z","caller":"traceutil/trace.go:171","msg":"trace[1482165403] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"967.210465ms","start":"2026-04-16T16:33:01.856612Z","end":"2026-04-16T16:33:02.823823Z","steps":["trace[1482165403] 'process raft request' (duration: 967.076081ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:33:02.823836Z","caller":"traceutil/trace.go:171","msg":"trace[322500593] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"1.032901838s","start":"2026-04-16T16:33:01.790902Z","end":"2026-04-16T16:33:02.823804Z","steps":["trace[322500593] 'process raft request' (duration: 1.032630042s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:33:02.823943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:33:01.832416Z","time spent":"991.45798ms","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:33:02.823942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:33:01.856593Z","time spent":"967.296727ms","remote":"127.0.0.1:52482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T16:33:02.824031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:33:01.790892Z","time spent":"1.033082781s","remote":"127.0.0.1:52482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":773,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T16:33:02.824104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.842975ms","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-16T16:33:02.824151Z","caller":"traceutil/trace.go:171","msg":"trace[1025537115] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1233; }","duration":"517.983018ms","start":"2026-04-16T16:33:02.306155Z","end":"2026-04-16T16:33:02.824138Z","steps":["trace[1025537115] 'agreement among raft nodes before linearized reading' (duration: 517.814815ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:33:02.824185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:33:02.306135Z","time spent":"518.042819ms","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-16T16:33:02.824348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.192209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:33:02.824371Z","caller":"traceutil/trace.go:171","msg":"trace[993183481] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1233; }","duration":"562.239309ms","start":"2026-04-16T16:33:02.262123Z","end":"2026-04-16T16:33:02.824362Z","steps":["trace[993183481] 'agreement among raft nodes before linearized reading' (duration: 562.209159ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:33:02.824387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:33:02.262108Z","time spent":"562.27567ms","remote":"127.0.0.1:52408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-16T16:33:03.01484Z","caller":"traceutil/trace.go:171","msg":"trace[1974310088] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"186.795821ms","start":"2026-04-16T16:33:02.828011Z","end":"2026-04-16T16:33:03.014807Z","steps":["trace[1974310088] 'process raft request' (duration: 152.781107ms)","trace[1974310088] 'compare' (duration: 33.859451ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:33:03.064189Z","caller":"traceutil/trace.go:171","msg":"trace[1764872847] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"234.896676ms","start":"2026-04-16T16:33:02.829268Z","end":"2026-04-16T16:33:03.064165Z","steps":["trace[1764872847] 'process raft request' (duration: 234.829844ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:33:03.064466Z","caller":"traceutil/trace.go:171","msg":"trace[1140628418] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"235.69322ms","start":"2026-04-16T16:33:02.82873Z","end":"2026-04-16T16:33:03.064423Z","steps":["trace[1140628418] 'process raft request' (duration: 235.322284ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:33:03.064553Z","caller":"traceutil/trace.go:171","msg":"trace[361538654] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"234.683263ms","start":"2026-04-16T16:33:02.829853Z","end":"2026-04-16T16:33:03.064536Z","steps":["trace[361538654] 'process raft request' (duration: 234.281794ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:33:03.064664Z","caller":"traceutil/trace.go:171","msg":"trace[417571743] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"234.373537ms","start":"2026-04-16T16:33:02.83028Z","end":"2026-04-16T16:33:03.064654Z","steps":["trace[417571743] 'process raft request' (duration: 234.187662ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:33:03.064924Z","caller":"traceutil/trace.go:171","msg":"trace[112800771] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"236.408685ms","start":"2026-04-16T16:33:02.828493Z","end":"2026-04-16T16:33:03.064358Z","steps":["trace[112800771] 'process raft request' (duration: 235.386535ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:33:03.541423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.683971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:33:03.541537Z","caller":"traceutil/trace.go:171","msg":"trace[2007848094] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1239; }","duration":"312.852054ms","start":"2026-04-16T16:33:03.228668Z","end":"2026-04-16T16:33:03.54152Z","steps":["trace[2007848094] 'count revisions from in-memory index tree' (duration: 312.600299ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:33:03.541608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:33:03.228646Z","time spent":"312.939326ms","remote":"127.0.0.1:34016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-04-16T16:33:03.541856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.907179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:33:03.541957Z","caller":"traceutil/trace.go:171","msg":"trace[1327683783] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1239; }","duration":"281.063842ms","start":"2026-04-16T16:33:03.260867Z","end":"2026-04-16T16:33:03.54193Z","steps":["trace[1327683783] 'range keys from in-memory index tree' (duration: 280.801408ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:33:03.541958Z","caller":"traceutil/trace.go:171","msg":"trace[597220477] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"186.862112ms","start":"2026-04-16T16:33:03.35507Z","end":"2026-04-16T16:33:03.541932Z","steps":["trace[597220477] 'process raft request' (duration: 87.769668ms)","trace[597220477] 'compare' (duration: 98.805379ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:33:11.600543Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620625909160029747,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T16:33:12.100832Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620625909160029747,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T16:33:12.440441Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.532127335s","expected-duration":"1s"} {"level":"info","ts":"2026-04-16T16:33:12.440839Z","caller":"traceutil/trace.go:171","msg":"trace[2050591997] linearizableReadLoop","detail":"{readStateIndex:1361; appliedIndex:1360; }","duration":"1.340316908s","start":"2026-04-16T16:33:11.1005Z","end":"2026-04-16T16:33:12.440817Z","steps":["trace[2050591997] 'read index received' (duration: 1.340074233s)","trace[2050591997] 'applied index is now lower than readState.Index' (duration: 241.995µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:33:12.440915Z","caller":"traceutil/trace.go:171","msg":"trace[568068050] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"1.532669135s","start":"2026-04-16T16:33:10.908197Z","end":"2026-04-16T16:33:12.440866Z","steps":["trace[568068050] 'process raft request' (duration: 1.532485642s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:33:12.441054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.340540151s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-16T16:33:12.441093Z","caller":"traceutil/trace.go:171","msg":"trace[1913104290] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1307; }","duration":"1.340633673s","start":"2026-04-16T16:33:11.100448Z","end":"2026-04-16T16:33:12.441082Z","steps":["trace[1913104290] 'agreement among raft nodes before linearized reading' (duration: 1.340498411s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:33:12.441111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:33:10.908177Z","time spent":"1.532839798s","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:33:12.441175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.026185568s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:33:12.441199Z","caller":"traceutil/trace.go:171","msg":"trace[547689934] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1307; }","duration":"1.026242838s","start":"2026-04-16T16:33:11.414948Z","end":"2026-04-16T16:33:12.441191Z","steps":["trace[547689934] 'agreement among raft nodes before linearized reading' (duration: 1.026206628s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:33:12.441203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.266100531s","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":"warn","ts":"2026-04-16T16:33:12.441224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:33:11.414925Z","time spent":"1.026294491s","remote":"127.0.0.1:52396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T16:33:12.441194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.340295137s","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-16T16:33:12.441258Z","caller":"traceutil/trace.go:171","msg":"trace[230460541] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1307; }","duration":"1.266190293s","start":"2026-04-16T16:33:11.175046Z","end":"2026-04-16T16:33:12.441236Z","steps":["trace[230460541] 'agreement among raft nodes before linearized reading' (duration: 1.26606175s)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:33:12.441276Z","caller":"traceutil/trace.go:171","msg":"trace[28286467] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1307; }","duration":"1.340434291s","start":"2026-04-16T16:33:11.100823Z","end":"2026-04-16T16:33:12.441257Z","steps":["trace[28286467] 'agreement among raft nodes before linearized reading' (duration: 1.340194425s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:33:12.441306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:33:11.175035Z","time spent":"1.266260965s","remote":"127.0.0.1:52782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":55,"response size":31,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-04-16T16:33:12.441329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:33:11.100803Z","time spent":"1.340514613s","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-16T16:33:12.441127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:33:11.100428Z","time spent":"1.340691095s","remote":"127.0.0.1:52682","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-16T16:33:12.441434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.259016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:33:12.441472Z","caller":"traceutil/trace.go:171","msg":"trace[681072314] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1307; }","duration":"180.331398ms","start":"2026-04-16T16:33:12.261136Z","end":"2026-04-16T16:33:12.441467Z","steps":["trace[681072314] 'agreement among raft nodes before linearized reading' (duration: 180.284697ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:33:12.441557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.280363356s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-04-16T16:33:12.441598Z","caller":"traceutil/trace.go:171","msg":"trace[1607856726] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1307; }","duration":"1.280452068s","start":"2026-04-16T16:33:11.161134Z","end":"2026-04-16T16:33:12.441586Z","steps":["trace[1607856726] 'agreement among raft nodes before linearized reading' (duration: 1.280354796s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:33:12.441607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.227787255s","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:507"} {"level":"warn","ts":"2026-04-16T16:33:12.441627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:33:11.161109Z","time spent":"1.280510498s","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-04-16T16:33:12.441649Z","caller":"traceutil/trace.go:171","msg":"trace[1980522700] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1307; }","duration":"1.227848786s","start":"2026-04-16T16:33:11.213785Z","end":"2026-04-16T16:33:12.441634Z","steps":["trace[1980522700] 'agreement among raft nodes before linearized reading' (duration: 1.227656852s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:33:12.441681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:33:11.213774Z","time spent":"1.227899956s","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-16T16:33:12.441752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.33516093s","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-16T16:33:12.441757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.335763061s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-04-16T16:33:12.441772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.333925535s","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-16T16:33:12.441798Z","caller":"traceutil/trace.go:171","msg":"trace[1505778905] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1307; }","duration":"1.333967077s","start":"2026-04-16T16:33:11.107825Z","end":"2026-04-16T16:33:12.441792Z","steps":["trace[1505778905] 'agreement among raft nodes before linearized reading' (duration: 1.333881535s)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:33:12.441796Z","caller":"traceutil/trace.go:171","msg":"trace[207082934] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1307; }","duration":"1.335873162s","start":"2026-04-16T16:33:11.105914Z","end":"2026-04-16T16:33:12.441787Z","steps":["trace[207082934] 'agreement among raft nodes before linearized reading' (duration: 1.335773271s)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:33:12.441796Z","caller":"traceutil/trace.go:171","msg":"trace[1229263164] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1307; }","duration":"1.33522268s","start":"2026-04-16T16:33:11.106561Z","end":"2026-04-16T16:33:12.441783Z","steps":["trace[1229263164] 'agreement among raft nodes before linearized reading' (duration: 1.335084648s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:33:12.441817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:33:11.107816Z","time spent":"1.333996617s","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-16T16:33:12.441823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:33:11.105882Z","time spent":"1.335934684s","remote":"127.0.0.1:52682","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-16T16:33:12.441829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:33:11.106549Z","time spent":"1.335273111s","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-16T16:33:12.858581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.472732ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T16:33:12.858849Z","caller":"traceutil/trace.go:171","msg":"trace[117667977] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"412.523032ms","start":"2026-04-16T16:33:12.446164Z","end":"2026-04-16T16:33:12.858687Z","steps":["trace[117667977] 'process raft request' (duration: 193.855607ms)","trace[117667977] 'compare' (duration: 218.344558ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:33:12.858935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:33:12.446142Z","time spent":"412.748656ms","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:33:12.859412Z","caller":"traceutil/trace.go:171","msg":"trace[482455232] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"411.369949ms","start":"2026-04-16T16:33:12.448013Z","end":"2026-04-16T16:33:12.859383Z","steps":["trace[482455232] 'process raft request' (duration: 411.326078ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:33:12.859455Z","caller":"traceutil/trace.go:171","msg":"trace[1157740089] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"411.931959ms","start":"2026-04-16T16:33:12.44748Z","end":"2026-04-16T16:33:12.859412Z","steps":["trace[1157740089] 'process raft request' (duration: 411.792067ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:33:12.859516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:33:12.447974Z","time spent":"411.482031ms","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:33:12.859671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:33:12.447454Z","time spent":"412.097793ms","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:33:12.859709Z","caller":"traceutil/trace.go:171","msg":"trace[2071905952] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"411.607003ms","start":"2026-04-16T16:33:12.448089Z","end":"2026-04-16T16:33:12.859696Z","steps":["trace[2071905952] 'process raft request' (duration: 411.268737ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:33:12.859752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:33:12.448081Z","time spent":"411.651424ms","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:33:12.859748Z","caller":"traceutil/trace.go:171","msg":"trace[434881680] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"411.624623ms","start":"2026-04-16T16:33:12.448109Z","end":"2026-04-16T16:33:12.859733Z","steps":["trace[434881680] 'process raft request' (duration: 411.45901ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:33:12.859802Z","caller":"traceutil/trace.go:171","msg":"trace[762859998] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"411.630063ms","start":"2026-04-16T16:33:12.448167Z","end":"2026-04-16T16:33:12.859797Z","steps":["trace[762859998] 'process raft request' (duration: 411.507981ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:33:12.859828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:33:12.448151Z","time spent":"411.662045ms","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:33:12.859807Z","caller":"traceutil/trace.go:171","msg":"trace[842592134] linearizableReadLoop","detail":"{readStateIndex:1368; appliedIndex:1361; }","duration":"278.497944ms","start":"2026-04-16T16:33:12.581277Z","end":"2026-04-16T16:33:12.859775Z","steps":["trace[842592134] 'read index received' (duration: 58.737269ms)","trace[842592134] 'applied index is now lower than readState.Index' (duration: 219.759125ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:33:12.859859Z","caller":"traceutil/trace.go:171","msg":"trace[2059997296] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"411.661324ms","start":"2026-04-16T16:33:12.44818Z","end":"2026-04-16T16:33:12.859841Z","steps":["trace[2059997296] 'process raft request' (duration: 411.560512ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:33:12.859889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:33:12.448069Z","time spent":"411.712765ms","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":492,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:33:12.859924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:33:12.448156Z","time spent":"411.732737ms","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:33:12.860117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.829611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-04-16T16:33:12.860161Z","caller":"traceutil/trace.go:171","msg":"trace[457925423] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1314; }","duration":"278.942854ms","start":"2026-04-16T16:33:12.581202Z","end":"2026-04-16T16:33:12.860145Z","steps":["trace[457925423] 'agreement among raft nodes before linearized reading' (duration: 278.74804ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:33:45.631157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.11889ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T16:33:45.63126Z","caller":"traceutil/trace.go:171","msg":"trace[1524154032] transaction","detail":"{read_only:false; response_revision:1460; number_of_response:1; }","duration":"309.076912ms","start":"2026-04-16T16:33:45.322167Z","end":"2026-04-16T16:33:45.631244Z","steps":["trace[1524154032] 'process raft request' (duration: 60.810549ms)","trace[1524154032] 'compare' (duration: 248.019878ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:33:45.631378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:33:45.322143Z","time spent":"309.147292ms","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:33:45.663026Z","caller":"traceutil/trace.go:171","msg":"trace[1385322136] transaction","detail":"{read_only:false; response_revision:1461; number_of_response:1; }","duration":"340.405274ms","start":"2026-04-16T16:33:45.322592Z","end":"2026-04-16T16:33:45.662998Z","steps":["trace[1385322136] 'process raft request' (duration: 340.16084ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:33:45.663075Z","caller":"traceutil/trace.go:171","msg":"trace[410850486] transaction","detail":"{read_only:false; response_revision:1462; number_of_response:1; }","duration":"340.347533ms","start":"2026-04-16T16:33:45.322699Z","end":"2026-04-16T16:33:45.663046Z","steps":["trace[410850486] 'process raft request' (duration: 340.197361ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:33:45.663213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:33:45.322684Z","time spent":"340.456785ms","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:33:45.663185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:33:45.322582Z","time spent":"340.519476ms","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:33:45.663528Z","caller":"traceutil/trace.go:171","msg":"trace[1206180885] transaction","detail":"{read_only:false; response_revision:1463; number_of_response:1; }","duration":"340.214951ms","start":"2026-04-16T16:33:45.3233Z","end":"2026-04-16T16:33:45.663515Z","steps":["trace[1206180885] 'process raft request' (duration: 339.63491ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:33:45.663625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:33:45.323289Z","time spent":"340.268852ms","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:33:45.663946Z","caller":"traceutil/trace.go:171","msg":"trace[741966665] transaction","detail":"{read_only:false; response_revision:1464; number_of_response:1; }","duration":"340.617079ms","start":"2026-04-16T16:33:45.323313Z","end":"2026-04-16T16:33:45.66393Z","steps":["trace[741966665] 'process raft request' (duration: 340.169169ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:33:45.664025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:33:45.323304Z","time spent":"340.682631ms","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:33:55.75416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.87439ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-16T16:33:55.992937Z","caller":"traceutil/trace.go:171","msg":"trace[147629426] transaction","detail":"{read_only:false; response_revision:1506; number_of_response:1; }","duration":"234.346045ms","start":"2026-04-16T16:33:55.758566Z","end":"2026-04-16T16:33:55.992912Z","steps":["trace[147629426] 'process raft request' (duration: 234.184282ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:33:56.524449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.568522ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T16:33:56.524628Z","caller":"traceutil/trace.go:171","msg":"trace[2131567725] transaction","detail":"{read_only:false; response_revision:1508; number_of_response:1; }","duration":"765.084649ms","start":"2026-04-16T16:33:55.759529Z","end":"2026-04-16T16:33:56.524614Z","steps":["trace[2131567725] 'process raft request' (duration: 764.991207ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:33:56.52467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:33:55.759504Z","time spent":"765.147761ms","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:33:56.524794Z","caller":"traceutil/trace.go:171","msg":"trace[1769540175] transaction","detail":"{read_only:false; response_revision:1507; number_of_response:1; }","duration":"765.283603ms","start":"2026-04-16T16:33:55.759503Z","end":"2026-04-16T16:33:56.524786Z","steps":["trace[1769540175] 'process raft request' (duration: 353.315203ms)","trace[1769540175] 'compare' (duration: 411.365768ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:33:56.524777Z","caller":"traceutil/trace.go:171","msg":"trace[1967148891] linearizableReadLoop","detail":"{readStateIndex:1573; appliedIndex:1569; }","duration":"263.814633ms","start":"2026-04-16T16:33:56.260938Z","end":"2026-04-16T16:33:56.524752Z","steps":["trace[1967148891] 'read index received' (duration: 41.931µs)","trace[1967148891] 'applied index is now lower than readState.Index' (duration: 263.771892ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:33:56.524909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.949525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:33:56.524955Z","caller":"traceutil/trace.go:171","msg":"trace[484319668] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1510; }","duration":"264.038076ms","start":"2026-04-16T16:33:56.260904Z","end":"2026-04-16T16:33:56.524942Z","steps":["trace[484319668] 'agreement among raft nodes before linearized reading' (duration: 263.936904ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:33:56.525089Z","caller":"traceutil/trace.go:171","msg":"trace[192951174] transaction","detail":"{read_only:false; response_revision:1509; number_of_response:1; }","duration":"765.502307ms","start":"2026-04-16T16:33:55.759553Z","end":"2026-04-16T16:33:56.525055Z","steps":["trace[192951174] 'process raft request' (duration: 765.036379ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:33:56.52517Z","caller":"traceutil/trace.go:171","msg":"trace[23546681] transaction","detail":"{read_only:false; response_revision:1510; number_of_response:1; }","duration":"765.262713ms","start":"2026-04-16T16:33:55.759893Z","end":"2026-04-16T16:33:56.525155Z","steps":["trace[23546681] 'process raft request' (duration: 764.785794ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:33:56.525261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:33:55.759881Z","time spent":"765.322354ms","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:33:56.525257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:33:55.759544Z","time spent":"765.629589ms","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:33:56.525497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:33:55.759493Z","time spent":"765.983746ms","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:36:29.133779Z","caller":"traceutil/trace.go:171","msg":"trace[282962839] transaction","detail":"{read_only:false; response_revision:2237; number_of_response:1; }","duration":"311.49495ms","start":"2026-04-16T16:36:28.822259Z","end":"2026-04-16T16:36:29.133754Z","steps":["trace[282962839] 'process raft request' (duration: 311.329707ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:36:29.133949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:36:28.822233Z","time spent":"311.632383ms","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":516,"response count":0,"response size":40,"request content":"compare: success:> failure: >"}