{"level":"warn","ts":"2026-07-23T03:19:55.408996Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-23T03:19:55.409099Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.138: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.138:2380","--initial-cluster=instance=https://199.204.45.138:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.138:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.138: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-07-23T03:19:55.409196Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-23T03:19:55.409214Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.138:2380"]} {"level":"info","ts":"2026-07-23T03:19:55.409235Z","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-07-23T03:19:55.410076Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"]} {"level":"info","ts":"2026-07-23T03:19:55.410171Z","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.138:2380"],"listen-peer-urls":["https://199.204.45.138:2380"],"advertise-client-urls":["https://199.204.45.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.138: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-07-23T03:19:55.412793Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.294792ms"} {"level":"info","ts":"2026-07-23T03:19:55.41768Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f3d52791b64f7591","cluster-id":"90076b89649fe294"} {"level":"info","ts":"2026-07-23T03:19:55.417754Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=()"} {"level":"info","ts":"2026-07-23T03:19:55.417777Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became follower at term 0"} {"level":"info","ts":"2026-07-23T03:19:55.417789Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f3d52791b64f7591 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-23T03:19:55.417796Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became follower at term 1"} {"level":"info","ts":"2026-07-23T03:19:55.41783Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=(17569993028038260113)"} {"level":"warn","ts":"2026-07-23T03:19:55.422165Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-23T03:19:55.423484Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-23T03:19:55.424457Z","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-07-23T03:19:55.426597Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f3d52791b64f7591","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-23T03:19:55.426967Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f3d52791b64f7591","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-23T03:19:55.427141Z","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-07-23T03:19:55.427228Z","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-07-23T03:19:55.427245Z","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-07-23T03:19:55.4293Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=(17569993028038260113)"} {"level":"info","ts":"2026-07-23T03:19:55.429437Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"90076b89649fe294","local-member-id":"f3d52791b64f7591","added-peer-id":"f3d52791b64f7591","added-peer-peer-urls":["https://199.204.45.138:2380"]} {"level":"info","ts":"2026-07-23T03:19:55.432151Z","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-07-23T03:19:55.432358Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f3d52791b64f7591","initial-advertise-peer-urls":["https://199.204.45.138:2380"],"listen-peer-urls":["https://199.204.45.138:2380"],"advertise-client-urls":["https://199.204.45.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-23T03:19:55.432399Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-23T03:19:55.432389Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.138:2380"} {"level":"info","ts":"2026-07-23T03:19:55.43255Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.138:2380"} {"level":"info","ts":"2026-07-23T03:19:55.718377Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 is starting a new election at term 1"} {"level":"info","ts":"2026-07-23T03:19:55.718474Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-23T03:19:55.718626Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 received MsgPreVoteResp from f3d52791b64f7591 at term 1"} {"level":"info","ts":"2026-07-23T03:19:55.71868Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became candidate at term 2"} {"level":"info","ts":"2026-07-23T03:19:55.7187Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 received MsgVoteResp from f3d52791b64f7591 at term 2"} {"level":"info","ts":"2026-07-23T03:19:55.718721Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became leader at term 2"} {"level":"info","ts":"2026-07-23T03:19:55.718784Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f3d52791b64f7591 elected leader f3d52791b64f7591 at term 2"} {"level":"info","ts":"2026-07-23T03:19:55.719686Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f3d52791b64f7591","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.138:2379]}","request-path":"/0/members/f3d52791b64f7591/attributes","cluster-id":"90076b89649fe294","publish-timeout":"7s"} {"level":"info","ts":"2026-07-23T03:19:55.719719Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-23T03:19:55.720071Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-23T03:19:55.720123Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-23T03:19:55.719866Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-23T03:19:55.722369Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-23T03:19:55.719817Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-23T03:19:55.724449Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.138:2379"} {"level":"info","ts":"2026-07-23T03:19:55.724591Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"90076b89649fe294","local-member-id":"f3d52791b64f7591","cluster-version":"3.5"} {"level":"info","ts":"2026-07-23T03:19:55.724657Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-23T03:19:55.724679Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-23T03:20:46.168794Z","caller":"traceutil/trace.go:171","msg":"trace[1888417514] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"113.935976ms","start":"2026-07-23T03:20:46.054823Z","end":"2026-07-23T03:20:46.168759Z","steps":["trace[1888417514] 'process raft request' (duration: 113.793472ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T03:20:46.168804Z","caller":"traceutil/trace.go:171","msg":"trace[1515467174] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"114.675196ms","start":"2026-07-23T03:20:46.054091Z","end":"2026-07-23T03:20:46.168766Z","steps":["trace[1515467174] 'process raft request' (duration: 110.259425ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T03:20:52.088538Z","caller":"traceutil/trace.go:171","msg":"trace[1501400478] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"249.156957ms","start":"2026-07-23T03:20:51.839348Z","end":"2026-07-23T03:20:52.088505Z","steps":["trace[1501400478] 'process raft request' (duration: 248.643372ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T03:20:52.801402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.238108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-nvhxl\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-23T03:20:52.801552Z","caller":"traceutil/trace.go:171","msg":"trace[822658027] range","detail":"{range_begin:/registry/pods/kube-system/cilium-nvhxl; range_end:; response_count:1; response_revision:622; }","duration":"405.428094ms","start":"2026-07-23T03:20:52.396085Z","end":"2026-07-23T03:20:52.801513Z","steps":["trace[822658027] 'range keys from in-memory index tree' (duration: 404.974261ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T03:20:52.801601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T03:20:52.396063Z","time spent":"405.528245ms","remote":"127.0.0.1:33598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16989,"request content":"key:\"/registry/pods/kube-system/cilium-nvhxl\" "} {"level":"info","ts":"2026-07-23T03:20:52.834156Z","caller":"traceutil/trace.go:171","msg":"trace[1752680446] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:645; }","duration":"110.940744ms","start":"2026-07-23T03:20:52.723126Z","end":"2026-07-23T03:20:52.834067Z","steps":["trace[1752680446] 'read index received' (duration: 109.161184ms)","trace[1752680446] 'applied index is now lower than readState.Index' (duration: 1.77761ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T03:20:52.834421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.288073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T03:20:52.834472Z","caller":"traceutil/trace.go:171","msg":"trace[1121708224] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:623; }","duration":"111.391526ms","start":"2026-07-23T03:20:52.723055Z","end":"2026-07-23T03:20:52.834446Z","steps":["trace[1121708224] 'agreement among raft nodes before linearized reading' (duration: 111.183181ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T03:20:52.837927Z","caller":"traceutil/trace.go:171","msg":"trace[838764678] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"271.819158ms","start":"2026-07-23T03:20:52.566035Z","end":"2026-07-23T03:20:52.837854Z","steps":["trace[838764678] 'process raft request' (duration: 266.499222ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T03:21:07.888372Z","caller":"traceutil/trace.go:171","msg":"trace[53053145] linearizableReadLoop","detail":"{readStateIndex:813; appliedIndex:812; }","duration":"232.429987ms","start":"2026-07-23T03:21:07.655926Z","end":"2026-07-23T03:21:07.888356Z","steps":["trace[53053145] 'read index received' (duration: 232.338195ms)","trace[53053145] 'applied index is now lower than readState.Index' (duration: 91.122µs)"],"step_count":2} {"level":"warn","ts":"2026-07-23T03:21:07.88856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.628312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-23T03:21:07.888545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.487355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T03:21:07.888583Z","caller":"traceutil/trace.go:171","msg":"trace[935294501] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:785; }","duration":"232.675805ms","start":"2026-07-23T03:21:07.655901Z","end":"2026-07-23T03:21:07.888576Z","steps":["trace[935294501] 'agreement among raft nodes before linearized reading' (duration: 232.55556ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T03:21:07.888601Z","caller":"traceutil/trace.go:171","msg":"trace[573007257] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:785; }","duration":"163.591018ms","start":"2026-07-23T03:21:07.724996Z","end":"2026-07-23T03:21:07.888587Z","steps":["trace[573007257] 'agreement among raft nodes before linearized reading' (duration: 163.482155ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T03:21:07.889125Z","caller":"traceutil/trace.go:171","msg":"trace[1827129871] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"387.47902ms","start":"2026-07-23T03:21:07.501549Z","end":"2026-07-23T03:21:07.889028Z","steps":["trace[1827129871] 'process raft request' (duration: 386.707809ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T03:21:07.889904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T03:21:07.50153Z","time spent":"387.750677ms","remote":"127.0.0.1:33598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4025,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T03:22:07.66837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.712478ms","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-07-23T03:22:07.668443Z","caller":"traceutil/trace.go:171","msg":"trace[988449613] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1079; }","duration":"176.840432ms","start":"2026-07-23T03:22:07.491589Z","end":"2026-07-23T03:22:07.668429Z","steps":["trace[988449613] 'range keys from in-memory index tree' (duration: 176.555963ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T03:22:07.849444Z","caller":"traceutil/trace.go:171","msg":"trace[1113423061] linearizableReadLoop","detail":"{readStateIndex:1120; appliedIndex:1119; }","duration":"127.508698ms","start":"2026-07-23T03:22:07.721913Z","end":"2026-07-23T03:22:07.849422Z","steps":["trace[1113423061] 'read index received' (duration: 127.403355ms)","trace[1113423061] 'applied index is now lower than readState.Index' (duration: 104.483µs)"],"step_count":2} {"level":"warn","ts":"2026-07-23T03:22:07.84955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.631151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T03:22:07.849571Z","caller":"traceutil/trace.go:171","msg":"trace[925501799] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1080; }","duration":"127.697744ms","start":"2026-07-23T03:22:07.721866Z","end":"2026-07-23T03:22:07.849564Z","steps":["trace[925501799] 'agreement among raft nodes before linearized reading' (duration: 127.648243ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T03:22:07.849661Z","caller":"traceutil/trace.go:171","msg":"trace[283533878] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"178.24474ms","start":"2026-07-23T03:22:07.671381Z","end":"2026-07-23T03:22:07.849626Z","steps":["trace[283533878] 'process raft request' (duration: 177.913211ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T03:22:24.673153Z","caller":"traceutil/trace.go:171","msg":"trace[561915589] transaction","detail":"{read_only:false; response_revision:1137; number_of_response:1; }","duration":"144.362827ms","start":"2026-07-23T03:22:24.528744Z","end":"2026-07-23T03:22:24.673107Z","steps":["trace[561915589] 'process raft request' (duration: 144.161471ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T03:22:24.908539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.18174ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-23T03:22:24.908715Z","caller":"traceutil/trace.go:171","msg":"trace[1705795735] transaction","detail":"{read_only:false; response_revision:1138; number_of_response:1; }","duration":"231.078874ms","start":"2026-07-23T03:22:24.677601Z","end":"2026-07-23T03:22:24.90868Z","steps":["trace[1705795735] 'process raft request' (duration: 89.507634ms)","trace[1705795735] 'compare' (duration: 141.058147ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T03:22:24.908679Z","caller":"traceutil/trace.go:171","msg":"trace[841994276] linearizableReadLoop","detail":"{readStateIndex:1182; appliedIndex:1181; }","duration":"185.506575ms","start":"2026-07-23T03:22:24.723155Z","end":"2026-07-23T03:22:24.908662Z","steps":["trace[841994276] 'read index received' (duration: 43.847122ms)","trace[841994276] 'applied index is now lower than readState.Index' (duration: 141.658473ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T03:22:24.908889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.71551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T03:22:24.908964Z","caller":"traceutil/trace.go:171","msg":"trace[1329684927] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1138; }","duration":"185.861685ms","start":"2026-07-23T03:22:24.723085Z","end":"2026-07-23T03:22:24.908947Z","steps":["trace[1329684927] 'agreement among raft nodes before linearized reading' (duration: 185.725201ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T03:22:25.627796Z","caller":"traceutil/trace.go:171","msg":"trace[2098699829] transaction","detail":"{read_only:false; response_revision:1139; number_of_response:1; }","duration":"185.052503ms","start":"2026-07-23T03:22:25.442714Z","end":"2026-07-23T03:22:25.627767Z","steps":["trace[2098699829] 'process raft request' (duration: 184.886988ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T03:22:40.971805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.421802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-23T03:22:40.971895Z","caller":"traceutil/trace.go:171","msg":"trace[1485147737] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1239; }","duration":"467.514985ms","start":"2026-07-23T03:22:40.504352Z","end":"2026-07-23T03:22:40.971867Z","steps":["trace[1485147737] 'range keys from in-memory index tree' (duration: 467.199006ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T03:22:40.97194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T03:22:40.504291Z","time spent":"467.637329ms","remote":"127.0.0.1:33690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-23T03:22:40.972108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.169739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T03:22:40.97219Z","caller":"traceutil/trace.go:171","msg":"trace[1661334346] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1239; }","duration":"249.320954ms","start":"2026-07-23T03:22:40.722847Z","end":"2026-07-23T03:22:40.972168Z","steps":["trace[1661334346] 'range keys from in-memory index tree' (duration: 249.083267ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T03:22:40.972566Z","caller":"traceutil/trace.go:171","msg":"trace[1399323317] linearizableReadLoop","detail":"{readStateIndex:1289; appliedIndex:1288; }","duration":"114.5672ms","start":"2026-07-23T03:22:40.857987Z","end":"2026-07-23T03:22:40.972554Z","steps":["trace[1399323317] 'read index received' (duration: 114.054456ms)","trace[1399323317] 'applied index is now lower than readState.Index' (duration: 511.894µs)"],"step_count":2} {"level":"info","ts":"2026-07-23T03:22:40.972641Z","caller":"traceutil/trace.go:171","msg":"trace[202409367] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"243.211045ms","start":"2026-07-23T03:22:40.729421Z","end":"2026-07-23T03:22:40.972632Z","steps":["trace[202409367] 'process raft request' (duration: 243.025301ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T03:22:40.972751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.762685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T03:22:40.972785Z","caller":"traceutil/trace.go:171","msg":"trace[2126677514] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1240; }","duration":"114.847468ms","start":"2026-07-23T03:22:40.857928Z","end":"2026-07-23T03:22:40.972776Z","steps":["trace[2126677514] 'agreement among raft nodes before linearized reading' (duration: 114.786576ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T03:22:40.972946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.824753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T03:22:40.97298Z","caller":"traceutil/trace.go:171","msg":"trace[2009309382] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1240; }","duration":"112.877614ms","start":"2026-07-23T03:22:40.860095Z","end":"2026-07-23T03:22:40.972972Z","steps":["trace[2009309382] 'agreement among raft nodes before linearized reading' (duration: 112.829323ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T03:22:58.387281Z","caller":"traceutil/trace.go:171","msg":"trace[14727531] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"204.116185ms","start":"2026-07-23T03:22:58.183146Z","end":"2026-07-23T03:22:58.387262Z","steps":["trace[14727531] 'process raft request' (duration: 204.009172ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T03:22:59.193157Z","caller":"traceutil/trace.go:171","msg":"trace[2028390415] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"410.10309ms","start":"2026-07-23T03:22:58.783034Z","end":"2026-07-23T03:22:59.193137Z","steps":["trace[2028390415] 'process raft request' (duration: 409.984807ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T03:22:59.193258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T03:22:58.783022Z","time spent":"410.189333ms","remote":"127.0.0.1:33690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-23T03:22:59.455903Z","caller":"traceutil/trace.go:171","msg":"trace[1310072482] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"590.27517ms","start":"2026-07-23T03:22:58.865605Z","end":"2026-07-23T03:22:59.45588Z","steps":["trace[1310072482] 'process raft request' (duration: 558.966642ms)","trace[1310072482] 'compare' (duration: 30.948218ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T03:22:59.455905Z","caller":"traceutil/trace.go:171","msg":"trace[925087274] linearizableReadLoop","detail":"{readStateIndex:1376; appliedIndex:1373; }","duration":"397.552057ms","start":"2026-07-23T03:22:59.058329Z","end":"2026-07-23T03:22:59.455881Z","steps":["trace[925087274] 'read index received' (duration: 134.673531ms)","trace[925087274] 'applied index is now lower than readState.Index' (duration: 262.877686ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T03:22:59.455999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T03:22:58.865581Z","time spent":"590.374052ms","remote":"127.0.0.1:33494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-23T03:22:59.456025Z","caller":"traceutil/trace.go:171","msg":"trace[1918057273] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"428.480515ms","start":"2026-07-23T03:22:59.027533Z","end":"2026-07-23T03:22:59.456013Z","steps":["trace[1918057273] 'process raft request' (duration: 428.286119ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T03:22:59.456145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T03:22:59.027509Z","time spent":"428.544187ms","remote":"127.0.0.1:33690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T03:22:59.456287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.942988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-23T03:22:59.45634Z","caller":"traceutil/trace.go:171","msg":"trace[998018390] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1323; }","duration":"398.040671ms","start":"2026-07-23T03:22:59.058288Z","end":"2026-07-23T03:22:59.456329Z","steps":["trace[998018390] 'agreement among raft nodes before linearized reading' (duration: 397.872586ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T03:22:59.456385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T03:22:59.058273Z","time spent":"398.103053ms","remote":"127.0.0.1:33494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":117,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-07-23T03:22:59.466926Z","caller":"traceutil/trace.go:171","msg":"trace[1663265630] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"267.740418ms","start":"2026-07-23T03:22:59.199169Z","end":"2026-07-23T03:22:59.46691Z","steps":["trace[1663265630] 'process raft request' (duration: 267.649006ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T03:22:59.467125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.903254ms","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-07-23T03:22:59.467203Z","caller":"traceutil/trace.go:171","msg":"trace[1009175969] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1324; }","duration":"403.028626ms","start":"2026-07-23T03:22:59.064158Z","end":"2026-07-23T03:22:59.467186Z","steps":["trace[1009175969] 'agreement among raft nodes before linearized reading' (duration: 402.813711ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T03:22:59.467267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T03:22:59.064124Z","time spent":"403.13175ms","remote":"127.0.0.1:33690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-23T03:22:59.467301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.148192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-07-23T03:22:59.467314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.906038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-07-23T03:22:59.467341Z","caller":"traceutil/trace.go:171","msg":"trace[1266800140] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1324; }","duration":"402.213095ms","start":"2026-07-23T03:22:59.065118Z","end":"2026-07-23T03:22:59.467331Z","steps":["trace[1266800140] 'agreement among raft nodes before linearized reading' (duration: 402.087742ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T03:22:59.467361Z","caller":"traceutil/trace.go:171","msg":"trace[1046628053] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1324; }","duration":"269.99348ms","start":"2026-07-23T03:22:59.197354Z","end":"2026-07-23T03:22:59.467347Z","steps":["trace[1046628053] 'agreement among raft nodes before linearized reading' (duration: 269.819835ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T03:22:59.467378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T03:22:59.065105Z","time spent":"402.265456ms","remote":"127.0.0.1:33690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-23T03:23:00.549094Z","caller":"traceutil/trace.go:171","msg":"trace[1128406040] linearizableReadLoop","detail":"{readStateIndex:1384; appliedIndex:1383; }","duration":"105.913082ms","start":"2026-07-23T03:23:00.443146Z","end":"2026-07-23T03:23:00.549059Z","steps":["trace[1128406040] 'read index received' (duration: 105.595273ms)","trace[1128406040] 'applied index is now lower than readState.Index' (duration: 316.699µs)"],"step_count":2} {"level":"warn","ts":"2026-07-23T03:23:00.549395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.208931ms","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-07-23T03:23:00.549436Z","caller":"traceutil/trace.go:171","msg":"trace[23524812] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1331; }","duration":"106.317114ms","start":"2026-07-23T03:23:00.443104Z","end":"2026-07-23T03:23:00.549421Z","steps":["trace[23524812] 'agreement among raft nodes before linearized reading' (duration: 106.090898ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T03:23:00.549642Z","caller":"traceutil/trace.go:171","msg":"trace[243579997] transaction","detail":"{read_only:false; response_revision:1331; number_of_response:1; }","duration":"142.609888ms","start":"2026-07-23T03:23:00.406954Z","end":"2026-07-23T03:23:00.549564Z","steps":["trace[243579997] 'process raft request' (duration: 141.878338ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T03:23:09.958041Z","caller":"traceutil/trace.go:171","msg":"trace[402488584] linearizableReadLoop","detail":"{readStateIndex:1457; appliedIndex:1456; }","duration":"116.77133ms","start":"2026-07-23T03:23:09.841244Z","end":"2026-07-23T03:23:09.958015Z","steps":["trace[402488584] 'read index received' (duration: 116.574934ms)","trace[402488584] 'applied index is now lower than readState.Index' (duration: 195.326µs)"],"step_count":2} {"level":"info","ts":"2026-07-23T03:23:09.958128Z","caller":"traceutil/trace.go:171","msg":"trace[1195482967] transaction","detail":"{read_only:false; response_revision:1402; number_of_response:1; }","duration":"120.891374ms","start":"2026-07-23T03:23:09.837217Z","end":"2026-07-23T03:23:09.958109Z","steps":["trace[1195482967] 'process raft request' (duration: 120.630136ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T03:23:09.958527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.232743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-07-23T03:23:09.958696Z","caller":"traceutil/trace.go:171","msg":"trace[200158049] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1402; }","duration":"117.333986ms","start":"2026-07-23T03:23:09.841232Z","end":"2026-07-23T03:23:09.958566Z","steps":["trace[200158049] 'agreement among raft nodes before linearized reading' (duration: 116.884604ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T03:23:10.520028Z","caller":"traceutil/trace.go:171","msg":"trace[679608262] linearizableReadLoop","detail":"{readStateIndex:1459; appliedIndex:1458; }","duration":"103.885107ms","start":"2026-07-23T03:23:10.416117Z","end":"2026-07-23T03:23:10.520002Z","steps":["trace[679608262] 'read index received' (duration: 103.742863ms)","trace[679608262] 'applied index is now lower than readState.Index' (duration: 140.924µs)"],"step_count":2} {"level":"info","ts":"2026-07-23T03:23:10.520072Z","caller":"traceutil/trace.go:171","msg":"trace[1129552638] transaction","detail":"{read_only:false; response_revision:1404; number_of_response:1; }","duration":"179.305225ms","start":"2026-07-23T03:23:10.340726Z","end":"2026-07-23T03:23:10.520032Z","steps":["trace[1129552638] 'process raft request' (duration: 179.12321ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T03:23:10.520185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.054413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-23T03:23:10.520225Z","caller":"traceutil/trace.go:171","msg":"trace[362985063] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1404; }","duration":"104.115324ms","start":"2026-07-23T03:23:10.416095Z","end":"2026-07-23T03:23:10.52021Z","steps":["trace[362985063] 'agreement among raft nodes before linearized reading' (duration: 104.004741ms)"],"step_count":1}