{"level":"warn","ts":"2026-04-19T11:01:10.712027Z","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-19T11:01:10.712161Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.157:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.157:2380","--initial-cluster=instance=https://199.19.213.157:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.157:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.157: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-19T11:01:10.712618Z","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-19T11:01:10.712644Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.157:2380"]} {"level":"info","ts":"2026-04-19T11:01:10.712687Z","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-19T11:01:10.714303Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.157:2379"]} {"level":"info","ts":"2026-04-19T11:01:10.714503Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.157:2380"],"listen-peer-urls":["https://199.19.213.157:2380"],"advertise-client-urls":["https://199.19.213.157:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.157:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.157: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-19T11:01:10.719695Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.825925ms"} {"level":"info","ts":"2026-04-19T11:01:10.725879Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"10058a425931b8de","cluster-id":"2ff97ff1d0272754"} {"level":"info","ts":"2026-04-19T11:01:10.725982Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"10058a425931b8de switched to configuration voters=()"} {"level":"info","ts":"2026-04-19T11:01:10.72601Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"10058a425931b8de became follower at term 0"} {"level":"info","ts":"2026-04-19T11:01:10.726031Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 10058a425931b8de [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-19T11:01:10.726039Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"10058a425931b8de became follower at term 1"} {"level":"info","ts":"2026-04-19T11:01:10.726084Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"10058a425931b8de switched to configuration voters=(1154480897059305694)"} {"level":"warn","ts":"2026-04-19T11:01:10.732611Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-19T11:01:10.733961Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-19T11:01:10.734953Z","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-19T11:01:10.737432Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"10058a425931b8de","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-19T11:01:10.738103Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"10058a425931b8de","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-19T11:01:10.738407Z","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-19T11:01:10.738461Z","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-19T11:01:10.738472Z","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-19T11:01:10.738948Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"10058a425931b8de switched to configuration voters=(1154480897059305694)"} {"level":"info","ts":"2026-04-19T11:01:10.741874Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2ff97ff1d0272754","local-member-id":"10058a425931b8de","added-peer-id":"10058a425931b8de","added-peer-peer-urls":["https://199.19.213.157:2380"]} {"level":"info","ts":"2026-04-19T11:01:10.743863Z","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-19T11:01:10.744054Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.157:2380"} {"level":"info","ts":"2026-04-19T11:01:10.744083Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"10058a425931b8de","initial-advertise-peer-urls":["https://199.19.213.157:2380"],"listen-peer-urls":["https://199.19.213.157:2380"],"advertise-client-urls":["https://199.19.213.157:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.157:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-19T11:01:10.744117Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.157:2380"} {"level":"info","ts":"2026-04-19T11:01:10.744125Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-19T11:01:11.226952Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"10058a425931b8de is starting a new election at term 1"} {"level":"info","ts":"2026-04-19T11:01:11.227023Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"10058a425931b8de became pre-candidate at term 1"} {"level":"info","ts":"2026-04-19T11:01:11.227056Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"10058a425931b8de received MsgPreVoteResp from 10058a425931b8de at term 1"} {"level":"info","ts":"2026-04-19T11:01:11.227077Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"10058a425931b8de became candidate at term 2"} {"level":"info","ts":"2026-04-19T11:01:11.227084Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"10058a425931b8de received MsgVoteResp from 10058a425931b8de at term 2"} {"level":"info","ts":"2026-04-19T11:01:11.227096Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"10058a425931b8de became leader at term 2"} {"level":"info","ts":"2026-04-19T11:01:11.227109Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 10058a425931b8de elected leader 10058a425931b8de at term 2"} {"level":"info","ts":"2026-04-19T11:01:11.297617Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"10058a425931b8de","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.157:2379]}","request-path":"/0/members/10058a425931b8de/attributes","cluster-id":"2ff97ff1d0272754","publish-timeout":"7s"} {"level":"info","ts":"2026-04-19T11:01:11.297751Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-19T11:01:11.297813Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-19T11:01:11.297842Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-19T11:01:11.299916Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-19T11:01:11.299947Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-19T11:01:11.300144Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-19T11:01:11.301529Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2ff97ff1d0272754","local-member-id":"10058a425931b8de","cluster-version":"3.5"} {"level":"info","ts":"2026-04-19T11:01:11.301631Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.157:2379"} {"level":"info","ts":"2026-04-19T11:01:11.301722Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-19T11:01:11.301788Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-19T11:01:27.955031Z","caller":"traceutil/trace.go:171","msg":"trace[1667471847] linearizableReadLoop","detail":"{readStateIndex:435; appliedIndex:434; }","duration":"209.461547ms","start":"2026-04-19T11:01:27.745547Z","end":"2026-04-19T11:01:27.955008Z","steps":["trace[1667471847] 'read index received' (duration: 15.799257ms)","trace[1667471847] 'applied index is now lower than readState.Index' (duration: 193.66141ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:01:27.955277Z","caller":"traceutil/trace.go:171","msg":"trace[681307864] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"210.711967ms","start":"2026-04-19T11:01:27.744556Z","end":"2026-04-19T11:01:27.955268Z","steps":["trace[681307864] 'process raft request' (duration: 120.572952ms)","trace[681307864] 'compare' (duration: 89.584248ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T11:01:27.955444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.340374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4195"} {"level":"info","ts":"2026-04-19T11:01:27.955517Z","caller":"traceutil/trace.go:171","msg":"trace[485895996] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:422; }","duration":"206.467421ms","start":"2026-04-19T11:01:27.749037Z","end":"2026-04-19T11:01:27.955504Z","steps":["trace[485895996] 'agreement among raft nodes before linearized reading' (duration: 206.313763ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:01:27.955547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.987291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-67659f764b\" ","response":"range_response_count:1 size:3810"} {"level":"info","ts":"2026-04-19T11:01:27.955575Z","caller":"traceutil/trace.go:171","msg":"trace[33942087] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-67659f764b; range_end:; response_count:1; response_revision:422; }","duration":"210.067466ms","start":"2026-04-19T11:01:27.745499Z","end":"2026-04-19T11:01:27.955567Z","steps":["trace[33942087] 'agreement among raft nodes before linearized reading' (duration: 209.987512ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:01:28.383613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.756833ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-19T11:01:28.383933Z","caller":"traceutil/trace.go:171","msg":"trace[1404565415] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"368.994213ms","start":"2026-04-19T11:01:28.014923Z","end":"2026-04-19T11:01:28.383917Z","steps":["trace[1404565415] 'process raft request' (duration: 368.94873ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:01:28.384039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:01:28.014914Z","time spent":"369.095227ms","remote":"127.0.0.1:60604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3563,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-19T11:01:28.384186Z","caller":"traceutil/trace.go:171","msg":"trace[408675256] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"372.843786ms","start":"2026-04-19T11:01:28.011297Z","end":"2026-04-19T11:01:28.38414Z","steps":["trace[408675256] 'process raft request' (duration: 372.492449ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:01:28.384237Z","caller":"traceutil/trace.go:171","msg":"trace[178281041] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"373.117199ms","start":"2026-04-19T11:01:28.011043Z","end":"2026-04-19T11:01:28.38416Z","steps":["trace[178281041] 'process raft request' (duration: 175.301015ms)","trace[178281041] 'compare' (duration: 196.399407ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T11:01:28.384532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:01:28.011287Z","time spent":"372.978953ms","remote":"127.0.0.1:60840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4157,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-19T11:01:28.384522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:01:28.011021Z","time spent":"373.446895ms","remote":"127.0.0.1:60502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":687,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-19T11:01:28.385423Z","caller":"traceutil/trace.go:171","msg":"trace[1761551316] linearizableReadLoop","detail":"{readStateIndex:446; appliedIndex:442; }","duration":"297.083654ms","start":"2026-04-19T11:01:28.088311Z","end":"2026-04-19T11:01:28.385395Z","steps":["trace[1761551316] 'read index received' (duration: 98.044891ms)","trace[1761551316] 'applied index is now lower than readState.Index' (duration: 199.037673ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T11:01:28.385678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.369798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6965"} {"level":"info","ts":"2026-04-19T11:01:28.385719Z","caller":"traceutil/trace.go:171","msg":"trace[973349385] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:431; }","duration":"297.438471ms","start":"2026-04-19T11:01:28.08827Z","end":"2026-04-19T11:01:28.385708Z","steps":["trace[973349385] 'agreement among raft nodes before linearized reading' (duration: 297.346186ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:01:28.385843Z","caller":"traceutil/trace.go:171","msg":"trace[639971080] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"334.007894ms","start":"2026-04-19T11:01:28.051827Z","end":"2026-04-19T11:01:28.385835Z","steps":["trace[639971080] 'process raft request' (duration: 332.68088ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:01:28.38589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:01:28.051806Z","time spent":"334.057756ms","remote":"127.0.0.1:60862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3804,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-19T11:01:35.497922Z","caller":"traceutil/trace.go:171","msg":"trace[2058350245] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"489.02639ms","start":"2026-04-19T11:01:35.00887Z","end":"2026-04-19T11:01:35.497896Z","steps":["trace[2058350245] 'process raft request' (duration: 488.884774ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:01:35.498086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:01:35.008845Z","time spent":"489.164766ms","remote":"127.0.0.1:60704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-19T11:01:41.349826Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13321258081528302159,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-19T11:01:41.625736Z","caller":"traceutil/trace.go:171","msg":"trace[810692531] linearizableReadLoop","detail":"{readStateIndex:525; appliedIndex:524; }","duration":"776.727889ms","start":"2026-04-19T11:01:40.848983Z","end":"2026-04-19T11:01:41.625711Z","steps":["trace[810692531] 'read index received' (duration: 776.582333ms)","trace[810692531] 'applied index is now lower than readState.Index' (duration: 144.426µs)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:01:41.625926Z","caller":"traceutil/trace.go:171","msg":"trace[1854877301] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"936.466975ms","start":"2026-04-19T11:01:40.689438Z","end":"2026-04-19T11:01:41.625905Z","steps":["trace[1854877301] 'process raft request' (duration: 936.134991ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:01:41.626091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:01:40.689419Z","time spent":"936.556929ms","remote":"127.0.0.1:60704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-19T11:01:41.626108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.271463ms","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-19T11:01:41.626178Z","caller":"traceutil/trace.go:171","msg":"trace[469217441] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:507; }","duration":"655.416679ms","start":"2026-04-19T11:01:40.970742Z","end":"2026-04-19T11:01:41.626159Z","steps":["trace[469217441] 'agreement among raft nodes before linearized reading' (duration: 655.285434ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:01:41.626216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:01:40.970719Z","time spent":"655.488712ms","remote":"127.0.0.1:60704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-19T11:01:41.626325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.624194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T11:01:41.626349Z","caller":"traceutil/trace.go:171","msg":"trace[826140784] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:507; }","duration":"272.678926ms","start":"2026-04-19T11:01:41.353662Z","end":"2026-04-19T11:01:41.626341Z","steps":["trace[826140784] 'agreement among raft nodes before linearized reading' (duration: 272.646245ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:01:41.626606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"777.617825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T11:01:41.626694Z","caller":"traceutil/trace.go:171","msg":"trace[2265180] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:507; }","duration":"777.73766ms","start":"2026-04-19T11:01:40.848942Z","end":"2026-04-19T11:01:41.626679Z","steps":["trace[2265180] 'agreement among raft nodes before linearized reading' (duration: 777.624756ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:01:41.626728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:01:40.848925Z","time spent":"777.794682ms","remote":"127.0.0.1:60516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"warn","ts":"2026-04-19T11:01:41.627031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.179661ms","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-19T11:01:41.627083Z","caller":"traceutil/trace.go:171","msg":"trace[229193716] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:507; }","duration":"107.294216ms","start":"2026-04-19T11:01:41.519775Z","end":"2026-04-19T11:01:41.627069Z","steps":["trace[229193716] 'agreement among raft nodes before linearized reading' (duration: 106.463622ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:01:45.915279Z","caller":"traceutil/trace.go:171","msg":"trace[1379216796] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"239.530054ms","start":"2026-04-19T11:01:45.675719Z","end":"2026-04-19T11:01:45.915249Z","steps":["trace[1379216796] 'process raft request' (duration: 239.340606ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:01:46.823683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"793.59969ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-19T11:01:46.823958Z","caller":"traceutil/trace.go:171","msg":"trace[34620550] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"1.147656176s","start":"2026-04-19T11:01:45.67625Z","end":"2026-04-19T11:01:46.823906Z","steps":["trace[34620550] 'process raft request' (duration: 353.729532ms)","trace[34620550] 'compare' (duration: 793.460885ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T11:01:46.824156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:01:45.676206Z","time spent":"1.147845773s","remote":"127.0.0.1:60704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-19T11:01:46.824175Z","caller":"traceutil/trace.go:171","msg":"trace[1674971850] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"1.144514992s","start":"2026-04-19T11:01:45.679587Z","end":"2026-04-19T11:01:46.824102Z","steps":["trace[1674971850] 'process raft request' (duration: 1.144200429s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:01:46.824333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:01:45.679569Z","time spent":"1.144685809s","remote":"127.0.0.1:60704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-19T11:01:46.824727Z","caller":"traceutil/trace.go:171","msg":"trace[1288636723] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"968.610333ms","start":"2026-04-19T11:01:45.856097Z","end":"2026-04-19T11:01:46.824708Z","steps":["trace[1288636723] 'process raft request' (duration: 967.945518ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:01:46.824794Z","caller":"traceutil/trace.go:171","msg":"trace[1174938613] linearizableReadLoop","detail":"{readStateIndex:623; appliedIndex:620; }","duration":"1.025314497s","start":"2026-04-19T11:01:45.799441Z","end":"2026-04-19T11:01:46.824756Z","steps":["trace[1174938613] 'read index received' (duration: 115.706918ms)","trace[1174938613] 'applied index is now lower than readState.Index' (duration: 909.605079ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T11:01:46.82483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:01:45.856067Z","time spent":"968.707577ms","remote":"127.0.0.1:60516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036105,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-19T11:01:46.824729Z","caller":"traceutil/trace.go:171","msg":"trace[1982484358] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"248.388141ms","start":"2026-04-19T11:01:46.57632Z","end":"2026-04-19T11:01:46.824708Z","steps":["trace[1982484358] 'process raft request' (duration: 248.340039ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:01:46.824986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.025517155s","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-19T11:01:46.825017Z","caller":"traceutil/trace.go:171","msg":"trace[1425158021] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:600; }","duration":"1.025594858s","start":"2026-04-19T11:01:45.799413Z","end":"2026-04-19T11:01:46.825008Z","steps":["trace[1425158021] 'agreement among raft nodes before linearized reading' (duration: 1.025486424s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:01:46.825044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:01:45.799398Z","time spent":"1.025639269s","remote":"127.0.0.1:60704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-19T11:01:46.825156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.559433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T11:01:46.825172Z","caller":"traceutil/trace.go:171","msg":"trace[1376576556] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:600; }","duration":"471.600005ms","start":"2026-04-19T11:01:46.353567Z","end":"2026-04-19T11:01:46.825167Z","steps":["trace[1376576556] 'agreement among raft nodes before linearized reading' (duration: 471.572864ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:01:46.825187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:01:46.353551Z","time spent":"471.632347ms","remote":"127.0.0.1:60442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-19T11:01:48.109603Z","caller":"traceutil/trace.go:171","msg":"trace[465165489] linearizableReadLoop","detail":"{readStateIndex:633; appliedIndex:632; }","duration":"190.192116ms","start":"2026-04-19T11:01:47.919387Z","end":"2026-04-19T11:01:48.109579Z","steps":["trace[465165489] 'read index received' (duration: 187.868489ms)","trace[465165489] 'applied index is now lower than readState.Index' (duration: 2.322787ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T11:01:48.109806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.407435ms","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-19T11:01:48.109845Z","caller":"traceutil/trace.go:171","msg":"trace[1555793265] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:608; }","duration":"190.500429ms","start":"2026-04-19T11:01:47.919333Z","end":"2026-04-19T11:01:48.109833Z","steps":["trace[1555793265] 'agreement among raft nodes before linearized reading' (duration: 190.339721ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:01:48.110703Z","caller":"traceutil/trace.go:171","msg":"trace[583990836] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"263.931923ms","start":"2026-04-19T11:01:47.846741Z","end":"2026-04-19T11:01:48.110673Z","steps":["trace[583990836] 'process raft request' (duration: 261.545482ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:01:48.72781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.473209ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-19T11:01:48.72796Z","caller":"traceutil/trace.go:171","msg":"trace[2118907980] linearizableReadLoop","detail":"{readStateIndex:634; appliedIndex:633; }","duration":"564.270708ms","start":"2026-04-19T11:01:48.163669Z","end":"2026-04-19T11:01:48.727939Z","steps":["trace[2118907980] 'read index received' (duration: 19.676325ms)","trace[2118907980] 'applied index is now lower than readState.Index' (duration: 544.592873ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:01:48.72822Z","caller":"traceutil/trace.go:171","msg":"trace[578025277] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"612.950234ms","start":"2026-04-19T11:01:48.11525Z","end":"2026-04-19T11:01:48.7282Z","steps":["trace[578025277] 'process raft request' (duration: 67.995448ms)","trace[578025277] 'compare' (duration: 544.148696ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T11:01:48.728317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:01:48.115229Z","time spent":"613.042438ms","remote":"127.0.0.1:60704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-19T11:01:48.72836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.699365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-gx8k8\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-19T11:01:48.728449Z","caller":"traceutil/trace.go:171","msg":"trace[315400213] range","detail":"{range_begin:/registry/pods/kube-system/cilium-gx8k8; range_end:; response_count:1; response_revision:609; }","duration":"564.772268ms","start":"2026-04-19T11:01:48.163609Z","end":"2026-04-19T11:01:48.728381Z","steps":["trace[315400213] 'agreement among raft nodes before linearized reading' (duration: 564.603171ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:01:48.728484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:01:48.163586Z","time spent":"564.891782ms","remote":"127.0.0.1:60604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-gx8k8\" "} {"level":"info","ts":"2026-04-19T11:01:48.943266Z","caller":"traceutil/trace.go:171","msg":"trace[1424664407] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:634; }","duration":"215.243819ms","start":"2026-04-19T11:01:48.727998Z","end":"2026-04-19T11:01:48.943242Z","steps":["trace[1424664407] 'read index received' (duration: 154.034069ms)","trace[1424664407] 'applied index is now lower than readState.Index' (duration: 61.20879ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:01:48.943372Z","caller":"traceutil/trace.go:171","msg":"trace[1914278632] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"773.180908ms","start":"2026-04-19T11:01:48.17018Z","end":"2026-04-19T11:01:48.943361Z","steps":["trace[1914278632] 'process raft request' (duration: 711.835962ms)","trace[1914278632] 'compare' (duration: 61.070435ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T11:01:48.943452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:01:48.170122Z","time spent":"773.277081ms","remote":"127.0.0.1:60738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1044,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-19T11:01:48.943623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.525444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T11:01:48.943685Z","caller":"traceutil/trace.go:171","msg":"trace[226104141] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:610; }","duration":"590.644748ms","start":"2026-04-19T11:01:48.353024Z","end":"2026-04-19T11:01:48.943669Z","steps":["trace[226104141] 'agreement among raft nodes before linearized reading' (duration: 590.351427ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:01:48.943763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:01:48.353008Z","time spent":"590.748792ms","remote":"127.0.0.1:60440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-19T11:01:49.040144Z","caller":"traceutil/trace.go:171","msg":"trace[1321794382] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"299.24256ms","start":"2026-04-19T11:01:48.740872Z","end":"2026-04-19T11:01:49.040115Z","steps":["trace[1321794382] 'process raft request' (duration: 298.9748ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:01:49.040211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.785594ms","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-19T11:01:49.040257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.58478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-19T11:01:49.040383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.008603ms","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-19T11:01:49.040418Z","caller":"traceutil/trace.go:171","msg":"trace[1753982671] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:611; }","duration":"212.087986ms","start":"2026-04-19T11:01:48.82832Z","end":"2026-04-19T11:01:49.040408Z","steps":["trace[1753982671] 'agreement among raft nodes before linearized reading' (duration: 211.957721ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:01:49.040281Z","caller":"traceutil/trace.go:171","msg":"trace[100202629] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:611; }","duration":"211.884288ms","start":"2026-04-19T11:01:48.828378Z","end":"2026-04-19T11:01:49.040263Z","steps":["trace[100202629] 'agreement among raft nodes before linearized reading' (duration: 211.700312ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:01:49.040567Z","caller":"traceutil/trace.go:171","msg":"trace[771038036] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:611; }","duration":"205.727575ms","start":"2026-04-19T11:01:48.834603Z","end":"2026-04-19T11:01:49.04033Z","steps":["trace[771038036] 'agreement among raft nodes before linearized reading' (duration: 205.460475ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:01:49.854062Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13321258081528302342,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-19T11:01:50.411224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.728358ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-19T11:01:50.411307Z","caller":"traceutil/trace.go:171","msg":"trace[1069896150] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"1.366603893s","start":"2026-04-19T11:01:49.044691Z","end":"2026-04-19T11:01:50.411294Z","steps":["trace[1069896150] 'process raft request' (duration: 960.72564ms)","trace[1069896150] 'compare' (duration: 405.575172ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T11:01:50.411344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:01:49.044668Z","time spent":"1.366659415s","remote":"127.0.0.1:60704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-19T11:01:50.413962Z","caller":"traceutil/trace.go:171","msg":"trace[1273102709] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"1.367940092s","start":"2026-04-19T11:01:49.045962Z","end":"2026-04-19T11:01:50.413902Z","steps":["trace[1273102709] 'process raft request' (duration: 1.367596789s)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:01:50.41405Z","caller":"traceutil/trace.go:171","msg":"trace[671538694] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"1.36734609s","start":"2026-04-19T11:01:49.046657Z","end":"2026-04-19T11:01:50.414003Z","steps":["trace[671538694] 'process raft request' (duration: 1.36708839s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:01:50.414257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:01:49.046639Z","time spent":"1.367497316s","remote":"127.0.0.1:60704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-19T11:01:50.414364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:01:49.045939Z","time spent":"1.36814211s","remote":"127.0.0.1:60704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-19T11:01:50.415027Z","caller":"traceutil/trace.go:171","msg":"trace[1874254927] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"988.802902ms","start":"2026-04-19T11:01:49.426206Z","end":"2026-04-19T11:01:50.415009Z","steps":["trace[1874254927] 'process raft request' (duration: 987.847267ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:01:50.415089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:01:49.426179Z","time spent":"988.882245ms","remote":"127.0.0.1:60516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036229,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-19T11:01:50.421765Z","caller":"traceutil/trace.go:171","msg":"trace[211687467] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:636; }","duration":"1.067899452s","start":"2026-04-19T11:01:49.353801Z","end":"2026-04-19T11:01:50.4217Z","steps":["trace[211687467] 'read index received' (duration: 651.727877ms)","trace[211687467] 'applied index is now lower than readState.Index' (duration: 416.170195ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T11:01:50.422097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.385853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-19T11:01:50.422112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.068286377s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T11:01:50.422235Z","caller":"traceutil/trace.go:171","msg":"trace[855420806] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:615; }","duration":"1.068456833s","start":"2026-04-19T11:01:49.353755Z","end":"2026-04-19T11:01:50.422212Z","steps":["trace[855420806] 'agreement among raft nodes before linearized reading' (duration: 1.068218594s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:01:50.422346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:01:49.353734Z","time spent":"1.068602789s","remote":"127.0.0.1:60442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-19T11:01:50.422165Z","caller":"traceutil/trace.go:171","msg":"trace[295313649] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:615; }","duration":"232.493997ms","start":"2026-04-19T11:01:50.189659Z","end":"2026-04-19T11:01:50.422153Z","steps":["trace[295313649] 'agreement among raft nodes before linearized reading' (duration: 232.353272ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:01:50.422689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.434547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T11:01:50.422767Z","caller":"traceutil/trace.go:171","msg":"trace[1446823453] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:615; }","duration":"231.506539ms","start":"2026-04-19T11:01:50.191206Z","end":"2026-04-19T11:01:50.422712Z","steps":["trace[1446823453] 'agreement among raft nodes before linearized reading' (duration: 231.457548ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:01:50.422961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.227934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T11:01:50.423011Z","caller":"traceutil/trace.go:171","msg":"trace[783718540] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:615; }","duration":"233.275885ms","start":"2026-04-19T11:01:50.18971Z","end":"2026-04-19T11:01:50.422986Z","steps":["trace[783718540] 'agreement among raft nodes before linearized reading' (duration: 233.226634ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:02:01.325056Z","caller":"traceutil/trace.go:171","msg":"trace[1516063128] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"511.690243ms","start":"2026-04-19T11:02:00.8133Z","end":"2026-04-19T11:02:01.32499Z","steps":["trace[1516063128] 'process raft request' (duration: 511.375213ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:02:01.325395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:02:00.813262Z","time spent":"511.966792ms","remote":"127.0.0.1:60704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-19T11:02:06.082343Z","caller":"traceutil/trace.go:171","msg":"trace[382403598] linearizableReadLoop","detail":"{readStateIndex:805; appliedIndex:804; }","duration":"199.809766ms","start":"2026-04-19T11:02:05.882507Z","end":"2026-04-19T11:02:06.082317Z","steps":["trace[382403598] 'read index received' (duration: 199.678632ms)","trace[382403598] 'applied index is now lower than readState.Index' (duration: 129.174µs)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:02:06.082411Z","caller":"traceutil/trace.go:171","msg":"trace[1245380186] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"730.998837ms","start":"2026-04-19T11:02:05.351363Z","end":"2026-04-19T11:02:06.082361Z","steps":["trace[1245380186] 'process raft request' (duration: 730.680687ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:02:06.082679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:02:05.351338Z","time spent":"731.183633ms","remote":"127.0.0.1:60704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-19T11:02:06.082868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.246749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-19T11:02:06.083041Z","caller":"traceutil/trace.go:171","msg":"trace[2106255008] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:775; }","duration":"200.517828ms","start":"2026-04-19T11:02:05.882423Z","end":"2026-04-19T11:02:06.082941Z","steps":["trace[2106255008] 'agreement among raft nodes before linearized reading' (duration: 199.994352ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:02:06.652638Z","caller":"traceutil/trace.go:171","msg":"trace[1599467503] linearizableReadLoop","detail":"{readStateIndex:806; appliedIndex:805; }","duration":"300.981645ms","start":"2026-04-19T11:02:06.351623Z","end":"2026-04-19T11:02:06.652604Z","steps":["trace[1599467503] 'read index received' (duration: 300.684375ms)","trace[1599467503] 'applied index is now lower than readState.Index' (duration: 295.88µs)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:02:06.652754Z","caller":"traceutil/trace.go:171","msg":"trace[2073892691] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"564.399315ms","start":"2026-04-19T11:02:06.088322Z","end":"2026-04-19T11:02:06.652721Z","steps":["trace[2073892691] 'process raft request' (duration: 564.072485ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:02:06.652879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.258933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-19T11:02:06.652927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:02:06.088292Z","time spent":"564.514669ms","remote":"127.0.0.1:60704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-19T11:02:06.652948Z","caller":"traceutil/trace.go:171","msg":"trace[1858273022] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:776; }","duration":"301.407998ms","start":"2026-04-19T11:02:06.351521Z","end":"2026-04-19T11:02:06.652929Z","steps":["trace[1858273022] 'agreement among raft nodes before linearized reading' (duration: 301.276344ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:02:06.65296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.956576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-19T11:02:06.652981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.630601ms","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-19T11:02:06.653003Z","caller":"traceutil/trace.go:171","msg":"trace[711513636] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:776; }","duration":"130.04724ms","start":"2026-04-19T11:02:06.522942Z","end":"2026-04-19T11:02:06.65299Z","steps":["trace[711513636] 'agreement among raft nodes before linearized reading' (duration: 129.858364ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:02:06.653016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:02:06.35149Z","time spent":"301.49523ms","remote":"127.0.0.1:60442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-19T11:02:06.653047Z","caller":"traceutil/trace.go:171","msg":"trace[176521852] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:776; }","duration":"117.727665ms","start":"2026-04-19T11:02:06.535302Z","end":"2026-04-19T11:02:06.653029Z","steps":["trace[176521852] 'agreement among raft nodes before linearized reading' (duration: 117.522568ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:02:06.653475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.746396ms","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-19T11:02:06.653625Z","caller":"traceutil/trace.go:171","msg":"trace[633843972] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:776; }","duration":"118.968323ms","start":"2026-04-19T11:02:06.534617Z","end":"2026-04-19T11:02:06.653586Z","steps":["trace[633843972] 'agreement among raft nodes before linearized reading' (duration: 118.313513ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:02:07.062724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.925381ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-19T11:02:07.063096Z","caller":"traceutil/trace.go:171","msg":"trace[533128604] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"403.076422ms","start":"2026-04-19T11:02:06.65999Z","end":"2026-04-19T11:02:07.063066Z","steps":["trace[533128604] 'process raft request' (duration: 403.018361ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:02:07.06326Z","caller":"traceutil/trace.go:171","msg":"trace[1795265934] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"404.686702ms","start":"2026-04-19T11:02:06.658542Z","end":"2026-04-19T11:02:07.063229Z","steps":["trace[1795265934] 'process raft request' (duration: 204.879694ms)","trace[1795265934] 'compare' (duration: 198.733405ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T11:02:07.063403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:02:06.65851Z","time spent":"404.831077ms","remote":"127.0.0.1:60704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-19T11:02:07.063269Z","caller":"traceutil/trace.go:171","msg":"trace[273742310] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"403.901288ms","start":"2026-04-19T11:02:06.659345Z","end":"2026-04-19T11:02:07.063246Z","steps":["trace[273742310] 'process raft request' (duration: 403.585078ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:02:07.063544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:02:06.659979Z","time spent":"403.468975ms","remote":"127.0.0.1:60704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-19T11:02:07.063551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:02:06.659321Z","time spent":"404.150286ms","remote":"127.0.0.1:60704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-19T11:02:07.548803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.893559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T11:02:07.548921Z","caller":"traceutil/trace.go:171","msg":"trace[1432820665] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:779; }","duration":"193.076734ms","start":"2026-04-19T11:02:07.355818Z","end":"2026-04-19T11:02:07.548894Z","steps":["trace[1432820665] 'range keys from in-memory index tree' (duration: 192.769915ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:02:07.693788Z","caller":"traceutil/trace.go:171","msg":"trace[1269790596] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"288.389379ms","start":"2026-04-19T11:02:07.405334Z","end":"2026-04-19T11:02:07.693723Z","steps":["trace[1269790596] 'process raft request' (duration: 288.186862ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:02:08.259985Z","caller":"traceutil/trace.go:171","msg":"trace[793675781] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"167.926525ms","start":"2026-04-19T11:02:08.092031Z","end":"2026-04-19T11:02:08.259958Z","steps":["trace[793675781] 'process raft request' (duration: 167.76901ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:02:08.661429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.43088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T11:02:08.66157Z","caller":"traceutil/trace.go:171","msg":"trace[986792900] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:781; }","duration":"307.662148ms","start":"2026-04-19T11:02:08.353885Z","end":"2026-04-19T11:02:08.661548Z","steps":["trace[986792900] 'range keys from in-memory index tree' (duration: 307.264786ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:02:08.661621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:02:08.353852Z","time spent":"307.758721ms","remote":"127.0.0.1:60440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-19T11:02:12.640658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.512042975s","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-19T11:02:12.640676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.914343481s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-19T11:02:12.64075Z","caller":"traceutil/trace.go:171","msg":"trace[1931154060] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:795; }","duration":"1.512161939s","start":"2026-04-19T11:02:11.128567Z","end":"2026-04-19T11:02:12.640729Z","steps":["trace[1931154060] 'range keys from in-memory index tree' (duration: 1.511887141s)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:02:12.640761Z","caller":"traceutil/trace.go:171","msg":"trace[1538302826] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:795; }","duration":"1.914473485s","start":"2026-04-19T11:02:10.726268Z","end":"2026-04-19T11:02:12.640742Z","steps":["trace[1538302826] 'range keys from in-memory index tree' (duration: 1.914178557s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:02:12.640799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:02:11.12853Z","time spent":"1.512259211s","remote":"127.0.0.1:60704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-19T11:02:12.640788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.511972823s","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-19T11:02:12.640869Z","caller":"traceutil/trace.go:171","msg":"trace[1395499597] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:795; }","duration":"1.512085336s","start":"2026-04-19T11:02:11.128763Z","end":"2026-04-19T11:02:12.640848Z","steps":["trace[1395499597] 'range keys from in-memory index tree' (duration: 1.511807088s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:02:12.640967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:02:11.128756Z","time spent":"1.512196899s","remote":"127.0.0.1:60704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-19T11:02:12.641022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.530650416s","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-19T11:02:12.641081Z","caller":"traceutil/trace.go:171","msg":"trace[1653006660] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:795; }","duration":"1.530707878s","start":"2026-04-19T11:02:11.110358Z","end":"2026-04-19T11:02:12.641066Z","steps":["trace[1653006660] 'range keys from in-memory index tree' (duration: 1.530525112s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:02:12.641166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:02:11.110305Z","time spent":"1.530808311s","remote":"127.0.0.1:60704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-19T11:02:12.640673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.286120121s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T11:02:12.642466Z","caller":"traceutil/trace.go:171","msg":"trace[380174472] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:795; }","duration":"2.287913494s","start":"2026-04-19T11:02:10.354449Z","end":"2026-04-19T11:02:12.642363Z","steps":["trace[380174472] 'range keys from in-memory index tree' (duration: 2.286007658s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:02:12.642606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:02:10.354422Z","time spent":"2.28809738s","remote":"127.0.0.1:60442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-19T11:02:12.640802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:02:10.726248Z","time spent":"1.914545258s","remote":"127.0.0.1:60704","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-19T11:02:12.778566Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13321258081528302755,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-19T11:02:13.278239Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.93960074s","expected-duration":"1s"} {"level":"info","ts":"2026-04-19T11:02:13.278674Z","caller":"traceutil/trace.go:171","msg":"trace[743736676] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"1.940139025s","start":"2026-04-19T11:02:11.338515Z","end":"2026-04-19T11:02:13.278654Z","steps":["trace[743736676] 'process raft request' (duration: 1.940030282s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:02:13.278793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:02:11.338494Z","time spent":"1.940222338s","remote":"127.0.0.1:60662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-19T11:02:14.355266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000190517s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-19T11:02:14.355491Z","caller":"traceutil/trace.go:171","msg":"trace[1382129848] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000488565s","start":"2026-04-19T11:02:12.354971Z","end":"2026-04-19T11:02:14.355459Z","steps":["trace[1382129848] 'agreement among raft nodes before linearized reading' (duration: 2.000232528s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:02:14.355628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:02:12.354949Z","time spent":"2.000663111s","remote":"127.0.0.1:60440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/19 11:02:14 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-19T11:02:14.409133Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.111440221s","expected-duration":"1s"} {"level":"info","ts":"2026-04-19T11:02:14.41Z","caller":"traceutil/trace.go:171","msg":"trace[1555733871] linearizableReadLoop","detail":"{readStateIndex:831; appliedIndex:826; }","duration":"2.132215473s","start":"2026-04-19T11:02:12.277763Z","end":"2026-04-19T11:02:14.409979Z","steps":["trace[1555733871] 'read index received' (duration: 1.000683437s)","trace[1555733871] 'applied index is now lower than readState.Index' (duration: 1.131531366s)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:02:14.410314Z","caller":"traceutil/trace.go:171","msg":"trace[1815387312] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"1.762228097s","start":"2026-04-19T11:02:12.648077Z","end":"2026-04-19T11:02:14.410305Z","steps":["trace[1815387312] 'process raft request' (duration: 1.761855227s)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:02:14.410402Z","caller":"traceutil/trace.go:171","msg":"trace[22495258] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"1.762873356s","start":"2026-04-19T11:02:12.647441Z","end":"2026-04-19T11:02:14.410314Z","steps":["trace[22495258] 'process raft request' (duration: 1.762468805s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:02:14.410437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:02:12.648069Z","time spent":"1.762273048s","remote":"127.0.0.1:60704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-19T11:02:14.410489Z","caller":"traceutil/trace.go:171","msg":"trace[1415683981] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"1.763229196s","start":"2026-04-19T11:02:12.647209Z","end":"2026-04-19T11:02:14.410438Z","steps":["trace[1415683981] 'process raft request' (duration: 1.762557857s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:02:14.410694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:02:12.647423Z","time spent":"1.763104593s","remote":"127.0.0.1:60704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-19T11:02:14.41085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.133106568s","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-19T11:02:14.41105Z","caller":"traceutil/trace.go:171","msg":"trace[1523496194] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:800; }","duration":"2.133349915s","start":"2026-04-19T11:02:12.277678Z","end":"2026-04-19T11:02:14.411028Z","steps":["trace[1523496194] 'agreement among raft nodes before linearized reading' (duration: 2.132970704s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:02:14.41111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:02:12.277629Z","time spent":"2.133463589s","remote":"127.0.0.1:60704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-19T11:02:14.410736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:02:12.647187Z","time spent":"1.763411882s","remote":"127.0.0.1:60704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-19T11:02:14.410945Z","caller":"traceutil/trace.go:171","msg":"trace[1715368713] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"1.763490714s","start":"2026-04-19T11:02:12.647437Z","end":"2026-04-19T11:02:14.410928Z","steps":["trace[1715368713] 'process raft request' (duration: 1.762445714s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:02:14.41136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:02:12.647425Z","time spent":"1.763881745s","remote":"127.0.0.1:60704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-19T11:02:14.710836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.477162ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-19T11:02:14.711198Z","caller":"traceutil/trace.go:171","msg":"trace[779025857] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"293.012015ms","start":"2026-04-19T11:02:14.418162Z","end":"2026-04-19T11:02:14.711174Z","steps":["trace[779025857] 'process raft request' (duration: 292.929253ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:02:14.711318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:02:14.359171Z","time spent":"352.142368ms","remote":"127.0.0.1:60502","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-19T11:02:14.711448Z","caller":"traceutil/trace.go:171","msg":"trace[1908313666] linearizableReadLoop","detail":"{readStateIndex:833; appliedIndex:831; }","duration":"301.310405ms","start":"2026-04-19T11:02:14.410064Z","end":"2026-04-19T11:02:14.711374Z","steps":["trace[1908313666] 'read index received' (duration: 142.311418ms)","trace[1908313666] 'applied index is now lower than readState.Index' (duration: 158.996507ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T11:02:14.711628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.194254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T11:02:14.711728Z","caller":"traceutil/trace.go:171","msg":"trace[1861192589] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"585.9501ms","start":"2026-04-19T11:02:14.125564Z","end":"2026-04-19T11:02:14.711514Z","steps":["trace[1861192589] 'process raft request' (duration: 426.699666ms)","trace[1861192589] 'compare' (duration: 158.314977ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T11:02:14.711826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.477524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.157\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-19T11:02:14.711729Z","caller":"traceutil/trace.go:171","msg":"trace[1691955331] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:802; }","duration":"348.343698ms","start":"2026-04-19T11:02:14.363362Z","end":"2026-04-19T11:02:14.711706Z","steps":["trace[1691955331] 'agreement among raft nodes before linearized reading' (duration: 348.05566ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:02:14.711879Z","caller":"traceutil/trace.go:171","msg":"trace[579228501] range","detail":"{range_begin:/registry/masterleases/199.19.213.157; range_end:; response_count:1; response_revision:802; }","duration":"191.577937ms","start":"2026-04-19T11:02:14.520288Z","end":"2026-04-19T11:02:14.711866Z","steps":["trace[579228501] 'agreement among raft nodes before linearized reading' (duration: 191.403581ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:02:14.711671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.155807932s","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":"warn","ts":"2026-04-19T11:02:14.711914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:02:14.363336Z","time spent":"348.566106ms","remote":"127.0.0.1:60442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-19T11:02:14.711981Z","caller":"traceutil/trace.go:171","msg":"trace[1623145435] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:802; }","duration":"2.156159163s","start":"2026-04-19T11:02:12.555796Z","end":"2026-04-19T11:02:14.711955Z","steps":["trace[1623145435] 'agreement among raft nodes before linearized reading' (duration: 2.155793801s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:02:14.712094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:02:12.555773Z","time spent":"2.156293107s","remote":"127.0.0.1:60842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":27,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-04-19T11:02:14.711834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:02:14.125537Z","time spent":"586.244729ms","remote":"127.0.0.1:60704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-19T11:02:14.712023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.737577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-4wstq\" ","response":"range_response_count:1 size:4043"} {"level":"info","ts":"2026-04-19T11:02:14.712596Z","caller":"traceutil/trace.go:171","msg":"trace[1911958457] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-4wstq; range_end:; response_count:1; response_revision:802; }","duration":"295.429376ms","start":"2026-04-19T11:02:14.417149Z","end":"2026-04-19T11:02:14.712578Z","steps":["trace[1911958457] 'agreement among raft nodes before linearized reading' (duration: 294.511859ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:02:15.189632Z","caller":"traceutil/trace.go:171","msg":"trace[235624803] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"470.35908ms","start":"2026-04-19T11:02:14.719243Z","end":"2026-04-19T11:02:15.189602Z","steps":["trace[235624803] 'process raft request' (duration: 470.243506ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:02:15.189789Z","caller":"traceutil/trace.go:171","msg":"trace[1138290661] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"475.973142ms","start":"2026-04-19T11:02:14.713713Z","end":"2026-04-19T11:02:15.189687Z","steps":["trace[1138290661] 'process raft request' (duration: 475.626942ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:02:15.189882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:02:14.713687Z","time spent":"476.160916ms","remote":"127.0.0.1:60502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-19T11:02:15.189909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:02:14.719217Z","time spent":"470.498394ms","remote":"127.0.0.1:60604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4214,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-19T11:02:15.204861Z","caller":"traceutil/trace.go:171","msg":"trace[1622847407] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"451.040189ms","start":"2026-04-19T11:02:14.753794Z","end":"2026-04-19T11:02:15.204834Z","steps":["trace[1622847407] 'process raft request' (duration: 450.914406ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:02:15.205093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:02:14.753769Z","time spent":"451.243307ms","remote":"127.0.0.1:60458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"}