{"level":"warn","ts":"2026-05-09T21:44:32.383273Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-09T21:44:32.383368Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.77: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.77:2380","--initial-cluster=instance=https://199.204.45.77:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.77:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.77: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-05-09T21:44:32.383434Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-09T21:44:32.383471Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.77:2380"]} {"level":"info","ts":"2026-05-09T21:44:32.383493Z","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-05-09T21:44:32.384121Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.77:2379"]} {"level":"info","ts":"2026-05-09T21:44:32.384191Z","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.77:2380"],"listen-peer-urls":["https://199.204.45.77:2380"],"advertise-client-urls":["https://199.204.45.77:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.77:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.77: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-05-09T21:44:32.484399Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"99.952016ms"} {"level":"info","ts":"2026-05-09T21:44:32.629534Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9133559b3198d4ec","cluster-id":"60f1aee33b0786f5"} {"level":"info","ts":"2026-05-09T21:44:32.629712Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec switched to configuration voters=()"} {"level":"info","ts":"2026-05-09T21:44:32.629759Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became follower at term 0"} {"level":"info","ts":"2026-05-09T21:44:32.62978Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9133559b3198d4ec [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-09T21:44:32.629797Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became follower at term 1"} {"level":"info","ts":"2026-05-09T21:44:32.629868Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec switched to configuration voters=(10462800484352185580)"} {"level":"warn","ts":"2026-05-09T21:44:32.640844Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-09T21:44:32.645486Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-09T21:44:32.650622Z","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-05-09T21:44:32.65617Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9133559b3198d4ec","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-09T21:44:32.661394Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9133559b3198d4ec","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-09T21:44:32.661442Z","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-05-09T21:44:32.661624Z","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-05-09T21:44:32.661663Z","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-05-09T21:44:32.668765Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec switched to configuration voters=(10462800484352185580)"} {"level":"info","ts":"2026-05-09T21:44:32.668922Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"60f1aee33b0786f5","local-member-id":"9133559b3198d4ec","added-peer-id":"9133559b3198d4ec","added-peer-peer-urls":["https://199.204.45.77:2380"]} {"level":"info","ts":"2026-05-09T21:44:32.676259Z","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-05-09T21:44:32.676476Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.77:2380"} {"level":"info","ts":"2026-05-09T21:44:32.676535Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.77:2380"} {"level":"info","ts":"2026-05-09T21:44:32.676753Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9133559b3198d4ec","initial-advertise-peer-urls":["https://199.204.45.77:2380"],"listen-peer-urls":["https://199.204.45.77:2380"],"advertise-client-urls":["https://199.204.45.77:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.77:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-09T21:44:32.676803Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-09T21:44:32.931112Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec is starting a new election at term 1"} {"level":"info","ts":"2026-05-09T21:44:32.93122Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became pre-candidate at term 1"} {"level":"info","ts":"2026-05-09T21:44:32.931293Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec received MsgPreVoteResp from 9133559b3198d4ec at term 1"} {"level":"info","ts":"2026-05-09T21:44:32.931324Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became candidate at term 2"} {"level":"info","ts":"2026-05-09T21:44:32.931339Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec received MsgVoteResp from 9133559b3198d4ec at term 2"} {"level":"info","ts":"2026-05-09T21:44:32.931407Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became leader at term 2"} {"level":"info","ts":"2026-05-09T21:44:32.931429Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9133559b3198d4ec elected leader 9133559b3198d4ec at term 2"} {"level":"info","ts":"2026-05-09T21:44:32.934117Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9133559b3198d4ec","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.77:2379]}","request-path":"/0/members/9133559b3198d4ec/attributes","cluster-id":"60f1aee33b0786f5","publish-timeout":"7s"} {"level":"info","ts":"2026-05-09T21:44:32.934344Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-09T21:44:32.934509Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T21:44:32.934585Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-09T21:44:32.934681Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-09T21:44:32.93513Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-09T21:44:32.937319Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"60f1aee33b0786f5","local-member-id":"9133559b3198d4ec","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T21:44:32.937416Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T21:44:32.937448Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T21:44:32.937865Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.77:2379"} {"level":"info","ts":"2026-05-09T21:44:32.93978Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-09T21:44:38.084594Z","caller":"traceutil/trace.go:171","msg":"trace[985612182] transaction","detail":"{read_only:false; response_revision:251; number_of_response:1; }","duration":"340.390295ms","start":"2026-05-09T21:44:37.744186Z","end":"2026-05-09T21:44:38.084576Z","steps":["trace[985612182] 'process raft request' (duration: 340.269951ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:44:38.085083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:44:37.744146Z","time spent":"340.526918ms","remote":"127.0.0.1:35946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4002,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T21:44:38.301747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.706118ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-09T21:44:38.301877Z","caller":"traceutil/trace.go:171","msg":"trace[1744109671] linearizableReadLoop","detail":"{readStateIndex:258; appliedIndex:257; }","duration":"138.669089ms","start":"2026-05-09T21:44:38.163191Z","end":"2026-05-09T21:44:38.30186Z","steps":["trace[1744109671] 'read index received' (duration: 24.57339ms)","trace[1744109671] 'applied index is now lower than readState.Index' (duration: 114.094389ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:44:38.301907Z","caller":"traceutil/trace.go:171","msg":"trace[1685485253] transaction","detail":"{read_only:false; response_revision:252; number_of_response:1; }","duration":"208.860704ms","start":"2026-05-09T21:44:38.093014Z","end":"2026-05-09T21:44:38.301874Z","steps":["trace[1685485253] 'process raft request' (duration: 94.771315ms)","trace[1685485253] 'compare' (duration: 113.563433ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T21:44:38.301971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.785632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T21:44:38.302068Z","caller":"traceutil/trace.go:171","msg":"trace[1091808910] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:252; }","duration":"138.929447ms","start":"2026-05-09T21:44:38.163128Z","end":"2026-05-09T21:44:38.302057Z","steps":["trace[1091808910] 'agreement among raft nodes before linearized reading' (duration: 138.789562ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:45:07.503276Z","caller":"traceutil/trace.go:171","msg":"trace[1579129246] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"105.145346ms","start":"2026-05-09T21:45:07.398095Z","end":"2026-05-09T21:45:07.503241Z","steps":["trace[1579129246] 'process raft request' (duration: 104.786746ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:45:08.177157Z","caller":"traceutil/trace.go:171","msg":"trace[1501137271] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"106.811365ms","start":"2026-05-09T21:45:08.070328Z","end":"2026-05-09T21:45:08.177139Z","steps":["trace[1501137271] 'process raft request' (duration: 90.266078ms)","trace[1501137271] 'compare' (duration: 15.993371ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:45:12.788957Z","caller":"traceutil/trace.go:171","msg":"trace[2104841512] linearizableReadLoop","detail":"{readStateIndex:682; appliedIndex:681; }","duration":"162.220999ms","start":"2026-05-09T21:45:12.626708Z","end":"2026-05-09T21:45:12.788929Z","steps":["trace[2104841512] 'read index received' (duration: 161.324984ms)","trace[2104841512] 'applied index is now lower than readState.Index' (duration: 894.725µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:45:12.789147Z","caller":"traceutil/trace.go:171","msg":"trace[16722643] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"165.288757ms","start":"2026-05-09T21:45:12.623847Z","end":"2026-05-09T21:45:12.789136Z","steps":["trace[16722643] 'process raft request' (duration: 164.485604ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:45:12.789556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.785205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T21:45:12.789751Z","caller":"traceutil/trace.go:171","msg":"trace[268360902] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:660; }","duration":"163.033213ms","start":"2026-05-09T21:45:12.626681Z","end":"2026-05-09T21:45:12.789714Z","steps":["trace[268360902] 'agreement among raft nodes before linearized reading' (duration: 162.715324ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:45:12.79734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.553869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T21:45:12.797335Z","caller":"traceutil/trace.go:171","msg":"trace[302274652] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"118.803419ms","start":"2026-05-09T21:45:12.678504Z","end":"2026-05-09T21:45:12.797307Z","steps":["trace[302274652] 'process raft request' (duration: 118.585993ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:45:12.797441Z","caller":"traceutil/trace.go:171","msg":"trace[1976848556] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:661; }","duration":"170.685933ms","start":"2026-05-09T21:45:12.626733Z","end":"2026-05-09T21:45:12.797419Z","steps":["trace[1976848556] 'agreement among raft nodes before linearized reading' (duration: 170.502657ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:45:12.797543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.260221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T21:45:12.797931Z","caller":"traceutil/trace.go:171","msg":"trace[1340788956] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:661; }","duration":"170.677443ms","start":"2026-05-09T21:45:12.627237Z","end":"2026-05-09T21:45:12.797915Z","steps":["trace[1340788956] 'agreement among raft nodes before linearized reading' (duration: 170.23822ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:45:15.196754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.964193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-09T21:45:15.196864Z","caller":"traceutil/trace.go:171","msg":"trace[1289830979] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:681; }","duration":"260.137288ms","start":"2026-05-09T21:45:14.936707Z","end":"2026-05-09T21:45:15.196845Z","steps":["trace[1289830979] 'range keys from in-memory index tree' (duration: 259.780108ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:45:15.196973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.325309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-ds9gj\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-05-09T21:45:15.197056Z","caller":"traceutil/trace.go:171","msg":"trace[885956503] range","detail":"{range_begin:/registry/pods/kube-system/cilium-ds9gj; range_end:; response_count:1; response_revision:681; }","duration":"231.476002ms","start":"2026-05-09T21:45:14.965557Z","end":"2026-05-09T21:45:15.197033Z","steps":["trace[885956503] 'range keys from in-memory index tree' (duration: 231.042231ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:45:15.198072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.511842ms","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-05-09T21:45:15.198133Z","caller":"traceutil/trace.go:171","msg":"trace[1275790253] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:681; }","duration":"306.578394ms","start":"2026-05-09T21:45:14.891542Z","end":"2026-05-09T21:45:15.19812Z","steps":["trace[1275790253] 'range keys from in-memory index tree' (duration: 306.351208ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:45:15.198166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:45:14.891487Z","time spent":"306.670436ms","remote":"127.0.0.1:36050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-09T21:45:15.34897Z","caller":"traceutil/trace.go:171","msg":"trace[1429550171] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"146.429185ms","start":"2026-05-09T21:45:15.202517Z","end":"2026-05-09T21:45:15.348946Z","steps":["trace[1429550171] 'process raft request' (duration: 146.2605ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:45:15.388403Z","caller":"traceutil/trace.go:171","msg":"trace[1911812001] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"184.474999ms","start":"2026-05-09T21:45:15.203894Z","end":"2026-05-09T21:45:15.388369Z","steps":["trace[1911812001] 'process raft request' (duration: 184.168131ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:45:15.389173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.511998ms","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-05-09T21:45:15.389332Z","caller":"traceutil/trace.go:171","msg":"trace[550178314] linearizableReadLoop","detail":"{readStateIndex:706; appliedIndex:703; }","duration":"169.084376ms","start":"2026-05-09T21:45:15.219641Z","end":"2026-05-09T21:45:15.388726Z","steps":["trace[550178314] 'read index received' (duration: 129.24749ms)","trace[550178314] 'applied index is now lower than readState.Index' (duration: 39.835996ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:45:15.389407Z","caller":"traceutil/trace.go:171","msg":"trace[25138651] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"174.841852ms","start":"2026-05-09T21:45:15.214102Z","end":"2026-05-09T21:45:15.388944Z","steps":["trace[25138651] 'process raft request' (duration: 174.170773ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:45:15.389609Z","caller":"traceutil/trace.go:171","msg":"trace[418747761] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:685; }","duration":"169.614981ms","start":"2026-05-09T21:45:15.219629Z","end":"2026-05-09T21:45:15.389244Z","steps":["trace[418747761] 'agreement among raft nodes before linearized reading' (duration: 169.409555ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:46:13.316317Z","caller":"traceutil/trace.go:171","msg":"trace[1899348831] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"156.915573ms","start":"2026-05-09T21:46:13.15937Z","end":"2026-05-09T21:46:13.316285Z","steps":["trace[1899348831] 'process raft request' (duration: 156.697937ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:46:38.695136Z","caller":"traceutil/trace.go:171","msg":"trace[711970400] transaction","detail":"{read_only:false; response_revision:1117; number_of_response:1; }","duration":"207.864127ms","start":"2026-05-09T21:46:38.487243Z","end":"2026-05-09T21:46:38.695107Z","steps":["trace[711970400] 'process raft request' (duration: 207.695482ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:46:39.583064Z","caller":"traceutil/trace.go:171","msg":"trace[942772102] transaction","detail":"{read_only:false; response_revision:1118; number_of_response:1; }","duration":"123.797713ms","start":"2026-05-09T21:46:39.459237Z","end":"2026-05-09T21:46:39.583034Z","steps":["trace[942772102] 'process raft request' (duration: 123.545446ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:10.305043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.624841ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-09T21:47:10.305161Z","caller":"traceutil/trace.go:171","msg":"trace[1755060007] linearizableReadLoop","detail":"{readStateIndex:1343; appliedIndex:1342; }","duration":"390.381728ms","start":"2026-05-09T21:47:09.914765Z","end":"2026-05-09T21:47:10.305147Z","steps":["trace[1755060007] 'read index received' (duration: 184.461369ms)","trace[1755060007] 'applied index is now lower than readState.Index' (duration: 205.919369ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T21:47:10.305296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.524153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T21:47:10.305319Z","caller":"traceutil/trace.go:171","msg":"trace[232480175] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1292; }","duration":"390.578284ms","start":"2026-05-09T21:47:09.914733Z","end":"2026-05-09T21:47:10.305311Z","steps":["trace[232480175] 'agreement among raft nodes before linearized reading' (duration: 390.488061ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:10.305347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:09.914723Z","time spent":"390.616575ms","remote":"127.0.0.1:36050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-09T21:47:10.305458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.07989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T21:47:10.305681Z","caller":"traceutil/trace.go:171","msg":"trace[1919877148] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1292; }","duration":"346.376779ms","start":"2026-05-09T21:47:09.959274Z","end":"2026-05-09T21:47:10.305651Z","steps":["trace[1919877148] 'agreement among raft nodes before linearized reading' (duration: 346.045529ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:10.305751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:09.959247Z","time spent":"346.489883ms","remote":"127.0.0.1:58066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-05-09T21:47:10.305806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.589324ms","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-05-09T21:47:10.305842Z","caller":"traceutil/trace.go:171","msg":"trace[1897812083] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1292; }","duration":"152.681947ms","start":"2026-05-09T21:47:10.153152Z","end":"2026-05-09T21:47:10.305834Z","steps":["trace[1897812083] 'agreement among raft nodes before linearized reading' (duration: 152.574854ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:47:10.305664Z","caller":"traceutil/trace.go:171","msg":"trace[924640533] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"392.91414ms","start":"2026-05-09T21:47:09.912245Z","end":"2026-05-09T21:47:10.305159Z","steps":["trace[924640533] 'process raft request' (duration: 187.097935ms)","trace[924640533] 'compare' (duration: 205.472006ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T21:47:10.306124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.35902ms","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-05-09T21:47:10.306148Z","caller":"traceutil/trace.go:171","msg":"trace[1086135655] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1292; }","duration":"213.430613ms","start":"2026-05-09T21:47:10.092713Z","end":"2026-05-09T21:47:10.306144Z","steps":["trace[1086135655] 'agreement among raft nodes before linearized reading' (duration: 213.376241ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:10.306233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.849265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-09T21:47:10.306193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:09.912216Z","time spent":"393.838347ms","remote":"127.0.0.1:35842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":836,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T21:47:10.306259Z","caller":"traceutil/trace.go:171","msg":"trace[175755986] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1292; }","duration":"188.914457ms","start":"2026-05-09T21:47:10.117338Z","end":"2026-05-09T21:47:10.306252Z","steps":["trace[175755986] 'agreement among raft nodes before linearized reading' (duration: 188.867276ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:47:10.48296Z","caller":"traceutil/trace.go:171","msg":"trace[1969552466] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"170.940645ms","start":"2026-05-09T21:47:10.311988Z","end":"2026-05-09T21:47:10.482929Z","steps":["trace[1969552466] 'process raft request' (duration: 136.507087ms)","trace[1969552466] 'compare' (duration: 34.293434ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:47:10.503317Z","caller":"traceutil/trace.go:171","msg":"trace[900371950] linearizableReadLoop","detail":"{readStateIndex:1348; appliedIndex:1343; }","duration":"108.145041ms","start":"2026-05-09T21:47:10.395132Z","end":"2026-05-09T21:47:10.503277Z","steps":["trace[900371950] 'read index received' (duration: 53.447838ms)","trace[900371950] 'applied index is now lower than readState.Index' (duration: 54.695123ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T21:47:10.503542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.382648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T21:47:10.5036Z","caller":"traceutil/trace.go:171","msg":"trace[480365776] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1297; }","duration":"108.508153ms","start":"2026-05-09T21:47:10.395076Z","end":"2026-05-09T21:47:10.503584Z","steps":["trace[480365776] 'agreement among raft nodes before linearized reading' (duration: 108.348978ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:47:10.504203Z","caller":"traceutil/trace.go:171","msg":"trace[1765784196] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"191.906191ms","start":"2026-05-09T21:47:10.312283Z","end":"2026-05-09T21:47:10.504189Z","steps":["trace[1765784196] 'process raft request' (duration: 190.565083ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:47:10.504414Z","caller":"traceutil/trace.go:171","msg":"trace[848107595] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"192.045865ms","start":"2026-05-09T21:47:10.312353Z","end":"2026-05-09T21:47:10.504399Z","steps":["trace[848107595] 'process raft request' (duration: 190.698947ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:47:10.504584Z","caller":"traceutil/trace.go:171","msg":"trace[955853850] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"192.21728ms","start":"2026-05-09T21:47:10.312353Z","end":"2026-05-09T21:47:10.50457Z","steps":["trace[955853850] 'process raft request' (duration: 190.755278ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:47:10.505109Z","caller":"traceutil/trace.go:171","msg":"trace[154481933] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"192.56486ms","start":"2026-05-09T21:47:10.312477Z","end":"2026-05-09T21:47:10.505042Z","steps":["trace[154481933] 'process raft request' (duration: 190.735958ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:47:11.327692Z","caller":"traceutil/trace.go:171","msg":"trace[1612825491] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"317.137398ms","start":"2026-05-09T21:47:11.010529Z","end":"2026-05-09T21:47:11.327666Z","steps":["trace[1612825491] 'process raft request' (duration: 316.937042ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:11.3279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:11.010503Z","time spent":"317.280011ms","remote":"127.0.0.1:36050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T21:47:25.542475Z","caller":"traceutil/trace.go:171","msg":"trace[1073223405] transaction","detail":"{read_only:false; response_revision:1392; number_of_response:1; }","duration":"186.413835ms","start":"2026-05-09T21:47:25.356027Z","end":"2026-05-09T21:47:25.542441Z","steps":["trace[1073223405] 'process raft request' (duration: 186.191458ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:47:25.604229Z","caller":"traceutil/trace.go:171","msg":"trace[1466688819] transaction","detail":"{read_only:false; response_revision:1393; number_of_response:1; }","duration":"205.518988ms","start":"2026-05-09T21:47:25.398678Z","end":"2026-05-09T21:47:25.604197Z","steps":["trace[1466688819] 'process raft request' (duration: 205.257221ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:25.781362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.700713ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T21:47:25.781493Z","caller":"traceutil/trace.go:171","msg":"trace[163480836] transaction","detail":"{read_only:false; response_revision:1394; number_of_response:1; }","duration":"171.703917ms","start":"2026-05-09T21:47:25.609767Z","end":"2026-05-09T21:47:25.781471Z","steps":["trace[163480836] 'process raft request' (duration: 28.758557ms)","trace[163480836] 'compare' (duration: 142.535509ms)"],"step_count":2}