{"level":"warn","ts":"2026-09-18T05:18:46.965129Z","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-18T05:18:46.965211Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.242: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.242:2380","--initial-cluster=instance=https://162.253.55.242:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.242:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.242: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-18T05:18:46.965275Z","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-18T05:18:46.965286Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.242:2380"]} {"level":"info","ts":"2026-09-18T05:18:46.965302Z","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-18T05:18:46.966027Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.242:2379"]} {"level":"info","ts":"2026-09-18T05:18:46.966159Z","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.242:2380"],"listen-peer-urls":["https://162.253.55.242:2380"],"advertise-client-urls":["https://162.253.55.242:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.242:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.242: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-18T05:18:46.96993Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.503147ms"} {"level":"info","ts":"2026-09-18T05:18:46.978837Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"cc70bec9404419f6","cluster-id":"38671954b404c50e"} {"level":"info","ts":"2026-09-18T05:18:46.978967Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cc70bec9404419f6 switched to configuration voters=()"} {"level":"info","ts":"2026-09-18T05:18:46.978987Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cc70bec9404419f6 became follower at term 0"} {"level":"info","ts":"2026-09-18T05:18:46.978998Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft cc70bec9404419f6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-18T05:18:46.979004Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cc70bec9404419f6 became follower at term 1"} {"level":"info","ts":"2026-09-18T05:18:46.979052Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cc70bec9404419f6 switched to configuration voters=(14731484152704801270)"} {"level":"warn","ts":"2026-09-18T05:18:46.987251Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-18T05:18:46.988802Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-18T05:18:46.991004Z","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-18T05:18:46.993246Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"cc70bec9404419f6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-18T05:18:46.99337Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"cc70bec9404419f6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-18T05:18:46.993443Z","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-18T05:18:46.99351Z","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-18T05:18:46.993518Z","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-18T05:18:47.00193Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cc70bec9404419f6 switched to configuration voters=(14731484152704801270)"} {"level":"info","ts":"2026-09-18T05:18:47.002092Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"38671954b404c50e","local-member-id":"cc70bec9404419f6","added-peer-id":"cc70bec9404419f6","added-peer-peer-urls":["https://162.253.55.242:2380"]} {"level":"info","ts":"2026-09-18T05:18:47.003306Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.242:2380"} {"level":"info","ts":"2026-09-18T05:18:47.003324Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.242:2380"} {"level":"info","ts":"2026-09-18T05:18:47.00316Z","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-18T05:18:47.004489Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"cc70bec9404419f6","initial-advertise-peer-urls":["https://162.253.55.242:2380"],"listen-peer-urls":["https://162.253.55.242:2380"],"advertise-client-urls":["https://162.253.55.242:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.242:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-18T05:18:47.004526Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-18T05:18:47.47994Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cc70bec9404419f6 is starting a new election at term 1"} {"level":"info","ts":"2026-09-18T05:18:47.479997Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cc70bec9404419f6 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-18T05:18:47.480033Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cc70bec9404419f6 received MsgPreVoteResp from cc70bec9404419f6 at term 1"} {"level":"info","ts":"2026-09-18T05:18:47.480052Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cc70bec9404419f6 became candidate at term 2"} {"level":"info","ts":"2026-09-18T05:18:47.480067Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cc70bec9404419f6 received MsgVoteResp from cc70bec9404419f6 at term 2"} {"level":"info","ts":"2026-09-18T05:18:47.480108Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cc70bec9404419f6 became leader at term 2"} {"level":"info","ts":"2026-09-18T05:18:47.480126Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: cc70bec9404419f6 elected leader cc70bec9404419f6 at term 2"} {"level":"info","ts":"2026-09-18T05:18:47.481118Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"cc70bec9404419f6","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.242:2379]}","request-path":"/0/members/cc70bec9404419f6/attributes","cluster-id":"38671954b404c50e","publish-timeout":"7s"} {"level":"info","ts":"2026-09-18T05:18:47.482117Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T05:18:47.4823Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-18T05:18:47.482665Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-18T05:18:47.484024Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-18T05:18:47.485106Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.242:2379"} {"level":"info","ts":"2026-09-18T05:18:47.489133Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"38671954b404c50e","local-member-id":"cc70bec9404419f6","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T05:18:47.489296Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T05:18:47.48935Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T05:18:47.503671Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-18T05:18:47.503755Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-18T05:18:49.137849Z","caller":"traceutil/trace.go:171","msg":"trace[2007744851] linearizableReadLoop","detail":"{readStateIndex:84; appliedIndex:83; }","duration":"109.820098ms","start":"2026-09-18T05:18:49.027995Z","end":"2026-09-18T05:18:49.137815Z","steps":["trace[2007744851] 'read index received' (duration: 106.991942ms)","trace[2007744851] 'applied index is now lower than readState.Index' (duration: 2.827286ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:18:49.137883Z","caller":"traceutil/trace.go:171","msg":"trace[1571476455] transaction","detail":"{read_only:false; response_revision:79; number_of_response:1; }","duration":"145.449568ms","start":"2026-09-18T05:18:48.992408Z","end":"2026-09-18T05:18:49.137857Z","steps":["trace[1571476455] 'process raft request' (duration: 142.638592ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:18:49.138191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.130657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-18T05:18:49.138284Z","caller":"traceutil/trace.go:171","msg":"trace[1864573259] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:79; }","duration":"110.305472ms","start":"2026-09-18T05:18:49.027959Z","end":"2026-09-18T05:18:49.138265Z","steps":["trace[1864573259] 'agreement among raft nodes before linearized reading' (duration: 110.029894ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:19:15.649361Z","caller":"traceutil/trace.go:171","msg":"trace[1901733003] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"142.105936ms","start":"2026-09-18T05:19:15.50723Z","end":"2026-09-18T05:19:15.649336Z","steps":["trace[1901733003] 'process raft request' (duration: 141.944531ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:19:34.57068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.835308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-rcqkf\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-09-18T05:19:34.570755Z","caller":"traceutil/trace.go:171","msg":"trace[1670852957] range","detail":"{range_begin:/registry/pods/kube-system/cilium-rcqkf; range_end:; response_count:1; response_revision:635; }","duration":"107.958452ms","start":"2026-09-18T05:19:34.462781Z","end":"2026-09-18T05:19:34.57074Z","steps":["trace[1670852957] 'range keys from in-memory index tree' (duration: 107.760675ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:19:34.570697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.200303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T05:19:34.570821Z","caller":"traceutil/trace.go:171","msg":"trace[1074438094] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:635; }","duration":"132.40877ms","start":"2026-09-18T05:19:34.438399Z","end":"2026-09-18T05:19:34.570807Z","steps":["trace[1074438094] 'range keys from in-memory index tree' (duration: 132.048588ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:19:35.115471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.569516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-18T05:19:35.115625Z","caller":"traceutil/trace.go:171","msg":"trace[567591853] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:636; }","duration":"410.848894ms","start":"2026-09-18T05:19:34.704752Z","end":"2026-09-18T05:19:35.115601Z","steps":["trace[567591853] 'range keys from in-memory index tree' (duration: 410.396601ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:19:35.11554Z","caller":"traceutil/trace.go:171","msg":"trace[745591967] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"410.004408ms","start":"2026-09-18T05:19:34.705511Z","end":"2026-09-18T05:19:35.115516Z","steps":["trace[745591967] 'process raft request' (duration: 343.700189ms)","trace[745591967] 'compare' (duration: 65.999189ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:19:35.115731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:19:34.704738Z","time spent":"410.975678ms","remote":"127.0.0.1:53412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-18T05:19:35.116565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:19:34.705208Z","time spent":"410.551765ms","remote":"127.0.0.1:53248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T05:19:35.828851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.759942ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T05:19:35.829183Z","caller":"traceutil/trace.go:171","msg":"trace[988271530] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"667.670076ms","start":"2026-09-18T05:19:35.161486Z","end":"2026-09-18T05:19:35.829156Z","steps":["trace[988271530] 'process raft request' (duration: 409.334167ms)","trace[988271530] 'compare' (duration: 257.604498ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:19:35.829308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:19:35.161467Z","time spent":"667.766019ms","remote":"127.0.0.1:53412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T05:19:35.829356Z","caller":"traceutil/trace.go:171","msg":"trace[666976796] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"637.703791ms","start":"2026-09-18T05:19:35.191625Z","end":"2026-09-18T05:19:35.829329Z","steps":["trace[666976796] 'process raft request' (duration: 637.435662ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:19:35.829461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:19:35.191608Z","time spent":"637.808424ms","remote":"127.0.0.1:53652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T05:19:35.856475Z","caller":"traceutil/trace.go:171","msg":"trace[1520313924] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:660; }","duration":"596.190621ms","start":"2026-09-18T05:19:35.26026Z","end":"2026-09-18T05:19:35.85645Z","steps":["trace[1520313924] 'read index received' (duration: 310.571358ms)","trace[1520313924] 'applied index is now lower than readState.Index' (duration: 285.617823ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:19:35.856628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.923791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T05:19:35.856665Z","caller":"traceutil/trace.go:171","msg":"trace[1659039871] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:640; }","duration":"420.011383ms","start":"2026-09-18T05:19:35.436645Z","end":"2026-09-18T05:19:35.856656Z","steps":["trace[1659039871] 'agreement among raft nodes before linearized reading' (duration: 419.936631ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:19:35.856696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:19:35.436625Z","time spent":"420.064114ms","remote":"127.0.0.1:53170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-18T05:19:35.856907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.613334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-18T05:19:35.856991Z","caller":"traceutil/trace.go:171","msg":"trace[1702680260] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:640; }","duration":"596.755618ms","start":"2026-09-18T05:19:35.260222Z","end":"2026-09-18T05:19:35.856977Z","steps":["trace[1702680260] 'agreement among raft nodes before linearized reading' (duration: 596.348796ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:19:35.857063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:19:35.260208Z","time spent":"596.84004ms","remote":"127.0.0.1:53412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-18T05:19:54.079971Z","caller":"traceutil/trace.go:171","msg":"trace[709712675] linearizableReadLoop","detail":"{readStateIndex:840; appliedIndex:839; }","duration":"161.408165ms","start":"2026-09-18T05:19:53.918541Z","end":"2026-09-18T05:19:54.079949Z","steps":["trace[709712675] 'read index received' (duration: 161.190568ms)","trace[709712675] 'applied index is now lower than readState.Index' (duration: 216.717µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:19:54.080065Z","caller":"traceutil/trace.go:171","msg":"trace[1986513101] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"409.629616ms","start":"2026-09-18T05:19:53.670389Z","end":"2026-09-18T05:19:54.080019Z","steps":["trace[1986513101] 'process raft request' (duration: 409.414119ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:19:54.080212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:19:53.670372Z","time spent":"409.758589ms","remote":"127.0.0.1:53354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4905,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T05:19:54.080409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.823689ms","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-09-18T05:19:54.080523Z","caller":"traceutil/trace.go:171","msg":"trace[552764148] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:812; }","duration":"162.022814ms","start":"2026-09-18T05:19:53.918482Z","end":"2026-09-18T05:19:54.080505Z","steps":["trace[552764148] 'agreement among raft nodes before linearized reading' (duration: 161.5858ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:20:34.856597Z","caller":"traceutil/trace.go:171","msg":"trace[1142398677] linearizableReadLoop","detail":"{readStateIndex:1132; appliedIndex:1131; }","duration":"480.235985ms","start":"2026-09-18T05:20:34.376338Z","end":"2026-09-18T05:20:34.856574Z","steps":["trace[1142398677] 'read index received' (duration: 480.115631ms)","trace[1142398677] 'applied index is now lower than readState.Index' (duration: 119.284µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:20:34.856693Z","caller":"traceutil/trace.go:171","msg":"trace[1253610663] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"508.899531ms","start":"2026-09-18T05:20:34.347784Z","end":"2026-09-18T05:20:34.856684Z","steps":["trace[1253610663] 'process raft request' (duration: 508.664784ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:20:34.856767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:20:34.347767Z","time spent":"508.945453ms","remote":"127.0.0.1:53412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T05:20:34.856831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.498294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-09-18T05:20:34.856845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.456048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-18T05:20:34.856841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.763707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T05:20:34.856862Z","caller":"traceutil/trace.go:171","msg":"trace[381052653] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1093; }","duration":"480.548915ms","start":"2026-09-18T05:20:34.376303Z","end":"2026-09-18T05:20:34.856852Z","steps":["trace[381052653] 'agreement among raft nodes before linearized reading' (duration: 480.456212ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:20:34.856868Z","caller":"traceutil/trace.go:171","msg":"trace[14982937] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1093; }","duration":"420.514789ms","start":"2026-09-18T05:20:34.436346Z","end":"2026-09-18T05:20:34.856861Z","steps":["trace[14982937] 'agreement among raft nodes before linearized reading' (duration: 420.474948ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:20:34.856897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:20:34.436327Z","time spent":"420.566631ms","remote":"127.0.0.1:53170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-18T05:20:34.856897Z","caller":"traceutil/trace.go:171","msg":"trace[548824808] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1093; }","duration":"127.84567ms","start":"2026-09-18T05:20:34.729025Z","end":"2026-09-18T05:20:34.85687Z","steps":["trace[548824808] 'agreement among raft nodes before linearized reading' (duration: 127.775427ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:20:34.856905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:20:34.376287Z","time spent":"480.612597ms","remote":"127.0.0.1:53412","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":"warn","ts":"2026-09-18T05:20:34.857013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.279725ms","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-09-18T05:20:34.857105Z","caller":"traceutil/trace.go:171","msg":"trace[2139645116] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1093; }","duration":"476.389928ms","start":"2026-09-18T05:20:34.380695Z","end":"2026-09-18T05:20:34.857085Z","steps":["trace[2139645116] 'agreement among raft nodes before linearized reading' (duration: 476.11499ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:20:34.857153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:20:34.380642Z","time spent":"476.502892ms","remote":"127.0.0.1:53412","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-09-18T05:20:50.742356Z","caller":"traceutil/trace.go:171","msg":"trace[199529562] transaction","detail":"{read_only:false; response_revision:1153; number_of_response:1; }","duration":"102.168884ms","start":"2026-09-18T05:20:50.640171Z","end":"2026-09-18T05:20:50.74234Z","steps":["trace[199529562] 'process raft request' (duration: 102.067591ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:20:51.962543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.594757ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T05:20:51.962639Z","caller":"traceutil/trace.go:171","msg":"trace[236275920] transaction","detail":"{read_only:false; response_revision:1159; number_of_response:1; }","duration":"332.522429ms","start":"2026-09-18T05:20:51.6301Z","end":"2026-09-18T05:20:51.962622Z","steps":["trace[236275920] 'process raft request' (duration: 128.765777ms)","trace[236275920] 'compare' (duration: 203.294077ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:20:51.962705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:20:51.630083Z","time spent":"332.585371ms","remote":"127.0.0.1:53412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T05:20:53.053415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.877896ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-18T05:20:53.053756Z","caller":"traceutil/trace.go:171","msg":"trace[1918461850] transaction","detail":"{read_only:false; response_revision:1163; number_of_response:1; }","duration":"195.25595ms","start":"2026-09-18T05:20:52.858483Z","end":"2026-09-18T05:20:53.053738Z","steps":["trace[1918461850] 'process raft request' (duration: 76.999363ms)","trace[1918461850] 'compare' (duration: 117.650369ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:20:53.05392Z","caller":"traceutil/trace.go:171","msg":"trace[180362133] linearizableReadLoop","detail":"{readStateIndex:1206; appliedIndex:1205; }","duration":"148.48497ms","start":"2026-09-18T05:20:52.905426Z","end":"2026-09-18T05:20:53.053911Z","steps":["trace[180362133] 'read index received' (duration: 30.031297ms)","trace[180362133] 'applied index is now lower than readState.Index' (duration: 118.452573ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:20:53.054152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.715758ms","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-09-18T05:20:53.054179Z","caller":"traceutil/trace.go:171","msg":"trace[1756007375] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1163; }","duration":"148.771219ms","start":"2026-09-18T05:20:52.905399Z","end":"2026-09-18T05:20:53.05417Z","steps":["trace[1756007375] 'agreement among raft nodes before linearized reading' (duration: 148.655815ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:21:10.033727Z","caller":"traceutil/trace.go:171","msg":"trace[1035683373] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"315.284781ms","start":"2026-09-18T05:21:09.718418Z","end":"2026-09-18T05:21:10.033703Z","steps":["trace[1035683373] 'process raft request' (duration: 315.140057ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:21:10.033867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:21:09.71839Z","time spent":"315.408485ms","remote":"127.0.0.1:53412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T05:21:10.604616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.150682ms","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-09-18T05:21:10.60475Z","caller":"traceutil/trace.go:171","msg":"trace[1681915955] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1283; }","duration":"427.290146ms","start":"2026-09-18T05:21:10.177436Z","end":"2026-09-18T05:21:10.604726Z","steps":["trace[1681915955] 'range keys from in-memory index tree' (duration: 427.118431ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:21:10.60554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.863583ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T05:21:10.605671Z","caller":"traceutil/trace.go:171","msg":"trace[2039284060] linearizableReadLoop","detail":"{readStateIndex:1331; appliedIndex:1330; }","duration":"501.915629ms","start":"2026-09-18T05:21:10.103718Z","end":"2026-09-18T05:21:10.605633Z","steps":["trace[2039284060] 'read index received' (duration: 370.787819ms)","trace[2039284060] 'applied index is now lower than readState.Index' (duration: 131.12651ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:21:10.605783Z","caller":"traceutil/trace.go:171","msg":"trace[1136507945] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"553.157756ms","start":"2026-09-18T05:21:10.052594Z","end":"2026-09-18T05:21:10.605752Z","steps":["trace[1136507945] 'process raft request' (duration: 421.970564ms)","trace[1136507945] 'compare' (duration: 130.722577ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:21:10.60595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.230918ms","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:507"} {"level":"warn","ts":"2026-09-18T05:21:10.605978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:21:10.052578Z","time spent":"553.258688ms","remote":"127.0.0.1:53412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T05:21:10.605986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.601364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-09-18T05:21:10.606008Z","caller":"traceutil/trace.go:171","msg":"trace[631801113] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1284; }","duration":"502.320191ms","start":"2026-09-18T05:21:10.103671Z","end":"2026-09-18T05:21:10.605991Z","steps":["trace[631801113] 'agreement among raft nodes before linearized reading' (duration: 502.081514ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:21:10.606074Z","caller":"traceutil/trace.go:171","msg":"trace[892499603] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1284; }","duration":"372.739289ms","start":"2026-09-18T05:21:10.233312Z","end":"2026-09-18T05:21:10.606051Z","steps":["trace[892499603] 'agreement among raft nodes before linearized reading' (duration: 372.473951ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:21:10.606077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:21:10.103617Z","time spent":"502.446905ms","remote":"127.0.0.1:53412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-09-18T05:21:10.606129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:21:10.233288Z","time spent":"372.830641ms","remote":"127.0.0.1:53412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":553,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-09-18T05:21:10.606257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.658532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.242\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-09-18T05:21:10.606309Z","caller":"traceutil/trace.go:171","msg":"trace[183143422] range","detail":"{range_begin:/registry/masterleases/162.253.55.242; range_end:; response_count:1; response_revision:1284; }","duration":"143.741826ms","start":"2026-09-18T05:21:10.462549Z","end":"2026-09-18T05:21:10.606291Z","steps":["trace[183143422] 'agreement among raft nodes before linearized reading' (duration: 143.570871ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:23:00.962701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.36786ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T05:23:00.963294Z","caller":"traceutil/trace.go:171","msg":"trace[177894112] transaction","detail":"{read_only:false; response_revision:1791; number_of_response:1; }","duration":"351.968203ms","start":"2026-09-18T05:23:00.611275Z","end":"2026-09-18T05:23:00.963244Z","steps":["trace[177894112] 'process raft request' (duration: 25.988035ms)","trace[177894112] 'compare' (duration: 325.246756ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:23:00.963636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:23:00.611257Z","time spent":"352.145328ms","remote":"127.0.0.1:53412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T05:24:19.90694Z","caller":"traceutil/trace.go:171","msg":"trace[1276129734] transaction","detail":"{read_only:false; response_revision:2154; number_of_response:1; }","duration":"140.651116ms","start":"2026-09-18T05:24:19.766264Z","end":"2026-09-18T05:24:19.906915Z","steps":["trace[1276129734] 'process raft request' (duration: 140.480151ms)"],"step_count":1}