{"level":"warn","ts":"2026-02-09T18:53:38.716625Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-09T18:53:38.71673Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.6: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.6:2380","--initial-cluster=instance=https://199.204.45.6:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.6:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.6: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-02-09T18:53:38.717794Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-09T18:53:38.717821Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.6:2380"]} {"level":"info","ts":"2026-02-09T18:53:38.718214Z","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-02-09T18:53:38.719007Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.6:2379"]} {"level":"info","ts":"2026-02-09T18:53:38.719982Z","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":2,"max-cpu-available":2,"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.6:2380"],"listen-peer-urls":["https://199.204.45.6:2380"],"advertise-client-urls":["https://199.204.45.6:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.6:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.6: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-02-09T18:53:38.726504Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.168058ms"} {"level":"info","ts":"2026-02-09T18:53:38.740306Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4ec288cbdba84d36","cluster-id":"9b45faacd38a820f"} {"level":"info","ts":"2026-02-09T18:53:38.740958Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 switched to configuration voters=()"} {"level":"info","ts":"2026-02-09T18:53:38.740984Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 became follower at term 0"} {"level":"info","ts":"2026-02-09T18:53:38.741036Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4ec288cbdba84d36 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-09T18:53:38.741052Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 became follower at term 1"} {"level":"info","ts":"2026-02-09T18:53:38.741127Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 switched to configuration voters=(5675248889585225014)"} {"level":"warn","ts":"2026-02-09T18:53:38.758864Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-09T18:53:38.763033Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-09T18:53:38.764914Z","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-02-09T18:53:38.767853Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4ec288cbdba84d36","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-09T18:53:38.768601Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4ec288cbdba84d36","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-09T18:53:38.768787Z","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-02-09T18:53:38.768853Z","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-02-09T18:53:38.76888Z","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-02-09T18:53:38.769445Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 switched to configuration voters=(5675248889585225014)"} {"level":"info","ts":"2026-02-09T18:53:38.769561Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9b45faacd38a820f","local-member-id":"4ec288cbdba84d36","added-peer-id":"4ec288cbdba84d36","added-peer-peer-urls":["https://199.204.45.6:2380"]} {"level":"info","ts":"2026-02-09T18:53:38.786689Z","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-02-09T18:53:38.786945Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.6:2380"} {"level":"info","ts":"2026-02-09T18:53:38.786966Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.6:2380"} {"level":"info","ts":"2026-02-09T18:53:38.786927Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4ec288cbdba84d36","initial-advertise-peer-urls":["https://199.204.45.6:2380"],"listen-peer-urls":["https://199.204.45.6:2380"],"advertise-client-urls":["https://199.204.45.6:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.6:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-09T18:53:38.787024Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-09T18:53:39.442107Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 is starting a new election at term 1"} {"level":"info","ts":"2026-02-09T18:53:39.442151Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-09T18:53:39.442174Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 received MsgPreVoteResp from 4ec288cbdba84d36 at term 1"} {"level":"info","ts":"2026-02-09T18:53:39.442185Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 became candidate at term 2"} {"level":"info","ts":"2026-02-09T18:53:39.44219Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 received MsgVoteResp from 4ec288cbdba84d36 at term 2"} {"level":"info","ts":"2026-02-09T18:53:39.442198Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 became leader at term 2"} {"level":"info","ts":"2026-02-09T18:53:39.442205Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4ec288cbdba84d36 elected leader 4ec288cbdba84d36 at term 2"} {"level":"info","ts":"2026-02-09T18:53:39.443948Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4ec288cbdba84d36","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.6:2379]}","request-path":"/0/members/4ec288cbdba84d36/attributes","cluster-id":"9b45faacd38a820f","publish-timeout":"7s"} {"level":"info","ts":"2026-02-09T18:53:39.444393Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-09T18:53:39.444399Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-09T18:53:39.444543Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-09T18:53:39.44586Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-09T18:53:39.445878Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-09T18:53:39.44783Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9b45faacd38a820f","local-member-id":"4ec288cbdba84d36","cluster-version":"3.5"} {"level":"info","ts":"2026-02-09T18:53:39.447905Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-09T18:53:39.447928Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-09T18:53:39.449551Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-09T18:53:39.451297Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.6:2379"} {"level":"info","ts":"2026-02-09T18:53:53.019386Z","caller":"traceutil/trace.go:171","msg":"trace[1431758205] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"369.632379ms","start":"2026-02-09T18:53:52.649732Z","end":"2026-02-09T18:53:53.019364Z","steps":["trace[1431758205] 'process raft request' (duration: 369.046856ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:53:53.022608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:53:52.649721Z","time spent":"369.758892ms","remote":"127.0.0.1:47712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5235,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T18:53:53.39189Z","caller":"traceutil/trace.go:171","msg":"trace[1227026678] linearizableReadLoop","detail":"{readStateIndex:412; appliedIndex:411; }","duration":"215.845395ms","start":"2026-02-09T18:53:53.176026Z","end":"2026-02-09T18:53:53.391871Z","steps":["trace[1227026678] 'read index received' (duration: 215.62795ms)","trace[1227026678] 'applied index is now lower than readState.Index' (duration: 216.485µs)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:53:53.392234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.769827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-09T18:53:53.392271Z","caller":"traceutil/trace.go:171","msg":"trace[834877235] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:402; }","duration":"204.827817ms","start":"2026-02-09T18:53:53.187436Z","end":"2026-02-09T18:53:53.392263Z","steps":["trace[834877235] 'agreement among raft nodes before linearized reading' (duration: 204.779556ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:53:53.392521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.020772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-09T18:53:53.392541Z","caller":"traceutil/trace.go:171","msg":"trace[1012268181] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:402; }","duration":"205.054633ms","start":"2026-02-09T18:53:53.187481Z","end":"2026-02-09T18:53:53.392536Z","steps":["trace[1012268181] 'agreement among raft nodes before linearized reading' (duration: 205.020542ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:53:53.392774Z","caller":"traceutil/trace.go:171","msg":"trace[610874377] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"356.622558ms","start":"2026-02-09T18:53:53.036145Z","end":"2026-02-09T18:53:53.392768Z","steps":["trace[610874377] 'process raft request' (duration: 355.566494ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:53:53.392845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:53:53.036123Z","time spent":"356.683969ms","remote":"127.0.0.1:47712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7204,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T18:53:53.392988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.121271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-09T18:53:53.39301Z","caller":"traceutil/trace.go:171","msg":"trace[1154322189] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:402; }","duration":"217.006472ms","start":"2026-02-09T18:53:53.175997Z","end":"2026-02-09T18:53:53.393003Z","steps":["trace[1154322189] 'agreement among raft nodes before linearized reading' (duration: 216.123031ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:54:09.125738Z","caller":"traceutil/trace.go:171","msg":"trace[1816486609] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"195.480368ms","start":"2026-02-09T18:54:08.930236Z","end":"2026-02-09T18:54:09.125716Z","steps":["trace[1816486609] 'process raft request' (duration: 195.282104ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:54:11.638385Z","caller":"traceutil/trace.go:171","msg":"trace[723278421] linearizableReadLoop","detail":"{readStateIndex:528; appliedIndex:527; }","duration":"131.892593ms","start":"2026-02-09T18:54:11.506472Z","end":"2026-02-09T18:54:11.638364Z","steps":["trace[723278421] 'read index received' (duration: 131.764399ms)","trace[723278421] 'applied index is now lower than readState.Index' (duration: 127.524µs)"],"step_count":2} {"level":"info","ts":"2026-02-09T18:54:11.638587Z","caller":"traceutil/trace.go:171","msg":"trace[1515443938] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"154.885255ms","start":"2026-02-09T18:54:11.483694Z","end":"2026-02-09T18:54:11.638579Z","steps":["trace[1515443938] 'process raft request' (duration: 154.589658ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:54:11.638725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.2524ms","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-02-09T18:54:11.638748Z","caller":"traceutil/trace.go:171","msg":"trace[1912927033] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:514; }","duration":"132.341162ms","start":"2026-02-09T18:54:11.506399Z","end":"2026-02-09T18:54:11.63874Z","steps":["trace[1912927033] 'agreement among raft nodes before linearized reading' (duration: 132.290641ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:54:11.638835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.023697ms","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-02-09T18:54:11.638852Z","caller":"traceutil/trace.go:171","msg":"trace[1466630594] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:514; }","duration":"125.054159ms","start":"2026-02-09T18:54:11.513792Z","end":"2026-02-09T18:54:11.638846Z","steps":["trace[1466630594] 'agreement among raft nodes before linearized reading' (duration: 125.025888ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:54:18.183688Z","caller":"traceutil/trace.go:171","msg":"trace[406450967] linearizableReadLoop","detail":"{readStateIndex:564; appliedIndex:563; }","duration":"435.371169ms","start":"2026-02-09T18:54:17.748301Z","end":"2026-02-09T18:54:18.183672Z","steps":["trace[406450967] 'read index received' (duration: 396.063663ms)","trace[406450967] 'applied index is now lower than readState.Index' (duration: 39.307016ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T18:54:18.183868Z","caller":"traceutil/trace.go:171","msg":"trace[1026273584] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"446.561754ms","start":"2026-02-09T18:54:17.737296Z","end":"2026-02-09T18:54:18.183858Z","steps":["trace[1026273584] 'process raft request' (duration: 407.058594ms)","trace[1026273584] 'compare' (duration: 39.247334ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:54:18.18393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:54:17.737286Z","time spent":"446.596584ms","remote":"127.0.0.1:47712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17148,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T18:54:18.184033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.161626ms","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-02-09T18:54:18.184049Z","caller":"traceutil/trace.go:171","msg":"trace[741131089] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:549; }","duration":"456.183206ms","start":"2026-02-09T18:54:17.72786Z","end":"2026-02-09T18:54:18.184044Z","steps":["trace[741131089] 'agreement among raft nodes before linearized reading' (duration: 456.149956ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:54:18.184061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:54:17.727856Z","time spent":"456.201907ms","remote":"127.0.0.1:47782","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-02-09T18:54:18.184133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.286068ms","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-02-09T18:54:18.184145Z","caller":"traceutil/trace.go:171","msg":"trace[393280150] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:549; }","duration":"456.309019ms","start":"2026-02-09T18:54:17.727832Z","end":"2026-02-09T18:54:18.184141Z","steps":["trace[393280150] 'agreement among raft nodes before linearized reading' (duration: 456.285589ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:54:18.184174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:54:17.727827Z","time spent":"456.34429ms","remote":"127.0.0.1:47782","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-02-09T18:54:18.198861Z","caller":"traceutil/trace.go:171","msg":"trace[489920487] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"426.100294ms","start":"2026-02-09T18:54:17.772749Z","end":"2026-02-09T18:54:18.198849Z","steps":["trace[489920487] 'process raft request' (duration: 425.767907ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:54:18.198942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:54:17.772731Z","time spent":"426.168925ms","remote":"127.0.0.1:47620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":722,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-09T18:54:18.199086Z","caller":"traceutil/trace.go:171","msg":"trace[1354519073] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"422.258303ms","start":"2026-02-09T18:54:17.776822Z","end":"2026-02-09T18:54:18.19908Z","steps":["trace[1354519073] 'process raft request' (duration: 421.750483ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:54:18.19913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:54:17.776792Z","time spent":"422.312295ms","remote":"127.0.0.1:47782","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-02-09T18:54:18.199198Z","caller":"traceutil/trace.go:171","msg":"trace[708471262] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"417.065344ms","start":"2026-02-09T18:54:17.782128Z","end":"2026-02-09T18:54:18.199194Z","steps":["trace[708471262] 'process raft request' (duration: 416.471111ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:54:18.199221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:54:17.782121Z","time spent":"417.086845ms","remote":"127.0.0.1:47986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T18:54:18.224147Z","caller":"traceutil/trace.go:171","msg":"trace[1610361860] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"118.064231ms","start":"2026-02-09T18:54:18.106066Z","end":"2026-02-09T18:54:18.22413Z","steps":["trace[1610361860] 'process raft request' (duration: 117.617621ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:54:18.226449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.743311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T18:54:18.226537Z","caller":"traceutil/trace.go:171","msg":"trace[769762699] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:556; }","duration":"449.833412ms","start":"2026-02-09T18:54:17.77666Z","end":"2026-02-09T18:54:18.226493Z","steps":["trace[769762699] 'agreement among raft nodes before linearized reading' (duration: 449.686569ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:54:18.226584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:54:17.776652Z","time spent":"449.919184ms","remote":"127.0.0.1:47590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-09T18:54:34.795028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.389212ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-09T18:54:34.795132Z","caller":"traceutil/trace.go:171","msg":"trace[64399610] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"407.145847ms","start":"2026-02-09T18:54:34.387976Z","end":"2026-02-09T18:54:34.795122Z","steps":["trace[64399610] 'process raft request' (duration: 407.111066ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:54:34.795168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:54:34.387962Z","time spent":"407.191688ms","remote":"127.0.0.1:47782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T18:54:34.795313Z","caller":"traceutil/trace.go:171","msg":"trace[98160038] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"408.807351ms","start":"2026-02-09T18:54:34.3865Z","end":"2026-02-09T18:54:34.795308Z","steps":["trace[98160038] 'process raft request' (duration: 277.027011ms)","trace[98160038] 'compare' (duration: 131.197758ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:54:34.795346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:54:34.386485Z","time spent":"408.848761ms","remote":"127.0.0.1:47782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T18:54:46.266027Z","caller":"traceutil/trace.go:171","msg":"trace[411395759] linearizableReadLoop","detail":"{readStateIndex:767; appliedIndex:766; }","duration":"190.017602ms","start":"2026-02-09T18:54:46.075991Z","end":"2026-02-09T18:54:46.266008Z","steps":["trace[411395759] 'read index received' (duration: 189.881409ms)","trace[411395759] 'applied index is now lower than readState.Index' (duration: 135.573µs)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:54:46.266167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.147255ms","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-02-09T18:54:46.266195Z","caller":"traceutil/trace.go:171","msg":"trace[1010010591] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:745; }","duration":"190.250517ms","start":"2026-02-09T18:54:46.075938Z","end":"2026-02-09T18:54:46.266189Z","steps":["trace[1010010591] 'agreement among raft nodes before linearized reading' (duration: 190.153535ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:54:46.266429Z","caller":"traceutil/trace.go:171","msg":"trace[1859394861] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"239.3709ms","start":"2026-02-09T18:54:46.027049Z","end":"2026-02-09T18:54:46.26642Z","steps":["trace[1859394861] 'process raft request' (duration: 238.871869ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:54:47.245628Z","caller":"traceutil/trace.go:171","msg":"trace[1175313410] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"365.48437ms","start":"2026-02-09T18:54:46.880082Z","end":"2026-02-09T18:54:47.245566Z","steps":["trace[1175313410] 'process raft request' (duration: 365.319676ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:54:47.245769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:54:46.880073Z","time spent":"365.616822ms","remote":"127.0.0.1:47782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T18:54:47.24599Z","caller":"traceutil/trace.go:171","msg":"trace[1787287271] linearizableReadLoop","detail":"{readStateIndex:769; appliedIndex:769; }","duration":"348.581614ms","start":"2026-02-09T18:54:46.8974Z","end":"2026-02-09T18:54:47.245982Z","steps":["trace[1787287271] 'read index received' (duration: 348.578524ms)","trace[1787287271] 'applied index is now lower than readState.Index' (duration: 2.59µs)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:54:47.24615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.26376ms","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-02-09T18:54:47.246193Z","caller":"traceutil/trace.go:171","msg":"trace[623451357] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:747; }","duration":"232.334982ms","start":"2026-02-09T18:54:47.013847Z","end":"2026-02-09T18:54:47.246182Z","steps":["trace[623451357] 'agreement among raft nodes before linearized reading' (duration: 232.193369ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:54:47.246479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.073865ms","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-02-09T18:54:47.246517Z","caller":"traceutil/trace.go:171","msg":"trace[1714631194] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:747; }","duration":"349.144367ms","start":"2026-02-09T18:54:46.897364Z","end":"2026-02-09T18:54:47.246509Z","steps":["trace[1714631194] 'agreement among raft nodes before linearized reading' (duration: 349.057044ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:54:47.246545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:54:46.897349Z","time spent":"349.189919ms","remote":"127.0.0.1:47782","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-02-09T18:55:08.958383Z","caller":"traceutil/trace.go:171","msg":"trace[1185262013] transaction","detail":"{read_only:false; response_revision:864; number_of_response:1; }","duration":"317.822792ms","start":"2026-02-09T18:55:08.640543Z","end":"2026-02-09T18:55:08.958366Z","steps":["trace[1185262013] 'process raft request' (duration: 317.676689ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:08.958485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:08.640534Z","time spent":"317.897244ms","remote":"127.0.0.1:47782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T18:55:18.180744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.499899ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-09T18:55:18.180824Z","caller":"traceutil/trace.go:171","msg":"trace[1802012583] linearizableReadLoop","detail":"{readStateIndex:922; appliedIndex:921; }","duration":"182.41309ms","start":"2026-02-09T18:55:17.998401Z","end":"2026-02-09T18:55:18.180814Z","steps":["trace[1802012583] 'read index received' (duration: 29.805679ms)","trace[1802012583] 'applied index is now lower than readState.Index' (duration: 152.606731ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:55:18.181019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.608934ms","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-02-09T18:55:18.181036Z","caller":"traceutil/trace.go:171","msg":"trace[853374280] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:890; }","duration":"182.655865ms","start":"2026-02-09T18:55:17.998373Z","end":"2026-02-09T18:55:18.181029Z","steps":["trace[853374280] 'agreement among raft nodes before linearized reading' (duration: 182.571374ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:21.813656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.892326ms","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-02-09T18:55:21.813729Z","caller":"traceutil/trace.go:171","msg":"trace[531580169] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:898; }","duration":"313.006688ms","start":"2026-02-09T18:55:21.500703Z","end":"2026-02-09T18:55:21.81371Z","steps":["trace[531580169] 'range keys from in-memory index tree' (duration: 312.762683ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:21.813771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:21.500687Z","time spent":"313.07199ms","remote":"127.0.0.1:47782","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-02-09T18:55:21.814009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.451345ms","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-02-09T18:55:21.814039Z","caller":"traceutil/trace.go:171","msg":"trace[1696581143] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:898; }","duration":"300.492377ms","start":"2026-02-09T18:55:21.513537Z","end":"2026-02-09T18:55:21.814029Z","steps":["trace[1696581143] 'range keys from in-memory index tree' (duration: 300.377084ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:21.814065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:21.513531Z","time spent":"300.527888ms","remote":"127.0.0.1:47782","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-02-09T18:55:21.814424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.942546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-09T18:55:21.814488Z","caller":"traceutil/trace.go:171","msg":"trace[1870368893] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:898; }","duration":"127.045548ms","start":"2026-02-09T18:55:21.687425Z","end":"2026-02-09T18:55:21.814471Z","steps":["trace[1870368893] 'count revisions from in-memory index tree' (duration: 126.885484ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:22.077549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.488638ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-09T18:55:22.077665Z","caller":"traceutil/trace.go:171","msg":"trace[118245801] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"259.314413ms","start":"2026-02-09T18:55:21.818333Z","end":"2026-02-09T18:55:22.077647Z","steps":["trace[118245801] 'process raft request' (duration: 119.660132ms)","trace[118245801] 'compare' (duration: 139.323064ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T18:55:23.081077Z","caller":"traceutil/trace.go:171","msg":"trace[302332761] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"106.226051ms","start":"2026-02-09T18:55:22.97483Z","end":"2026-02-09T18:55:23.081057Z","steps":["trace[302332761] 'process raft request' (duration: 105.984356ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:55:31.656892Z","caller":"traceutil/trace.go:171","msg":"trace[2131222806] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"161.995272ms","start":"2026-02-09T18:55:31.494882Z","end":"2026-02-09T18:55:31.656877Z","steps":["trace[2131222806] 'process raft request' (duration: 161.637763ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:58:55.601897Z","caller":"traceutil/trace.go:171","msg":"trace[101771751] transaction","detail":"{read_only:false; response_revision:1841; number_of_response:1; }","duration":"103.576065ms","start":"2026-02-09T18:58:55.498302Z","end":"2026-02-09T18:58:55.601878Z","steps":["trace[101771751] 'process raft request' (duration: 103.176917ms)"],"step_count":1}