{"level":"warn","ts":"2026-04-23T14:46:37.237891Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-23T14:46:37.238001Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.44: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.44:2380","--initial-cluster=instance=https://199.204.45.44:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.44:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.44:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-23T14:46:37.238285Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-23T14:46:37.238321Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.44:2380"]} {"level":"info","ts":"2026-04-23T14:46:37.238401Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-23T14:46:37.239316Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.44:2379"]} {"level":"info","ts":"2026-04-23T14:46:37.239502Z","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.44:2380"],"listen-peer-urls":["https://199.204.45.44:2380"],"advertise-client-urls":["https://199.204.45.44:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.44:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.44:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-23T14:46:37.244031Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.213053ms"} {"level":"info","ts":"2026-04-23T14:46:37.252602Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a0a01fc7fbf6be45","cluster-id":"92fa3fee501eaaec"} {"level":"info","ts":"2026-04-23T14:46:37.252699Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 switched to configuration voters=()"} {"level":"info","ts":"2026-04-23T14:46:37.252724Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became follower at term 0"} {"level":"info","ts":"2026-04-23T14:46:37.252738Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a0a01fc7fbf6be45 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-23T14:46:37.252754Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became follower at term 1"} {"level":"info","ts":"2026-04-23T14:46:37.25279Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 switched to configuration voters=(11574285986128379461)"} {"level":"warn","ts":"2026-04-23T14:46:37.260354Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-23T14:46:37.261669Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-23T14:46:37.262866Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-23T14:46:37.264825Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a0a01fc7fbf6be45","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-23T14:46:37.265098Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-23T14:46:37.265137Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a0a01fc7fbf6be45","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-23T14:46:37.265542Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-23T14:46:37.265595Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-23T14:46:37.266066Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 switched to configuration voters=(11574285986128379461)"} {"level":"info","ts":"2026-04-23T14:46:37.266369Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"92fa3fee501eaaec","local-member-id":"a0a01fc7fbf6be45","added-peer-id":"a0a01fc7fbf6be45","added-peer-peer-urls":["https://199.204.45.44:2380"]} {"level":"info","ts":"2026-04-23T14:46:37.271658Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-23T14:46:37.271922Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a0a01fc7fbf6be45","initial-advertise-peer-urls":["https://199.204.45.44:2380"],"listen-peer-urls":["https://199.204.45.44:2380"],"advertise-client-urls":["https://199.204.45.44:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.44:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-23T14:46:37.271956Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-23T14:46:37.272437Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.44:2380"} {"level":"info","ts":"2026-04-23T14:46:37.272458Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.44:2380"} {"level":"info","ts":"2026-04-23T14:46:37.353416Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 is starting a new election at term 1"} {"level":"info","ts":"2026-04-23T14:46:37.353464Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-23T14:46:37.353501Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 received MsgPreVoteResp from a0a01fc7fbf6be45 at term 1"} {"level":"info","ts":"2026-04-23T14:46:37.353612Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became candidate at term 2"} {"level":"info","ts":"2026-04-23T14:46:37.353636Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 received MsgVoteResp from a0a01fc7fbf6be45 at term 2"} {"level":"info","ts":"2026-04-23T14:46:37.353654Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became leader at term 2"} {"level":"info","ts":"2026-04-23T14:46:37.353713Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a0a01fc7fbf6be45 elected leader a0a01fc7fbf6be45 at term 2"} {"level":"info","ts":"2026-04-23T14:46:37.354626Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a0a01fc7fbf6be45","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.44:2379]}","request-path":"/0/members/a0a01fc7fbf6be45/attributes","cluster-id":"92fa3fee501eaaec","publish-timeout":"7s"} {"level":"info","ts":"2026-04-23T14:46:37.354634Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-23T14:46:37.354633Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T14:46:37.354671Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-23T14:46:37.35487Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-23T14:46:37.354976Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-23T14:46:37.355487Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"92fa3fee501eaaec","local-member-id":"a0a01fc7fbf6be45","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T14:46:37.355615Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T14:46:37.355702Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T14:46:37.357382Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.44:2379"} {"level":"info","ts":"2026-04-23T14:46:37.358327Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-23T14:46:51.549199Z","caller":"traceutil/trace.go:171","msg":"trace[1386754956] linearizableReadLoop","detail":"{readStateIndex:377; appliedIndex:376; }","duration":"112.456985ms","start":"2026-04-23T14:46:51.436719Z","end":"2026-04-23T14:46:51.549176Z","steps":["trace[1386754956] 'read index received' (duration: 112.254389ms)","trace[1386754956] 'applied index is now lower than readState.Index' (duration: 201.686µs)"],"step_count":2} {"level":"warn","ts":"2026-04-23T14:46:51.549375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.643201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-23T14:46:51.54945Z","caller":"traceutil/trace.go:171","msg":"trace[1068373602] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:365; }","duration":"112.771694ms","start":"2026-04-23T14:46:51.436666Z","end":"2026-04-23T14:46:51.549438Z","steps":["trace[1068373602] 'agreement among raft nodes before linearized reading' (duration: 112.654291ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:46:51.549289Z","caller":"traceutil/trace.go:171","msg":"trace[74239683] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"137.693424ms","start":"2026-04-23T14:46:51.411556Z","end":"2026-04-23T14:46:51.54925Z","steps":["trace[74239683] 'process raft request' (duration: 137.485918ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:46:53.335297Z","caller":"traceutil/trace.go:171","msg":"trace[1214087816] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"104.042979ms","start":"2026-04-23T14:46:53.231227Z","end":"2026-04-23T14:46:53.33527Z","steps":["trace[1214087816] 'process raft request' (duration: 103.854424ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:46:53.335561Z","caller":"traceutil/trace.go:171","msg":"trace[1099636941] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"102.351501ms","start":"2026-04-23T14:46:53.233185Z","end":"2026-04-23T14:46:53.335536Z","steps":["trace[1099636941] 'process raft request' (duration: 102.085233ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:46:53.33563Z","caller":"traceutil/trace.go:171","msg":"trace[891599540] transaction","detail":"{read_only:false; number_of_response:1; response_revision:389; }","duration":"102.337061ms","start":"2026-04-23T14:46:53.233198Z","end":"2026-04-23T14:46:53.335535Z","steps":["trace[891599540] 'process raft request' (duration: 102.018342ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:47:13.852851Z","caller":"traceutil/trace.go:171","msg":"trace[669562847] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"115.443213ms","start":"2026-04-23T14:47:13.737383Z","end":"2026-04-23T14:47:13.852826Z","steps":["trace[669562847] 'process raft request' (duration: 115.284849ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:47:13.853004Z","caller":"traceutil/trace.go:171","msg":"trace[1987394534] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"115.576838ms","start":"2026-04-23T14:47:13.737401Z","end":"2026-04-23T14:47:13.852978Z","steps":["trace[1987394534] 'process raft request' (duration: 115.385172ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:47:14.69837Z","caller":"traceutil/trace.go:171","msg":"trace[1920449219] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"154.648865ms","start":"2026-04-23T14:47:14.543675Z","end":"2026-04-23T14:47:14.698324Z","steps":["trace[1920449219] 'process raft request' (duration: 154.415138ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:48:00.102694Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710538066255530957,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T14:48:00.603533Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710538066255530957,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T14:48:01.103816Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710538066255530957,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T14:48:01.278655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.67698124s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-23T14:48:01.278758Z","caller":"traceutil/trace.go:171","msg":"trace[749192590] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.677152286s","start":"2026-04-23T14:47:59.601586Z","end":"2026-04-23T14:48:01.278738Z","steps":["trace[749192590] 'agreement among raft nodes before linearized reading' (duration: 1.677009621s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:48:01.278812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:47:59.601566Z","time spent":"1.677235089s","remote":"127.0.0.1:34522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/23 14:48:01 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-23T14:48:01.415161Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.131482885s","expected-duration":"1s"} {"level":"info","ts":"2026-04-23T14:48:01.415849Z","caller":"traceutil/trace.go:171","msg":"trace[770933732] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"2.132243286s","start":"2026-04-23T14:47:59.283584Z","end":"2026-04-23T14:48:01.415827Z","steps":["trace[770933732] 'process raft request' (duration: 2.132101752s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:48:01.416724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:47:59.283569Z","time spent":"2.132396721s","remote":"127.0.0.1:34758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T14:48:01.420311Z","caller":"traceutil/trace.go:171","msg":"trace[1725360342] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"1.640117585s","start":"2026-04-23T14:47:59.780174Z","end":"2026-04-23T14:48:01.420291Z","steps":["trace[1725360342] 'process raft request' (duration: 1.639975052s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:48:01.420446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:47:59.780155Z","time spent":"1.640220789s","remote":"127.0.0.1:34758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T14:48:01.420468Z","caller":"traceutil/trace.go:171","msg":"trace[1823853111] linearizableReadLoop","detail":"{readStateIndex:978; appliedIndex:976; }","duration":"1.818756332s","start":"2026-04-23T14:47:59.601625Z","end":"2026-04-23T14:48:01.420382Z","steps":["trace[1823853111] 'read index received' (duration: 1.813967407s)","trace[1823853111] 'applied index is now lower than readState.Index' (duration: 4.787385ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T14:48:01.4206Z","caller":"traceutil/trace.go:171","msg":"trace[352347526] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"267.278445ms","start":"2026-04-23T14:48:01.153302Z","end":"2026-04-23T14:48:01.42058Z","steps":["trace[352347526] 'process raft request' (duration: 266.956617ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:48:01.420894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.998464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-23T14:48:01.420935Z","caller":"traceutil/trace.go:171","msg":"trace[1761014761] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:945; }","duration":"269.066126ms","start":"2026-04-23T14:48:01.151859Z","end":"2026-04-23T14:48:01.420925Z","steps":["trace[1761014761] 'agreement among raft nodes before linearized reading' (duration: 268.998344ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:48:01.420973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.354081643s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-23T14:48:01.420982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.159698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.44\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-23T14:48:01.421022Z","caller":"traceutil/trace.go:171","msg":"trace[1760052242] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:945; }","duration":"1.354168205s","start":"2026-04-23T14:48:00.066842Z","end":"2026-04-23T14:48:01.42101Z","steps":["trace[1760052242] 'agreement among raft nodes before linearized reading' (duration: 1.354053432s)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:48:01.421028Z","caller":"traceutil/trace.go:171","msg":"trace[184063839] range","detail":"{range_begin:/registry/masterleases/199.204.45.44; range_end:; response_count:1; response_revision:945; }","duration":"269.232371ms","start":"2026-04-23T14:48:01.151785Z","end":"2026-04-23T14:48:01.421018Z","steps":["trace[184063839] 'agreement among raft nodes before linearized reading' (duration: 269.120957ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:48:01.421029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.293015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T14:48:01.421053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:48:00.066821Z","time spent":"1.354226617s","remote":"127.0.0.1:34758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-23T14:48:01.421064Z","caller":"traceutil/trace.go:171","msg":"trace[516202588] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:945; }","duration":"142.354597ms","start":"2026-04-23T14:48:01.2787Z","end":"2026-04-23T14:48:01.421054Z","steps":["trace[516202588] 'agreement among raft nodes before linearized reading' (duration: 142.308395ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:48:01.420974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.354007261s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-23T14:48:01.421131Z","caller":"traceutil/trace.go:171","msg":"trace[1456328862] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:945; }","duration":"1.354224868s","start":"2026-04-23T14:48:00.066899Z","end":"2026-04-23T14:48:01.421124Z","steps":["trace[1456328862] 'agreement among raft nodes before linearized reading' (duration: 1.353982591s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:48:01.42114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.967633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-23T14:48:01.421155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:48:00.066872Z","time spent":"1.354277198s","remote":"127.0.0.1:34758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-23T14:48:01.421163Z","caller":"traceutil/trace.go:171","msg":"trace[91834697] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:945; }","duration":"268.990893ms","start":"2026-04-23T14:48:01.152166Z","end":"2026-04-23T14:48:01.421157Z","steps":["trace[91834697] 'agreement among raft nodes before linearized reading' (duration: 268.945842ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:48:01.421002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.621804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-23T14:48:01.421285Z","caller":"traceutil/trace.go:171","msg":"trace[1821327206] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:945; }","duration":"268.917911ms","start":"2026-04-23T14:48:01.152349Z","end":"2026-04-23T14:48:01.421267Z","steps":["trace[1821327206] 'agreement among raft nodes before linearized reading' (duration: 268.545121ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:48:01.421422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.525707263s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-23T14:48:01.421452Z","caller":"traceutil/trace.go:171","msg":"trace[609760004] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:945; }","duration":"1.525780264s","start":"2026-04-23T14:47:59.895664Z","end":"2026-04-23T14:48:01.421445Z","steps":["trace[609760004] 'agreement among raft nodes before linearized reading' (duration: 1.525188248s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:48:01.421477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:47:59.895643Z","time spent":"1.525824466s","remote":"127.0.0.1:34758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-23T14:48:01.421319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.349621828s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-23T14:48:01.421538Z","caller":"traceutil/trace.go:171","msg":"trace[1484963021] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:945; }","duration":"1.350159193s","start":"2026-04-23T14:48:00.071372Z","end":"2026-04-23T14:48:01.421532Z","steps":["trace[1484963021] 'agreement among raft nodes before linearized reading' (duration: 1.349486414s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:48:01.421556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:48:00.07136Z","time spent":"1.350191344s","remote":"127.0.0.1:34758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-23T14:48:01.543449Z","caller":"traceutil/trace.go:171","msg":"trace[986620612] linearizableReadLoop","detail":"{readStateIndex:986; appliedIndex:980; }","duration":"112.686434ms","start":"2026-04-23T14:48:01.430736Z","end":"2026-04-23T14:48:01.543422Z","steps":["trace[986620612] 'read index received' (duration: 9.036533ms)","trace[986620612] 'applied index is now lower than readState.Index' (duration: 103.648491ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T14:48:01.543532Z","caller":"traceutil/trace.go:171","msg":"trace[1952636161] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"117.50083ms","start":"2026-04-23T14:48:01.426017Z","end":"2026-04-23T14:48:01.543518Z","steps":["trace[1952636161] 'process raft request' (duration: 117.286554ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:48:01.543611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.860869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T14:48:01.543621Z","caller":"traceutil/trace.go:171","msg":"trace[116694574] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"117.540631ms","start":"2026-04-23T14:48:01.426045Z","end":"2026-04-23T14:48:01.543586Z","steps":["trace[116694574] 'process raft request' (duration: 117.297294ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:48:01.543685Z","caller":"traceutil/trace.go:171","msg":"trace[1582924739] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"117.690785ms","start":"2026-04-23T14:48:01.425985Z","end":"2026-04-23T14:48:01.543676Z","steps":["trace[1582924739] 'process raft request' (duration: 117.216952ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:48:01.543747Z","caller":"traceutil/trace.go:171","msg":"trace[1867081535] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"114.607238ms","start":"2026-04-23T14:48:01.429108Z","end":"2026-04-23T14:48:01.543715Z","steps":["trace[1867081535] 'process raft request' (duration: 114.268049ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:48:01.543926Z","caller":"traceutil/trace.go:171","msg":"trace[886723198] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:950; }","duration":"112.958872ms","start":"2026-04-23T14:48:01.430691Z","end":"2026-04-23T14:48:01.54365Z","steps":["trace[886723198] 'agreement among raft nodes before linearized reading' (duration: 112.843428ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:48:01.694975Z","caller":"traceutil/trace.go:171","msg":"trace[882111133] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"149.980211ms","start":"2026-04-23T14:48:01.544969Z","end":"2026-04-23T14:48:01.694949Z","steps":["trace[882111133] 'process raft request' (duration: 149.620911ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:48:01.695096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.55855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-04-23T14:48:01.695135Z","caller":"traceutil/trace.go:171","msg":"trace[1113827505] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:951; }","duration":"149.637881ms","start":"2026-04-23T14:48:01.54549Z","end":"2026-04-23T14:48:01.695128Z","steps":["trace[1113827505] 'agreement among raft nodes before linearized reading' (duration: 149.522048ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:48:01.694971Z","caller":"traceutil/trace.go:171","msg":"trace[60039282] linearizableReadLoop","detail":"{readStateIndex:987; appliedIndex:986; }","duration":"149.414496ms","start":"2026-04-23T14:48:01.545518Z","end":"2026-04-23T14:48:01.694932Z","steps":["trace[60039282] 'read index received' (duration: 149.126328ms)","trace[60039282] 'applied index is now lower than readState.Index' (duration: 287.368µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T14:49:02.74688Z","caller":"traceutil/trace.go:171","msg":"trace[1595616859] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"149.346102ms","start":"2026-04-23T14:49:02.597504Z","end":"2026-04-23T14:49:02.74685Z","steps":["trace[1595616859] 'process raft request' (duration: 149.186657ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:49:03.896229Z","caller":"traceutil/trace.go:171","msg":"trace[1645109705] linearizableReadLoop","detail":"{readStateIndex:1298; appliedIndex:1297; }","duration":"115.311825ms","start":"2026-04-23T14:49:03.780879Z","end":"2026-04-23T14:49:03.896191Z","steps":["trace[1645109705] 'read index received' (duration: 81.356641ms)","trace[1645109705] 'applied index is now lower than readState.Index' (duration: 33.953764ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T14:49:03.896423Z","caller":"traceutil/trace.go:171","msg":"trace[691580070] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"115.560202ms","start":"2026-04-23T14:49:03.780849Z","end":"2026-04-23T14:49:03.896409Z","steps":["trace[691580070] 'process raft request' (duration: 81.399113ms)","trace[691580070] 'compare' (duration: 33.753938ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T14:49:03.896577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.671796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T14:49:03.896676Z","caller":"traceutil/trace.go:171","msg":"trace[500228592] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1248; }","duration":"115.83617ms","start":"2026-04-23T14:49:03.780818Z","end":"2026-04-23T14:49:03.896654Z","steps":["trace[500228592] 'agreement among raft nodes before linearized reading' (duration: 115.667005ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:49:04.347475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.557525ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T14:49:04.347641Z","caller":"traceutil/trace.go:171","msg":"trace[2049126881] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"289.085256ms","start":"2026-04-23T14:49:04.058543Z","end":"2026-04-23T14:49:04.347628Z","steps":["trace[2049126881] 'process raft request' (duration: 133.145521ms)","trace[2049126881] 'compare' (duration: 155.093312ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T14:49:04.348007Z","caller":"traceutil/trace.go:171","msg":"trace[1955555777] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"277.018851ms","start":"2026-04-23T14:49:04.070926Z","end":"2026-04-23T14:49:04.347945Z","steps":["trace[1955555777] 'process raft request' (duration: 276.637821ms)"],"step_count":1}