{"level":"warn","ts":"2026-10-16T00:06:52.298921Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-16T00:06:52.299015Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.79: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.79:2380","--initial-cluster=instance=https://162.253.55.79:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.79:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.79: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-10-16T00:06:52.299078Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-16T00:06:52.299093Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.79:2380"]} {"level":"info","ts":"2026-10-16T00:06:52.299112Z","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-10-16T00:06:52.300729Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.79:2379"]} {"level":"info","ts":"2026-10-16T00:06:52.300806Z","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.79:2380"],"listen-peer-urls":["https://162.253.55.79:2380"],"advertise-client-urls":["https://162.253.55.79:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.79:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.79: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-10-16T00:06:52.305189Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.20132ms"} {"level":"info","ts":"2026-10-16T00:06:52.310673Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"401ca643156fc5da","cluster-id":"e9f568c3681bb0d0"} {"level":"info","ts":"2026-10-16T00:06:52.310746Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"401ca643156fc5da switched to configuration voters=()"} {"level":"info","ts":"2026-10-16T00:06:52.310767Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"401ca643156fc5da became follower at term 0"} {"level":"info","ts":"2026-10-16T00:06:52.31078Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 401ca643156fc5da [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-16T00:06:52.310794Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"401ca643156fc5da became follower at term 1"} {"level":"info","ts":"2026-10-16T00:06:52.310827Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"401ca643156fc5da switched to configuration voters=(4619750124827952602)"} {"level":"warn","ts":"2026-10-16T00:06:52.319424Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-16T00:06:52.321259Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-16T00:06:52.322645Z","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-10-16T00:06:52.324755Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"401ca643156fc5da","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-16T00:06:52.325029Z","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-10-16T00:06:52.325098Z","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-10-16T00:06:52.325104Z","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-10-16T00:06:52.325291Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"401ca643156fc5da","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-16T00:06:52.326431Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"401ca643156fc5da switched to configuration voters=(4619750124827952602)"} {"level":"info","ts":"2026-10-16T00:06:52.326566Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e9f568c3681bb0d0","local-member-id":"401ca643156fc5da","added-peer-id":"401ca643156fc5da","added-peer-peer-urls":["https://162.253.55.79:2380"]} {"level":"info","ts":"2026-10-16T00:06:52.32821Z","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-10-16T00:06:52.32831Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.79:2380"} {"level":"info","ts":"2026-10-16T00:06:52.328334Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.79:2380"} {"level":"info","ts":"2026-10-16T00:06:52.330853Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"401ca643156fc5da","initial-advertise-peer-urls":["https://162.253.55.79:2380"],"listen-peer-urls":["https://162.253.55.79:2380"],"advertise-client-urls":["https://162.253.55.79:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.79:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-16T00:06:52.330886Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-16T00:06:52.711306Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"401ca643156fc5da is starting a new election at term 1"} {"level":"info","ts":"2026-10-16T00:06:52.711394Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"401ca643156fc5da became pre-candidate at term 1"} {"level":"info","ts":"2026-10-16T00:06:52.711418Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"401ca643156fc5da received MsgPreVoteResp from 401ca643156fc5da at term 1"} {"level":"info","ts":"2026-10-16T00:06:52.711436Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"401ca643156fc5da became candidate at term 2"} {"level":"info","ts":"2026-10-16T00:06:52.711447Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"401ca643156fc5da received MsgVoteResp from 401ca643156fc5da at term 2"} {"level":"info","ts":"2026-10-16T00:06:52.711462Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"401ca643156fc5da became leader at term 2"} {"level":"info","ts":"2026-10-16T00:06:52.711475Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 401ca643156fc5da elected leader 401ca643156fc5da at term 2"} {"level":"info","ts":"2026-10-16T00:06:52.713489Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"401ca643156fc5da","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.79:2379]}","request-path":"/0/members/401ca643156fc5da/attributes","cluster-id":"e9f568c3681bb0d0","publish-timeout":"7s"} {"level":"info","ts":"2026-10-16T00:06:52.713558Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-16T00:06:52.71389Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-16T00:06:52.714154Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-16T00:06:52.714528Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-16T00:06:52.714623Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-16T00:06:52.714679Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e9f568c3681bb0d0","local-member-id":"401ca643156fc5da","cluster-version":"3.5"} {"level":"info","ts":"2026-10-16T00:06:52.714756Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-16T00:06:52.714773Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-16T00:06:52.715702Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-16T00:06:52.71681Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.79:2379"} {"level":"info","ts":"2026-10-16T00:06:54.475704Z","caller":"traceutil/trace.go:171","msg":"trace[516704371] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"218.777955ms","start":"2026-10-16T00:06:54.256867Z","end":"2026-10-16T00:06:54.475645Z","steps":["trace[516704371] 'process raft request' (duration: 145.888184ms)","trace[516704371] 'compare' (duration: 72.716038ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:06:54.475891Z","caller":"traceutil/trace.go:171","msg":"trace[1238636812] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"204.158107ms","start":"2026-10-16T00:06:54.271693Z","end":"2026-10-16T00:06:54.475851Z","steps":["trace[1238636812] 'process raft request' (duration: 204.003034ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:06:54.475905Z","caller":"traceutil/trace.go:171","msg":"trace[851656014] linearizableReadLoop","detail":"{readStateIndex:15; appliedIndex:7; }","duration":"186.730367ms","start":"2026-10-16T00:06:54.289147Z","end":"2026-10-16T00:06:54.475877Z","steps":["trace[851656014] 'read index received' (duration: 113.583701ms)","trace[851656014] 'applied index is now lower than readState.Index' (duration: 73.145716ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:06:54.476042Z","caller":"traceutil/trace.go:171","msg":"trace[317323706] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"204.25985ms","start":"2026-10-16T00:06:54.271769Z","end":"2026-10-16T00:06:54.476029Z","steps":["trace[317323706] 'process raft request' (duration: 203.958624ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:06:54.476155Z","caller":"traceutil/trace.go:171","msg":"trace[1201027176] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"204.757469ms","start":"2026-10-16T00:06:54.271386Z","end":"2026-10-16T00:06:54.476143Z","steps":["trace[1201027176] 'process raft request' (duration: 204.195248ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:06:54.476243Z","caller":"traceutil/trace.go:171","msg":"trace[1366311992] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"204.77917ms","start":"2026-10-16T00:06:54.271455Z","end":"2026-10-16T00:06:54.476235Z","steps":["trace[1366311992] 'process raft request' (duration: 204.169198ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:06:54.476294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.815175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-16T00:06:54.476388Z","caller":"traceutil/trace.go:171","msg":"trace[118052051] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:12; }","duration":"209.976718ms","start":"2026-10-16T00:06:54.266396Z","end":"2026-10-16T00:06:54.476372Z","steps":["trace[118052051] 'agreement among raft nodes before linearized reading' (duration: 209.738134ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:06:54.476397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.379775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-10-16T00:06:54.476443Z","caller":"traceutil/trace.go:171","msg":"trace[711139153] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:12; }","duration":"220.498287ms","start":"2026-10-16T00:06:54.255929Z","end":"2026-10-16T00:06:54.476427Z","steps":["trace[711139153] 'agreement among raft nodes before linearized reading' (duration: 220.372345ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:06:54.476447Z","caller":"traceutil/trace.go:171","msg":"trace[1924770577] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"204.503225ms","start":"2026-10-16T00:06:54.271881Z","end":"2026-10-16T00:06:54.476384Z","steps":["trace[1924770577] 'process raft request' (duration: 203.870762ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:06:54.476507Z","caller":"traceutil/trace.go:171","msg":"trace[1249247090] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"192.499677ms","start":"2026-10-16T00:06:54.283997Z","end":"2026-10-16T00:06:54.476497Z","steps":["trace[1249247090] 'process raft request' (duration: 191.778703ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:06:54.476595Z","caller":"traceutil/trace.go:171","msg":"trace[300869707] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"192.300332ms","start":"2026-10-16T00:06:54.284282Z","end":"2026-10-16T00:06:54.476583Z","steps":["trace[300869707] 'process raft request' (duration: 191.539658ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:06:54.476685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.899345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-10-16T00:06:54.476726Z","caller":"traceutil/trace.go:171","msg":"trace[1309302105] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:12; }","duration":"220.961086ms","start":"2026-10-16T00:06:54.255753Z","end":"2026-10-16T00:06:54.476714Z","steps":["trace[1309302105] 'agreement among raft nodes before linearized reading' (duration: 220.815093ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:06:54.493165Z","caller":"traceutil/trace.go:171","msg":"trace[519155263] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"201.63496ms","start":"2026-10-16T00:06:54.291513Z","end":"2026-10-16T00:06:54.493148Z","steps":["trace[519155263] 'process raft request' (duration: 201.496907ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:06:54.49333Z","caller":"traceutil/trace.go:171","msg":"trace[103456389] transaction","detail":"{read_only:false; number_of_response:0; response_revision:13; }","duration":"201.749072ms","start":"2026-10-16T00:06:54.291547Z","end":"2026-10-16T00:06:54.493296Z","steps":["trace[103456389] 'process raft request' (duration: 201.550048ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:06:54.493436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.555507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-16T00:06:54.49347Z","caller":"traceutil/trace.go:171","msg":"trace[1806126010] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:13; }","duration":"174.634539ms","start":"2026-10-16T00:06:54.318825Z","end":"2026-10-16T00:06:54.49346Z","steps":["trace[1806126010] 'agreement among raft nodes before linearized reading' (duration: 174.568698ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:06:54.493682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.166233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-10-16T00:06:54.493951Z","caller":"traceutil/trace.go:171","msg":"trace[725677271] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:13; }","duration":"171.477658ms","start":"2026-10-16T00:06:54.322267Z","end":"2026-10-16T00:06:54.493745Z","steps":["trace[725677271] 'agreement among raft nodes before linearized reading' (duration: 171.008749ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:06:59.969777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.225212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-10-16T00:06:59.969879Z","caller":"traceutil/trace.go:171","msg":"trace[2040314671] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:320; }","duration":"277.376995ms","start":"2026-10-16T00:06:59.692476Z","end":"2026-10-16T00:06:59.969853Z","steps":["trace[2040314671] 'range keys from in-memory index tree' (duration: 277.062859ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:07:00.076811Z","caller":"traceutil/trace.go:171","msg":"trace[806802517] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"103.621413ms","start":"2026-10-16T00:06:59.973167Z","end":"2026-10-16T00:07:00.076789Z","steps":["trace[806802517] 'process raft request' (duration: 103.48212ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:07:00.286272Z","caller":"traceutil/trace.go:171","msg":"trace[1127060399] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"201.354524ms","start":"2026-10-16T00:07:00.084886Z","end":"2026-10-16T00:07:00.286241Z","steps":["trace[1127060399] 'process raft request' (duration: 201.15763ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:07:00.510521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.657886ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-16T00:07:00.510653Z","caller":"traceutil/trace.go:171","msg":"trace[630458520] linearizableReadLoop","detail":"{readStateIndex:333; appliedIndex:332; }","duration":"218.408018ms","start":"2026-10-16T00:07:00.29221Z","end":"2026-10-16T00:07:00.510618Z","steps":["trace[630458520] 'read index received' (duration: 115.840074ms)","trace[630458520] 'applied index is now lower than readState.Index' (duration: 102.566394ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:07:00.51068Z","caller":"traceutil/trace.go:171","msg":"trace[1814220134] transaction","detail":"{read_only:false; response_revision:323; number_of_response:1; }","duration":"220.325454ms","start":"2026-10-16T00:07:00.290311Z","end":"2026-10-16T00:07:00.510636Z","steps":["trace[1814220134] 'process raft request' (duration: 117.800951ms)","trace[1814220134] 'compare' (duration: 101.493863ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:07:00.510737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.52604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-10-16T00:07:00.510801Z","caller":"traceutil/trace.go:171","msg":"trace[350550228] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:323; }","duration":"218.591371ms","start":"2026-10-16T00:07:00.292179Z","end":"2026-10-16T00:07:00.51077Z","steps":["trace[350550228] 'agreement among raft nodes before linearized reading' (duration: 218.510469ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:07:37.047991Z","caller":"traceutil/trace.go:171","msg":"trace[784496136] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"188.804186ms","start":"2026-10-16T00:07:36.859141Z","end":"2026-10-16T00:07:37.047946Z","steps":["trace[784496136] 'process raft request' (duration: 180.918677ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:07:37.048411Z","caller":"traceutil/trace.go:171","msg":"trace[910379690] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"189.057651ms","start":"2026-10-16T00:07:36.859307Z","end":"2026-10-16T00:07:37.048365Z","steps":["trace[910379690] 'process raft request' (duration: 188.713144ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:07:37.048843Z","caller":"traceutil/trace.go:171","msg":"trace[1440479628] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"177.086255ms","start":"2026-10-16T00:07:36.871738Z","end":"2026-10-16T00:07:37.048824Z","steps":["trace[1440479628] 'process raft request' (duration: 176.749708ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:07:37.048848Z","caller":"traceutil/trace.go:171","msg":"trace[1706635639] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"183.880213ms","start":"2026-10-16T00:07:36.864941Z","end":"2026-10-16T00:07:37.048821Z","steps":["trace[1706635639] 'process raft request' (duration: 183.21407ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:07:37.048897Z","caller":"traceutil/trace.go:171","msg":"trace[1162510666] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"180.323467ms","start":"2026-10-16T00:07:36.868553Z","end":"2026-10-16T00:07:37.048876Z","steps":["trace[1162510666] 'process raft request' (duration: 179.876218ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:07:37.049116Z","caller":"traceutil/trace.go:171","msg":"trace[1970482357] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"181.880035ms","start":"2026-10-16T00:07:36.867222Z","end":"2026-10-16T00:07:37.049102Z","steps":["trace[1970482357] 'process raft request' (duration: 180.992328ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:07:37.049282Z","caller":"traceutil/trace.go:171","msg":"trace[570867461] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"181.979007ms","start":"2026-10-16T00:07:36.867276Z","end":"2026-10-16T00:07:37.049255Z","steps":["trace[570867461] 'process raft request' (duration: 180.995978ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:07:43.156162Z","caller":"traceutil/trace.go:171","msg":"trace[2117132975] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"212.351744ms","start":"2026-10-16T00:07:42.943786Z","end":"2026-10-16T00:07:43.156138Z","steps":["trace[2117132975] 'process raft request' (duration: 210.871775ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:07:43.943722Z","caller":"traceutil/trace.go:171","msg":"trace[1242509107] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"243.388921ms","start":"2026-10-16T00:07:43.700305Z","end":"2026-10-16T00:07:43.943694Z","steps":["trace[1242509107] 'process raft request' (duration: 243.231618ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:07:43.956154Z","caller":"traceutil/trace.go:171","msg":"trace[1414652860] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"207.586413ms","start":"2026-10-16T00:07:43.748548Z","end":"2026-10-16T00:07:43.956134Z","steps":["trace[1414652860] 'process raft request' (duration: 207.131414ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:07:59.421486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.336159ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-16T00:07:59.421549Z","caller":"traceutil/trace.go:171","msg":"trace[1017474613] linearizableReadLoop","detail":"{readStateIndex:846; appliedIndex:845; }","duration":"175.776651ms","start":"2026-10-16T00:07:59.245764Z","end":"2026-10-16T00:07:59.42154Z","steps":["trace[1017474613] 'read index received' (duration: 17.243279ms)","trace[1017474613] 'applied index is now lower than readState.Index' (duration: 158.532532ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:07:59.421874Z","caller":"traceutil/trace.go:171","msg":"trace[580675688] transaction","detail":"{read_only:false; response_revision:818; number_of_response:1; }","duration":"309.939402ms","start":"2026-10-16T00:07:59.111884Z","end":"2026-10-16T00:07:59.421824Z","steps":["trace[580675688] 'process raft request' (duration: 151.216357ms)","trace[580675688] 'compare' (duration: 158.263557ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:07:59.422026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.26096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-10-16T00:07:59.422047Z","caller":"traceutil/trace.go:171","msg":"trace[2029151840] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:818; }","duration":"176.318901ms","start":"2026-10-16T00:07:59.245722Z","end":"2026-10-16T00:07:59.422041Z","steps":["trace[2029151840] 'agreement among raft nodes before linearized reading' (duration: 175.962584ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:07:59.422069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:07:59.111858Z","time spent":"310.106336ms","remote":"127.0.0.1:39278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:08:40.652874Z","caller":"traceutil/trace.go:171","msg":"trace[1425152944] linearizableReadLoop","detail":"{readStateIndex:1139; appliedIndex:1138; }","duration":"250.071845ms","start":"2026-10-16T00:08:40.402772Z","end":"2026-10-16T00:08:40.652844Z","steps":["trace[1425152944] 'read index received' (duration: 249.79789ms)","trace[1425152944] 'applied index is now lower than readState.Index' (duration: 272.745µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:08:40.65299Z","caller":"traceutil/trace.go:171","msg":"trace[439411106] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"404.38875ms","start":"2026-10-16T00:08:40.248559Z","end":"2026-10-16T00:08:40.652947Z","steps":["trace[439411106] 'process raft request' (duration: 404.112935ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:08:40.65313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.289788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:08:40.653217Z","caller":"traceutil/trace.go:171","msg":"trace[1161435871] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1102; }","duration":"250.461322ms","start":"2026-10-16T00:08:40.40274Z","end":"2026-10-16T00:08:40.653201Z","steps":["trace[1161435871] 'agreement among raft nodes before linearized reading' (duration: 250.284159ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:08:40.653191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:08:40.248528Z","time spent":"404.552952ms","remote":"127.0.0.1:39278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:08:53.818122Z","caller":"traceutil/trace.go:171","msg":"trace[768214962] transaction","detail":"{read_only:false; response_revision:1146; number_of_response:1; }","duration":"102.234999ms","start":"2026-10-16T00:08:53.715858Z","end":"2026-10-16T00:08:53.818093Z","steps":["trace[768214962] 'process raft request' (duration: 102.069546ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:08:55.856727Z","caller":"traceutil/trace.go:171","msg":"trace[1777473859] transaction","detail":"{read_only:false; response_revision:1159; number_of_response:1; }","duration":"170.426904ms","start":"2026-10-16T00:08:55.686256Z","end":"2026-10-16T00:08:55.856683Z","steps":["trace[1777473859] 'process raft request' (duration: 170.167779ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:08:56.141105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.192737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.79\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-10-16T00:08:56.141177Z","caller":"traceutil/trace.go:171","msg":"trace[1939925819] range","detail":"{range_begin:/registry/masterleases/162.253.55.79; range_end:; response_count:1; response_revision:1160; }","duration":"174.313869ms","start":"2026-10-16T00:08:55.966847Z","end":"2026-10-16T00:08:56.141161Z","steps":["trace[1939925819] 'range keys from in-memory index tree' (duration: 173.985962ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:08:56.597767Z","caller":"traceutil/trace.go:171","msg":"trace[632960066] transaction","detail":"{read_only:false; response_revision:1163; number_of_response:1; }","duration":"132.271303ms","start":"2026-10-16T00:08:56.464715Z","end":"2026-10-16T00:08:56.596986Z","steps":["trace[632960066] 'process raft request' (duration: 130.707233ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:08:56.828845Z","caller":"traceutil/trace.go:171","msg":"trace[1916252669] transaction","detail":"{read_only:false; response_revision:1164; number_of_response:1; }","duration":"131.630032ms","start":"2026-10-16T00:08:56.697171Z","end":"2026-10-16T00:08:56.828801Z","steps":["trace[1916252669] 'process raft request' (duration: 131.437149ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:08:56.833511Z","caller":"traceutil/trace.go:171","msg":"trace[1709061706] linearizableReadLoop","detail":"{readStateIndex:1207; appliedIndex:1205; }","duration":"110.168541ms","start":"2026-10-16T00:08:56.723301Z","end":"2026-10-16T00:08:56.83347Z","steps":["trace[1709061706] 'read index received' (duration: 105.46926ms)","trace[1709061706] 'applied index is now lower than readState.Index' (duration: 4.698161ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:08:56.833707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.383575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5373"} {"level":"info","ts":"2026-10-16T00:08:56.833734Z","caller":"traceutil/trace.go:171","msg":"trace[590870683] transaction","detail":"{read_only:false; response_revision:1165; number_of_response:1; }","duration":"133.381546ms","start":"2026-10-16T00:08:56.700333Z","end":"2026-10-16T00:08:56.833714Z","steps":["trace[590870683] 'process raft request' (duration: 132.997208ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:08:56.833752Z","caller":"traceutil/trace.go:171","msg":"trace[1560416738] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1165; }","duration":"110.473447ms","start":"2026-10-16T00:08:56.723265Z","end":"2026-10-16T00:08:56.833739Z","steps":["trace[1560416738] 'agreement among raft nodes before linearized reading' (duration: 110.316634ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:08:56.947194Z","caller":"traceutil/trace.go:171","msg":"trace[1003413855] transaction","detail":"{read_only:false; response_revision:1167; number_of_response:1; }","duration":"104.159855ms","start":"2026-10-16T00:08:56.843Z","end":"2026-10-16T00:08:56.94716Z","steps":["trace[1003413855] 'process raft request' (duration: 103.876439ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:08:57.27983Z","caller":"traceutil/trace.go:171","msg":"trace[2072272894] linearizableReadLoop","detail":"{readStateIndex:1212; appliedIndex:1211; }","duration":"109.544937ms","start":"2026-10-16T00:08:57.170255Z","end":"2026-10-16T00:08:57.2798Z","steps":["trace[2072272894] 'read index received' (duration: 109.292922ms)","trace[2072272894] 'applied index is now lower than readState.Index' (duration: 250.985µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:08:57.280134Z","caller":"traceutil/trace.go:171","msg":"trace[113791558] transaction","detail":"{read_only:false; response_revision:1170; number_of_response:1; }","duration":"127.086984ms","start":"2026-10-16T00:08:57.153011Z","end":"2026-10-16T00:08:57.280098Z","steps":["trace[113791558] 'process raft request' (duration: 126.635405ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:08:57.280239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.966185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-10-16T00:08:57.28061Z","caller":"traceutil/trace.go:171","msg":"trace[1341087282] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1170; }","duration":"110.380384ms","start":"2026-10-16T00:08:57.170208Z","end":"2026-10-16T00:08:57.280588Z","steps":["trace[1341087282] 'agreement among raft nodes before linearized reading' (duration: 109.852564ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:08:57.423145Z","caller":"traceutil/trace.go:171","msg":"trace[1767797566] transaction","detail":"{read_only:false; response_revision:1171; number_of_response:1; }","duration":"138.430631ms","start":"2026-10-16T00:08:57.284688Z","end":"2026-10-16T00:08:57.423119Z","steps":["trace[1767797566] 'process raft request' (duration: 138.182096ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:09:11.457645Z","caller":"traceutil/trace.go:171","msg":"trace[1633337753] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"111.411064ms","start":"2026-10-16T00:09:11.346193Z","end":"2026-10-16T00:09:11.457604Z","steps":["trace[1633337753] 'process raft request' (duration: 111.254711ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:09:11.650309Z","caller":"traceutil/trace.go:171","msg":"trace[1038951922] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"165.823097ms","start":"2026-10-16T00:09:11.484462Z","end":"2026-10-16T00:09:11.650285Z","steps":["trace[1038951922] 'process raft request' (duration: 131.921657ms)","trace[1038951922] 'compare' (duration: 33.292708ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:09:11.651821Z","caller":"traceutil/trace.go:171","msg":"trace[288331271] linearizableReadLoop","detail":"{readStateIndex:1318; appliedIndex:1316; }","duration":"142.291346ms","start":"2026-10-16T00:09:11.509505Z","end":"2026-10-16T00:09:11.651796Z","steps":["trace[288331271] 'read index received' (duration: 106.853947ms)","trace[288331271] 'applied index is now lower than readState.Index' (duration: 35.436089ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:09:11.652023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.50641ms","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-10-16T00:09:11.652062Z","caller":"traceutil/trace.go:171","msg":"trace[909938893] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1273; }","duration":"142.582521ms","start":"2026-10-16T00:09:11.509469Z","end":"2026-10-16T00:09:11.652051Z","steps":["trace[909938893] 'agreement among raft nodes before linearized reading' (duration: 142.422498ms)"],"step_count":1}