{"level":"warn","ts":"2026-05-25T00:46:18.665511Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-25T00:46:18.665622Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.132: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.132:2380","--initial-cluster=instance=https://199.204.45.132:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.132:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.132: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-05-25T00:46:18.665711Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-25T00:46:18.665741Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.132:2380"]} {"level":"info","ts":"2026-05-25T00:46:18.665762Z","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-05-25T00:46:18.666538Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.132:2379"]} {"level":"info","ts":"2026-05-25T00:46:18.666651Z","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.132:2380"],"listen-peer-urls":["https://199.204.45.132:2380"],"advertise-client-urls":["https://199.204.45.132:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.132:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.132: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-05-25T00:46:18.670602Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.717507ms"} {"level":"info","ts":"2026-05-25T00:46:18.67868Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"56c01ea8fdc90390","cluster-id":"c930fe821aab8609"} {"level":"info","ts":"2026-05-25T00:46:18.678804Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=()"} {"level":"info","ts":"2026-05-25T00:46:18.678833Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became follower at term 0"} {"level":"info","ts":"2026-05-25T00:46:18.678868Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 56c01ea8fdc90390 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-25T00:46:18.678887Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became follower at term 1"} {"level":"info","ts":"2026-05-25T00:46:18.678925Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=(6251029993951396752)"} {"level":"warn","ts":"2026-05-25T00:46:18.685403Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-25T00:46:18.686782Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-25T00:46:18.687982Z","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-05-25T00:46:18.690016Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"56c01ea8fdc90390","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-25T00:46:18.690112Z","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-05-25T00:46:18.690188Z","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-05-25T00:46:18.690199Z","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-05-25T00:46:18.69048Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"56c01ea8fdc90390","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-25T00:46:18.694519Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=(6251029993951396752)"} {"level":"info","ts":"2026-05-25T00:46:18.695089Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c930fe821aab8609","local-member-id":"56c01ea8fdc90390","added-peer-id":"56c01ea8fdc90390","added-peer-peer-urls":["https://199.204.45.132:2380"]} {"level":"info","ts":"2026-05-25T00:46:18.696723Z","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-05-25T00:46:18.696913Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.132:2380"} {"level":"info","ts":"2026-05-25T00:46:18.696964Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.132:2380"} {"level":"info","ts":"2026-05-25T00:46:18.697009Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"56c01ea8fdc90390","initial-advertise-peer-urls":["https://199.204.45.132:2380"],"listen-peer-urls":["https://199.204.45.132:2380"],"advertise-client-urls":["https://199.204.45.132:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.132:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-25T00:46:18.697105Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-25T00:46:19.280109Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 is starting a new election at term 1"} {"level":"info","ts":"2026-05-25T00:46:19.280153Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-25T00:46:19.280193Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 received MsgPreVoteResp from 56c01ea8fdc90390 at term 1"} {"level":"info","ts":"2026-05-25T00:46:19.280212Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became candidate at term 2"} {"level":"info","ts":"2026-05-25T00:46:19.280223Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 received MsgVoteResp from 56c01ea8fdc90390 at term 2"} {"level":"info","ts":"2026-05-25T00:46:19.280237Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became leader at term 2"} {"level":"info","ts":"2026-05-25T00:46:19.280251Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 56c01ea8fdc90390 elected leader 56c01ea8fdc90390 at term 2"} {"level":"info","ts":"2026-05-25T00:46:19.281693Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"56c01ea8fdc90390","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.132:2379]}","request-path":"/0/members/56c01ea8fdc90390/attributes","cluster-id":"c930fe821aab8609","publish-timeout":"7s"} {"level":"info","ts":"2026-05-25T00:46:19.281816Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-25T00:46:19.281893Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-25T00:46:19.281927Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-25T00:46:19.282228Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-25T00:46:19.282244Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-25T00:46:19.282715Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c930fe821aab8609","local-member-id":"56c01ea8fdc90390","cluster-version":"3.5"} {"level":"info","ts":"2026-05-25T00:46:19.282816Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-25T00:46:19.282848Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-25T00:46:19.284042Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.132:2379"} {"level":"info","ts":"2026-05-25T00:46:19.284798Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-05-25T00:46:37.231975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.043935ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T00:46:37.232133Z","caller":"traceutil/trace.go:171","msg":"trace[1333373097] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:375; }","duration":"192.224751ms","start":"2026-05-25T00:46:37.039896Z","end":"2026-05-25T00:46:37.232121Z","steps":["trace[1333373097] 'range keys from in-memory index tree' (duration: 191.964792ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:46:37.702557Z","caller":"traceutil/trace.go:171","msg":"trace[1230358037] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"462.371529ms","start":"2026-05-25T00:46:37.240158Z","end":"2026-05-25T00:46:37.702529Z","steps":["trace[1230358037] 'process raft request' (duration: 462.185654ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:46:37.703276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T00:46:37.240138Z","time spent":"462.508954ms","remote":"127.0.0.1:57230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5321,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T00:46:37.980792Z","caller":"traceutil/trace.go:171","msg":"trace[339564956] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"267.025731ms","start":"2026-05-25T00:46:37.713237Z","end":"2026-05-25T00:46:37.980262Z","steps":["trace[339564956] 'process raft request' (duration: 266.778854ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:47:03.624072Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":256879299297950779,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-25T00:47:03.897498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"881.70584ms","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-05-25T00:47:03.897612Z","caller":"traceutil/trace.go:171","msg":"trace[1142437780] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:590; }","duration":"882.038529ms","start":"2026-05-25T00:47:03.015545Z","end":"2026-05-25T00:47:03.897583Z","steps":["trace[1142437780] 'range keys from in-memory index tree' (duration: 881.579657ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:47:03.897671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T00:47:03.015524Z","time spent":"882.127791ms","remote":"127.0.0.1:57308","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-05-25T00:47:04.124612Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":256879299297950779,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-25T00:47:04.212043Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.112662937s","expected-duration":"1s"} {"level":"info","ts":"2026-05-25T00:47:04.213257Z","caller":"traceutil/trace.go:171","msg":"trace[653330068] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"1.119591476s","start":"2026-05-25T00:47:03.093634Z","end":"2026-05-25T00:47:04.213226Z","steps":["trace[653330068] 'process raft request' (duration: 1.118926347s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:47:04.21348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T00:47:03.0936Z","time spent":"1.119789372s","remote":"127.0.0.1:57130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036257,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T00:47:04.547547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.632824ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-25T00:47:04.547799Z","caller":"traceutil/trace.go:171","msg":"trace[152687674] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"272.317799ms","start":"2026-05-25T00:47:04.275461Z","end":"2026-05-25T00:47:04.547779Z","steps":["trace[152687674] 'process raft request' (duration: 272.231297ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:47:04.547995Z","caller":"traceutil/trace.go:171","msg":"trace[274227305] linearizableReadLoop","detail":"{readStateIndex:612; appliedIndex:610; }","duration":"1.424802446s","start":"2026-05-25T00:47:03.123087Z","end":"2026-05-25T00:47:04.54789Z","steps":["trace[274227305] 'read index received' (duration: 1.089337579s)","trace[274227305] 'applied index is now lower than readState.Index' (duration: 335.462337ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T00:47:04.548133Z","caller":"traceutil/trace.go:171","msg":"trace[1425591358] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"645.536469ms","start":"2026-05-25T00:47:03.902572Z","end":"2026-05-25T00:47:04.548108Z","steps":["trace[1425591358] 'process raft request' (duration: 460.982361ms)","trace[1425591358] 'compare' (duration: 183.402297ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T00:47:04.54844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.379489737s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T00:47:04.549133Z","caller":"traceutil/trace.go:171","msg":"trace[211383056] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:593; }","duration":"1.380241058s","start":"2026-05-25T00:47:03.168873Z","end":"2026-05-25T00:47:04.549114Z","steps":["trace[211383056] 'agreement among raft nodes before linearized reading' (duration: 1.379536039s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:47:04.549202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T00:47:03.168828Z","time spent":"1.380359861s","remote":"127.0.0.1:57046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-25T00:47:04.548525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.425404703s","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-05-25T00:47:04.549485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T00:47:03.902542Z","time spent":"646.463644ms","remote":"127.0.0.1:57308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T00:47:04.549506Z","caller":"traceutil/trace.go:171","msg":"trace[1619286486] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:593; }","duration":"1.426426681s","start":"2026-05-25T00:47:03.123063Z","end":"2026-05-25T00:47:04.54949Z","steps":["trace[1619286486] 'agreement among raft nodes before linearized reading' (duration: 1.425235639s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:47:04.549779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T00:47:03.123047Z","time spent":"1.426642607s","remote":"127.0.0.1:57308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-25T00:47:05.390022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"717.093946ms","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-05-25T00:47:05.390111Z","caller":"traceutil/trace.go:171","msg":"trace[2010341141] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:594; }","duration":"717.23855ms","start":"2026-05-25T00:47:04.672853Z","end":"2026-05-25T00:47:05.390092Z","steps":["trace[2010341141] 'range keys from in-memory index tree' (duration: 716.09481ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:47:05.390153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T00:47:04.672819Z","time spent":"717.325823ms","remote":"127.0.0.1:57308","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-05-25T00:47:05.390444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.319916ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-25T00:47:05.390659Z","caller":"traceutil/trace.go:171","msg":"trace[1929340892] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"652.791118ms","start":"2026-05-25T00:47:04.737837Z","end":"2026-05-25T00:47:05.390628Z","steps":["trace[1929340892] 'process raft request' (duration: 356.158513ms)","trace[1929340892] 'compare' (duration: 295.835863ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T00:47:05.390827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T00:47:04.737801Z","time spent":"652.92768ms","remote":"127.0.0.1:57308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T00:47:05.391517Z","caller":"traceutil/trace.go:171","msg":"trace[1672869449] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"399.984262ms","start":"2026-05-25T00:47:04.991514Z","end":"2026-05-25T00:47:05.391499Z","steps":["trace[1672869449] 'process raft request' (duration: 399.342415ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:47:05.391652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T00:47:04.991479Z","time spent":"400.091395ms","remote":"127.0.0.1:57130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T00:47:05.391918Z","caller":"traceutil/trace.go:171","msg":"trace[1233410947] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:614; }","duration":"532.379574ms","start":"2026-05-25T00:47:04.859526Z","end":"2026-05-25T00:47:05.391906Z","steps":["trace[1233410947] 'read index received' (duration: 234.547227ms)","trace[1233410947] 'applied index is now lower than readState.Index' (duration: 297.830947ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T00:47:05.39216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.61234ms","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-05-25T00:47:05.392202Z","caller":"traceutil/trace.go:171","msg":"trace[1737951991] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:596; }","duration":"532.704342ms","start":"2026-05-25T00:47:04.859487Z","end":"2026-05-25T00:47:05.392192Z","steps":["trace[1737951991] 'agreement among raft nodes before linearized reading' (duration: 532.462565ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:47:05.392976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T00:47:04.859467Z","time spent":"533.481564ms","remote":"127.0.0.1:57308","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-05-25T00:47:20.268977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.255656ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-25T00:47:20.269276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.764187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T00:47:20.269395Z","caller":"traceutil/trace.go:171","msg":"trace[284118064] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:759; }","duration":"100.917211ms","start":"2026-05-25T00:47:20.168451Z","end":"2026-05-25T00:47:20.269368Z","steps":["trace[284118064] 'range keys from in-memory index tree' (duration: 100.652214ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:47:20.269435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.770997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-g4wlr\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-05-25T00:47:20.269463Z","caller":"traceutil/trace.go:171","msg":"trace[1727347132] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:759; }","duration":"288.429551ms","start":"2026-05-25T00:47:19.98068Z","end":"2026-05-25T00:47:20.269109Z","steps":["trace[1727347132] 'range keys from in-memory index tree' (duration: 288.239516ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:47:20.269536Z","caller":"traceutil/trace.go:171","msg":"trace[941169996] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-g4wlr; range_end:; response_count:1; response_revision:759; }","duration":"240.932581ms","start":"2026-05-25T00:47:20.028581Z","end":"2026-05-25T00:47:20.269513Z","steps":["trace[941169996] 'range keys from in-memory index tree' (duration: 240.51004ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:48:15.993624Z","caller":"traceutil/trace.go:171","msg":"trace[469512605] linearizableReadLoop","detail":"{readStateIndex:1079; appliedIndex:1078; }","duration":"136.658601ms","start":"2026-05-25T00:48:15.856935Z","end":"2026-05-25T00:48:15.993594Z","steps":["trace[469512605] 'read index received' (duration: 136.425255ms)","trace[469512605] 'applied index is now lower than readState.Index' (duration: 231.946µs)"],"step_count":2} {"level":"info","ts":"2026-05-25T00:48:15.993754Z","caller":"traceutil/trace.go:171","msg":"trace[2031263105] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"136.99497ms","start":"2026-05-25T00:48:15.856715Z","end":"2026-05-25T00:48:15.99371Z","steps":["trace[2031263105] 'process raft request' (duration: 136.708152ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:48:15.993896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.914589ms","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-05-25T00:48:15.993981Z","caller":"traceutil/trace.go:171","msg":"trace[1866940516] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1042; }","duration":"137.044571ms","start":"2026-05-25T00:48:15.856886Z","end":"2026-05-25T00:48:15.993931Z","steps":["trace[1866940516] 'agreement among raft nodes before linearized reading' (duration: 136.836406ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:48:16.196372Z","caller":"traceutil/trace.go:171","msg":"trace[692936751] linearizableReadLoop","detail":"{readStateIndex:1080; appliedIndex:1079; }","duration":"164.154051ms","start":"2026-05-25T00:48:16.03219Z","end":"2026-05-25T00:48:16.196344Z","steps":["trace[692936751] 'read index received' (duration: 150.499014ms)","trace[692936751] 'applied index is now lower than readState.Index' (duration: 13.653917ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T00:48:16.196562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.342875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T00:48:16.196601Z","caller":"traceutil/trace.go:171","msg":"trace[1239549300] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1043; }","duration":"164.431988ms","start":"2026-05-25T00:48:16.032158Z","end":"2026-05-25T00:48:16.19659Z","steps":["trace[1239549300] 'agreement among raft nodes before linearized reading' (duration: 164.340416ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:48:16.196446Z","caller":"traceutil/trace.go:171","msg":"trace[1199624123] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"197.928712ms","start":"2026-05-25T00:48:15.998482Z","end":"2026-05-25T00:48:16.196411Z","steps":["trace[1199624123] 'process raft request' (duration: 184.245985ms)","trace[1199624123] 'compare' (duration: 13.170364ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T00:48:17.049065Z","caller":"traceutil/trace.go:171","msg":"trace[52505637] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"153.468225ms","start":"2026-05-25T00:48:16.895572Z","end":"2026-05-25T00:48:17.04904Z","steps":["trace[52505637] 'process raft request' (duration: 153.321261ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:48:17.10401Z","caller":"traceutil/trace.go:171","msg":"trace[455289623] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"206.471507ms","start":"2026-05-25T00:48:16.897477Z","end":"2026-05-25T00:48:17.103949Z","steps":["trace[455289623] 'process raft request' (duration: 206.273042ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:48:17.104189Z","caller":"traceutil/trace.go:171","msg":"trace[1967837256] linearizableReadLoop","detail":"{readStateIndex:1083; appliedIndex:1080; }","duration":"149.66891ms","start":"2026-05-25T00:48:16.954482Z","end":"2026-05-25T00:48:17.104151Z","steps":["trace[1967837256] 'read index received' (duration: 94.465937ms)","trace[1967837256] 'applied index is now lower than readState.Index' (duration: 55.201133ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T00:48:17.104381Z","caller":"traceutil/trace.go:171","msg":"trace[451673033] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"185.18299ms","start":"2026-05-25T00:48:16.919183Z","end":"2026-05-25T00:48:17.104366Z","steps":["trace[451673033] 'process raft request' (duration: 184.721348ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:48:17.104448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.943719ms","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-05-25T00:48:17.104494Z","caller":"traceutil/trace.go:171","msg":"trace[1421627033] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1046; }","duration":"150.05609ms","start":"2026-05-25T00:48:16.954424Z","end":"2026-05-25T00:48:17.10448Z","steps":["trace[1421627033] 'agreement among raft nodes before linearized reading' (duration: 149.859635ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:48:20.394593Z","caller":"traceutil/trace.go:171","msg":"trace[1474568190] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"131.51168ms","start":"2026-05-25T00:48:20.263049Z","end":"2026-05-25T00:48:20.394561Z","steps":["trace[1474568190] 'process raft request' (duration: 131.334225ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:48:35.559205Z","caller":"traceutil/trace.go:171","msg":"trace[1823768585] transaction","detail":"{read_only:false; response_revision:1156; number_of_response:1; }","duration":"372.073799ms","start":"2026-05-25T00:48:35.187099Z","end":"2026-05-25T00:48:35.559173Z","steps":["trace[1823768585] 'process raft request' (duration: 371.899044ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:48:35.559376Z","caller":"traceutil/trace.go:171","msg":"trace[1481333882] linearizableReadLoop","detail":"{readStateIndex:1199; appliedIndex:1199; }","duration":"371.965865ms","start":"2026-05-25T00:48:35.187395Z","end":"2026-05-25T00:48:35.559361Z","steps":["trace[1481333882] 'read index received' (duration: 371.957315ms)","trace[1481333882] 'applied index is now lower than readState.Index' (duration: 7.33µs)"],"step_count":2} {"level":"warn","ts":"2026-05-25T00:48:35.559416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T00:48:35.187081Z","time spent":"372.222242ms","remote":"127.0.0.1:57308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T00:48:35.559564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.16038ms","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-05-25T00:48:35.55962Z","caller":"traceutil/trace.go:171","msg":"trace[1097406085] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1156; }","duration":"372.229842ms","start":"2026-05-25T00:48:35.187366Z","end":"2026-05-25T00:48:35.559596Z","steps":["trace[1097406085] 'agreement among raft nodes before linearized reading' (duration: 372.081489ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:48:35.559654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T00:48:35.187354Z","time spent":"372.291604ms","remote":"127.0.0.1:57308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-25T00:48:35.666655Z","caller":"traceutil/trace.go:171","msg":"trace[175500697] linearizableReadLoop","detail":"{readStateIndex:1200; appliedIndex:1199; }","duration":"107.201929ms","start":"2026-05-25T00:48:35.559419Z","end":"2026-05-25T00:48:35.666621Z","steps":["trace[175500697] 'read index received' (duration: 104.205836ms)","trace[175500697] 'applied index is now lower than readState.Index' (duration: 2.995363ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T00:48:35.666777Z","caller":"traceutil/trace.go:171","msg":"trace[545512237] transaction","detail":"{read_only:false; response_revision:1158; number_of_response:1; }","duration":"103.788635ms","start":"2026-05-25T00:48:35.562975Z","end":"2026-05-25T00:48:35.666764Z","steps":["trace[545512237] 'process raft request' (duration: 103.620731ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:48:35.666666Z","caller":"traceutil/trace.go:171","msg":"trace[624280496] transaction","detail":"{read_only:false; response_revision:1157; number_of_response:1; }","duration":"219.447996ms","start":"2026-05-25T00:48:35.447181Z","end":"2026-05-25T00:48:35.666629Z","steps":["trace[624280496] 'process raft request' (duration: 216.423933ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:48:35.667108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.820885ms","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-05-25T00:48:35.667164Z","caller":"traceutil/trace.go:171","msg":"trace[498348760] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1158; }","duration":"436.932948ms","start":"2026-05-25T00:48:35.230216Z","end":"2026-05-25T00:48:35.667149Z","steps":["trace[498348760] 'agreement among raft nodes before linearized reading' (duration: 436.528706ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:48:35.667207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T00:48:35.230191Z","time spent":"437.005611ms","remote":"127.0.0.1:57308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-25T00:48:54.172266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.041866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T00:48:54.17235Z","caller":"traceutil/trace.go:171","msg":"trace[371275747] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1247; }","duration":"102.17778ms","start":"2026-05-25T00:48:54.070152Z","end":"2026-05-25T00:48:54.17233Z","steps":["trace[371275747] 'range keys from in-memory index tree' (duration: 101.964694ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:48:54.402024Z","caller":"traceutil/trace.go:171","msg":"trace[347949179] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"218.302145ms","start":"2026-05-25T00:48:54.183695Z","end":"2026-05-25T00:48:54.401997Z","steps":["trace[347949179] 'process raft request' (duration: 218.109549ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:48:54.402521Z","caller":"traceutil/trace.go:171","msg":"trace[1310089984] linearizableReadLoop","detail":"{readStateIndex:1296; appliedIndex:1296; }","duration":"215.189679ms","start":"2026-05-25T00:48:54.187305Z","end":"2026-05-25T00:48:54.402495Z","steps":["trace[1310089984] 'read index received' (duration: 215.175508ms)","trace[1310089984] 'applied index is now lower than readState.Index' (duration: 12.681µs)"],"step_count":2} {"level":"warn","ts":"2026-05-25T00:48:54.402759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.409755ms","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":"info","ts":"2026-05-25T00:48:54.402795Z","caller":"traceutil/trace.go:171","msg":"trace[87466843] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1249; }","duration":"215.517508ms","start":"2026-05-25T00:48:54.187268Z","end":"2026-05-25T00:48:54.402786Z","steps":["trace[87466843] 'agreement among raft nodes before linearized reading' (duration: 215.317553ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T00:48:54.405837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.924045ms","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-05-25T00:48:54.405883Z","caller":"traceutil/trace.go:171","msg":"trace[587666945] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1250; }","duration":"163.025179ms","start":"2026-05-25T00:48:54.242849Z","end":"2026-05-25T00:48:54.405874Z","steps":["trace[587666945] 'agreement among raft nodes before linearized reading' (duration: 162.844484ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:48:54.406032Z","caller":"traceutil/trace.go:171","msg":"trace[1782040155] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"217.733809ms","start":"2026-05-25T00:48:54.188291Z","end":"2026-05-25T00:48:54.406025Z","steps":["trace[1782040155] 'process raft request' (duration: 217.287976ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:49:04.825659Z","caller":"traceutil/trace.go:171","msg":"trace[855108162] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"124.804454ms","start":"2026-05-25T00:49:04.700828Z","end":"2026-05-25T00:49:04.825633Z","steps":["trace[855108162] 'process raft request' (duration: 124.662049ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:49:05.059253Z","caller":"traceutil/trace.go:171","msg":"trace[250910433] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"266.559906ms","start":"2026-05-25T00:49:04.792665Z","end":"2026-05-25T00:49:05.059225Z","steps":["trace[250910433] 'process raft request' (duration: 266.311449ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T00:49:05.345707Z","caller":"traceutil/trace.go:171","msg":"trace[1641804080] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"219.399216ms","start":"2026-05-25T00:49:05.125849Z","end":"2026-05-25T00:49:05.345248Z","steps":["trace[1641804080] 'process raft request' (duration: 215.038395ms)"],"step_count":1}