{"level":"warn","ts":"2026-10-02T15:14:49.596055Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-02T15:14:49.596123Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.193:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.193:2380","--initial-cluster=instance=https://162.253.55.193:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.193:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.193:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-10-02T15:14:49.596175Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-02T15:14:49.596184Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.193:2380"]} {"level":"info","ts":"2026-10-02T15:14:49.5962Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-02T15:14:49.596917Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.193:2379"]} {"level":"info","ts":"2026-10-02T15:14:49.59698Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.193:2380"],"listen-peer-urls":["https://162.253.55.193:2380"],"advertise-client-urls":["https://162.253.55.193:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.193:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.193:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-10-02T15:14:49.600774Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.609993ms"} {"level":"info","ts":"2026-10-02T15:14:49.609084Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6814e3e3d6b4d713","cluster-id":"e5e99c9118b1492a"} {"level":"info","ts":"2026-10-02T15:14:49.609154Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6814e3e3d6b4d713 switched to configuration voters=()"} {"level":"info","ts":"2026-10-02T15:14:49.609173Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6814e3e3d6b4d713 became follower at term 0"} {"level":"info","ts":"2026-10-02T15:14:49.609183Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6814e3e3d6b4d713 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-02T15:14:49.609191Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6814e3e3d6b4d713 became follower at term 1"} {"level":"info","ts":"2026-10-02T15:14:49.609221Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6814e3e3d6b4d713 switched to configuration voters=(7499869847177975571)"} {"level":"warn","ts":"2026-10-02T15:14:49.614961Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-02T15:14:49.616389Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-02T15:14:49.617681Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-10-02T15:14:49.620086Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6814e3e3d6b4d713","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-02T15:14:49.620626Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6814e3e3d6b4d713","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-02T15:14:49.620732Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-02T15:14:49.620792Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-02T15:14:49.620804Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-02T15:14:49.622142Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6814e3e3d6b4d713 switched to configuration voters=(7499869847177975571)"} {"level":"info","ts":"2026-10-02T15:14:49.622351Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e5e99c9118b1492a","local-member-id":"6814e3e3d6b4d713","added-peer-id":"6814e3e3d6b4d713","added-peer-peer-urls":["https://162.253.55.193:2380"]} {"level":"info","ts":"2026-10-02T15:14:49.6239Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-02T15:14:49.624077Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6814e3e3d6b4d713","initial-advertise-peer-urls":["https://162.253.55.193:2380"],"listen-peer-urls":["https://162.253.55.193:2380"],"advertise-client-urls":["https://162.253.55.193:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.193:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-02T15:14:49.624103Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-02T15:14:49.624189Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.193:2380"} {"level":"info","ts":"2026-10-02T15:14:49.624201Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.193:2380"} {"level":"info","ts":"2026-10-02T15:14:50.109821Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6814e3e3d6b4d713 is starting a new election at term 1"} {"level":"info","ts":"2026-10-02T15:14:50.109887Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6814e3e3d6b4d713 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-02T15:14:50.11006Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6814e3e3d6b4d713 received MsgPreVoteResp from 6814e3e3d6b4d713 at term 1"} {"level":"info","ts":"2026-10-02T15:14:50.110093Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6814e3e3d6b4d713 became candidate at term 2"} {"level":"info","ts":"2026-10-02T15:14:50.110129Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6814e3e3d6b4d713 received MsgVoteResp from 6814e3e3d6b4d713 at term 2"} {"level":"info","ts":"2026-10-02T15:14:50.11015Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6814e3e3d6b4d713 became leader at term 2"} {"level":"info","ts":"2026-10-02T15:14:50.110608Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6814e3e3d6b4d713 elected leader 6814e3e3d6b4d713 at term 2"} {"level":"info","ts":"2026-10-02T15:14:50.112901Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6814e3e3d6b4d713","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.193:2379]}","request-path":"/0/members/6814e3e3d6b4d713/attributes","cluster-id":"e5e99c9118b1492a","publish-timeout":"7s"} {"level":"info","ts":"2026-10-02T15:14:50.112933Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-02T15:14:50.112981Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-02T15:14:50.114173Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-02T15:14:50.114211Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-02T15:14:50.11504Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.193:2379"} {"level":"info","ts":"2026-10-02T15:14:50.112436Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-02T15:14:50.116901Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e5e99c9118b1492a","local-member-id":"6814e3e3d6b4d713","cluster-version":"3.5"} {"level":"info","ts":"2026-10-02T15:14:50.116977Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-02T15:14:50.117628Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-02T15:14:50.117861Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-02T15:15:31.704566Z","caller":"traceutil/trace.go:171","msg":"trace[936729668] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"197.536801ms","start":"2026-10-02T15:15:31.506964Z","end":"2026-10-02T15:15:31.704501Z","steps":["trace[936729668] 'process raft request' (duration: 197.010207ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:15:31.836552Z","caller":"traceutil/trace.go:171","msg":"trace[430258210] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"127.486189ms","start":"2026-10-02T15:15:31.708986Z","end":"2026-10-02T15:15:31.836472Z","steps":["trace[430258210] 'process raft request' (duration: 93.904066ms)","trace[430258210] 'compare' (duration: 33.265056ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T15:15:32.273174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.465905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-02T15:15:32.273339Z","caller":"traceutil/trace.go:171","msg":"trace[992971070] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:595; }","duration":"275.69176ms","start":"2026-10-02T15:15:31.997632Z","end":"2026-10-02T15:15:32.273324Z","steps":["trace[992971070] 'range keys from in-memory index tree' (duration: 275.29763ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:15:32.273381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.558133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-95gn4\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-10-02T15:15:32.273512Z","caller":"traceutil/trace.go:171","msg":"trace[1447691934] range","detail":"{range_begin:/registry/pods/kube-system/cilium-95gn4; range_end:; response_count:1; response_revision:595; }","duration":"208.77893ms","start":"2026-10-02T15:15:32.064706Z","end":"2026-10-02T15:15:32.273485Z","steps":["trace[1447691934] 'range keys from in-memory index tree' (duration: 208.366528ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:15:32.273248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.829023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T15:15:32.273664Z","caller":"traceutil/trace.go:171","msg":"trace[1081857414] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:595; }","duration":"185.288045ms","start":"2026-10-02T15:15:32.088348Z","end":"2026-10-02T15:15:32.273636Z","steps":["trace[1081857414] 'range keys from in-memory index tree' (duration: 184.720871ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:15:32.493421Z","caller":"traceutil/trace.go:171","msg":"trace[1642747534] linearizableReadLoop","detail":"{readStateIndex:622; appliedIndex:621; }","duration":"217.916505ms","start":"2026-10-02T15:15:32.275478Z","end":"2026-10-02T15:15:32.493394Z","steps":["trace[1642747534] 'read index received' (duration: 217.246048ms)","trace[1642747534] 'applied index is now lower than readState.Index' (duration: 669.287µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T15:15:32.493492Z","caller":"traceutil/trace.go:171","msg":"trace[1091118696] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"253.841548ms","start":"2026-10-02T15:15:32.239606Z","end":"2026-10-02T15:15:32.493448Z","steps":["trace[1091118696] 'process raft request' (duration: 253.244993ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:15:32.493613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.104439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T15:15:32.493698Z","caller":"traceutil/trace.go:171","msg":"trace[309872217] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:597; }","duration":"218.214652ms","start":"2026-10-02T15:15:32.275463Z","end":"2026-10-02T15:15:32.493678Z","steps":["trace[309872217] 'agreement among raft nodes before linearized reading' (duration: 218.051978ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:15:32.499812Z","caller":"traceutil/trace.go:171","msg":"trace[887974078] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"209.797275ms","start":"2026-10-02T15:15:32.289969Z","end":"2026-10-02T15:15:32.499766Z","steps":["trace[887974078] 'process raft request' (duration: 209.700003ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:15:32.499906Z","caller":"traceutil/trace.go:171","msg":"trace[1883082376] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"221.026054ms","start":"2026-10-02T15:15:32.278835Z","end":"2026-10-02T15:15:32.499861Z","steps":["trace[1883082376] 'process raft request' (duration: 220.668455ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:16:30.587269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.727506ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-02T15:16:30.58747Z","caller":"traceutil/trace.go:171","msg":"trace[1067803931] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"398.418426ms","start":"2026-10-02T15:16:30.189038Z","end":"2026-10-02T15:16:30.587456Z","steps":["trace[1067803931] 'process raft request' (duration: 398.381025ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:16:30.587512Z","caller":"traceutil/trace.go:171","msg":"trace[1148259501] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"400.207023ms","start":"2026-10-02T15:16:30.187252Z","end":"2026-10-02T15:16:30.587459Z","steps":["trace[1148259501] 'process raft request' (duration: 99.778198ms)","trace[1148259501] 'compare' (duration: 299.322876ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T15:16:30.587559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:16:30.189024Z","time spent":"398.475057ms","remote":"127.0.0.1:50100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T15:16:30.58768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:16:30.187236Z","time spent":"400.365788ms","remote":"127.0.0.1:50100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T15:16:30.587746Z","caller":"traceutil/trace.go:171","msg":"trace[1397093120] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"400.282135ms","start":"2026-10-02T15:16:30.18743Z","end":"2026-10-02T15:16:30.587713Z","steps":["trace[1397093120] 'process raft request' (duration: 399.937846ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:16:30.587852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:16:30.187421Z","time spent":"400.379217ms","remote":"127.0.0.1:50100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T15:16:30.729567Z","caller":"traceutil/trace.go:171","msg":"trace[119720249] linearizableReadLoop","detail":"{readStateIndex:1089; appliedIndex:1085; }","duration":"184.318455ms","start":"2026-10-02T15:16:30.545229Z","end":"2026-10-02T15:16:30.729548Z","steps":["trace[119720249] 'read index received' (duration: 28.162892ms)","trace[119720249] 'applied index is now lower than readState.Index' (duration: 156.154833ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T15:16:30.729624Z","caller":"traceutil/trace.go:171","msg":"trace[1166149626] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"206.766826ms","start":"2026-10-02T15:16:30.522836Z","end":"2026-10-02T15:16:30.729603Z","steps":["trace[1166149626] 'process raft request' (duration: 204.019274ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:16:30.729697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.441268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T15:16:30.729724Z","caller":"traceutil/trace.go:171","msg":"trace[94551057] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:1048; }","duration":"184.547301ms","start":"2026-10-02T15:16:30.545167Z","end":"2026-10-02T15:16:30.729715Z","steps":["trace[94551057] 'agreement among raft nodes before linearized reading' (duration: 184.470469ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:16:48.124013Z","caller":"traceutil/trace.go:171","msg":"trace[552812417] transaction","detail":"{read_only:false; response_revision:1123; number_of_response:1; }","duration":"119.942299ms","start":"2026-10-02T15:16:48.004048Z","end":"2026-10-02T15:16:48.12399Z","steps":["trace[552812417] 'process raft request' (duration: 119.648691ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:16:49.256267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.601322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T15:16:49.256374Z","caller":"traceutil/trace.go:171","msg":"trace[1688886574] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1126; }","duration":"682.757976ms","start":"2026-10-02T15:16:48.573597Z","end":"2026-10-02T15:16:49.256355Z","steps":["trace[1688886574] 'range keys from in-memory index tree' (duration: 682.52647ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:16:49.256414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:16:48.57358Z","time spent":"682.824508ms","remote":"127.0.0.1:50100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-10-02T15:16:49.256425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.908423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-10-02T15:16:49.256425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.037196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-10-02T15:16:49.256484Z","caller":"traceutil/trace.go:171","msg":"trace[1877833942] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1126; }","duration":"572.994766ms","start":"2026-10-02T15:16:48.683473Z","end":"2026-10-02T15:16:49.256467Z","steps":["trace[1877833942] 'range keys from in-memory index tree' (duration: 572.778399ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:16:49.256506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:16:48.683451Z","time spent":"573.050126ms","remote":"127.0.0.1:50100","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-svcs-lock\" "} {"level":"info","ts":"2026-10-02T15:16:49.256506Z","caller":"traceutil/trace.go:171","msg":"trace[1295596815] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1126; }","duration":"573.118438ms","start":"2026-10-02T15:16:48.683357Z","end":"2026-10-02T15:16:49.256475Z","steps":["trace[1295596815] 'range keys from in-memory index tree' (duration: 572.864422ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:16:49.256536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:16:48.683287Z","time spent":"573.241611ms","remote":"127.0.0.1:50100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-02T15:16:49.256562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.277497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-02T15:16:49.256643Z","caller":"traceutil/trace.go:171","msg":"trace[211916619] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1126; }","duration":"588.41438ms","start":"2026-10-02T15:16:48.668207Z","end":"2026-10-02T15:16:49.256621Z","steps":["trace[211916619] 'range keys from in-memory index tree' (duration: 588.096163ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:16:49.256692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:16:48.668189Z","time spent":"588.494133ms","remote":"127.0.0.1:50100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-02T15:16:49.285554Z","caller":"traceutil/trace.go:171","msg":"trace[1966970524] linearizableReadLoop","detail":"{readStateIndex:1172; appliedIndex:1171; }","duration":"252.648765ms","start":"2026-10-02T15:16:49.032886Z","end":"2026-10-02T15:16:49.285535Z","steps":["trace[1966970524] 'read index received' (duration: 252.509832ms)","trace[1966970524] 'applied index is now lower than readState.Index' (duration: 138.153µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T15:16:49.285772Z","caller":"traceutil/trace.go:171","msg":"trace[863970795] transaction","detail":"{read_only:false; response_revision:1127; number_of_response:1; }","duration":"490.378788ms","start":"2026-10-02T15:16:48.79538Z","end":"2026-10-02T15:16:49.285759Z","steps":["trace[863970795] 'process raft request' (duration: 490.05407ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:16:49.285875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:16:48.795355Z","time spent":"490.441581ms","remote":"127.0.0.1:50100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T15:16:49.286192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.300382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-02T15:16:49.286226Z","caller":"traceutil/trace.go:171","msg":"trace[202772476] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1127; }","duration":"253.370154ms","start":"2026-10-02T15:16:49.032846Z","end":"2026-10-02T15:16:49.286216Z","steps":["trace[202772476] 'agreement among raft nodes before linearized reading' (duration: 253.219671ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:16:49.310245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.013169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T15:16:49.310309Z","caller":"traceutil/trace.go:171","msg":"trace[334687389] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1131; }","duration":"222.131222ms","start":"2026-10-02T15:16:49.088163Z","end":"2026-10-02T15:16:49.310294Z","steps":["trace[334687389] 'agreement among raft nodes before linearized reading' (duration: 222.022489ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:16:49.442372Z","caller":"traceutil/trace.go:171","msg":"trace[749430759] linearizableReadLoop","detail":"{readStateIndex:1177; appliedIndex:1176; }","duration":"126.104732ms","start":"2026-10-02T15:16:49.316244Z","end":"2026-10-02T15:16:49.442349Z","steps":["trace[749430759] 'read index received' (duration: 66.282716ms)","trace[749430759] 'applied index is now lower than readState.Index' (duration: 59.821106ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T15:16:49.44243Z","caller":"traceutil/trace.go:171","msg":"trace[739343802] transaction","detail":"{read_only:false; response_revision:1132; number_of_response:1; }","duration":"152.832805ms","start":"2026-10-02T15:16:49.289562Z","end":"2026-10-02T15:16:49.442395Z","steps":["trace[739343802] 'process raft request' (duration: 92.955638ms)","trace[739343802] 'compare' (duration: 59.716323ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T15:16:49.442559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.296727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-10-02T15:16:49.44259Z","caller":"traceutil/trace.go:171","msg":"trace[70021525] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1132; }","duration":"126.375859ms","start":"2026-10-02T15:16:49.316205Z","end":"2026-10-02T15:16:49.442581Z","steps":["trace[70021525] 'agreement among raft nodes before linearized reading' (duration: 126.246016ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:16:49.471261Z","caller":"traceutil/trace.go:171","msg":"trace[1930426455] transaction","detail":"{read_only:false; response_revision:1133; number_of_response:1; }","duration":"154.957282ms","start":"2026-10-02T15:16:49.316257Z","end":"2026-10-02T15:16:49.471214Z","steps":["trace[1930426455] 'process raft request' (duration: 154.698615ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:16:49.471269Z","caller":"traceutil/trace.go:171","msg":"trace[294803812] transaction","detail":"{read_only:false; response_revision:1134; number_of_response:1; }","duration":"154.922351ms","start":"2026-10-02T15:16:49.316315Z","end":"2026-10-02T15:16:49.471237Z","steps":["trace[294803812] 'process raft request' (duration: 154.781148ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:16:49.471597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.067086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T15:16:49.471651Z","caller":"traceutil/trace.go:171","msg":"trace[1235327199] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1134; }","duration":"155.149558ms","start":"2026-10-02T15:16:49.316487Z","end":"2026-10-02T15:16:49.471637Z","steps":["trace[1235327199] 'agreement among raft nodes before linearized reading' (duration: 154.700796ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:17:01.476064Z","caller":"traceutil/trace.go:171","msg":"trace[1481499322] linearizableReadLoop","detail":"{readStateIndex:1260; appliedIndex:1259; }","duration":"141.562571ms","start":"2026-10-02T15:17:01.33448Z","end":"2026-10-02T15:17:01.476042Z","steps":["trace[1481499322] 'read index received' (duration: 141.334205ms)","trace[1481499322] 'applied index is now lower than readState.Index' (duration: 227.276µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T15:17:01.476178Z","caller":"traceutil/trace.go:171","msg":"trace[1113326107] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"144.656169ms","start":"2026-10-02T15:17:01.331458Z","end":"2026-10-02T15:17:01.476115Z","steps":["trace[1113326107] 'process raft request' (duration: 144.437404ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:17:01.476277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.784566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-02T15:17:01.476317Z","caller":"traceutil/trace.go:171","msg":"trace[1101026467] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1213; }","duration":"141.870158ms","start":"2026-10-02T15:17:01.334433Z","end":"2026-10-02T15:17:01.476303Z","steps":["trace[1101026467] 'agreement among raft nodes before linearized reading' (duration: 141.736505ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:17:01.47667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.75246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-10-02T15:17:01.476709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.879927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-02T15:17:01.476759Z","caller":"traceutil/trace.go:171","msg":"trace[480253086] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1213; }","duration":"114.99862ms","start":"2026-10-02T15:17:01.361748Z","end":"2026-10-02T15:17:01.476746Z","steps":["trace[480253086] 'agreement among raft nodes before linearized reading' (duration: 114.756743ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:17:01.476765Z","caller":"traceutil/trace.go:171","msg":"trace[186102344] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1213; }","duration":"117.887424ms","start":"2026-10-02T15:17:01.358854Z","end":"2026-10-02T15:17:01.476741Z","steps":["trace[186102344] 'agreement among raft nodes before linearized reading' (duration: 117.590186ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:17:01.476937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.626656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-02T15:17:01.476959Z","caller":"traceutil/trace.go:171","msg":"trace[1226298131] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1213; }","duration":"115.679817ms","start":"2026-10-02T15:17:01.361272Z","end":"2026-10-02T15:17:01.476952Z","steps":["trace[1226298131] 'agreement among raft nodes before linearized reading' (duration: 115.613635ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:17:01.587376Z","caller":"traceutil/trace.go:171","msg":"trace[1561555227] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"105.130176ms","start":"2026-10-02T15:17:01.482217Z","end":"2026-10-02T15:17:01.587347Z","steps":["trace[1561555227] 'process raft request' (duration: 70.501969ms)","trace[1561555227] 'compare' (duration: 34.354201ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T15:17:01.587717Z","caller":"traceutil/trace.go:171","msg":"trace[1041982274] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"105.430362ms","start":"2026-10-02T15:17:01.48227Z","end":"2026-10-02T15:17:01.5877Z","steps":["trace[1041982274] 'process raft request' (duration: 105.020242ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:17:01.588008Z","caller":"traceutil/trace.go:171","msg":"trace[243781162] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"105.179807ms","start":"2026-10-02T15:17:01.482805Z","end":"2026-10-02T15:17:01.587985Z","steps":["trace[243781162] 'process raft request' (duration: 104.834138ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:17:02.260049Z","caller":"traceutil/trace.go:171","msg":"trace[1360192021] linearizableReadLoop","detail":"{readStateIndex:1265; appliedIndex:1264; }","duration":"171.087407ms","start":"2026-10-02T15:17:02.088941Z","end":"2026-10-02T15:17:02.260028Z","steps":["trace[1360192021] 'read index received' (duration: 170.802939ms)","trace[1360192021] 'applied index is now lower than readState.Index' (duration: 283.328µs)"],"step_count":2} {"level":"warn","ts":"2026-10-02T15:17:02.260216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.262722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T15:17:02.260249Z","caller":"traceutil/trace.go:171","msg":"trace[566595387] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1218; }","duration":"171.364095ms","start":"2026-10-02T15:17:02.088876Z","end":"2026-10-02T15:17:02.26024Z","steps":["trace[566595387] 'agreement among raft nodes before linearized reading' (duration: 171.232221ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:17:02.260813Z","caller":"traceutil/trace.go:171","msg":"trace[532937395] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"667.727161ms","start":"2026-10-02T15:17:01.59306Z","end":"2026-10-02T15:17:02.260787Z","steps":["trace[532937395] 'process raft request' (duration: 666.802087ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:17:02.260953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:17:01.593027Z","time spent":"667.853465ms","remote":"127.0.0.1:50100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"}