{"level":"warn","ts":"2026-06-13T22:46:13.891421Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-13T22:46:13.891534Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.248: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.248:2380","--initial-cluster=instance=https://199.204.45.248:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.248:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.248: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-06-13T22:46:13.891615Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-13T22:46:13.89163Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.248:2380"]} {"level":"info","ts":"2026-06-13T22:46:13.891656Z","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-06-13T22:46:13.892327Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"]} {"level":"info","ts":"2026-06-13T22:46:13.892402Z","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.248:2380"],"listen-peer-urls":["https://199.204.45.248:2380"],"advertise-client-urls":["https://199.204.45.248:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.248: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-06-13T22:46:13.896711Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.101772ms"} {"level":"info","ts":"2026-06-13T22:46:13.902298Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"39582465ceaa2f67","cluster-id":"87fd8ddedde3be45"} {"level":"info","ts":"2026-06-13T22:46:13.902399Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=()"} {"level":"info","ts":"2026-06-13T22:46:13.90243Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became follower at term 0"} {"level":"info","ts":"2026-06-13T22:46:13.902444Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 39582465ceaa2f67 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-13T22:46:13.902523Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became follower at term 1"} {"level":"info","ts":"2026-06-13T22:46:13.902593Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=(4132092677789986663)"} {"level":"warn","ts":"2026-06-13T22:46:13.908919Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-13T22:46:13.910267Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-13T22:46:13.911145Z","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-06-13T22:46:13.912791Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"39582465ceaa2f67","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-13T22:46:13.913097Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"39582465ceaa2f67","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-13T22:46:13.913446Z","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-06-13T22:46:13.913689Z","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-06-13T22:46:13.914384Z","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-06-13T22:46:13.915893Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=(4132092677789986663)"} {"level":"info","ts":"2026-06-13T22:46:13.916046Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"87fd8ddedde3be45","local-member-id":"39582465ceaa2f67","added-peer-id":"39582465ceaa2f67","added-peer-peer-urls":["https://199.204.45.248:2380"]} {"level":"info","ts":"2026-06-13T22:46:13.917278Z","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-06-13T22:46:13.91742Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.248:2380"} {"level":"info","ts":"2026-06-13T22:46:13.917685Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.248:2380"} {"level":"info","ts":"2026-06-13T22:46:13.918693Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"39582465ceaa2f67","initial-advertise-peer-urls":["https://199.204.45.248:2380"],"listen-peer-urls":["https://199.204.45.248:2380"],"advertise-client-urls":["https://199.204.45.248:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-13T22:46:13.918756Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-13T22:46:14.102854Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 is starting a new election at term 1"} {"level":"info","ts":"2026-06-13T22:46:14.103009Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-13T22:46:14.103045Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 received MsgPreVoteResp from 39582465ceaa2f67 at term 1"} {"level":"info","ts":"2026-06-13T22:46:14.103064Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became candidate at term 2"} {"level":"info","ts":"2026-06-13T22:46:14.103076Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 received MsgVoteResp from 39582465ceaa2f67 at term 2"} {"level":"info","ts":"2026-06-13T22:46:14.103093Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became leader at term 2"} {"level":"info","ts":"2026-06-13T22:46:14.103145Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 39582465ceaa2f67 elected leader 39582465ceaa2f67 at term 2"} {"level":"info","ts":"2026-06-13T22:46:14.104257Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-13T22:46:14.10605Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"39582465ceaa2f67","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.248:2379]}","request-path":"/0/members/39582465ceaa2f67/attributes","cluster-id":"87fd8ddedde3be45","publish-timeout":"7s"} {"level":"info","ts":"2026-06-13T22:46:14.106077Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-13T22:46:14.106191Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-13T22:46:14.106357Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-13T22:46:14.10639Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-13T22:46:14.106547Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"87fd8ddedde3be45","local-member-id":"39582465ceaa2f67","cluster-version":"3.5"} {"level":"info","ts":"2026-06-13T22:46:14.106647Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-13T22:46:14.106674Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-13T22:46:14.10821Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.248:2379"} {"level":"info","ts":"2026-06-13T22:46:14.109802Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-06-13T22:46:16.652855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.750889ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-06-13T22:46:16.652976Z","caller":"traceutil/trace.go:171","msg":"trace[637730871] linearizableReadLoop","detail":"{readStateIndex:76; appliedIndex:75; }","duration":"280.989634ms","start":"2026-06-13T22:46:16.371968Z","end":"2026-06-13T22:46:16.652958Z","steps":["trace[637730871] 'read index received' (duration: 120.61006ms)","trace[637730871] 'applied index is now lower than readState.Index' (duration: 160.378364ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:46:16.653319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.709833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-13T22:46:16.653367Z","caller":"traceutil/trace.go:171","msg":"trace[22351215] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:73; }","duration":"219.787624ms","start":"2026-06-13T22:46:16.433566Z","end":"2026-06-13T22:46:16.653353Z","steps":["trace[22351215] 'agreement among raft nodes before linearized reading' (duration: 219.650801ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:46:16.653361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.369512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-view\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-13T22:46:16.653307Z","caller":"traceutil/trace.go:171","msg":"trace[613057228] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"282.468326ms","start":"2026-06-13T22:46:16.370803Z","end":"2026-06-13T22:46:16.653272Z","steps":["trace[613057228] 'process raft request' (duration: 121.789886ms)","trace[613057228] 'compare' (duration: 159.610085ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:46:16.653474Z","caller":"traceutil/trace.go:171","msg":"trace[1314881165] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-view; range_end:; response_count:0; response_revision:73; }","duration":"281.547876ms","start":"2026-06-13T22:46:16.3719Z","end":"2026-06-13T22:46:16.653448Z","steps":["trace[1314881165] 'agreement among raft nodes before linearized reading' (duration: 281.223889ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:46:16.836141Z","caller":"traceutil/trace.go:171","msg":"trace[1540303786] transaction","detail":"{read_only:false; response_revision:74; number_of_response:1; }","duration":"172.991724ms","start":"2026-06-13T22:46:16.663126Z","end":"2026-06-13T22:46:16.836118Z","steps":["trace[1540303786] 'process raft request' (duration: 138.334614ms)","trace[1540303786] 'compare' (duration: 34.562798ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:46:50.05515Z","caller":"traceutil/trace.go:171","msg":"trace[247450396] linearizableReadLoop","detail":"{readStateIndex:500; appliedIndex:498; }","duration":"137.315461ms","start":"2026-06-13T22:46:49.917805Z","end":"2026-06-13T22:46:50.055121Z","steps":["trace[247450396] 'read index received' (duration: 10.821602ms)","trace[247450396] 'applied index is now lower than readState.Index' (duration: 126.492409ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:46:50.0553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.477715ms","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-06-13T22:46:50.055337Z","caller":"traceutil/trace.go:171","msg":"trace[778576129] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:484; }","duration":"137.578057ms","start":"2026-06-13T22:46:49.917747Z","end":"2026-06-13T22:46:50.055325Z","steps":["trace[778576129] 'agreement among raft nodes before linearized reading' (duration: 137.487465ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:46:50.055517Z","caller":"traceutil/trace.go:171","msg":"trace[754277145] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"127.541454ms","start":"2026-06-13T22:46:49.927945Z","end":"2026-06-13T22:46:50.055487Z","steps":["trace[754277145] 'process raft request' (duration: 127.096783ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:46:50.055192Z","caller":"traceutil/trace.go:171","msg":"trace[505006773] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"207.486618ms","start":"2026-06-13T22:46:49.847667Z","end":"2026-06-13T22:46:50.055154Z","steps":["trace[505006773] 'process raft request' (duration: 207.074228ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:46:52.201063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.408592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-13T22:46:52.201126Z","caller":"traceutil/trace.go:171","msg":"trace[1191665084] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:562; }","duration":"132.509904ms","start":"2026-06-13T22:46:52.068601Z","end":"2026-06-13T22:46:52.201111Z","steps":["trace[1191665084] 'range keys from in-memory index tree' (duration: 132.32442ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:46:52.201146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.104467ms","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-06-13T22:46:52.201206Z","caller":"traceutil/trace.go:171","msg":"trace[411698726] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:562; }","duration":"123.194449ms","start":"2026-06-13T22:46:52.077998Z","end":"2026-06-13T22:46:52.201193Z","steps":["trace[411698726] 'range keys from in-memory index tree' (duration: 122.987414ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:46:56.176516Z","caller":"traceutil/trace.go:171","msg":"trace[1904209114] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"161.000923ms","start":"2026-06-13T22:46:56.015493Z","end":"2026-06-13T22:46:56.176494Z","steps":["trace[1904209114] 'process raft request' (duration: 160.585203ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:46:56.735051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.70722ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-13T22:46:56.735222Z","caller":"traceutil/trace.go:171","msg":"trace[1430402163] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"502.797531ms","start":"2026-06-13T22:46:56.232405Z","end":"2026-06-13T22:46:56.735203Z","steps":["trace[1430402163] 'process raft request' (duration: 278.704271ms)","trace[1430402163] 'compare' (duration: 223.536785ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:46:56.735403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:46:56.232376Z","time spent":"502.988405ms","remote":"127.0.0.1:34842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T22:46:56.746238Z","caller":"traceutil/trace.go:171","msg":"trace[997446078] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:612; }","duration":"433.604887ms","start":"2026-06-13T22:46:56.312614Z","end":"2026-06-13T22:46:56.746219Z","steps":["trace[997446078] 'read index received' (duration: 198.897151ms)","trace[997446078] 'applied index is now lower than readState.Index' (duration: 234.706876ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:46:56.746317Z","caller":"traceutil/trace.go:171","msg":"trace[292324417] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"505.231749ms","start":"2026-06-13T22:46:56.241078Z","end":"2026-06-13T22:46:56.74631Z","steps":["trace[292324417] 'process raft request' (duration: 505.115156ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:46:56.746329Z","caller":"traceutil/trace.go:171","msg":"trace[556087126] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"511.551072ms","start":"2026-06-13T22:46:56.234751Z","end":"2026-06-13T22:46:56.746302Z","steps":["trace[556087126] 'process raft request' (duration: 511.346887ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:46:56.74638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:46:56.241055Z","time spent":"505.279911ms","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-13T22:46:56.746478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:46:56.234736Z","time spent":"511.655104ms","remote":"127.0.0.1:34842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-13T22:46:56.746492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.823692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T22:46:56.746661Z","caller":"traceutil/trace.go:171","msg":"trace[2125459722] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:591; }","duration":"434.058977ms","start":"2026-06-13T22:46:56.31258Z","end":"2026-06-13T22:46:56.746639Z","steps":["trace[2125459722] 'agreement among raft nodes before linearized reading' (duration: 433.75177ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:46:56.746729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:46:56.312564Z","time spent":"434.130899ms","remote":"127.0.0.1:34576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-13T22:46:56.748367Z","caller":"traceutil/trace.go:171","msg":"trace[1198012293] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"120.925264ms","start":"2026-06-13T22:46:56.627369Z","end":"2026-06-13T22:46:56.748294Z","steps":["trace[1198012293] 'process raft request' (duration: 120.096595ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:46:56.748573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.280585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-whn29\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-06-13T22:46:56.748612Z","caller":"traceutil/trace.go:171","msg":"trace[1662505793] range","detail":"{range_begin:/registry/pods/kube-system/cilium-whn29; range_end:; response_count:1; response_revision:592; }","duration":"332.353407ms","start":"2026-06-13T22:46:56.416247Z","end":"2026-06-13T22:46:56.748601Z","steps":["trace[1662505793] 'agreement among raft nodes before linearized reading' (duration: 332.245194ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:46:56.748632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:46:56.41623Z","time spent":"332.398078ms","remote":"127.0.0.1:34788","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-whn29\" "} {"level":"info","ts":"2026-06-13T22:47:40.296142Z","caller":"traceutil/trace.go:171","msg":"trace[1958227246] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"106.751776ms","start":"2026-06-13T22:47:40.189364Z","end":"2026-06-13T22:47:40.296116Z","steps":["trace[1958227246] 'process raft request' (duration: 106.572362ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:48:07.207617Z","caller":"traceutil/trace.go:171","msg":"trace[1814186145] linearizableReadLoop","detail":"{readStateIndex:1079; appliedIndex:1078; }","duration":"141.886525ms","start":"2026-06-13T22:48:07.065711Z","end":"2026-06-13T22:48:07.207598Z","steps":["trace[1814186145] 'read index received' (duration: 141.783732ms)","trace[1814186145] 'applied index is now lower than readState.Index' (duration: 102.003µs)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:48:07.207839Z","caller":"traceutil/trace.go:171","msg":"trace[1363791134] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"144.445325ms","start":"2026-06-13T22:48:07.063384Z","end":"2026-06-13T22:48:07.20783Z","steps":["trace[1363791134] 'process raft request' (duration: 144.120177ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:48:07.208049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.322055ms","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-06-13T22:48:07.208077Z","caller":"traceutil/trace.go:171","msg":"trace[865515506] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1038; }","duration":"142.389707ms","start":"2026-06-13T22:48:07.06568Z","end":"2026-06-13T22:48:07.20807Z","steps":["trace[865515506] 'agreement among raft nodes before linearized reading' (duration: 142.282094ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:48:07.827383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.107361ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-13T22:48:07.827505Z","caller":"traceutil/trace.go:171","msg":"trace[586355994] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"162.876001ms","start":"2026-06-13T22:48:07.664609Z","end":"2026-06-13T22:48:07.827485Z","steps":["trace[586355994] 'process raft request' (duration: 56.573535ms)","trace[586355994] 'compare' (duration: 106.000759ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:48:07.844448Z","caller":"traceutil/trace.go:171","msg":"trace[465905919] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"176.592042ms","start":"2026-06-13T22:48:07.667837Z","end":"2026-06-13T22:48:07.844429Z","steps":["trace[465905919] 'process raft request' (duration: 176.496639ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:48:07.991338Z","caller":"traceutil/trace.go:171","msg":"trace[380436931] linearizableReadLoop","detail":"{readStateIndex:1084; appliedIndex:1083; }","duration":"162.156513ms","start":"2026-06-13T22:48:07.829161Z","end":"2026-06-13T22:48:07.991317Z","steps":["trace[380436931] 'read index received' (duration: 15.332339ms)","trace[380436931] 'applied index is now lower than readState.Index' (duration: 146.823224ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:48:07.991467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.287957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-06-13T22:48:07.99149Z","caller":"traceutil/trace.go:171","msg":"trace[1316701961] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1042; }","duration":"162.353928ms","start":"2026-06-13T22:48:07.829129Z","end":"2026-06-13T22:48:07.991483Z","steps":["trace[1316701961] 'agreement among raft nodes before linearized reading' (duration: 162.261876ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:48:07.991637Z","caller":"traceutil/trace.go:171","msg":"trace[465405215] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"205.568362ms","start":"2026-06-13T22:48:07.786043Z","end":"2026-06-13T22:48:07.991611Z","steps":["trace[465405215] 'process raft request' (duration: 178.373805ms)","trace[465405215] 'compare' (duration: 26.805878ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:48:24.039418Z","caller":"traceutil/trace.go:171","msg":"trace[1682614365] linearizableReadLoop","detail":"{readStateIndex:1193; appliedIndex:1192; }","duration":"116.312978ms","start":"2026-06-13T22:48:23.923081Z","end":"2026-06-13T22:48:24.039394Z","steps":["trace[1682614365] 'read index received' (duration: 116.138274ms)","trace[1682614365] 'applied index is now lower than readState.Index' (duration: 173.874µs)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:48:24.039514Z","caller":"traceutil/trace.go:171","msg":"trace[1857612304] transaction","detail":"{read_only:false; response_revision:1145; number_of_response:1; }","duration":"122.854665ms","start":"2026-06-13T22:48:23.916639Z","end":"2026-06-13T22:48:24.039494Z","steps":["trace[1857612304] 'process raft request' (duration: 122.6283ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:48:24.039535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.438591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-13T22:48:24.039566Z","caller":"traceutil/trace.go:171","msg":"trace[1874829609] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1145; }","duration":"116.506003ms","start":"2026-06-13T22:48:23.923051Z","end":"2026-06-13T22:48:24.039557Z","steps":["trace[1874829609] 'agreement among raft nodes before linearized reading' (duration: 116.435161ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:48:42.262553Z","caller":"traceutil/trace.go:171","msg":"trace[323227321] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"154.568312ms","start":"2026-06-13T22:48:42.107948Z","end":"2026-06-13T22:48:42.262516Z","steps":["trace[323227321] 'process raft request' (duration: 154.289575ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:48:42.262784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.581242ms","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-06-13T22:48:42.262794Z","caller":"traceutil/trace.go:171","msg":"trace[1025578210] linearizableReadLoop","detail":"{readStateIndex:1290; appliedIndex:1289; }","duration":"142.222523ms","start":"2026-06-13T22:48:42.120172Z","end":"2026-06-13T22:48:42.262394Z","steps":["trace[1025578210] 'read index received' (duration: 141.971357ms)","trace[1025578210] 'applied index is now lower than readState.Index' (duration: 250.306µs)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:48:42.262873Z","caller":"traceutil/trace.go:171","msg":"trace[962840810] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1239; }","duration":"142.709096ms","start":"2026-06-13T22:48:42.120141Z","end":"2026-06-13T22:48:42.26285Z","steps":["trace[962840810] 'agreement among raft nodes before linearized reading' (duration: 142.433018ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:48:42.753599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.398869ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-13T22:48:42.753695Z","caller":"traceutil/trace.go:171","msg":"trace[1333206383] linearizableReadLoop","detail":"{readStateIndex:1293; appliedIndex:1292; }","duration":"156.828596ms","start":"2026-06-13T22:48:42.596849Z","end":"2026-06-13T22:48:42.753678Z","steps":["trace[1333206383] 'read index received' (duration: 4.078038ms)","trace[1333206383] 'applied index is now lower than readState.Index' (duration: 152.748908ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:48:42.753853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.991821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-13T22:48:42.753886Z","caller":"traceutil/trace.go:171","msg":"trace[2139653673] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1241; }","duration":"157.074072ms","start":"2026-06-13T22:48:42.596802Z","end":"2026-06-13T22:48:42.753876Z","steps":["trace[2139653673] 'agreement among raft nodes before linearized reading' (duration: 156.921629ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:48:42.900495Z","caller":"traceutil/trace.go:171","msg":"trace[1899958862] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"141.981018ms","start":"2026-06-13T22:48:42.758488Z","end":"2026-06-13T22:48:42.900469Z","steps":["trace[1899958862] 'process raft request' (duration: 141.787963ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:48:42.925673Z","caller":"traceutil/trace.go:171","msg":"trace[1268966735] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"166.648884ms","start":"2026-06-13T22:48:42.75899Z","end":"2026-06-13T22:48:42.925638Z","steps":["trace[1268966735] 'process raft request' (duration: 166.546291ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:48:43.513039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.000836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T22:48:43.513145Z","caller":"traceutil/trace.go:171","msg":"trace[1871666062] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1249; }","duration":"197.16031ms","start":"2026-06-13T22:48:43.31596Z","end":"2026-06-13T22:48:43.51312Z","steps":["trace[1871666062] 'range keys from in-memory index tree' (duration: 196.861113ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:48:53.079834Z","caller":"traceutil/trace.go:171","msg":"trace[396425379] linearizableReadLoop","detail":"{readStateIndex:1365; appliedIndex:1364; }","duration":"101.277675ms","start":"2026-06-13T22:48:52.97853Z","end":"2026-06-13T22:48:53.079808Z","steps":["trace[396425379] 'read index received' (duration: 101.137662ms)","trace[396425379] 'applied index is now lower than readState.Index' (duration: 139.133µs)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:48:53.079964Z","caller":"traceutil/trace.go:171","msg":"trace[1297483513] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"129.613309ms","start":"2026-06-13T22:48:52.950322Z","end":"2026-06-13T22:48:53.079936Z","steps":["trace[1297483513] 'process raft request' (duration: 129.317722ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:48:53.080523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.975701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-06-13T22:48:53.080607Z","caller":"traceutil/trace.go:171","msg":"trace[1985221343] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1311; }","duration":"102.141666ms","start":"2026-06-13T22:48:52.978443Z","end":"2026-06-13T22:48:53.080585Z","steps":["trace[1985221343] 'agreement among raft nodes before linearized reading' (duration: 101.49469ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:48:53.315554Z","caller":"traceutil/trace.go:171","msg":"trace[897367203] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"230.658459ms","start":"2026-06-13T22:48:53.084865Z","end":"2026-06-13T22:48:53.315524Z","steps":["trace[897367203] 'process raft request' (duration: 179.069284ms)","trace[897367203] 'compare' (duration: 51.215306ms)"],"step_count":2}