{"level":"warn","ts":"2026-09-18T15:33:03.99406Z","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-18T15:33:03.994217Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.113: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.113:2380","--initial-cluster=instance=https://162.253.55.113:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.113:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.113: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-18T15:33:03.994371Z","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-18T15:33:03.994398Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.113:2380"]} {"level":"info","ts":"2026-09-18T15:33:03.994429Z","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-18T15:33:03.995037Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.113:2379"]} {"level":"info","ts":"2026-09-18T15:33:03.995146Z","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.113:2380"],"listen-peer-urls":["https://162.253.55.113:2380"],"advertise-client-urls":["https://162.253.55.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.113: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-18T15:33:03.997818Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.446884ms"} {"level":"info","ts":"2026-09-18T15:33:04.003765Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3b717bddf98d48e3","cluster-id":"cef0f7c69f0576fc"} {"level":"info","ts":"2026-09-18T15:33:04.004041Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 switched to configuration voters=()"} {"level":"info","ts":"2026-09-18T15:33:04.004135Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 became follower at term 0"} {"level":"info","ts":"2026-09-18T15:33:04.004182Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3b717bddf98d48e3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-18T15:33:04.004233Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 became follower at term 1"} {"level":"info","ts":"2026-09-18T15:33:04.004286Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 switched to configuration voters=(4283340913910827235)"} {"level":"warn","ts":"2026-09-18T15:33:04.013264Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-18T15:33:04.014675Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-18T15:33:04.015921Z","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-18T15:33:04.018327Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3b717bddf98d48e3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-18T15:33:04.018488Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3b717bddf98d48e3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-18T15:33:04.018665Z","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-18T15:33:04.018698Z","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-18T15:33:04.01871Z","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-18T15:33:04.020212Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 switched to configuration voters=(4283340913910827235)"} {"level":"info","ts":"2026-09-18T15:33:04.020367Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cef0f7c69f0576fc","local-member-id":"3b717bddf98d48e3","added-peer-id":"3b717bddf98d48e3","added-peer-peer-urls":["https://162.253.55.113:2380"]} {"level":"info","ts":"2026-09-18T15:33:04.021224Z","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-18T15:33:04.021312Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.113:2380"} {"level":"info","ts":"2026-09-18T15:33:04.021362Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.113:2380"} {"level":"info","ts":"2026-09-18T15:33:04.021401Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3b717bddf98d48e3","initial-advertise-peer-urls":["https://162.253.55.113:2380"],"listen-peer-urls":["https://162.253.55.113:2380"],"advertise-client-urls":["https://162.253.55.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-18T15:33:04.021441Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-18T15:33:05.004577Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 is starting a new election at term 1"} {"level":"info","ts":"2026-09-18T15:33:05.004648Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-18T15:33:05.004669Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 received MsgPreVoteResp from 3b717bddf98d48e3 at term 1"} {"level":"info","ts":"2026-09-18T15:33:05.004686Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 became candidate at term 2"} {"level":"info","ts":"2026-09-18T15:33:05.004696Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 received MsgVoteResp from 3b717bddf98d48e3 at term 2"} {"level":"info","ts":"2026-09-18T15:33:05.004712Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 became leader at term 2"} {"level":"info","ts":"2026-09-18T15:33:05.004724Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3b717bddf98d48e3 elected leader 3b717bddf98d48e3 at term 2"} {"level":"info","ts":"2026-09-18T15:33:05.005759Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T15:33:05.006969Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3b717bddf98d48e3","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.113:2379]}","request-path":"/0/members/3b717bddf98d48e3/attributes","cluster-id":"cef0f7c69f0576fc","publish-timeout":"7s"} {"level":"info","ts":"2026-09-18T15:33:05.007019Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-18T15:33:05.00709Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-18T15:33:05.00741Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-18T15:33:05.007512Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-18T15:33:05.008313Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cef0f7c69f0576fc","local-member-id":"3b717bddf98d48e3","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T15:33:05.008416Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T15:33:05.008458Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T15:33:05.010725Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-18T15:33:05.010766Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.113:2379"} {"level":"warn","ts":"2026-09-18T15:33:11.9137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.349601ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-18T15:33:11.913833Z","caller":"traceutil/trace.go:171","msg":"trace[134405213] transaction","detail":"{read_only:false; response_revision:289; number_of_response:1; }","duration":"401.258116ms","start":"2026-09-18T15:33:11.512553Z","end":"2026-09-18T15:33:11.913812Z","steps":["trace[134405213] 'process raft request' (duration: 141.856003ms)","trace[134405213] 'compare' (duration: 258.171575ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:33:11.913965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:33:11.512525Z","time spent":"401.38734ms","remote":"127.0.0.1:33506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":199,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-18T15:33:12.322611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.393356ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-18T15:33:12.322724Z","caller":"traceutil/trace.go:171","msg":"trace[50943308] transaction","detail":"{read_only:false; response_revision:290; number_of_response:1; }","duration":"377.997986ms","start":"2026-09-18T15:33:11.944706Z","end":"2026-09-18T15:33:12.322704Z","steps":["trace[50943308] 'process raft request' (duration: 187.449196ms)","trace[50943308] 'compare' (duration: 190.243841ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:33:12.322778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:33:11.944693Z","time spent":"378.065379ms","remote":"127.0.0.1:33506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":178,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-18T15:33:44.660815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.818859ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-18T15:33:44.661082Z","caller":"traceutil/trace.go:171","msg":"trace[912786005] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"312.818756ms","start":"2026-09-18T15:33:44.348241Z","end":"2026-09-18T15:33:44.66106Z","steps":["trace[912786005] 'process raft request' (duration: 65.698337ms)","trace[912786005] 'compare' (duration: 246.588502ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:33:44.661176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:33:44.348221Z","time spent":"312.925969ms","remote":"127.0.0.1:33482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15015,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T15:33:44.663963Z","caller":"traceutil/trace.go:171","msg":"trace[195255446] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"313.293671ms","start":"2026-09-18T15:33:44.35065Z","end":"2026-09-18T15:33:44.663943Z","steps":["trace[195255446] 'process raft request' (duration: 312.671161ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:33:44.663958Z","caller":"traceutil/trace.go:171","msg":"trace[1442644882] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"314.288841ms","start":"2026-09-18T15:33:44.349622Z","end":"2026-09-18T15:33:44.663911Z","steps":["trace[1442644882] 'process raft request' (duration: 313.598809ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:33:44.664035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:33:44.350636Z","time spent":"313.369532ms","remote":"127.0.0.1:33482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3576,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-18T15:33:44.664094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:33:44.349609Z","time spent":"314.421725ms","remote":"127.0.0.1:33388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":712,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T15:33:44.664266Z","caller":"traceutil/trace.go:171","msg":"trace[1254986290] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"312.29441ms","start":"2026-09-18T15:33:44.351953Z","end":"2026-09-18T15:33:44.664247Z","steps":["trace[1254986290] 'process raft request' (duration: 311.410172ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:33:44.664424Z","caller":"traceutil/trace.go:171","msg":"trace[608079705] linearizableReadLoop","detail":"{readStateIndex:493; appliedIndex:489; }","duration":"193.324385ms","start":"2026-09-18T15:33:44.471078Z","end":"2026-09-18T15:33:44.664403Z","steps":["trace[608079705] 'read index received' (duration: 192.072547ms)","trace[608079705] 'applied index is now lower than readState.Index' (duration: 1.251048ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:33:44.664517Z","caller":"traceutil/trace.go:171","msg":"trace[1783285284] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"289.044048ms","start":"2026-09-18T15:33:44.375463Z","end":"2026-09-18T15:33:44.664507Z","steps":["trace[1783285284] 'process raft request' (duration: 288.251994ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:33:44.664534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:33:44.351941Z","time spent":"312.374242ms","remote":"127.0.0.1:33744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4320,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T15:33:44.664715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.634494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T15:33:44.664764Z","caller":"traceutil/trace.go:171","msg":"trace[2066691837] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:476; }","duration":"193.737308ms","start":"2026-09-18T15:33:44.471017Z","end":"2026-09-18T15:33:44.664754Z","steps":["trace[2066691837] 'agreement among raft nodes before linearized reading' (duration: 193.659105ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:33:46.438792Z","caller":"traceutil/trace.go:171","msg":"trace[569551536] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"114.534619ms","start":"2026-09-18T15:33:46.324243Z","end":"2026-09-18T15:33:46.438778Z","steps":["trace[569551536] 'process raft request' (duration: 114.461897ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:33:46.445846Z","caller":"traceutil/trace.go:171","msg":"trace[576665068] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"105.522393ms","start":"2026-09-18T15:33:46.340303Z","end":"2026-09-18T15:33:46.445826Z","steps":["trace[576665068] 'process raft request' (duration: 104.022707ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:33:46.44598Z","caller":"traceutil/trace.go:171","msg":"trace[12080683] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"116.89627ms","start":"2026-09-18T15:33:46.329051Z","end":"2026-09-18T15:33:46.445948Z","steps":["trace[12080683] 'process raft request' (duration: 115.139636ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:33:46.446065Z","caller":"traceutil/trace.go:171","msg":"trace[1629357420] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"100.041016ms","start":"2026-09-18T15:33:46.345998Z","end":"2026-09-18T15:33:46.446039Z","steps":["trace[1629357420] 'process raft request' (duration: 98.370235ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:33:52.301364Z","caller":"traceutil/trace.go:171","msg":"trace[267838162] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"115.223029ms","start":"2026-09-18T15:33:52.186112Z","end":"2026-09-18T15:33:52.301335Z","steps":["trace[267838162] 'process raft request' (duration: 110.65086ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:33:53.186074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.92377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-nb6fv\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-09-18T15:33:53.186169Z","caller":"traceutil/trace.go:171","msg":"trace[741043159] range","detail":"{range_begin:/registry/pods/kube-system/cilium-nb6fv; range_end:; response_count:1; response_revision:606; }","duration":"372.058005ms","start":"2026-09-18T15:33:52.814087Z","end":"2026-09-18T15:33:53.186145Z","steps":["trace[741043159] 'range keys from in-memory index tree' (duration: 371.682763ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:33:53.186216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:33:52.81406Z","time spent":"372.143917ms","remote":"127.0.0.1:33482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-nb6fv\" "} {"level":"warn","ts":"2026-09-18T15:33:53.326603Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5252218290336925980,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-18T15:33:53.359894Z","caller":"traceutil/trace.go:171","msg":"trace[1909342194] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"535.135995ms","start":"2026-09-18T15:33:52.824715Z","end":"2026-09-18T15:33:53.359851Z","steps":["trace[1909342194] 'process raft request' (duration: 534.682972ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:33:53.360053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:33:52.824691Z","time spent":"535.28895ms","remote":"127.0.0.1:33412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T15:33:53.384191Z","caller":"traceutil/trace.go:171","msg":"trace[936189639] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:627; }","duration":"558.415477ms","start":"2026-09-18T15:33:52.825751Z","end":"2026-09-18T15:33:53.384166Z","steps":["trace[936189639] 'read index received' (duration: 533.433273ms)","trace[936189639] 'applied index is now lower than readState.Index' (duration: 24.980964ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:33:53.384541Z","caller":"traceutil/trace.go:171","msg":"trace[107738530] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"187.773835ms","start":"2026-09-18T15:33:53.196755Z","end":"2026-09-18T15:33:53.384528Z","steps":["trace[107738530] 'process raft request' (duration: 187.294651ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:33:53.384837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.078817ms","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-18T15:33:53.384908Z","caller":"traceutil/trace.go:171","msg":"trace[1285615059] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:608; }","duration":"559.161219ms","start":"2026-09-18T15:33:52.825735Z","end":"2026-09-18T15:33:53.384896Z","steps":["trace[1285615059] 'agreement among raft nodes before linearized reading' (duration: 558.998655ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:33:53.384981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:33:52.825685Z","time spent":"559.284403ms","remote":"127.0.0.1:33572","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-18T15:33:53.385197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.016732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-09-18T15:33:53.385249Z","caller":"traceutil/trace.go:171","msg":"trace[679013759] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:608; }","duration":"151.099535ms","start":"2026-09-18T15:33:53.234135Z","end":"2026-09-18T15:33:53.385234Z","steps":["trace[679013759] 'agreement among raft nodes before linearized reading' (duration: 150.951221ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:34:10.047209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.926202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-qclgq\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-09-18T15:34:10.047302Z","caller":"traceutil/trace.go:171","msg":"trace[579578413] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-qclgq; range_end:; response_count:1; response_revision:777; }","duration":"128.078207ms","start":"2026-09-18T15:34:09.919204Z","end":"2026-09-18T15:34:10.047282Z","steps":["trace[579578413] 'range keys from in-memory index tree' (duration: 127.666475ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:34:56.023023Z","caller":"traceutil/trace.go:171","msg":"trace[1701494511] linearizableReadLoop","detail":"{readStateIndex:1125; appliedIndex:1124; }","duration":"310.396788ms","start":"2026-09-18T15:34:55.712604Z","end":"2026-09-18T15:34:56.023001Z","steps":["trace[1701494511] 'read index received' (duration: 310.202832ms)","trace[1701494511] 'applied index is now lower than readState.Index' (duration: 193.176µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:34:56.023097Z","caller":"traceutil/trace.go:171","msg":"trace[1720737436] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"313.475172ms","start":"2026-09-18T15:34:55.709581Z","end":"2026-09-18T15:34:56.023056Z","steps":["trace[1720737436] 'process raft request' (duration: 313.227564ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:34:56.023214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.602124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-18T15:34:56.023252Z","caller":"traceutil/trace.go:171","msg":"trace[1283188257] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1086; }","duration":"310.673096ms","start":"2026-09-18T15:34:55.712569Z","end":"2026-09-18T15:34:56.023243Z","steps":["trace[1283188257] 'agreement among raft nodes before linearized reading' (duration: 310.548403ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:34:56.02326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:34:55.709572Z","time spent":"313.576594ms","remote":"127.0.0.1:33572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T15:34:56.02328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:34:55.712555Z","time spent":"310.717497ms","remote":"127.0.0.1:33572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-18T15:34:56.023426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.876032ms","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-18T15:34:56.023493Z","caller":"traceutil/trace.go:171","msg":"trace[1163892478] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1086; }","duration":"309.963874ms","start":"2026-09-18T15:34:55.713511Z","end":"2026-09-18T15:34:56.023474Z","steps":["trace[1163892478] 'agreement among raft nodes before linearized reading' (duration: 309.760948ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:34:56.023531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:34:55.713504Z","time spent":"310.018856ms","remote":"127.0.0.1:33572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-09-18T15:35:08.219009Z","caller":"traceutil/trace.go:171","msg":"trace[1512453730] linearizableReadLoop","detail":"{readStateIndex:1168; appliedIndex:1166; }","duration":"119.048772ms","start":"2026-09-18T15:35:08.09993Z","end":"2026-09-18T15:35:08.218979Z","steps":["trace[1512453730] 'read index received' (duration: 8.851201ms)","trace[1512453730] 'applied index is now lower than readState.Index' (duration: 110.196011ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:35:08.219314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.358402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.113\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-09-18T15:35:08.219356Z","caller":"traceutil/trace.go:171","msg":"trace[1008387598] range","detail":"{range_begin:/registry/masterleases/162.253.55.113; range_end:; response_count:1; response_revision:1127; }","duration":"119.488525ms","start":"2026-09-18T15:35:08.099855Z","end":"2026-09-18T15:35:08.219344Z","steps":["trace[1008387598] 'agreement among raft nodes before linearized reading' (duration: 119.246168ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:35:08.219725Z","caller":"traceutil/trace.go:171","msg":"trace[760260631] transaction","detail":"{read_only:false; response_revision:1127; number_of_response:1; }","duration":"122.036832ms","start":"2026-09-18T15:35:08.097675Z","end":"2026-09-18T15:35:08.219712Z","steps":["trace[760260631] 'process raft request' (duration: 121.133924ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:35:13.380113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.125256ms","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-18T15:35:13.380164Z","caller":"traceutil/trace.go:171","msg":"trace[1142761070] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1164; }","duration":"283.210789ms","start":"2026-09-18T15:35:13.096942Z","end":"2026-09-18T15:35:13.380153Z","steps":["trace[1142761070] 'range keys from in-memory index tree' (duration: 282.999203ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:35:13.468515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.59653ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-09-18T15:35:13.468883Z","caller":"traceutil/trace.go:171","msg":"trace[1110645111] transaction","detail":"{read_only:false; response_revision:1165; number_of_response:1; }","duration":"217.094241ms","start":"2026-09-18T15:35:13.251756Z","end":"2026-09-18T15:35:13.46885Z","steps":["trace[1110645111] 'process raft request' (duration: 216.847374ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:35:14.290286Z","caller":"traceutil/trace.go:171","msg":"trace[1577633381] linearizableReadLoop","detail":"{readStateIndex:1212; appliedIndex:1211; }","duration":"175.886665ms","start":"2026-09-18T15:35:14.114378Z","end":"2026-09-18T15:35:14.290265Z","steps":["trace[1577633381] 'read index received' (duration: 175.73035ms)","trace[1577633381] 'applied index is now lower than readState.Index' (duration: 155.465µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:35:14.290379Z","caller":"traceutil/trace.go:171","msg":"trace[1865890619] transaction","detail":"{read_only:false; response_revision:1168; number_of_response:1; }","duration":"316.431652ms","start":"2026-09-18T15:35:13.973936Z","end":"2026-09-18T15:35:14.290368Z","steps":["trace[1865890619] 'process raft request' (duration: 316.223815ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:35:14.290461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:35:13.973914Z","time spent":"316.488813ms","remote":"127.0.0.1:33388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":785,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-18T15:35:14.29083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.444662ms","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-18T15:35:14.290868Z","caller":"traceutil/trace.go:171","msg":"trace[376311296] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1168; }","duration":"176.510813ms","start":"2026-09-18T15:35:14.114347Z","end":"2026-09-18T15:35:14.290858Z","steps":["trace[376311296] 'agreement among raft nodes before linearized reading' (duration: 176.364429ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:35:14.290928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.151736ms","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-18T15:35:14.291002Z","caller":"traceutil/trace.go:171","msg":"trace[1941417921] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1168; }","duration":"163.253009ms","start":"2026-09-18T15:35:14.127729Z","end":"2026-09-18T15:35:14.290982Z","steps":["trace[1941417921] 'agreement among raft nodes before linearized reading' (duration: 163.027212ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:35:14.422424Z","caller":"traceutil/trace.go:171","msg":"trace[655891539] transaction","detail":"{read_only:false; response_revision:1173; number_of_response:1; }","duration":"100.199797ms","start":"2026-09-18T15:35:14.322206Z","end":"2026-09-18T15:35:14.422406Z","steps":["trace[655891539] 'process raft request' (duration: 100.113534ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:35:15.224884Z","caller":"traceutil/trace.go:171","msg":"trace[423752092] transaction","detail":"{read_only:false; response_revision:1182; number_of_response:1; }","duration":"139.334989ms","start":"2026-09-18T15:35:15.085516Z","end":"2026-09-18T15:35:15.224851Z","steps":["trace[423752092] 'process raft request' (duration: 58.645829ms)","trace[423752092] 'compare' (duration: 80.515275ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:35:15.235444Z","caller":"traceutil/trace.go:171","msg":"trace[18439728] transaction","detail":"{read_only:false; response_revision:1183; number_of_response:1; }","duration":"114.508133ms","start":"2026-09-18T15:35:15.120905Z","end":"2026-09-18T15:35:15.235413Z","steps":["trace[18439728] 'process raft request' (duration: 114.386169ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:35:15.511701Z","caller":"traceutil/trace.go:171","msg":"trace[1328150663] transaction","detail":"{read_only:false; response_revision:1190; number_of_response:1; }","duration":"236.873705ms","start":"2026-09-18T15:35:15.274786Z","end":"2026-09-18T15:35:15.51166Z","steps":["trace[1328150663] 'process raft request' (duration: 163.727395ms)","trace[1328150663] 'compare' (duration: 72.982985ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:35:15.51848Z","caller":"traceutil/trace.go:171","msg":"trace[1658655345] transaction","detail":"{read_only:false; response_revision:1192; number_of_response:1; }","duration":"230.339006ms","start":"2026-09-18T15:35:15.288118Z","end":"2026-09-18T15:35:15.518457Z","steps":["trace[1658655345] 'process raft request' (duration: 230.229993ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:35:15.518675Z","caller":"traceutil/trace.go:171","msg":"trace[719841472] linearizableReadLoop","detail":"{readStateIndex:1235; appliedIndex:1233; }","duration":"232.476351ms","start":"2026-09-18T15:35:15.286176Z","end":"2026-09-18T15:35:15.518652Z","steps":["trace[719841472] 'read index received' (duration: 152.268554ms)","trace[719841472] 'applied index is now lower than readState.Index' (duration: 80.206167ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:35:15.518847Z","caller":"traceutil/trace.go:171","msg":"trace[810510262] transaction","detail":"{read_only:false; response_revision:1191; number_of_response:1; }","duration":"233.308675ms","start":"2026-09-18T15:35:15.285526Z","end":"2026-09-18T15:35:15.518835Z","steps":["trace[810510262] 'process raft request' (duration: 232.730688ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:35:15.519345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.146161ms","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:534"} {"level":"info","ts":"2026-09-18T15:35:15.519448Z","caller":"traceutil/trace.go:171","msg":"trace[622001640] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1193; }","duration":"233.283726ms","start":"2026-09-18T15:35:15.286137Z","end":"2026-09-18T15:35:15.519421Z","steps":["trace[622001640] 'agreement among raft nodes before linearized reading' (duration: 232.920544ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:35:15.518959Z","caller":"traceutil/trace.go:171","msg":"trace[285733712] transaction","detail":"{read_only:false; response_revision:1193; number_of_response:1; }","duration":"230.609033ms","start":"2026-09-18T15:35:15.288336Z","end":"2026-09-18T15:35:15.518945Z","steps":["trace[285733712] 'process raft request' (duration: 230.076538ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:39:07.569935Z","caller":"traceutil/trace.go:171","msg":"trace[1735589983] transaction","detail":"{read_only:false; response_revision:2380; number_of_response:1; }","duration":"104.506152ms","start":"2026-09-18T15:39:07.465394Z","end":"2026-09-18T15:39:07.5699Z","steps":["trace[1735589983] 'process raft request' (duration: 104.222334ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:39:46.730822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.777315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T15:39:46.730955Z","caller":"traceutil/trace.go:171","msg":"trace[978242669] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2596; }","duration":"256.97219ms","start":"2026-09-18T15:39:46.473955Z","end":"2026-09-18T15:39:46.730928Z","steps":["trace[978242669] 'range keys from in-memory index tree' (duration: 255.96859ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:39:46.731176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.189483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-18T15:39:46.731266Z","caller":"traceutil/trace.go:171","msg":"trace[338843979] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2596; }","duration":"239.402611ms","start":"2026-09-18T15:39:46.491846Z","end":"2026-09-18T15:39:46.731248Z","steps":["trace[338843979] 'range keys from in-memory index tree' (duration: 238.946866ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:39:46.758458Z","caller":"traceutil/trace.go:171","msg":"trace[1305746931] transaction","detail":"{read_only:false; response_revision:2597; number_of_response:1; }","duration":"134.519206ms","start":"2026-09-18T15:39:46.623895Z","end":"2026-09-18T15:39:46.758414Z","steps":["trace[1305746931] 'process raft request' (duration: 134.32129ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:40:00.220075Z","caller":"traceutil/trace.go:171","msg":"trace[634093899] transaction","detail":"{read_only:false; response_revision:2674; number_of_response:1; }","duration":"158.638838ms","start":"2026-09-18T15:40:00.061411Z","end":"2026-09-18T15:40:00.22005Z","steps":["trace[634093899] 'process raft request' (duration: 71.285162ms)","trace[634093899] 'compare' (duration: 87.055847ms)"],"step_count":2}