{"level":"warn","ts":"2026-05-06T00:47:14.732581Z","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-06T00:47:14.732656Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.111: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.19.213.111:2380","--initial-cluster=instance=https://199.19.213.111:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.111:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.111: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-06T00:47:14.732717Z","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-06T00:47:14.732729Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.111:2380"]} {"level":"info","ts":"2026-05-06T00:47:14.732746Z","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-06T00:47:14.733357Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.111:2379"]} {"level":"info","ts":"2026-05-06T00:47:14.733426Z","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.19.213.111:2380"],"listen-peer-urls":["https://199.19.213.111:2380"],"advertise-client-urls":["https://199.19.213.111:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.111:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.111: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-06T00:47:14.738787Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.064053ms"} {"level":"info","ts":"2026-05-06T00:47:14.743688Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4edbe0dc99dceaa8","cluster-id":"9f725a00b80fcc82"} {"level":"info","ts":"2026-05-06T00:47:14.743751Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4edbe0dc99dceaa8 switched to configuration voters=()"} {"level":"info","ts":"2026-05-06T00:47:14.743776Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4edbe0dc99dceaa8 became follower at term 0"} {"level":"info","ts":"2026-05-06T00:47:14.74379Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4edbe0dc99dceaa8 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-06T00:47:14.743806Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4edbe0dc99dceaa8 became follower at term 1"} {"level":"info","ts":"2026-05-06T00:47:14.74384Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4edbe0dc99dceaa8 switched to configuration voters=(5682382592936831656)"} {"level":"warn","ts":"2026-05-06T00:47:14.749238Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-06T00:47:14.750367Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-06T00:47:14.751285Z","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-06T00:47:14.753172Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4edbe0dc99dceaa8","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-06T00:47:14.753632Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4edbe0dc99dceaa8","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-06T00:47:14.753656Z","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-06T00:47:14.753759Z","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-06T00:47:14.753774Z","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-06T00:47:14.755341Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4edbe0dc99dceaa8 switched to configuration voters=(5682382592936831656)"} {"level":"info","ts":"2026-05-06T00:47:14.755551Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9f725a00b80fcc82","local-member-id":"4edbe0dc99dceaa8","added-peer-id":"4edbe0dc99dceaa8","added-peer-peer-urls":["https://199.19.213.111:2380"]} {"level":"info","ts":"2026-05-06T00:47:14.757917Z","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-06T00:47:14.757963Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.111:2380"} {"level":"info","ts":"2026-05-06T00:47:14.75798Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.111:2380"} {"level":"info","ts":"2026-05-06T00:47:14.758214Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4edbe0dc99dceaa8","initial-advertise-peer-urls":["https://199.19.213.111:2380"],"listen-peer-urls":["https://199.19.213.111:2380"],"advertise-client-urls":["https://199.19.213.111:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.111:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-06T00:47:14.758268Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-06T00:47:15.044022Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4edbe0dc99dceaa8 is starting a new election at term 1"} {"level":"info","ts":"2026-05-06T00:47:15.044075Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4edbe0dc99dceaa8 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-06T00:47:15.044093Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4edbe0dc99dceaa8 received MsgPreVoteResp from 4edbe0dc99dceaa8 at term 1"} {"level":"info","ts":"2026-05-06T00:47:15.044104Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4edbe0dc99dceaa8 became candidate at term 2"} {"level":"info","ts":"2026-05-06T00:47:15.044109Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4edbe0dc99dceaa8 received MsgVoteResp from 4edbe0dc99dceaa8 at term 2"} {"level":"info","ts":"2026-05-06T00:47:15.044117Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4edbe0dc99dceaa8 became leader at term 2"} {"level":"info","ts":"2026-05-06T00:47:15.044125Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4edbe0dc99dceaa8 elected leader 4edbe0dc99dceaa8 at term 2"} {"level":"info","ts":"2026-05-06T00:47:15.045192Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4edbe0dc99dceaa8","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.111:2379]}","request-path":"/0/members/4edbe0dc99dceaa8/attributes","cluster-id":"9f725a00b80fcc82","publish-timeout":"7s"} {"level":"info","ts":"2026-05-06T00:47:15.045278Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-06T00:47:15.04537Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T00:47:15.045372Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-06T00:47:15.04611Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9f725a00b80fcc82","local-member-id":"4edbe0dc99dceaa8","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T00:47:15.046204Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T00:47:15.046235Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T00:47:15.046262Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-06T00:47:15.046278Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-06T00:47:15.046918Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.111:2379"} {"level":"info","ts":"2026-05-06T00:47:15.048671Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-06T00:47:52.334835Z","caller":"traceutil/trace.go:171","msg":"trace[1069465929] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:604; }","duration":"190.636395ms","start":"2026-05-06T00:47:52.144168Z","end":"2026-05-06T00:47:52.334805Z","steps":["trace[1069465929] 'read index received' (duration: 190.47195ms)","trace[1069465929] 'applied index is now lower than readState.Index' (duration: 163.265µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:47:52.334892Z","caller":"traceutil/trace.go:171","msg":"trace[729652292] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"197.907204ms","start":"2026-05-06T00:47:52.136949Z","end":"2026-05-06T00:47:52.334856Z","steps":["trace[729652292] 'process raft request' (duration: 197.638596ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:47:52.335136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.940463ms","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-06T00:47:52.335234Z","caller":"traceutil/trace.go:171","msg":"trace[91122796] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:584; }","duration":"191.083088ms","start":"2026-05-06T00:47:52.144135Z","end":"2026-05-06T00:47:52.335218Z","steps":["trace[91122796] 'agreement among raft nodes before linearized reading' (duration: 190.814891ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:47:52.342611Z","caller":"traceutil/trace.go:171","msg":"trace[156550204] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"166.750495ms","start":"2026-05-06T00:47:52.175841Z","end":"2026-05-06T00:47:52.342592Z","steps":["trace[156550204] 'process raft request' (duration: 166.403336ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:47:52.342895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.374331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:7708"} {"level":"info","ts":"2026-05-06T00:47:52.342945Z","caller":"traceutil/trace.go:171","msg":"trace[684315212] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:585; }","duration":"182.662429ms","start":"2026-05-06T00:47:52.160271Z","end":"2026-05-06T00:47:52.342933Z","steps":["trace[684315212] 'agreement among raft nodes before linearized reading' (duration: 182.286159ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:47:59.095366Z","caller":"traceutil/trace.go:171","msg":"trace[171046986] linearizableReadLoop","detail":"{readStateIndex:679; appliedIndex:678; }","duration":"243.9287ms","start":"2026-05-06T00:47:58.851414Z","end":"2026-05-06T00:47:59.095343Z","steps":["trace[171046986] 'read index received' (duration: 243.726285ms)","trace[171046986] 'applied index is now lower than readState.Index' (duration: 201.495µs)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:47:59.095505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.079395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:47:59.09551Z","caller":"traceutil/trace.go:171","msg":"trace[1114417245] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"359.106915ms","start":"2026-05-06T00:47:58.736346Z","end":"2026-05-06T00:47:59.095453Z","steps":["trace[1114417245] 'process raft request' (duration: 358.843878ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:47:59.095538Z","caller":"traceutil/trace.go:171","msg":"trace[105953000] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:656; }","duration":"244.145516ms","start":"2026-05-06T00:47:58.851381Z","end":"2026-05-06T00:47:59.095527Z","steps":["trace[105953000] 'agreement among raft nodes before linearized reading' (duration: 244.079694ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:47:59.096331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:47:58.736325Z","time spent":"359.287389ms","remote":"127.0.0.1:45670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:48:48.879793Z","caller":"traceutil/trace.go:171","msg":"trace[1936208523] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"125.968398ms","start":"2026-05-06T00:48:48.753803Z","end":"2026-05-06T00:48:48.879771Z","steps":["trace[1936208523] 'process raft request' (duration: 125.859086ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:49.096849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.148883ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T00:48:49.097002Z","caller":"traceutil/trace.go:171","msg":"trace[1467565825] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"330.606111ms","start":"2026-05-06T00:48:48.766347Z","end":"2026-05-06T00:48:49.096953Z","steps":["trace[1467565825] 'process raft request' (duration: 204.121149ms)","trace[1467565825] 'compare' (duration: 126.01913ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:48:49.097097Z","caller":"traceutil/trace.go:171","msg":"trace[465744306] linearizableReadLoop","detail":"{readStateIndex:1014; appliedIndex:1012; }","duration":"245.000327ms","start":"2026-05-06T00:48:48.852069Z","end":"2026-05-06T00:48:49.09707Z","steps":["trace[465744306] 'read index received' (duration: 27.540549ms)","trace[465744306] 'applied index is now lower than readState.Index' (duration: 217.457468ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:48:49.0972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:48:48.766323Z","time spent":"330.780895ms","remote":"127.0.0.1:45762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:48:49.09727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.198681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:48:49.097312Z","caller":"traceutil/trace.go:171","msg":"trace[1936915760] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:979; }","duration":"245.270384ms","start":"2026-05-06T00:48:48.85203Z","end":"2026-05-06T00:48:49.0973Z","steps":["trace[1936915760] 'agreement among raft nodes before linearized reading' (duration: 245.180551ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:49.097492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.423192ms","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-06T00:48:49.097545Z","caller":"traceutil/trace.go:171","msg":"trace[404503999] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:979; }","duration":"205.503054ms","start":"2026-05-06T00:48:48.892024Z","end":"2026-05-06T00:48:49.097527Z","steps":["trace[404503999] 'agreement among raft nodes before linearized reading' (duration: 205.33679ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:49.097597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.384459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-06T00:48:49.097654Z","caller":"traceutil/trace.go:171","msg":"trace[984107737] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:979; }","duration":"139.487861ms","start":"2026-05-06T00:48:48.958146Z","end":"2026-05-06T00:48:49.097634Z","steps":["trace[984107737] 'agreement among raft nodes before linearized reading' (duration: 139.408859ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:49:42.573041Z","caller":"traceutil/trace.go:171","msg":"trace[1808031606] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"124.756567ms","start":"2026-05-06T00:49:42.448267Z","end":"2026-05-06T00:49:42.573024Z","steps":["trace[1808031606] 'process raft request' (duration: 124.660064ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:49:42.74244Z","caller":"traceutil/trace.go:171","msg":"trace[1243631984] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"172.282837ms","start":"2026-05-06T00:49:42.570133Z","end":"2026-05-06T00:49:42.742416Z","steps":["trace[1243631984] 'process raft request' (duration: 172.117233ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:49:43.51485Z","caller":"traceutil/trace.go:171","msg":"trace[1607503884] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"269.521496ms","start":"2026-05-06T00:49:43.245291Z","end":"2026-05-06T00:49:43.514812Z","steps":["trace[1607503884] 'process raft request' (duration: 269.346481ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:43.514906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.930868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:49:43.514923Z","caller":"traceutil/trace.go:171","msg":"trace[1264533099] linearizableReadLoop","detail":"{readStateIndex:1292; appliedIndex:1291; }","duration":"266.845706ms","start":"2026-05-06T00:49:43.247935Z","end":"2026-05-06T00:49:43.514781Z","steps":["trace[1264533099] 'read index received' (duration: 266.749363ms)","trace[1264533099] 'applied index is now lower than readState.Index' (duration: 95.283µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:49:43.514992Z","caller":"traceutil/trace.go:171","msg":"trace[247233515] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1244; }","duration":"267.074711ms","start":"2026-05-06T00:49:43.247882Z","end":"2026-05-06T00:49:43.514957Z","steps":["trace[247233515] 'agreement among raft nodes before linearized reading' (duration: 266.907887ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:43.711899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.425434ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T00:49:43.711981Z","caller":"traceutil/trace.go:171","msg":"trace[1434700298] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"437.48312ms","start":"2026-05-06T00:49:43.274484Z","end":"2026-05-06T00:49:43.711967Z","steps":["trace[1434700298] 'process raft request' (duration: 319.925392ms)","trace[1434700298] 'compare' (duration: 117.323722ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:49:43.712013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:43.274459Z","time spent":"437.539391ms","remote":"127.0.0.1:45762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:49:43.765689Z","caller":"traceutil/trace.go:171","msg":"trace[154524043] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"246.947746ms","start":"2026-05-06T00:49:43.518721Z","end":"2026-05-06T00:49:43.765669Z","steps":["trace[154524043] 'process raft request' (duration: 246.902385ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:49:43.765623Z","caller":"traceutil/trace.go:171","msg":"trace[531326782] linearizableReadLoop","detail":"{readStateIndex:1294; appliedIndex:1292; }","duration":"250.598752ms","start":"2026-05-06T00:49:43.515006Z","end":"2026-05-06T00:49:43.765605Z","steps":["trace[531326782] 'read index received' (duration: 79.372582ms)","trace[531326782] 'applied index is now lower than readState.Index' (duration: 171.22438ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:49:43.765715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.06195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:49:43.765758Z","caller":"traceutil/trace.go:171","msg":"trace[1772155742] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"247.241515ms","start":"2026-05-06T00:49:43.51845Z","end":"2026-05-06T00:49:43.765692Z","steps":["trace[1772155742] 'process raft request' (duration: 247.03906ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:49:43.765813Z","caller":"traceutil/trace.go:171","msg":"trace[526806785] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1247; }","duration":"270.256335ms","start":"2026-05-06T00:49:43.495548Z","end":"2026-05-06T00:49:43.765804Z","steps":["trace[526806785] 'agreement among raft nodes before linearized reading' (duration: 270.112671ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:43.76612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.456356ms","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-06T00:49:43.766138Z","caller":"traceutil/trace.go:171","msg":"trace[691035150] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1247; }","duration":"443.505237ms","start":"2026-05-06T00:49:43.322627Z","end":"2026-05-06T00:49:43.766132Z","steps":["trace[691035150] 'agreement among raft nodes before linearized reading' (duration: 443.132198ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:43.766152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:43.322614Z","time spent":"443.534199ms","remote":"127.0.0.1:45762","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-06T00:49:43.901024Z","caller":"traceutil/trace.go:171","msg":"trace[1874090379] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"128.541346ms","start":"2026-05-06T00:49:43.772442Z","end":"2026-05-06T00:49:43.900984Z","steps":["trace[1874090379] 'process raft request' (duration: 128.490364ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:49:43.901181Z","caller":"traceutil/trace.go:171","msg":"trace[1091832683] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"129.071839ms","start":"2026-05-06T00:49:43.772091Z","end":"2026-05-06T00:49:43.901163Z","steps":["trace[1091832683] 'process raft request' (duration: 128.667039ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:54.250046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.904504ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:49:54.250176Z","caller":"traceutil/trace.go:171","msg":"trace[1872122770] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1313; }","duration":"188.068378ms","start":"2026-05-06T00:49:54.062089Z","end":"2026-05-06T00:49:54.250157Z","steps":["trace[1872122770] 'range keys from in-memory index tree' (duration: 187.885344ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:49:54.250207Z","caller":"traceutil/trace.go:171","msg":"trace[1507570327] linearizableReadLoop","detail":"{readStateIndex:1365; appliedIndex:1364; }","duration":"245.209638ms","start":"2026-05-06T00:49:54.004978Z","end":"2026-05-06T00:49:54.250187Z","steps":["trace[1507570327] 'read index received' (duration: 184.278989ms)","trace[1507570327] 'applied index is now lower than readState.Index' (duration: 60.929929ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:49:54.250304Z","caller":"traceutil/trace.go:171","msg":"trace[2097542360] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"333.275475ms","start":"2026-05-06T00:49:53.91702Z","end":"2026-05-06T00:49:54.250295Z","steps":["trace[2097542360] 'process raft request' (duration: 272.295304ms)","trace[2097542360] 'compare' (duration: 60.588911ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:49:54.250392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:53.916997Z","time spent":"333.329897ms","remote":"127.0.0.1:45762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:49:54.250522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.547587ms","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-05-06T00:49:54.250563Z","caller":"traceutil/trace.go:171","msg":"trace[437131722] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1314; }","duration":"245.629789ms","start":"2026-05-06T00:49:54.004921Z","end":"2026-05-06T00:49:54.250551Z","steps":["trace[437131722] 'agreement among raft nodes before linearized reading' (duration: 245.493115ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:49:54.371786Z","caller":"traceutil/trace.go:171","msg":"trace[156573338] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"117.072234ms","start":"2026-05-06T00:49:54.254688Z","end":"2026-05-06T00:49:54.37176Z","steps":["trace[156573338] 'process raft request' (duration: 110.087378ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:49:54.408626Z","caller":"traceutil/trace.go:171","msg":"trace[36638924] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"153.387823ms","start":"2026-05-06T00:49:54.255221Z","end":"2026-05-06T00:49:54.408609Z","steps":["trace[36638924] 'process raft request' (duration: 153.296741ms)"],"step_count":1}