{"level":"warn","ts":"2026-03-05T04:52:31.841393Z","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-05T04:52:31.841473Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.227: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://162.253.55.227:2380","--initial-cluster=instance=https://162.253.55.227:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.227:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.227: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-05T04:52:31.841529Z","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-05T04:52:31.841542Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.227:2380"]} {"level":"info","ts":"2026-03-05T04:52:31.841561Z","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-05T04:52:31.843381Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.227:2379"]} {"level":"info","ts":"2026-03-05T04:52:31.843453Z","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://162.253.55.227:2380"],"listen-peer-urls":["https://162.253.55.227:2380"],"advertise-client-urls":["https://162.253.55.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.227: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-05T04:52:31.848534Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.827871ms"} {"level":"info","ts":"2026-03-05T04:52:31.860596Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9bd37ed2859641e3","cluster-id":"47d0f4f0a1c3b0a9"} {"level":"info","ts":"2026-03-05T04:52:31.860782Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 switched to configuration voters=()"} {"level":"info","ts":"2026-03-05T04:52:31.860849Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became follower at term 0"} {"level":"info","ts":"2026-03-05T04:52:31.86087Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9bd37ed2859641e3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-05T04:52:31.86089Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became follower at term 1"} {"level":"info","ts":"2026-03-05T04:52:31.860958Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 switched to configuration voters=(11228457738614227427)"} {"level":"warn","ts":"2026-03-05T04:52:31.876894Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-05T04:52:31.885468Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-05T04:52:31.887014Z","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-05T04:52:31.88937Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9bd37ed2859641e3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-05T04:52:31.889675Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9bd37ed2859641e3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-05T04:52:31.892684Z","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-05T04:52:31.893886Z","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-05T04:52:31.893921Z","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-05T04:52:31.897132Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 switched to configuration voters=(11228457738614227427)"} {"level":"info","ts":"2026-03-05T04:52:31.897246Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"47d0f4f0a1c3b0a9","local-member-id":"9bd37ed2859641e3","added-peer-id":"9bd37ed2859641e3","added-peer-peer-urls":["https://162.253.55.227:2380"]} {"level":"info","ts":"2026-03-05T04:52:31.898616Z","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-05T04:52:31.898874Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.227:2380"} {"level":"info","ts":"2026-03-05T04:52:31.898966Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.227:2380"} {"level":"info","ts":"2026-03-05T04:52:31.899483Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-05T04:52:31.899586Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9bd37ed2859641e3","initial-advertise-peer-urls":["https://162.253.55.227:2380"],"listen-peer-urls":["https://162.253.55.227:2380"],"advertise-client-urls":["https://162.253.55.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-05T04:52:32.062265Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 is starting a new election at term 1"} {"level":"info","ts":"2026-03-05T04:52:32.062346Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-05T04:52:32.062374Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 received MsgPreVoteResp from 9bd37ed2859641e3 at term 1"} {"level":"info","ts":"2026-03-05T04:52:32.062395Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became candidate at term 2"} {"level":"info","ts":"2026-03-05T04:52:32.062407Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 received MsgVoteResp from 9bd37ed2859641e3 at term 2"} {"level":"info","ts":"2026-03-05T04:52:32.062424Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became leader at term 2"} {"level":"info","ts":"2026-03-05T04:52:32.062445Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9bd37ed2859641e3 elected leader 9bd37ed2859641e3 at term 2"} {"level":"info","ts":"2026-03-05T04:52:32.064453Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-05T04:52:32.065034Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9bd37ed2859641e3","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.227:2379]}","request-path":"/0/members/9bd37ed2859641e3/attributes","cluster-id":"47d0f4f0a1c3b0a9","publish-timeout":"7s"} {"level":"info","ts":"2026-03-05T04:52:32.06509Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-05T04:52:32.065619Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-05T04:52:32.065614Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"47d0f4f0a1c3b0a9","local-member-id":"9bd37ed2859641e3","cluster-version":"3.5"} {"level":"info","ts":"2026-03-05T04:52:32.065729Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-05T04:52:32.065774Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-05T04:52:32.068401Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.227:2379"} {"level":"info","ts":"2026-03-05T04:52:32.068892Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-05T04:52:32.069104Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-05T04:52:32.069182Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-05T04:52:39.912985Z","caller":"traceutil/trace.go:171","msg":"trace[370948549] transaction","detail":"{read_only:false; response_revision:287; number_of_response:1; }","duration":"145.394404ms","start":"2026-03-05T04:52:39.767511Z","end":"2026-03-05T04:52:39.912906Z","steps":["trace[370948549] 'process raft request' (duration: 144.983967ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T04:52:45.227369Z","caller":"traceutil/trace.go:171","msg":"trace[204162682] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"123.787839ms","start":"2026-03-05T04:52:45.103534Z","end":"2026-03-05T04:52:45.227321Z","steps":["trace[204162682] 'process raft request' (duration: 123.598136ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T04:52:52.312914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"939.541704ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-05T04:52:52.31306Z","caller":"traceutil/trace.go:171","msg":"trace[1532932305] linearizableReadLoop","detail":"{readStateIndex:423; appliedIndex:422; }","duration":"1.388095849s","start":"2026-03-05T04:52:50.92494Z","end":"2026-03-05T04:52:52.313036Z","steps":["trace[1532932305] 'read index received' (duration: 448.28879ms)","trace[1532932305] 'applied index is now lower than readState.Index' (duration: 939.805459ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T04:52:52.313197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.388233961s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T04:52:52.313234Z","caller":"traceutil/trace.go:171","msg":"trace[1184679529] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:407; }","duration":"1.388326073s","start":"2026-03-05T04:52:50.924899Z","end":"2026-03-05T04:52:52.313225Z","steps":["trace[1184679529] 'agreement among raft nodes before linearized reading' (duration: 1.388238332s)"],"step_count":1} {"level":"warn","ts":"2026-03-05T04:52:52.313269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T04:52:50.924883Z","time spent":"1.388379164s","remote":"127.0.0.1:44616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-05T04:52:52.313336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"675.059777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-05T04:52:52.3134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.066302118s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-03-05T04:52:52.313373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.73616ms","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-05T04:52:52.313429Z","caller":"traceutil/trace.go:171","msg":"trace[2070811821] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:407; }","duration":"675.204978ms","start":"2026-03-05T04:52:51.638203Z","end":"2026-03-05T04:52:52.313408Z","steps":["trace[2070811821] 'agreement among raft nodes before linearized reading' (duration: 675.005645ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T04:52:52.313443Z","caller":"traceutil/trace.go:171","msg":"trace[11708169] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:407; }","duration":"1.066362349s","start":"2026-03-05T04:52:51.247069Z","end":"2026-03-05T04:52:52.313432Z","steps":["trace[11708169] 'agreement among raft nodes before linearized reading' (duration: 1.066233287s)"],"step_count":1} {"level":"warn","ts":"2026-03-05T04:52:52.313478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T04:52:51.638181Z","time spent":"675.287691ms","remote":"127.0.0.1:44850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-05T04:52:52.31349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T04:52:51.247011Z","time spent":"1.066457261s","remote":"127.0.0.1:44850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-05T04:52:52.313491Z","caller":"traceutil/trace.go:171","msg":"trace[1591347356] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:407; }","duration":"329.896523ms","start":"2026-03-05T04:52:51.983558Z","end":"2026-03-05T04:52:52.313455Z","steps":["trace[1591347356] 'agreement among raft nodes before linearized reading' (duration: 329.68211ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T04:52:52.313583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T04:52:51.983537Z","time spent":"329.997245ms","remote":"127.0.0.1:44850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-05T04:52:52.313478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.38414497s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-05T04:52:52.313803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"864.074707ms","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-05T04:52:52.313854Z","caller":"traceutil/trace.go:171","msg":"trace[1551071985] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:407; }","duration":"864.154418ms","start":"2026-03-05T04:52:51.449684Z","end":"2026-03-05T04:52:52.313838Z","steps":["trace[1551071985] 'agreement among raft nodes before linearized reading' (duration: 864.065276ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T04:52:52.313885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T04:52:51.449667Z","time spent":"864.209589ms","remote":"127.0.0.1:44850","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-03-05T04:52:52.31379Z","caller":"traceutil/trace.go:171","msg":"trace[1097592295] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:407; }","duration":"1.384644588s","start":"2026-03-05T04:52:50.929118Z","end":"2026-03-05T04:52:52.313763Z","steps":["trace[1097592295] 'agreement among raft nodes before linearized reading' (duration: 1.384066229s)"],"step_count":1} {"level":"warn","ts":"2026-03-05T04:52:52.314443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T04:52:50.929098Z","time spent":"1.38532706s","remote":"127.0.0.1:44612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-05T04:52:52.655403Z","caller":"traceutil/trace.go:171","msg":"trace[1387107970] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"337.50032ms","start":"2026-03-05T04:52:52.317877Z","end":"2026-03-05T04:52:52.655378Z","steps":["trace[1387107970] 'process raft request' (duration: 337.349557ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T04:52:52.656067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T04:52:52.317853Z","time spent":"337.625593ms","remote":"127.0.0.1:44850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-05T04:52:52.658161Z","caller":"traceutil/trace.go:171","msg":"trace[1883687413] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"340.137175ms","start":"2026-03-05T04:52:52.317995Z","end":"2026-03-05T04:52:52.658132Z","steps":["trace[1883687413] 'process raft request' (duration: 340.021063ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T04:52:52.65825Z","caller":"traceutil/trace.go:171","msg":"trace[1619224714] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"340.257167ms","start":"2026-03-05T04:52:52.317937Z","end":"2026-03-05T04:52:52.658194Z","steps":["trace[1619224714] 'process raft request' (duration: 339.905091ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T04:52:52.658497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T04:52:52.317984Z","time spent":"340.262878ms","remote":"127.0.0.1:44850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-05T04:52:52.658772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T04:52:52.317909Z","time spent":"340.581433ms","remote":"127.0.0.1:44850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-05T04:52:52.659349Z","caller":"traceutil/trace.go:171","msg":"trace[158303301] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"341.294545ms","start":"2026-03-05T04:52:52.318041Z","end":"2026-03-05T04:52:52.659335Z","steps":["trace[158303301] 'process raft request' (duration: 340.038014ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T04:52:52.659535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.82504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T04:52:52.659608Z","caller":"traceutil/trace.go:171","msg":"trace[459849976] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:411; }","duration":"336.918752ms","start":"2026-03-05T04:52:52.322671Z","end":"2026-03-05T04:52:52.65959Z","steps":["trace[459849976] 'agreement among raft nodes before linearized reading' (duration: 336.791689ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T04:52:52.659558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T04:52:52.318031Z","time spent":"341.353896ms","remote":"127.0.0.1:44850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-05T04:52:52.659844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T04:52:52.322662Z","time spent":"336.986763ms","remote":"127.0.0.1:44616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-05T04:52:52.659349Z","caller":"traceutil/trace.go:171","msg":"trace[286742982] linearizableReadLoop","detail":"{readStateIndex:427; appliedIndex:423; }","duration":"336.632135ms","start":"2026-03-05T04:52:52.322694Z","end":"2026-03-05T04:52:52.659326Z","steps":["trace[286742982] 'read index received' (duration: 332.599506ms)","trace[286742982] 'applied index is now lower than readState.Index' (duration: 4.029949ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T04:53:00.980462Z","caller":"traceutil/trace.go:171","msg":"trace[2062460052] transaction","detail":"{read_only:false; response_revision:440; number_of_response:1; }","duration":"480.305217ms","start":"2026-03-05T04:53:00.50012Z","end":"2026-03-05T04:53:00.980425Z","steps":["trace[2062460052] 'process raft request' (duration: 403.987173ms)","trace[2062460052] 'compare' (duration: 76.144482ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T04:53:00.980623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T04:53:00.50011Z","time spent":"480.442379ms","remote":"127.0.0.1:45074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4177,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-05T04:53:00.98225Z","caller":"traceutil/trace.go:171","msg":"trace[1795539793] transaction","detail":"{read_only:false; response_revision:441; number_of_response:1; }","duration":"479.820429ms","start":"2026-03-05T04:53:00.502377Z","end":"2026-03-05T04:53:00.982198Z","steps":["trace[1795539793] 'process raft request' (duration: 479.049747ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T04:53:00.982501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T04:53:00.502364Z","time spent":"480.019643ms","remote":"127.0.0.1:44790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15015,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-05T04:53:00.982953Z","caller":"traceutil/trace.go:171","msg":"trace[874054045] linearizableReadLoop","detail":"{readStateIndex:460; appliedIndex:457; }","duration":"283.732163ms","start":"2026-03-05T04:53:00.699201Z","end":"2026-03-05T04:53:00.982933Z","steps":["trace[874054045] 'read index received' (duration: 204.838208ms)","trace[874054045] 'applied index is now lower than readState.Index' (duration: 78.892735ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T04:53:00.983118Z","caller":"traceutil/trace.go:171","msg":"trace[1882815961] transaction","detail":"{read_only:false; response_revision:442; number_of_response:1; }","duration":"410.531827ms","start":"2026-03-05T04:53:00.572572Z","end":"2026-03-05T04:53:00.983104Z","steps":["trace[1882815961] 'process raft request' (duration: 409.609852ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T04:53:00.98329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T04:53:00.572531Z","time spent":"410.65947ms","remote":"127.0.0.1:44694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":317279,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-05T04:53:00.983349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.457672ms","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-03-05T04:53:00.98341Z","caller":"traceutil/trace.go:171","msg":"trace[362526626] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:442; }","duration":"277.561914ms","start":"2026-03-05T04:53:00.705833Z","end":"2026-03-05T04:53:00.983395Z","steps":["trace[362526626] 'agreement among raft nodes before linearized reading' (duration: 277.34704ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T04:53:00.983554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.347023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-05T04:53:00.9836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.057263ms","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-05T04:53:00.983635Z","caller":"traceutil/trace.go:171","msg":"trace[158232638] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:442; }","duration":"270.117643ms","start":"2026-03-05T04:53:00.713506Z","end":"2026-03-05T04:53:00.983624Z","steps":["trace[158232638] 'agreement among raft nodes before linearized reading' (duration: 270.050222ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T04:53:00.98364Z","caller":"traceutil/trace.go:171","msg":"trace[1255213940] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:442; }","duration":"284.495075ms","start":"2026-03-05T04:53:00.699119Z","end":"2026-03-05T04:53:00.983614Z","steps":["trace[1255213940] 'agreement among raft nodes before linearized reading' (duration: 284.341683ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T04:53:00.983743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.865022ms","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-05T04:53:00.983821Z","caller":"traceutil/trace.go:171","msg":"trace[1964588023] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:442; }","duration":"266.945003ms","start":"2026-03-05T04:53:00.716838Z","end":"2026-03-05T04:53:00.983783Z","steps":["trace[1964588023] 'agreement among raft nodes before linearized reading' (duration: 266.841721ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T04:53:03.164598Z","caller":"traceutil/trace.go:171","msg":"trace[989848518] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"158.085117ms","start":"2026-03-05T04:53:03.006478Z","end":"2026-03-05T04:53:03.164564Z","steps":["trace[989848518] 'process raft request' (duration: 154.888876ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T04:53:03.305085Z","caller":"traceutil/trace.go:171","msg":"trace[246900199] linearizableReadLoop","detail":"{readStateIndex:485; appliedIndex:483; }","duration":"295.692622ms","start":"2026-03-05T04:53:03.009366Z","end":"2026-03-05T04:53:03.305058Z","steps":["trace[246900199] 'read index received' (duration: 151.994811ms)","trace[246900199] 'applied index is now lower than readState.Index' (duration: 143.696641ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T04:53:03.305228Z","caller":"traceutil/trace.go:171","msg":"trace[1135178958] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"297.042552ms","start":"2026-03-05T04:53:03.008148Z","end":"2026-03-05T04:53:03.305191Z","steps":["trace[1135178958] 'process raft request' (duration: 291.77564ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T04:53:03.305287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.899095ms","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-05T04:53:03.305326Z","caller":"traceutil/trace.go:171","msg":"trace[2007557932] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:467; }","duration":"295.990896ms","start":"2026-03-05T04:53:03.009323Z","end":"2026-03-05T04:53:03.305314Z","steps":["trace[2007557932] 'agreement among raft nodes before linearized reading' (duration: 295.865964ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T04:53:03.305459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.037826ms","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-05T04:53:03.305575Z","caller":"traceutil/trace.go:171","msg":"trace[1705109717] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:467; }","duration":"294.193048ms","start":"2026-03-05T04:53:03.011357Z","end":"2026-03-05T04:53:03.30555Z","steps":["trace[1705109717] 'agreement among raft nodes before linearized reading' (duration: 294.004725ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T04:53:09.307269Z","caller":"traceutil/trace.go:171","msg":"trace[92213649] linearizableReadLoop","detail":"{readStateIndex:525; appliedIndex:524; }","duration":"379.508762ms","start":"2026-03-05T04:53:08.927736Z","end":"2026-03-05T04:53:09.307244Z","steps":["trace[92213649] 'read index received' (duration: 379.267198ms)","trace[92213649] 'applied index is now lower than readState.Index' (duration: 240.674µs)"],"step_count":2} {"level":"info","ts":"2026-03-05T04:53:09.307551Z","caller":"traceutil/trace.go:171","msg":"trace[613190229] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"428.795825ms","start":"2026-03-05T04:53:08.878741Z","end":"2026-03-05T04:53:09.307536Z","steps":["trace[613190229] 'process raft request' (duration: 428.357179ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T04:53:09.30767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T04:53:08.878722Z","time spent":"428.865466ms","remote":"127.0.0.1:44790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-05T04:53:09.307846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.788401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-03-05T04:53:09.307827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.064231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T04:53:09.307912Z","caller":"traceutil/trace.go:171","msg":"trace[1232887971] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:506; }","duration":"117.914323ms","start":"2026-03-05T04:53:09.189982Z","end":"2026-03-05T04:53:09.307896Z","steps":["trace[1232887971] 'agreement among raft nodes before linearized reading' (duration: 117.71226ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T04:53:09.307935Z","caller":"traceutil/trace.go:171","msg":"trace[1854024398] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:506; }","duration":"380.217503ms","start":"2026-03-05T04:53:08.927692Z","end":"2026-03-05T04:53:09.307909Z","steps":["trace[1854024398] 'agreement among raft nodes before linearized reading' (duration: 380.03573ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T04:53:09.307994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T04:53:08.927679Z","time spent":"380.306275ms","remote":"127.0.0.1:44616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-05T04:53:09.737616Z","caller":"traceutil/trace.go:171","msg":"trace[1854940277] linearizableReadLoop","detail":"{readStateIndex:526; appliedIndex:525; }","duration":"407.672925ms","start":"2026-03-05T04:53:09.329916Z","end":"2026-03-05T04:53:09.737589Z","steps":["trace[1854940277] 'read index received' (duration: 407.494143ms)","trace[1854940277] 'applied index is now lower than readState.Index' (duration: 177.762µs)"],"step_count":2} {"level":"info","ts":"2026-03-05T04:53:09.737658Z","caller":"traceutil/trace.go:171","msg":"trace[1119611844] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"425.606773ms","start":"2026-03-05T04:53:09.312016Z","end":"2026-03-05T04:53:09.737622Z","steps":["trace[1119611844] 'process raft request' (duration: 425.39102ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T04:53:09.737838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.890739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-05T04:53:09.737841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.546134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-05T04:53:09.737846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T04:53:09.312001Z","time spent":"425.734106ms","remote":"127.0.0.1:44850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-05T04:53:09.737888Z","caller":"traceutil/trace.go:171","msg":"trace[375108828] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:507; }","duration":"408.001711ms","start":"2026-03-05T04:53:09.329872Z","end":"2026-03-05T04:53:09.737873Z","steps":["trace[375108828] 'agreement among raft nodes before linearized reading' (duration: 407.865829ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T04:53:09.737895Z","caller":"traceutil/trace.go:171","msg":"trace[722736217] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:507; }","duration":"387.647706ms","start":"2026-03-05T04:53:09.350235Z","end":"2026-03-05T04:53:09.737883Z","steps":["trace[722736217] 'agreement among raft nodes before linearized reading' (duration: 387.511644ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T04:53:09.737925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T04:53:09.329851Z","time spent":"408.065751ms","remote":"127.0.0.1:44850","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":"warn","ts":"2026-03-05T04:53:09.737945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T04:53:09.350219Z","time spent":"387.718567ms","remote":"127.0.0.1:44850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-05T04:53:09.737998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.539649ms","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-05T04:53:09.738189Z","caller":"traceutil/trace.go:171","msg":"trace[93167835] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:507; }","duration":"370.832004ms","start":"2026-03-05T04:53:09.367334Z","end":"2026-03-05T04:53:09.738166Z","steps":["trace[93167835] 'agreement among raft nodes before linearized reading' (duration: 370.480739ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T04:53:09.738244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T04:53:09.367314Z","time spent":"370.919195ms","remote":"127.0.0.1:44850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-05T04:53:11.243614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.264821ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-05T04:53:11.243719Z","caller":"traceutil/trace.go:171","msg":"trace[797085056] linearizableReadLoop","detail":"{readStateIndex:530; appliedIndex:529; }","duration":"319.028798ms","start":"2026-03-05T04:53:10.924672Z","end":"2026-03-05T04:53:11.243701Z","steps":["trace[797085056] 'read index received' (duration: 128.082µs)","trace[797085056] 'applied index is now lower than readState.Index' (duration: 318.899306ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T04:53:11.243904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.22701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T04:53:11.243997Z","caller":"traceutil/trace.go:171","msg":"trace[366920641] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:510; }","duration":"319.361053ms","start":"2026-03-05T04:53:10.924618Z","end":"2026-03-05T04:53:11.243979Z","steps":["trace[366920641] 'agreement among raft nodes before linearized reading' (duration: 319.18736ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T04:53:11.244061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T04:53:10.924601Z","time spent":"319.446364ms","remote":"127.0.0.1:44616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-05T04:53:11.243903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.605272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T04:53:11.244217Z","caller":"traceutil/trace.go:171","msg":"trace[528593608] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:510; }","duration":"314.883086ms","start":"2026-03-05T04:53:10.929252Z","end":"2026-03-05T04:53:11.244135Z","steps":["trace[528593608] 'agreement among raft nodes before linearized reading' (duration: 314.595542ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T04:53:11.244365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T04:53:10.929237Z","time spent":"315.074979ms","remote":"127.0.0.1:44612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-05T04:53:13.236627Z","caller":"traceutil/trace.go:171","msg":"trace[1822486740] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"123.275699ms","start":"2026-03-05T04:53:13.113332Z","end":"2026-03-05T04:53:13.236608Z","steps":["trace[1822486740] 'process raft request' (duration: 121.651985ms)"],"step_count":1}