{"level":"warn","ts":"2026-10-04T05:43:49.426007Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-04T05:43:49.426151Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.213: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.213:2380","--initial-cluster=instance=https://162.253.55.213:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.213:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.213: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-10-04T05:43:49.426249Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-04T05:43:49.426271Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.213:2380"]} {"level":"info","ts":"2026-10-04T05:43:49.426308Z","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-10-04T05:43:49.427538Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.213:2379"]} {"level":"info","ts":"2026-10-04T05:43:49.427665Z","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.213:2380"],"listen-peer-urls":["https://162.253.55.213:2380"],"advertise-client-urls":["https://162.253.55.213:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.213:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.213: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-10-04T05:43:49.433742Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.750659ms"} {"level":"info","ts":"2026-10-04T05:43:49.441603Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"43ce62d0a10d51da","cluster-id":"25e0c2268a6b42c6"} {"level":"info","ts":"2026-10-04T05:43:49.441681Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"43ce62d0a10d51da switched to configuration voters=()"} {"level":"info","ts":"2026-10-04T05:43:49.441714Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"43ce62d0a10d51da became follower at term 0"} {"level":"info","ts":"2026-10-04T05:43:49.441736Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 43ce62d0a10d51da [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-04T05:43:49.44175Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"43ce62d0a10d51da became follower at term 1"} {"level":"info","ts":"2026-10-04T05:43:49.441807Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"43ce62d0a10d51da switched to configuration voters=(4885951293938291162)"} {"level":"warn","ts":"2026-10-04T05:43:49.465698Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-04T05:43:49.467224Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-04T05:43:49.468591Z","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-10-04T05:43:49.470562Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"43ce62d0a10d51da","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-04T05:43:49.471071Z","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-10-04T05:43:49.471185Z","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-10-04T05:43:49.471212Z","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-10-04T05:43:49.4711Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"43ce62d0a10d51da","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-04T05:43:49.476502Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"43ce62d0a10d51da switched to configuration voters=(4885951293938291162)"} {"level":"info","ts":"2026-10-04T05:43:49.478707Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"25e0c2268a6b42c6","local-member-id":"43ce62d0a10d51da","added-peer-id":"43ce62d0a10d51da","added-peer-peer-urls":["https://162.253.55.213:2380"]} {"level":"info","ts":"2026-10-04T05:43:49.479504Z","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-10-04T05:43:49.479671Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"43ce62d0a10d51da","initial-advertise-peer-urls":["https://162.253.55.213:2380"],"listen-peer-urls":["https://162.253.55.213:2380"],"advertise-client-urls":["https://162.253.55.213:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.213:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-04T05:43:49.479692Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-04T05:43:49.47977Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.213:2380"} {"level":"info","ts":"2026-10-04T05:43:49.479875Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.213:2380"} {"level":"info","ts":"2026-10-04T05:43:49.943832Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"43ce62d0a10d51da is starting a new election at term 1"} {"level":"info","ts":"2026-10-04T05:43:49.943904Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"43ce62d0a10d51da became pre-candidate at term 1"} {"level":"info","ts":"2026-10-04T05:43:49.943954Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"43ce62d0a10d51da received MsgPreVoteResp from 43ce62d0a10d51da at term 1"} {"level":"info","ts":"2026-10-04T05:43:49.943974Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"43ce62d0a10d51da became candidate at term 2"} {"level":"info","ts":"2026-10-04T05:43:49.943985Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"43ce62d0a10d51da received MsgVoteResp from 43ce62d0a10d51da at term 2"} {"level":"info","ts":"2026-10-04T05:43:49.944001Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"43ce62d0a10d51da became leader at term 2"} {"level":"info","ts":"2026-10-04T05:43:49.944172Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 43ce62d0a10d51da elected leader 43ce62d0a10d51da at term 2"} {"level":"info","ts":"2026-10-04T05:43:49.947514Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"43ce62d0a10d51da","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.213:2379]}","request-path":"/0/members/43ce62d0a10d51da/attributes","cluster-id":"25e0c2268a6b42c6","publish-timeout":"7s"} {"level":"info","ts":"2026-10-04T05:43:49.947834Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-04T05:43:49.947869Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-04T05:43:49.947913Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-04T05:43:49.948162Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-04T05:43:49.94824Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-04T05:43:49.95022Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.213:2379"} {"level":"info","ts":"2026-10-04T05:43:49.952894Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"25e0c2268a6b42c6","local-member-id":"43ce62d0a10d51da","cluster-version":"3.5"} {"level":"info","ts":"2026-10-04T05:43:49.953163Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-04T05:43:49.953198Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-04T05:43:49.957206Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-04T05:43:54.533746Z","caller":"traceutil/trace.go:171","msg":"trace[855121989] transaction","detail":"{read_only:false; response_revision:247; number_of_response:1; }","duration":"421.259829ms","start":"2026-10-04T05:43:54.112463Z","end":"2026-10-04T05:43:54.533723Z","steps":["trace[855121989] 'process raft request' (duration: 421.126976ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:43:54.534253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:43:54.112444Z","time spent":"421.387622ms","remote":"127.0.0.1:48668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-04T05:43:54.625507Z","caller":"traceutil/trace.go:171","msg":"trace[568015109] linearizableReadLoop","detail":"{readStateIndex:254; appliedIndex:252; }","duration":"462.411007ms","start":"2026-10-04T05:43:54.163076Z","end":"2026-10-04T05:43:54.625487Z","steps":["trace[568015109] 'read index received' (duration: 370.440242ms)","trace[568015109] 'applied index is now lower than readState.Index' (duration: 91.969815ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T05:43:54.625886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.808907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3492"} {"level":"info","ts":"2026-10-04T05:43:54.62603Z","caller":"traceutil/trace.go:171","msg":"trace[1411824385] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:248; }","duration":"462.984451ms","start":"2026-10-04T05:43:54.162988Z","end":"2026-10-04T05:43:54.625973Z","steps":["trace[1411824385] 'agreement among raft nodes before linearized reading' (duration: 462.710544ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:43:54.626193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:43:54.162972Z","time spent":"463.206787ms","remote":"127.0.0.1:48568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3514,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-10-04T05:43:54.626092Z","caller":"traceutil/trace.go:171","msg":"trace[460350711] transaction","detail":"{read_only:false; response_revision:248; number_of_response:1; }","duration":"503.862043ms","start":"2026-10-04T05:43:54.122217Z","end":"2026-10-04T05:43:54.626079Z","steps":["trace[460350711] 'process raft request' (duration: 503.126285ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:43:54.626371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:43:54.122203Z","time spent":"504.045167ms","remote":"127.0.0.1:48576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5882,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-04T05:43:59.896915Z","caller":"traceutil/trace.go:171","msg":"trace[1400771852] linearizableReadLoop","detail":"{readStateIndex:370; appliedIndex:368; }","duration":"105.438253ms","start":"2026-10-04T05:43:59.791429Z","end":"2026-10-04T05:43:59.896867Z","steps":["trace[1400771852] 'read index received' (duration: 38.283809ms)","trace[1400771852] 'applied index is now lower than readState.Index' (duration: 67.153534ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:43:59.896945Z","caller":"traceutil/trace.go:171","msg":"trace[1644605011] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"128.600754ms","start":"2026-10-04T05:43:59.768317Z","end":"2026-10-04T05:43:59.896918Z","steps":["trace[1644605011] 'process raft request' (duration: 118.544255ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:43:59.89715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.694439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-10-04T05:43:59.897246Z","caller":"traceutil/trace.go:171","msg":"trace[528823572] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:358; }","duration":"105.817933ms","start":"2026-10-04T05:43:59.791404Z","end":"2026-10-04T05:43:59.897222Z","steps":["trace[528823572] 'agreement among raft nodes before linearized reading' (duration: 105.617038ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:44:25.894335Z","caller":"traceutil/trace.go:171","msg":"trace[317612485] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"128.212082ms","start":"2026-10-04T05:44:25.766098Z","end":"2026-10-04T05:44:25.89431Z","steps":["trace[317612485] 'process raft request' (duration: 128.041878ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:44:27.000782Z","caller":"traceutil/trace.go:171","msg":"trace[2092303802] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"111.766354ms","start":"2026-10-04T05:44:26.888985Z","end":"2026-10-04T05:44:27.000751Z","steps":["trace[2092303802] 'process raft request' (duration: 52.333053ms)","trace[2092303802] 'compare' (duration: 59.316489ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:44:27.087841Z","caller":"traceutil/trace.go:171","msg":"trace[1611067111] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"194.865918ms","start":"2026-10-04T05:44:26.892956Z","end":"2026-10-04T05:44:27.087822Z","steps":["trace[1611067111] 'process raft request' (duration: 194.699014ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:44:27.087966Z","caller":"traceutil/trace.go:171","msg":"trace[1837172040] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"194.490761ms","start":"2026-10-04T05:44:26.893442Z","end":"2026-10-04T05:44:27.087933Z","steps":["trace[1837172040] 'process raft request' (duration: 194.306487ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:44:27.088116Z","caller":"traceutil/trace.go:171","msg":"trace[370981784] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"196.392088ms","start":"2026-10-04T05:44:26.891707Z","end":"2026-10-04T05:44:27.088099Z","steps":["trace[370981784] 'process raft request' (duration: 195.765786ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:44:27.088206Z","caller":"traceutil/trace.go:171","msg":"trace[1110965038] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"191.782118ms","start":"2026-10-04T05:44:26.896414Z","end":"2026-10-04T05:44:27.088196Z","steps":["trace[1110965038] 'process raft request' (duration: 191.648735ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:44:27.088207Z","caller":"traceutil/trace.go:171","msg":"trace[1539974274] transaction","detail":"{read_only:false; number_of_response:1; response_revision:533; }","duration":"193.451591ms","start":"2026-10-04T05:44:26.894677Z","end":"2026-10-04T05:44:27.088128Z","steps":["trace[1539974274] 'process raft request' (duration: 193.167265ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:44:27.088276Z","caller":"traceutil/trace.go:171","msg":"trace[969629588] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"191.775347ms","start":"2026-10-04T05:44:26.896443Z","end":"2026-10-04T05:44:27.088218Z","steps":["trace[969629588] 'process raft request' (duration: 191.661225ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:44:27.08836Z","caller":"traceutil/trace.go:171","msg":"trace[1836767697] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"191.541913ms","start":"2026-10-04T05:44:26.896782Z","end":"2026-10-04T05:44:27.088323Z","steps":["trace[1836767697] 'process raft request' (duration: 191.37452ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:44:27.088484Z","caller":"traceutil/trace.go:171","msg":"trace[187515163] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"181.354944ms","start":"2026-10-04T05:44:26.907115Z","end":"2026-10-04T05:44:27.08847Z","steps":["trace[187515163] 'process raft request' (duration: 181.13203ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:44:27.088485Z","caller":"traceutil/trace.go:171","msg":"trace[1980729543] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"148.872789ms","start":"2026-10-04T05:44:26.939587Z","end":"2026-10-04T05:44:27.08846Z","steps":["trace[1980729543] 'process raft request' (duration: 148.766317ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:44:27.088282Z","caller":"traceutil/trace.go:171","msg":"trace[1491970439] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"192.106234ms","start":"2026-10-04T05:44:26.896164Z","end":"2026-10-04T05:44:27.08827Z","steps":["trace[1491970439] 'process raft request' (duration: 191.818179ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:44:27.088591Z","caller":"traceutil/trace.go:171","msg":"trace[997770095] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"188.650126ms","start":"2026-10-04T05:44:26.899931Z","end":"2026-10-04T05:44:27.088581Z","steps":["trace[997770095] 'process raft request' (duration: 188.272189ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:44:27.08864Z","caller":"traceutil/trace.go:171","msg":"trace[161707552] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"173.259095ms","start":"2026-10-04T05:44:26.915369Z","end":"2026-10-04T05:44:27.088628Z","steps":["trace[161707552] 'process raft request' (duration: 172.917929ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:44:27.100585Z","caller":"traceutil/trace.go:171","msg":"trace[1355951570] linearizableReadLoop","detail":"{readStateIndex:563; appliedIndex:550; }","duration":"144.471842ms","start":"2026-10-04T05:44:26.956093Z","end":"2026-10-04T05:44:27.100564Z","steps":["trace[1355951570] 'read index received' (duration: 131.394558ms)","trace[1355951570] 'applied index is now lower than readState.Index' (duration: 13.076664ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T05:44:27.100783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.596764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-04T05:44:27.100811Z","caller":"traceutil/trace.go:171","msg":"trace[33190385] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:543; }","duration":"144.748536ms","start":"2026-10-04T05:44:26.956056Z","end":"2026-10-04T05:44:27.100804Z","steps":["trace[33190385] 'agreement among raft nodes before linearized reading' (duration: 144.589253ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:44:27.100937Z","caller":"traceutil/trace.go:171","msg":"trace[1259503904] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"148.445399ms","start":"2026-10-04T05:44:26.952472Z","end":"2026-10-04T05:44:27.100917Z","steps":["trace[1259503904] 'process raft request' (duration: 147.640023ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:44:31.95211Z","caller":"traceutil/trace.go:171","msg":"trace[1820932026] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"303.678941ms","start":"2026-10-04T05:44:31.648398Z","end":"2026-10-04T05:44:31.952077Z","steps":["trace[1820932026] 'process raft request' (duration: 302.933667ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:44:31.952549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:44:31.648365Z","time spent":"303.849505ms","remote":"127.0.0.1:48468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036189,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T05:44:32.488621Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5898203706731513947,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-10-04T05:44:32.989348Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5898203706731513947,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-10-04T05:44:33.071191Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.114729253s","expected-duration":"1s"} {"level":"info","ts":"2026-10-04T05:44:33.071862Z","caller":"traceutil/trace.go:171","msg":"trace[1974748371] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"1.115472037s","start":"2026-10-04T05:44:31.956363Z","end":"2026-10-04T05:44:33.071835Z","steps":["trace[1974748371] 'process raft request' (duration: 1.115187642s)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:44:33.072106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:44:31.956341Z","time spent":"1.11566393s","remote":"127.0.0.1:48668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T05:44:33.972634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.984569753s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-10-04T05:44:33.972696Z","caller":"traceutil/trace.go:171","msg":"trace[1538379721] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.984673395s","start":"2026-10-04T05:44:31.988006Z","end":"2026-10-04T05:44:33.972679Z","steps":["trace[1538379721] 'agreement among raft nodes before linearized reading' (duration: 1.984606183s)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:44:33.972738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:44:31.987984Z","time spent":"1.984746527s","remote":"127.0.0.1:48344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/10/04 05:44:33 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-10-04T05:44:34.755079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"955.784642ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-04T05:44:34.755177Z","caller":"traceutil/trace.go:171","msg":"trace[108116105] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:620; }","duration":"955.887514ms","start":"2026-10-04T05:44:33.799276Z","end":"2026-10-04T05:44:34.755163Z","steps":["trace[108116105] 'range keys from in-memory index tree' (duration: 955.771882ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:44:34.755557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"871.812511ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-04T05:44:34.755729Z","caller":"traceutil/trace.go:171","msg":"trace[909992582] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:641; }","duration":"2.767661214s","start":"2026-10-04T05:44:31.988051Z","end":"2026-10-04T05:44:34.755713Z","steps":["trace[909992582] 'read index received' (duration: 1.08342418s)","trace[909992582] 'applied index is now lower than readState.Index' (duration: 1.684236264s)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:44:34.755843Z","caller":"traceutil/trace.go:171","msg":"trace[1778044162] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"2.381308599s","start":"2026-10-04T05:44:32.374522Z","end":"2026-10-04T05:44:34.755831Z","steps":["trace[1778044162] 'process raft request' (duration: 2.381118975s)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:44:34.755913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:44:32.374498Z","time spent":"2.381370149s","remote":"127.0.0.1:48668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T05:44:34.75596Z","caller":"traceutil/trace.go:171","msg":"trace[255667649] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"2.716304105s","start":"2026-10-04T05:44:32.039544Z","end":"2026-10-04T05:44:34.755848Z","steps":["trace[255667649] 'process raft request' (duration: 1.843997805s)","trace[255667649] 'compare' (duration: 871.557326ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T05:44:34.756019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.903313151s","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-10-04T05:44:34.75606Z","caller":"traceutil/trace.go:171","msg":"trace[1092323190] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:622; }","duration":"1.903392603s","start":"2026-10-04T05:44:32.852656Z","end":"2026-10-04T05:44:34.756048Z","steps":["trace[1092323190] 'agreement among raft nodes before linearized reading' (duration: 1.90326601s)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:44:34.756088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:44:32.852635Z","time spent":"1.903445503s","remote":"127.0.0.1:48668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-04T05:44:34.756169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:44:32.03952Z","time spent":"2.716546389s","remote":"127.0.0.1:48750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1044,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-04T05:44:34.756195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"782.872516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-04T05:44:34.756237Z","caller":"traceutil/trace.go:171","msg":"trace[2029590085] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:622; }","duration":"782.949418ms","start":"2026-10-04T05:44:33.973279Z","end":"2026-10-04T05:44:34.756229Z","steps":["trace[2029590085] 'agreement among raft nodes before linearized reading' (duration: 782.897097ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:44:34.756259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:44:33.973261Z","time spent":"782.992729ms","remote":"127.0.0.1:48360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-04T05:44:34.756467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.339720437s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-04T05:44:34.756493Z","caller":"traceutil/trace.go:171","msg":"trace[1399798305] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:622; }","duration":"1.339764298s","start":"2026-10-04T05:44:33.41672Z","end":"2026-10-04T05:44:34.756485Z","steps":["trace[1399798305] 'agreement among raft nodes before linearized reading' (duration: 1.339683876s)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:44:34.756515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:44:33.416708Z","time spent":"1.339801788s","remote":"127.0.0.1:48668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-04T05:44:34.756528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.843135538s","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-10-04T05:44:34.756566Z","caller":"traceutil/trace.go:171","msg":"trace[385791536] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:622; }","duration":"1.843211009s","start":"2026-10-04T05:44:32.913343Z","end":"2026-10-04T05:44:34.756554Z","steps":["trace[385791536] 'agreement among raft nodes before linearized reading' (duration: 1.843078537s)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:44:34.756553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.498226723s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.213\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-10-04T05:44:34.756593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:44:32.913322Z","time spent":"1.843264931s","remote":"127.0.0.1:48668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-04T05:44:34.756622Z","caller":"traceutil/trace.go:171","msg":"trace[264277602] range","detail":"{range_begin:/registry/masterleases/162.253.55.213; range_end:; response_count:1; response_revision:622; }","duration":"1.498358255s","start":"2026-10-04T05:44:33.258244Z","end":"2026-10-04T05:44:34.756602Z","steps":["trace[264277602] 'agreement among raft nodes before linearized reading' (duration: 1.498205952s)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:44:34.756662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:44:33.258221Z","time spent":"1.498432167s","remote":"127.0.0.1:48412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/162.253.55.213\" "} {"level":"info","ts":"2026-10-04T05:44:35.162864Z","caller":"traceutil/trace.go:171","msg":"trace[779592855] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"401.423339ms","start":"2026-10-04T05:44:34.761416Z","end":"2026-10-04T05:44:35.16284Z","steps":["trace[779592855] 'process raft request' (duration: 401.149214ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:44:35.163072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:44:34.761398Z","time spent":"401.546702ms","remote":"127.0.0.1:48668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T05:44:35.163328Z","caller":"traceutil/trace.go:171","msg":"trace[182458189] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:644; }","duration":"397.695841ms","start":"2026-10-04T05:44:34.765601Z","end":"2026-10-04T05:44:35.163297Z","steps":["trace[182458189] 'read index received' (duration: 22.358556ms)","trace[182458189] 'applied index is now lower than readState.Index' (duration: 375.336005ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:44:35.163376Z","caller":"traceutil/trace.go:171","msg":"trace[526555775] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"401.222706ms","start":"2026-10-04T05:44:34.762119Z","end":"2026-10-04T05:44:35.163341Z","steps":["trace[526555775] 'process raft request' (duration: 400.639255ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:44:35.163451Z","caller":"traceutil/trace.go:171","msg":"trace[728354386] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"400.526953ms","start":"2026-10-04T05:44:34.762912Z","end":"2026-10-04T05:44:35.163439Z","steps":["trace[728354386] 'process raft request' (duration: 400.280939ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:44:35.163441Z","caller":"traceutil/trace.go:171","msg":"trace[1520662733] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"400.582754ms","start":"2026-10-04T05:44:34.762844Z","end":"2026-10-04T05:44:35.163426Z","steps":["trace[1520662733] 'process raft request' (duration: 400.249778ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:44:35.1635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:44:34.762104Z","time spent":"401.334418ms","remote":"127.0.0.1:48450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-04T05:44:35.163522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:44:34.762833Z","time spent":"400.653255ms","remote":"127.0.0.1:48668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T05:44:35.163579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:44:34.762904Z","time spent":"400.581554ms","remote":"127.0.0.1:48668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T05:44:35.163878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.19257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-04T05:44:35.163971Z","caller":"traceutil/trace.go:171","msg":"trace[70357600] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:626; }","duration":"398.439434ms","start":"2026-10-04T05:44:34.765516Z","end":"2026-10-04T05:44:35.163955Z","steps":["trace[70357600] 'agreement among raft nodes before linearized reading' (duration: 398.154019ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:44:35.164046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:44:34.765502Z","time spent":"398.506105ms","remote":"127.0.0.1:48344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-04T05:44:35.228231Z","caller":"traceutil/trace.go:171","msg":"trace[1112425557] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"439.224266ms","start":"2026-10-04T05:44:34.788982Z","end":"2026-10-04T05:44:35.228206Z","steps":["trace[1112425557] 'process raft request' (duration: 415.364775ms)","trace[1112425557] 'compare' (duration: 23.726809ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T05:44:35.228343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:44:34.788966Z","time spent":"439.320908ms","remote":"127.0.0.1:48412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T05:44:35.228354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.968139ms","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-10-04T05:44:35.2284Z","caller":"traceutil/trace.go:171","msg":"trace[2091300967] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:627; }","duration":"153.04281ms","start":"2026-10-04T05:44:35.075345Z","end":"2026-10-04T05:44:35.228388Z","steps":["trace[2091300967] 'agreement among raft nodes before linearized reading' (duration: 152.896328ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:44:35.228521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.39195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/envoy-gateway-gateway-helm-certgen:envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-04T05:44:35.228584Z","caller":"traceutil/trace.go:171","msg":"trace[718462911] range","detail":"{range_begin:/registry/clusterroles/envoy-gateway-gateway-helm-certgen:envoy-gateway-system; range_end:; response_count:0; response_revision:627; }","duration":"460.519542ms","start":"2026-10-04T05:44:34.768049Z","end":"2026-10-04T05:44:35.228569Z","steps":["trace[718462911] 'agreement among raft nodes before linearized reading' (duration: 460.41739ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:44:35.228623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:44:34.76804Z","time spent":"460.574473ms","remote":"127.0.0.1:48752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/envoy-gateway-gateway-helm-certgen:envoy-gateway-system\" "} {"level":"warn","ts":"2026-10-04T05:44:36.459531Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5898203706731513966,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-10-04T05:44:36.959911Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5898203706731513966,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-10-04T05:44:37.460734Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5898203706731513966,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-10-04T05:44:37.463817Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.824401561s","expected-duration":"1s"} {"level":"info","ts":"2026-10-04T05:44:37.465355Z","caller":"traceutil/trace.go:171","msg":"trace[1613027132] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"1.833780794s","start":"2026-10-04T05:44:35.631536Z","end":"2026-10-04T05:44:37.465317Z","steps":["trace[1613027132] 'process raft request' (duration: 1.832860307s)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:44:37.465592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:44:35.631491Z","time spent":"1.833963778s","remote":"127.0.0.1:48468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036221,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T05:44:37.959488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000098434s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-10-04T05:44:37.959583Z","caller":"traceutil/trace.go:171","msg":"trace[1659973263] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000259858s","start":"2026-10-04T05:44:35.959301Z","end":"2026-10-04T05:44:37.959561Z","steps":["trace[1659973263] 'agreement among raft nodes before linearized reading' (duration: 2.000139005s)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:44:37.959643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:44:35.959211Z","time spent":"2.000418161s","remote":"127.0.0.1:48344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/10/04 05:44:37 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-10-04T05:44:38.322133Z","caller":"traceutil/trace.go:171","msg":"trace[2105573080] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:651; }","duration":"2.362750871s","start":"2026-10-04T05:44:35.959358Z","end":"2026-10-04T05:44:38.322109Z","steps":["trace[2105573080] 'read index received' (duration: 1.504692169s)","trace[2105573080] 'applied index is now lower than readState.Index' (duration: 858.057762ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:44:38.322188Z","caller":"traceutil/trace.go:171","msg":"trace[1494408132] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"1.811911459s","start":"2026-10-04T05:44:36.510232Z","end":"2026-10-04T05:44:38.322143Z","steps":["trace[1494408132] 'process raft request' (duration: 1.76187959s)","trace[1494408132] 'compare' (duration: 49.613952ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T05:44:38.322477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:44:36.510208Z","time spent":"1.812059763s","remote":"127.0.0.1:48668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T05:44:38.442922Z","caller":"traceutil/trace.go:171","msg":"trace[1741416356] linearizableReadLoop","detail":"{readStateIndex:654; appliedIndex:653; }","duration":"120.686334ms","start":"2026-10-04T05:44:38.32219Z","end":"2026-10-04T05:44:38.442877Z","steps":["trace[1741416356] 'read index received' (duration: 105.040799ms)","trace[1741416356] 'applied index is now lower than readState.Index' (duration: 15.643745ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T05:44:38.44304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.280659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-04T05:44:38.443128Z","caller":"traceutil/trace.go:171","msg":"trace[1032786556] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:631; }","duration":"483.412371ms","start":"2026-10-04T05:44:37.959691Z","end":"2026-10-04T05:44:38.443103Z","steps":["trace[1032786556] 'agreement among raft nodes before linearized reading' (duration: 483.255428ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:44:38.443185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:44:37.959653Z","time spent":"483.520443ms","remote":"127.0.0.1:48360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-04T05:44:38.443224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.169054854s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-10-04T05:44:38.443238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.273796589s","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-10-04T05:44:38.443235Z","caller":"traceutil/trace.go:171","msg":"trace[456949152] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"905.218011ms","start":"2026-10-04T05:44:37.537985Z","end":"2026-10-04T05:44:38.443203Z","steps":["trace[456949152] 'process raft request' (duration: 889.311692ms)","trace[456949152] 'compare' (duration: 15.392949ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:44:38.443279Z","caller":"traceutil/trace.go:171","msg":"trace[1839551725] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:631; }","duration":"1.169146306s","start":"2026-10-04T05:44:37.274119Z","end":"2026-10-04T05:44:38.443265Z","steps":["trace[1839551725] 'agreement among raft nodes before linearized reading' (duration: 1.168945132s)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:44:38.443287Z","caller":"traceutil/trace.go:171","msg":"trace[2144379350] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:631; }","duration":"1.273924981s","start":"2026-10-04T05:44:37.169347Z","end":"2026-10-04T05:44:38.443272Z","steps":["trace[2144379350] 'agreement among raft nodes before linearized reading' (duration: 1.273689557s)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:44:38.443317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:44:37.274107Z","time spent":"1.169201887s","remote":"127.0.0.1:48668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-04T05:44:38.443304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.273847519s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-10-04T05:44:38.443331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:44:37.169307Z","time spent":"1.274013323s","remote":"127.0.0.1:48668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-04T05:44:38.443371Z","caller":"traceutil/trace.go:171","msg":"trace[167177868] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:631; }","duration":"1.273931911s","start":"2026-10-04T05:44:37.169421Z","end":"2026-10-04T05:44:38.443353Z","steps":["trace[167177868] 'agreement among raft nodes before linearized reading' (duration: 1.273714368s)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:44:38.443389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:44:37.537955Z","time spent":"905.357484ms","remote":"127.0.0.1:48752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-04T05:44:38.443429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:44:37.169414Z","time spent":"1.274002003s","remote":"127.0.0.1:48668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-04T05:44:38.443503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.273630895s","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-10-04T05:44:38.443612Z","caller":"traceutil/trace.go:171","msg":"trace[22874807] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:631; }","duration":"1.273800839s","start":"2026-10-04T05:44:37.169782Z","end":"2026-10-04T05:44:38.443583Z","steps":["trace[22874807] 'agreement among raft nodes before linearized reading' (duration: 1.273198508s)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:44:38.443687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:44:37.169757Z","time spent":"1.27391809s","remote":"127.0.0.1:48668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-04T05:44:39.0301Z","caller":"traceutil/trace.go:171","msg":"trace[1261814503] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"162.648033ms","start":"2026-10-04T05:44:38.867418Z","end":"2026-10-04T05:44:39.030066Z","steps":["trace[1261814503] 'process raft request' (duration: 162.46876ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:44:39.071174Z","caller":"traceutil/trace.go:171","msg":"trace[1873898884] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"199.700212ms","start":"2026-10-04T05:44:38.871435Z","end":"2026-10-04T05:44:39.071136Z","steps":["trace[1873898884] 'process raft request' (duration: 189.800903ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:44:39.471514Z","caller":"traceutil/trace.go:171","msg":"trace[1607675657] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"316.816476ms","start":"2026-10-04T05:44:39.15464Z","end":"2026-10-04T05:44:39.471456Z","steps":["trace[1607675657] 'process raft request' (duration: 282.364764ms)","trace[1607675657] 'compare' (duration: 34.081456ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T05:44:39.471644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:44:39.154607Z","time spent":"316.969918ms","remote":"127.0.0.1:48764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-04T05:44:54.118172Z","caller":"traceutil/trace.go:171","msg":"trace[2137756583] linearizableReadLoop","detail":"{readStateIndex:828; appliedIndex:827; }","duration":"157.600747ms","start":"2026-10-04T05:44:53.960548Z","end":"2026-10-04T05:44:54.118149Z","steps":["trace[2137756583] 'read index received' (duration: 157.344213ms)","trace[2137756583] 'applied index is now lower than readState.Index' (duration: 255.324µs)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:44:54.11824Z","caller":"traceutil/trace.go:171","msg":"trace[1177573913] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"158.425801ms","start":"2026-10-04T05:44:53.959766Z","end":"2026-10-04T05:44:54.118192Z","steps":["trace[1177573913] 'process raft request' (duration: 158.232687ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:44:54.118295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.728299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-04T05:44:54.118328Z","caller":"traceutil/trace.go:171","msg":"trace[1594144453] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:799; }","duration":"157.80264ms","start":"2026-10-04T05:44:53.960515Z","end":"2026-10-04T05:44:54.118318Z","steps":["trace[1594144453] 'agreement among raft nodes before linearized reading' (duration: 157.727819ms)"],"step_count":1}