{"level":"warn","ts":"2026-05-05T06:42:36.575241Z","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-05T06:42:36.575315Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.35: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.35:2380","--initial-cluster=instance=https://199.204.45.35:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.35:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.35: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-05T06:42:36.575378Z","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-05T06:42:36.57539Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.35:2380"]} {"level":"info","ts":"2026-05-05T06:42:36.57543Z","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-05T06:42:36.576054Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"]} {"level":"info","ts":"2026-05-05T06:42:36.576123Z","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.35:2380"],"listen-peer-urls":["https://199.204.45.35:2380"],"advertise-client-urls":["https://199.204.45.35:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.35: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-05T06:42:36.580262Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.934772ms"} {"level":"info","ts":"2026-05-05T06:42:36.592876Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fb85913747d92cac","cluster-id":"636a84d5a3ba9c31"} {"level":"info","ts":"2026-05-05T06:42:36.592966Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=()"} {"level":"info","ts":"2026-05-05T06:42:36.592992Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became follower at term 0"} {"level":"info","ts":"2026-05-05T06:42:36.593002Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fb85913747d92cac [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-05T06:42:36.593032Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became follower at term 1"} {"level":"info","ts":"2026-05-05T06:42:36.593072Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=(18124051942037073068)"} {"level":"warn","ts":"2026-05-05T06:42:36.604174Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-05T06:42:36.606243Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-05T06:42:36.610261Z","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-05T06:42:36.613411Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fb85913747d92cac","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-05T06:42:36.613941Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fb85913747d92cac","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-05T06:42:36.614096Z","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-05T06:42:36.614205Z","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-05T06:42:36.61422Z","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-05T06:42:36.614574Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=(18124051942037073068)"} {"level":"info","ts":"2026-05-05T06:42:36.614768Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"636a84d5a3ba9c31","local-member-id":"fb85913747d92cac","added-peer-id":"fb85913747d92cac","added-peer-peer-urls":["https://199.204.45.35:2380"]} {"level":"info","ts":"2026-05-05T06:42:36.62003Z","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-05T06:42:36.620312Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fb85913747d92cac","initial-advertise-peer-urls":["https://199.204.45.35:2380"],"listen-peer-urls":["https://199.204.45.35:2380"],"advertise-client-urls":["https://199.204.45.35:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-05T06:42:36.620365Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-05T06:42:36.621057Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.35:2380"} {"level":"info","ts":"2026-05-05T06:42:36.621095Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.35:2380"} {"level":"info","ts":"2026-05-05T06:42:36.793905Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac is starting a new election at term 1"} {"level":"info","ts":"2026-05-05T06:42:36.794003Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became pre-candidate at term 1"} {"level":"info","ts":"2026-05-05T06:42:36.794036Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac received MsgPreVoteResp from fb85913747d92cac at term 1"} {"level":"info","ts":"2026-05-05T06:42:36.794062Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became candidate at term 2"} {"level":"info","ts":"2026-05-05T06:42:36.794084Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac received MsgVoteResp from fb85913747d92cac at term 2"} {"level":"info","ts":"2026-05-05T06:42:36.794105Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became leader at term 2"} {"level":"info","ts":"2026-05-05T06:42:36.79413Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fb85913747d92cac elected leader fb85913747d92cac at term 2"} {"level":"info","ts":"2026-05-05T06:42:36.79552Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fb85913747d92cac","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.35:2379]}","request-path":"/0/members/fb85913747d92cac/attributes","cluster-id":"636a84d5a3ba9c31","publish-timeout":"7s"} {"level":"info","ts":"2026-05-05T06:42:36.795758Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T06:42:36.795801Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T06:42:36.795862Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-05T06:42:36.795914Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-05T06:42:36.795929Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T06:42:36.796839Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"636a84d5a3ba9c31","local-member-id":"fb85913747d92cac","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T06:42:36.796908Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T06:42:36.796928Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T06:42:36.798523Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-05T06:42:36.799433Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.35:2379"} {"level":"warn","ts":"2026-05-05T06:42:40.582937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.790885ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-05T06:42:40.583048Z","caller":"traceutil/trace.go:171","msg":"trace[1271229170] transaction","detail":"{read_only:false; response_revision:115; number_of_response:1; }","duration":"654.755095ms","start":"2026-05-05T06:42:39.928276Z","end":"2026-05-05T06:42:40.583031Z","steps":["trace[1271229170] 'process raft request' (duration: 18.312194ms)","trace[1271229170] 'compare' (duration: 635.638174ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:42:40.583116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:42:39.928266Z","time spent":"654.828186ms","remote":"127.0.0.1:41606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":746,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T06:42:41.467446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.00716ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-05T06:42:41.467544Z","caller":"traceutil/trace.go:171","msg":"trace[240915065] linearizableReadLoop","detail":"{readStateIndex:119; appliedIndex:118; }","duration":"644.725504ms","start":"2026-05-05T06:42:40.822801Z","end":"2026-05-05T06:42:41.467526Z","steps":["trace[240915065] 'read index received' (duration: 98.536653ms)","trace[240915065] 'applied index is now lower than readState.Index' (duration: 546.187791ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:42:41.467657Z","caller":"traceutil/trace.go:171","msg":"trace[1040813012] transaction","detail":"{read_only:false; response_revision:116; number_of_response:1; }","duration":"878.037556ms","start":"2026-05-05T06:42:40.589582Z","end":"2026-05-05T06:42:41.46762Z","steps":["trace[1040813012] 'process raft request' (duration: 331.781165ms)","trace[1040813012] 'compare' (duration: 545.839328ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:42:41.467795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:42:40.589557Z","time spent":"878.175938ms","remote":"127.0.0.1:41606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":680,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T06:42:41.467857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.040067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T06:42:41.467947Z","caller":"traceutil/trace.go:171","msg":"trace[2101776694] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:116; }","duration":"645.197368ms","start":"2026-05-05T06:42:40.82273Z","end":"2026-05-05T06:42:41.467927Z","steps":["trace[2101776694] 'agreement among raft nodes before linearized reading' (duration: 644.976697ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:42:41.467994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:42:40.822704Z","time spent":"645.281139ms","remote":"127.0.0.1:41300","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-05-05T06:42:41.96844Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219121517305639482,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-05T06:42:42.154458Z","caller":"traceutil/trace.go:171","msg":"trace[1170896510] linearizableReadLoop","detail":"{readStateIndex:120; appliedIndex:119; }","duration":"686.734389ms","start":"2026-05-05T06:42:41.467691Z","end":"2026-05-05T06:42:42.154425Z","steps":["trace[1170896510] 'read index received' (duration: 686.498597ms)","trace[1170896510] 'applied index is now lower than readState.Index' (duration: 234.732µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:42:42.154577Z","caller":"traceutil/trace.go:171","msg":"trace[709206429] transaction","detail":"{read_only:false; response_revision:117; number_of_response:1; }","duration":"997.589429ms","start":"2026-05-05T06:42:41.156924Z","end":"2026-05-05T06:42:42.154513Z","steps":["trace[709206429] 'process raft request' (duration: 997.329966ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:42:42.15467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.090240252s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-05T06:42:42.154698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.717882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T06:42:42.154715Z","caller":"traceutil/trace.go:171","msg":"trace[1293807406] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:117; }","duration":"1.090338044s","start":"2026-05-05T06:42:41.064364Z","end":"2026-05-05T06:42:42.154702Z","steps":["trace[1293807406] 'agreement among raft nodes before linearized reading' (duration: 1.090219863s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:42:42.154721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:42:41.156902Z","time spent":"997.74397ms","remote":"127.0.0.1:41452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T06:42:42.154742Z","caller":"traceutil/trace.go:171","msg":"trace[430499857] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:117; }","duration":"685.804942ms","start":"2026-05-05T06:42:41.468925Z","end":"2026-05-05T06:42:42.15473Z","steps":["trace[430499857] 'agreement among raft nodes before linearized reading' (duration: 685.734511ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:42:42.15475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:42:41.06434Z","time spent":"1.090403164s","remote":"127.0.0.1:41526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-05T06:42:42.154774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:42:41.468903Z","time spent":"685.864642ms","remote":"127.0.0.1:41304","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-05-05T06:42:42.154823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.438352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:horizontal-pod-autoscaler\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T06:42:42.154917Z","caller":"traceutil/trace.go:171","msg":"trace[550922132] range","detail":"{range_begin:/registry/clusterroles/system:controller:horizontal-pod-autoscaler; range_end:; response_count:0; response_revision:117; }","duration":"684.574243ms","start":"2026-05-05T06:42:41.470313Z","end":"2026-05-05T06:42:42.154887Z","steps":["trace[550922132] 'agreement among raft nodes before linearized reading' (duration: 684.421072ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:42:42.154805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.039414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-05T06:42:42.154977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:42:41.470292Z","time spent":"684.672934ms","remote":"127.0.0.1:41606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/system:controller:horizontal-pod-autoscaler\" "} {"level":"info","ts":"2026-05-05T06:42:42.155057Z","caller":"traceutil/trace.go:171","msg":"trace[951989730] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:117; }","duration":"165.317406ms","start":"2026-05-05T06:42:41.989682Z","end":"2026-05-05T06:42:42.154999Z","steps":["trace[951989730] 'agreement among raft nodes before linearized reading' (duration: 165.009964ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:42:42.155225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"842.182354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T06:42:42.155304Z","caller":"traceutil/trace.go:171","msg":"trace[883588155] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:117; }","duration":"842.533827ms","start":"2026-05-05T06:42:41.312741Z","end":"2026-05-05T06:42:42.155275Z","steps":["trace[883588155] 'agreement among raft nodes before linearized reading' (duration: 841.807072ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:42:42.155355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:42:41.312719Z","time spent":"842.621237ms","remote":"127.0.0.1:41526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-05T06:42:43.082699Z","caller":"traceutil/trace.go:171","msg":"trace[688123513] transaction","detail":"{read_only:false; response_revision:200; number_of_response:1; }","duration":"339.718737ms","start":"2026-05-05T06:42:42.74293Z","end":"2026-05-05T06:42:43.082648Z","steps":["trace[688123513] 'process raft request' (duration: 305.073858ms)","trace[688123513] 'compare' (duration: 34.195065ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:42:43.0832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:42:42.742898Z","time spent":"339.887148ms","remote":"127.0.0.1:41324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} 2026/05/05 06:42:44 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-05-05T06:42:44.660685Z","caller":"traceutil/trace.go:171","msg":"trace[904741282] transaction","detail":"{read_only:false; response_revision:228; number_of_response:1; }","duration":"118.687823ms","start":"2026-05-05T06:42:44.541963Z","end":"2026-05-05T06:42:44.660651Z","steps":["trace[904741282] 'process raft request' (duration: 104.125811ms)","trace[904741282] 'compare' (duration: 14.375301ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:42:44.790495Z","caller":"traceutil/trace.go:171","msg":"trace[1658289925] linearizableReadLoop","detail":"{readStateIndex:233; appliedIndex:232; }","duration":"129.88814ms","start":"2026-05-05T06:42:44.66058Z","end":"2026-05-05T06:42:44.790468Z","steps":["trace[1658289925] 'read index received' (duration: 112.964949ms)","trace[1658289925] 'applied index is now lower than readState.Index' (duration: 16.92171ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:42:44.790687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.401897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:42:44.790776Z","caller":"traceutil/trace.go:171","msg":"trace[1088194194] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:228; }","duration":"185.519549ms","start":"2026-05-05T06:42:44.605234Z","end":"2026-05-05T06:42:44.790754Z","steps":["trace[1088194194] 'agreement among raft nodes before linearized reading' (duration: 185.358967ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:42:44.795171Z","caller":"traceutil/trace.go:171","msg":"trace[47465873] transaction","detail":"{read_only:false; response_revision:229; number_of_response:1; }","duration":"130.508824ms","start":"2026-05-05T06:42:44.664647Z","end":"2026-05-05T06:42:44.795156Z","steps":["trace[47465873] 'process raft request' (duration: 130.123541ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:42:44.795655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.668083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-05-05T06:42:44.795812Z","caller":"traceutil/trace.go:171","msg":"trace[1781852140] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:230; }","duration":"131.846955ms","start":"2026-05-05T06:42:44.663949Z","end":"2026-05-05T06:42:44.795796Z","steps":["trace[1781852140] 'agreement among raft nodes before linearized reading' (duration: 131.578813ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:42:47.752269Z","caller":"traceutil/trace.go:171","msg":"trace[653779316] transaction","detail":"{read_only:false; response_revision:286; number_of_response:1; }","duration":"102.446878ms","start":"2026-05-05T06:42:47.649796Z","end":"2026-05-05T06:42:47.752243Z","steps":["trace[653779316] 'process raft request' (duration: 102.286437ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:43:14.278055Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219121517305640381,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-05T06:43:14.507209Z","caller":"traceutil/trace.go:171","msg":"trace[617163464] linearizableReadLoop","detail":"{readStateIndex:585; appliedIndex:584; }","duration":"729.431583ms","start":"2026-05-05T06:43:13.777709Z","end":"2026-05-05T06:43:14.50714Z","steps":["trace[617163464] 'read index received' (duration: 723.827505ms)","trace[617163464] 'applied index is now lower than readState.Index' (duration: 5.602648ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:43:14.50835Z","caller":"traceutil/trace.go:171","msg":"trace[2063258531] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"967.586108ms","start":"2026-05-05T06:43:13.539933Z","end":"2026-05-05T06:43:14.507519Z","steps":["trace[2063258531] 'process raft request' (duration: 966.406969ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:43:14.508472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:43:13.539906Z","time spent":"968.506828ms","remote":"127.0.0.1:41376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036023,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T06:43:14.508469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.741039ms","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-05-05T06:43:14.508546Z","caller":"traceutil/trace.go:171","msg":"trace[121345484] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:564; }","duration":"730.879375ms","start":"2026-05-05T06:43:13.777646Z","end":"2026-05-05T06:43:14.508525Z","steps":["trace[121345484] 'agreement among raft nodes before linearized reading' (duration: 729.774287ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:43:14.508601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:43:13.777631Z","time spent":"730.958679ms","remote":"127.0.0.1:41526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-05T06:43:14.508858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.29165ms","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-05-05T06:43:14.508996Z","caller":"traceutil/trace.go:171","msg":"trace[1040983223] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:564; }","duration":"175.617234ms","start":"2026-05-05T06:43:14.333363Z","end":"2026-05-05T06:43:14.508981Z","steps":["trace[1040983223] 'agreement among raft nodes before linearized reading' (duration: 174.077348ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:43:23.998237Z","caller":"traceutil/trace.go:171","msg":"trace[487121784] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"255.159015ms","start":"2026-05-05T06:43:23.743055Z","end":"2026-05-05T06:43:23.998214Z","steps":["trace[487121784] 'process raft request' (duration: 254.950427ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:43:27.576749Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.812954528s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-05T06:43:27.699762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.127285163s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-05T06:43:27.699845Z","caller":"traceutil/trace.go:171","msg":"trace[982149934] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:610; }","duration":"3.127402398s","start":"2026-05-05T06:43:24.572425Z","end":"2026-05-05T06:43:27.699827Z","steps":["trace[982149934] 'range keys from in-memory index tree' (duration: 3.127145858s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:43:27.699868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.173952372s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T06:43:27.699886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:43:24.572412Z","time spent":"3.12746312s","remote":"127.0.0.1:41526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-05T06:43:27.69994Z","caller":"traceutil/trace.go:171","msg":"trace[1179584110] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:610; }","duration":"2.174060986s","start":"2026-05-05T06:43:25.525861Z","end":"2026-05-05T06:43:27.699922Z","steps":["trace[1179584110] 'range keys from in-memory index tree' (duration: 2.173763426s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:43:27.699982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:43:25.525841Z","time spent":"2.17413354s","remote":"127.0.0.1:41300","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-05T06:43:27.700038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.13320985s","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-05-05T06:43:27.700054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.133833893s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-05T06:43:27.700095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.243282886s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:43:27.700108Z","caller":"traceutil/trace.go:171","msg":"trace[712581332] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:610; }","duration":"3.243295487s","start":"2026-05-05T06:43:24.456808Z","end":"2026-05-05T06:43:27.700104Z","steps":["trace[712581332] 'range keys from in-memory index tree' (duration: 3.243214453s)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:43:27.700103Z","caller":"traceutil/trace.go:171","msg":"trace[111242460] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:610; }","duration":"3.133953578s","start":"2026-05-05T06:43:24.566137Z","end":"2026-05-05T06:43:27.700091Z","steps":["trace[111242460] 'range keys from in-memory index tree' (duration: 3.133683738s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:43:27.700126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:43:24.456755Z","time spent":"3.243367469s","remote":"127.0.0.1:41300","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-05T06:43:27.700138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:43:24.566116Z","time spent":"3.13401495s","remote":"127.0.0.1:41526","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-05-05T06:43:27.699744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.129734426s","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-05T06:43:27.700225Z","caller":"traceutil/trace.go:171","msg":"trace[469672163] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:610; }","duration":"3.130271068s","start":"2026-05-05T06:43:24.569941Z","end":"2026-05-05T06:43:27.700212Z","steps":["trace[469672163] 'range keys from in-memory index tree' (duration: 3.129588001s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:43:27.700259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:43:24.56992Z","time spent":"3.13032921s","remote":"127.0.0.1:41526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-05T06:43:27.70007Z","caller":"traceutil/trace.go:171","msg":"trace[204664250] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:610; }","duration":"3.133270452s","start":"2026-05-05T06:43:24.566787Z","end":"2026-05-05T06:43:27.700057Z","steps":["trace[204664250] 'range keys from in-memory index tree' (duration: 3.133097596s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:43:27.700361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:43:24.566766Z","time spent":"3.133590344s","remote":"127.0.0.1:41526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-05T06:43:27.700532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.490968ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T06:43:27.700641Z","caller":"traceutil/trace.go:171","msg":"trace[769726182] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"1.936963744s","start":"2026-05-05T06:43:25.763658Z","end":"2026-05-05T06:43:27.700622Z","steps":["trace[769726182] 'process raft request' (duration: 1.81330716s)","trace[769726182] 'compare' (duration: 123.27193ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:43:27.700729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:43:25.763632Z","time spent":"1.937068738s","remote":"127.0.0.1:41526","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-05T06:43:27.824929Z","caller":"traceutil/trace.go:171","msg":"trace[2033913601] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:633; }","duration":"298.206816ms","start":"2026-05-05T06:43:27.526698Z","end":"2026-05-05T06:43:27.824905Z","steps":["trace[2033913601] 'read index received' (duration: 50.307827ms)","trace[2033913601] 'applied index is now lower than readState.Index' (duration: 247.897979ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:43:27.82508Z","caller":"traceutil/trace.go:171","msg":"trace[462091149] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"1.36502202s","start":"2026-05-05T06:43:26.459938Z","end":"2026-05-05T06:43:27.82496Z","steps":["trace[462091149] 'process raft request' (duration: 1.364800472s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:43:27.825159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.439494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:43:27.825217Z","caller":"traceutil/trace.go:171","msg":"trace[508265200] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:612; }","duration":"298.556659ms","start":"2026-05-05T06:43:27.526648Z","end":"2026-05-05T06:43:27.825205Z","steps":["trace[508265200] 'agreement among raft nodes before linearized reading' (duration: 298.365412ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:43:27.82541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:43:26.459915Z","time spent":"1.365337472s","remote":"127.0.0.1:41366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":758,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T06:43:27.82619Z","caller":"traceutil/trace.go:171","msg":"trace[332529007] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"121.337259ms","start":"2026-05-05T06:43:27.7048Z","end":"2026-05-05T06:43:27.826137Z","steps":["trace[332529007] 'process raft request' (duration: 121.121822ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:43:27.826222Z","caller":"traceutil/trace.go:171","msg":"trace[1817545654] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"121.029398ms","start":"2026-05-05T06:43:27.705163Z","end":"2026-05-05T06:43:27.826192Z","steps":["trace[1817545654] 'process raft request' (duration: 120.901354ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:43:27.826252Z","caller":"traceutil/trace.go:171","msg":"trace[298345639] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"120.968166ms","start":"2026-05-05T06:43:27.705266Z","end":"2026-05-05T06:43:27.826234Z","steps":["trace[298345639] 'process raft request' (duration: 120.849732ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:43:27.826342Z","caller":"traceutil/trace.go:171","msg":"trace[249454210] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"120.764727ms","start":"2026-05-05T06:43:27.705564Z","end":"2026-05-05T06:43:27.826328Z","steps":["trace[249454210] 'process raft request' (duration: 120.620132ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:43:33.208674Z","caller":"traceutil/trace.go:171","msg":"trace[1848948895] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"137.598087ms","start":"2026-05-05T06:43:33.07105Z","end":"2026-05-05T06:43:33.208648Z","steps":["trace[1848948895] 'process raft request' (duration: 137.379519ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:43:33.533102Z","caller":"traceutil/trace.go:171","msg":"trace[1054403193] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"339.44363ms","start":"2026-05-05T06:43:33.193627Z","end":"2026-05-05T06:43:33.53307Z","steps":["trace[1054403193] 'process raft request' (duration: 327.302589ms)","trace[1054403193] 'compare' (duration: 11.868312ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:43:33.533321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:43:33.193606Z","time spent":"339.623147ms","remote":"127.0.0.1:41366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T06:43:33.537Z","caller":"traceutil/trace.go:171","msg":"trace[830451370] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"174.839387ms","start":"2026-05-05T06:43:33.362137Z","end":"2026-05-05T06:43:33.536976Z","steps":["trace[830451370] 'process raft request' (duration: 174.502976ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:43:39.431017Z","caller":"traceutil/trace.go:171","msg":"trace[12801462] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"237.8286ms","start":"2026-05-05T06:43:39.193161Z","end":"2026-05-05T06:43:39.43099Z","steps":["trace[12801462] 'process raft request' (duration: 174.183536ms)","trace[12801462] 'compare' (duration: 63.495209ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:43:39.441068Z","caller":"traceutil/trace.go:171","msg":"trace[335265359] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"243.253727ms","start":"2026-05-05T06:43:39.19779Z","end":"2026-05-05T06:43:39.441043Z","steps":["trace[335265359] 'process raft request' (duration: 243.081442ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:43:40.048769Z","caller":"traceutil/trace.go:171","msg":"trace[271640839] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"124.802473ms","start":"2026-05-05T06:43:39.923944Z","end":"2026-05-05T06:43:40.048746Z","steps":["trace[271640839] 'process raft request' (duration: 124.666928ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:43:40.079996Z","caller":"traceutil/trace.go:171","msg":"trace[462157187] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"155.335167ms","start":"2026-05-05T06:43:39.924638Z","end":"2026-05-05T06:43:40.079973Z","steps":["trace[462157187] 'process raft request' (duration: 155.262514ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:43:40.08002Z","caller":"traceutil/trace.go:171","msg":"trace[1435489081] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"155.638587ms","start":"2026-05-05T06:43:39.92435Z","end":"2026-05-05T06:43:40.079988Z","steps":["trace[1435489081] 'process raft request' (duration: 155.517523ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:43:40.080174Z","caller":"traceutil/trace.go:171","msg":"trace[1704815704] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"113.014676ms","start":"2026-05-05T06:43:39.967137Z","end":"2026-05-05T06:43:40.080151Z","steps":["trace[1704815704] 'process raft request' (duration: 112.80111ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:43:40.080246Z","caller":"traceutil/trace.go:171","msg":"trace[1865244015] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"155.917946ms","start":"2026-05-05T06:43:39.924315Z","end":"2026-05-05T06:43:40.080233Z","steps":["trace[1865244015] 'process raft request' (duration: 155.443951ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:43:40.334562Z","caller":"traceutil/trace.go:171","msg":"trace[336624422] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"145.819204ms","start":"2026-05-05T06:43:40.18872Z","end":"2026-05-05T06:43:40.33454Z","steps":["trace[336624422] 'process raft request' (duration: 145.660959ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:43:42.72799Z","caller":"traceutil/trace.go:171","msg":"trace[2114879988] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"624.859383ms","start":"2026-05-05T06:43:42.103108Z","end":"2026-05-05T06:43:42.727968Z","steps":["trace[2114879988] 'process raft request' (duration: 624.668567ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:43:42.728203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:43:42.103089Z","time spent":"624.974847ms","remote":"127.0.0.1:41526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T06:43:42.731017Z","caller":"traceutil/trace.go:171","msg":"trace[497480203] linearizableReadLoop","detail":"{readStateIndex:788; appliedIndex:785; }","duration":"204.532369ms","start":"2026-05-05T06:43:42.526459Z","end":"2026-05-05T06:43:42.730991Z","steps":["trace[497480203] 'read index received' (duration: 201.375529ms)","trace[497480203] 'applied index is now lower than readState.Index' (duration: 3.15545ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:43:42.7311Z","caller":"traceutil/trace.go:171","msg":"trace[729115077] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"627.948031ms","start":"2026-05-05T06:43:42.103112Z","end":"2026-05-05T06:43:42.73106Z","steps":["trace[729115077] 'process raft request' (duration: 626.142554ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:43:42.731248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.766536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T06:43:42.731278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.361642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:43:42.731364Z","caller":"traceutil/trace.go:171","msg":"trace[1200274851] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:761; }","duration":"191.452215ms","start":"2026-05-05T06:43:42.539892Z","end":"2026-05-05T06:43:42.731344Z","steps":["trace[1200274851] 'agreement among raft nodes before linearized reading' (duration: 191.324531ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:43:42.731162Z","caller":"traceutil/trace.go:171","msg":"trace[1732070805] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"627.007382ms","start":"2026-05-05T06:43:42.104141Z","end":"2026-05-05T06:43:42.731148Z","steps":["trace[1732070805] 'process raft request' (duration: 626.792445ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:43:42.731418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:43:42.10309Z","time spent":"628.184418ms","remote":"127.0.0.1:41526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T06:43:42.731295Z","caller":"traceutil/trace.go:171","msg":"trace[584370445] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:761; }","duration":"204.86806ms","start":"2026-05-05T06:43:42.526414Z","end":"2026-05-05T06:43:42.731282Z","steps":["trace[584370445] 'agreement among raft nodes before linearized reading' (duration: 204.776067ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:43:42.731866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:43:42.104133Z","time spent":"627.315001ms","remote":"127.0.0.1:41526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T06:43:46.533183Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.768649828s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-05T06:43:46.644147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.879537351s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T06:43:46.644286Z","caller":"traceutil/trace.go:171","msg":"trace[554918308] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"1.902704179s","start":"2026-05-05T06:43:44.74157Z","end":"2026-05-05T06:43:46.644274Z","steps":["trace[554918308] 'process raft request' (duration: 1.902662677s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:43:46.64433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:43:44.741551Z","time spent":"1.902762801s","remote":"127.0.0.1:41526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T06:43:46.64432Z","caller":"traceutil/trace.go:171","msg":"trace[2111619869] linearizableReadLoop","detail":"{readStateIndex:801; appliedIndex:800; }","duration":"1.904956819s","start":"2026-05-05T06:43:44.739336Z","end":"2026-05-05T06:43:46.644292Z","steps":["trace[2111619869] 'read index received' (duration: 25.218552ms)","trace[2111619869] 'applied index is now lower than readState.Index' (duration: 1.879733357s)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:43:46.644432Z","caller":"traceutil/trace.go:171","msg":"trace[221110264] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"1.906323211s","start":"2026-05-05T06:43:44.738091Z","end":"2026-05-05T06:43:46.644414Z","steps":["trace[221110264] 'process raft request' (duration: 26.45138ms)","trace[221110264] 'compare' (duration: 1.878248431s)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:43:46.644518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:43:44.738047Z","time spent":"1.906431054s","remote":"127.0.0.1:41526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T06:43:46.644687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.905348671s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-05T06:43:46.644724Z","caller":"traceutil/trace.go:171","msg":"trace[34810755] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:774; }","duration":"1.905401613s","start":"2026-05-05T06:43:44.739312Z","end":"2026-05-05T06:43:46.644713Z","steps":["trace[34810755] 'agreement among raft nodes before linearized reading' (duration: 1.905288359s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:43:46.644754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:43:44.739302Z","time spent":"1.905446174s","remote":"127.0.0.1:41526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-05T06:43:46.878996Z","caller":"traceutil/trace.go:171","msg":"trace[1337504609] linearizableReadLoop","detail":"{readStateIndex:803; appliedIndex:802; }","duration":"234.532295ms","start":"2026-05-05T06:43:46.644432Z","end":"2026-05-05T06:43:46.878964Z","steps":["trace[1337504609] 'read index received' (duration: 227.550442ms)","trace[1337504609] 'applied index is now lower than readState.Index' (duration: 6.980033ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:43:46.879201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.008313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-05T06:43:46.879252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"910.000491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T06:43:46.879277Z","caller":"traceutil/trace.go:171","msg":"trace[908511057] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:775; }","duration":"507.140637ms","start":"2026-05-05T06:43:46.372119Z","end":"2026-05-05T06:43:46.879259Z","steps":["trace[908511057] 'agreement among raft nodes before linearized reading' (duration: 507.007153ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:43:46.87932Z","caller":"traceutil/trace.go:171","msg":"trace[748801462] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:775; }","duration":"910.123445ms","start":"2026-05-05T06:43:45.96918Z","end":"2026-05-05T06:43:46.879304Z","steps":["trace[748801462] 'agreement among raft nodes before linearized reading' (duration: 909.978111ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:43:46.879317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"766.346652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-05T06:43:46.879363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:43:45.969159Z","time spent":"910.197478ms","remote":"127.0.0.1:41498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":1,"response size":30,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"info","ts":"2026-05-05T06:43:46.879364Z","caller":"traceutil/trace.go:171","msg":"trace[96620258] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"941.830149ms","start":"2026-05-05T06:43:45.937524Z","end":"2026-05-05T06:43:46.879355Z","steps":["trace[96620258] 'process raft request' (duration: 934.360591ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:43:46.879383Z","caller":"traceutil/trace.go:171","msg":"trace[1282227929] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:775; }","duration":"766.460755ms","start":"2026-05-05T06:43:46.112906Z","end":"2026-05-05T06:43:46.879366Z","steps":["trace[1282227929] 'agreement among raft nodes before linearized reading' (duration: 766.28156ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:43:46.879425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:43:46.112885Z","time spent":"766.532327ms","remote":"127.0.0.1:41526","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-05T06:43:46.879453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:43:45.937501Z","time spent":"941.892221ms","remote":"127.0.0.1:41444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5051,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T06:43:46.879323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:43:46.372098Z","time spent":"507.215198ms","remote":"127.0.0.1:41452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":12,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-05-05T06:43:46.87957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.3537287s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:43:46.879604Z","caller":"traceutil/trace.go:171","msg":"trace[958356318] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:775; }","duration":"1.353789882s","start":"2026-05-05T06:43:45.525805Z","end":"2026-05-05T06:43:46.879595Z","steps":["trace[958356318] 'agreement among raft nodes before linearized reading' (duration: 1.35374076s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:43:46.879629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:43:45.525782Z","time spent":"1.353842464s","remote":"127.0.0.1:41300","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-05T06:43:46.879799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.038370595s","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-05-05T06:43:46.879884Z","caller":"traceutil/trace.go:171","msg":"trace[1274092564] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:775; }","duration":"1.038515489s","start":"2026-05-05T06:43:45.841348Z","end":"2026-05-05T06:43:46.879863Z","steps":["trace[1274092564] 'agreement among raft nodes before linearized reading' (duration: 1.038155068s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:43:46.879939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:43:45.841324Z","time spent":"1.038604912s","remote":"127.0.0.1:41526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-05T06:43:46.880133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.039821221s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-4nczn\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-05-05T06:43:46.880202Z","caller":"traceutil/trace.go:171","msg":"trace[235860072] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-4nczn; range_end:; response_count:1; response_revision:775; }","duration":"2.039921675s","start":"2026-05-05T06:43:44.840263Z","end":"2026-05-05T06:43:46.880185Z","steps":["trace[235860072] 'agreement among raft nodes before linearized reading' (duration: 2.038803351s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:43:46.880241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:43:44.840248Z","time spent":"2.039984646s","remote":"127.0.0.1:41452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-4nczn\" "} {"level":"info","ts":"2026-05-05T06:43:46.937615Z","caller":"traceutil/trace.go:171","msg":"trace[1899989139] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"289.516627ms","start":"2026-05-05T06:43:46.64807Z","end":"2026-05-05T06:43:46.937587Z","steps":["trace[1899989139] 'process raft request' (duration: 289.113895ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:43:47.061801Z","caller":"traceutil/trace.go:171","msg":"trace[470974953] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"176.688117ms","start":"2026-05-05T06:43:46.885091Z","end":"2026-05-05T06:43:47.061779Z","steps":["trace[470974953] 'process raft request' (duration: 167.794099ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:43:47.062253Z","caller":"traceutil/trace.go:171","msg":"trace[1630723751] linearizableReadLoop","detail":"{readStateIndex:807; appliedIndex:804; }","duration":"116.259798ms","start":"2026-05-05T06:43:46.945977Z","end":"2026-05-05T06:43:47.062237Z","steps":["trace[1630723751] 'read index received' (duration: 106.943568ms)","trace[1630723751] 'applied index is now lower than readState.Index' (duration: 9.31538ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:43:47.062303Z","caller":"traceutil/trace.go:171","msg":"trace[832598941] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"177.096219ms","start":"2026-05-05T06:43:46.885182Z","end":"2026-05-05T06:43:47.062279Z","steps":["trace[832598941] 'process raft request' (duration: 176.522542ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:43:47.062348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.346661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:43:47.062392Z","caller":"traceutil/trace.go:171","msg":"trace[1852412196] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:779; }","duration":"116.447344ms","start":"2026-05-05T06:43:46.945934Z","end":"2026-05-05T06:43:47.062381Z","steps":["trace[1852412196] 'agreement among raft nodes before linearized reading' (duration: 116.365971ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:43:47.062382Z","caller":"traceutil/trace.go:171","msg":"trace[2057405874] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"173.815189ms","start":"2026-05-05T06:43:46.888514Z","end":"2026-05-05T06:43:47.062329Z","steps":["trace[2057405874] 'process raft request' (duration: 173.627274ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:44:11.265969Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219121517305641276,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-05T06:44:11.495848Z","caller":"traceutil/trace.go:171","msg":"trace[517501530] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"733.925877ms","start":"2026-05-05T06:44:10.761905Z","end":"2026-05-05T06:44:11.495831Z","steps":["trace[517501530] 'process raft request' (duration: 733.828885ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:44:11.495959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:44:10.761887Z","time spent":"734.01425ms","remote":"127.0.0.1:41526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T06:44:11.505413Z","caller":"traceutil/trace.go:171","msg":"trace[753043350] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"652.66267ms","start":"2026-05-05T06:44:10.852737Z","end":"2026-05-05T06:44:11.5054Z","steps":["trace[753043350] 'process raft request' (duration: 652.499446ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:44:11.505435Z","caller":"traceutil/trace.go:171","msg":"trace[1914167505] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"389.173981ms","start":"2026-05-05T06:44:11.116251Z","end":"2026-05-05T06:44:11.505425Z","steps":["trace[1914167505] 'process raft request' (duration: 389.085409ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:44:11.505533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:44:11.116229Z","time spent":"389.235802ms","remote":"127.0.0.1:41526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T06:44:11.505533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:44:10.852708Z","time spent":"652.755902ms","remote":"127.0.0.1:41526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T06:44:11.505626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"740.711403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-05T06:44:11.505679Z","caller":"traceutil/trace.go:171","msg":"trace[346466208] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:981; }","duration":"740.807205ms","start":"2026-05-05T06:44:10.764858Z","end":"2026-05-05T06:44:11.505665Z","steps":["trace[346466208] 'agreement among raft nodes before linearized reading' (duration: 740.660311ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:44:11.50572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:44:10.764836Z","time spent":"740.876507ms","remote":"127.0.0.1:41526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-05T06:44:11.505384Z","caller":"traceutil/trace.go:171","msg":"trace[1764022725] linearizableReadLoop","detail":"{readStateIndex:1016; appliedIndex:1013; }","duration":"740.459657ms","start":"2026-05-05T06:44:10.764907Z","end":"2026-05-05T06:44:11.505367Z","steps":["trace[1764022725] 'read index received' (duration: 730.76989ms)","trace[1764022725] 'applied index is now lower than readState.Index' (duration: 9.688887ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:44:11.505854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.666641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-05-05T06:44:11.505874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.769734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-05T06:44:11.505889Z","caller":"traceutil/trace.go:171","msg":"trace[1884788392] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:981; }","duration":"228.719462ms","start":"2026-05-05T06:44:11.27716Z","end":"2026-05-05T06:44:11.50588Z","steps":["trace[1884788392] 'agreement among raft nodes before linearized reading' (duration: 228.636ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:44:11.505911Z","caller":"traceutil/trace.go:171","msg":"trace[1653456891] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:981; }","duration":"416.826155ms","start":"2026-05-05T06:44:11.08907Z","end":"2026-05-05T06:44:11.505896Z","steps":["trace[1653456891] 'agreement among raft nodes before linearized reading' (duration: 416.716152ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:44:11.505964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:44:11.089055Z","time spent":"416.902087ms","remote":"127.0.0.1:41526","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-05-05T06:44:11.506027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.479492ms","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-05T06:44:11.506056Z","caller":"traceutil/trace.go:171","msg":"trace[1314948982] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:981; }","duration":"266.545033ms","start":"2026-05-05T06:44:11.239503Z","end":"2026-05-05T06:44:11.506048Z","steps":["trace[1314948982] 'agreement among raft nodes before linearized reading' (duration: 266.469501ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:44:11.774934Z","caller":"traceutil/trace.go:171","msg":"trace[23401869] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"265.433916ms","start":"2026-05-05T06:44:11.509458Z","end":"2026-05-05T06:44:11.774892Z","steps":["trace[23401869] 'process raft request' (duration: 252.214254ms)","trace[23401869] 'compare' (duration: 12.850253ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:44:11.775086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.01393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:44:11.775147Z","caller":"traceutil/trace.go:171","msg":"trace[2086346245] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:985; }","duration":"250.107143ms","start":"2026-05-05T06:44:11.525026Z","end":"2026-05-05T06:44:11.775133Z","steps":["trace[2086346245] 'agreement among raft nodes before linearized reading' (duration: 249.9736ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:44:11.774958Z","caller":"traceutil/trace.go:171","msg":"trace[845252402] linearizableReadLoop","detail":"{readStateIndex:1020; appliedIndex:1017; }","duration":"249.889997ms","start":"2026-05-05T06:44:11.525048Z","end":"2026-05-05T06:44:11.774938Z","steps":["trace[845252402] 'read index received' (duration: 236.630054ms)","trace[845252402] 'applied index is now lower than readState.Index' (duration: 13.258773ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:44:11.775033Z","caller":"traceutil/trace.go:171","msg":"trace[670288596] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"265.111269ms","start":"2026-05-05T06:44:11.509894Z","end":"2026-05-05T06:44:11.775005Z","steps":["trace[670288596] 'process raft request' (duration: 264.987026ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:44:11.775044Z","caller":"traceutil/trace.go:171","msg":"trace[675412401] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"265.487538ms","start":"2026-05-05T06:44:11.509543Z","end":"2026-05-05T06:44:11.77503Z","steps":["trace[675412401] 'process raft request' (duration: 265.213451ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:45:52.757903Z","caller":"traceutil/trace.go:171","msg":"trace[872473301] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"131.632271ms","start":"2026-05-05T06:45:52.626234Z","end":"2026-05-05T06:45:52.757866Z","steps":["trace[872473301] 'process raft request' (duration: 110.597288ms)","trace[872473301] 'compare' (duration: 20.82784ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:45:52.758293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.239993ms","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-05-05T06:45:52.758379Z","caller":"traceutil/trace.go:171","msg":"trace[1406953248] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1361; }","duration":"121.350554ms","start":"2026-05-05T06:45:52.636996Z","end":"2026-05-05T06:45:52.758347Z","steps":["trace[1406953248] 'agreement among raft nodes before linearized reading' (duration: 121.092991ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:45:52.758379Z","caller":"traceutil/trace.go:171","msg":"trace[418976141] linearizableReadLoop","detail":"{readStateIndex:1420; appliedIndex:1419; }","duration":"120.764145ms","start":"2026-05-05T06:45:52.637034Z","end":"2026-05-05T06:45:52.757798Z","steps":["trace[418976141] 'read index received' (duration: 99.673411ms)","trace[418976141] 'applied index is now lower than readState.Index' (duration: 21.089304ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:45:52.758552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.489567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:ephemeral-volume-controller\" ","response":"range_response_count:1 size:783"} {"level":"info","ts":"2026-05-05T06:45:52.75861Z","caller":"traceutil/trace.go:171","msg":"trace[895792527] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:ephemeral-volume-controller; range_end:; response_count:1; response_revision:1361; }","duration":"119.561128ms","start":"2026-05-05T06:45:52.639033Z","end":"2026-05-05T06:45:52.758594Z","steps":["trace[895792527] 'agreement among raft nodes before linearized reading' (duration: 119.471587ms)"],"step_count":1}