{"level":"warn","ts":"2026-03-02T02:01:26.156059Z","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-02T02:01:26.156137Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.200: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.200:2380","--initial-cluster=instance=https://162.253.55.200:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.200:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.200: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-02T02:01:26.15622Z","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-02T02:01:26.156235Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.200:2380"]} {"level":"info","ts":"2026-03-02T02:01:26.156254Z","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-02T02:01:26.157007Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.200:2379"]} {"level":"info","ts":"2026-03-02T02:01:26.157097Z","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.200:2380"],"listen-peer-urls":["https://162.253.55.200:2380"],"advertise-client-urls":["https://162.253.55.200:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.200:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.200: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-02T02:01:26.1614Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.061384ms"} {"level":"info","ts":"2026-03-02T02:01:26.170238Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e10bb51ed7578852","cluster-id":"aa14459abd2161c3"} {"level":"info","ts":"2026-03-02T02:01:26.17029Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 switched to configuration voters=()"} {"level":"info","ts":"2026-03-02T02:01:26.170314Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became follower at term 0"} {"level":"info","ts":"2026-03-02T02:01:26.170326Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e10bb51ed7578852 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-02T02:01:26.170338Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became follower at term 1"} {"level":"info","ts":"2026-03-02T02:01:26.170366Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 switched to configuration voters=(16216254027344087122)"} {"level":"warn","ts":"2026-03-02T02:01:26.17889Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-02T02:01:26.180176Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-02T02:01:26.181032Z","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-02T02:01:26.182766Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e10bb51ed7578852","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-02T02:01:26.182982Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e10bb51ed7578852","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-02T02:01:26.183301Z","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-02T02:01:26.183346Z","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-02T02:01:26.183537Z","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-02T02:01:26.186858Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 switched to configuration voters=(16216254027344087122)"} {"level":"info","ts":"2026-03-02T02:01:26.187271Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"aa14459abd2161c3","local-member-id":"e10bb51ed7578852","added-peer-id":"e10bb51ed7578852","added-peer-peer-urls":["https://162.253.55.200:2380"]} {"level":"info","ts":"2026-03-02T02:01:26.188492Z","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-02T02:01:26.188614Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.200:2380"} {"level":"info","ts":"2026-03-02T02:01:26.188666Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.200:2380"} {"level":"info","ts":"2026-03-02T02:01:26.188771Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e10bb51ed7578852","initial-advertise-peer-urls":["https://162.253.55.200:2380"],"listen-peer-urls":["https://162.253.55.200:2380"],"advertise-client-urls":["https://162.253.55.200:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.200:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-02T02:01:26.188849Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-02T02:01:26.972346Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 is starting a new election at term 1"} {"level":"info","ts":"2026-03-02T02:01:26.972413Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-02T02:01:26.972443Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 received MsgPreVoteResp from e10bb51ed7578852 at term 1"} {"level":"info","ts":"2026-03-02T02:01:26.972468Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became candidate at term 2"} {"level":"info","ts":"2026-03-02T02:01:26.972485Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 received MsgVoteResp from e10bb51ed7578852 at term 2"} {"level":"info","ts":"2026-03-02T02:01:26.972507Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became leader at term 2"} {"level":"info","ts":"2026-03-02T02:01:26.972549Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e10bb51ed7578852 elected leader e10bb51ed7578852 at term 2"} {"level":"info","ts":"2026-03-02T02:01:26.973804Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e10bb51ed7578852","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.200:2379]}","request-path":"/0/members/e10bb51ed7578852/attributes","cluster-id":"aa14459abd2161c3","publish-timeout":"7s"} {"level":"info","ts":"2026-03-02T02:01:26.976634Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-02T02:01:26.976656Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-02T02:01:26.97688Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-02T02:01:26.976963Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-02T02:01:26.977022Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-02T02:01:26.977915Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"aa14459abd2161c3","local-member-id":"e10bb51ed7578852","cluster-version":"3.5"} {"level":"info","ts":"2026-03-02T02:01:26.978041Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-02T02:01:26.978096Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-02T02:01:26.978606Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.200:2379"} {"level":"info","ts":"2026-03-02T02:01:26.979913Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-02T02:01:29.880899Z","caller":"traceutil/trace.go:171","msg":"trace[2029213204] transaction","detail":"{read_only:false; response_revision:148; number_of_response:1; }","duration":"207.036971ms","start":"2026-03-02T02:01:29.673837Z","end":"2026-03-02T02:01:29.880874Z","steps":["trace[2029213204] 'process raft request' (duration: 139.962651ms)","trace[2029213204] 'compare' (duration: 66.948478ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T02:01:30.353376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.165977ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-02T02:01:30.353563Z","caller":"traceutil/trace.go:171","msg":"trace[1692817579] transaction","detail":"{read_only:false; response_revision:150; number_of_response:1; }","duration":"429.66014ms","start":"2026-03-02T02:01:29.923872Z","end":"2026-03-02T02:01:30.353532Z","steps":["trace[1692817579] 'process raft request' (duration: 77.651623ms)","trace[1692817579] 'compare' (duration: 350.901394ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T02:01:30.353763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:01:29.92385Z","time spent":"429.868863ms","remote":"127.0.0.1:41360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":732,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-02T02:01:30.877347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.687003ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-02T02:01:30.877457Z","caller":"traceutil/trace.go:171","msg":"trace[1062475881] linearizableReadLoop","detail":"{readStateIndex:155; appliedIndex:154; }","duration":"483.110858ms","start":"2026-03-02T02:01:30.394328Z","end":"2026-03-02T02:01:30.877439Z","steps":["trace[1062475881] 'read index received' (duration: 113.155232ms)","trace[1062475881] 'applied index is now lower than readState.Index' (duration: 369.954406ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T02:01:30.877618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.215349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T02:01:30.877653Z","caller":"traceutil/trace.go:171","msg":"trace[452082237] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:151; }","duration":"483.366101ms","start":"2026-03-02T02:01:30.394276Z","end":"2026-03-02T02:01:30.877643Z","steps":["trace[452082237] 'agreement among raft nodes before linearized reading' (duration: 483.209199ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:01:30.877628Z","caller":"traceutil/trace.go:171","msg":"trace[973700436] transaction","detail":"{read_only:false; response_revision:151; number_of_response:1; }","duration":"496.880305ms","start":"2026-03-02T02:01:30.380706Z","end":"2026-03-02T02:01:30.877586Z","steps":["trace[973700436] 'process raft request' (duration: 126.880068ms)","trace[973700436] 'compare' (duration: 369.542482ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T02:01:30.87769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:01:30.394254Z","time spent":"483.428822ms","remote":"127.0.0.1:41000","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-03-02T02:01:30.877789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:01:30.380695Z","time spent":"497.016166ms","remote":"127.0.0.1:41360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":744,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-02T02:01:31.824783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.613948ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-02T02:01:31.825034Z","caller":"traceutil/trace.go:171","msg":"trace[219999251] linearizableReadLoop","detail":"{readStateIndex:156; appliedIndex:155; }","duration":"337.93093ms","start":"2026-03-02T02:01:31.487074Z","end":"2026-03-02T02:01:31.825005Z","steps":["trace[219999251] 'read index received' (duration: 37.381µs)","trace[219999251] 'applied index is now lower than readState.Index' (duration: 337.891019ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T02:01:31.825218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.132202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T02:01:31.825289Z","caller":"traceutil/trace.go:171","msg":"trace[1163919010] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:152; }","duration":"338.249343ms","start":"2026-03-02T02:01:31.487027Z","end":"2026-03-02T02:01:31.825276Z","steps":["trace[1163919010] 'agreement among raft nodes before linearized reading' (duration: 338.151032ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:01:31.825347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:01:31.487007Z","time spent":"338.330093ms","remote":"127.0.0.1:41000","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-03-02T02:01:31.825447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.231239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T02:01:31.825523Z","caller":"traceutil/trace.go:171","msg":"trace[836344377] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:152; }","duration":"331.40553ms","start":"2026-03-02T02:01:31.494103Z","end":"2026-03-02T02:01:31.825509Z","steps":["trace[836344377] 'agreement among raft nodes before linearized reading' (duration: 331.185278ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:01:31.82564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:01:31.494093Z","time spent":"331.534871ms","remote":"127.0.0.1:41250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-02T02:01:31.826048Z","caller":"traceutil/trace.go:171","msg":"trace[215405107] transaction","detail":"{read_only:false; response_revision:152; number_of_response:1; }","duration":"941.467002ms","start":"2026-03-02T02:01:30.883423Z","end":"2026-03-02T02:01:31.82489Z","steps":["trace[215405107] 'process raft request' (duration: 341.640782ms)","trace[215405107] 'compare' (duration: 599.441826ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T02:01:31.826445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:01:30.883407Z","time spent":"942.681804ms","remote":"127.0.0.1:41360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":744,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-02T02:01:34.704015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.336083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-03-02T02:01:34.70412Z","caller":"traceutil/trace.go:171","msg":"trace[473576773] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:316; }","duration":"100.475045ms","start":"2026-03-02T02:01:34.60362Z","end":"2026-03-02T02:01:34.704095Z","steps":["trace[473576773] 'range keys from in-memory index tree' (duration: 100.248622ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:01:57.547657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.008821ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-03-02T02:01:57.995549Z","caller":"traceutil/trace.go:171","msg":"trace[593473757] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"317.506607ms","start":"2026-03-02T02:01:57.678017Z","end":"2026-03-02T02:01:57.995523Z","steps":["trace[593473757] 'process raft request' (duration: 317.242135ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:01:57.995932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:01:57.677987Z","time spent":"317.630348ms","remote":"127.0.0.1:41166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4567,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T02:02:02.580365Z","caller":"traceutil/trace.go:171","msg":"trace[1579295129] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"557.161133ms","start":"2026-03-02T02:02:02.023178Z","end":"2026-03-02T02:02:02.580339Z","steps":["trace[1579295129] 'process raft request' (duration: 557.023002ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:02:02.58052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:02:02.023161Z","time spent":"557.283084ms","remote":"127.0.0.1:41250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T02:02:02.624265Z","caller":"traceutil/trace.go:171","msg":"trace[2064882621] linearizableReadLoop","detail":"{readStateIndex:561; appliedIndex:558; }","duration":"528.369151ms","start":"2026-03-02T02:02:02.095871Z","end":"2026-03-02T02:02:02.62424Z","steps":["trace[2064882621] 'read index received' (duration: 484.305123ms)","trace[2064882621] 'applied index is now lower than readState.Index' (duration: 44.063018ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:02:02.62431Z","caller":"traceutil/trace.go:171","msg":"trace[1059691925] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"599.186664ms","start":"2026-03-02T02:02:02.025102Z","end":"2026-03-02T02:02:02.624289Z","steps":["trace[1059691925] 'process raft request' (duration: 598.993132ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:02:02.624345Z","caller":"traceutil/trace.go:171","msg":"trace[658047518] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"597.857333ms","start":"2026-03-02T02:02:02.026455Z","end":"2026-03-02T02:02:02.624312Z","steps":["trace[658047518] 'process raft request' (duration: 597.744041ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:02:02.624431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.545763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.200\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-03-02T02:02:02.624449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:02:02.025082Z","time spent":"599.291675ms","remote":"127.0.0.1:41250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T02:02:02.624468Z","caller":"traceutil/trace.go:171","msg":"trace[1841312323] range","detail":"{range_begin:/registry/masterleases/162.253.55.200; range_end:; response_count:1; response_revision:543; }","duration":"528.621094ms","start":"2026-03-02T02:02:02.095836Z","end":"2026-03-02T02:02:02.624457Z","steps":["trace[1841312323] 'agreement among raft nodes before linearized reading' (duration: 528.529814ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:02:02.6245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:02:02.095818Z","time spent":"528.675225ms","remote":"127.0.0.1:41034","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.200\" "} {"level":"warn","ts":"2026-03-02T02:02:02.624517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:02:02.026442Z","time spent":"597.972045ms","remote":"127.0.0.1:41250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T02:02:02.624731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.790151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T02:02:02.624779Z","caller":"traceutil/trace.go:171","msg":"trace[755966160] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:543; }","duration":"494.864432ms","start":"2026-03-02T02:02:02.129901Z","end":"2026-03-02T02:02:02.624766Z","steps":["trace[755966160] 'agreement among raft nodes before linearized reading' (duration: 494.791261ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:02:02.62481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:02:02.129887Z","time spent":"494.915942ms","remote":"127.0.0.1:41000","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-03-02T02:02:05.627547Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9823086000994623423,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-02T02:02:06.128246Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9823086000994623423,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-02T02:02:06.254211Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.18606004s","expected-duration":"1s"} {"level":"info","ts":"2026-03-02T02:02:06.254633Z","caller":"traceutil/trace.go:171","msg":"trace[726458843] linearizableReadLoop","detail":"{readStateIndex:573; appliedIndex:572; }","duration":"1.127457217s","start":"2026-03-02T02:02:05.127158Z","end":"2026-03-02T02:02:06.254615Z","steps":["trace[726458843] 'read index received' (duration: 1.127136024s)","trace[726458843] 'applied index is now lower than readState.Index' (duration: 320.663µs)"],"step_count":2} {"level":"warn","ts":"2026-03-02T02:02:06.254696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.127528817s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T02:02:06.254711Z","caller":"traceutil/trace.go:171","msg":"trace[112942715] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:554; }","duration":"1.127586227s","start":"2026-03-02T02:02:05.127119Z","end":"2026-03-02T02:02:06.254706Z","steps":["trace[112942715] 'agreement among raft nodes before linearized reading' (duration: 1.127532257s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:02:06.254729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:02:05.127102Z","time spent":"1.127623629s","remote":"127.0.0.1:41000","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-02T02:02:06.254945Z","caller":"traceutil/trace.go:171","msg":"trace[1875533609] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"1.186978889s","start":"2026-03-02T02:02:05.067961Z","end":"2026-03-02T02:02:06.25494Z","steps":["trace[1875533609] 'process raft request' (duration: 1.186371783s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:02:06.254988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:02:05.067917Z","time spent":"1.187041219s","remote":"127.0.0.1:41168","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-02T02:02:07.137946Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9823086000994623428,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-02T02:02:07.29687Z","caller":"traceutil/trace.go:171","msg":"trace[585802480] linearizableReadLoop","detail":"{readStateIndex:574; appliedIndex:573; }","duration":"659.180988ms","start":"2026-03-02T02:02:06.637672Z","end":"2026-03-02T02:02:07.296853Z","steps":["trace[585802480] 'read index received' (duration: 659.114617ms)","trace[585802480] 'applied index is now lower than readState.Index' (duration: 65.131µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:02:07.296986Z","caller":"traceutil/trace.go:171","msg":"trace[1190534536] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"699.733481ms","start":"2026-03-02T02:02:06.597208Z","end":"2026-03-02T02:02:07.296941Z","steps":["trace[1190534536] 'process raft request' (duration: 699.524429ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:02:07.297033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.34263ms","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-02T02:02:07.297057Z","caller":"traceutil/trace.go:171","msg":"trace[547046492] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:555; }","duration":"659.4065ms","start":"2026-03-02T02:02:06.637644Z","end":"2026-03-02T02:02:07.29705Z","steps":["trace[547046492] 'agreement among raft nodes before linearized reading' (duration: 659.295669ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:02:07.297114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:02:06.637632Z","time spent":"659.475961ms","remote":"127.0.0.1:41250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-02T02:02:07.297177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:02:06.597191Z","time spent":"699.870722ms","remote":"127.0.0.1:41250","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-03-02T02:02:07.492249Z","caller":"traceutil/trace.go:171","msg":"trace[1098374474] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"191.987271ms","start":"2026-03-02T02:02:07.30024Z","end":"2026-03-02T02:02:07.492227Z","steps":["trace[1098374474] 'process raft request' (duration: 191.87211ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:02:07.492288Z","caller":"traceutil/trace.go:171","msg":"trace[150682841] linearizableReadLoop","detail":"{readStateIndex:575; appliedIndex:574; }","duration":"195.334238ms","start":"2026-03-02T02:02:07.29692Z","end":"2026-03-02T02:02:07.492254Z","steps":["trace[150682841] 'read index received' (duration: 122.993586ms)","trace[150682841] 'applied index is now lower than readState.Index' (duration: 72.335522ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T02:02:07.492485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"854.470136ms","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-02T02:02:07.492519Z","caller":"traceutil/trace.go:171","msg":"trace[1643030403] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:556; }","duration":"854.564757ms","start":"2026-03-02T02:02:06.637944Z","end":"2026-03-02T02:02:07.492509Z","steps":["trace[1643030403] 'agreement among raft nodes before linearized reading' (duration: 854.403696ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:02:07.49255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:02:06.637928Z","time spent":"854.613647ms","remote":"127.0.0.1:41250","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-03-02T02:02:07.492549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"838.217042ms","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-02T02:02:07.492702Z","caller":"traceutil/trace.go:171","msg":"trace[1389233159] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:556; }","duration":"838.402603ms","start":"2026-03-02T02:02:06.654258Z","end":"2026-03-02T02:02:07.49266Z","steps":["trace[1389233159] 'agreement among raft nodes before linearized reading' (duration: 838.07029ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:02:07.492768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:02:06.654237Z","time spent":"838.517415ms","remote":"127.0.0.1:41250","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":"info","ts":"2026-03-02T02:02:07.691494Z","caller":"traceutil/trace.go:171","msg":"trace[297066281] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"194.537151ms","start":"2026-03-02T02:02:07.496931Z","end":"2026-03-02T02:02:07.691468Z","steps":["trace[297066281] 'process raft request' (duration: 130.238565ms)","trace[297066281] 'compare' (duration: 63.939773ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T02:02:08.555454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.95862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T02:02:08.555538Z","caller":"traceutil/trace.go:171","msg":"trace[880103290] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:558; }","duration":"432.091801ms","start":"2026-03-02T02:02:08.123427Z","end":"2026-03-02T02:02:08.555519Z","steps":["trace[880103290] 'range keys from in-memory index tree' (duration: 431.89118ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:02:08.555577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:02:08.123407Z","time spent":"432.161341ms","remote":"127.0.0.1:41000","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-02T02:02:10.785056Z","caller":"traceutil/trace.go:171","msg":"trace[567011467] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"511.639658ms","start":"2026-03-02T02:02:10.273392Z","end":"2026-03-02T02:02:10.785032Z","steps":["trace[567011467] 'process raft request' (duration: 511.518047ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:02:10.785181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:02:10.27338Z","time spent":"511.744569ms","remote":"127.0.0.1:41080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":765,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-02T02:02:10.789012Z","caller":"traceutil/trace.go:171","msg":"trace[1183009340] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"503.109079ms","start":"2026-03-02T02:02:10.285887Z","end":"2026-03-02T02:02:10.788996Z","steps":["trace[1183009340] 'process raft request' (duration: 503.016938ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:02:10.789104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:02:10.285859Z","time spent":"503.19755ms","remote":"127.0.0.1:41168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17232,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T02:02:27.647069Z","caller":"traceutil/trace.go:171","msg":"trace[193234797] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"206.928296ms","start":"2026-03-02T02:02:27.440119Z","end":"2026-03-02T02:02:27.647047Z","steps":["trace[193234797] 'process raft request' (duration: 206.727778ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:03:37.196273Z","caller":"traceutil/trace.go:171","msg":"trace[251167941] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"155.689913ms","start":"2026-03-02T02:03:37.040525Z","end":"2026-03-02T02:03:37.196215Z","steps":["trace[251167941] 'process raft request' (duration: 155.318406ms)"],"step_count":1}