{"level":"warn","ts":"2026-09-19T06:06:39.085627Z","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-19T06:06:39.085731Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.52: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.52:2380","--initial-cluster=instance=https://162.253.55.52:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.52:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.52: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-19T06:06:39.085809Z","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-19T06:06:39.085822Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.52:2380"]} {"level":"info","ts":"2026-09-19T06:06:39.085846Z","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-19T06:06:39.086604Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.52:2379"]} {"level":"info","ts":"2026-09-19T06:06:39.086696Z","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.52:2380"],"listen-peer-urls":["https://162.253.55.52:2380"],"advertise-client-urls":["https://162.253.55.52:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.52:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.52: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-19T06:06:39.096301Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"9.365297ms"} {"level":"info","ts":"2026-09-19T06:06:39.105353Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b11e7fc5714be64b","cluster-id":"9349e4f32a52a90a"} {"level":"info","ts":"2026-09-19T06:06:39.10542Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b switched to configuration voters=()"} {"level":"info","ts":"2026-09-19T06:06:39.10544Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became follower at term 0"} {"level":"info","ts":"2026-09-19T06:06:39.10545Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b11e7fc5714be64b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-19T06:06:39.105461Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became follower at term 1"} {"level":"info","ts":"2026-09-19T06:06:39.10549Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b switched to configuration voters=(12762778880000648779)"} {"level":"warn","ts":"2026-09-19T06:06:39.116776Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-19T06:06:39.118622Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-19T06:06:39.120228Z","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-19T06:06:39.123241Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b11e7fc5714be64b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-19T06:06:39.123526Z","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-19T06:06:39.124914Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b switched to configuration voters=(12762778880000648779)"} {"level":"info","ts":"2026-09-19T06:06:39.125054Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9349e4f32a52a90a","local-member-id":"b11e7fc5714be64b","added-peer-id":"b11e7fc5714be64b","added-peer-peer-urls":["https://162.253.55.52:2380"]} {"level":"info","ts":"2026-09-19T06:06:39.125381Z","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-19T06:06:39.125459Z","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-19T06:06:39.124456Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b11e7fc5714be64b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-19T06:06:39.133739Z","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-19T06:06:39.1339Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b11e7fc5714be64b","initial-advertise-peer-urls":["https://162.253.55.52:2380"],"listen-peer-urls":["https://162.253.55.52:2380"],"advertise-client-urls":["https://162.253.55.52:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.52:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-19T06:06:39.133922Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-19T06:06:39.133974Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.52:2380"} {"level":"info","ts":"2026-09-19T06:06:39.133985Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.52:2380"} {"level":"info","ts":"2026-09-19T06:06:39.906086Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b is starting a new election at term 1"} {"level":"info","ts":"2026-09-19T06:06:39.906154Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became pre-candidate at term 1"} {"level":"info","ts":"2026-09-19T06:06:39.906205Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b received MsgPreVoteResp from b11e7fc5714be64b at term 1"} {"level":"info","ts":"2026-09-19T06:06:39.906227Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became candidate at term 2"} {"level":"info","ts":"2026-09-19T06:06:39.906248Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b received MsgVoteResp from b11e7fc5714be64b at term 2"} {"level":"info","ts":"2026-09-19T06:06:39.906268Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became leader at term 2"} {"level":"info","ts":"2026-09-19T06:06:39.906285Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b11e7fc5714be64b elected leader b11e7fc5714be64b at term 2"} {"level":"info","ts":"2026-09-19T06:06:39.907445Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b11e7fc5714be64b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.52:2379]}","request-path":"/0/members/b11e7fc5714be64b/attributes","cluster-id":"9349e4f32a52a90a","publish-timeout":"7s"} {"level":"info","ts":"2026-09-19T06:06:39.907498Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-19T06:06:39.907482Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T06:06:39.907662Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-19T06:06:39.907743Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-19T06:06:39.907816Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-19T06:06:39.908601Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9349e4f32a52a90a","local-member-id":"b11e7fc5714be64b","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T06:06:39.908722Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T06:06:39.90878Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T06:06:39.909259Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-19T06:06:39.912609Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.52:2379"} {"level":"info","ts":"2026-09-19T06:07:17.706963Z","caller":"traceutil/trace.go:171","msg":"trace[1633906607] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"111.313635ms","start":"2026-09-19T06:07:17.595608Z","end":"2026-09-19T06:07:17.706921Z","steps":["trace[1633906607] 'process raft request' (duration: 111.161791ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:07:30.128114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.433856ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-19T06:07:30.128425Z","caller":"traceutil/trace.go:171","msg":"trace[495344287] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:603; }","duration":"117.584685ms","start":"2026-09-19T06:07:30.010823Z","end":"2026-09-19T06:07:30.128408Z","steps":["trace[495344287] 'read index received' (duration: 116.862135ms)","trace[495344287] 'applied index is now lower than readState.Index' (duration: 721.65µs)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:07:30.128705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.846232ms","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-19T06:07:30.128706Z","caller":"traceutil/trace.go:171","msg":"trace[1373427197] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"252.86965ms","start":"2026-09-19T06:07:29.875793Z","end":"2026-09-19T06:07:30.128663Z","steps":["trace[1373427197] 'compare' (duration: 247.243641ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:07:30.128706Z","caller":"traceutil/trace.go:171","msg":"trace[2074762777] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"247.160588ms","start":"2026-09-19T06:07:29.881504Z","end":"2026-09-19T06:07:30.128664Z","steps":["trace[2074762777] 'process raft request' (duration: 246.815099ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:07:30.128744Z","caller":"traceutil/trace.go:171","msg":"trace[1946013100] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:581; }","duration":"117.956414ms","start":"2026-09-19T06:07:30.010777Z","end":"2026-09-19T06:07:30.128733Z","steps":["trace[1946013100] 'agreement among raft nodes before linearized reading' (duration: 117.745659ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:07:36.30273Z","caller":"traceutil/trace.go:171","msg":"trace[1373569746] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"142.301672ms","start":"2026-09-19T06:07:36.160402Z","end":"2026-09-19T06:07:36.302704Z","steps":["trace[1373569746] 'process raft request' (duration: 142.135107ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:07:36.302774Z","caller":"traceutil/trace.go:171","msg":"trace[1153859815] linearizableReadLoop","detail":"{readStateIndex:673; appliedIndex:673; }","duration":"138.804968ms","start":"2026-09-19T06:07:36.163927Z","end":"2026-09-19T06:07:36.302731Z","steps":["trace[1153859815] 'read index received' (duration: 138.788038ms)","trace[1153859815] 'applied index is now lower than readState.Index' (duration: 13.78µs)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:07:36.30307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.110777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-ltbr9\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-09-19T06:07:36.303113Z","caller":"traceutil/trace.go:171","msg":"trace[183571060] range","detail":"{range_begin:/registry/pods/kube-system/cilium-ltbr9; range_end:; response_count:1; response_revision:647; }","duration":"139.203809ms","start":"2026-09-19T06:07:36.163894Z","end":"2026-09-19T06:07:36.303098Z","steps":["trace[183571060] 'agreement among raft nodes before linearized reading' (duration: 138.980583ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:07:36.428733Z","caller":"traceutil/trace.go:171","msg":"trace[1177216547] linearizableReadLoop","detail":"{readStateIndex:674; appliedIndex:673; }","duration":"125.847504ms","start":"2026-09-19T06:07:36.302849Z","end":"2026-09-19T06:07:36.428697Z","steps":["trace[1177216547] 'read index received' (duration: 97.137221ms)","trace[1177216547] 'applied index is now lower than readState.Index' (duration: 28.709073ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:07:36.429006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.300483ms","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-19T06:07:36.429046Z","caller":"traceutil/trace.go:171","msg":"trace[368577515] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:648; }","duration":"165.403945ms","start":"2026-09-19T06:07:36.26363Z","end":"2026-09-19T06:07:36.429034Z","steps":["trace[368577515] 'agreement among raft nodes before linearized reading' (duration: 165.195919ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:07:36.429225Z","caller":"traceutil/trace.go:171","msg":"trace[1072248502] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"241.630391ms","start":"2026-09-19T06:07:36.187569Z","end":"2026-09-19T06:07:36.429199Z","steps":["trace[1072248502] 'process raft request' (duration: 212.936779ms)","trace[1072248502] 'compare' (duration: 26.950886ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:07:36.444651Z","caller":"traceutil/trace.go:171","msg":"trace[1528949014] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"122.237088ms","start":"2026-09-19T06:07:36.322388Z","end":"2026-09-19T06:07:36.444625Z","steps":["trace[1528949014] 'process raft request' (duration: 121.93464ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:07:36.885725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.191954ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T06:07:36.885931Z","caller":"traceutil/trace.go:171","msg":"trace[1012609873] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"451.90115ms","start":"2026-09-19T06:07:36.434015Z","end":"2026-09-19T06:07:36.885916Z","steps":["trace[1012609873] 'process raft request' (duration: 211.347597ms)","trace[1012609873] 'compare' (duration: 239.939057ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:07:36.885983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:07:36.433987Z","time spent":"451.971862ms","remote":"127.0.0.1:59512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T06:07:36.886273Z","caller":"traceutil/trace.go:171","msg":"trace[707103393] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"388.265259ms","start":"2026-09-19T06:07:36.497995Z","end":"2026-09-19T06:07:36.886261Z","steps":["trace[707103393] 'process raft request' (duration: 387.808196ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:07:36.886323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:07:36.49797Z","time spent":"388.328791ms","remote":"127.0.0.1:59752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-19T06:07:37.478771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.275131ms","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-19T06:07:37.478926Z","caller":"traceutil/trace.go:171","msg":"trace[1214876241] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:651; }","duration":"186.451295ms","start":"2026-09-19T06:07:37.292448Z","end":"2026-09-19T06:07:37.4789Z","steps":["trace[1214876241] 'range keys from in-memory index tree' (duration: 186.254219ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:07:37.699765Z","caller":"traceutil/trace.go:171","msg":"trace[2009240536] linearizableReadLoop","detail":"{readStateIndex:678; appliedIndex:677; }","duration":"125.200408ms","start":"2026-09-19T06:07:37.574539Z","end":"2026-09-19T06:07:37.69974Z","steps":["trace[2009240536] 'read index received' (duration: 124.635073ms)","trace[2009240536] 'applied index is now lower than readState.Index' (duration: 563.975µs)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:07:37.699874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.32256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T06:07:37.699902Z","caller":"traceutil/trace.go:171","msg":"trace[1272451653] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:652; }","duration":"125.433144ms","start":"2026-09-19T06:07:37.57446Z","end":"2026-09-19T06:07:37.699893Z","steps":["trace[1272451653] 'agreement among raft nodes before linearized reading' (duration: 125.366102ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:07:37.699954Z","caller":"traceutil/trace.go:171","msg":"trace[1133918305] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"364.351593ms","start":"2026-09-19T06:07:37.335545Z","end":"2026-09-19T06:07:37.699897Z","steps":["trace[1133918305] 'process raft request' (duration: 363.644464ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:07:37.700506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:07:37.335513Z","time spent":"364.63414ms","remote":"127.0.0.1:59362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T06:07:53.794035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.650327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T06:07:53.794151Z","caller":"traceutil/trace.go:171","msg":"trace[519166651] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:816; }","duration":"219.819172ms","start":"2026-09-19T06:07:53.574309Z","end":"2026-09-19T06:07:53.794128Z","steps":["trace[519166651] 'range keys from in-memory index tree' (duration: 219.576236ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:08:33.326199Z","caller":"traceutil/trace.go:171","msg":"trace[537844689] linearizableReadLoop","detail":"{readStateIndex:1135; appliedIndex:1134; }","duration":"155.196468ms","start":"2026-09-19T06:08:33.170978Z","end":"2026-09-19T06:08:33.326175Z","steps":["trace[537844689] 'read index received' (duration: 155.021803ms)","trace[537844689] 'applied index is now lower than readState.Index' (duration: 173.295µs)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:08:33.326403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.399663ms","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-19T06:08:33.326441Z","caller":"traceutil/trace.go:171","msg":"trace[1413856060] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1094; }","duration":"155.475305ms","start":"2026-09-19T06:08:33.170955Z","end":"2026-09-19T06:08:33.32643Z","steps":["trace[1413856060] 'agreement among raft nodes before linearized reading' (duration: 155.323811ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:08:34.548824Z","caller":"traceutil/trace.go:171","msg":"trace[2098387825] linearizableReadLoop","detail":"{readStateIndex:1140; appliedIndex:1139; }","duration":"392.559341ms","start":"2026-09-19T06:08:34.156231Z","end":"2026-09-19T06:08:34.548791Z","steps":["trace[2098387825] 'read index received' (duration: 354.956961ms)","trace[2098387825] 'applied index is now lower than readState.Index' (duration: 37.60111ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:08:34.548971Z","caller":"traceutil/trace.go:171","msg":"trace[549538651] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"818.311544ms","start":"2026-09-19T06:08:33.730641Z","end":"2026-09-19T06:08:34.548953Z","steps":["trace[549538651] 'process raft request' (duration: 780.508409ms)","trace[549538651] 'compare' (duration: 37.22828ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:08:34.54909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:08:33.730618Z","time spent":"818.390206ms","remote":"127.0.0.1:59512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T06:08:34.549462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.012372ms","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-19T06:08:34.549539Z","caller":"traceutil/trace.go:171","msg":"trace[1362639435] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1099; }","duration":"352.143575ms","start":"2026-09-19T06:08:34.197382Z","end":"2026-09-19T06:08:34.549526Z","steps":["trace[1362639435] 'agreement among raft nodes before linearized reading' (duration: 351.809007ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:08:34.549561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:08:34.197339Z","time spent":"352.215917ms","remote":"127.0.0.1:59512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-19T06:08:34.549542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.28862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-19T06:08:34.549629Z","caller":"traceutil/trace.go:171","msg":"trace[184772972] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1099; }","duration":"393.443714ms","start":"2026-09-19T06:08:34.156164Z","end":"2026-09-19T06:08:34.549608Z","steps":["trace[184772972] 'agreement among raft nodes before linearized reading' (duration: 393.026473ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:08:34.549714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:08:34.156149Z","time spent":"393.551487ms","remote":"127.0.0.1:59512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-09-19T06:08:49.692097Z","caller":"traceutil/trace.go:171","msg":"trace[13015073] linearizableReadLoop","detail":"{readStateIndex:1193; appliedIndex:1192; }","duration":"265.713426ms","start":"2026-09-19T06:08:49.426356Z","end":"2026-09-19T06:08:49.69207Z","steps":["trace[13015073] 'read index received' (duration: 265.51435ms)","trace[13015073] 'applied index is now lower than readState.Index' (duration: 198.055µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:08:49.69224Z","caller":"traceutil/trace.go:171","msg":"trace[491804674] transaction","detail":"{read_only:false; response_revision:1149; number_of_response:1; }","duration":"276.699658ms","start":"2026-09-19T06:08:49.415503Z","end":"2026-09-19T06:08:49.692202Z","steps":["trace[491804674] 'process raft request' (duration: 276.39612ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:08:49.692369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.990354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-19T06:08:49.692587Z","caller":"traceutil/trace.go:171","msg":"trace[1779869796] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1149; }","duration":"266.271231ms","start":"2026-09-19T06:08:49.426305Z","end":"2026-09-19T06:08:49.692576Z","steps":["trace[1779869796] 'agreement among raft nodes before linearized reading' (duration: 265.903251ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:08:49.692406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.788572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T06:08:49.692734Z","caller":"traceutil/trace.go:171","msg":"trace[11474090] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1149; }","duration":"118.165722ms","start":"2026-09-19T06:08:49.574542Z","end":"2026-09-19T06:08:49.692707Z","steps":["trace[11474090] 'agreement among raft nodes before linearized reading' (duration: 117.781032ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:08:49.69241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.55001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-19T06:08:49.692837Z","caller":"traceutil/trace.go:171","msg":"trace[255978211] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1149; }","duration":"252.014802ms","start":"2026-09-19T06:08:49.440779Z","end":"2026-09-19T06:08:49.692794Z","steps":["trace[255978211] 'agreement among raft nodes before linearized reading' (duration: 251.519559ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:08:49.919665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.631155ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T06:08:49.919838Z","caller":"traceutil/trace.go:171","msg":"trace[1514736129] transaction","detail":"{read_only:false; response_revision:1150; number_of_response:1; }","duration":"222.119098ms","start":"2026-09-19T06:08:49.697699Z","end":"2026-09-19T06:08:49.919818Z","steps":["trace[1514736129] 'process raft request' (duration: 106.223475ms)","trace[1514736129] 'compare' (duration: 115.500472ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:08:49.920015Z","caller":"traceutil/trace.go:171","msg":"trace[1951086071] transaction","detail":"{read_only:false; response_revision:1151; number_of_response:1; }","duration":"213.19717ms","start":"2026-09-19T06:08:49.706797Z","end":"2026-09-19T06:08:49.919994Z","steps":["trace[1951086071] 'process raft request' (duration: 212.981085ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:08:50.563146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.570063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T06:08:50.563241Z","caller":"traceutil/trace.go:171","msg":"trace[852434887] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1153; }","duration":"168.681436ms","start":"2026-09-19T06:08:50.394536Z","end":"2026-09-19T06:08:50.563218Z","steps":["trace[852434887] 'count revisions from in-memory index tree' (duration: 168.45824ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:08:50.563592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.608179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5330"} {"level":"info","ts":"2026-09-19T06:08:50.563681Z","caller":"traceutil/trace.go:171","msg":"trace[777074756] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1153; }","duration":"155.751072ms","start":"2026-09-19T06:08:50.407908Z","end":"2026-09-19T06:08:50.563659Z","steps":["trace[777074756] 'range keys from in-memory index tree' (duration: 155.361352ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:08:51.241462Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594533965287787245,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-19T06:08:51.260598Z","caller":"traceutil/trace.go:171","msg":"trace[867405556] transaction","detail":"{read_only:false; response_revision:1154; number_of_response:1; }","duration":"612.49316ms","start":"2026-09-19T06:08:50.648086Z","end":"2026-09-19T06:08:51.260579Z","steps":["trace[867405556] 'process raft request' (duration: 612.389597ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:08:51.260711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:08:50.648064Z","time spent":"612.587722ms","remote":"127.0.0.1:59512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T06:08:51.304091Z","caller":"traceutil/trace.go:171","msg":"trace[1556896452] transaction","detail":"{read_only:false; response_revision:1155; number_of_response:1; }","duration":"653.622614ms","start":"2026-09-19T06:08:50.650447Z","end":"2026-09-19T06:08:51.30407Z","steps":["trace[1556896452] 'process raft request' (duration: 621.860139ms)","trace[1556896452] 'compare' (duration: 31.613741ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:08:51.304232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:08:50.650423Z","time spent":"653.724697ms","remote":"127.0.0.1:59512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T06:08:51.304271Z","caller":"traceutil/trace.go:171","msg":"trace[1347540695] transaction","detail":"{read_only:false; response_revision:1156; number_of_response:1; }","duration":"619.875107ms","start":"2026-09-19T06:08:50.684375Z","end":"2026-09-19T06:08:51.30425Z","steps":["trace[1347540695] 'process raft request' (duration: 619.657871ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:08:51.304272Z","caller":"traceutil/trace.go:171","msg":"trace[1176884696] linearizableReadLoop","detail":"{readStateIndex:1201; appliedIndex:1198; }","duration":"563.424655ms","start":"2026-09-19T06:08:50.740818Z","end":"2026-09-19T06:08:51.304243Z","steps":["trace[1176884696] 'read index received' (duration: 519.714063ms)","trace[1176884696] 'applied index is now lower than readState.Index' (duration: 43.708522ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:08:51.30434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:08:50.684307Z","time spent":"619.99792ms","remote":"127.0.0.1:59332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":751,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-19T06:08:51.304563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.730403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-19T06:08:51.304595Z","caller":"traceutil/trace.go:171","msg":"trace[1502876617] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1156; }","duration":"563.815505ms","start":"2026-09-19T06:08:50.740769Z","end":"2026-09-19T06:08:51.304585Z","steps":["trace[1502876617] 'agreement among raft nodes before linearized reading' (duration: 563.60498ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:08:51.304596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.939732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T06:08:51.304627Z","caller":"traceutil/trace.go:171","msg":"trace[565684614] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1156; }","duration":"137.005264ms","start":"2026-09-19T06:08:51.167612Z","end":"2026-09-19T06:08:51.304617Z","steps":["trace[565684614] 'agreement among raft nodes before linearized reading' (duration: 136.957643ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:08:51.30463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:08:50.740752Z","time spent":"563.866987ms","remote":"127.0.0.1:59512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-19T06:08:51.897711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.904963ms","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-19T06:08:51.897816Z","caller":"traceutil/trace.go:171","msg":"trace[248536834] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1159; }","duration":"201.087258ms","start":"2026-09-19T06:08:51.696708Z","end":"2026-09-19T06:08:51.897795Z","steps":["trace[248536834] 'range keys from in-memory index tree' (duration: 200.707468ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:09:07.466412Z","caller":"traceutil/trace.go:171","msg":"trace[1736427618] linearizableReadLoop","detail":"{readStateIndex:1322; appliedIndex:1321; }","duration":"280.341285ms","start":"2026-09-19T06:09:07.186048Z","end":"2026-09-19T06:09:07.466389Z","steps":["trace[1736427618] 'read index received' (duration: 280.129409ms)","trace[1736427618] 'applied index is now lower than readState.Index' (duration: 210.586µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:09:07.466479Z","caller":"traceutil/trace.go:171","msg":"trace[410931577] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"397.934582ms","start":"2026-09-19T06:09:07.068514Z","end":"2026-09-19T06:09:07.466449Z","steps":["trace[410931577] 'process raft request' (duration: 397.707286ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:09:07.466687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.632022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-09-19T06:09:07.466803Z","caller":"traceutil/trace.go:171","msg":"trace[1118950853] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1274; }","duration":"280.774926ms","start":"2026-09-19T06:09:07.186011Z","end":"2026-09-19T06:09:07.466786Z","steps":["trace[1118950853] 'agreement among raft nodes before linearized reading' (duration: 280.502149ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:09:07.466834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.135857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-19T06:09:07.466698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:09:07.06846Z","time spent":"398.107247ms","remote":"127.0.0.1:59512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":516,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T06:09:07.466916Z","caller":"traceutil/trace.go:171","msg":"trace[33372727] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1274; }","duration":"119.257931ms","start":"2026-09-19T06:09:07.347638Z","end":"2026-09-19T06:09:07.466896Z","steps":["trace[33372727] 'agreement among raft nodes before linearized reading' (duration: 119.116377ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:09:07.467061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.360375ms","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-19T06:09:07.467114Z","caller":"traceutil/trace.go:171","msg":"trace[1390434219] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1274; }","duration":"136.457358ms","start":"2026-09-19T06:09:07.330645Z","end":"2026-09-19T06:09:07.467102Z","steps":["trace[1390434219] 'agreement among raft nodes before linearized reading' (duration: 135.945144ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:13:41.219993Z","caller":"traceutil/trace.go:171","msg":"trace[841180923] transaction","detail":"{read_only:false; response_revision:2560; number_of_response:1; }","duration":"113.593656ms","start":"2026-09-19T06:13:41.106371Z","end":"2026-09-19T06:13:41.219965Z","steps":["trace[841180923] 'process raft request' (duration: 113.487953ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:13:41.220223Z","caller":"traceutil/trace.go:171","msg":"trace[470887012] transaction","detail":"{read_only:false; response_revision:2559; number_of_response:1; }","duration":"114.410888ms","start":"2026-09-19T06:13:41.105697Z","end":"2026-09-19T06:13:41.220107Z","steps":["trace[470887012] 'process raft request' (duration: 59.193932ms)","trace[470887012] 'compare' (duration: 54.857536ms)"],"step_count":2}