{"level":"warn","ts":"2026-09-15T22:25:39.423577Z","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-15T22:25:39.423704Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.156: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.156:2380","--initial-cluster=instance=https://162.253.55.156:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.156:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.156: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-15T22:25:39.423945Z","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-15T22:25:39.423975Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.156:2380"]} {"level":"info","ts":"2026-09-15T22:25:39.424006Z","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-15T22:25:39.425271Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.156:2379"]} {"level":"info","ts":"2026-09-15T22:25:39.425443Z","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.156:2380"],"listen-peer-urls":["https://162.253.55.156:2380"],"advertise-client-urls":["https://162.253.55.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.156: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-15T22:25:39.437643Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"11.825687ms"} {"level":"info","ts":"2026-09-15T22:25:39.448376Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"40fb1eca294b6a5a","cluster-id":"17c6999805fcc0eb"} {"level":"info","ts":"2026-09-15T22:25:39.448499Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a switched to configuration voters=()"} {"level":"info","ts":"2026-09-15T22:25:39.448528Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a became follower at term 0"} {"level":"info","ts":"2026-09-15T22:25:39.448566Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 40fb1eca294b6a5a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-15T22:25:39.448593Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a became follower at term 1"} {"level":"info","ts":"2026-09-15T22:25:39.448635Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a switched to configuration voters=(4682370091206797914)"} {"level":"warn","ts":"2026-09-15T22:25:39.460972Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-15T22:25:39.462685Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-15T22:25:39.465304Z","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-15T22:25:39.468168Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"40fb1eca294b6a5a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-15T22:25:39.46929Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"40fb1eca294b6a5a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-15T22:25:39.470187Z","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-15T22:25:39.470275Z","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-15T22:25:39.47029Z","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-15T22:25:39.470568Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a switched to configuration voters=(4682370091206797914)"} {"level":"info","ts":"2026-09-15T22:25:39.47083Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"17c6999805fcc0eb","local-member-id":"40fb1eca294b6a5a","added-peer-id":"40fb1eca294b6a5a","added-peer-peer-urls":["https://162.253.55.156:2380"]} {"level":"info","ts":"2026-09-15T22:25:39.489106Z","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-15T22:25:39.489146Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.156:2380"} {"level":"info","ts":"2026-09-15T22:25:39.489231Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.156:2380"} {"level":"info","ts":"2026-09-15T22:25:39.489584Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"40fb1eca294b6a5a","initial-advertise-peer-urls":["https://162.253.55.156:2380"],"listen-peer-urls":["https://162.253.55.156:2380"],"advertise-client-urls":["https://162.253.55.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-15T22:25:39.489648Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-15T22:25:40.449213Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a is starting a new election at term 1"} {"level":"info","ts":"2026-09-15T22:25:40.449282Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a became pre-candidate at term 1"} {"level":"info","ts":"2026-09-15T22:25:40.449319Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a received MsgPreVoteResp from 40fb1eca294b6a5a at term 1"} {"level":"info","ts":"2026-09-15T22:25:40.449338Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a became candidate at term 2"} {"level":"info","ts":"2026-09-15T22:25:40.449348Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a received MsgVoteResp from 40fb1eca294b6a5a at term 2"} {"level":"info","ts":"2026-09-15T22:25:40.449366Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a became leader at term 2"} {"level":"info","ts":"2026-09-15T22:25:40.449382Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 40fb1eca294b6a5a elected leader 40fb1eca294b6a5a at term 2"} {"level":"info","ts":"2026-09-15T22:25:40.451044Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"40fb1eca294b6a5a","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.156:2379]}","request-path":"/0/members/40fb1eca294b6a5a/attributes","cluster-id":"17c6999805fcc0eb","publish-timeout":"7s"} {"level":"info","ts":"2026-09-15T22:25:40.451238Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-15T22:25:40.451479Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-15T22:25:40.451497Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-15T22:25:40.451279Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-15T22:25:40.451323Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-15T22:25:40.454654Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.156:2379"} {"level":"info","ts":"2026-09-15T22:25:40.455147Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"17c6999805fcc0eb","local-member-id":"40fb1eca294b6a5a","cluster-version":"3.5"} {"level":"info","ts":"2026-09-15T22:25:40.455392Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-15T22:25:40.455425Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-15T22:25:40.455809Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-15T22:25:41.965008Z","caller":"traceutil/trace.go:171","msg":"trace[287486471] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"107.809062ms","start":"2026-09-15T22:25:41.857176Z","end":"2026-09-15T22:25:41.964985Z","steps":["trace[287486471] 'process raft request' (duration: 107.635443ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:25:41.991526Z","caller":"traceutil/trace.go:171","msg":"trace[1578364126] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"133.657089ms","start":"2026-09-15T22:25:41.857833Z","end":"2026-09-15T22:25:41.99149Z","steps":["trace[1578364126] 'process raft request' (duration: 133.456639ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:25:41.991955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.8165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-15T22:25:41.992065Z","caller":"traceutil/trace.go:171","msg":"trace[919989497] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:11; }","duration":"130.980198ms","start":"2026-09-15T22:25:41.861065Z","end":"2026-09-15T22:25:41.992045Z","steps":["trace[919989497] 'agreement among raft nodes before linearized reading' (duration: 130.767618ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:25:41.992197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.100334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-15T22:25:41.992258Z","caller":"traceutil/trace.go:171","msg":"trace[932269829] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:11; }","duration":"131.190619ms","start":"2026-09-15T22:25:41.861054Z","end":"2026-09-15T22:25:41.992244Z","steps":["trace[932269829] 'agreement among raft nodes before linearized reading' (duration: 131.110825ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:25:41.991594Z","caller":"traceutil/trace.go:171","msg":"trace[312984075] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"130.469563ms","start":"2026-09-15T22:25:41.861089Z","end":"2026-09-15T22:25:41.991559Z","steps":["trace[312984075] 'read index received' (duration: 103.746013ms)","trace[312984075] 'applied index is now lower than readState.Index' (duration: 26.72242ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T22:25:41.993085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.930032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-15T22:25:41.99313Z","caller":"traceutil/trace.go:171","msg":"trace[519767345] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"124.997325ms","start":"2026-09-15T22:25:41.868125Z","end":"2026-09-15T22:25:41.993122Z","steps":["trace[519767345] 'agreement among raft nodes before linearized reading' (duration: 124.910001ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:26:17.278594Z","caller":"traceutil/trace.go:171","msg":"trace[652876721] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"148.570686ms","start":"2026-09-15T22:26:17.130007Z","end":"2026-09-15T22:26:17.278578Z","steps":["trace[652876721] 'process raft request' (duration: 148.331587ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:26:18.035921Z","caller":"traceutil/trace.go:171","msg":"trace[1687244732] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"141.303961ms","start":"2026-09-15T22:26:17.894585Z","end":"2026-09-15T22:26:18.035889Z","steps":["trace[1687244732] 'process raft request' (duration: 141.003213ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:26:18.035934Z","caller":"traceutil/trace.go:171","msg":"trace[815227870] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"109.516833ms","start":"2026-09-15T22:26:17.926398Z","end":"2026-09-15T22:26:18.035915Z","steps":["trace[815227870] 'process raft request' (duration: 109.416784ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:26:18.521344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.508229ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-15T22:26:18.521447Z","caller":"traceutil/trace.go:171","msg":"trace[841675441] linearizableReadLoop","detail":"{readStateIndex:596; appliedIndex:595; }","duration":"484.448993ms","start":"2026-09-15T22:26:18.036988Z","end":"2026-09-15T22:26:18.521437Z","steps":["trace[841675441] 'read index received' (duration: 211.404476ms)","trace[841675441] 'applied index is now lower than readState.Index' (duration: 273.043787ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:26:18.521488Z","caller":"traceutil/trace.go:171","msg":"trace[1364696093] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"505.534734ms","start":"2026-09-15T22:26:18.015948Z","end":"2026-09-15T22:26:18.521483Z","steps":["trace[1364696093] 'process raft request' (duration: 232.507437ms)","trace[1364696093] 'compare' (duration: 272.373209ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T22:26:18.521517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:26:18.015927Z","time spent":"505.573194ms","remote":"127.0.0.1:42898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80688,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-15T22:26:18.521601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.589422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-15T22:26:18.521674Z","caller":"traceutil/trace.go:171","msg":"trace[2101619800] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:579; }","duration":"484.696281ms","start":"2026-09-15T22:26:18.036961Z","end":"2026-09-15T22:26:18.521657Z","steps":["trace[2101619800] 'agreement among raft nodes before linearized reading' (duration: 484.542712ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:26:18.521712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:26:18.036949Z","time spent":"484.755551ms","remote":"127.0.0.1:42842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-15T22:26:18.523637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.029159ms","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-15T22:26:18.523687Z","caller":"traceutil/trace.go:171","msg":"trace[2098293055] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:580; }","duration":"477.10965ms","start":"2026-09-15T22:26:18.046566Z","end":"2026-09-15T22:26:18.523676Z","steps":["trace[2098293055] 'agreement among raft nodes before linearized reading' (duration: 477.009969ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:26:18.523717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:26:18.046554Z","time spent":"477.15502ms","remote":"127.0.0.1:43068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-09-15T22:26:18.523668Z","caller":"traceutil/trace.go:171","msg":"trace[1591672613] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"419.179425ms","start":"2026-09-15T22:26:18.104462Z","end":"2026-09-15T22:26:18.523642Z","steps":["trace[1591672613] 'process raft request' (duration: 419.036975ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:26:18.523815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:26:18.104443Z","time spent":"419.308864ms","remote":"127.0.0.1:42898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64392,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-15T22:26:18.523898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.428763ms","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-15T22:26:18.523928Z","caller":"traceutil/trace.go:171","msg":"trace[320589635] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:580; }","duration":"255.492253ms","start":"2026-09-15T22:26:18.268425Z","end":"2026-09-15T22:26:18.523917Z","steps":["trace[320589635] 'agreement among raft nodes before linearized reading' (duration: 255.440363ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:26:18.524006Z","caller":"traceutil/trace.go:171","msg":"trace[392423595] transaction","detail":"{read_only:false; number_of_response:1; response_revision:579; }","duration":"480.413707ms","start":"2026-09-15T22:26:18.043576Z","end":"2026-09-15T22:26:18.52399Z","steps":["trace[392423595] 'process raft request' (duration: 479.54525ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:26:18.524058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.132594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-09-15T22:26:18.524107Z","caller":"traceutil/trace.go:171","msg":"trace[687076736] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:580; }","duration":"263.237693ms","start":"2026-09-15T22:26:18.260861Z","end":"2026-09-15T22:26:18.524099Z","steps":["trace[687076736] 'agreement among raft nodes before linearized reading' (duration: 263.167683ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:26:18.524113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:26:18.043551Z","time spent":"480.489657ms","remote":"127.0.0.1:42898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":97,"response count":0,"response size":150530,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-15T22:26:23.550997Z","caller":"traceutil/trace.go:171","msg":"trace[117804382] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"144.711789ms","start":"2026-09-15T22:26:23.406263Z","end":"2026-09-15T22:26:23.550975Z","steps":["trace[117804382] 'process raft request' (duration: 144.580959ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:26:24.770213Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7663614355799309673,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-09-15T22:26:25.007189Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.000941561s","expected-duration":"1s"} {"level":"info","ts":"2026-09-15T22:26:25.008046Z","caller":"traceutil/trace.go:171","msg":"trace[659490680] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:643; }","duration":"738.58293ms","start":"2026-09-15T22:26:24.269432Z","end":"2026-09-15T22:26:25.008015Z","steps":["trace[659490680] 'read index received' (duration: 737.925378ms)","trace[659490680] 'applied index is now lower than readState.Index' (duration: 656.812µs)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:26:25.008137Z","caller":"traceutil/trace.go:171","msg":"trace[511942099] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"1.003698494s","start":"2026-09-15T22:26:24.00441Z","end":"2026-09-15T22:26:25.008109Z","steps":["trace[511942099] 'process raft request' (duration: 1.003221513s)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:26:25.008274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:26:24.004379Z","time spent":"1.003821604s","remote":"127.0.0.1:42928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-15T22:26:25.00846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.02059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-rzcqj\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-09-15T22:26:25.008576Z","caller":"traceutil/trace.go:171","msg":"trace[1170536391] range","detail":"{range_begin:/registry/pods/kube-system/cilium-rzcqj; range_end:; response_count:1; response_revision:624; }","duration":"739.14751ms","start":"2026-09-15T22:26:24.269379Z","end":"2026-09-15T22:26:25.008527Z","steps":["trace[1170536391] 'agreement among raft nodes before linearized reading' (duration: 738.79621ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:26:25.008677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:26:24.269358Z","time spent":"739.26102ms","remote":"127.0.0.1:43016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-rzcqj\" "} {"level":"warn","ts":"2026-09-15T22:26:25.00873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.069861ms","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-15T22:26:25.008781Z","caller":"traceutil/trace.go:171","msg":"trace[776663840] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:624; }","duration":"434.167831ms","start":"2026-09-15T22:26:24.574599Z","end":"2026-09-15T22:26:25.008767Z","steps":["trace[776663840] 'agreement among raft nodes before linearized reading' (duration: 434.026331ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:26:25.008827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:26:24.574587Z","time spent":"434.230211ms","remote":"127.0.0.1:43068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-15T22:26:25.008851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.731433ms","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-15T22:26:25.008983Z","caller":"traceutil/trace.go:171","msg":"trace[2015827460] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:624; }","duration":"438.007804ms","start":"2026-09-15T22:26:24.570952Z","end":"2026-09-15T22:26:25.00896Z","steps":["trace[2015827460] 'agreement among raft nodes before linearized reading' (duration: 437.723393ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:26:25.009027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:26:24.570935Z","time spent":"438.083264ms","remote":"127.0.0.1:43068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-15T22:26:25.009232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.090494ms","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-09-15T22:26:25.009491Z","caller":"traceutil/trace.go:171","msg":"trace[1039654742] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:624; }","duration":"438.390064ms","start":"2026-09-15T22:26:24.571027Z","end":"2026-09-15T22:26:25.009417Z","steps":["trace[1039654742] 'agreement among raft nodes before linearized reading' (duration: 437.522443ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:26:25.010663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:26:24.570859Z","time spent":"439.630976ms","remote":"127.0.0.1:43068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-09-15T22:26:26.571044Z","caller":"traceutil/trace.go:171","msg":"trace[1058140782] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"158.548313ms","start":"2026-09-15T22:26:26.412465Z","end":"2026-09-15T22:26:26.571013Z","steps":["trace[1058140782] 'process raft request' (duration: 158.246553ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:26:43.505557Z","caller":"traceutil/trace.go:171","msg":"trace[82740071] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"351.181866ms","start":"2026-09-15T22:26:43.154351Z","end":"2026-09-15T22:26:43.505533Z","steps":["trace[82740071] 'process raft request' (duration: 351.004154ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:26:43.505745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:26:43.154327Z","time spent":"351.335958ms","remote":"127.0.0.1:43068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-15T22:26:43.635974Z","caller":"traceutil/trace.go:171","msg":"trace[770980369] linearizableReadLoop","detail":"{readStateIndex:834; appliedIndex:830; }","duration":"173.503168ms","start":"2026-09-15T22:26:43.462422Z","end":"2026-09-15T22:26:43.635925Z","steps":["trace[770980369] 'read index received' (duration: 42.893881ms)","trace[770980369] 'applied index is now lower than readState.Index' (duration: 130.607227ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:26:43.636737Z","caller":"traceutil/trace.go:171","msg":"trace[354617504] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"478.732677ms","start":"2026-09-15T22:26:43.157972Z","end":"2026-09-15T22:26:43.636705Z","steps":["trace[354617504] 'process raft request' (duration: 477.697454ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:26:43.6368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.340228ms","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-15T22:26:43.636927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:26:43.157955Z","time spent":"478.867328ms","remote":"127.0.0.1:43068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-15T22:26:43.636945Z","caller":"traceutil/trace.go:171","msg":"trace[1626744652] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:810; }","duration":"174.532351ms","start":"2026-09-15T22:26:43.462375Z","end":"2026-09-15T22:26:43.636907Z","steps":["trace[1626744652] 'agreement among raft nodes before linearized reading' (duration: 174.069026ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:26:43.637114Z","caller":"traceutil/trace.go:171","msg":"trace[1013272352] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"224.702418ms","start":"2026-09-15T22:26:43.412336Z","end":"2026-09-15T22:26:43.637039Z","steps":["trace[1013272352] 'process raft request' (duration: 223.533784ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:26:43.637069Z","caller":"traceutil/trace.go:171","msg":"trace[825026053] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"478.884078ms","start":"2026-09-15T22:26:43.158158Z","end":"2026-09-15T22:26:43.637042Z","steps":["trace[825026053] 'process raft request' (duration: 477.660264ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:26:43.637357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:26:43.158146Z","time spent":"479.124751ms","remote":"127.0.0.1:43068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-15T22:26:55.850001Z","caller":"traceutil/trace.go:171","msg":"trace[807493307] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"118.2883ms","start":"2026-09-15T22:26:55.731684Z","end":"2026-09-15T22:26:55.849972Z","steps":["trace[807493307] 'process raft request' (duration: 71.213816ms)","trace[807493307] 'compare' (duration: 46.944872ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:26:55.853663Z","caller":"traceutil/trace.go:171","msg":"trace[1415805317] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"121.894454ms","start":"2026-09-15T22:26:55.73175Z","end":"2026-09-15T22:26:55.853644Z","steps":["trace[1415805317] 'process raft request' (duration: 121.822452ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:26:56.161454Z","caller":"traceutil/trace.go:171","msg":"trace[897112243] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"153.187005ms","start":"2026-09-15T22:26:56.008249Z","end":"2026-09-15T22:26:56.161436Z","steps":["trace[897112243] 'process raft request' (duration: 153.129994ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:26:56.161618Z","caller":"traceutil/trace.go:171","msg":"trace[954003622] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"159.036138ms","start":"2026-09-15T22:26:56.002428Z","end":"2026-09-15T22:26:56.161465Z","steps":["trace[954003622] 'process raft request' (duration: 135.880714ms)","trace[954003622] 'compare' (duration: 22.956681ms)"],"step_count":2}