{"level":"warn","ts":"2026-09-18T02:21:17.214192Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-18T02:21:17.214271Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.92: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.92:2380","--initial-cluster=instance=https://162.253.55.92:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.92:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.92: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-09-18T02:21:17.215918Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-18T02:21:17.215954Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.92:2380"]} {"level":"info","ts":"2026-09-18T02:21:17.215993Z","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-09-18T02:21:17.216614Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.92:2379"]} {"level":"info","ts":"2026-09-18T02:21:17.216701Z","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.92:2380"],"listen-peer-urls":["https://162.253.55.92:2380"],"advertise-client-urls":["https://162.253.55.92:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.92:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.92: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-09-18T02:21:17.222431Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.456005ms"} {"level":"info","ts":"2026-09-18T02:21:17.22817Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"39a4f14f6507253d","cluster-id":"22d06cab78b8ace5"} {"level":"info","ts":"2026-09-18T02:21:17.228243Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39a4f14f6507253d switched to configuration voters=()"} {"level":"info","ts":"2026-09-18T02:21:17.22827Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39a4f14f6507253d became follower at term 0"} {"level":"info","ts":"2026-09-18T02:21:17.228309Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 39a4f14f6507253d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-18T02:21:17.228319Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39a4f14f6507253d became follower at term 1"} {"level":"info","ts":"2026-09-18T02:21:17.228362Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39a4f14f6507253d switched to configuration voters=(4153710079642117437)"} {"level":"warn","ts":"2026-09-18T02:21:17.234474Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-18T02:21:17.235689Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-18T02:21:17.236981Z","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-09-18T02:21:17.238966Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"39a4f14f6507253d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-18T02:21:17.239324Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"39a4f14f6507253d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-18T02:21:17.239419Z","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-09-18T02:21:17.239484Z","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-09-18T02:21:17.239503Z","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-09-18T02:21:17.242432Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39a4f14f6507253d switched to configuration voters=(4153710079642117437)"} {"level":"info","ts":"2026-09-18T02:21:17.242537Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"22d06cab78b8ace5","local-member-id":"39a4f14f6507253d","added-peer-id":"39a4f14f6507253d","added-peer-peer-urls":["https://162.253.55.92:2380"]} {"level":"info","ts":"2026-09-18T02:21:17.243775Z","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-09-18T02:21:17.243921Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"39a4f14f6507253d","initial-advertise-peer-urls":["https://162.253.55.92:2380"],"listen-peer-urls":["https://162.253.55.92:2380"],"advertise-client-urls":["https://162.253.55.92:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.92:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-18T02:21:17.243969Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-18T02:21:17.243994Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.92:2380"} {"level":"info","ts":"2026-09-18T02:21:17.244019Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.92:2380"} {"level":"info","ts":"2026-09-18T02:21:17.729218Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39a4f14f6507253d is starting a new election at term 1"} {"level":"info","ts":"2026-09-18T02:21:17.729279Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39a4f14f6507253d became pre-candidate at term 1"} {"level":"info","ts":"2026-09-18T02:21:17.729311Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39a4f14f6507253d received MsgPreVoteResp from 39a4f14f6507253d at term 1"} {"level":"info","ts":"2026-09-18T02:21:17.729326Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39a4f14f6507253d became candidate at term 2"} {"level":"info","ts":"2026-09-18T02:21:17.729334Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39a4f14f6507253d received MsgVoteResp from 39a4f14f6507253d at term 2"} {"level":"info","ts":"2026-09-18T02:21:17.729348Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39a4f14f6507253d became leader at term 2"} {"level":"info","ts":"2026-09-18T02:21:17.729358Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 39a4f14f6507253d elected leader 39a4f14f6507253d at term 2"} {"level":"info","ts":"2026-09-18T02:21:17.756701Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T02:21:17.759344Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-18T02:21:17.759328Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"39a4f14f6507253d","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.92:2379]}","request-path":"/0/members/39a4f14f6507253d/attributes","cluster-id":"22d06cab78b8ace5","publish-timeout":"7s"} {"level":"info","ts":"2026-09-18T02:21:17.759387Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"22d06cab78b8ace5","local-member-id":"39a4f14f6507253d","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T02:21:17.759422Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-18T02:21:17.759536Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T02:21:17.759592Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T02:21:17.760441Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-18T02:21:17.760499Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-18T02:21:17.764794Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-18T02:21:17.765008Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.92:2379"} {"level":"info","ts":"2026-09-18T02:21:19.230136Z","caller":"traceutil/trace.go:171","msg":"trace[398306341] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"115.5296ms","start":"2026-09-18T02:21:19.114571Z","end":"2026-09-18T02:21:19.2301Z","steps":["trace[398306341] 'process raft request' (duration: 115.279825ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:21:19.230407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.864766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-18T02:21:19.230474Z","caller":"traceutil/trace.go:171","msg":"trace[278389859] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"114.886146ms","start":"2026-09-18T02:21:19.115576Z","end":"2026-09-18T02:21:19.230462Z","steps":["trace[278389859] 'process raft request' (duration: 114.372875ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:21:19.230507Z","caller":"traceutil/trace.go:171","msg":"trace[62223547] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19; }","duration":"115.008509ms","start":"2026-09-18T02:21:19.115483Z","end":"2026-09-18T02:21:19.230491Z","steps":["trace[62223547] 'agreement among raft nodes before linearized reading' (duration: 114.797874ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:21:19.230629Z","caller":"traceutil/trace.go:171","msg":"trace[1610492928] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"111.885609ms","start":"2026-09-18T02:21:19.118732Z","end":"2026-09-18T02:21:19.230617Z","steps":["trace[1610492928] 'process raft request' (duration: 111.685703ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:21:19.230632Z","caller":"traceutil/trace.go:171","msg":"trace[1543245910] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"114.389436ms","start":"2026-09-18T02:21:19.116236Z","end":"2026-09-18T02:21:19.230626Z","steps":["trace[1543245910] 'process raft request' (duration: 113.883134ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:21:19.2302Z","caller":"traceutil/trace.go:171","msg":"trace[1185815450] linearizableReadLoop","detail":"{readStateIndex:22; appliedIndex:16; }","duration":"112.699577ms","start":"2026-09-18T02:21:19.117456Z","end":"2026-09-18T02:21:19.230155Z","steps":["trace[1185815450] 'read index received' (duration: 44.396723ms)","trace[1185815450] 'applied index is now lower than readState.Index' (duration: 68.301724ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:21:19.230674Z","caller":"traceutil/trace.go:171","msg":"trace[1818946453] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"114.516548ms","start":"2026-09-18T02:21:19.116126Z","end":"2026-09-18T02:21:19.230643Z","steps":["trace[1818946453] 'process raft request' (duration: 113.949225ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:21:19.230452Z","caller":"traceutil/trace.go:171","msg":"trace[563641707] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"114.744163ms","start":"2026-09-18T02:21:19.115695Z","end":"2026-09-18T02:21:19.230439Z","steps":["trace[563641707] 'process raft request' (duration: 114.316893ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:21:19.230215Z","caller":"traceutil/trace.go:171","msg":"trace[887444406] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"117.161867ms","start":"2026-09-18T02:21:19.113027Z","end":"2026-09-18T02:21:19.230189Z","steps":["trace[887444406] 'process raft request' (duration: 48.815243ms)","trace[887444406] 'compare' (duration: 67.855434ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:21:19.231121Z","caller":"traceutil/trace.go:171","msg":"trace[1434929919] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"111.454499ms","start":"2026-09-18T02:21:19.119651Z","end":"2026-09-18T02:21:19.231105Z","steps":["trace[1434929919] 'process raft request' (duration: 110.957848ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:21:19.231407Z","caller":"traceutil/trace.go:171","msg":"trace[2039112928] transaction","detail":"{read_only:false; number_of_response:0; response_revision:20; }","duration":"111.95005ms","start":"2026-09-18T02:21:19.118899Z","end":"2026-09-18T02:21:19.230849Z","steps":["trace[2039112928] 'process raft request' (duration: 111.593621ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:21:20.196341Z","caller":"traceutil/trace.go:171","msg":"trace[987142063] linearizableReadLoop","detail":"{readStateIndex:120; appliedIndex:119; }","duration":"119.666358ms","start":"2026-09-18T02:21:20.076635Z","end":"2026-09-18T02:21:20.196302Z","steps":["trace[987142063] 'read index received' (duration: 31.61741ms)","trace[987142063] 'applied index is now lower than readState.Index' (duration: 88.047058ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:21:20.196404Z","caller":"traceutil/trace.go:171","msg":"trace[80117479] transaction","detail":"{read_only:false; response_revision:115; number_of_response:1; }","duration":"120.497876ms","start":"2026-09-18T02:21:20.075865Z","end":"2026-09-18T02:21:20.196363Z","steps":["trace[80117479] 'process raft request' (duration: 32.398557ms)","trace[80117479] 'compare' (duration: 87.904975ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:21:20.196797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.120698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-18T02:21:20.197048Z","caller":"traceutil/trace.go:171","msg":"trace[105514796] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-edit; range_end:; response_count:0; response_revision:115; }","duration":"120.431825ms","start":"2026-09-18T02:21:20.076569Z","end":"2026-09-18T02:21:20.197001Z","steps":["trace[105514796] 'agreement among raft nodes before linearized reading' (duration: 119.900633ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:21:20.696548Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2683477640713743636,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-09-18T02:21:21.197786Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2683477640713743636,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-09-18T02:21:21.2686Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.083070415s","expected-duration":"1s"} {"level":"info","ts":"2026-09-18T02:21:21.268961Z","caller":"traceutil/trace.go:171","msg":"trace[714225769] linearizableReadLoop","detail":"{readStateIndex:121; appliedIndex:120; }","duration":"1.072500218s","start":"2026-09-18T02:21:20.196436Z","end":"2026-09-18T02:21:21.268936Z","steps":["trace[714225769] 'read index received' (duration: 1.072282804s)","trace[714225769] 'applied index is now lower than readState.Index' (duration: 172.943µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:21:21.269038Z","caller":"traceutil/trace.go:171","msg":"trace[1687218892] transaction","detail":"{read_only:false; response_revision:116; number_of_response:1; }","duration":"1.083665419s","start":"2026-09-18T02:21:20.185362Z","end":"2026-09-18T02:21:21.269027Z","steps":["trace[1687218892] 'process raft request' (duration: 1.083420884s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:21:21.269229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.133409507s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-09-18T02:21:21.26925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.754538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-18T02:21:21.269284Z","caller":"traceutil/trace.go:171","msg":"trace[1786178836] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:116; }","duration":"1.13358133s","start":"2026-09-18T02:21:20.135687Z","end":"2026-09-18T02:21:21.269268Z","steps":["trace[1786178836] 'agreement among raft nodes before linearized reading' (duration: 1.133406116s)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:21:21.269339Z","caller":"traceutil/trace.go:171","msg":"trace[1350015543] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:116; }","duration":"432.88188ms","start":"2026-09-18T02:21:20.836432Z","end":"2026-09-18T02:21:21.269314Z","steps":["trace[1350015543] 'agreement among raft nodes before linearized reading' (duration: 432.763378ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:21:21.26924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.069210415s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-09-18T02:21:21.269359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:21:20.13563Z","time spent":"1.133722134s","remote":"127.0.0.1:34272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-18T02:21:21.269416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:21:20.836408Z","time spent":"432.995263ms","remote":"127.0.0.1:34608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":26,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"info","ts":"2026-09-18T02:21:21.269432Z","caller":"traceutil/trace.go:171","msg":"trace[1489650410] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:0; response_revision:116; }","duration":"1.0694571s","start":"2026-09-18T02:21:20.19995Z","end":"2026-09-18T02:21:21.269408Z","steps":["trace[1489650410] 'agreement among raft nodes before linearized reading' (duration: 1.069118772s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:21:21.269495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:21:20.199931Z","time spent":"1.069546642s","remote":"127.0.0.1:34706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/edit\" "} {"level":"warn","ts":"2026-09-18T02:21:21.269578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:21:20.18532Z","time spent":"1.083743501s","remote":"127.0.0.1:34498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-18T02:21:21.737655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.650282ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-09-18T02:21:21.737767Z","caller":"traceutil/trace.go:171","msg":"trace[1859682873] linearizableReadLoop","detail":"{readStateIndex:122; appliedIndex:121; }","duration":"459.718466ms","start":"2026-09-18T02:21:21.278028Z","end":"2026-09-18T02:21:21.737747Z","steps":["trace[1859682873] 'read index received' (duration: 275.650276ms)","trace[1859682873] 'applied index is now lower than readState.Index' (duration: 184.06693ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:21:21.737887Z","caller":"traceutil/trace.go:171","msg":"trace[1405752268] transaction","detail":"{read_only:false; response_revision:117; number_of_response:1; }","duration":"461.792843ms","start":"2026-09-18T02:21:21.276057Z","end":"2026-09-18T02:21:21.73785Z","steps":["trace[1405752268] 'process raft request' (duration: 277.700243ms)","trace[1405752268] 'compare' (duration: 183.497927ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:21:21.737965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.92461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:discovery\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-18T02:21:21.738004Z","caller":"traceutil/trace.go:171","msg":"trace[2051988335] range","detail":"{range_begin:/registry/clusterrolebindings/system:discovery; range_end:; response_count:0; response_revision:117; }","duration":"459.989582ms","start":"2026-09-18T02:21:21.278002Z","end":"2026-09-18T02:21:21.737991Z","steps":["trace[2051988335] 'agreement among raft nodes before linearized reading' (duration: 459.86558ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:21:21.738005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.257617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-09-18T02:21:21.738043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:21:21.277989Z","time spent":"460.044223ms","remote":"127.0.0.1:34708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":26,"request content":"key:\"/registry/clusterrolebindings/system:discovery\" "} {"level":"info","ts":"2026-09-18T02:21:21.738064Z","caller":"traceutil/trace.go:171","msg":"trace[958732549] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:117; }","duration":"404.36967ms","start":"2026-09-18T02:21:21.33368Z","end":"2026-09-18T02:21:21.73805Z","steps":["trace[958732549] 'agreement among raft nodes before linearized reading' (duration: 404.200386ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:21:21.738096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:21:21.33366Z","time spent":"404.43099ms","remote":"127.0.0.1:34280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-18T02:21:21.738006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.945081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:430"} {"level":"warn","ts":"2026-09-18T02:21:21.738107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:21:21.276031Z","time spent":"461.942696ms","remote":"127.0.0.1:34608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-18T02:21:21.738104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.220488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-18T02:21:21.738144Z","caller":"traceutil/trace.go:171","msg":"trace[1769037232] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:117; }","duration":"434.124725ms","start":"2026-09-18T02:21:21.303994Z","end":"2026-09-18T02:21:21.738119Z","steps":["trace[1769037232] 'agreement among raft nodes before linearized reading' (duration: 433.87905ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:21:21.738157Z","caller":"traceutil/trace.go:171","msg":"trace[203832944] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:117; }","duration":"417.32871ms","start":"2026-09-18T02:21:21.320816Z","end":"2026-09-18T02:21:21.738145Z","steps":["trace[203832944] 'agreement among raft nodes before linearized reading' (duration: 417.187277ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:21:21.738178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:21:21.303966Z","time spent":"434.203267ms","remote":"127.0.0.1:34608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":452,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-18T02:21:21.738189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:21:21.320801Z","time spent":"417.381421ms","remote":"127.0.0.1:34608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-18T02:21:59.950043Z","caller":"traceutil/trace.go:171","msg":"trace[1019642919] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"106.894097ms","start":"2026-09-18T02:21:59.843128Z","end":"2026-09-18T02:21:59.950022Z","steps":["trace[1019642919] 'process raft request' (duration: 106.786885ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:21:59.958326Z","caller":"traceutil/trace.go:171","msg":"trace[130140205] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"104.559004ms","start":"2026-09-18T02:21:59.853739Z","end":"2026-09-18T02:21:59.958298Z","steps":["trace[130140205] 'process raft request' (duration: 104.405741ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:22:02.332766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.353706ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T02:22:02.33302Z","caller":"traceutil/trace.go:171","msg":"trace[242005136] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:650; }","duration":"134.495872ms","start":"2026-09-18T02:22:02.198508Z","end":"2026-09-18T02:22:02.333004Z","steps":["trace[242005136] 'read index received' (duration: 35.44µs)","trace[242005136] 'applied index is now lower than readState.Index' (duration: 134.459362ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:22:02.333085Z","caller":"traceutil/trace.go:171","msg":"trace[320359919] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"244.398393ms","start":"2026-09-18T02:22:02.088655Z","end":"2026-09-18T02:22:02.333053Z","steps":["trace[320359919] 'compare' (duration: 235.204734ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:22:02.333351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.824798ms","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-09-18T02:22:02.33344Z","caller":"traceutil/trace.go:171","msg":"trace[1133726977] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:621; }","duration":"134.962751ms","start":"2026-09-18T02:22:02.198466Z","end":"2026-09-18T02:22:02.333429Z","steps":["trace[1133726977] 'agreement among raft nodes before linearized reading' (duration: 134.606304ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:22:03.798381Z","caller":"traceutil/trace.go:171","msg":"trace[938309930] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"124.718585ms","start":"2026-09-18T02:22:03.673636Z","end":"2026-09-18T02:22:03.798354Z","steps":["trace[938309930] 'process raft request' (duration: 124.542511ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:22:07.000332Z","caller":"traceutil/trace.go:171","msg":"trace[2074850353] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"275.534911ms","start":"2026-09-18T02:22:06.724769Z","end":"2026-09-18T02:22:07.000304Z","steps":["trace[2074850353] 'process raft request' (duration: 274.958771ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:22:07.746319Z","caller":"traceutil/trace.go:171","msg":"trace[1489503736] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"667.066012ms","start":"2026-09-18T02:22:07.079205Z","end":"2026-09-18T02:22:07.746271Z","steps":["trace[1489503736] 'process raft request' (duration: 666.807068ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:22:07.746605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:22:07.079176Z","time spent":"667.289276ms","remote":"127.0.0.1:34498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T02:22:07.756552Z","caller":"traceutil/trace.go:171","msg":"trace[506372513] linearizableReadLoop","detail":"{readStateIndex:685; appliedIndex:683; }","duration":"126.273151ms","start":"2026-09-18T02:22:07.63024Z","end":"2026-09-18T02:22:07.756513Z","steps":["trace[506372513] 'read index received' (duration: 115.71832ms)","trace[506372513] 'applied index is now lower than readState.Index' (duration: 10.553311ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:22:07.756742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.483824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T02:22:07.756797Z","caller":"traceutil/trace.go:171","msg":"trace[1550908737] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:654; }","duration":"126.604036ms","start":"2026-09-18T02:22:07.630176Z","end":"2026-09-18T02:22:07.75678Z","steps":["trace[1550908737] 'agreement among raft nodes before linearized reading' (duration: 126.462494ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:22:07.75698Z","caller":"traceutil/trace.go:171","msg":"trace[473505773] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"674.842392ms","start":"2026-09-18T02:22:07.082091Z","end":"2026-09-18T02:22:07.756934Z","steps":["trace[473505773] 'process raft request' (duration: 674.282511ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:22:07.757206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:22:07.082077Z","time spent":"675.004076ms","remote":"127.0.0.1:34578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T02:22:09.388875Z","caller":"traceutil/trace.go:171","msg":"trace[494547386] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"171.379672ms","start":"2026-09-18T02:22:09.217466Z","end":"2026-09-18T02:22:09.388846Z","steps":["trace[494547386] 'process raft request' (duration: 156.120269ms)","trace[494547386] 'compare' (duration: 15.012629ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:22:22.566416Z","caller":"traceutil/trace.go:171","msg":"trace[1131569026] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"108.452906ms","start":"2026-09-18T02:22:22.45794Z","end":"2026-09-18T02:22:22.566393Z","steps":["trace[1131569026] 'process raft request' (duration: 108.035899ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:22:38.481374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.132852ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-09-18T02:24:08.475943Z","caller":"traceutil/trace.go:171","msg":"trace[173679956] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"130.823676ms","start":"2026-09-18T02:24:08.345096Z","end":"2026-09-18T02:24:08.47592Z","steps":["trace[173679956] 'process raft request' (duration: 115.026559ms)","trace[173679956] 'compare' (duration: 15.709902ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:24:28.892401Z","caller":"traceutil/trace.go:171","msg":"trace[1810363470] transaction","detail":"{read_only:false; response_revision:1399; number_of_response:1; }","duration":"228.098253ms","start":"2026-09-18T02:24:28.664283Z","end":"2026-09-18T02:24:28.892382Z","steps":["trace[1810363470] 'process raft request' (duration: 227.979627ms)"],"step_count":1}