{"level":"warn","ts":"2026-02-06T18:42:28.963251Z","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-06T18:42:28.963405Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.204: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.204:2380","--initial-cluster=instance=https://162.253.55.204:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.204:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.204: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-06T18:42:28.963823Z","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-06T18:42:28.963867Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.204:2380"]} {"level":"info","ts":"2026-02-06T18:42:28.963899Z","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-06T18:42:28.964522Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.204:2379"]} {"level":"info","ts":"2026-02-06T18:42:28.966888Z","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://162.253.55.204:2380"],"listen-peer-urls":["https://162.253.55.204:2380"],"advertise-client-urls":["https://162.253.55.204:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.204:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.204: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-06T18:42:28.972329Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.170051ms"} {"level":"info","ts":"2026-02-06T18:42:28.97871Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a11c20306510c1f7","cluster-id":"97da89d0cbebd2b5"} {"level":"info","ts":"2026-02-06T18:42:28.978802Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 switched to configuration voters=()"} {"level":"info","ts":"2026-02-06T18:42:28.97885Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became follower at term 0"} {"level":"info","ts":"2026-02-06T18:42:28.978886Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a11c20306510c1f7 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-06T18:42:28.978907Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became follower at term 1"} {"level":"info","ts":"2026-02-06T18:42:28.97897Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 switched to configuration voters=(11609189331680412151)"} {"level":"warn","ts":"2026-02-06T18:42:28.987436Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-06T18:42:29.006012Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-06T18:42:29.013Z","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-06T18:42:29.017044Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a11c20306510c1f7","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-06T18:42:29.024111Z","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-06T18:42:29.026663Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a11c20306510c1f7","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-06T18:42:29.026804Z","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-06T18:42:29.026925Z","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-06T18:42:29.027046Z","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-06T18:42:29.027179Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.204:2380"} {"level":"info","ts":"2026-02-06T18:42:29.029449Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.204:2380"} {"level":"info","ts":"2026-02-06T18:42:29.027377Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 switched to configuration voters=(11609189331680412151)"} {"level":"info","ts":"2026-02-06T18:42:29.029629Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"97da89d0cbebd2b5","local-member-id":"a11c20306510c1f7","added-peer-id":"a11c20306510c1f7","added-peer-peer-urls":["https://162.253.55.204:2380"]} {"level":"info","ts":"2026-02-06T18:42:29.027457Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a11c20306510c1f7","initial-advertise-peer-urls":["https://162.253.55.204:2380"],"listen-peer-urls":["https://162.253.55.204:2380"],"advertise-client-urls":["https://162.253.55.204:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.204:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-06T18:42:29.027473Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-06T18:42:29.080248Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 is starting a new election at term 1"} {"level":"info","ts":"2026-02-06T18:42:29.080304Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-06T18:42:29.080325Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 received MsgPreVoteResp from a11c20306510c1f7 at term 1"} {"level":"info","ts":"2026-02-06T18:42:29.080375Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became candidate at term 2"} {"level":"info","ts":"2026-02-06T18:42:29.080409Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 received MsgVoteResp from a11c20306510c1f7 at term 2"} {"level":"info","ts":"2026-02-06T18:42:29.080431Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became leader at term 2"} {"level":"info","ts":"2026-02-06T18:42:29.080511Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a11c20306510c1f7 elected leader a11c20306510c1f7 at term 2"} {"level":"info","ts":"2026-02-06T18:42:29.083386Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a11c20306510c1f7","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.204:2379]}","request-path":"/0/members/a11c20306510c1f7/attributes","cluster-id":"97da89d0cbebd2b5","publish-timeout":"7s"} {"level":"info","ts":"2026-02-06T18:42:29.083456Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-06T18:42:29.083484Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-06T18:42:29.08352Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-06T18:42:29.083623Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-06T18:42:29.094629Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-06T18:42:29.094381Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-06T18:42:29.094429Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"97da89d0cbebd2b5","local-member-id":"a11c20306510c1f7","cluster-version":"3.5"} {"level":"info","ts":"2026-02-06T18:42:29.094993Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-06T18:42:29.095053Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-06T18:42:29.095996Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.204:2379"} {"level":"warn","ts":"2026-02-06T18:42:53.3891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:42:52.783687Z","time spent":"605.409195ms","remote":"127.0.0.1:47678","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-06T18:42:58.84678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.623844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T18:42:58.846865Z","caller":"traceutil/trace.go:171","msg":"trace[1545663397] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:510; }","duration":"184.756295ms","start":"2026-02-06T18:42:58.662082Z","end":"2026-02-06T18:42:58.846838Z","steps":["trace[1545663397] 'range keys from in-memory index tree' (duration: 184.563132ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T18:43:00.172486Z","caller":"traceutil/trace.go:171","msg":"trace[1102131090] linearizableReadLoop","detail":"{readStateIndex:531; appliedIndex:530; }","duration":"177.017317ms","start":"2026-02-06T18:42:59.995451Z","end":"2026-02-06T18:43:00.172469Z","steps":["trace[1102131090] 'read index received' (duration: 176.912976ms)","trace[1102131090] 'applied index is now lower than readState.Index' (duration: 103.571µs)"],"step_count":2} {"level":"warn","ts":"2026-02-06T18:43:00.172623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.1618ms","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-06T18:43:00.172638Z","caller":"traceutil/trace.go:171","msg":"trace[1267070363] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:514; }","duration":"177.217061ms","start":"2026-02-06T18:42:59.995417Z","end":"2026-02-06T18:43:00.172634Z","steps":["trace[1267070363] 'agreement among raft nodes before linearized reading' (duration: 177.11246ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T18:43:00.172713Z","caller":"traceutil/trace.go:171","msg":"trace[1429463320] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"227.383578ms","start":"2026-02-06T18:42:59.945324Z","end":"2026-02-06T18:43:00.172708Z","steps":["trace[1429463320] 'process raft request' (duration: 227.039993ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T18:43:01.872581Z","caller":"traceutil/trace.go:171","msg":"trace[401336605] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"146.489761ms","start":"2026-02-06T18:43:01.726073Z","end":"2026-02-06T18:43:01.872563Z","steps":["trace[401336605] 'process raft request' (duration: 146.235367ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:43:03.607608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.427147135s","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-02-06T18:43:03.607922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.426930543s","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-06T18:43:03.608007Z","caller":"traceutil/trace.go:171","msg":"trace[346186525] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:518; }","duration":"1.427041314s","start":"2026-02-06T18:43:02.180946Z","end":"2026-02-06T18:43:03.607987Z","steps":["trace[346186525] 'range keys from in-memory index tree' (duration: 1.4267891s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:43:03.608052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:43:02.180938Z","time spent":"1.427105035s","remote":"127.0.0.1:47878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-06T18:43:03.608131Z","caller":"traceutil/trace.go:171","msg":"trace[472218939] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:518; }","duration":"1.427531982s","start":"2026-02-06T18:43:02.180399Z","end":"2026-02-06T18:43:03.607931Z","steps":["trace[472218939] 'range keys from in-memory index tree' (duration: 1.426974293s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:43:03.608258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.42203458s","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-06T18:43:03.608296Z","caller":"traceutil/trace.go:171","msg":"trace[1463060941] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:518; }","duration":"1.422088541s","start":"2026-02-06T18:43:02.186197Z","end":"2026-02-06T18:43:03.608286Z","steps":["trace[1463060941] 'range keys from in-memory index tree' (duration: 1.421953178s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:43:03.608327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:43:02.186187Z","time spent":"1.422133902s","remote":"127.0.0.1:47878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-06T18:43:03.608469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:43:02.180382Z","time spent":"1.427896979s","remote":"127.0.0.1:47878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-06T18:43:03.608689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"944.886126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T18:43:03.608727Z","caller":"traceutil/trace.go:171","msg":"trace[1383014886] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:518; }","duration":"944.948857ms","start":"2026-02-06T18:43:02.663769Z","end":"2026-02-06T18:43:03.608718Z","steps":["trace[1383014886] 'range keys from in-memory index tree' (duration: 944.837825ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:43:03.608759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:43:02.663739Z","time spent":"945.013628ms","remote":"127.0.0.1:47660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-06T18:43:03.60918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"825.663463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.204\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-06T18:43:03.60932Z","caller":"traceutil/trace.go:171","msg":"trace[1406294820] range","detail":"{range_begin:/registry/masterleases/162.253.55.204; range_end:; response_count:1; response_revision:518; }","duration":"825.818755ms","start":"2026-02-06T18:43:02.783487Z","end":"2026-02-06T18:43:03.609305Z","steps":["trace[1406294820] 'range keys from in-memory index tree' (duration: 825.591891ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:43:03.609482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:43:02.783479Z","time spent":"825.989638ms","remote":"127.0.0.1:47678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/162.253.55.204\" "} {"level":"info","ts":"2026-02-06T18:43:03.804783Z","caller":"traceutil/trace.go:171","msg":"trace[1330045241] linearizableReadLoop","detail":"{readStateIndex:536; appliedIndex:536; }","duration":"190.420703ms","start":"2026-02-06T18:43:03.614341Z","end":"2026-02-06T18:43:03.804762Z","steps":["trace[1330045241] 'read index received' (duration: 190.413063ms)","trace[1330045241] 'applied index is now lower than readState.Index' (duration: 6.62µs)"],"step_count":2} {"level":"warn","ts":"2026-02-06T18:43:03.804958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.600066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T18:43:03.805014Z","caller":"traceutil/trace.go:171","msg":"trace[100159323] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:518; }","duration":"190.691537ms","start":"2026-02-06T18:43:03.614312Z","end":"2026-02-06T18:43:03.805004Z","steps":["trace[100159323] 'agreement among raft nodes before linearized reading' (duration: 190.598266ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T18:43:03.840572Z","caller":"traceutil/trace.go:171","msg":"trace[528936824] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"224.263065ms","start":"2026-02-06T18:43:03.616288Z","end":"2026-02-06T18:43:03.840551Z","steps":["trace[528936824] 'process raft request' (duration: 223.585104ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T18:43:03.840827Z","caller":"traceutil/trace.go:171","msg":"trace[1204249659] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"219.429994ms","start":"2026-02-06T18:43:03.621385Z","end":"2026-02-06T18:43:03.840815Z","steps":["trace[1204249659] 'process raft request' (duration: 219.108708ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T18:43:03.841156Z","caller":"traceutil/trace.go:171","msg":"trace[174797475] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"217.011704ms","start":"2026-02-06T18:43:03.624128Z","end":"2026-02-06T18:43:03.84114Z","steps":["trace[174797475] 'process raft request' (duration: 216.641398ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T18:43:11.11604Z","caller":"traceutil/trace.go:171","msg":"trace[691448055] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"409.707162ms","start":"2026-02-06T18:43:10.706316Z","end":"2026-02-06T18:43:11.116023Z","steps":["trace[691448055] 'process raft request' (duration: 409.59686ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:43:11.116356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:43:10.706289Z","time spent":"409.796613ms","remote":"127.0.0.1:47820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T18:43:12.391508Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976811716848252882,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-06T18:43:12.643428Z","caller":"traceutil/trace.go:171","msg":"trace[1273327226] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"753.900924ms","start":"2026-02-06T18:43:11.889509Z","end":"2026-02-06T18:43:12.64341Z","steps":["trace[1273327226] 'process raft request' (duration: 753.788632ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:43:12.643547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:43:11.8895Z","time spent":"753.986735ms","remote":"127.0.0.1:47878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T18:43:12.668177Z","caller":"traceutil/trace.go:171","msg":"trace[1547082059] linearizableReadLoop","detail":"{readStateIndex:583; appliedIndex:582; }","duration":"776.673194ms","start":"2026-02-06T18:43:11.891486Z","end":"2026-02-06T18:43:12.668159Z","steps":["trace[1547082059] 'read index received' (duration: 752.025594ms)","trace[1547082059] 'applied index is now lower than readState.Index' (duration: 24.64643ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T18:43:12.668546Z","caller":"traceutil/trace.go:171","msg":"trace[1831602466] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"582.867658ms","start":"2026-02-06T18:43:12.085668Z","end":"2026-02-06T18:43:12.668536Z","steps":["trace[1831602466] 'process raft request' (duration: 582.37807ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:43:12.668729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:43:12.085655Z","time spent":"582.948949ms","remote":"127.0.0.1:47878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T18:43:12.669594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"778.104267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-06T18:43:12.669671Z","caller":"traceutil/trace.go:171","msg":"trace[2062463270] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:564; }","duration":"778.214668ms","start":"2026-02-06T18:43:11.89145Z","end":"2026-02-06T18:43:12.669664Z","steps":["trace[2062463270] 'agreement among raft nodes before linearized reading' (duration: 778.062116ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:43:12.669765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:43:11.891433Z","time spent":"778.324711ms","remote":"127.0.0.1:47878","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-02-06T18:43:12.669962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"777.795001ms","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-02-06T18:43:12.670013Z","caller":"traceutil/trace.go:171","msg":"trace[1695688208] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:564; }","duration":"777.861482ms","start":"2026-02-06T18:43:11.892146Z","end":"2026-02-06T18:43:12.670008Z","steps":["trace[1695688208] 'agreement among raft nodes before linearized reading' (duration: 777.73342ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:43:12.670041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:43:11.892139Z","time spent":"777.897272ms","remote":"127.0.0.1:47878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-06T18:43:12.67047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"772.511913ms","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-02-06T18:43:12.670542Z","caller":"traceutil/trace.go:171","msg":"trace[951170571] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:564; }","duration":"772.626564ms","start":"2026-02-06T18:43:11.897909Z","end":"2026-02-06T18:43:12.670535Z","steps":["trace[951170571] 'agreement among raft nodes before linearized reading' (duration: 772.466322ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:43:12.67061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:43:11.897901Z","time spent":"772.696305ms","remote":"127.0.0.1:47878","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-02-06T18:43:13.287633Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976811716848252887,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-06T18:43:13.510172Z","caller":"traceutil/trace.go:171","msg":"trace[962598137] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"831.348754ms","start":"2026-02-06T18:43:12.678683Z","end":"2026-02-06T18:43:13.510032Z","steps":["trace[962598137] 'process raft request' (duration: 827.379388ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:43:13.510257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:43:12.678667Z","time spent":"831.546537ms","remote":"127.0.0.1:47878","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-02-06T18:43:13.524313Z","caller":"traceutil/trace.go:171","msg":"trace[950394853] linearizableReadLoop","detail":"{readStateIndex:586; appliedIndex:583; }","duration":"737.677086ms","start":"2026-02-06T18:43:12.786617Z","end":"2026-02-06T18:43:13.524294Z","steps":["trace[950394853] 'read index received' (duration: 719.455434ms)","trace[950394853] 'applied index is now lower than readState.Index' (duration: 18.220982ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T18:43:13.524536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"737.91673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.204\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-06T18:43:13.524577Z","caller":"traceutil/trace.go:171","msg":"trace[39199732] range","detail":"{range_begin:/registry/masterleases/162.253.55.204; range_end:; response_count:1; response_revision:567; }","duration":"738.004811ms","start":"2026-02-06T18:43:12.786565Z","end":"2026-02-06T18:43:13.52457Z","steps":["trace[39199732] 'agreement among raft nodes before linearized reading' (duration: 737.800167ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T18:43:13.524741Z","caller":"traceutil/trace.go:171","msg":"trace[26736463] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"846.022207ms","start":"2026-02-06T18:43:12.678709Z","end":"2026-02-06T18:43:13.524731Z","steps":["trace[26736463] 'process raft request' (duration: 845.465028ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:43:13.524849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:43:12.786548Z","time spent":"738.105383ms","remote":"127.0.0.1:47678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/162.253.55.204\" "} {"level":"info","ts":"2026-02-06T18:43:13.525267Z","caller":"traceutil/trace.go:171","msg":"trace[1988817793] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"845.336816ms","start":"2026-02-06T18:43:12.67992Z","end":"2026-02-06T18:43:13.525257Z","steps":["trace[1988817793] 'process raft request' (duration: 844.346009ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:43:13.526879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:43:12.678693Z","time spent":"846.073758ms","remote":"127.0.0.1:47878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T18:43:13.527983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:43:12.679913Z","time spent":"845.378026ms","remote":"127.0.0.1:47878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T18:43:15.816645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.100675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-skrph\" ","response":"range_response_count:1 size:17171"} {"level":"info","ts":"2026-02-06T18:43:15.816712Z","caller":"traceutil/trace.go:171","msg":"trace[1951457365] range","detail":"{range_begin:/registry/pods/kube-system/cilium-skrph; range_end:; response_count:1; response_revision:581; }","duration":"123.196288ms","start":"2026-02-06T18:43:15.693501Z","end":"2026-02-06T18:43:15.816697Z","steps":["trace[1951457365] 'range keys from in-memory index tree' (duration: 123.030144ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:43:15.816719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.529263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T18:43:15.816765Z","caller":"traceutil/trace.go:171","msg":"trace[758694869] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:581; }","duration":"155.601904ms","start":"2026-02-06T18:43:15.66115Z","end":"2026-02-06T18:43:15.816752Z","steps":["trace[758694869] 'range keys from in-memory index tree' (duration: 155.473542ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T18:43:15.816894Z","caller":"traceutil/trace.go:171","msg":"trace[1709901757] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"121.875566ms","start":"2026-02-06T18:43:15.695012Z","end":"2026-02-06T18:43:15.816888Z","steps":["trace[1709901757] 'process raft request' (duration: 103.499691ms)","trace[1709901757] 'compare' (duration: 17.937047ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T18:43:26.972279Z","caller":"traceutil/trace.go:171","msg":"trace[1225264268] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"126.879507ms","start":"2026-02-06T18:43:26.845385Z","end":"2026-02-06T18:43:26.972265Z","steps":["trace[1225264268] 'process raft request' (duration: 124.031413ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T18:43:26.972633Z","caller":"traceutil/trace.go:171","msg":"trace[176404112] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"127.05847ms","start":"2026-02-06T18:43:26.845569Z","end":"2026-02-06T18:43:26.972628Z","steps":["trace[176404112] 'process raft request' (duration: 126.650281ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:43:26.973722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.755011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-j7t8w\" ","response":"range_response_count:1 size:5023"} {"level":"info","ts":"2026-02-06T18:43:26.973744Z","caller":"traceutil/trace.go:171","msg":"trace[1270862940] range","detail":"{range_begin:/registry/pods/kube-system/coredns-77cccfdc44-j7t8w; range_end:; response_count:1; response_revision:718; }","duration":"125.801252ms","start":"2026-02-06T18:43:26.847936Z","end":"2026-02-06T18:43:26.973737Z","steps":["trace[1270862940] 'agreement among raft nodes before linearized reading' (duration: 125.70249ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T18:43:26.97544Z","caller":"traceutil/trace.go:171","msg":"trace[859326946] linearizableReadLoop","detail":"{readStateIndex:741; appliedIndex:739; }","duration":"124.293778ms","start":"2026-02-06T18:43:26.847954Z","end":"2026-02-06T18:43:26.972247Z","steps":["trace[859326946] 'read index received' (duration: 121.554147ms)","trace[859326946] 'applied index is now lower than readState.Index' (duration: 2.739111ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T18:44:38.395141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.73959ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T18:44:38.395718Z","caller":"traceutil/trace.go:171","msg":"trace[510356590] linearizableReadLoop","detail":"{readStateIndex:1029; appliedIndex:1028; }","duration":"519.159407ms","start":"2026-02-06T18:44:37.876544Z","end":"2026-02-06T18:44:38.395704Z","steps":["trace[510356590] 'read index received' (duration: 124.646652ms)","trace[510356590] 'applied index is now lower than readState.Index' (duration: 394.511485ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T18:44:38.39589Z","caller":"traceutil/trace.go:171","msg":"trace[1439210815] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"602.434238ms","start":"2026-02-06T18:44:37.793444Z","end":"2026-02-06T18:44:38.395878Z","steps":["trace[1439210815] 'process raft request' (duration: 207.834472ms)","trace[1439210815] 'compare' (duration: 393.673339ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T18:44:38.39602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:44:37.793433Z","time spent":"602.53511ms","remote":"127.0.0.1:47878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T18:44:38.396035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.476913ms","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-02-06T18:44:38.396149Z","caller":"traceutil/trace.go:171","msg":"trace[1454694629] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:990; }","duration":"519.597225ms","start":"2026-02-06T18:44:37.876534Z","end":"2026-02-06T18:44:38.396132Z","steps":["trace[1454694629] 'agreement among raft nodes before linearized reading' (duration: 519.34922ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:44:38.396195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:44:37.876495Z","time spent":"519.691367ms","remote":"127.0.0.1:47878","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-02-06T18:44:38.411017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.801592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-06T18:44:38.411053Z","caller":"traceutil/trace.go:171","msg":"trace[710231780] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:990; }","duration":"523.854702ms","start":"2026-02-06T18:44:37.88719Z","end":"2026-02-06T18:44:38.411045Z","steps":["trace[710231780] 'agreement among raft nodes before linearized reading' (duration: 523.798411ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:44:38.411075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:44:37.887184Z","time spent":"523.885693ms","remote":"127.0.0.1:47878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":7,"response size":31,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-02-06T18:44:38.41138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.406381ms","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-06T18:44:38.41141Z","caller":"traceutil/trace.go:171","msg":"trace[1747587463] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:990; }","duration":"297.472392ms","start":"2026-02-06T18:44:38.113929Z","end":"2026-02-06T18:44:38.411402Z","steps":["trace[1747587463] 'agreement among raft nodes before linearized reading' (duration: 297.40159ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:44:38.411572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.309264ms","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-02-06T18:44:38.411605Z","caller":"traceutil/trace.go:171","msg":"trace[844816482] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:990; }","duration":"300.376325ms","start":"2026-02-06T18:44:38.11122Z","end":"2026-02-06T18:44:38.411597Z","steps":["trace[844816482] 'agreement among raft nodes before linearized reading' (duration: 300.299414ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T18:44:38.411634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T18:44:38.111202Z","time spent":"300.426566ms","remote":"127.0.0.1:47878","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\" "}