{"level":"warn","ts":"2026-04-22T14:24:48.052548Z","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-22T14:24:48.053059Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.19: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.19:2380","--initial-cluster=instance=https://199.204.45.19:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.19:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.19: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-22T14:24:48.053379Z","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-22T14:24:48.053514Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.19:2380"]} {"level":"info","ts":"2026-04-22T14:24:48.053653Z","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-22T14:24:48.054615Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.19:2379"]} {"level":"info","ts":"2026-04-22T14:24:48.054811Z","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.19:2380"],"listen-peer-urls":["https://199.204.45.19:2380"],"advertise-client-urls":["https://199.204.45.19:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.19:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.19: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-22T14:24:48.060517Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.355867ms"} {"level":"info","ts":"2026-04-22T14:24:48.066399Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a847c2e9c7010db6","cluster-id":"2b11a68e27507b05"} {"level":"info","ts":"2026-04-22T14:24:48.066607Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 switched to configuration voters=()"} {"level":"info","ts":"2026-04-22T14:24:48.0667Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became follower at term 0"} {"level":"info","ts":"2026-04-22T14:24:48.066784Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a847c2e9c7010db6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-22T14:24:48.066843Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became follower at term 1"} {"level":"info","ts":"2026-04-22T14:24:48.066943Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 switched to configuration voters=(12125874831040253366)"} {"level":"warn","ts":"2026-04-22T14:24:48.072982Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-22T14:24:48.074511Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-22T14:24:48.075799Z","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-22T14:24:48.078794Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a847c2e9c7010db6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-22T14:24:48.079173Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a847c2e9c7010db6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-22T14:24:48.079329Z","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-22T14:24:48.079528Z","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-22T14:24:48.079543Z","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-22T14:24:48.081283Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 switched to configuration voters=(12125874831040253366)"} {"level":"info","ts":"2026-04-22T14:24:48.081679Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2b11a68e27507b05","local-member-id":"a847c2e9c7010db6","added-peer-id":"a847c2e9c7010db6","added-peer-peer-urls":["https://199.204.45.19:2380"]} {"level":"info","ts":"2026-04-22T14:24:48.093698Z","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-22T14:24:48.094002Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a847c2e9c7010db6","initial-advertise-peer-urls":["https://199.204.45.19:2380"],"listen-peer-urls":["https://199.204.45.19:2380"],"advertise-client-urls":["https://199.204.45.19:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.19:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-22T14:24:48.094033Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-22T14:24:48.094186Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.19:2380"} {"level":"info","ts":"2026-04-22T14:24:48.094202Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.19:2380"} {"level":"info","ts":"2026-04-22T14:24:48.467623Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 is starting a new election at term 1"} {"level":"info","ts":"2026-04-22T14:24:48.467759Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-22T14:24:48.467872Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 received MsgPreVoteResp from a847c2e9c7010db6 at term 1"} {"level":"info","ts":"2026-04-22T14:24:48.467906Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became candidate at term 2"} {"level":"info","ts":"2026-04-22T14:24:48.467919Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 received MsgVoteResp from a847c2e9c7010db6 at term 2"} {"level":"info","ts":"2026-04-22T14:24:48.467938Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became leader at term 2"} {"level":"info","ts":"2026-04-22T14:24:48.467963Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a847c2e9c7010db6 elected leader a847c2e9c7010db6 at term 2"} {"level":"info","ts":"2026-04-22T14:24:48.469134Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T14:24:48.469636Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a847c2e9c7010db6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.19:2379]}","request-path":"/0/members/a847c2e9c7010db6/attributes","cluster-id":"2b11a68e27507b05","publish-timeout":"7s"} {"level":"info","ts":"2026-04-22T14:24:48.469647Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-22T14:24:48.469778Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2b11a68e27507b05","local-member-id":"a847c2e9c7010db6","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T14:24:48.469728Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-22T14:24:48.469862Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T14:24:48.469898Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T14:24:48.470247Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-22T14:24:48.470318Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-22T14:24:48.47414Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-22T14:24:48.474358Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.19:2379"} {"level":"info","ts":"2026-04-22T14:24:51.081995Z","caller":"traceutil/trace.go:171","msg":"trace[618052217] linearizableReadLoop","detail":"{readStateIndex:82; appliedIndex:81; }","duration":"140.052347ms","start":"2026-04-22T14:24:50.941906Z","end":"2026-04-22T14:24:51.081958Z","steps":["trace[618052217] 'read index received' (duration: 133.987712ms)","trace[618052217] 'applied index is now lower than readState.Index' (duration: 6.063015ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T14:24:51.082102Z","caller":"traceutil/trace.go:171","msg":"trace[1954320553] transaction","detail":"{read_only:false; response_revision:78; number_of_response:1; }","duration":"141.878699ms","start":"2026-04-22T14:24:50.940195Z","end":"2026-04-22T14:24:51.082074Z","steps":["trace[1954320553] 'process raft request' (duration: 135.706803ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T14:24:51.082362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.379824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-22T14:24:51.082489Z","caller":"traceutil/trace.go:171","msg":"trace[684320377] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:0; response_revision:78; }","duration":"140.597621ms","start":"2026-04-22T14:24:50.941863Z","end":"2026-04-22T14:24:51.082461Z","steps":["trace[684320377] 'agreement among raft nodes before linearized reading' (duration: 140.244291ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T14:25:18.720488Z","caller":"traceutil/trace.go:171","msg":"trace[702133431] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"118.504339ms","start":"2026-04-22T14:25:18.601959Z","end":"2026-04-22T14:25:18.720463Z","steps":["trace[702133431] 'process raft request' (duration: 118.455598ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T14:25:18.720555Z","caller":"traceutil/trace.go:171","msg":"trace[1978849944] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"118.642592ms","start":"2026-04-22T14:25:18.601872Z","end":"2026-04-22T14:25:18.720515Z","steps":["trace[1978849944] 'process raft request' (duration: 112.760682ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T14:25:18.721518Z","caller":"traceutil/trace.go:171","msg":"trace[1400387808] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"109.307007ms","start":"2026-04-22T14:25:18.612185Z","end":"2026-04-22T14:25:18.721492Z","steps":["trace[1400387808] 'process raft request' (duration: 109.241605ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T14:25:18.721791Z","caller":"traceutil/trace.go:171","msg":"trace[754724405] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"111.055916ms","start":"2026-04-22T14:25:18.610709Z","end":"2026-04-22T14:25:18.721765Z","steps":["trace[754724405] 'process raft request' (duration: 110.613666ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T14:25:18.879198Z","caller":"traceutil/trace.go:171","msg":"trace[319175037] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"139.749077ms","start":"2026-04-22T14:25:18.739421Z","end":"2026-04-22T14:25:18.87917Z","steps":["trace[319175037] 'process raft request' (duration: 128.425018ms)","trace[319175037] 'compare' (duration: 11.009492ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T14:25:18.879244Z","caller":"traceutil/trace.go:171","msg":"trace[1297911924] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"137.292143ms","start":"2026-04-22T14:25:18.741928Z","end":"2026-04-22T14:25:18.87922Z","steps":["trace[1297911924] 'process raft request' (duration: 137.115709ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T14:25:18.87943Z","caller":"traceutil/trace.go:171","msg":"trace[1320108757] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"132.467647ms","start":"2026-04-22T14:25:18.746951Z","end":"2026-04-22T14:25:18.879419Z","steps":["trace[1320108757] 'process raft request' (duration: 132.213131ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T14:25:18.879498Z","caller":"traceutil/trace.go:171","msg":"trace[1396505863] transaction","detail":"{read_only:false; number_of_response:1; response_revision:520; }","duration":"124.803507ms","start":"2026-04-22T14:25:18.754682Z","end":"2026-04-22T14:25:18.879486Z","steps":["trace[1396505863] 'process raft request' (duration: 124.773526ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T14:25:18.87954Z","caller":"traceutil/trace.go:171","msg":"trace[592048969] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"127.438956ms","start":"2026-04-22T14:25:18.752092Z","end":"2026-04-22T14:25:18.879531Z","steps":["trace[592048969] 'process raft request' (duration: 127.18252ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T14:25:18.879594Z","caller":"traceutil/trace.go:171","msg":"trace[197015177] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"127.461456ms","start":"2026-04-22T14:25:18.752126Z","end":"2026-04-22T14:25:18.879587Z","steps":["trace[197015177] 'process raft request' (duration: 127.209741ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T14:25:18.880485Z","caller":"traceutil/trace.go:171","msg":"trace[795848487] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"118.05291ms","start":"2026-04-22T14:25:18.762416Z","end":"2026-04-22T14:25:18.880469Z","steps":["trace[795848487] 'process raft request' (duration: 117.923417ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T14:25:18.880627Z","caller":"traceutil/trace.go:171","msg":"trace[798590020] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"115.758299ms","start":"2026-04-22T14:25:18.764837Z","end":"2026-04-22T14:25:18.880595Z","steps":["trace[798590020] 'process raft request' (duration: 115.566895ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T14:25:18.880793Z","caller":"traceutil/trace.go:171","msg":"trace[757468010] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"107.61226ms","start":"2026-04-22T14:25:18.773137Z","end":"2026-04-22T14:25:18.880749Z","steps":["trace[757468010] 'process raft request' (duration: 107.372645ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T14:25:25.055267Z","caller":"traceutil/trace.go:171","msg":"trace[1939748691] linearizableReadLoop","detail":"{readStateIndex:628; appliedIndex:627; }","duration":"132.337914ms","start":"2026-04-22T14:25:24.922902Z","end":"2026-04-22T14:25:25.05524Z","steps":["trace[1939748691] 'read index received' (duration: 97.389614ms)","trace[1939748691] 'applied index is now lower than readState.Index' (duration: 34.94692ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T14:25:25.055345Z","caller":"traceutil/trace.go:171","msg":"trace[368944651] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"165.999446ms","start":"2026-04-22T14:25:24.889314Z","end":"2026-04-22T14:25:25.055314Z","steps":["trace[368944651] 'process raft request' (duration: 131.041066ms)","trace[368944651] 'compare' (duration: 34.750655ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T14:25:25.05558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.664722ms","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-04-22T14:25:25.055653Z","caller":"traceutil/trace.go:171","msg":"trace[197558317] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:607; }","duration":"132.749504ms","start":"2026-04-22T14:25:24.922887Z","end":"2026-04-22T14:25:25.055637Z","steps":["trace[197558317] 'agreement among raft nodes before linearized reading' (duration: 132.472678ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T14:25:26.097303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.331552ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T14:25:26.097579Z","caller":"traceutil/trace.go:171","msg":"trace[349099329] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"558.688742ms","start":"2026-04-22T14:25:25.538857Z","end":"2026-04-22T14:25:26.097546Z","steps":["trace[349099329] 'process raft request' (duration: 118.164983ms)","trace[349099329] 'compare' (duration: 439.146178ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T14:25:26.097703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T14:25:25.538839Z","time spent":"558.806854ms","remote":"127.0.0.1:54330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T14:25:26.097689Z","caller":"traceutil/trace.go:171","msg":"trace[1309160431] linearizableReadLoop","detail":"{readStateIndex:632; appliedIndex:630; }","duration":"344.678949ms","start":"2026-04-22T14:25:25.752927Z","end":"2026-04-22T14:25:26.097606Z","steps":["trace[1309160431] 'read index received' (duration: 242.036059ms)","trace[1309160431] 'applied index is now lower than readState.Index' (duration: 102.64202ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T14:25:26.097779Z","caller":"traceutil/trace.go:171","msg":"trace[933278429] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"428.635837ms","start":"2026-04-22T14:25:25.669105Z","end":"2026-04-22T14:25:26.097741Z","steps":["trace[933278429] 'process raft request' (duration: 428.388952ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T14:25:26.098118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T14:25:25.669077Z","time spent":"428.971115ms","remote":"127.0.0.1:54298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T14:25:26.098283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.355893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-lxzj9\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-22T14:25:26.09841Z","caller":"traceutil/trace.go:171","msg":"trace[1230120984] range","detail":"{range_begin:/registry/pods/kube-system/cilium-lxzj9; range_end:; response_count:1; response_revision:611; }","duration":"345.477436ms","start":"2026-04-22T14:25:25.752872Z","end":"2026-04-22T14:25:26.09835Z","steps":["trace[1230120984] 'agreement among raft nodes before linearized reading' (duration: 344.908914ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T14:25:26.098495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T14:25:25.75285Z","time spent":"345.631079ms","remote":"127.0.0.1:54236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-lxzj9\" "} {"level":"warn","ts":"2026-04-22T14:25:41.249877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.873074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T14:25:41.249976Z","caller":"traceutil/trace.go:171","msg":"trace[1678421659] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:771; }","duration":"157.023877ms","start":"2026-04-22T14:25:41.092931Z","end":"2026-04-22T14:25:41.249955Z","steps":["trace[1678421659] 'range keys from in-memory index tree' (duration: 156.72844ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T14:26:14.42599Z","caller":"traceutil/trace.go:171","msg":"trace[1382341432] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"110.51134ms","start":"2026-04-22T14:26:14.315455Z","end":"2026-04-22T14:26:14.425966Z","steps":["trace[1382341432] 'process raft request' (duration: 110.363827ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T14:26:14.680674Z","caller":"traceutil/trace.go:171","msg":"trace[86186327] linearizableReadLoop","detail":"{readStateIndex:1014; appliedIndex:1013; }","duration":"107.064011ms","start":"2026-04-22T14:26:14.573589Z","end":"2026-04-22T14:26:14.680653Z","steps":["trace[86186327] 'read index received' (duration: 27.196181ms)","trace[86186327] 'applied index is now lower than readState.Index' (duration: 79.86685ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T14:26:14.680888Z","caller":"traceutil/trace.go:171","msg":"trace[226445281] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"218.156494ms","start":"2026-04-22T14:26:14.462714Z","end":"2026-04-22T14:26:14.680871Z","steps":["trace[226445281] 'process raft request' (duration: 138.10892ms)","trace[226445281] 'compare' (duration: 79.745067ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T14:26:14.680896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.290845ms","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-04-22T14:26:14.680937Z","caller":"traceutil/trace.go:171","msg":"trace[2020514295] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:981; }","duration":"107.463021ms","start":"2026-04-22T14:26:14.573464Z","end":"2026-04-22T14:26:14.680927Z","steps":["trace[2020514295] 'agreement among raft nodes before linearized reading' (duration: 107.274576ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T14:26:35.46063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.94961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T14:26:35.461355Z","caller":"traceutil/trace.go:171","msg":"trace[1040164911] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1049; }","duration":"114.717287ms","start":"2026-04-22T14:26:35.346605Z","end":"2026-04-22T14:26:35.461322Z","steps":["trace[1040164911] 'count revisions from in-memory index tree' (duration: 113.871077ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T14:26:50.922526Z","caller":"traceutil/trace.go:171","msg":"trace[1284165111] transaction","detail":"{read_only:false; response_revision:1149; number_of_response:1; }","duration":"263.868463ms","start":"2026-04-22T14:26:50.658626Z","end":"2026-04-22T14:26:50.922494Z","steps":["trace[1284165111] 'process raft request' (duration: 263.544495ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T14:26:51.089989Z","caller":"traceutil/trace.go:171","msg":"trace[2090821769] transaction","detail":"{read_only:false; response_revision:1151; number_of_response:1; }","duration":"162.541586ms","start":"2026-04-22T14:26:50.927425Z","end":"2026-04-22T14:26:51.089966Z","steps":["trace[2090821769] 'process raft request' (duration: 162.478914ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T14:26:51.09009Z","caller":"traceutil/trace.go:171","msg":"trace[1179445225] transaction","detail":"{read_only:false; response_revision:1150; number_of_response:1; }","duration":"163.376484ms","start":"2026-04-22T14:26:50.926663Z","end":"2026-04-22T14:26:51.09004Z","steps":["trace[1179445225] 'process raft request' (duration: 161.030261ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T14:27:07.371721Z","caller":"traceutil/trace.go:171","msg":"trace[1173054681] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"128.776033ms","start":"2026-04-22T14:27:07.242919Z","end":"2026-04-22T14:27:07.371695Z","steps":["trace[1173054681] 'process raft request' (duration: 128.595209ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T14:27:07.372039Z","caller":"traceutil/trace.go:171","msg":"trace[1692786324] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"124.344481ms","start":"2026-04-22T14:27:07.247661Z","end":"2026-04-22T14:27:07.372006Z","steps":["trace[1692786324] 'process raft request' (duration: 123.983632ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T14:27:07.372168Z","caller":"traceutil/trace.go:171","msg":"trace[193407532] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"123.786379ms","start":"2026-04-22T14:27:07.248354Z","end":"2026-04-22T14:27:07.37214Z","steps":["trace[193407532] 'process raft request' (duration: 123.547303ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T14:27:07.520372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.359556ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-04-22T14:27:08.262027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.071949ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-22T14:27:08.262125Z","caller":"traceutil/trace.go:171","msg":"trace[1113436943] linearizableReadLoop","detail":"{readStateIndex:1277; appliedIndex:1276; }","duration":"167.583332ms","start":"2026-04-22T14:27:08.094526Z","end":"2026-04-22T14:27:08.262109Z","steps":["trace[1113436943] 'read index received' (duration: 51.284888ms)","trace[1113436943] 'applied index is now lower than readState.Index' (duration: 116.297294ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T14:27:08.26221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.687705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T14:27:08.262246Z","caller":"traceutil/trace.go:171","msg":"trace[1244670721] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1231; }","duration":"167.752946ms","start":"2026-04-22T14:27:08.094482Z","end":"2026-04-22T14:27:08.262235Z","steps":["trace[1244670721] 'agreement among raft nodes before linearized reading' (duration: 167.672624ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T14:27:08.262232Z","caller":"traceutil/trace.go:171","msg":"trace[287314859] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"199.01554ms","start":"2026-04-22T14:27:08.063181Z","end":"2026-04-22T14:27:08.262197Z","steps":["trace[287314859] 'process raft request' (duration: 82.711946ms)","trace[287314859] 'compare' (duration: 115.907495ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T14:27:09.318166Z","caller":"traceutil/trace.go:171","msg":"trace[373336377] linearizableReadLoop","detail":"{readStateIndex:1282; appliedIndex:1281; }","duration":"149.715018ms","start":"2026-04-22T14:27:09.168433Z","end":"2026-04-22T14:27:09.318148Z","steps":["trace[373336377] 'read index received' (duration: 149.601436ms)","trace[373336377] 'applied index is now lower than readState.Index' (duration: 112.722µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T14:27:09.318239Z","caller":"traceutil/trace.go:171","msg":"trace[694045868] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"325.803658ms","start":"2026-04-22T14:27:08.992397Z","end":"2026-04-22T14:27:09.318201Z","steps":["trace[694045868] 'process raft request' (duration: 325.637294ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T14:27:09.318365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.892952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"warn","ts":"2026-04-22T14:27:09.31837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T14:27:08.992377Z","time spent":"325.92284ms","remote":"127.0.0.1:54330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T14:27:09.318396Z","caller":"traceutil/trace.go:171","msg":"trace[226350703] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1236; }","duration":"150.003045ms","start":"2026-04-22T14:27:09.168384Z","end":"2026-04-22T14:27:09.318387Z","steps":["trace[226350703] 'agreement among raft nodes before linearized reading' (duration: 149.863512ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T14:27:09.318553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.09256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-22T14:27:09.318616Z","caller":"traceutil/trace.go:171","msg":"trace[1914791161] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1236; }","duration":"145.181033ms","start":"2026-04-22T14:27:09.173417Z","end":"2026-04-22T14:27:09.318599Z","steps":["trace[1914791161] 'agreement among raft nodes before linearized reading' (duration: 144.921527ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T14:27:09.484135Z","caller":"traceutil/trace.go:171","msg":"trace[1072130133] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"106.341742ms","start":"2026-04-22T14:27:09.377772Z","end":"2026-04-22T14:27:09.484114Z","steps":["trace[1072130133] 'process raft request' (duration: 100.720002ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T14:27:21.530185Z","caller":"traceutil/trace.go:171","msg":"trace[1460198557] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"161.56652ms","start":"2026-04-22T14:27:21.368585Z","end":"2026-04-22T14:27:21.530152Z","steps":["trace[1460198557] 'process raft request' (duration: 161.239401ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T14:27:21.530489Z","caller":"traceutil/trace.go:171","msg":"trace[1322062465] linearizableReadLoop","detail":"{readStateIndex:1365; appliedIndex:1364; }","duration":"109.373655ms","start":"2026-04-22T14:27:21.421079Z","end":"2026-04-22T14:27:21.530452Z","steps":["trace[1322062465] 'read index received' (duration: 108.619427ms)","trace[1322062465] 'applied index is now lower than readState.Index' (duration: 751.588µs)"],"step_count":2} {"level":"warn","ts":"2026-04-22T14:27:21.53086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.262961ms","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-04-22T14:27:21.530915Z","caller":"traceutil/trace.go:171","msg":"trace[542043364] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1317; }","duration":"109.362794ms","start":"2026-04-22T14:27:21.421538Z","end":"2026-04-22T14:27:21.530901Z","steps":["trace[542043364] 'agreement among raft nodes before linearized reading' (duration: 109.171799ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T14:27:21.530968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.873935ms","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-04-22T14:27:21.53103Z","caller":"traceutil/trace.go:171","msg":"trace[730609576] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1317; }","duration":"109.977958ms","start":"2026-04-22T14:27:21.42103Z","end":"2026-04-22T14:27:21.531008Z","steps":["trace[730609576] 'agreement among raft nodes before linearized reading' (duration: 109.643441ms)"],"step_count":1}