{"level":"warn","ts":"2026-03-10T18:01:12.794024Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-10T18:01:12.794092Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.222:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.222:2380","--initial-cluster=instance=https://199.19.213.222:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.222:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.222: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-03-10T18:01:12.794149Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-10T18:01:12.794161Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.222:2380"]} {"level":"info","ts":"2026-03-10T18:01:12.794179Z","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-03-10T18:01:12.794772Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.222:2379"]} {"level":"info","ts":"2026-03-10T18:01:12.794837Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.222:2380"],"listen-peer-urls":["https://199.19.213.222:2380"],"advertise-client-urls":["https://199.19.213.222:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.222:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.222: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-03-10T18:01:12.798766Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.753793ms"} {"level":"info","ts":"2026-03-10T18:01:12.805036Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b78f8b1ca6063edc","cluster-id":"eff609df5818f5a9"} {"level":"info","ts":"2026-03-10T18:01:12.805109Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc switched to configuration voters=()"} {"level":"info","ts":"2026-03-10T18:01:12.805135Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc became follower at term 0"} {"level":"info","ts":"2026-03-10T18:01:12.805149Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b78f8b1ca6063edc [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-10T18:01:12.805167Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc became follower at term 1"} {"level":"info","ts":"2026-03-10T18:01:12.805206Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc switched to configuration voters=(13226943585771208412)"} {"level":"warn","ts":"2026-03-10T18:01:12.814521Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-10T18:01:12.81828Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-10T18:01:12.822078Z","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-03-10T18:01:12.828536Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b78f8b1ca6063edc","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-10T18:01:12.829307Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b78f8b1ca6063edc","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-10T18:01:12.829416Z","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-03-10T18:01:12.829514Z","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-03-10T18:01:12.829525Z","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-03-10T18:01:12.830733Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc switched to configuration voters=(13226943585771208412)"} {"level":"info","ts":"2026-03-10T18:01:12.836586Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"eff609df5818f5a9","local-member-id":"b78f8b1ca6063edc","added-peer-id":"b78f8b1ca6063edc","added-peer-peer-urls":["https://199.19.213.222:2380"]} {"level":"info","ts":"2026-03-10T18:01:12.83906Z","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-03-10T18:01:12.839159Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.222:2380"} {"level":"info","ts":"2026-03-10T18:01:12.839244Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.222:2380"} {"level":"info","ts":"2026-03-10T18:01:12.839537Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b78f8b1ca6063edc","initial-advertise-peer-urls":["https://199.19.213.222:2380"],"listen-peer-urls":["https://199.19.213.222:2380"],"advertise-client-urls":["https://199.19.213.222:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.222:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-10T18:01:12.839607Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-10T18:01:13.306399Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc is starting a new election at term 1"} {"level":"info","ts":"2026-03-10T18:01:13.306477Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc became pre-candidate at term 1"} {"level":"info","ts":"2026-03-10T18:01:13.30665Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc received MsgPreVoteResp from b78f8b1ca6063edc at term 1"} {"level":"info","ts":"2026-03-10T18:01:13.306684Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc became candidate at term 2"} {"level":"info","ts":"2026-03-10T18:01:13.306768Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc received MsgVoteResp from b78f8b1ca6063edc at term 2"} {"level":"info","ts":"2026-03-10T18:01:13.317138Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc became leader at term 2"} {"level":"info","ts":"2026-03-10T18:01:13.317175Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b78f8b1ca6063edc elected leader b78f8b1ca6063edc at term 2"} {"level":"info","ts":"2026-03-10T18:01:13.329989Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b78f8b1ca6063edc","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.222:2379]}","request-path":"/0/members/b78f8b1ca6063edc/attributes","cluster-id":"eff609df5818f5a9","publish-timeout":"7s"} {"level":"info","ts":"2026-03-10T18:01:13.330302Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-10T18:01:13.330302Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-10T18:01:13.330446Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-10T18:01:13.330465Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-10T18:01:13.33051Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-10T18:01:13.333464Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-10T18:01:13.334079Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"eff609df5818f5a9","local-member-id":"b78f8b1ca6063edc","cluster-version":"3.5"} {"level":"info","ts":"2026-03-10T18:01:13.334174Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-10T18:01:13.3342Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-10T18:01:13.355215Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.222:2379"} {"level":"info","ts":"2026-03-10T18:01:37.206176Z","caller":"traceutil/trace.go:171","msg":"trace[1962542708] linearizableReadLoop","detail":"{readStateIndex:428; appliedIndex:427; }","duration":"211.536774ms","start":"2026-03-10T18:01:36.994612Z","end":"2026-03-10T18:01:37.206149Z","steps":["trace[1962542708] 'read index received' (duration: 166.935153ms)","trace[1962542708] 'applied index is now lower than readState.Index' (duration: 44.600481ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T18:01:37.206239Z","caller":"traceutil/trace.go:171","msg":"trace[707403526] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"254.030962ms","start":"2026-03-10T18:01:36.95216Z","end":"2026-03-10T18:01:37.206191Z","steps":["trace[707403526] 'process raft request' (duration: 209.381181ms)","trace[707403526] 'compare' (duration: 44.48883ms)"],"step_count":2} {"level":"warn","ts":"2026-03-10T18:01:37.206624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.153357ms","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-03-10T18:01:37.206655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.966817ms","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-03-10T18:01:37.206733Z","caller":"traceutil/trace.go:171","msg":"trace[1114437621] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:410; }","duration":"199.310839ms","start":"2026-03-10T18:01:37.007407Z","end":"2026-03-10T18:01:37.206718Z","steps":["trace[1114437621] 'agreement among raft nodes before linearized reading' (duration: 199.097157ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:01:37.206618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.628451ms","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-03-10T18:01:37.206829Z","caller":"traceutil/trace.go:171","msg":"trace[1021433549] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:410; }","duration":"212.194378ms","start":"2026-03-10T18:01:36.994616Z","end":"2026-03-10T18:01:37.206811Z","steps":["trace[1021433549] 'agreement among raft nodes before linearized reading' (duration: 211.769245ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:01:37.20683Z","caller":"traceutil/trace.go:171","msg":"trace[1753626036] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:410; }","duration":"189.858082ms","start":"2026-03-10T18:01:37.016934Z","end":"2026-03-10T18:01:37.206792Z","steps":["trace[1753626036] 'agreement among raft nodes before linearized reading' (duration: 189.5265ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:01:37.207317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.695031ms","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-03-10T18:01:37.207421Z","caller":"traceutil/trace.go:171","msg":"trace[413204336] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:410; }","duration":"212.802472ms","start":"2026-03-10T18:01:36.994606Z","end":"2026-03-10T18:01:37.207408Z","steps":["trace[413204336] 'agreement among raft nodes before linearized reading' (duration: 211.676575ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:01:41.840659Z","caller":"traceutil/trace.go:171","msg":"trace[1089550103] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"139.560136ms","start":"2026-03-10T18:01:41.701069Z","end":"2026-03-10T18:01:41.840629Z","steps":["trace[1089550103] 'process raft request' (duration: 139.198733ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:01:42.866418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.704963ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-10T18:01:42.867014Z","caller":"traceutil/trace.go:171","msg":"trace[2065060205] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"995.761098ms","start":"2026-03-10T18:01:41.871226Z","end":"2026-03-10T18:01:42.866987Z","steps":["trace[2065060205] 'process raft request' (duration: 995.357974ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:01:42.866772Z","caller":"traceutil/trace.go:171","msg":"trace[443007722] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"1.000453109s","start":"2026-03-10T18:01:41.866242Z","end":"2026-03-10T18:01:42.866695Z","steps":["trace[443007722] 'process raft request' (duration: 444.83201ms)","trace[443007722] 'compare' (duration: 554.512082ms)"],"step_count":2} {"level":"warn","ts":"2026-03-10T18:01:42.867201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:01:41.871192Z","time spent":"995.900678ms","remote":"127.0.0.1:47108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":505,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-10T18:01:42.867701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:01:41.866221Z","time spent":"1.001279505s","remote":"127.0.0.1:47108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-10T18:01:42.873263Z","caller":"traceutil/trace.go:171","msg":"trace[1543445183] linearizableReadLoop","detail":"{readStateIndex:445; appliedIndex:442; }","duration":"239.892825ms","start":"2026-03-10T18:01:42.633341Z","end":"2026-03-10T18:01:42.873234Z","steps":["trace[1543445183] 'read index received' (duration: 219.000313ms)","trace[1543445183] 'applied index is now lower than readState.Index' (duration: 20.890741ms)"],"step_count":2} {"level":"warn","ts":"2026-03-10T18:01:42.873379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.034976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-10T18:01:42.873482Z","caller":"traceutil/trace.go:171","msg":"trace[724041947] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:426; }","duration":"240.178387ms","start":"2026-03-10T18:01:42.633289Z","end":"2026-03-10T18:01:42.873467Z","steps":["trace[724041947] 'agreement among raft nodes before linearized reading' (duration: 240.033186ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:01:51.685484Z","caller":"traceutil/trace.go:171","msg":"trace[1379785375] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"305.14521ms","start":"2026-03-10T18:01:51.380306Z","end":"2026-03-10T18:01:51.685451Z","steps":["trace[1379785375] 'process raft request' (duration: 304.982948ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:01:51.685822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:01:51.380293Z","time spent":"305.277801ms","remote":"127.0.0.1:47184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-10T18:01:51.948437Z","caller":"traceutil/trace.go:171","msg":"trace[1104095844] linearizableReadLoop","detail":"{readStateIndex:527; appliedIndex:524; }","duration":"549.28309ms","start":"2026-03-10T18:01:51.39913Z","end":"2026-03-10T18:01:51.948413Z","steps":["trace[1104095844] 'read index received' (duration: 286.22089ms)","trace[1104095844] 'applied index is now lower than readState.Index' (duration: 263.06096ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T18:01:51.948463Z","caller":"traceutil/trace.go:171","msg":"trace[225428347] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"567.506006ms","start":"2026-03-10T18:01:51.380926Z","end":"2026-03-10T18:01:51.948432Z","steps":["trace[225428347] 'process raft request' (duration: 527.43567ms)","trace[225428347] 'compare' (duration: 39.887315ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T18:01:51.948541Z","caller":"traceutil/trace.go:171","msg":"trace[1247947968] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"564.804158ms","start":"2026-03-10T18:01:51.383707Z","end":"2026-03-10T18:01:51.948512Z","steps":["trace[1247947968] 'process raft request' (duration: 564.661607ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:01:51.948618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:01:51.380909Z","time spent":"567.633766ms","remote":"127.0.0.1:47184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-10T18:01:51.948639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.494171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-10T18:01:51.948678Z","caller":"traceutil/trace.go:171","msg":"trace[276114050] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:507; }","duration":"549.552002ms","start":"2026-03-10T18:01:51.399115Z","end":"2026-03-10T18:01:51.948667Z","steps":["trace[276114050] 'agreement among raft nodes before linearized reading' (duration: 549.38043ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:01:51.948701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:01:51.399059Z","time spent":"549.638033ms","remote":"127.0.0.1:47184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-10T18:01:51.948693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:01:51.383694Z","time spent":"564.924089ms","remote":"127.0.0.1:47184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-10T18:01:51.948696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.58548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-xhc5j\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-03-10T18:01:51.948735Z","caller":"traceutil/trace.go:171","msg":"trace[1600452009] range","detail":"{range_begin:/registry/pods/kube-system/cilium-xhc5j; range_end:; response_count:1; response_revision:507; }","duration":"436.67066ms","start":"2026-03-10T18:01:51.512053Z","end":"2026-03-10T18:01:51.948724Z","steps":["trace[1600452009] 'agreement among raft nodes before linearized reading' (duration: 436.513779ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:01:51.948751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.966864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-10T18:01:51.948778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:01:51.512034Z","time spent":"436.736221ms","remote":"127.0.0.1:47088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16989,"request content":"key:\"/registry/pods/kube-system/cilium-xhc5j\" "} {"level":"info","ts":"2026-03-10T18:01:51.9488Z","caller":"traceutil/trace.go:171","msg":"trace[1675800858] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:507; }","duration":"315.046224ms","start":"2026-03-10T18:01:51.63374Z","end":"2026-03-10T18:01:51.948786Z","steps":["trace[1675800858] 'agreement among raft nodes before linearized reading' (duration: 314.974593ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:01:51.948881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:01:51.633725Z","time spent":"315.146024ms","remote":"127.0.0.1:46944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-10T18:01:52.463191Z","caller":"traceutil/trace.go:171","msg":"trace[2098958555] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"503.608694ms","start":"2026-03-10T18:01:51.959558Z","end":"2026-03-10T18:01:52.463166Z","steps":["trace[2098958555] 'process raft request' (duration: 503.412683ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:01:52.463411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:01:51.959539Z","time spent":"503.778585ms","remote":"127.0.0.1:47088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-10T18:01:52.661753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.82541ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-03-10T18:01:53.928494Z","caller":"traceutil/trace.go:171","msg":"trace[1960660053] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"234.058271ms","start":"2026-03-10T18:01:53.694415Z","end":"2026-03-10T18:01:53.928473Z","steps":["trace[1960660053] 'process raft request' (duration: 233.89364ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:01:54.568048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.610578ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-10T18:01:54.568179Z","caller":"traceutil/trace.go:171","msg":"trace[1671496067] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"612.009666ms","start":"2026-03-10T18:01:53.956151Z","end":"2026-03-10T18:01:54.56816Z","steps":["trace[1671496067] 'process raft request' (duration: 434.206816ms)","trace[1671496067] 'compare' (duration: 177.372506ms)"],"step_count":2} {"level":"warn","ts":"2026-03-10T18:01:54.568243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:01:53.956134Z","time spent":"612.079506ms","remote":"127.0.0.1:47184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-10T18:01:54.650907Z","caller":"traceutil/trace.go:171","msg":"trace[136533383] linearizableReadLoop","detail":"{readStateIndex:533; appliedIndex:531; }","duration":"656.268662ms","start":"2026-03-10T18:01:53.994618Z","end":"2026-03-10T18:01:54.650886Z","steps":["trace[136533383] 'read index received' (duration: 395.753732ms)","trace[136533383] 'applied index is now lower than readState.Index' (duration: 260.50886ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T18:01:54.650991Z","caller":"traceutil/trace.go:171","msg":"trace[2142831841] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"694.608096ms","start":"2026-03-10T18:01:53.956323Z","end":"2026-03-10T18:01:54.650931Z","steps":["trace[2142831841] 'process raft request' (duration: 694.439175ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:01:54.651134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.503294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-10T18:01:54.651164Z","caller":"traceutil/trace.go:171","msg":"trace[913476332] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:512; }","duration":"656.589975ms","start":"2026-03-10T18:01:53.994565Z","end":"2026-03-10T18:01:54.651155Z","steps":["trace[913476332] 'agreement among raft nodes before linearized reading' (duration: 656.403604ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:01:54.651196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:01:53.994511Z","time spent":"656.677935ms","remote":"127.0.0.1:47184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-10T18:01:54.651292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:01:53.956316Z","time spent":"694.783617ms","remote":"127.0.0.1:47184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-10T18:01:56.117791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.751346ms","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-03-10T18:01:56.117886Z","caller":"traceutil/trace.go:171","msg":"trace[1324865942] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:517; }","duration":"184.879836ms","start":"2026-03-10T18:01:55.93299Z","end":"2026-03-10T18:01:56.11787Z","steps":["trace[1324865942] 'range keys from in-memory index tree' (duration: 184.622804ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:01:56.118235Z","caller":"traceutil/trace.go:171","msg":"trace[1386056871] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"181.672436ms","start":"2026-03-10T18:01:55.936526Z","end":"2026-03-10T18:01:56.118199Z","steps":["trace[1386056871] 'process raft request' (duration: 179.779844ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:02:14.865404Z","caller":"traceutil/trace.go:171","msg":"trace[2129934496] linearizableReadLoop","detail":"{readStateIndex:738; appliedIndex:737; }","duration":"126.61444ms","start":"2026-03-10T18:02:14.738767Z","end":"2026-03-10T18:02:14.865382Z","steps":["trace[2129934496] 'read index received' (duration: 50.791826ms)","trace[2129934496] 'applied index is now lower than readState.Index' (duration: 75.821864ms)"],"step_count":2} {"level":"warn","ts":"2026-03-10T18:02:14.865614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.823188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-10T18:02:14.865645Z","caller":"traceutil/trace.go:171","msg":"trace[674332495] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:712; }","duration":"126.917832ms","start":"2026-03-10T18:02:14.738717Z","end":"2026-03-10T18:02:14.865634Z","steps":["trace[674332495] 'agreement among raft nodes before linearized reading' (duration: 126.751015ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:02:14.865754Z","caller":"traceutil/trace.go:171","msg":"trace[1181156385] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"132.843061ms","start":"2026-03-10T18:02:14.7329Z","end":"2026-03-10T18:02:14.865743Z","steps":["trace[1181156385] 'process raft request' (duration: 56.738066ms)","trace[1181156385] 'compare' (duration: 75.657998ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T18:03:27.203458Z","caller":"traceutil/trace.go:171","msg":"trace[1322368576] linearizableReadLoop","detail":"{readStateIndex:997; appliedIndex:996; }","duration":"325.875162ms","start":"2026-03-10T18:03:26.877558Z","end":"2026-03-10T18:03:27.203433Z","steps":["trace[1322368576] 'read index received' (duration: 325.633958ms)","trace[1322368576] 'applied index is now lower than readState.Index' (duration: 240.184µs)"],"step_count":2} {"level":"warn","ts":"2026-03-10T18:03:27.203919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.810147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.222\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-03-10T18:03:27.203977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.320089ms","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-03-10T18:03:27.204025Z","caller":"traceutil/trace.go:171","msg":"trace[1082353387] range","detail":"{range_begin:/registry/masterleases/199.19.213.222; range_end:; response_count:1; response_revision:955; }","duration":"294.98689ms","start":"2026-03-10T18:03:26.909013Z","end":"2026-03-10T18:03:27.204Z","steps":["trace[1082353387] 'agreement among raft nodes before linearized reading' (duration: 294.698224ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:03:27.204032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.455893ms","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-03-10T18:03:27.204161Z","caller":"traceutil/trace.go:171","msg":"trace[542597042] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:955; }","duration":"326.651896ms","start":"2026-03-10T18:03:26.877486Z","end":"2026-03-10T18:03:27.204138Z","steps":["trace[542597042] 'agreement among raft nodes before linearized reading' (duration: 326.365491ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:03:27.204243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:03:26.877457Z","time spent":"326.7735ms","remote":"127.0.0.1:47184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-10T18:03:27.204029Z","caller":"traceutil/trace.go:171","msg":"trace[1671687157] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:955; }","duration":"163.413901ms","start":"2026-03-10T18:03:27.040601Z","end":"2026-03-10T18:03:27.204015Z","steps":["trace[1671687157] 'agreement among raft nodes before linearized reading' (duration: 163.255048ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T18:03:27.205056Z","caller":"traceutil/trace.go:171","msg":"trace[551992772] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"611.972111ms","start":"2026-03-10T18:03:26.593028Z","end":"2026-03-10T18:03:27.205Z","steps":["trace[551992772] 'process raft request' (duration: 610.264008ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T18:03:27.205192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T18:03:26.593001Z","time spent":"612.107294ms","remote":"127.0.0.1:47184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"}