{"level":"warn","ts":"2026-06-18T12:11:22.910107Z","caller":"embed/config.go:689","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-18T12:11:22.910442Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.87:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.87:2380","--initial-cluster=controller-1=https://199.19.213.87:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.87:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.87:2380","--name=controller-1","--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-06-18T12:11:22.910992Z","caller":"embed/config.go:689","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-18T12:11:22.911010Z","caller":"embed/etcd.go:140","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.87:2380"]} {"level":"info","ts":"2026-06-18T12:11:22.911084Z","caller":"embed/etcd.go:528","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-06-18T12:11:22.911722Z","caller":"embed/etcd.go:148","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.87:2379"]} {"level":"info","ts":"2026-06-18T12:11:22.911986Z","caller":"embed/etcd.go:323","msg":"starting an etcd server","etcd-version":"3.5.21","git-sha":"a17edfd","go-version":"go1.23.7","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"controller-1","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.87:2380"],"listen-peer-urls":["https://199.19.213.87:2380"],"advertise-client-urls":["https://199.19.213.87:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.87:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"controller-1=https://199.19.213.87: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-06-18T12:11:22.919748Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.880007ms"} {"level":"info","ts":"2026-06-18T12:11:22.936238Z","caller":"etcdserver/raft.go:506","msg":"starting local member","local-member-id":"bc3cb673a921c717","cluster-id":"f315f77360ac6549"} {"level":"info","ts":"2026-06-18T12:11:22.936380Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 switched to configuration voters=()"} {"level":"info","ts":"2026-06-18T12:11:22.936419Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 became follower at term 0"} {"level":"info","ts":"2026-06-18T12:11:22.936438Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft bc3cb673a921c717 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-18T12:11:22.936451Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 became follower at term 1"} {"level":"info","ts":"2026-06-18T12:11:22.936518Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 switched to configuration voters=(13563916785608148759)"} {"level":"warn","ts":"2026-06-18T12:11:22.942830Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-18T12:11:22.944180Z","caller":"mvcc/kvstore.go:425","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-18T12:11:22.944227Z","caller":"etcdserver/server.go:628","msg":"restore consistentIndex","index":0} {"level":"info","ts":"2026-06-18T12:11:22.945379Z","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-06-18T12:11:22.947190Z","caller":"etcdserver/server.go:875","msg":"starting etcd server","local-member-id":"bc3cb673a921c717","local-server-version":"3.5.21","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-18T12:11:22.947359Z","caller":"etcdserver/server.go:759","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"bc3cb673a921c717","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-18T12:11:22.947565Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"} {"level":"info","ts":"2026-06-18T12:11:22.947899Z","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-06-18T12:11:22.947940Z","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-06-18T12:11:22.947949Z","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-06-18T12:11:22.953854Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 switched to configuration voters=(13563916785608148759)"} {"level":"info","ts":"2026-06-18T12:11:22.953995Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f315f77360ac6549","local-member-id":"bc3cb673a921c717","added-peer-id":"bc3cb673a921c717","added-peer-peer-urls":["https://199.19.213.87:2380"],"added-peer-is-learner":false} {"level":"info","ts":"2026-06-18T12:11:22.955204Z","caller":"embed/etcd.go:762","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-06-18T12:11:22.955346Z","caller":"embed/etcd.go:633","msg":"serving peer traffic","address":"199.19.213.87:2380"} {"level":"info","ts":"2026-06-18T12:11:22.955444Z","caller":"embed/etcd.go:603","msg":"cmux::serve","address":"199.19.213.87:2380"} {"level":"info","ts":"2026-06-18T12:11:22.955540Z","caller":"embed/etcd.go:292","msg":"now serving peer/client/metrics","local-member-id":"bc3cb673a921c717","initial-advertise-peer-urls":["https://199.19.213.87:2380"],"listen-peer-urls":["https://199.19.213.87:2380"],"advertise-client-urls":["https://199.19.213.87:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.87:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-18T12:11:22.955602Z","caller":"embed/etcd.go:908","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-18T12:11:23.237353Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 is starting a new election at term 1"} {"level":"info","ts":"2026-06-18T12:11:23.237414Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-18T12:11:23.237443Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 received MsgPreVoteResp from bc3cb673a921c717 at term 1"} {"level":"info","ts":"2026-06-18T12:11:23.237459Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 became candidate at term 2"} {"level":"info","ts":"2026-06-18T12:11:23.237466Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 received MsgVoteResp from bc3cb673a921c717 at term 2"} {"level":"info","ts":"2026-06-18T12:11:23.237477Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 became leader at term 2"} {"level":"info","ts":"2026-06-18T12:11:23.237490Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: bc3cb673a921c717 elected leader bc3cb673a921c717 at term 2"} {"level":"info","ts":"2026-06-18T12:11:23.239260Z","caller":"etcdserver/server.go:2697","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-18T12:11:23.240331Z","caller":"embed/serve.go:124","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-18T12:11:23.240331Z","caller":"etcdserver/server.go:2144","msg":"published local member to cluster through raft","local-member-id":"bc3cb673a921c717","local-member-attributes":"{Name:controller-1 ClientURLs:[https://199.19.213.87:2379]}","request-path":"/0/members/bc3cb673a921c717/attributes","cluster-id":"f315f77360ac6549","publish-timeout":"7s"} {"level":"info","ts":"2026-06-18T12:11:23.240385Z","caller":"embed/serve.go:124","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-18T12:11:23.240571Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-18T12:11:23.240593Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-18T12:11:23.240973Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"} {"level":"info","ts":"2026-06-18T12:11:23.241137Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"} {"level":"info","ts":"2026-06-18T12:11:23.244184Z","caller":"embed/serve.go:275","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-18T12:11:23.244369Z","caller":"membership/cluster.go:587","msg":"set initial cluster version","cluster-id":"f315f77360ac6549","local-member-id":"bc3cb673a921c717","cluster-version":"3.5"} {"level":"info","ts":"2026-06-18T12:11:23.244436Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-18T12:11:23.244504Z","caller":"etcdserver/server.go:2721","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-18T12:11:23.244976Z","caller":"embed/serve.go:275","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.87:2379"} {"level":"warn","ts":"2026-06-18T12:11:24.315410Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.290756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-18T12:11:24.316630Z","caller":"traceutil/trace.go:171","msg":"trace[1894399593] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:2; }","duration":"103.125989ms","start":"2026-06-18T12:11:24.213486Z","end":"2026-06-18T12:11:24.316612Z","steps":["trace[1894399593] 'count revisions from in-memory index tree' (duration: 101.212414ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:11:24.315400Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.365599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-18T12:11:24.317501Z","caller":"traceutil/trace.go:171","msg":"trace[1796100985] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:2; }","duration":"115.507788ms","start":"2026-06-18T12:11:24.201947Z","end":"2026-06-18T12:11:24.317455Z","steps":["trace[1796100985] 'count revisions from in-memory index tree' (duration: 113.285757ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:11:24.317793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.145103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-18T12:11:24.317894Z","caller":"traceutil/trace.go:171","msg":"trace[556684146] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:2; }","duration":"104.263875ms","start":"2026-06-18T12:11:24.213594Z","end":"2026-06-18T12:11:24.317858Z","steps":["trace[556684146] 'range keys from in-memory index tree' (duration: 104.01791ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:11:26.000393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.362979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-06-18T12:11:26.000563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.46145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/controller-1\" limit:1 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-18T12:11:26.000698Z","caller":"traceutil/trace.go:171","msg":"trace[1033672449] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:71; }","duration":"397.564904ms","start":"2026-06-18T12:11:25.602990Z","end":"2026-06-18T12:11:26.000555Z","steps":["trace[1033672449] 'range keys from in-memory index tree' (duration: 397.329468ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:11:26.000734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:11:25.602986Z","time spent":"397.740588ms","remote":"127.0.0.1:47002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 "} {"level":"info","ts":"2026-06-18T12:11:26.000740Z","caller":"traceutil/trace.go:171","msg":"trace[364821798] range","detail":"{range_begin:/registry/csinodes/controller-1; range_end:; response_count:0; response_revision:71; }","duration":"456.678076ms","start":"2026-06-18T12:11:25.544042Z","end":"2026-06-18T12:11:26.000720Z","steps":["trace[364821798] 'range keys from in-memory index tree' (duration: 456.360089ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:11:26.000826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:11:25.544027Z","time spent":"456.789348ms","remote":"127.0.0.1:54802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":0,"response size":28,"request content":"key:\"/registry/csinodes/controller-1\" limit:1 "} {"level":"warn","ts":"2026-06-18T12:11:26.000645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.80603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-18T12:11:26.001042Z","caller":"traceutil/trace.go:171","msg":"trace[1925464809] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:71; }","duration":"398.224819ms","start":"2026-06-18T12:11:25.602809Z","end":"2026-06-18T12:11:26.001033Z","steps":["trace[1925464809] 'range keys from in-memory index tree' (duration: 397.759009ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:11:26.001075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:11:25.602794Z","time spent":"398.27324ms","remote":"127.0.0.1:47002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 "} {"level":"info","ts":"2026-06-18T12:11:29.070318Z","caller":"traceutil/trace.go:171","msg":"trace[2028599951] transaction","detail":"{read_only:false; number_of_response:0; response_revision:249; }","duration":"150.70243ms","start":"2026-06-18T12:11:28.919594Z","end":"2026-06-18T12:11:29.070297Z","steps":["trace[2028599951] 'process raft request' (duration: 150.641579ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:11:29.084271Z","caller":"traceutil/trace.go:171","msg":"trace[133644960] transaction","detail":"{read_only:false; number_of_response:0; response_revision:249; }","duration":"164.624518ms","start":"2026-06-18T12:11:28.919626Z","end":"2026-06-18T12:11:29.084250Z","steps":["trace[133644960] 'process raft request' (duration: 164.538265ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:11:29.084409Z","caller":"traceutil/trace.go:171","msg":"trace[842678633] transaction","detail":"{read_only:false; response_revision:250; number_of_response:1; }","duration":"164.72787ms","start":"2026-06-18T12:11:28.919667Z","end":"2026-06-18T12:11:29.084395Z","steps":["trace[842678633] 'process raft request' (duration: 164.553006ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:11:29.084555Z","caller":"traceutil/trace.go:171","msg":"trace[2135309850] transaction","detail":"{read_only:false; response_revision:252; number_of_response:1; }","duration":"114.740518ms","start":"2026-06-18T12:11:28.969806Z","end":"2026-06-18T12:11:29.084547Z","steps":["trace[2135309850] 'process raft request' (duration: 114.590125ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:11:29.084535Z","caller":"traceutil/trace.go:171","msg":"trace[538324080] transaction","detail":"{read_only:false; number_of_response:0; response_revision:252; }","duration":"113.836168ms","start":"2026-06-18T12:11:28.970672Z","end":"2026-06-18T12:11:29.084508Z","steps":["trace[538324080] 'process raft request' (duration: 113.753126ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:11:29.084587Z","caller":"traceutil/trace.go:171","msg":"trace[1050388957] transaction","detail":"{read_only:false; response_revision:251; number_of_response:1; }","duration":"164.76318ms","start":"2026-06-18T12:11:28.919726Z","end":"2026-06-18T12:11:29.084489Z","steps":["trace[1050388957] 'process raft request' (duration: 164.646777ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:11:29.085339Z","caller":"traceutil/trace.go:171","msg":"trace[1375963039] transaction","detail":"{read_only:false; number_of_response:0; response_revision:250; }","duration":"165.56405ms","start":"2026-06-18T12:11:28.919711Z","end":"2026-06-18T12:11:29.085275Z","steps":["trace[1375963039] 'process raft request' (duration: 164.644708ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:11:30.953588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.042667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" limit:1 ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-06-18T12:11:30.953690Z","caller":"traceutil/trace.go:171","msg":"trace[1187806626] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:293; }","duration":"297.18482ms","start":"2026-06-18T12:11:30.656480Z","end":"2026-06-18T12:11:30.953665Z","steps":["trace[1187806626] 'range keys from in-memory index tree' (duration: 296.954014ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:11:31.236468Z","caller":"traceutil/trace.go:171","msg":"trace[1648054648] transaction","detail":"{read_only:false; response_revision:294; number_of_response:1; }","duration":"277.971822ms","start":"2026-06-18T12:11:30.958468Z","end":"2026-06-18T12:11:31.236440Z","steps":["trace[1648054648] 'process raft request' (duration: 277.847389ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:11:31.385061Z","caller":"traceutil/trace.go:171","msg":"trace[158915274] transaction","detail":"{read_only:false; response_revision:295; number_of_response:1; }","duration":"138.447899ms","start":"2026-06-18T12:11:31.246583Z","end":"2026-06-18T12:11:31.385030Z","steps":["trace[158915274] 'process raft request' (duration: 138.319376ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:11:31.741624Z","caller":"traceutil/trace.go:171","msg":"trace[547177120] transaction","detail":"{read_only:false; response_revision:301; number_of_response:1; }","duration":"134.807354ms","start":"2026-06-18T12:11:31.606785Z","end":"2026-06-18T12:11:31.741592Z","steps":["trace[547177120] 'process raft request' (duration: 50.170977ms)","trace[547177120] 'compare' (duration: 84.501754ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T12:11:33.045277Z","caller":"traceutil/trace.go:171","msg":"trace[113879597] linearizableReadLoop","detail":"{readStateIndex:331; appliedIndex:330; }","duration":"138.872642ms","start":"2026-06-18T12:11:32.906380Z","end":"2026-06-18T12:11:33.045253Z","steps":["trace[113879597] 'read index received' (duration: 116.273221ms)","trace[113879597] 'applied index is now lower than readState.Index' (duration: 22.598551ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T12:11:33.045456Z","caller":"traceutil/trace.go:171","msg":"trace[1329905980] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"176.173068ms","start":"2026-06-18T12:11:32.869249Z","end":"2026-06-18T12:11:33.045422Z","steps":["trace[1329905980] 'process raft request' (duration: 153.447784ms)","trace[1329905980] 'compare' (duration: 22.466708ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T12:11:33.045522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.128368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" limit:1 ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-06-18T12:11:33.045560Z","caller":"traceutil/trace.go:171","msg":"trace[1118113570] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:314; }","duration":"139.21067ms","start":"2026-06-18T12:11:32.906338Z","end":"2026-06-18T12:11:33.045549Z","steps":["trace[1118113570] 'agreement among raft nodes before linearized reading' (duration: 139.034486ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:11:33.267273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.10067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T12:11:33.267372Z","caller":"traceutil/trace.go:171","msg":"trace[1398760834] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:0; response_revision:316; }","duration":"111.242724ms","start":"2026-06-18T12:11:33.156095Z","end":"2026-06-18T12:11:33.267338Z","steps":["trace[1398760834] 'range keys from in-memory index tree' (duration: 111.024609ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:11:33.604040Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.315636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" limit:1 ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-06-18T12:11:33.604139Z","caller":"traceutil/trace.go:171","msg":"trace[1356946358] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:1; response_revision:317; }","duration":"197.470199ms","start":"2026-06-18T12:11:33.406649Z","end":"2026-06-18T12:11:33.604120Z","steps":["trace[1356946358] 'range keys from in-memory index tree' (duration: 197.228794ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:11:33.606537Z","caller":"traceutil/trace.go:171","msg":"trace[1538335964] transaction","detail":"{read_only:false; response_revision:318; number_of_response:1; }","duration":"181.140347ms","start":"2026-06-18T12:11:33.425379Z","end":"2026-06-18T12:11:33.606519Z","steps":["trace[1538335964] 'process raft request' (duration: 180.905211ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:11:35.130798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.953146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" limit:1 ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-06-18T12:11:35.130910Z","caller":"traceutil/trace.go:171","msg":"trace[1550441179] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:345; }","duration":"124.11315ms","start":"2026-06-18T12:11:35.006783Z","end":"2026-06-18T12:11:35.130897Z","steps":["trace[1550441179] 'range keys from in-memory index tree' (duration: 123.843944ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:11:35.761652Z","caller":"traceutil/trace.go:171","msg":"trace[146084743] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"291.540948ms","start":"2026-06-18T12:11:35.470096Z","end":"2026-06-18T12:11:35.761637Z","steps":["trace[146084743] 'process raft request' (duration: 291.420885ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:11:36.055774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.143308ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-18T12:11:36.055941Z","caller":"traceutil/trace.go:171","msg":"trace[366091172] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"300.023019ms","start":"2026-06-18T12:11:35.755905Z","end":"2026-06-18T12:11:36.055928Z","steps":["trace[366091172] 'process raft request' (duration: 299.978978ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:11:36.055973Z","caller":"traceutil/trace.go:171","msg":"trace[358819779] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"445.159912ms","start":"2026-06-18T12:11:35.610799Z","end":"2026-06-18T12:11:36.055959Z","steps":["trace[358819779] 'process raft request' (duration: 210.462991ms)","trace[358819779] 'compare' (duration: 234.057026ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T12:11:36.055993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:11:35.755887Z","time spent":"300.08386ms","remote":"127.0.0.1:47002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":468,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-18T12:11:36.056039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:11:35.610783Z","time spent":"445.225305ms","remote":"127.0.0.1:47002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":495,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T12:11:36.336529Z","caller":"traceutil/trace.go:171","msg":"trace[47722945] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"198.930553ms","start":"2026-06-18T12:11:36.137564Z","end":"2026-06-18T12:11:36.336495Z","steps":["trace[47722945] 'process raft request' (duration: 149.608281ms)","trace[47722945] 'compare' (duration: 49.102676ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T12:11:36.336650Z","caller":"traceutil/trace.go:171","msg":"trace[473583350] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"197.220892ms","start":"2026-06-18T12:11:36.139395Z","end":"2026-06-18T12:11:36.336616Z","steps":["trace[473583350] 'process raft request' (duration: 197.048858ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:11:36.974742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.45868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/controller-1\" limit:1 ","response":"range_response_count:1 size:4059"} {"level":"info","ts":"2026-06-18T12:11:36.974798Z","caller":"traceutil/trace.go:171","msg":"trace[1161488055] range","detail":"{range_begin:/registry/minions/controller-1; range_end:; response_count:1; response_revision:381; }","duration":"189.582022ms","start":"2026-06-18T12:11:36.785202Z","end":"2026-06-18T12:11:36.974784Z","steps":["trace[1161488055] 'range keys from in-memory index tree' (duration: 189.388028ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:11:38.144526Z","caller":"traceutil/trace.go:171","msg":"trace[833030310] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"144.591947ms","start":"2026-06-18T12:11:37.999907Z","end":"2026-06-18T12:11:38.144499Z","steps":["trace[833030310] 'process raft request' (duration: 144.447664ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:11:38.361717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.633482ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-18T12:11:38.361882Z","caller":"traceutil/trace.go:171","msg":"trace[212741200] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"299.936088ms","start":"2026-06-18T12:11:38.061923Z","end":"2026-06-18T12:11:38.361859Z","steps":["trace[212741200] 'process raft request' (duration: 168.101711ms)","trace[212741200] 'compare' (duration: 131.523999ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T12:11:38.361974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:11:38.061898Z","time spent":"300.036909ms","remote":"127.0.0.1:47002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":467,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T12:11:38.361904Z","caller":"traceutil/trace.go:171","msg":"trace[1284452350] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"299.966878ms","start":"2026-06-18T12:11:38.061926Z","end":"2026-06-18T12:11:38.361893Z","steps":["trace[1284452350] 'process raft request' (duration: 299.890807ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:11:38.362070Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:11:38.061903Z","time spent":"300.139102ms","remote":"127.0.0.1:47002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T12:11:38.362111Z","caller":"traceutil/trace.go:171","msg":"trace[1163538011] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"209.272141ms","start":"2026-06-18T12:11:38.152825Z","end":"2026-06-18T12:11:38.362097Z","steps":["trace[1163538011] 'process raft request' (duration: 209.030296ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:11:38.521379Z","caller":"traceutil/trace.go:171","msg":"trace[1412692790] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"154.875719ms","start":"2026-06-18T12:11:38.366476Z","end":"2026-06-18T12:11:38.521352Z","steps":["trace[1412692790] 'process raft request' (duration: 139.022306ms)","trace[1412692790] 'compare' (duration: 15.638658ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T12:11:38.537149Z","caller":"traceutil/trace.go:171","msg":"trace[1493216950] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"169.863153ms","start":"2026-06-18T12:11:38.367261Z","end":"2026-06-18T12:11:38.537125Z","steps":["trace[1493216950] 'process raft request' (duration: 169.663458ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:11:39.079356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.466209ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-18T12:11:39.079730Z","caller":"traceutil/trace.go:171","msg":"trace[1907405175] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"178.709331ms","start":"2026-06-18T12:11:38.900990Z","end":"2026-06-18T12:11:39.079699Z","steps":["trace[1907405175] 'process raft request' (duration: 60.841653ms)","trace[1907405175] 'compare' (duration: 117.362837ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T12:11:40.518603Z","caller":"traceutil/trace.go:171","msg":"trace[1930128016] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"150.324136ms","start":"2026-06-18T12:11:40.368245Z","end":"2026-06-18T12:11:40.518569Z","steps":["trace[1930128016] 'process raft request' (duration: 124.364863ms)","trace[1930128016] 'compare' (duration: 25.628365ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T12:11:47.622933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.249879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-18T12:11:47.622997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.683084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.87\" limit:1 ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-06-18T12:11:47.623074Z","caller":"traceutil/trace.go:171","msg":"trace[58060088] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:415; }","duration":"178.446394ms","start":"2026-06-18T12:11:47.444606Z","end":"2026-06-18T12:11:47.623052Z","steps":["trace[58060088] 'range keys from in-memory index tree' (duration: 178.116107ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:11:47.623101Z","caller":"traceutil/trace.go:171","msg":"trace[364753546] range","detail":"{range_begin:/registry/masterleases/199.19.213.87; range_end:; response_count:1; response_revision:415; }","duration":"544.91239ms","start":"2026-06-18T12:11:47.078164Z","end":"2026-06-18T12:11:47.623077Z","steps":["trace[364753546] 'range keys from in-memory index tree' (duration: 544.594192ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:11:47.623076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.432286ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-18T12:11:47.623163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:11:47.078146Z","time spent":"544.999391ms","remote":"127.0.0.1:46778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.87\" limit:1 "} {"level":"info","ts":"2026-06-18T12:11:47.623182Z","caller":"traceutil/trace.go:171","msg":"trace[28797854] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:415; }","duration":"527.559679ms","start":"2026-06-18T12:11:47.095599Z","end":"2026-06-18T12:11:47.623158Z","steps":["trace[28797854] 'range keys from in-memory index tree' (duration: 527.387135ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:12:04.677508Z","caller":"traceutil/trace.go:171","msg":"trace[1835217388] transaction","detail":"{read_only:false; response_revision:453; number_of_response:1; }","duration":"112.540033ms","start":"2026-06-18T12:12:04.564939Z","end":"2026-06-18T12:12:04.677479Z","steps":["trace[1835217388] 'process raft request' (duration: 112.391349ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:12:04.850000Z","caller":"traceutil/trace.go:171","msg":"trace[1188608983] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"144.839183ms","start":"2026-06-18T12:12:04.705127Z","end":"2026-06-18T12:12:04.849967Z","steps":["trace[1188608983] 'process raft request' (duration: 144.343231ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:12:05.010545Z","caller":"traceutil/trace.go:171","msg":"trace[350471203] linearizableReadLoop","detail":"{readStateIndex:479; appliedIndex:478; }","duration":"106.573961ms","start":"2026-06-18T12:12:04.903950Z","end":"2026-06-18T12:12:05.010524Z","steps":["trace[350471203] 'read index received' (duration: 72.54663ms)","trace[350471203] 'applied index is now lower than readState.Index' (duration: 34.026891ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T12:12:05.010645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.670874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-06-18T12:12:05.010667Z","caller":"traceutil/trace.go:171","msg":"trace[270054170] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:455; }","duration":"106.727705ms","start":"2026-06-18T12:12:04.903932Z","end":"2026-06-18T12:12:05.010660Z","steps":["trace[270054170] 'agreement among raft nodes before linearized reading' (duration: 106.668243ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:12:05.010647Z","caller":"traceutil/trace.go:171","msg":"trace[47782707] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"120.685948ms","start":"2026-06-18T12:12:04.889931Z","end":"2026-06-18T12:12:05.010617Z","steps":["trace[47782707] 'process raft request' (duration: 86.530883ms)","trace[47782707] 'compare' (duration: 33.992201ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T12:12:20.099704Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 switched to configuration voters=(13563916785608148759) learners=(2541242970083901836)"} {"level":"info","ts":"2026-06-18T12:12:20.100207Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f315f77360ac6549","local-member-id":"bc3cb673a921c717","added-peer-id":"23444f0467cf418c","added-peer-peer-urls":["https://199.19.213.213:2380"],"added-peer-is-learner":true} {"level":"info","ts":"2026-06-18T12:12:20.100262Z","caller":"rafthttp/peer.go:133","msg":"starting remote peer","remote-peer-id":"23444f0467cf418c"} {"level":"info","ts":"2026-06-18T12:12:20.100315Z","caller":"rafthttp/pipeline.go:72","msg":"started HTTP pipelining with remote peer","local-member-id":"bc3cb673a921c717","remote-peer-id":"23444f0467cf418c"} {"level":"info","ts":"2026-06-18T12:12:20.100425Z","caller":"rafthttp/stream.go:169","msg":"started stream writer with remote peer","local-member-id":"bc3cb673a921c717","remote-peer-id":"23444f0467cf418c"} {"level":"info","ts":"2026-06-18T12:12:20.100523Z","caller":"rafthttp/peer.go:137","msg":"started remote peer","remote-peer-id":"23444f0467cf418c"} {"level":"info","ts":"2026-06-18T12:12:20.100543Z","caller":"rafthttp/stream.go:395","msg":"started stream reader with remote peer","stream-reader-type":"stream Message","local-member-id":"bc3cb673a921c717","remote-peer-id":"23444f0467cf418c"} {"level":"info","ts":"2026-06-18T12:12:20.100582Z","caller":"rafthttp/stream.go:169","msg":"started stream writer with remote peer","local-member-id":"bc3cb673a921c717","remote-peer-id":"23444f0467cf418c"} {"level":"info","ts":"2026-06-18T12:12:20.100627Z","caller":"rafthttp/transport.go:317","msg":"added remote peer","local-member-id":"bc3cb673a921c717","remote-peer-id":"23444f0467cf418c","remote-peer-urls":["https://199.19.213.213:2380"]} {"level":"info","ts":"2026-06-18T12:12:20.100666Z","caller":"rafthttp/stream.go:395","msg":"started stream reader with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"bc3cb673a921c717","remote-peer-id":"23444f0467cf418c"} {"level":"info","ts":"2026-06-18T12:12:20.100718Z","caller":"etcdserver/server.go:2021","msg":"applied a configuration change through raft","local-member-id":"bc3cb673a921c717","raft-conf-change":"ConfChangeAddLearnerNode","raft-conf-change-node-id":"23444f0467cf418c"} {"level":"info","ts":"2026-06-18T12:12:21.702476Z","caller":"rafthttp/stream.go:249","msg":"set message encoder","from":"bc3cb673a921c717","to":"23444f0467cf418c","stream-type":"stream MsgApp v2"} {"level":"info","ts":"2026-06-18T12:12:21.702647Z","caller":"rafthttp/peer_status.go:53","msg":"peer became active","peer-id":"23444f0467cf418c"} {"level":"info","ts":"2026-06-18T12:12:21.702750Z","caller":"rafthttp/stream.go:274","msg":"established TCP streaming connection with remote peer","stream-writer-type":"stream MsgApp v2","local-member-id":"bc3cb673a921c717","remote-peer-id":"23444f0467cf418c"} {"level":"info","ts":"2026-06-18T12:12:21.710009Z","caller":"rafthttp/stream.go:412","msg":"established TCP streaming connection with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"bc3cb673a921c717","remote-peer-id":"23444f0467cf418c"} {"level":"info","ts":"2026-06-18T12:12:21.710081Z","caller":"rafthttp/stream.go:249","msg":"set message encoder","from":"bc3cb673a921c717","to":"23444f0467cf418c","stream-type":"stream Message"} {"level":"info","ts":"2026-06-18T12:12:21.710112Z","caller":"rafthttp/stream.go:274","msg":"established TCP streaming connection with remote peer","stream-writer-type":"stream Message","local-member-id":"bc3cb673a921c717","remote-peer-id":"23444f0467cf418c"} {"level":"info","ts":"2026-06-18T12:12:21.713136Z","caller":"rafthttp/stream.go:412","msg":"established TCP streaming connection with remote peer","stream-reader-type":"stream Message","local-member-id":"bc3cb673a921c717","remote-peer-id":"23444f0467cf418c"} {"level":"info","ts":"2026-06-18T12:12:22.146889Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 switched to configuration voters=(2541242970083901836 13563916785608148759)"} {"level":"info","ts":"2026-06-18T12:12:22.147082Z","caller":"membership/cluster.go:537","msg":"promote member","cluster-id":"f315f77360ac6549","local-member-id":"bc3cb673a921c717","promoted-member-id":"23444f0467cf418c"} {"level":"info","ts":"2026-06-18T12:12:22.147160Z","caller":"etcdserver/server.go:2021","msg":"applied a configuration change through raft","local-member-id":"bc3cb673a921c717","raft-conf-change":"ConfChangeAddNode","raft-conf-change-node-id":"23444f0467cf418c"} {"level":"info","ts":"2026-06-18T12:12:30.557483Z","caller":"traceutil/trace.go:171","msg":"trace[249920187] linearizableReadLoop","detail":"{readStateIndex:575; appliedIndex:576; }","duration":"462.137723ms","start":"2026-06-18T12:12:30.095323Z","end":"2026-06-18T12:12:30.557460Z","steps":["trace[249920187] 'read index received' (duration: 462.132003ms)","trace[249920187] 'applied index is now lower than readState.Index' (duration: 4.28µs)"],"step_count":2} {"level":"warn","ts":"2026-06-18T12:12:30.557634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.293287ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T12:12:30.557678Z","caller":"traceutil/trace.go:171","msg":"trace[1441881045] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:541; }","duration":"462.350578ms","start":"2026-06-18T12:12:30.095316Z","end":"2026-06-18T12:12:30.557667Z","steps":["trace[1441881045] 'agreement among raft nodes before linearized reading' (duration: 462.271446ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:12:30.558332Z","caller":"traceutil/trace.go:171","msg":"trace[115623974] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"1.07397733s","start":"2026-06-18T12:12:29.484336Z","end":"2026-06-18T12:12:30.558314Z","steps":["trace[115623974] 'process raft request' (duration: 1.073228512s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:12:30.558446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:12:29.484318Z","time spent":"1.074069802s","remote":"127.0.0.1:54936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-18T12:12:30.558697Z","caller":"traceutil/trace.go:171","msg":"trace[1519782175] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"427.999113ms","start":"2026-06-18T12:12:30.130499Z","end":"2026-06-18T12:12:30.558498Z","steps":["trace[1519782175] 'process raft request' (duration: 427.759707ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:12:30.559176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.4415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T12:12:30.559309Z","caller":"traceutil/trace.go:171","msg":"trace[1890021668] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:543; }","duration":"114.578293ms","start":"2026-06-18T12:12:30.444716Z","end":"2026-06-18T12:12:30.559294Z","steps":["trace[1890021668] 'agreement among raft nodes before linearized reading' (duration: 114.422899ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:12:30.559631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:12:30.130479Z","time spent":"428.326191ms","remote":"127.0.0.1:47002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":536,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T12:12:51.328229Z","caller":"traceutil/trace.go:171","msg":"trace[1129088824] linearizableReadLoop","detail":"{readStateIndex:695; appliedIndex:695; }","duration":"232.833617ms","start":"2026-06-18T12:12:51.095374Z","end":"2026-06-18T12:12:51.328207Z","steps":["trace[1129088824] 'read index received' (duration: 232.828377ms)","trace[1129088824] 'applied index is now lower than readState.Index' (duration: 4.03µs)"],"step_count":2} {"level":"warn","ts":"2026-06-18T12:12:51.328452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.072404ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T12:12:51.328490Z","caller":"traceutil/trace.go:171","msg":"trace[1838316090] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:652; }","duration":"233.124035ms","start":"2026-06-18T12:12:51.095357Z","end":"2026-06-18T12:12:51.328481Z","steps":["trace[1838316090] 'agreement among raft nodes before linearized reading' (duration: 233.052583ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:12:51.328843Z","caller":"traceutil/trace.go:171","msg":"trace[243107353] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"268.106899ms","start":"2026-06-18T12:12:51.060725Z","end":"2026-06-18T12:12:51.328832Z","steps":["trace[243107353] 'process raft request' (duration: 268.010747ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:12:54.996244Z","caller":"traceutil/trace.go:171","msg":"trace[2067282557] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"110.106568ms","start":"2026-06-18T12:12:54.886101Z","end":"2026-06-18T12:12:54.996208Z","steps":["trace[2067282557] 'process raft request' (duration: 109.935614ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:12:57.288063Z","caller":"traceutil/trace.go:171","msg":"trace[1741502185] linearizableReadLoop","detail":"{readStateIndex:710; appliedIndex:710; }","duration":"203.907095ms","start":"2026-06-18T12:12:57.084131Z","end":"2026-06-18T12:12:57.288038Z","steps":["trace[1741502185] 'read index received' (duration: 203.899075ms)","trace[1741502185] 'applied index is now lower than readState.Index' (duration: 5.84µs)"],"step_count":2} {"level":"info","ts":"2026-06-18T12:12:57.288149Z","caller":"traceutil/trace.go:171","msg":"trace[1201027830] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"287.478133ms","start":"2026-06-18T12:12:57.000648Z","end":"2026-06-18T12:12:57.288126Z","steps":["trace[1201027830] 'process raft request' (duration: 287.36764ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:12:57.288277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.13107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.87\" limit:1 ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-06-18T12:12:57.288321Z","caller":"traceutil/trace.go:171","msg":"trace[860661990] range","detail":"{range_begin:/registry/masterleases/199.19.213.87; range_end:; response_count:1; response_revision:666; }","duration":"204.232092ms","start":"2026-06-18T12:12:57.084079Z","end":"2026-06-18T12:12:57.288311Z","steps":["trace[860661990] 'agreement among raft nodes before linearized reading' (duration: 204.095719ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:12:57.289625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.953844ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T12:12:57.289747Z","caller":"traceutil/trace.go:171","msg":"trace[2122490482] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:666; }","duration":"194.102138ms","start":"2026-06-18T12:12:57.095621Z","end":"2026-06-18T12:12:57.289723Z","steps":["trace[2122490482] 'agreement among raft nodes before linearized reading' (duration: 193.912133ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:12:57.498062Z","caller":"traceutil/trace.go:171","msg":"trace[859068758] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"124.290611ms","start":"2026-06-18T12:12:57.373746Z","end":"2026-06-18T12:12:57.498037Z","steps":["trace[859068758] 'process raft request' (duration: 124.2432ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:12:57.498082Z","caller":"traceutil/trace.go:171","msg":"trace[1967186714] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"127.487409ms","start":"2026-06-18T12:12:57.370561Z","end":"2026-06-18T12:12:57.498049Z","steps":["trace[1967186714] 'process raft request' (duration: 107.071666ms)","trace[1967186714] 'compare' (duration: 20.248309ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T12:12:58.089402Z","caller":"etcdserver/raft.go:427","msg":"leader failed to send out heartbeat on time; took too long, leader is overloaded likely from slow disk","to":"23444f0467cf418c","heartbeat-interval":"100ms","expected-duration":"200ms","exceeded-duration":"151.831788ms"} {"level":"info","ts":"2026-06-18T12:12:58.144207Z","caller":"traceutil/trace.go:171","msg":"trace[912906036] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"332.836409ms","start":"2026-06-18T12:12:57.811347Z","end":"2026-06-18T12:12:58.144184Z","steps":["trace[912906036] 'process raft request' (duration: 332.609804ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:12:58.144433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:12:57.811325Z","time spent":"332.950841ms","remote":"127.0.0.1:47002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":677,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T12:13:06.193975Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 switched to configuration voters=(2541242970083901836 13563916785608148759) learners=(16919855025062231661)"} {"level":"info","ts":"2026-06-18T12:13:06.194110Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f315f77360ac6549","local-member-id":"bc3cb673a921c717","added-peer-id":"eacf668003f13e6d","added-peer-peer-urls":["https://199.19.213.175:2380"],"added-peer-is-learner":true} {"level":"info","ts":"2026-06-18T12:13:06.194142Z","caller":"rafthttp/peer.go:133","msg":"starting remote peer","remote-peer-id":"eacf668003f13e6d"} {"level":"info","ts":"2026-06-18T12:13:06.194167Z","caller":"rafthttp/pipeline.go:72","msg":"started HTTP pipelining with remote peer","local-member-id":"bc3cb673a921c717","remote-peer-id":"eacf668003f13e6d"} {"level":"info","ts":"2026-06-18T12:13:06.194332Z","caller":"rafthttp/peer.go:137","msg":"started remote peer","remote-peer-id":"eacf668003f13e6d"} {"level":"info","ts":"2026-06-18T12:13:06.194370Z","caller":"rafthttp/transport.go:317","msg":"added remote peer","local-member-id":"bc3cb673a921c717","remote-peer-id":"eacf668003f13e6d","remote-peer-urls":["https://199.19.213.175:2380"]} {"level":"info","ts":"2026-06-18T12:13:06.194365Z","caller":"rafthttp/stream.go:169","msg":"started stream writer with remote peer","local-member-id":"bc3cb673a921c717","remote-peer-id":"eacf668003f13e6d"} {"level":"info","ts":"2026-06-18T12:13:06.194406Z","caller":"etcdserver/server.go:2021","msg":"applied a configuration change through raft","local-member-id":"bc3cb673a921c717","raft-conf-change":"ConfChangeAddLearnerNode","raft-conf-change-node-id":"eacf668003f13e6d"} {"level":"info","ts":"2026-06-18T12:13:06.194435Z","caller":"rafthttp/stream.go:395","msg":"started stream reader with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"bc3cb673a921c717","remote-peer-id":"eacf668003f13e6d"} {"level":"info","ts":"2026-06-18T12:13:06.194443Z","caller":"rafthttp/stream.go:395","msg":"started stream reader with remote peer","stream-reader-type":"stream Message","local-member-id":"bc3cb673a921c717","remote-peer-id":"eacf668003f13e6d"} {"level":"info","ts":"2026-06-18T12:13:06.194275Z","caller":"rafthttp/stream.go:169","msg":"started stream writer with remote peer","local-member-id":"bc3cb673a921c717","remote-peer-id":"eacf668003f13e6d"} {"level":"warn","ts":"2026-06-18T12:13:06.754391Z","caller":"etcdhttp/peer.go:150","msg":"failed to promote a member","member-id":"eacf668003f13e6d","error":"etcdserver: can only promote a learner member which is in sync with leader"} {"level":"warn","ts":"2026-06-18T12:13:07.280218Z","caller":"etcdserver/cluster_util.go:294","msg":"failed to reach the peer URL","address":"https://199.19.213.175:2380/version","remote-member-id":"eacf668003f13e6d","error":"Get \"https://199.19.213.175:2380/version\": dial tcp 199.19.213.175:2380: connect: connection refused"} {"level":"warn","ts":"2026-06-18T12:13:07.280299Z","caller":"etcdserver/cluster_util.go:158","msg":"failed to get version","remote-member-id":"eacf668003f13e6d","error":"Get \"https://199.19.213.175:2380/version\": dial tcp 199.19.213.175:2380: connect: connection refused"} {"level":"warn","ts":"2026-06-18T12:13:07.746541Z","caller":"etcdhttp/peer.go:150","msg":"failed to promote a member","member-id":"eacf668003f13e6d","error":"etcdserver: can only promote a learner member which is in sync with leader"} {"level":"info","ts":"2026-06-18T12:13:08.197352Z","caller":"rafthttp/peer_status.go:53","msg":"peer became active","peer-id":"eacf668003f13e6d"} {"level":"info","ts":"2026-06-18T12:13:08.205384Z","caller":"rafthttp/stream.go:249","msg":"set message encoder","from":"bc3cb673a921c717","to":"eacf668003f13e6d","stream-type":"stream Message"} {"level":"info","ts":"2026-06-18T12:13:08.205434Z","caller":"rafthttp/stream.go:274","msg":"established TCP streaming connection with remote peer","stream-writer-type":"stream Message","local-member-id":"bc3cb673a921c717","remote-peer-id":"eacf668003f13e6d"} {"level":"info","ts":"2026-06-18T12:13:08.205709Z","caller":"rafthttp/stream.go:249","msg":"set message encoder","from":"bc3cb673a921c717","to":"eacf668003f13e6d","stream-type":"stream MsgApp v2"} {"level":"info","ts":"2026-06-18T12:13:08.205822Z","caller":"rafthttp/stream.go:274","msg":"established TCP streaming connection with remote peer","stream-writer-type":"stream MsgApp v2","local-member-id":"bc3cb673a921c717","remote-peer-id":"eacf668003f13e6d"} {"level":"info","ts":"2026-06-18T12:13:08.207813Z","caller":"rafthttp/stream.go:412","msg":"established TCP streaming connection with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"bc3cb673a921c717","remote-peer-id":"eacf668003f13e6d"} {"level":"info","ts":"2026-06-18T12:13:08.211146Z","caller":"rafthttp/stream.go:412","msg":"established TCP streaming connection with remote peer","stream-reader-type":"stream Message","local-member-id":"bc3cb673a921c717","remote-peer-id":"eacf668003f13e6d"} {"level":"info","ts":"2026-06-18T12:13:08.786407Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 switched to configuration voters=(2541242970083901836 13563916785608148759 16919855025062231661)"} {"level":"info","ts":"2026-06-18T12:13:08.786560Z","caller":"membership/cluster.go:537","msg":"promote member","cluster-id":"f315f77360ac6549","local-member-id":"bc3cb673a921c717","promoted-member-id":"eacf668003f13e6d"} {"level":"info","ts":"2026-06-18T12:13:08.786607Z","caller":"etcdserver/server.go:2021","msg":"applied a configuration change through raft","local-member-id":"bc3cb673a921c717","raft-conf-change":"ConfChangeAddNode","raft-conf-change-node-id":"eacf668003f13e6d"} {"level":"warn","ts":"2026-06-18T12:13:42.071815Z","caller":"etcdserver/raft.go:427","msg":"leader failed to send out heartbeat on time; took too long, leader is overloaded likely from slow disk","to":"eacf668003f13e6d","heartbeat-interval":"100ms","expected-duration":"200ms","exceeded-duration":"95.282815ms"} {"level":"warn","ts":"2026-06-18T12:13:42.071933Z","caller":"etcdserver/raft.go:427","msg":"leader failed to send out heartbeat on time; took too long, leader is overloaded likely from slow disk","to":"23444f0467cf418c","heartbeat-interval":"100ms","expected-duration":"200ms","exceeded-duration":"95.418099ms"} {"level":"info","ts":"2026-06-18T12:13:42.072305Z","caller":"traceutil/trace.go:171","msg":"trace[639622882] transaction","detail":"{read_only:false; response_revision:861; number_of_response:1; }","duration":"291.090482ms","start":"2026-06-18T12:13:41.781178Z","end":"2026-06-18T12:13:42.072268Z","steps":["trace[639622882] 'process raft request' (duration: 290.745033ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:13:51.596773Z","caller":"etcdserver/v3_server.go:920","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14346109799914127302,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-18T12:13:51.970290Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 [logterm: 2, index: 1031, vote: bc3cb673a921c717] ignored MsgPreVote from eacf668003f13e6d [logterm: 2, index: 1017] at term 2: lease is not expired (remaining ticks: 8)"} {"level":"info","ts":"2026-06-18T12:13:51.972579Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 [logterm: 2, index: 1031, vote: bc3cb673a921c717] ignored MsgVote from eacf668003f13e6d [logterm: 2, index: 1017] at term 2: lease is not expired (remaining ticks: 8)"} {"level":"warn","ts":"2026-06-18T12:13:52.097814Z","caller":"etcdserver/v3_server.go:920","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14346109799914127302,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-18T12:13:52.598369Z","caller":"etcdserver/v3_server.go:920","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14346109799914127302,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-18T12:13:52.629769Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.948006367s","expected-duration":"1s"} {"level":"warn","ts":"2026-06-18T12:13:52.629895Z","caller":"etcdserver/raft.go:427","msg":"leader failed to send out heartbeat on time; took too long, leader is overloaded likely from slow disk","to":"eacf668003f13e6d","heartbeat-interval":"100ms","expected-duration":"200ms","exceeded-duration":"1.792510577s"} {"level":"warn","ts":"2026-06-18T12:13:52.629912Z","caller":"etcdserver/raft.go:427","msg":"leader failed to send out heartbeat on time; took too long, leader is overloaded likely from slow disk","to":"23444f0467cf418c","heartbeat-interval":"100ms","expected-duration":"200ms","exceeded-duration":"1.792530908s"} {"level":"info","ts":"2026-06-18T12:13:52.630222Z","caller":"traceutil/trace.go:171","msg":"trace[144764060] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"1.948562792s","start":"2026-06-18T12:13:50.681637Z","end":"2026-06-18T12:13:52.630200Z","steps":["trace[144764060] 'process raft request' (duration: 1.948432568s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:13:52.630352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:13:50.681619Z","time spent":"1.948678865s","remote":"127.0.0.1:46804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-18T12:13:52.631316Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 [term: 2] received a MsgAppResp message with higher term from eacf668003f13e6d [term: 3]"} {"level":"info","ts":"2026-06-18T12:13:52.631403Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 became follower at term 3"} {"level":"info","ts":"2026-06-18T12:13:52.631427Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: bc3cb673a921c717 lost leader bc3cb673a921c717 at term 3"} {"level":"info","ts":"2026-06-18T12:13:52.688131Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"found conflict at index 1018 [existing term: 2, conflicting term: 3]"} {"level":"info","ts":"2026-06-18T12:13:52.688176Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"replace the unstable entries from index 1018"} {"level":"info","ts":"2026-06-18T12:13:52.688184Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: bc3cb673a921c717 elected leader eacf668003f13e6d at term 3"} {"level":"warn","ts":"2026-06-18T12:13:52.752536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.656170824s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"","error":"etcdserver: leader changed"} {"level":"info","ts":"2026-06-18T12:13:52.752613Z","caller":"traceutil/trace.go:171","msg":"trace[1364174030] range","detail":"{range_begin:; range_end:; }","duration":"1.656265826s","start":"2026-06-18T12:13:51.096333Z","end":"2026-06-18T12:13:52.752599Z","steps":["trace[1364174030] 'agreement among raft nodes before linearized reading' (duration: 1.656168514s)"],"step_count":1} {"level":"error","ts":"2026-06-18T12:13:52.752653Z","caller":"etcdhttp/health.go:370","msg":"Health check error","path":"/readyz","reason":"[+]non_learner ok\n[+]data_corruption ok\n[+]serializable_read ok\n[-]linearizable_read failed: etcdserver: leader changed\n","status-code":503,"stacktrace":"go.etcd.io/etcd/server/v3/etcdserver/api/etcdhttp.(*CheckRegistry).installRootHttpEndpoint.newHealthHandler.func2\n\tgo.etcd.io/etcd/server/v3/etcdserver/api/etcdhttp/health.go:370\nnet/http.HandlerFunc.ServeHTTP\n\tnet/http/server.go:2220\nnet/http.(*ServeMux).ServeHTTP\n\tnet/http/server.go:2747\nnet/http.serverHandler.ServeHTTP\n\tnet/http/server.go:3210\nnet/http.(*conn).serve\n\tnet/http/server.go:2092"} {"level":"info","ts":"2026-06-18T12:13:52.780575Z","caller":"etcdserver/v3_server.go:912","msg":"first commit in current term: resending ReadIndex request"} {"level":"info","ts":"2026-06-18T12:13:52.873669Z","caller":"traceutil/trace.go:171","msg":"trace[1424382325] linearizableReadLoop","detail":"{readStateIndex:1018; appliedIndex:1019; }","duration":"121.177126ms","start":"2026-06-18T12:13:52.752477Z","end":"2026-06-18T12:13:52.873654Z","steps":["trace[1424382325] 'read index received' (duration: 121.172826ms)","trace[1424382325] 'applied index is now lower than readState.Index' (duration: 3.7µs)"],"step_count":2} {"level":"warn","ts":"2026-06-18T12:13:52.873805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"693.62476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-06-18T12:13:52.873796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.838973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-18T12:13:52.873777Z","caller":"etcdserver/server.go:1246","msg":"Current member isn't a leader","local-member-id":13563916785608148759,"new-lead":16919855025062231661} {"level":"info","ts":"2026-06-18T12:13:52.873850Z","caller":"traceutil/trace.go:171","msg":"trace[1260694100] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:935; }","duration":"693.703922ms","start":"2026-06-18T12:13:52.180137Z","end":"2026-06-18T12:13:52.873841Z","steps":["trace[1260694100] 'agreement among raft nodes before linearized reading' (duration: 693.61381ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:13:52.873870Z","caller":"etcdserver/server.go:1179","msg":"Ignore the lease revoking request because current member isn't a leader","local-member-id":13563916785608148759} {"level":"info","ts":"2026-06-18T12:13:52.873869Z","caller":"traceutil/trace.go:171","msg":"trace[719218912] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:935; }","duration":"430.961646ms","start":"2026-06-18T12:13:52.442892Z","end":"2026-06-18T12:13:52.873854Z","steps":["trace[719218912] 'agreement among raft nodes before linearized reading' (duration: 430.796912ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:13:52.873895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:13:52.180120Z","time spent":"693.762013ms","remote":"127.0.0.1:47002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 "} {"level":"warn","ts":"2026-06-18T12:13:52.873921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:13:52.442869Z","time spent":"431.035499ms","remote":"127.0.0.1:46760","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-06-18T12:13:52.873914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.036401924s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-06-18T12:13:52.873987Z","caller":"traceutil/trace.go:171","msg":"trace[1392914917] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:935; }","duration":"1.036529367s","start":"2026-06-18T12:13:51.837442Z","end":"2026-06-18T12:13:52.873971Z","steps":["trace[1392914917] 'agreement among raft nodes before linearized reading' (duration: 1.036345673s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:13:52.874024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:13:51.837425Z","time spent":"1.03659017s","remote":"127.0.0.1:47002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":465,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 "} {"level":"info","ts":"2026-06-18T12:13:52.874683Z","caller":"traceutil/trace.go:171","msg":"trace[932949869] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"234.584023ms","start":"2026-06-18T12:13:52.639455Z","end":"2026-06-18T12:13:52.874039Z","steps":["trace[932949869] 'process raft request' (duration: 234.421339ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:13:52.874738Z","caller":"traceutil/trace.go:171","msg":"trace[359585777] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"237.42187ms","start":"2026-06-18T12:13:52.637231Z","end":"2026-06-18T12:13:52.874653Z","steps":["trace[359585777] 'process raft request' (duration: 236.537566ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:13:52.874019Z","caller":"traceutil/trace.go:171","msg":"trace[552979670] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"118.947816ms","start":"2026-06-18T12:13:52.755051Z","end":"2026-06-18T12:13:52.873999Z","steps":["trace[552979670] 'process raft request' (duration: 118.874284ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:13:52.877411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.794776ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T12:13:52.877457Z","caller":"traceutil/trace.go:171","msg":"trace[304156736] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:938; }","duration":"123.830557ms","start":"2026-06-18T12:13:52.753605Z","end":"2026-06-18T12:13:52.877435Z","steps":["trace[304156736] 'agreement among raft nodes before linearized reading' (duration: 123.778166ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:13:56.525053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:13:51.527162Z","time spent":"4.997885205s","remote":"127.0.0.1:47002","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/06/18 12:13:56 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-18T12:13:56.596246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:13:51.597495Z","time spent":"4.998745711s","remote":"127.0.0.1:47002","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-06-18T12:13:57.684046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:13:50.683202Z","time spent":"7.00084021s","remote":"127.0.0.1:46804","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-06-18T12:13:57.686230Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:13:50.685904Z","time spent":"7.000321486s","remote":"127.0.0.1:46804","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-06-18T12:13:57.693547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:13:50.741425Z","time spent":"6.952110676s","remote":"127.0.0.1:46804","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/06/18 12:13:57 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-18T12:13:57.693600Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:13:50.702031Z","time spent":"6.991565411s","remote":"127.0.0.1:46804","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-06-18T12:13:57.693706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:13:50.793153Z","time spent":"6.900551849s","remote":"127.0.0.1:46804","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/06/18 12:13:57 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-18T12:13:57.693768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:13:50.745453Z","time spent":"6.948313075s","remote":"127.0.0.1:46804","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/06/18 12:13:57 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-18T12:13:57.693806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:13:50.763466Z","time spent":"6.930339255s","remote":"127.0.0.1:46804","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/06/18 12:13:57 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-18T12:13:57.693836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:13:50.756317Z","time spent":"6.937518066s","remote":"127.0.0.1:46804","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/06/18 12:13:57 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-18T12:13:57.693963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:13:50.745265Z","time spent":"6.948695755s","remote":"127.0.0.1:46804","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-06-18T12:13:57.694001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:13:50.906635Z","time spent":"6.787365093s","remote":"127.0.0.1:46804","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/06/18 12:13:57 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" 2026/06/18 12:13:57 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-18T12:13:57.694058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:13:50.777612Z","time spent":"6.916444833s","remote":"127.0.0.1:46804","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/06/18 12:13:57 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-18T12:13:58.660819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:13:51.659796Z","time spent":"7.001018411s","remote":"127.0.0.1:47002","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-06-18T12:14:00.833607Z","caller":"etcdserver/v3_server.go:920","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14346109799914127369,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-18T12:14:01.318744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.223001372s","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-06-18T12:14:01.318831Z","caller":"traceutil/trace.go:171","msg":"trace[1049071146] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:981; }","duration":"1.223093385s","start":"2026-06-18T12:14:00.095717Z","end":"2026-06-18T12:14:01.318810Z","steps":["trace[1049071146] 'range keys from in-memory index tree' (duration: 1.222965192s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:14:01.319637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"723.372802ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"warn","ts":"2026-06-18T12:14:01.334228Z","caller":"etcdserver/v3_server.go:920","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14346109799914127369,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-18T12:14:01.834696Z","caller":"etcdserver/v3_server.go:920","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14346109799914127369,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-18T12:14:02.335765Z","caller":"etcdserver/v3_server.go:920","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14346109799914127369,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-18T12:14:02.517937Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.855927332s","expected-duration":"1s"} {"level":"warn","ts":"2026-06-18T12:14:02.836220Z","caller":"etcdserver/v3_server.go:920","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14346109799914127369,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-18T12:14:03.170900Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 [logterm: 3, index: 1079, vote: 0] ignored MsgPreVote from eacf668003f13e6d [logterm: 3, index: 1079] at term 3: lease is not expired (remaining ticks: 3)"} {"level":"warn","ts":"2026-06-18T12:14:03.336900Z","caller":"etcdserver/v3_server.go:920","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14346109799914127369,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-18T12:14:03.352964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000238234s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-06-18T12:14:03.353096Z","caller":"traceutil/trace.go:171","msg":"trace[668520155] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000525952s","start":"2026-06-18T12:14:01.352532Z","end":"2026-06-18T12:14:03.353058Z","steps":["trace[668520155] 'agreement among raft nodes before linearized reading' (duration: 2.000353197s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:14:03.353227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:14:01.352515Z","time spent":"2.000696247s","remote":"127.0.0.1:46760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-18T12:14:03.445571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.001273879s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-06-18T12:14:03.445733Z","caller":"traceutil/trace.go:171","msg":"trace[934843421] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.001407243s","start":"2026-06-18T12:14:01.444234Z","end":"2026-06-18T12:14:03.445641Z","steps":["trace[934843421] 'agreement among raft nodes before linearized reading' (duration: 2.00129613s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:14:03.445941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:14:01.444221Z","time spent":"2.001661589s","remote":"127.0.0.1:46768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/06/18 12:14:03 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-06-18T12:14:03.777110Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 [logterm: 3, index: 1079, vote: 0] cast MsgPreVote for 23444f0467cf418c [logterm: 3, index: 1079] at term 3"} {"level":"warn","ts":"2026-06-18T12:14:03.837823Z","caller":"etcdserver/v3_server.go:920","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14346109799914127369,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-18T12:14:04.137495Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 is starting a new election at term 3"} {"level":"info","ts":"2026-06-18T12:14:04.137558Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 became pre-candidate at term 3"} {"level":"info","ts":"2026-06-18T12:14:04.137580Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 received MsgPreVoteResp from bc3cb673a921c717 at term 3"} {"level":"info","ts":"2026-06-18T12:14:04.137606Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 [logterm: 3, index: 1079] sent MsgPreVote request to 23444f0467cf418c at term 3"} {"level":"info","ts":"2026-06-18T12:14:04.137621Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 [logterm: 3, index: 1079] sent MsgPreVote request to eacf668003f13e6d at term 3"} {"level":"info","ts":"2026-06-18T12:14:04.137646Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: bc3cb673a921c717 lost leader eacf668003f13e6d at term 3"} {"level":"info","ts":"2026-06-18T12:14:04.138802Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 received MsgPreVoteResp from eacf668003f13e6d at term 3"} {"level":"info","ts":"2026-06-18T12:14:04.138889Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 has received 2 MsgPreVoteResp votes and 0 vote rejections"} {"level":"info","ts":"2026-06-18T12:14:04.138995Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 became candidate at term 4"} {"level":"info","ts":"2026-06-18T12:14:04.139021Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 received MsgVoteResp from bc3cb673a921c717 at term 4"} {"level":"info","ts":"2026-06-18T12:14:04.139044Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 [logterm: 3, index: 1079] sent MsgVote request to 23444f0467cf418c at term 4"} {"level":"info","ts":"2026-06-18T12:14:04.139059Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 [logterm: 3, index: 1079] sent MsgVote request to eacf668003f13e6d at term 4"} {"level":"warn","ts":"2026-06-18T12:14:04.338922Z","caller":"etcdserver/v3_server.go:920","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14346109799914127369,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-18T12:14:04.571864Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 [logterm: 3, index: 1079, vote: bc3cb673a921c717] rejected MsgPreVote from eacf668003f13e6d [logterm: 3, index: 1079] at term 4"} {"level":"info","ts":"2026-06-18T12:14:04.604626Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 [logterm: 3, index: 1079, vote: bc3cb673a921c717] rejected MsgVote from 23444f0467cf418c [logterm: 3, index: 1079] at term 4"} {"level":"info","ts":"2026-06-18T12:14:04.607363Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 became follower at term 4"} {"level":"info","ts":"2026-06-18T12:14:04.607447Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: bc3cb673a921c717 elected leader 23444f0467cf418c at term 4"} {"level":"warn","ts":"2026-06-18T12:14:04.839261Z","caller":"etcdserver/v3_server.go:920","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14346109799914127369,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-18T12:14:04.988222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.654640267s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-h8q5q\" limit:1 ","response":"","error":"etcdserver: leader changed"} {"level":"info","ts":"2026-06-18T12:14:04.988319Z","caller":"traceutil/trace.go:171","msg":"trace[1712261467] range","detail":"{range_begin:/registry/pods/kube-system/cilium-h8q5q; range_end:; }","duration":"4.654798781s","start":"2026-06-18T12:14:00.333499Z","end":"2026-06-18T12:14:04.988298Z","steps":["trace[1712261467] 'agreement among raft nodes before linearized reading' (duration: 4.654670037s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:14:04.988375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:14:00.333484Z","time spent":"4.654876394s","remote":"127.0.0.1:46910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":0,"request content":"key:\"/registry/pods/kube-system/cilium-h8q5q\" limit:1 "} {"level":"warn","ts":"2026-06-18T12:14:05.453083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000528722s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-06-18T12:14:05.453171Z","caller":"traceutil/trace.go:171","msg":"trace[555085131] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000655375s","start":"2026-06-18T12:14:03.452490Z","end":"2026-06-18T12:14:05.453146Z","steps":["trace[555085131] 'agreement among raft nodes before linearized reading' (duration: 2.000563483s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:14:05.453260Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:14:03.452472Z","time spent":"2.000755217s","remote":"127.0.0.1:46760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/06/18 12:14:05 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-18T12:14:05.489238Z","caller":"etcdserver/v3_server.go:920","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14346109799914127376,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-18T12:14:05.549612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:14:00.550629Z","time spent":"4.998971481s","remote":"127.0.0.1:47002","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/06/18 12:14:05 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-18T12:14:05.619067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:14:00.618658Z","time spent":"4.999193263s","remote":"127.0.0.1:47002","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/06/18 12:14:05 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-06-18T12:14:05.791533Z","caller":"etcdserver/v3_server.go:912","msg":"first commit in current term: resending ReadIndex request"} {"level":"info","ts":"2026-06-18T12:14:05.979257Z","caller":"traceutil/trace.go:171","msg":"trace[1796887046] linearizableReadLoop","detail":"{readStateIndex:1090; appliedIndex:1080; }","duration":"991.114386ms","start":"2026-06-18T12:14:04.988125Z","end":"2026-06-18T12:14:05.979239Z","steps":["trace[1796887046] 'read index received' (duration: 972.542904ms)","trace[1796887046] 'applied index is now lower than readState.Index' (duration: 18.570982ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T12:14:05.979396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.74778653s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T12:14:05.979454Z","caller":"traceutil/trace.go:171","msg":"trace[338113774] range","detail":"{range_begin:/registry/clusterroles; range_end:; response_count:0; response_revision:995; }","duration":"4.747889712s","start":"2026-06-18T12:14:01.231549Z","end":"2026-06-18T12:14:05.979438Z","steps":["trace[338113774] 'agreement among raft nodes before linearized reading' (duration: 4.74780143s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:14:05.979482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:14:01.231527Z","time spent":"4.747949504s","remote":"127.0.0.1:54762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":26,"response count":0,"response size":29,"request content":"key:\"/registry/clusterroles\" limit:1 "} {"level":"warn","ts":"2026-06-18T12:14:05.979480Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.609979522s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-18T12:14:05.979480Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.562066109s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-18T12:14:05.979551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.564339969s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T12:14:05.979570Z","caller":"traceutil/trace.go:171","msg":"trace[1436583038] range","detail":"{range_begin:/registry/clusterroles; range_end:; response_count:0; response_revision:995; }","duration":"5.564403001s","start":"2026-06-18T12:14:00.415162Z","end":"2026-06-18T12:14:05.979565Z","steps":["trace[1436583038] 'agreement among raft nodes before linearized reading' (duration: 5.56436525s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:14:05.979588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:14:00.415145Z","time spent":"5.564439461s","remote":"127.0.0.1:54762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":26,"response count":0,"response size":29,"request content":"key:\"/registry/clusterroles\" limit:1 "} {"level":"info","ts":"2026-06-18T12:14:05.979577Z","caller":"traceutil/trace.go:171","msg":"trace[759533245] range","detail":"{range_begin:/registry/minions; range_end:; response_count:0; response_revision:995; }","duration":"5.562194362s","start":"2026-06-18T12:14:00.417357Z","end":"2026-06-18T12:14:05.979551Z","steps":["trace[759533245] 'agreement among raft nodes before linearized reading' (duration: 5.562028818s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:14:05.979627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.237480289s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-18T12:14:05.979627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:14:00.417345Z","time spent":"5.562273324s","remote":"127.0.0.1:46900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":21,"response count":0,"response size":29,"request content":"key:\"/registry/minions\" limit:1 "} {"level":"warn","ts":"2026-06-18T12:14:05.979646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.98982792s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-06-18T12:14:05.979656Z","caller":"traceutil/trace.go:171","msg":"trace[2053200804] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:995; }","duration":"1.237547201s","start":"2026-06-18T12:14:04.742101Z","end":"2026-06-18T12:14:05.979649Z","steps":["trace[2053200804] 'agreement among raft nodes before linearized reading' (duration: 1.23750256s)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:14:05.979679Z","caller":"traceutil/trace.go:171","msg":"trace[1556698372] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:995; }","duration":"4.98989411s","start":"2026-06-18T12:14:00.989775Z","end":"2026-06-18T12:14:05.979669Z","steps":["trace[1556698372] 'agreement among raft nodes before linearized reading' (duration: 4.989840279s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:14:05.979708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:14:00.989757Z","time spent":"4.989944502s","remote":"127.0.0.1:47002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":465,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 "} {"level":"warn","ts":"2026-06-18T12:14:05.979742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.660858599s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T12:14:05.979762Z","caller":"traceutil/trace.go:171","msg":"trace[1621741425] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:995; }","duration":"4.6608771s","start":"2026-06-18T12:14:01.318878Z","end":"2026-06-18T12:14:05.979755Z","steps":["trace[1621741425] 'agreement among raft nodes before linearized reading' (duration: 4.660850019s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:14:05.979757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.602364359s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T12:14:05.979822Z","caller":"traceutil/trace.go:171","msg":"trace[566972723] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"2.623017096s","start":"2026-06-18T12:14:03.356797Z","end":"2026-06-18T12:14:05.979814Z","steps":["trace[566972723] 'process raft request' (duration: 2.622178734s)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:14:05.979824Z","caller":"traceutil/trace.go:171","msg":"trace[1201033272] range","detail":"{range_begin:/registry/clusterroles; range_end:; response_count:0; response_revision:995; }","duration":"5.602458662s","start":"2026-06-18T12:14:00.377347Z","end":"2026-06-18T12:14:05.979805Z","steps":["trace[1201033272] 'agreement among raft nodes before linearized reading' (duration: 5.602331079s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:14:05.979872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.989762648s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-18T12:14:05.979884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:14:00.377333Z","time spent":"5.602538475s","remote":"127.0.0.1:54762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":26,"response count":0,"response size":29,"request content":"key:\"/registry/clusterroles\" limit:1 "} {"level":"info","ts":"2026-06-18T12:14:05.979900Z","caller":"traceutil/trace.go:171","msg":"trace[1747302993] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:995; }","duration":"4.989817619s","start":"2026-06-18T12:14:00.990074Z","end":"2026-06-18T12:14:05.979892Z","steps":["trace[1747302993] 'agreement among raft nodes before linearized reading' (duration: 4.989766258s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:14:05.979939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:14:00.990063Z","time spent":"4.98986892s","remote":"127.0.0.1:47002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 "} {"level":"warn","ts":"2026-06-18T12:14:05.979937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:14:03.356778Z","time spent":"2.623069718s","remote":"127.0.0.1:46828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":778,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-18T12:14:05.979513Z","caller":"traceutil/trace.go:171","msg":"trace[1846672503] range","detail":"{range_begin:/registry/minions; range_end:; response_count:0; response_revision:995; }","duration":"5.610047914s","start":"2026-06-18T12:14:00.369456Z","end":"2026-06-18T12:14:05.979504Z","steps":["trace[1846672503] 'agreement among raft nodes before linearized reading' (duration: 5.609994963s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:14:05.980014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:14:00.369438Z","time spent":"5.610568997s","remote":"127.0.0.1:46900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":21,"response count":0,"response size":29,"request content":"key:\"/registry/minions\" limit:1 "} {"level":"warn","ts":"2026-06-18T12:14:05.980088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.370384184s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T12:14:05.980110Z","caller":"traceutil/trace.go:171","msg":"trace[291501599] range","detail":"{range_begin:/registry/minions; range_end:; response_count:0; response_revision:995; }","duration":"5.370437726s","start":"2026-06-18T12:14:00.609666Z","end":"2026-06-18T12:14:05.980103Z","steps":["trace[291501599] 'agreement among raft nodes before linearized reading' (duration: 5.370407475s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:14:05.980132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:14:00.609649Z","time spent":"5.370479777s","remote":"127.0.0.1:46900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":21,"response count":0,"response size":29,"request content":"key:\"/registry/minions\" limit:1 "} {"level":"warn","ts":"2026-06-18T12:14:05.979681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:14:04.742078Z","time spent":"1.237597143s","remote":"127.0.0.1:32978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-06-18T12:14:05.980185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.23751725s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T12:14:05.980214Z","caller":"traceutil/trace.go:171","msg":"trace[920761076] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:995; }","duration":"1.237569472s","start":"2026-06-18T12:14:04.742635Z","end":"2026-06-18T12:14:05.980204Z","steps":["trace[920761076] 'agreement among raft nodes before linearized reading' (duration: 1.237461629s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:14:05.979714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.681364174s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-18T12:14:05.980233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:14:04.742618Z","time spent":"1.237610143s","remote":"127.0.0.1:32978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 "} {"level":"info","ts":"2026-06-18T12:14:05.980255Z","caller":"traceutil/trace.go:171","msg":"trace[1068096401] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:0; response_revision:995; }","duration":"2.681968141s","start":"2026-06-18T12:14:03.298281Z","end":"2026-06-18T12:14:05.980249Z","steps":["trace[1068096401] 'agreement among raft nodes before linearized reading' (duration: 2.681414976s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:14:05.980291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:14:03.298263Z","time spent":"2.682008071s","remote":"127.0.0.1:47002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" limit:1 "} {"level":"warn","ts":"2026-06-18T12:14:05.979506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.621524408s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T12:14:05.980336Z","caller":"traceutil/trace.go:171","msg":"trace[1042068740] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:995; }","duration":"5.622397462s","start":"2026-06-18T12:14:00.357934Z","end":"2026-06-18T12:14:05.980332Z","steps":["trace[1042068740] 'agreement among raft nodes before linearized reading' (duration: 5.621515259s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:14:05.980362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:14:00.357920Z","time spent":"5.622436943s","remote":"127.0.0.1:46866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":29,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-06-18T12:14:05.979596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.237395397s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T12:14:05.984365Z","caller":"traceutil/trace.go:171","msg":"trace[163001702] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:995; }","duration":"1.242172544s","start":"2026-06-18T12:14:04.742170Z","end":"2026-06-18T12:14:05.984342Z","steps":["trace[163001702] 'agreement among raft nodes before linearized reading' (duration: 1.237391398s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:14:05.984512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:14:04.742161Z","time spent":"1.242326938s","remote":"127.0.0.1:32978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 "} {"level":"warn","ts":"2026-06-18T12:14:05.992275Z","caller":"etcdserver/v3_server.go:897","msg":"ignored out-of-date read index response; local node read indexes queueing up and waiting to be in sync with leader","sent-request-id":14346109799914127377,"received-request-id":14346109799914127376} {"level":"warn","ts":"2026-06-18T12:14:06.027601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.43525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" limit:1 ","response":"range_response_count:1 size:510"} {"level":"warn","ts":"2026-06-18T12:14:06.027768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.421478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T12:14:06.028361Z","caller":"traceutil/trace.go:171","msg":"trace[1735539068] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1006; }","duration":"575.024863ms","start":"2026-06-18T12:14:05.453322Z","end":"2026-06-18T12:14:06.028346Z","steps":["trace[1735539068] 'agreement among raft nodes before linearized reading' (duration: 574.212012ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:14:06.028423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:14:05.453245Z","time spent":"575.170077ms","remote":"127.0.0.1:46768","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-06-18T12:14:06.027786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.085574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-18T12:14:06.028499Z","caller":"traceutil/trace.go:171","msg":"trace[1079491358] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1006; }","duration":"670.821913ms","start":"2026-06-18T12:14:05.357658Z","end":"2026-06-18T12:14:06.028480Z","steps":["trace[1079491358] 'agreement among raft nodes before linearized reading' (duration: 669.902559ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:14:06.028556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:14:05.357644Z","time spent":"670.891155ms","remote":"127.0.0.1:54886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":2,"response size":31,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-06-18T12:14:06.027800Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.503149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" limit:1 ","response":"range_response_count:1 size:483"} {"level":"info","ts":"2026-06-18T12:14:06.028602Z","caller":"traceutil/trace.go:171","msg":"trace[1744279885] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1006; }","duration":"409.358052ms","start":"2026-06-18T12:14:05.619235Z","end":"2026-06-18T12:14:06.028593Z","steps":["trace[1744279885] 'agreement among raft nodes before linearized reading' (duration: 408.300804ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:14:06.028637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:14:05.619217Z","time spent":"409.410474ms","remote":"127.0.0.1:47002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":507,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" limit:1 "} {"level":"info","ts":"2026-06-18T12:14:06.027667Z","caller":"traceutil/trace.go:171","msg":"trace[1767732089] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1006; }","duration":"476.576524ms","start":"2026-06-18T12:14:05.551075Z","end":"2026-06-18T12:14:06.027652Z","steps":["trace[1767732089] 'agreement among raft nodes before linearized reading' (duration: 476.43226ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:14:06.028761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:14:05.551057Z","time spent":"477.689553ms","remote":"127.0.0.1:47002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":534,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" limit:1 "} {"level":"info","ts":"2026-06-18T12:14:07.762532Z","caller":"traceutil/trace.go:171","msg":"trace[1393357426] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1079; }","duration":"103.788038ms","start":"2026-06-18T12:14:07.658728Z","end":"2026-06-18T12:14:07.762516Z","steps":["trace[1393357426] 'process raft request' (duration: 103.751867ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:14:10.397571Z","caller":"traceutil/trace.go:171","msg":"trace[1553839512] linearizableReadLoop","detail":"{readStateIndex:1261; appliedIndex:1262; }","duration":"106.68428ms","start":"2026-06-18T12:14:10.290859Z","end":"2026-06-18T12:14:10.397543Z","steps":["trace[1553839512] 'read index received' (duration: 106.67678ms)","trace[1553839512] 'applied index is now lower than readState.Index' (duration: 6.15µs)"],"step_count":2} {"level":"warn","ts":"2026-06-18T12:14:10.397825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.946308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:replicaset-controller\" limit:1 ","response":"range_response_count:1 size:843"} {"level":"info","ts":"2026-06-18T12:14:10.397881Z","caller":"traceutil/trace.go:171","msg":"trace[1612658548] range","detail":"{range_begin:/registry/clusterroles/system:controller:replicaset-controller; range_end:; response_count:1; response_revision:1119; }","duration":"107.03851ms","start":"2026-06-18T12:14:10.290828Z","end":"2026-06-18T12:14:10.397867Z","steps":["trace[1612658548] 'agreement among raft nodes before linearized reading' (duration: 106.906797ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:14:10.400548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.973667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/cluster-admin\" limit:1 ","response":"range_response_count:1 size:679"} {"level":"info","ts":"2026-06-18T12:14:10.400608Z","caller":"traceutil/trace.go:171","msg":"trace[1264303111] range","detail":"{range_begin:/registry/clusterrolebindings/cluster-admin; range_end:; response_count:1; response_revision:1120; }","duration":"102.061608ms","start":"2026-06-18T12:14:10.298531Z","end":"2026-06-18T12:14:10.400593Z","steps":["trace[1264303111] 'agreement among raft nodes before linearized reading' (duration: 101.930705ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:14:19.372560Z","caller":"traceutil/trace.go:171","msg":"trace[1896353429] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"155.806751ms","start":"2026-06-18T12:14:19.216732Z","end":"2026-06-18T12:14:19.372539Z","steps":["trace[1896353429] 'process raft request' (duration: 155.642627ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:14:19.393106Z","caller":"traceutil/trace.go:171","msg":"trace[1272863638] linearizableReadLoop","detail":"{readStateIndex:1377; appliedIndex:1377; }","duration":"172.228554ms","start":"2026-06-18T12:14:19.220860Z","end":"2026-06-18T12:14:19.393089Z","steps":["trace[1272863638] 'read index received' (duration: 172.221484ms)","trace[1272863638] 'applied index is now lower than readState.Index' (duration: 5.9µs)"],"step_count":2} {"level":"warn","ts":"2026-06-18T12:14:19.542786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.892014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" limit:1 ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-06-18T12:14:19.542921Z","caller":"traceutil/trace.go:171","msg":"trace[1307841437] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:1226; }","duration":"322.072189ms","start":"2026-06-18T12:14:19.220832Z","end":"2026-06-18T12:14:19.542904Z","steps":["trace[1307841437] 'agreement among raft nodes before linearized reading' (duration: 172.665406ms)","trace[1307841437] 'range keys from in-memory index tree' (duration: 149.168887ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T12:14:19.542957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:14:19.220795Z","time spent":"322.15253ms","remote":"127.0.0.1:46854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":1,"response size":375,"request content":"key:\"/registry/namespaces/kube-system\" limit:1 "} {"level":"warn","ts":"2026-06-18T12:14:19.542994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.355562ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-18T12:14:19.543340Z","caller":"traceutil/trace.go:171","msg":"trace[1735361863] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"179.183847ms","start":"2026-06-18T12:14:19.364118Z","end":"2026-06-18T12:14:19.543302Z","steps":["trace[1735361863] 'process raft request' (duration: 29.437984ms)","trace[1735361863] 'compare' (duration: 149.209638ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T12:14:19.543551Z","caller":"traceutil/trace.go:171","msg":"trace[2085081107] linearizableReadLoop","detail":"{readStateIndex:1378; appliedIndex:1377; }","duration":"150.378129ms","start":"2026-06-18T12:14:19.393162Z","end":"2026-06-18T12:14:19.543540Z","steps":["trace[2085081107] 'read index received' (duration: 110.079498ms)","trace[2085081107] 'applied index is now lower than readState.Index' (duration: 40.297401ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T12:14:19.543628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.169731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-node-lease\" limit:1 ","response":"range_response_count:1 size:363"} {"level":"info","ts":"2026-06-18T12:14:19.543648Z","caller":"traceutil/trace.go:171","msg":"trace[199924161] range","detail":"{range_begin:/registry/namespaces/kube-node-lease; range_end:; response_count:1; response_revision:1227; }","duration":"284.212282ms","start":"2026-06-18T12:14:19.259430Z","end":"2026-06-18T12:14:19.543642Z","steps":["trace[199924161] 'agreement among raft nodes before linearized reading' (duration: 284.16826ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:14:19.543687Z","caller":"traceutil/trace.go:171","msg":"trace[915070042] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"164.623553ms","start":"2026-06-18T12:14:19.379043Z","end":"2026-06-18T12:14:19.543667Z","steps":["trace[915070042] 'process raft request' (duration: 164.550051ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:14:19.543841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.70767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-public\" limit:1 ","response":"range_response_count:1 size:351"} {"level":"warn","ts":"2026-06-18T12:14:19.543931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.940138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/coredns-687748f8b7-vz8r2.18ba2be31edf70fd\" limit:1 ","response":"range_response_count:1 size:898"} {"level":"info","ts":"2026-06-18T12:14:19.544021Z","caller":"traceutil/trace.go:171","msg":"trace[252778249] range","detail":"{range_begin:/registry/events/kube-system/coredns-687748f8b7-vz8r2.18ba2be31edf70fd; range_end:; response_count:1; response_revision:1227; }","duration":"166.102713ms","start":"2026-06-18T12:14:19.377900Z","end":"2026-06-18T12:14:19.544003Z","steps":["trace[252778249] 'agreement among raft nodes before linearized reading' (duration: 165.667832ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:14:19.544096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.864496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-public\" limit:1 ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-06-18T12:14:19.544157Z","caller":"traceutil/trace.go:171","msg":"trace[1835901997] range","detail":"{range_begin:/registry/namespaces/kube-public; range_end:; response_count:1; response_revision:1228; }","duration":"282.941769ms","start":"2026-06-18T12:14:19.261199Z","end":"2026-06-18T12:14:19.544141Z","steps":["trace[1835901997] 'agreement among raft nodes before linearized reading' (duration: 282.344843ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:14:19.544808Z","caller":"traceutil/trace.go:171","msg":"trace[1990754337] range","detail":"{range_begin:/registry/namespaces/kube-public; range_end:; response_count:1; response_revision:1228; }","duration":"318.863484ms","start":"2026-06-18T12:14:19.225061Z","end":"2026-06-18T12:14:19.543925Z","steps":["trace[1990754337] 'agreement among raft nodes before linearized reading' (duration: 318.612857ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:14:19.545006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T12:14:19.225053Z","time spent":"319.827569ms","remote":"127.0.0.1:46854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":1,"response size":375,"request content":"key:\"/registry/namespaces/kube-public\" limit:1 "} {"level":"warn","ts":"2026-06-18T12:14:19.546210Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.641449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T12:14:19.546259Z","caller":"traceutil/trace.go:171","msg":"trace[869674589] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1228; }","duration":"103.728591ms","start":"2026-06-18T12:14:19.442522Z","end":"2026-06-18T12:14:19.546250Z","steps":["trace[869674589] 'agreement among raft nodes before linearized reading' (duration: 103.654579ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:14:19.652032Z","caller":"traceutil/trace.go:171","msg":"trace[550790019] linearizableReadLoop","detail":"{readStateIndex:1379; appliedIndex:1379; }","duration":"103.349551ms","start":"2026-06-18T12:14:19.548651Z","end":"2026-06-18T12:14:19.652000Z","steps":["trace[550790019] 'read index received' (duration: 103.341951ms)","trace[550790019] 'applied index is now lower than readState.Index' (duration: 6.27µs)"],"step_count":2} {"level":"warn","ts":"2026-06-18T12:14:19.670322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.645763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T12:14:19.670408Z","caller":"traceutil/trace.go:171","msg":"trace[1971186491] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1228; }","duration":"121.756535ms","start":"2026-06-18T12:14:19.548630Z","end":"2026-06-18T12:14:19.670386Z","steps":["trace[1971186491] 'agreement among raft nodes before linearized reading' (duration: 103.504035ms)","trace[1971186491] 'range keys from in-memory index tree' (duration: 18.133477ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T12:14:19.670524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.821967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-node-lease\" limit:1 ","response":"range_response_count:1 size:363"} {"level":"info","ts":"2026-06-18T12:14:19.670584Z","caller":"traceutil/trace.go:171","msg":"trace[221942275] range","detail":"{range_begin:/registry/namespaces/kube-node-lease; range_end:; response_count:1; response_revision:1229; }","duration":"121.903829ms","start":"2026-06-18T12:14:19.548668Z","end":"2026-06-18T12:14:19.670572Z","steps":["trace[221942275] 'agreement among raft nodes before linearized reading' (duration: 121.797576ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T12:14:19.670685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.312704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-public\" limit:1 ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-06-18T12:14:19.670730Z","caller":"traceutil/trace.go:171","msg":"trace[1190797727] range","detail":"{range_begin:/registry/namespaces/kube-public; range_end:; response_count:1; response_revision:1229; }","duration":"121.397936ms","start":"2026-06-18T12:14:19.549322Z","end":"2026-06-18T12:14:19.670720Z","steps":["trace[1190797727] 'agreement among raft nodes before linearized reading' (duration: 121.320744ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T12:14:19.670826Z","caller":"traceutil/trace.go:171","msg":"trace[2061783464] transaction","detail":"{read_only:false; response_revision:1229; number_of_response:1; }","duration":"122.797672ms","start":"2026-06-18T12:14:19.548013Z","end":"2026-06-18T12:14:19.670811Z","steps":["trace[2061783464] 'process raft request' (duration: 104.131851ms)","trace[2061783464] 'compare' (duration: 18.141338ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T12:14:19.671081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.713014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-public\" limit:1 ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-06-18T12:14:19.671150Z","caller":"traceutil/trace.go:171","msg":"trace[1702478423] range","detail":"{range_begin:/registry/namespaces/kube-public; range_end:; response_count:1; response_revision:1229; }","duration":"121.792336ms","start":"2026-06-18T12:14:19.549346Z","end":"2026-06-18T12:14:19.671139Z","steps":["trace[1702478423] 'agreement among raft nodes before linearized reading' (duration: 121.665743ms)"],"step_count":1}