{"level":"warn","ts":"2026-04-28T05:01:43.712287Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-28T05:01:43.712385Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.23: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://199.204.45.23:2380","--initial-cluster=instance=https://199.204.45.23:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.23:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.23: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-04-28T05:01:43.712459Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-28T05:01:43.712468Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.23:2380"]} {"level":"info","ts":"2026-04-28T05:01:43.712486Z","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-04-28T05:01:43.713162Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"]} {"level":"info","ts":"2026-04-28T05:01:43.713622Z","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://199.204.45.23:2380"],"listen-peer-urls":["https://199.204.45.23:2380"],"advertise-client-urls":["https://199.204.45.23:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.23: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-04-28T05:01:43.7315Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"16.815635ms"} {"level":"info","ts":"2026-04-28T05:01:43.775242Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"51a5c416a4d98c86","cluster-id":"ade2045974c19907"} {"level":"info","ts":"2026-04-28T05:01:43.775347Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=()"} {"level":"info","ts":"2026-04-28T05:01:43.775369Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became follower at term 0"} {"level":"info","ts":"2026-04-28T05:01:43.77538Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 51a5c416a4d98c86 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-28T05:01:43.775392Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became follower at term 1"} {"level":"info","ts":"2026-04-28T05:01:43.775432Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=(5883324089763466374)"} {"level":"warn","ts":"2026-04-28T05:01:44.24506Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-28T05:01:44.247184Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-28T05:01:44.391738Z","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-04-28T05:01:44.39463Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"51a5c416a4d98c86","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-28T05:01:44.395876Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"51a5c416a4d98c86","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-28T05:01:44.396132Z","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-04-28T05:01:44.396181Z","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-04-28T05:01:44.39621Z","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-04-28T05:01:44.398585Z","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-04-28T05:01:44.398652Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=(5883324089763466374)"} {"level":"info","ts":"2026-04-28T05:01:44.398851Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ade2045974c19907","local-member-id":"51a5c416a4d98c86","added-peer-id":"51a5c416a4d98c86","added-peer-peer-urls":["https://199.204.45.23:2380"]} {"level":"info","ts":"2026-04-28T05:01:44.398903Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-28T05:01:44.398866Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"51a5c416a4d98c86","initial-advertise-peer-urls":["https://199.204.45.23:2380"],"listen-peer-urls":["https://199.204.45.23:2380"],"advertise-client-urls":["https://199.204.45.23:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-28T05:01:44.398948Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.23:2380"} {"level":"info","ts":"2026-04-28T05:01:44.398981Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.23:2380"} {"level":"info","ts":"2026-04-28T05:01:44.776226Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 is starting a new election at term 1"} {"level":"info","ts":"2026-04-28T05:01:44.776266Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-28T05:01:44.776286Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 received MsgPreVoteResp from 51a5c416a4d98c86 at term 1"} {"level":"info","ts":"2026-04-28T05:01:44.776296Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became candidate at term 2"} {"level":"info","ts":"2026-04-28T05:01:44.776302Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 received MsgVoteResp from 51a5c416a4d98c86 at term 2"} {"level":"info","ts":"2026-04-28T05:01:44.776314Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became leader at term 2"} {"level":"info","ts":"2026-04-28T05:01:44.776321Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 51a5c416a4d98c86 elected leader 51a5c416a4d98c86 at term 2"} {"level":"info","ts":"2026-04-28T05:01:44.782685Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"51a5c416a4d98c86","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.23:2379]}","request-path":"/0/members/51a5c416a4d98c86/attributes","cluster-id":"ade2045974c19907","publish-timeout":"7s"} {"level":"info","ts":"2026-04-28T05:01:44.782725Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T05:01:44.782828Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T05:01:44.782828Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T05:01:44.786482Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.23:2379"} {"level":"info","ts":"2026-04-28T05:01:44.786531Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ade2045974c19907","local-member-id":"51a5c416a4d98c86","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T05:01:44.782893Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-28T05:01:44.786766Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-28T05:01:44.786897Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T05:01:44.786936Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T05:01:44.78863Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-04-28T05:01:52.283076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.646476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-28T05:01:52.284143Z","caller":"traceutil/trace.go:171","msg":"trace[291557737] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:288; }","duration":"274.766214ms","start":"2026-04-28T05:01:52.009342Z","end":"2026-04-28T05:01:52.284108Z","steps":["trace[291557737] 'range keys from in-memory index tree' (duration: 273.473012ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T05:01:52.539899Z","caller":"traceutil/trace.go:171","msg":"trace[824055723] transaction","detail":"{read_only:false; response_revision:292; number_of_response:1; }","duration":"211.035357ms","start":"2026-04-28T05:01:52.328838Z","end":"2026-04-28T05:01:52.539873Z","steps":["trace[824055723] 'process raft request' (duration: 171.726165ms)","trace[824055723] 'compare' (duration: 39.201529ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T05:01:52.811976Z","caller":"traceutil/trace.go:171","msg":"trace[1465411487] transaction","detail":"{read_only:false; response_revision:295; number_of_response:1; }","duration":"176.109984ms","start":"2026-04-28T05:01:52.635825Z","end":"2026-04-28T05:01:52.811935Z","steps":["trace[1465411487] 'process raft request' (duration: 96.588477ms)","trace[1465411487] 'compare' (duration: 79.310382ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T05:01:52.845025Z","caller":"traceutil/trace.go:171","msg":"trace[51387019] transaction","detail":"{read_only:false; response_revision:296; number_of_response:1; }","duration":"185.25234ms","start":"2026-04-28T05:01:52.659728Z","end":"2026-04-28T05:01:52.84498Z","steps":["trace[51387019] 'process raft request' (duration: 184.981223ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T05:01:53.092118Z","caller":"traceutil/trace.go:171","msg":"trace[1537181707] transaction","detail":"{read_only:false; response_revision:298; number_of_response:1; }","duration":"220.169794ms","start":"2026-04-28T05:01:52.871914Z","end":"2026-04-28T05:01:53.092083Z","steps":["trace[1537181707] 'process raft request' (duration: 128.259882ms)","trace[1537181707] 'compare' (duration: 91.770569ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T05:01:53.595735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.157581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-28T05:01:53.595804Z","caller":"traceutil/trace.go:171","msg":"trace[1560987722] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:306; }","duration":"137.278854ms","start":"2026-04-28T05:01:53.458511Z","end":"2026-04-28T05:01:53.59579Z","steps":["trace[1560987722] 'range keys from in-memory index tree' (duration: 137.09307ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T05:01:53.727583Z","caller":"traceutil/trace.go:171","msg":"trace[2117187847] transaction","detail":"{read_only:false; response_revision:309; number_of_response:1; }","duration":"123.946895ms","start":"2026-04-28T05:01:53.603616Z","end":"2026-04-28T05:01:53.727563Z","steps":["trace[2117187847] 'process raft request' (duration: 123.881973ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T05:01:53.727604Z","caller":"traceutil/trace.go:171","msg":"trace[1107889731] transaction","detail":"{read_only:false; response_revision:308; number_of_response:1; }","duration":"124.371225ms","start":"2026-04-28T05:01:53.603198Z","end":"2026-04-28T05:01:53.727569Z","steps":["trace[1107889731] 'process raft request' (duration: 124.18995ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T05:01:53.727727Z","caller":"traceutil/trace.go:171","msg":"trace[1086833220] linearizableReadLoop","detail":"{readStateIndex:320; appliedIndex:316; }","duration":"119.832972ms","start":"2026-04-28T05:01:53.607887Z","end":"2026-04-28T05:01:53.72772Z","steps":["trace[1086833220] 'read index received' (duration: 2.172814ms)","trace[1086833220] 'applied index is now lower than readState.Index' (duration: 117.659528ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T05:01:53.727928Z","caller":"traceutil/trace.go:171","msg":"trace[1099707006] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"124.15808ms","start":"2026-04-28T05:01:53.603763Z","end":"2026-04-28T05:01:53.727921Z","steps":["trace[1099707006] 'process raft request' (duration: 123.76291ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T05:01:53.728074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.171882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-28T05:01:53.728105Z","caller":"traceutil/trace.go:171","msg":"trace[1887930580] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:310; }","duration":"120.238573ms","start":"2026-04-28T05:01:53.607857Z","end":"2026-04-28T05:01:53.728096Z","steps":["trace[1887930580] 'agreement among raft nodes before linearized reading' (duration: 120.172191ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T05:01:53.893655Z","caller":"traceutil/trace.go:171","msg":"trace[2142791943] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"157.440512ms","start":"2026-04-28T05:01:53.736199Z","end":"2026-04-28T05:01:53.893639Z","steps":["trace[2142791943] 'process raft request' (duration: 106.182634ms)","trace[2142791943] 'compare' (duration: 51.114115ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T05:01:53.893749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.68566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-04-28T05:01:53.893781Z","caller":"traceutil/trace.go:171","msg":"trace[84691728] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:313; }","duration":"134.758722ms","start":"2026-04-28T05:01:53.759014Z","end":"2026-04-28T05:01:53.893773Z","steps":["trace[84691728] 'agreement among raft nodes before linearized reading' (duration: 134.66957ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T05:01:53.893648Z","caller":"traceutil/trace.go:171","msg":"trace[946029269] linearizableReadLoop","detail":"{readStateIndex:323; appliedIndex:321; }","duration":"134.561517ms","start":"2026-04-28T05:01:53.759053Z","end":"2026-04-28T05:01:53.893615Z","steps":["trace[946029269] 'read index received' (duration: 45.696899ms)","trace[946029269] 'applied index is now lower than readState.Index' (duration: 88.863968ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T05:01:53.894073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.43041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4196"} {"level":"info","ts":"2026-04-28T05:01:53.894108Z","caller":"traceutil/trace.go:171","msg":"trace[1396494114] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:313; }","duration":"131.465581ms","start":"2026-04-28T05:01:53.762633Z","end":"2026-04-28T05:01:53.894098Z","steps":["trace[1396494114] 'agreement among raft nodes before linearized reading' (duration: 131.405319ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T05:01:53.894745Z","caller":"traceutil/trace.go:171","msg":"trace[342190896] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"157.504754ms","start":"2026-04-28T05:01:53.737231Z","end":"2026-04-28T05:01:53.894736Z","steps":["trace[342190896] 'process raft request' (duration: 156.349126ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T05:01:53.894985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.042495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4322"} {"level":"info","ts":"2026-04-28T05:01:53.895054Z","caller":"traceutil/trace.go:171","msg":"trace[1398610609] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:313; }","duration":"132.118626ms","start":"2026-04-28T05:01:53.762914Z","end":"2026-04-28T05:01:53.895033Z","steps":["trace[1398610609] 'agreement among raft nodes before linearized reading' (duration: 132.008124ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T05:01:53.895073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.297281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:1 size:536"} {"level":"info","ts":"2026-04-28T05:01:53.895218Z","caller":"traceutil/trace.go:171","msg":"trace[2140847610] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:1; response_revision:313; }","duration":"132.474065ms","start":"2026-04-28T05:01:53.762727Z","end":"2026-04-28T05:01:53.895201Z","steps":["trace[2140847610] 'agreement among raft nodes before linearized reading' (duration: 132.225389ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T05:01:53.895253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.377102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7294"} {"level":"info","ts":"2026-04-28T05:01:53.895362Z","caller":"traceutil/trace.go:171","msg":"trace[1363218481] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:313; }","duration":"132.483775ms","start":"2026-04-28T05:01:53.762824Z","end":"2026-04-28T05:01:53.895307Z","steps":["trace[1363218481] 'agreement among raft nodes before linearized reading' (duration: 132.173477ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T05:01:53.895463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.460064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4659"} {"level":"warn","ts":"2026-04-28T05:01:53.895447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.440935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5326"} {"level":"info","ts":"2026-04-28T05:01:53.895486Z","caller":"traceutil/trace.go:171","msg":"trace[1810558550] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:313; }","duration":"132.507236ms","start":"2026-04-28T05:01:53.762973Z","end":"2026-04-28T05:01:53.89548Z","steps":["trace[1810558550] 'agreement among raft nodes before linearized reading' (duration: 132.454785ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T05:01:53.895521Z","caller":"traceutil/trace.go:171","msg":"trace[205918562] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:313; }","duration":"132.534637ms","start":"2026-04-28T05:01:53.762967Z","end":"2026-04-28T05:01:53.895502Z","steps":["trace[205918562] 'agreement among raft nodes before linearized reading' (duration: 132.368723ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T05:01:53.895465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.494995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6961"} {"level":"info","ts":"2026-04-28T05:01:53.895622Z","caller":"traceutil/trace.go:171","msg":"trace[1354571625] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:313; }","duration":"132.634848ms","start":"2026-04-28T05:01:53.762935Z","end":"2026-04-28T05:01:53.89557Z","steps":["trace[1354571625] 'agreement among raft nodes before linearized reading' (duration: 132.452884ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T05:02:12.10293Z","caller":"traceutil/trace.go:171","msg":"trace[1119661797] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"241.236554ms","start":"2026-04-28T05:02:11.861661Z","end":"2026-04-28T05:02:12.102897Z","steps":["trace[1119661797] 'process raft request' (duration: 211.325455ms)","trace[1119661797] 'compare' (duration: 29.717515ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T05:02:12.106739Z","caller":"traceutil/trace.go:171","msg":"trace[1536327340] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"228.634583ms","start":"2026-04-28T05:02:11.878081Z","end":"2026-04-28T05:02:12.106716Z","steps":["trace[1536327340] 'process raft request' (duration: 228.391017ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T05:02:12.107163Z","caller":"traceutil/trace.go:171","msg":"trace[1491359745] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"217.822335ms","start":"2026-04-28T05:02:11.889242Z","end":"2026-04-28T05:02:12.107065Z","steps":["trace[1491359745] 'process raft request' (duration: 217.422805ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T05:02:13.595555Z","caller":"traceutil/trace.go:171","msg":"trace[1701328731] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"372.721615ms","start":"2026-04-28T05:02:13.222797Z","end":"2026-04-28T05:02:13.595519Z","steps":["trace[1701328731] 'process raft request' (duration: 372.300844ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T05:02:13.5959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.366407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoyextensionpolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:74769"} {"level":"info","ts":"2026-04-28T05:02:13.59601Z","caller":"traceutil/trace.go:171","msg":"trace[1047246916] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/envoyextensionpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:544; }","duration":"370.527271ms","start":"2026-04-28T05:02:13.225459Z","end":"2026-04-28T05:02:13.595986Z","steps":["trace[1047246916] 'agreement among raft nodes before linearized reading' (duration: 370.164502ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T05:02:13.596036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T05:02:13.222771Z","time spent":"372.853878ms","remote":"127.0.0.1:34140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":208470,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T05:02:13.596074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T05:02:13.225442Z","time spent":"370.607972ms","remote":"127.0.0.1:34140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":103,"response count":1,"response size":74792,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoyextensionpolicies.gateway.envoyproxy.io\" "} {"level":"info","ts":"2026-04-28T05:02:13.59621Z","caller":"traceutil/trace.go:171","msg":"trace[164969902] linearizableReadLoop","detail":"{readStateIndex:562; appliedIndex:561; }","duration":"369.930426ms","start":"2026-04-28T05:02:13.225504Z","end":"2026-04-28T05:02:13.595434Z","steps":["trace[164969902] 'read index received' (duration: 369.432363ms)","trace[164969902] 'applied index is now lower than readState.Index' (duration: 496.933µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T05:06:33.902569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.593824ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-28T05:06:34.247874Z","caller":"traceutil/trace.go:171","msg":"trace[969535140] linearizableReadLoop","detail":"{readStateIndex:1694; appliedIndex:1693; }","duration":"213.414678ms","start":"2026-04-28T05:06:34.034433Z","end":"2026-04-28T05:06:34.247848Z","steps":["trace[969535140] 'read index received' (duration: 213.242873ms)","trace[969535140] 'applied index is now lower than readState.Index' (duration: 170.594µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T05:06:34.248022Z","caller":"traceutil/trace.go:171","msg":"trace[1558414057] transaction","detail":"{read_only:false; response_revision:1620; number_of_response:1; }","duration":"301.589445ms","start":"2026-04-28T05:06:33.946421Z","end":"2026-04-28T05:06:34.24801Z","steps":["trace[1558414057] 'process raft request' (duration: 301.261558ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T05:06:34.248138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T05:06:33.946395Z","time spent":"301.661927ms","remote":"127.0.0.1:34336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T05:06:34.248289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.703335ms","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":"warn","ts":"2026-04-28T05:06:34.248243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.123993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T05:06:34.248368Z","caller":"traceutil/trace.go:171","msg":"trace[991764095] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1620; }","duration":"190.835109ms","start":"2026-04-28T05:06:34.057516Z","end":"2026-04-28T05:06:34.248351Z","steps":["trace[991764095] 'agreement among raft nodes before linearized reading' (duration: 190.613403ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T05:06:34.24842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.979231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-04-28T05:06:34.248466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.367608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T05:06:34.248532Z","caller":"traceutil/trace.go:171","msg":"trace[1497688735] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1620; }","duration":"214.126545ms","start":"2026-04-28T05:06:34.034388Z","end":"2026-04-28T05:06:34.248515Z","steps":["trace[1497688735] 'agreement among raft nodes before linearized reading' (duration: 213.806437ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T05:06:34.248566Z","caller":"traceutil/trace.go:171","msg":"trace[1766838139] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1620; }","duration":"181.501332ms","start":"2026-04-28T05:06:34.067036Z","end":"2026-04-28T05:06:34.248537Z","steps":["trace[1766838139] 'agreement among raft nodes before linearized reading' (duration: 181.266596ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T05:06:34.248437Z","caller":"traceutil/trace.go:171","msg":"trace[582015761] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1620; }","duration":"141.337948ms","start":"2026-04-28T05:06:34.107045Z","end":"2026-04-28T05:06:34.248383Z","steps":["trace[582015761] 'agreement among raft nodes before linearized reading' (duration: 141.095693ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T05:06:48.40717Z","caller":"traceutil/trace.go:171","msg":"trace[106617844] linearizableReadLoop","detail":"{readStateIndex:1796; appliedIndex:1796; }","duration":"115.42405ms","start":"2026-04-28T05:06:48.291722Z","end":"2026-04-28T05:06:48.407146Z","steps":["trace[106617844] 'read index received' (duration: 115.41456ms)","trace[106617844] 'applied index is now lower than readState.Index' (duration: 7.51µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T05:06:48.407367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.624355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T05:06:48.407447Z","caller":"traceutil/trace.go:171","msg":"trace[853179402] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1717; }","duration":"115.738618ms","start":"2026-04-28T05:06:48.291688Z","end":"2026-04-28T05:06:48.407426Z","steps":["trace[853179402] 'agreement among raft nodes before linearized reading' (duration: 115.588934ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T05:07:02.636544Z","caller":"traceutil/trace.go:171","msg":"trace[283597271] transaction","detail":"{read_only:false; response_revision:1764; number_of_response:1; }","duration":"168.254751ms","start":"2026-04-28T05:07:02.468242Z","end":"2026-04-28T05:07:02.636497Z","steps":["trace[283597271] 'process raft request' (duration: 167.950744ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T05:07:02.781309Z","caller":"traceutil/trace.go:171","msg":"trace[1420052396] transaction","detail":"{read_only:false; response_revision:1765; number_of_response:1; }","duration":"138.971951ms","start":"2026-04-28T05:07:02.642312Z","end":"2026-04-28T05:07:02.781284Z","steps":["trace[1420052396] 'process raft request' (duration: 133.537349ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T05:07:02.789629Z","caller":"traceutil/trace.go:171","msg":"trace[1351152015] transaction","detail":"{read_only:false; response_revision:1766; number_of_response:1; }","duration":"146.670828ms","start":"2026-04-28T05:07:02.642938Z","end":"2026-04-28T05:07:02.789608Z","steps":["trace[1351152015] 'process raft request' (duration: 146.527415ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T05:07:02.789785Z","caller":"traceutil/trace.go:171","msg":"trace[1354431948] transaction","detail":"{read_only:false; response_revision:1767; number_of_response:1; }","duration":"146.73287ms","start":"2026-04-28T05:07:02.643034Z","end":"2026-04-28T05:07:02.789767Z","steps":["trace[1354431948] 'process raft request' (duration: 146.534065ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T05:07:02.789945Z","caller":"traceutil/trace.go:171","msg":"trace[1748819031] transaction","detail":"{read_only:false; response_revision:1768; number_of_response:1; }","duration":"146.684489ms","start":"2026-04-28T05:07:02.643231Z","end":"2026-04-28T05:07:02.789916Z","steps":["trace[1748819031] 'process raft request' (duration: 146.474004ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T05:07:02.790023Z","caller":"traceutil/trace.go:171","msg":"trace[1140601037] transaction","detail":"{read_only:false; response_revision:1769; number_of_response:1; }","duration":"110.650364ms","start":"2026-04-28T05:07:02.679363Z","end":"2026-04-28T05:07:02.790013Z","steps":["trace[1140601037] 'process raft request' (duration: 110.49792ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T05:07:03.356408Z","caller":"traceutil/trace.go:171","msg":"trace[2048115448] transaction","detail":"{read_only:false; response_revision:1776; number_of_response:1; }","duration":"135.709922ms","start":"2026-04-28T05:07:03.220672Z","end":"2026-04-28T05:07:03.356382Z","steps":["trace[2048115448] 'process raft request' (duration: 135.560258ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T05:07:08.80047Z","caller":"traceutil/trace.go:171","msg":"trace[47737830] transaction","detail":"{read_only:false; response_revision:1828; number_of_response:1; }","duration":"145.501489ms","start":"2026-04-28T05:07:08.654945Z","end":"2026-04-28T05:07:08.800447Z","steps":["trace[47737830] 'process raft request' (duration: 145.353356ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T05:07:08.982355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.901451ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T05:07:08.982418Z","caller":"traceutil/trace.go:171","msg":"trace[31560222] linearizableReadLoop","detail":"{readStateIndex:1912; appliedIndex:1911; }","duration":"168.340653ms","start":"2026-04-28T05:07:08.814066Z","end":"2026-04-28T05:07:08.982407Z","steps":["trace[31560222] 'read index received' (duration: 38.33044ms)","trace[31560222] 'applied index is now lower than readState.Index' (duration: 130.009633ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T05:07:08.982488Z","caller":"traceutil/trace.go:171","msg":"trace[1993343979] transaction","detail":"{read_only:false; response_revision:1829; number_of_response:1; }","duration":"177.192118ms","start":"2026-04-28T05:07:08.805284Z","end":"2026-04-28T05:07:08.982476Z","steps":["trace[1993343979] 'process raft request' (duration: 47.113773ms)","trace[1993343979] 'compare' (duration: 129.840939ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T05:07:08.982605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.537578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-28T05:07:08.982626Z","caller":"traceutil/trace.go:171","msg":"trace[1594994559] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1829; }","duration":"168.584669ms","start":"2026-04-28T05:07:08.814037Z","end":"2026-04-28T05:07:08.982622Z","steps":["trace[1594994559] 'agreement among raft nodes before linearized reading' (duration: 168.520458ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T05:07:08.982656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.200391ms","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":"warn","ts":"2026-04-28T05:07:08.982712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.368466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-28T05:07:08.982741Z","caller":"traceutil/trace.go:171","msg":"trace[1855675220] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1829; }","duration":"164.423978ms","start":"2026-04-28T05:07:08.81831Z","end":"2026-04-28T05:07:08.982734Z","steps":["trace[1855675220] 'agreement among raft nodes before linearized reading' (duration: 164.354326ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T05:07:08.982714Z","caller":"traceutil/trace.go:171","msg":"trace[1416829212] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1829; }","duration":"166.305053ms","start":"2026-04-28T05:07:08.816397Z","end":"2026-04-28T05:07:08.982702Z","steps":["trace[1416829212] 'agreement among raft nodes before linearized reading' (duration: 166.15795ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T05:07:08.982782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.229342ms","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-04-28T05:07:08.982802Z","caller":"traceutil/trace.go:171","msg":"trace[566410204] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1829; }","duration":"166.287324ms","start":"2026-04-28T05:07:08.816509Z","end":"2026-04-28T05:07:08.982797Z","steps":["trace[566410204] 'agreement among raft nodes before linearized reading' (duration: 166.220632ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T05:07:09.31746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.6919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T05:07:09.317631Z","caller":"traceutil/trace.go:171","msg":"trace[190026592] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1833; }","duration":"219.867673ms","start":"2026-04-28T05:07:09.097701Z","end":"2026-04-28T05:07:09.317569Z","steps":["trace[190026592] 'count revisions from in-memory index tree' (duration: 219.609848ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T05:07:09.798405Z","caller":"traceutil/trace.go:171","msg":"trace[1581936000] transaction","detail":"{read_only:false; response_revision:1834; number_of_response:1; }","duration":"159.723284ms","start":"2026-04-28T05:07:09.638657Z","end":"2026-04-28T05:07:09.79838Z","steps":["trace[1581936000] 'process raft request' (duration: 159.58828ms)"],"step_count":1}