{"level":"warn","ts":"2026-03-14T01:26:16.417316Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-14T01:26:16.417409Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.248:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.248:2380","--initial-cluster=instance=https://199.204.45.248:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.248:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.248:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-03-14T01:26:16.417473Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-14T01:26:16.417481Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.248:2380"]} {"level":"info","ts":"2026-03-14T01:26:16.417503Z","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-03-14T01:26:16.419061Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"]} {"level":"info","ts":"2026-03-14T01:26:16.41915Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.248:2380"],"listen-peer-urls":["https://199.204.45.248:2380"],"advertise-client-urls":["https://199.204.45.248:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.248:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-03-14T01:26:16.425348Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.939239ms"} {"level":"info","ts":"2026-03-14T01:26:16.433361Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"39582465ceaa2f67","cluster-id":"87fd8ddedde3be45"} {"level":"info","ts":"2026-03-14T01:26:16.433463Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=()"} {"level":"info","ts":"2026-03-14T01:26:16.433491Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became follower at term 0"} {"level":"info","ts":"2026-03-14T01:26:16.43352Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 39582465ceaa2f67 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-14T01:26:16.433551Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became follower at term 1"} {"level":"info","ts":"2026-03-14T01:26:16.433622Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=(4132092677789986663)"} {"level":"warn","ts":"2026-03-14T01:26:16.441655Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-14T01:26:16.442857Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-14T01:26:16.444126Z","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-03-14T01:26:16.446165Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"39582465ceaa2f67","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-14T01:26:16.446921Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"39582465ceaa2f67","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-14T01:26:16.447358Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=(4132092677789986663)"} {"level":"info","ts":"2026-03-14T01:26:16.447528Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"87fd8ddedde3be45","local-member-id":"39582465ceaa2f67","added-peer-id":"39582465ceaa2f67","added-peer-peer-urls":["https://199.204.45.248:2380"]} {"level":"info","ts":"2026-03-14T01:26:16.447673Z","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-03-14T01:26:16.44772Z","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-03-14T01:26:16.447775Z","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-03-14T01:26:16.450378Z","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-03-14T01:26:16.450593Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"39582465ceaa2f67","initial-advertise-peer-urls":["https://199.204.45.248:2380"],"listen-peer-urls":["https://199.204.45.248:2380"],"advertise-client-urls":["https://199.204.45.248:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-14T01:26:16.450627Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-14T01:26:16.450735Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.248:2380"} {"level":"info","ts":"2026-03-14T01:26:16.450749Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.248:2380"} {"level":"info","ts":"2026-03-14T01:26:17.338558Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 is starting a new election at term 1"} {"level":"info","ts":"2026-03-14T01:26:17.340468Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-14T01:26:17.341432Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 received MsgPreVoteResp from 39582465ceaa2f67 at term 1"} {"level":"info","ts":"2026-03-14T01:26:17.341459Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became candidate at term 2"} {"level":"info","ts":"2026-03-14T01:26:17.341466Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 received MsgVoteResp from 39582465ceaa2f67 at term 2"} {"level":"info","ts":"2026-03-14T01:26:17.341477Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became leader at term 2"} {"level":"info","ts":"2026-03-14T01:26:17.341488Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 39582465ceaa2f67 elected leader 39582465ceaa2f67 at term 2"} {"level":"info","ts":"2026-03-14T01:26:17.34255Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"39582465ceaa2f67","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.248:2379]}","request-path":"/0/members/39582465ceaa2f67/attributes","cluster-id":"87fd8ddedde3be45","publish-timeout":"7s"} {"level":"info","ts":"2026-03-14T01:26:17.342607Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-14T01:26:17.342641Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-14T01:26:17.342734Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-14T01:26:17.342759Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-14T01:26:17.34364Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-14T01:26:17.344529Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"87fd8ddedde3be45","local-member-id":"39582465ceaa2f67","cluster-version":"3.5"} {"level":"info","ts":"2026-03-14T01:26:17.344612Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-14T01:26:17.344638Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-14T01:26:17.345153Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.248:2379"} {"level":"info","ts":"2026-03-14T01:26:17.345873Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-03-14T01:26:20.246769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.426214ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-03-14T01:26:20.246898Z","caller":"traceutil/trace.go:171","msg":"trace[1247360233] linearizableReadLoop","detail":"{readStateIndex:76; appliedIndex:75; }","duration":"594.771431ms","start":"2026-03-14T01:26:19.65211Z","end":"2026-03-14T01:26:20.246882Z","steps":["trace[1247360233] 'read index received' (duration: 287.46906ms)","trace[1247360233] 'applied index is now lower than readState.Index' (duration: 307.301171ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:26:20.246972Z","caller":"traceutil/trace.go:171","msg":"trace[1949512006] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"595.501963ms","start":"2026-03-14T01:26:19.651426Z","end":"2026-03-14T01:26:20.246928Z","steps":["trace[1949512006] 'process raft request' (duration: 288.216953ms)","trace[1949512006] 'compare' (duration: 306.28951ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T01:26:20.24704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.918706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-14T01:26:20.247069Z","caller":"traceutil/trace.go:171","msg":"trace[653036638] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:0; response_revision:72; }","duration":"594.972527ms","start":"2026-03-14T01:26:19.652088Z","end":"2026-03-14T01:26:20.24706Z","steps":["trace[653036638] 'agreement among raft nodes before linearized reading' (duration: 594.879235ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:26:20.24711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:26:19.652078Z","time spent":"595.025039ms","remote":"127.0.0.1:36632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/edit\" "} {"level":"warn","ts":"2026-03-14T01:26:20.247143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:26:19.651406Z","time spent":"595.652458ms","remote":"127.0.0.1:36638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-14T01:26:20.247142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.003793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-14T01:26:20.2472Z","caller":"traceutil/trace.go:171","msg":"trace[1868047570] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:72; }","duration":"549.102005ms","start":"2026-03-14T01:26:19.69808Z","end":"2026-03-14T01:26:20.247182Z","steps":["trace[1868047570] 'agreement among raft nodes before linearized reading' (duration: 548.998512ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:26:20.247239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:26:19.698065Z","time spent":"549.167308ms","remote":"127.0.0.1:36306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-14T01:26:20.247373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.11294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-14T01:26:20.247425Z","caller":"traceutil/trace.go:171","msg":"trace[1453904088] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:72; }","duration":"191.172572ms","start":"2026-03-14T01:26:20.056238Z","end":"2026-03-14T01:26:20.247411Z","steps":["trace[1453904088] 'agreement among raft nodes before linearized reading' (duration: 191.081919ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:26:20.675233Z","caller":"traceutil/trace.go:171","msg":"trace[2053769880] linearizableReadLoop","detail":"{readStateIndex:77; appliedIndex:76; }","duration":"423.209935ms","start":"2026-03-14T01:26:20.251983Z","end":"2026-03-14T01:26:20.675193Z","steps":["trace[2053769880] 'read index received' (duration: 423.052721ms)","trace[2053769880] 'applied index is now lower than readState.Index' (duration: 155.494µs)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:26:20.675277Z","caller":"traceutil/trace.go:171","msg":"trace[1332192693] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"425.314539ms","start":"2026-03-14T01:26:20.249927Z","end":"2026-03-14T01:26:20.675241Z","steps":["trace[1332192693] 'process raft request' (duration: 425.086722ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:26:20.675521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.461163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/view\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-14T01:26:20.67557Z","caller":"traceutil/trace.go:171","msg":"trace[1125613712] range","detail":"{range_begin:/registry/clusterroles/view; range_end:; response_count:0; response_revision:73; }","duration":"423.605997ms","start":"2026-03-14T01:26:20.25195Z","end":"2026-03-14T01:26:20.675556Z","steps":["trace[1125613712] 'agreement among raft nodes before linearized reading' (duration: 423.37951ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:26:20.675619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:26:20.251934Z","time spent":"423.67591ms","remote":"127.0.0.1:36632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/view\" "} {"level":"warn","ts":"2026-03-14T01:26:20.675719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:26:20.249902Z","time spent":"425.486944ms","remote":"127.0.0.1:36496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5882,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-14T01:26:20.678882Z","caller":"traceutil/trace.go:171","msg":"trace[1149468211] transaction","detail":"{read_only:false; response_revision:74; number_of_response:1; }","duration":"133.263837ms","start":"2026-03-14T01:26:20.545601Z","end":"2026-03-14T01:26:20.678865Z","steps":["trace[1149468211] 'process raft request' (duration: 133.01948ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:26:31.516707Z","caller":"traceutil/trace.go:171","msg":"trace[1899009151] linearizableReadLoop","detail":"{readStateIndex:418; appliedIndex:417; }","duration":"117.28018ms","start":"2026-03-14T01:26:31.399407Z","end":"2026-03-14T01:26:31.516687Z","steps":["trace[1899009151] 'read index received' (duration: 117.263009ms)","trace[1899009151] 'applied index is now lower than readState.Index' (duration: 16.471µs)"],"step_count":2} {"level":"warn","ts":"2026-03-14T01:26:31.51703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.613561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.248\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-14T01:26:31.517053Z","caller":"traceutil/trace.go:171","msg":"trace[1105672975] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"131.659665ms","start":"2026-03-14T01:26:31.385333Z","end":"2026-03-14T01:26:31.516992Z","steps":["trace[1105672975] 'process raft request' (duration: 131.037866ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:26:31.517173Z","caller":"traceutil/trace.go:171","msg":"trace[2009874890] range","detail":"{range_begin:/registry/masterleases/199.204.45.248; range_end:; response_count:1; response_revision:404; }","duration":"117.796356ms","start":"2026-03-14T01:26:31.399362Z","end":"2026-03-14T01:26:31.517159Z","steps":["trace[2009874890] 'agreement among raft nodes before linearized reading' (duration: 117.610211ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:26:32.482092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.608392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-14T01:26:32.482147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.689511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4325"} {"level":"info","ts":"2026-03-14T01:26:32.482183Z","caller":"traceutil/trace.go:171","msg":"trace[1562199346] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:407; }","duration":"119.710805ms","start":"2026-03-14T01:26:32.362446Z","end":"2026-03-14T01:26:32.482156Z","steps":["trace[1562199346] 'range keys from in-memory index tree' (duration: 119.454438ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:26:32.482237Z","caller":"traceutil/trace.go:171","msg":"trace[642764869] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:407; }","duration":"141.817335ms","start":"2026-03-14T01:26:32.340396Z","end":"2026-03-14T01:26:32.482214Z","steps":["trace[642764869] 'range keys from in-memory index tree' (duration: 141.587607ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:26:46.931108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.456721ms","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-03-14T01:26:46.931195Z","caller":"traceutil/trace.go:171","msg":"trace[2107989496] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:506; }","duration":"350.580965ms","start":"2026-03-14T01:26:46.580595Z","end":"2026-03-14T01:26:46.931176Z","steps":["trace[2107989496] 'range keys from in-memory index tree' (duration: 350.378838ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:26:46.931234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:26:46.580581Z","time spent":"350.644096ms","remote":"127.0.0.1:36582","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-03-14T01:26:47.325135Z","caller":"traceutil/trace.go:171","msg":"trace[1814906746] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"390.492239ms","start":"2026-03-14T01:26:46.934617Z","end":"2026-03-14T01:26:47.325109Z","steps":["trace[1814906746] 'process raft request' (duration: 390.336895ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:26:47.325479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:26:46.934596Z","time spent":"390.615973ms","remote":"127.0.0.1:36582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T01:26:47.585663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.930631ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-14T01:26:47.585847Z","caller":"traceutil/trace.go:171","msg":"trace[1654380386] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"650.825922ms","start":"2026-03-14T01:26:46.934989Z","end":"2026-03-14T01:26:47.585815Z","steps":["trace[1654380386] 'process raft request' (duration: 492.653854ms)","trace[1654380386] 'compare' (duration: 157.806017ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T01:26:47.586002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:26:46.934973Z","time spent":"650.950307ms","remote":"127.0.0.1:36582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T01:26:47.640636Z","caller":"traceutil/trace.go:171","msg":"trace[1419198300] linearizableReadLoop","detail":"{readStateIndex:526; appliedIndex:523; }","duration":"348.03156ms","start":"2026-03-14T01:26:47.292576Z","end":"2026-03-14T01:26:47.640608Z","steps":["trace[1419198300] 'read index received' (duration: 32.464517ms)","trace[1419198300] 'applied index is now lower than readState.Index' (duration: 315.565413ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T01:26:47.640854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.1229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4375"} {"level":"warn","ts":"2026-03-14T01:26:47.640843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.230686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T01:26:47.64089Z","caller":"traceutil/trace.go:171","msg":"trace[868034285] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:508; }","duration":"293.202493ms","start":"2026-03-14T01:26:47.347677Z","end":"2026-03-14T01:26:47.64088Z","steps":["trace[868034285] 'agreement among raft nodes before linearized reading' (duration: 293.09389ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:26:47.640935Z","caller":"traceutil/trace.go:171","msg":"trace[1430813035] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:508; }","duration":"348.36736ms","start":"2026-03-14T01:26:47.292539Z","end":"2026-03-14T01:26:47.640907Z","steps":["trace[1430813035] 'agreement among raft nodes before linearized reading' (duration: 348.196655ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:26:47.640992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:26:47.292522Z","time spent":"348.461222ms","remote":"127.0.0.1:36306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-14T01:26:54.106529Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415871371010668474,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-14T01:26:54.209923Z","caller":"traceutil/trace.go:171","msg":"trace[1340928304] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"783.497201ms","start":"2026-03-14T01:26:53.426404Z","end":"2026-03-14T01:26:54.209901Z","steps":["trace[1340928304] 'process raft request' (duration: 783.376657ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:26:54.210047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:26:53.426371Z","time spent":"783.610334ms","remote":"127.0.0.1:36496","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-03-14T01:26:54.682405Z","caller":"traceutil/trace.go:171","msg":"trace[1506635198] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"1.185330494s","start":"2026-03-14T01:26:53.497053Z","end":"2026-03-14T01:26:54.682383Z","steps":["trace[1506635198] 'process raft request' (duration: 1.104452016s)","trace[1506635198] 'compare' (duration: 80.716014ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T01:26:54.682522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:26:53.497014Z","time spent":"1.185450988s","remote":"127.0.0.1:36582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T01:26:54.682529Z","caller":"traceutil/trace.go:171","msg":"trace[539269200] linearizableReadLoop","detail":"{readStateIndex:569; appliedIndex:566; }","duration":"1.076512572s","start":"2026-03-14T01:26:53.605988Z","end":"2026-03-14T01:26:54.682501Z","steps":["trace[539269200] 'read index received' (duration: 603.798402ms)","trace[539269200] 'applied index is now lower than readState.Index' (duration: 472.71224ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:26:54.682622Z","caller":"traceutil/trace.go:171","msg":"trace[665272082] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"811.306775ms","start":"2026-03-14T01:26:53.871284Z","end":"2026-03-14T01:26:54.682591Z","steps":["trace[665272082] 'process raft request' (duration: 811.025657ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:26:54.682753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.016187109s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-14T01:26:54.682759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:26:53.87126Z","time spent":"811.427329ms","remote":"127.0.0.1:36482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4745,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T01:26:54.682778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.076779079s","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-03-14T01:26:54.68279Z","caller":"traceutil/trace.go:171","msg":"trace[1866046979] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:550; }","duration":"1.01623619s","start":"2026-03-14T01:26:53.666546Z","end":"2026-03-14T01:26:54.682782Z","steps":["trace[1866046979] 'agreement among raft nodes before linearized reading' (duration: 1.016135108s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:26:54.682813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:26:53.666538Z","time spent":"1.016270962s","remote":"127.0.0.1:36582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-14T01:26:54.682815Z","caller":"traceutil/trace.go:171","msg":"trace[1755532633] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:550; }","duration":"1.076873012s","start":"2026-03-14T01:26:53.60593Z","end":"2026-03-14T01:26:54.682803Z","steps":["trace[1755532633] 'agreement among raft nodes before linearized reading' (duration: 1.076705807s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:26:54.682849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:26:53.605906Z","time spent":"1.076934994s","remote":"127.0.0.1:36582","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":"warn","ts":"2026-03-14T01:26:54.682853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.00763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-03-14T01:26:54.682876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.452448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T01:26:54.682894Z","caller":"traceutil/trace.go:171","msg":"trace[1842312136] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:550; }","duration":"389.506049ms","start":"2026-03-14T01:26:54.293382Z","end":"2026-03-14T01:26:54.682888Z","steps":["trace[1842312136] 'agreement among raft nodes before linearized reading' (duration: 389.480408ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:26:54.682893Z","caller":"traceutil/trace.go:171","msg":"trace[1776224375] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:550; }","duration":"272.075351ms","start":"2026-03-14T01:26:54.410808Z","end":"2026-03-14T01:26:54.682883Z","steps":["trace[1776224375] 'agreement among raft nodes before linearized reading' (duration: 271.985659ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:26:54.682913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:26:54.29336Z","time spent":"389.54967ms","remote":"127.0.0.1:36306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-14T01:26:56.162072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"807.979669ms","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-03-14T01:26:56.162154Z","caller":"traceutil/trace.go:171","msg":"trace[848713858] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:553; }","duration":"808.111402ms","start":"2026-03-14T01:26:55.354022Z","end":"2026-03-14T01:26:56.162134Z","steps":["trace[848713858] 'range keys from in-memory index tree' (duration: 807.799454ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:26:56.162195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:26:55.354003Z","time spent":"808.183004ms","remote":"127.0.0.1:36582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-14T01:26:56.162338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"862.154526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T01:26:56.162387Z","caller":"traceutil/trace.go:171","msg":"trace[1490403490] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:553; }","duration":"862.24887ms","start":"2026-03-14T01:26:55.300122Z","end":"2026-03-14T01:26:56.162371Z","steps":["trace[1490403490] 'range keys from in-memory index tree' (duration: 862.056014ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:26:56.162421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:26:55.300096Z","time spent":"862.317571ms","remote":"127.0.0.1:36306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-14T01:26:57.937566Z","caller":"traceutil/trace.go:171","msg":"trace[234296540] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"350.763261ms","start":"2026-03-14T01:26:57.586778Z","end":"2026-03-14T01:26:57.937541Z","steps":["trace[234296540] 'process raft request' (duration: 350.582716ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:26:57.937698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:26:57.586747Z","time spent":"350.891075ms","remote":"127.0.0.1:36582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-14T01:28:26.180953Z","caller":"traceutil/trace.go:171","msg":"trace[1336936839] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"149.574445ms","start":"2026-03-14T01:28:26.031343Z","end":"2026-03-14T01:28:26.180917Z","steps":["trace[1336936839] 'process raft request' (duration: 149.39237ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:28:26.529718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.658942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T01:28:26.529858Z","caller":"traceutil/trace.go:171","msg":"trace[1954481054] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:994; }","duration":"235.855008ms","start":"2026-03-14T01:28:26.293959Z","end":"2026-03-14T01:28:26.529814Z","steps":["trace[1954481054] 'range keys from in-memory index tree' (duration: 235.541498ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:28:26.529926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.387977ms","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-03-14T01:28:26.530328Z","caller":"traceutil/trace.go:171","msg":"trace[1418700301] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:994; }","duration":"137.717587ms","start":"2026-03-14T01:28:26.392434Z","end":"2026-03-14T01:28:26.530152Z","steps":["trace[1418700301] 'range keys from in-memory index tree' (duration: 137.13362ms)"],"step_count":1}