{"level":"warn","ts":"2026-03-04T04:58:32.346375Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-04T04:58:32.346504Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.36: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.36:2380","--initial-cluster=instance=https://162.253.55.36:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.36:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.36: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-03-04T04:58:32.346607Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-04T04:58:32.34662Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.36:2380"]} {"level":"info","ts":"2026-03-04T04:58:32.346669Z","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-03-04T04:58:32.347634Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.36:2379"]} {"level":"info","ts":"2026-03-04T04:58:32.34794Z","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.36:2380"],"listen-peer-urls":["https://162.253.55.36:2380"],"advertise-client-urls":["https://162.253.55.36:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.36:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.36: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-03-04T04:58:32.355016Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.723566ms"} {"level":"info","ts":"2026-03-04T04:58:32.365092Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"cb89ec34a9687f0b","cluster-id":"d8e0d5e222e856c9"} {"level":"info","ts":"2026-03-04T04:58:32.365178Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b switched to configuration voters=()"} {"level":"info","ts":"2026-03-04T04:58:32.365206Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b became follower at term 0"} {"level":"info","ts":"2026-03-04T04:58:32.365222Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft cb89ec34a9687f0b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-04T04:58:32.365231Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b became follower at term 1"} {"level":"info","ts":"2026-03-04T04:58:32.365284Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b switched to configuration voters=(14666513372433383179)"} {"level":"warn","ts":"2026-03-04T04:58:32.378401Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-04T04:58:32.380221Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-04T04:58:32.381769Z","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-03-04T04:58:32.383907Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"cb89ec34a9687f0b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-04T04:58:32.384867Z","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-03-04T04:58:32.385249Z","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-03-04T04:58:32.385265Z","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-03-04T04:58:32.385889Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b switched to configuration voters=(14666513372433383179)"} {"level":"info","ts":"2026-03-04T04:58:32.386044Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"d8e0d5e222e856c9","local-member-id":"cb89ec34a9687f0b","added-peer-id":"cb89ec34a9687f0b","added-peer-peer-urls":["https://162.253.55.36:2380"]} {"level":"info","ts":"2026-03-04T04:58:32.384367Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"cb89ec34a9687f0b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-04T04:58:32.389414Z","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-03-04T04:58:32.389605Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.36:2380"} {"level":"info","ts":"2026-03-04T04:58:32.389751Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.36:2380"} {"level":"info","ts":"2026-03-04T04:58:32.391064Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"cb89ec34a9687f0b","initial-advertise-peer-urls":["https://162.253.55.36:2380"],"listen-peer-urls":["https://162.253.55.36:2380"],"advertise-client-urls":["https://162.253.55.36:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.36:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-04T04:58:32.39112Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-04T04:58:32.565922Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b is starting a new election at term 1"} {"level":"info","ts":"2026-03-04T04:58:32.566008Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b became pre-candidate at term 1"} {"level":"info","ts":"2026-03-04T04:58:32.566236Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b received MsgPreVoteResp from cb89ec34a9687f0b at term 1"} {"level":"info","ts":"2026-03-04T04:58:32.566276Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b became candidate at term 2"} {"level":"info","ts":"2026-03-04T04:58:32.566377Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b received MsgVoteResp from cb89ec34a9687f0b at term 2"} {"level":"info","ts":"2026-03-04T04:58:32.566497Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b became leader at term 2"} {"level":"info","ts":"2026-03-04T04:58:32.566594Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: cb89ec34a9687f0b elected leader cb89ec34a9687f0b at term 2"} {"level":"info","ts":"2026-03-04T04:58:32.568Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-04T04:58:32.569434Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"cb89ec34a9687f0b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.36:2379]}","request-path":"/0/members/cb89ec34a9687f0b/attributes","cluster-id":"d8e0d5e222e856c9","publish-timeout":"7s"} {"level":"info","ts":"2026-03-04T04:58:32.569491Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-04T04:58:32.569532Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-04T04:58:32.569889Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-04T04:58:32.569958Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-04T04:58:32.570123Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"d8e0d5e222e856c9","local-member-id":"cb89ec34a9687f0b","cluster-version":"3.5"} {"level":"info","ts":"2026-03-04T04:58:32.570358Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-04T04:58:32.570446Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-04T04:58:32.572106Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-04T04:58:32.572958Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.36:2379"} {"level":"info","ts":"2026-03-04T04:59:02.59083Z","caller":"traceutil/trace.go:171","msg":"trace[1551757563] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"198.52922ms","start":"2026-03-04T04:59:02.392278Z","end":"2026-03-04T04:59:02.590807Z","steps":["trace[1551757563] 'process raft request' (duration: 198.09904ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T04:59:08.596069Z","caller":"traceutil/trace.go:171","msg":"trace[1054863981] linearizableReadLoop","detail":"{readStateIndex:549; appliedIndex:548; }","duration":"101.668484ms","start":"2026-03-04T04:59:08.494376Z","end":"2026-03-04T04:59:08.596044Z","steps":["trace[1054863981] 'read index received' (duration: 101.50715ms)","trace[1054863981] 'applied index is now lower than readState.Index' (duration: 160.344µs)"],"step_count":2} {"level":"info","ts":"2026-03-04T04:59:08.596122Z","caller":"traceutil/trace.go:171","msg":"trace[1201286817] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"118.708901ms","start":"2026-03-04T04:59:08.477384Z","end":"2026-03-04T04:59:08.596092Z","steps":["trace[1201286817] 'process raft request' (duration: 118.545797ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T04:59:08.596246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.851768ms","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-03-04T04:59:08.596313Z","caller":"traceutil/trace.go:171","msg":"trace[553245308] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:529; }","duration":"101.97812ms","start":"2026-03-04T04:59:08.494324Z","end":"2026-03-04T04:59:08.596302Z","steps":["trace[553245308] 'agreement among raft nodes before linearized reading' (duration: 101.806137ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T04:59:08.756935Z","caller":"traceutil/trace.go:171","msg":"trace[1130710213] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"154.306579ms","start":"2026-03-04T04:59:08.602584Z","end":"2026-03-04T04:59:08.75689Z","steps":["trace[1130710213] 'process raft request' (duration: 54.936647ms)","trace[1130710213] 'compare' (duration: 98.933012ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T04:59:08.756828Z","caller":"traceutil/trace.go:171","msg":"trace[67798799] linearizableReadLoop","detail":"{readStateIndex:550; appliedIndex:549; }","duration":"142.985778ms","start":"2026-03-04T04:59:08.613799Z","end":"2026-03-04T04:59:08.756785Z","steps":["trace[67798799] 'read index received' (duration: 43.646118ms)","trace[67798799] 'applied index is now lower than readState.Index' (duration: 99.33792ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T04:59:08.757893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.675103ms","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-03-04T04:59:08.757997Z","caller":"traceutil/trace.go:171","msg":"trace[184041413] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:530; }","duration":"144.237236ms","start":"2026-03-04T04:59:08.613743Z","end":"2026-03-04T04:59:08.757981Z","steps":["trace[184041413] 'agreement among raft nodes before linearized reading' (duration: 143.209653ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T04:59:08.997461Z","caller":"traceutil/trace.go:171","msg":"trace[1639480106] linearizableReadLoop","detail":"{readStateIndex:552; appliedIndex:551; }","duration":"158.673636ms","start":"2026-03-04T04:59:08.838762Z","end":"2026-03-04T04:59:08.997436Z","steps":["trace[1639480106] 'read index received' (duration: 158.39852ms)","trace[1639480106] 'applied index is now lower than readState.Index' (duration: 274.186µs)"],"step_count":2} {"level":"info","ts":"2026-03-04T04:59:08.997657Z","caller":"traceutil/trace.go:171","msg":"trace[1071755706] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"234.674241ms","start":"2026-03-04T04:59:08.762951Z","end":"2026-03-04T04:59:08.997626Z","steps":["trace[1071755706] 'process raft request' (duration: 234.301232ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T04:59:08.997821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.027954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-tbkjf\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-03-04T04:59:08.998004Z","caller":"traceutil/trace.go:171","msg":"trace[494026841] range","detail":"{range_begin:/registry/pods/kube-system/cilium-tbkjf; range_end:; response_count:1; response_revision:532; }","duration":"159.241659ms","start":"2026-03-04T04:59:08.838712Z","end":"2026-03-04T04:59:08.997953Z","steps":["trace[494026841] 'agreement among raft nodes before linearized reading' (duration: 158.903441ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T04:59:09.153347Z","caller":"traceutil/trace.go:171","msg":"trace[1754345783] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"138.93996ms","start":"2026-03-04T04:59:09.014389Z","end":"2026-03-04T04:59:09.153329Z","steps":["trace[1754345783] 'process raft request' (duration: 138.747804ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T04:59:09.868167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.685825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-04T04:59:09.868242Z","caller":"traceutil/trace.go:171","msg":"trace[1863917221] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:533; }","duration":"180.804527ms","start":"2026-03-04T04:59:09.68742Z","end":"2026-03-04T04:59:09.868224Z","steps":["trace[1863917221] 'range keys from in-memory index tree' (duration: 180.513261ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T04:59:11.125641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.089308ms","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-03-04T04:59:11.125759Z","caller":"traceutil/trace.go:171","msg":"trace[1401410025] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:543; }","duration":"123.256261ms","start":"2026-03-04T04:59:11.002478Z","end":"2026-03-04T04:59:11.125734Z","steps":["trace[1401410025] 'range keys from in-memory index tree' (duration: 122.921304ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T04:59:30.309947Z","caller":"traceutil/trace.go:171","msg":"trace[1090683675] linearizableReadLoop","detail":"{readStateIndex:719; appliedIndex:718; }","duration":"335.434613ms","start":"2026-03-04T04:59:29.974484Z","end":"2026-03-04T04:59:30.309919Z","steps":["trace[1090683675] 'read index received' (duration: 335.110636ms)","trace[1090683675] 'applied index is now lower than readState.Index' (duration: 323.087µs)"],"step_count":2} {"level":"warn","ts":"2026-03-04T04:59:30.310174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.661728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-04T04:59:30.310216Z","caller":"traceutil/trace.go:171","msg":"trace[1421358596] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:694; }","duration":"335.771321ms","start":"2026-03-04T04:59:29.974432Z","end":"2026-03-04T04:59:30.310203Z","steps":["trace[1421358596] 'agreement among raft nodes before linearized reading' (duration: 335.595677ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T04:59:30.310253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T04:59:29.974401Z","time spent":"335.843792ms","remote":"127.0.0.1:52910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-04T04:59:30.310353Z","caller":"traceutil/trace.go:171","msg":"trace[208957979] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"342.52712ms","start":"2026-03-04T04:59:29.967783Z","end":"2026-03-04T04:59:30.31031Z","steps":["trace[208957979] 'process raft request' (duration: 341.897316ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T04:59:30.311155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T04:59:29.967737Z","time spent":"342.728274ms","remote":"127.0.0.1:52818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5034,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-04T04:59:30.528041Z","caller":"traceutil/trace.go:171","msg":"trace[245773311] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"213.039591ms","start":"2026-03-04T04:59:30.314963Z","end":"2026-03-04T04:59:30.528003Z","steps":["trace[245773311] 'process raft request' (duration: 212.737015ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T04:59:30.713475Z","caller":"traceutil/trace.go:171","msg":"trace[7463347] linearizableReadLoop","detail":"{readStateIndex:721; appliedIndex:719; }","duration":"395.523084ms","start":"2026-03-04T04:59:30.317922Z","end":"2026-03-04T04:59:30.713445Z","steps":["trace[7463347] 'read index received' (duration: 209.798659ms)","trace[7463347] 'applied index is now lower than readState.Index' (duration: 185.723435ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T04:59:30.713484Z","caller":"traceutil/trace.go:171","msg":"trace[2079610123] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"395.803761ms","start":"2026-03-04T04:59:30.317645Z","end":"2026-03-04T04:59:30.713449Z","steps":["trace[2079610123] 'process raft request' (duration: 382.418194ms)","trace[2079610123] 'compare' (duration: 13.206793ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T04:59:30.713659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T04:59:30.317612Z","time spent":"395.953464ms","remote":"127.0.0.1:52926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1290,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-04T04:59:30.713751Z","caller":"traceutil/trace.go:171","msg":"trace[1504755791] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"395.155987ms","start":"2026-03-04T04:59:30.318554Z","end":"2026-03-04T04:59:30.71371Z","steps":["trace[1504755791] 'process raft request' (duration: 394.83429ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T04:59:30.713868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.924694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-67659f764b-54qtz\" ","response":"range_response_count:1 size:4776"} {"level":"info","ts":"2026-03-04T04:59:30.713924Z","caller":"traceutil/trace.go:171","msg":"trace[1547685921] range","detail":"{range_begin:/registry/pods/kube-system/coredns-67659f764b-54qtz; range_end:; response_count:1; response_revision:697; }","duration":"396.046557ms","start":"2026-03-04T04:59:30.317865Z","end":"2026-03-04T04:59:30.713911Z","steps":["trace[1547685921] 'agreement among raft nodes before linearized reading' (duration: 395.849762ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T04:59:30.713961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T04:59:30.317855Z","time spent":"396.097707ms","remote":"127.0.0.1:52818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4800,"request content":"key:\"/registry/pods/kube-system/coredns-67659f764b-54qtz\" "} {"level":"warn","ts":"2026-03-04T04:59:30.713972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T04:59:30.318528Z","time spent":"395.32118ms","remote":"127.0.0.1:52798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":781,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-04T04:59:57.716127Z","caller":"traceutil/trace.go:171","msg":"trace[658855635] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"292.391289ms","start":"2026-03-04T04:59:57.423705Z","end":"2026-03-04T04:59:57.716096Z","steps":["trace[658855635] 'process raft request' (duration: 292.138213ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T04:59:57.716621Z","caller":"traceutil/trace.go:171","msg":"trace[1266552473] linearizableReadLoop","detail":"{readStateIndex:835; appliedIndex:834; }","duration":"275.045865ms","start":"2026-03-04T04:59:57.441555Z","end":"2026-03-04T04:59:57.716601Z","steps":["trace[1266552473] 'read index received' (duration: 274.145235ms)","trace[1266552473] 'applied index is now lower than readState.Index' (duration: 898.81µs)"],"step_count":2} {"level":"warn","ts":"2026-03-04T04:59:57.716811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.229939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-04T04:59:57.716852Z","caller":"traceutil/trace.go:171","msg":"trace[1644693762] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:802; }","duration":"275.384702ms","start":"2026-03-04T04:59:57.441455Z","end":"2026-03-04T04:59:57.716839Z","steps":["trace[1644693762] 'agreement among raft nodes before linearized reading' (duration: 275.220879ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T05:00:09.015834Z","caller":"traceutil/trace.go:171","msg":"trace[1722347959] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"249.993006ms","start":"2026-03-04T05:00:08.765821Z","end":"2026-03-04T05:00:09.015814Z","steps":["trace[1722347959] 'process raft request' (duration: 249.889163ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T05:00:09.349676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.366706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-03-04T05:00:09.349744Z","caller":"traceutil/trace.go:171","msg":"trace[1061992906] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:883; }","duration":"197.484249ms","start":"2026-03-04T05:00:09.152242Z","end":"2026-03-04T05:00:09.349726Z","steps":["trace[1061992906] 'range keys from in-memory index tree' (duration: 197.221222ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T05:00:09.349802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.662039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-03-04T05:00:09.349886Z","caller":"traceutil/trace.go:171","msg":"trace[1896454697] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:883; }","duration":"192.775962ms","start":"2026-03-04T05:00:09.157093Z","end":"2026-03-04T05:00:09.349869Z","steps":["trace[1896454697] 'range keys from in-memory index tree' (duration: 192.516106ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T05:00:23.19488Z","caller":"traceutil/trace.go:171","msg":"trace[685520355] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"114.281976ms","start":"2026-03-04T05:00:23.080569Z","end":"2026-03-04T05:00:23.194851Z","steps":["trace[685520355] 'process raft request' (duration: 114.111532ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T05:00:27.005995Z","caller":"traceutil/trace.go:171","msg":"trace[1074604472] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"119.606315ms","start":"2026-03-04T05:00:26.886362Z","end":"2026-03-04T05:00:27.005968Z","steps":["trace[1074604472] 'process raft request' (duration: 119.445702ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T05:00:27.7365Z","caller":"traceutil/trace.go:171","msg":"trace[786587930] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"156.260695ms","start":"2026-03-04T05:00:27.580221Z","end":"2026-03-04T05:00:27.736481Z","steps":["trace[786587930] 'process raft request' (duration: 156.111182ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T05:00:28.071245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.699764ms","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-03-04T05:00:28.071345Z","caller":"traceutil/trace.go:171","msg":"trace[99947191] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:964; }","duration":"205.879498ms","start":"2026-03-04T05:00:27.865445Z","end":"2026-03-04T05:00:28.071324Z","steps":["trace[99947191] 'range keys from in-memory index tree' (duration: 205.47234ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T05:00:28.07141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.251197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-04T05:00:28.071489Z","caller":"traceutil/trace.go:171","msg":"trace[499688065] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:964; }","duration":"115.38708ms","start":"2026-03-04T05:00:27.956081Z","end":"2026-03-04T05:00:28.071468Z","steps":["trace[499688065] 'range keys from in-memory index tree' (duration: 115.044943ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T05:00:28.234316Z","caller":"traceutil/trace.go:171","msg":"trace[402712988] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"157.898461ms","start":"2026-03-04T05:00:28.076374Z","end":"2026-03-04T05:00:28.234272Z","steps":["trace[402712988] 'process raft request' (duration: 108.400681ms)","trace[402712988] 'compare' (duration: 49.241795ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T05:00:28.81355Z","caller":"traceutil/trace.go:171","msg":"trace[1748077058] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"159.263904ms","start":"2026-03-04T05:00:28.654244Z","end":"2026-03-04T05:00:28.813508Z","steps":["trace[1748077058] 'process raft request' (duration: 159.039149ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T05:00:41.193514Z","caller":"traceutil/trace.go:171","msg":"trace[129147912] linearizableReadLoop","detail":"{readStateIndex:1088; appliedIndex:1087; }","duration":"151.660841ms","start":"2026-03-04T05:00:41.041832Z","end":"2026-03-04T05:00:41.193492Z","steps":["trace[129147912] 'read index received' (duration: 146.377311ms)","trace[129147912] 'applied index is now lower than readState.Index' (duration: 5.28188ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T05:00:41.193995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.026979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-03-04T05:00:41.194095Z","caller":"traceutil/trace.go:171","msg":"trace[1976199392] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1044; }","duration":"152.294055ms","start":"2026-03-04T05:00:41.041767Z","end":"2026-03-04T05:00:41.194061Z","steps":["trace[1976199392] 'agreement among raft nodes before linearized reading' (duration: 151.872535ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T05:00:41.472246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.897624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T05:00:41.472358Z","caller":"traceutil/trace.go:171","msg":"trace[957879107] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1045; }","duration":"147.063317ms","start":"2026-03-04T05:00:41.325271Z","end":"2026-03-04T05:00:41.472334Z","steps":["trace[957879107] 'range keys from in-memory index tree' (duration: 146.805751ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T05:00:41.472409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.716723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-04T05:00:41.472492Z","caller":"traceutil/trace.go:171","msg":"trace[1035404694] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1045; }","duration":"167.864748ms","start":"2026-03-04T05:00:41.304602Z","end":"2026-03-04T05:00:41.472467Z","steps":["trace[1035404694] 'range keys from in-memory index tree' (duration: 167.498168ms)"],"step_count":1}