{"level":"warn","ts":"2026-07-16T00:16:34.751659Z","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-16T00:16:34.751725Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.216: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.216:2380","--initial-cluster=instance=https://199.204.45.216:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.216:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.216: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-16T00:16:34.751783Z","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-16T00:16:34.751804Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-07-16T00:16:34.751823Z","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-16T00:16:34.752479Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"]} {"level":"info","ts":"2026-07-16T00:16:34.752786Z","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.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.216: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-16T00:16:34.757157Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.182231ms"} {"level":"info","ts":"2026-07-16T00:16:34.772137Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e98569549cc430c","cluster-id":"65d70b4d1d4a8bf9"} {"level":"info","ts":"2026-07-16T00:16:34.772208Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=()"} {"level":"info","ts":"2026-07-16T00:16:34.772231Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 0"} {"level":"info","ts":"2026-07-16T00:16:34.772248Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e98569549cc430c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-16T00:16:34.772258Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 1"} {"level":"info","ts":"2026-07-16T00:16:34.772298Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"warn","ts":"2026-07-16T00:16:34.783286Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-16T00:16:34.785053Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-16T00:16:34.786292Z","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-16T00:16:34.788684Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e98569549cc430c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-16T00:16:34.788833Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e98569549cc430c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-16T00:16:34.789059Z","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-16T00:16:34.789117Z","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-16T00:16:34.78913Z","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-16T00:16:34.795888Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"info","ts":"2026-07-16T00:16:34.795993Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","added-peer-id":"4e98569549cc430c","added-peer-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-07-16T00:16:34.802793Z","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-16T00:16:34.803037Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-07-16T00:16:34.803062Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e98569549cc430c","initial-advertise-peer-urls":["https://199.204.45.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-16T00:16:34.803089Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-07-16T00:16:34.803092Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-16T00:16:34.974384Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c is starting a new election at term 1"} {"level":"info","ts":"2026-07-16T00:16:34.974753Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became pre-candidate at term 1"} {"level":"info","ts":"2026-07-16T00:16:34.974954Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgPreVoteResp from 4e98569549cc430c at term 1"} {"level":"info","ts":"2026-07-16T00:16:34.975159Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became candidate at term 2"} {"level":"info","ts":"2026-07-16T00:16:34.975336Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgVoteResp from 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-07-16T00:16:34.975509Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became leader at term 2"} {"level":"info","ts":"2026-07-16T00:16:34.975721Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e98569549cc430c elected leader 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-07-16T00:16:34.977086Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e98569549cc430c","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.216:2379]}","request-path":"/0/members/4e98569549cc430c/attributes","cluster-id":"65d70b4d1d4a8bf9","publish-timeout":"7s"} {"level":"info","ts":"2026-07-16T00:16:34.977478Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-16T00:16:34.977752Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-16T00:16:34.977755Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T00:16:34.977909Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-16T00:16:34.97856Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-16T00:16:34.981835Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.216:2379"} {"level":"info","ts":"2026-07-16T00:16:34.982376Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-16T00:16:34.98919Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T00:16:34.9893Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T00:16:34.989339Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-07-16T00:16:36.98508Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831411770499650934,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-16T00:16:37.453458Z","caller":"traceutil/trace.go:171","msg":"trace[499580092] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"969.895192ms","start":"2026-07-16T00:16:36.483536Z","end":"2026-07-16T00:16:37.453431Z","steps":["trace[499580092] 'process raft request' (duration: 968.13634ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:16:37.454856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:16:36.483524Z","time spent":"970.029739ms","remote":"127.0.0.1:39040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T00:16:37.517532Z","caller":"traceutil/trace.go:171","msg":"trace[890025901] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"961.866662ms","start":"2026-07-16T00:16:36.555618Z","end":"2026-07-16T00:16:37.517485Z","steps":["trace[890025901] 'process raft request' (duration: 961.657473ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:16:37.517579Z","caller":"traceutil/trace.go:171","msg":"trace[205837462] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"1.033692677s","start":"2026-07-16T00:16:36.48384Z","end":"2026-07-16T00:16:37.517533Z","steps":["trace[205837462] 'process raft request' (duration: 1.03327314s)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:16:37.517727Z","caller":"traceutil/trace.go:171","msg":"trace[1634776514] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"960.399551ms","start":"2026-07-16T00:16:36.557315Z","end":"2026-07-16T00:16:37.517715Z","steps":["trace[1634776514] 'process raft request' (duration: 960.078127ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:16:37.517699Z","caller":"traceutil/trace.go:171","msg":"trace[739231971] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"961.208464ms","start":"2026-07-16T00:16:36.556425Z","end":"2026-07-16T00:16:37.517633Z","steps":["trace[739231971] 'process raft request' (duration: 960.896371ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:16:37.517802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:16:36.555586Z","time spent":"962.040189ms","remote":"127.0.0.1:39416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-16T00:16:37.517647Z","caller":"traceutil/trace.go:171","msg":"trace[1554200147] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"960.510925ms","start":"2026-07-16T00:16:36.557099Z","end":"2026-07-16T00:16:37.51761Z","steps":["trace[1554200147] 'process raft request' (duration: 960.260215ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:16:37.517869Z","caller":"traceutil/trace.go:171","msg":"trace[803932127] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"960.38728ms","start":"2026-07-16T00:16:36.557474Z","end":"2026-07-16T00:16:37.517861Z","steps":["trace[803932127] 'process raft request' (duration: 959.947221ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:16:37.517904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:16:36.557452Z","time spent":"960.434542ms","remote":"127.0.0.1:39514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-16T00:16:37.517893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:16:36.556374Z","time spent":"961.434013ms","remote":"127.0.0.1:39514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-16T00:16:37.517973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:16:36.557055Z","time spent":"960.834698ms","remote":"127.0.0.1:39514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-16T00:16:37.517678Z","caller":"traceutil/trace.go:171","msg":"trace[1131617356] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"948.675194ms","start":"2026-07-16T00:16:36.568961Z","end":"2026-07-16T00:16:37.517636Z","steps":["trace[1131617356] 'process raft request' (duration: 948.519738ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:16:37.518082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:16:36.568954Z","time spent":"949.103752ms","remote":"127.0.0.1:39172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3159,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-16T00:16:37.518105Z","caller":"traceutil/trace.go:171","msg":"trace[1092948056] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"933.555745ms","start":"2026-07-16T00:16:36.584541Z","end":"2026-07-16T00:16:37.518096Z","steps":["trace[1092948056] 'process raft request' (duration: 932.978001ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:16:37.517822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:16:36.557299Z","time spent":"960.454542ms","remote":"127.0.0.1:39514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-16T00:16:37.517658Z","caller":"traceutil/trace.go:171","msg":"trace[1161802399] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"959.832796ms","start":"2026-07-16T00:16:36.557794Z","end":"2026-07-16T00:16:37.517626Z","steps":["trace[1161802399] 'process raft request' (duration: 959.652968ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:16:37.518148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:16:36.584526Z","time spent":"933.600678ms","remote":"127.0.0.1:39272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-16T00:16:37.518277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:16:36.557741Z","time spent":"960.523344ms","remote":"127.0.0.1:39514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-16T00:16:37.518454Z","caller":"traceutil/trace.go:171","msg":"trace[268691856] linearizableReadLoop","detail":"{readStateIndex:14; appliedIndex:4; }","duration":"1.033551251s","start":"2026-07-16T00:16:36.484008Z","end":"2026-07-16T00:16:37.517559Z","steps":["trace[268691856] 'read index received' (duration: 967.836238ms)","trace[268691856] 'applied index is now lower than readState.Index' (duration: 65.713603ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T00:16:37.518502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.03447204s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-16T00:16:37.518554Z","caller":"traceutil/trace.go:171","msg":"trace[165578017] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:11; }","duration":"1.034566664s","start":"2026-07-16T00:16:36.48398Z","end":"2026-07-16T00:16:37.518546Z","steps":["trace[165578017] 'agreement among raft nodes before linearized reading' (duration: 1.034443658s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:16:37.518592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:16:36.483971Z","time spent":"1.034616276s","remote":"127.0.0.1:39276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":26,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-16T00:16:37.51874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"961.233425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-16T00:16:37.518768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"963.750571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.216\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-16T00:16:37.51879Z","caller":"traceutil/trace.go:171","msg":"trace[1181727843] range","detail":"{range_begin:/registry/masterleases/199.204.45.216; range_end:; response_count:0; response_revision:11; }","duration":"963.811184ms","start":"2026-07-16T00:16:36.554972Z","end":"2026-07-16T00:16:37.518783Z","steps":["trace[1181727843] 'agreement among raft nodes before linearized reading' (duration: 963.772692ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:16:37.518796Z","caller":"traceutil/trace.go:171","msg":"trace[1889636368] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"961.346291ms","start":"2026-07-16T00:16:36.557434Z","end":"2026-07-16T00:16:37.51878Z","steps":["trace[1889636368] 'agreement among raft nodes before linearized reading' (duration: 961.246457ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:16:37.518824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:16:36.554946Z","time spent":"963.873736ms","remote":"127.0.0.1:38986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":26,"request content":"key:\"/registry/masterleases/199.204.45.216\" "} {"level":"warn","ts":"2026-07-16T00:16:37.518833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:16:36.557423Z","time spent":"961.401963ms","remote":"127.0.0.1:39060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":26,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-07-16T00:16:37.518978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"963.095393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-16T00:16:37.518992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"911.804317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-16T00:16:37.519025Z","caller":"traceutil/trace.go:171","msg":"trace[1400680154] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"911.866969ms","start":"2026-07-16T00:16:36.607147Z","end":"2026-07-16T00:16:37.519014Z","steps":["trace[1400680154] 'agreement among raft nodes before linearized reading' (duration: 911.818617ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:16:37.519061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:16:36.607128Z","time spent":"911.926412ms","remote":"127.0.0.1:39082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-07-16T00:16:37.519Z","caller":"traceutil/trace.go:171","msg":"trace[623251180] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"963.148316ms","start":"2026-07-16T00:16:36.555846Z","end":"2026-07-16T00:16:37.518994Z","steps":["trace[623251180] 'agreement among raft nodes before linearized reading' (duration: 963.098254ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:16:37.519229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:16:36.555831Z","time spent":"963.389346ms","remote":"127.0.0.1:39082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-07-16T00:16:37.519597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.035506743s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3171"} {"level":"info","ts":"2026-07-16T00:16:37.519637Z","caller":"traceutil/trace.go:171","msg":"trace[739734025] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:11; }","duration":"1.035562125s","start":"2026-07-16T00:16:36.484065Z","end":"2026-07-16T00:16:37.519627Z","steps":["trace[739734025] 'agreement among raft nodes before linearized reading' (duration: 1.035484762s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:16:37.519664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:16:36.484058Z","time spent":"1.035599867s","remote":"127.0.0.1:39172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3193,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-07-16T00:16:37.520756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.036705762s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-16T00:16:37.520807Z","caller":"traceutil/trace.go:171","msg":"trace[1782701170] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:11; }","duration":"1.036785396s","start":"2026-07-16T00:16:36.484008Z","end":"2026-07-16T00:16:37.520794Z","steps":["trace[1782701170] 'agreement among raft nodes before linearized reading' (duration: 1.034630337s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:16:37.52091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:16:36.483991Z","time spent":"1.036908301s","remote":"127.0.0.1:39276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":26,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-16T00:16:37.522139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:16:36.483805Z","time spent":"1.033972748s","remote":"127.0.0.1:39024","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":100,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T00:16:37.522766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"987.785476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-16T00:16:37.522824Z","caller":"traceutil/trace.go:171","msg":"trace[414029333] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"987.917451ms","start":"2026-07-16T00:16:36.534889Z","end":"2026-07-16T00:16:37.522807Z","steps":["trace[414029333] 'agreement among raft nodes before linearized reading' (duration: 983.857891ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:16:37.522859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:16:36.534859Z","time spent":"987.991924ms","remote":"127.0.0.1:39082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-07-16T00:16:42.803781Z","caller":"traceutil/trace.go:171","msg":"trace[1966653750] transaction","detail":"{read_only:false; response_revision:297; number_of_response:1; }","duration":"113.846112ms","start":"2026-07-16T00:16:42.6899Z","end":"2026-07-16T00:16:42.803746Z","steps":["trace[1966653750] 'process raft request' (duration: 69.678373ms)","trace[1966653750] 'compare' (duration: 43.968701ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T00:16:53.185937Z","caller":"traceutil/trace.go:171","msg":"trace[1233329709] linearizableReadLoop","detail":"{readStateIndex:393; appliedIndex:392; }","duration":"221.761842ms","start":"2026-07-16T00:16:52.964156Z","end":"2026-07-16T00:16:53.185917Z","steps":["trace[1233329709] 'read index received' (duration: 221.607237ms)","trace[1233329709] 'applied index is now lower than readState.Index' (duration: 153.615µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T00:16:53.186054Z","caller":"traceutil/trace.go:171","msg":"trace[2060005381] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"328.994381ms","start":"2026-07-16T00:16:52.857049Z","end":"2026-07-16T00:16:53.186043Z","steps":["trace[2060005381] 'process raft request' (duration: 328.730672ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:16:53.186225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.012261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:476"} {"level":"info","ts":"2026-07-16T00:16:53.186284Z","caller":"traceutil/trace.go:171","msg":"trace[1205572640] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:379; }","duration":"101.118875ms","start":"2026-07-16T00:16:53.085148Z","end":"2026-07-16T00:16:53.186267Z","steps":["trace[1205572640] 'agreement among raft nodes before linearized reading' (duration: 101.015422ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:16:53.186318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:16:52.856986Z","time spent":"329.251529ms","remote":"127.0.0.1:39276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T00:16:53.186439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.290871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T00:16:53.186474Z","caller":"traceutil/trace.go:171","msg":"trace[757551381] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:379; }","duration":"222.354663ms","start":"2026-07-16T00:16:52.964111Z","end":"2026-07-16T00:16:53.186466Z","steps":["trace[757551381] 'agreement among raft nodes before linearized reading' (duration: 222.306942ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:16:53.341145Z","caller":"traceutil/trace.go:171","msg":"trace[274453865] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"150.676788ms","start":"2026-07-16T00:16:53.190405Z","end":"2026-07-16T00:16:53.341082Z","steps":["trace[274453865] 'process raft request' (duration: 149.799176ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:16:53.804365Z","caller":"traceutil/trace.go:171","msg":"trace[793718037] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"117.323787ms","start":"2026-07-16T00:16:53.687017Z","end":"2026-07-16T00:16:53.804341Z","steps":["trace[793718037] 'process raft request' (duration: 117.144301ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:16:53.897513Z","caller":"traceutil/trace.go:171","msg":"trace[714249640] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"207.862595ms","start":"2026-07-16T00:16:53.6896Z","end":"2026-07-16T00:16:53.897463Z","steps":["trace[714249640] 'process raft request' (duration: 174.117772ms)","trace[714249640] 'compare' (duration: 33.582087ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T00:17:08.450877Z","caller":"traceutil/trace.go:171","msg":"trace[720977505] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"106.36855ms","start":"2026-07-16T00:17:08.344257Z","end":"2026-07-16T00:17:08.450626Z","steps":["trace[720977505] 'process raft request' (duration: 106.191525ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:17:15.054637Z","caller":"traceutil/trace.go:171","msg":"trace[296420438] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"576.212962ms","start":"2026-07-16T00:17:14.478388Z","end":"2026-07-16T00:17:15.0546Z","steps":["trace[296420438] 'process raft request' (duration: 575.868482ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:17:15.054802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:17:14.478354Z","time spent":"576.372166ms","remote":"127.0.0.1:39010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-16T00:17:15.846908Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831411770499651975,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-16T00:17:16.019042Z","caller":"traceutil/trace.go:171","msg":"trace[606205476] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"864.75619ms","start":"2026-07-16T00:17:15.154268Z","end":"2026-07-16T00:17:16.019024Z","steps":["trace[606205476] 'process raft request' (duration: 864.633866ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:17:16.019146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:17:15.154243Z","time spent":"864.855503ms","remote":"127.0.0.1:39010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14661,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-16T00:17:17.284893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:17:16.945684Z","time spent":"339.202199ms","remote":"127.0.0.1:60758","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-16T00:17:17.286288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"953.999575ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T00:17:17.286427Z","caller":"traceutil/trace.go:171","msg":"trace[1642309549] linearizableReadLoop","detail":"{readStateIndex:584; appliedIndex:582; }","duration":"1.940155681s","start":"2026-07-16T00:17:15.346215Z","end":"2026-07-16T00:17:17.286371Z","steps":["trace[1642309549] 'read index received' (duration: 672.703858ms)","trace[1642309549] 'applied index is now lower than readState.Index' (duration: 1.267450683s)"],"step_count":2} {"level":"info","ts":"2026-07-16T00:17:17.286508Z","caller":"traceutil/trace.go:171","msg":"trace[756619204] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"2.040521682s","start":"2026-07-16T00:17:15.245939Z","end":"2026-07-16T00:17:17.28646Z","steps":["trace[756619204] 'process raft request' (duration: 1.086147297s)","trace[756619204] 'compare' (duration: 953.705487ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T00:17:17.286628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.940394008s","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-07-16T00:17:17.286671Z","caller":"traceutil/trace.go:171","msg":"trace[1298072243] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:562; }","duration":"1.94049089s","start":"2026-07-16T00:17:15.34617Z","end":"2026-07-16T00:17:17.286661Z","steps":["trace[1298072243] 'agreement among raft nodes before linearized reading' (duration: 1.940324856s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:17:17.286684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:17:15.24591Z","time spent":"2.040683836s","remote":"127.0.0.1:39010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570081,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T00:17:17.286708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:17:15.346148Z","time spent":"1.940553711s","remote":"127.0.0.1:39276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-16T00:17:17.321541Z","caller":"traceutil/trace.go:171","msg":"trace[1626482059] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"1.172321844s","start":"2026-07-16T00:17:16.149196Z","end":"2026-07-16T00:17:17.321518Z","steps":["trace[1626482059] 'process raft request' (duration: 1.172191051s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:17:17.321658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:17:16.149179Z","time spent":"1.172425768s","remote":"127.0.0.1:39010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":207462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-16T00:17:17.322681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.743968665s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-07-16T00:17:17.322757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.21072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T00:17:17.322811Z","caller":"traceutil/trace.go:171","msg":"trace[81051833] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:563; }","duration":"357.281562ms","start":"2026-07-16T00:17:16.965513Z","end":"2026-07-16T00:17:17.322794Z","steps":["trace[81051833] 'agreement among raft nodes before linearized reading' (duration: 357.223931ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:17:17.322844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.334626615s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-16T00:17:17.322856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:17:16.9655Z","time spent":"357.349085ms","remote":"127.0.0.1:38962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-16T00:17:17.322877Z","caller":"traceutil/trace.go:171","msg":"trace[955994536] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:563; }","duration":"1.334687126s","start":"2026-07-16T00:17:15.988182Z","end":"2026-07-16T00:17:17.322869Z","steps":["trace[955994536] 'agreement among raft nodes before linearized reading' (duration: 1.334606644s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:17:17.322917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:17:15.988165Z","time spent":"1.334744089s","remote":"127.0.0.1:39276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-16T00:17:17.322762Z","caller":"traceutil/trace.go:171","msg":"trace[361304717] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:563; }","duration":"1.744081678s","start":"2026-07-16T00:17:15.578663Z","end":"2026-07-16T00:17:17.322745Z","steps":["trace[361304717] 'agreement among raft nodes before linearized reading' (duration: 1.74379453s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:17:17.323064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:17:15.578603Z","time spent":"1.744451218s","remote":"127.0.0.1:39276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-16T00:17:17.322692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.197762409s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-16T00:17:17.323272Z","caller":"traceutil/trace.go:171","msg":"trace[2057562367] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:563; }","duration":"1.198367625s","start":"2026-07-16T00:17:16.124886Z","end":"2026-07-16T00:17:17.323254Z","steps":["trace[2057562367] 'agreement among raft nodes before linearized reading' (duration: 1.197596834s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:17:17.323315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:17:16.124875Z","time spent":"1.198429906s","remote":"127.0.0.1:39276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-16T00:17:21.558096Z","caller":"traceutil/trace.go:171","msg":"trace[775426809] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"178.288075ms","start":"2026-07-16T00:17:21.379774Z","end":"2026-07-16T00:17:21.558062Z","steps":["trace[775426809] 'process raft request' (duration: 145.361667ms)","trace[775426809] 'compare' (duration: 32.708202ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T00:17:21.558277Z","caller":"traceutil/trace.go:171","msg":"trace[2086042742] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"177.802722ms","start":"2026-07-16T00:17:21.380439Z","end":"2026-07-16T00:17:21.558241Z","steps":["trace[2086042742] 'process raft request' (duration: 177.559645ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:17:22.134735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.698513ms","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-07-16T00:17:22.134921Z","caller":"traceutil/trace.go:171","msg":"trace[1964879559] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:598; }","duration":"472.888417ms","start":"2026-07-16T00:17:21.661996Z","end":"2026-07-16T00:17:22.134884Z","steps":["trace[1964879559] 'range keys from in-memory index tree' (duration: 472.646821ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:17:22.287419Z","caller":"traceutil/trace.go:171","msg":"trace[815808011] linearizableReadLoop","detail":"{readStateIndex:622; appliedIndex:621; }","duration":"320.286494ms","start":"2026-07-16T00:17:21.967104Z","end":"2026-07-16T00:17:22.28739Z","steps":["trace[815808011] 'read index received' (duration: 319.569576ms)","trace[815808011] 'applied index is now lower than readState.Index' (duration: 715.878µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T00:17:22.287543Z","caller":"traceutil/trace.go:171","msg":"trace[942456339] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"673.995368ms","start":"2026-07-16T00:17:21.613539Z","end":"2026-07-16T00:17:22.287534Z","steps":["trace[942456339] 'process raft request' (duration: 673.323181ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:17:22.287645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:17:21.613497Z","time spent":"674.07271ms","remote":"127.0.0.1:39064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036265,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T00:17:22.287901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.797888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T00:17:22.287933Z","caller":"traceutil/trace.go:171","msg":"trace[1272581872] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:599; }","duration":"320.829029ms","start":"2026-07-16T00:17:21.967094Z","end":"2026-07-16T00:17:22.287923Z","steps":["trace[1272581872] 'agreement among raft nodes before linearized reading' (duration: 320.775527ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:17:22.287959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:17:21.967006Z","time spent":"320.947932ms","remote":"127.0.0.1:38976","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-07-16T00:17:22.288105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.287963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-mrmc6\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-16T00:17:22.288131Z","caller":"traceutil/trace.go:171","msg":"trace[384687645] range","detail":"{range_begin:/registry/pods/kube-system/cilium-mrmc6; range_end:; response_count:1; response_revision:599; }","duration":"305.338735ms","start":"2026-07-16T00:17:21.982784Z","end":"2026-07-16T00:17:22.288123Z","steps":["trace[384687645] 'agreement among raft nodes before linearized reading' (duration: 305.284603ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:17:22.288155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:17:21.982772Z","time spent":"305.377336ms","remote":"127.0.0.1:39188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-mrmc6\" "} {"level":"warn","ts":"2026-07-16T00:17:22.904086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.315748ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T00:17:22.904207Z","caller":"traceutil/trace.go:171","msg":"trace[1925522793] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"591.296138ms","start":"2026-07-16T00:17:22.312893Z","end":"2026-07-16T00:17:22.904189Z","steps":["trace[1925522793] 'process raft request' (duration: 118.774595ms)","trace[1925522793] 'compare' (duration: 472.185074ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T00:17:22.90427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:17:22.312867Z","time spent":"591.373249ms","remote":"127.0.0.1:39188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T00:17:23.464728Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831411770499652076,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-16T00:17:23.728034Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.296480911s","expected-duration":"1s"} {"level":"info","ts":"2026-07-16T00:17:23.73836Z","caller":"traceutil/trace.go:171","msg":"trace[1848725850] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:623; }","duration":"774.620492ms","start":"2026-07-16T00:17:22.963718Z","end":"2026-07-16T00:17:23.738338Z","steps":["trace[1848725850] 'read index received' (duration: 764.607216ms)","trace[1848725850] 'applied index is now lower than readState.Index' (duration: 10.012326ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T00:17:23.738463Z","caller":"traceutil/trace.go:171","msg":"trace[942385445] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"1.37216141s","start":"2026-07-16T00:17:22.366281Z","end":"2026-07-16T00:17:23.738442Z","steps":["trace[942385445] 'process raft request' (duration: 1.362057133s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:17:23.738668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:17:22.366254Z","time spent":"1.372296434s","remote":"127.0.0.1:39352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-16T00:17:23.738722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"774.993972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-16T00:17:23.738977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.304694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-16T00:17:23.739061Z","caller":"traceutil/trace.go:171","msg":"trace[1149729556] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:601; }","duration":"305.448678ms","start":"2026-07-16T00:17:23.433592Z","end":"2026-07-16T00:17:23.739041Z","steps":["trace[1149729556] 'agreement among raft nodes before linearized reading' (duration: 305.202012ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:17:23.739107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:17:23.433569Z","time spent":"305.529601ms","remote":"127.0.0.1:39276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-16T00:17:23.739164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.43722ms","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-16T00:17:23.739208Z","caller":"traceutil/trace.go:171","msg":"trace[1029770229] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:601; }","duration":"176.511302ms","start":"2026-07-16T00:17:23.562686Z","end":"2026-07-16T00:17:23.739197Z","steps":["trace[1029770229] 'agreement among raft nodes before linearized reading' (duration: 176.389259ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:17:23.739229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.521461ms","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-07-16T00:17:23.739241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.896492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-16T00:17:23.739294Z","caller":"traceutil/trace.go:171","msg":"trace[1507883890] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:601; }","duration":"305.629573ms","start":"2026-07-16T00:17:23.433648Z","end":"2026-07-16T00:17:23.739278Z","steps":["trace[1507883890] 'agreement among raft nodes before linearized reading' (duration: 305.457749ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:17:23.739355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:17:23.433617Z","time spent":"305.725736ms","remote":"127.0.0.1:39276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-16T00:17:23.739367Z","caller":"traceutil/trace.go:171","msg":"trace[912962919] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:601; }","duration":"177.039815ms","start":"2026-07-16T00:17:23.562286Z","end":"2026-07-16T00:17:23.739326Z","steps":["trace[912962919] 'agreement among raft nodes before linearized reading' (duration: 176.740718ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:17:23.738892Z","caller":"traceutil/trace.go:171","msg":"trace[2136105054] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:601; }","duration":"775.215458ms","start":"2026-07-16T00:17:22.963656Z","end":"2026-07-16T00:17:23.738872Z","steps":["trace[2136105054] 'agreement among raft nodes before linearized reading' (duration: 774.809577ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:17:23.740257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:17:22.96363Z","time spent":"776.611592ms","remote":"127.0.0.1:38962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-16T00:17:25.370885Z","caller":"traceutil/trace.go:171","msg":"trace[1230123995] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"163.981994ms","start":"2026-07-16T00:17:25.206877Z","end":"2026-07-16T00:17:25.370859Z","steps":["trace[1230123995] 'process raft request' (duration: 163.804539ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:17:39.512403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.017021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T00:17:39.51249Z","caller":"traceutil/trace.go:171","msg":"trace[2138397967] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:762; }","duration":"545.147283ms","start":"2026-07-16T00:17:38.967324Z","end":"2026-07-16T00:17:39.512471Z","steps":["trace[2138397967] 'range keys from in-memory index tree' (duration: 544.935349ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:17:39.512535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:17:38.967292Z","time spent":"545.233426ms","remote":"127.0.0.1:38962","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-07-16T00:17:39.513886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.681481ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T00:17:39.514294Z","caller":"traceutil/trace.go:171","msg":"trace[1205601081] linearizableReadLoop","detail":"{readStateIndex:791; appliedIndex:790; }","duration":"433.584654ms","start":"2026-07-16T00:17:39.080696Z","end":"2026-07-16T00:17:39.51428Z","steps":["trace[1205601081] 'read index received' (duration: 303.492549ms)","trace[1205601081] 'applied index is now lower than readState.Index' (duration: 130.090985ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T00:17:39.514441Z","caller":"traceutil/trace.go:171","msg":"trace[1513946544] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"522.732293ms","start":"2026-07-16T00:17:38.991696Z","end":"2026-07-16T00:17:39.514429Z","steps":["trace[1513946544] 'process raft request' (duration: 393.752793ms)","trace[1513946544] 'compare' (duration: 127.309423ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T00:17:39.514544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.851089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-hbqrm\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-07-16T00:17:39.514577Z","caller":"traceutil/trace.go:171","msg":"trace[1810019549] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-hbqrm; range_end:; response_count:1; response_revision:763; }","duration":"433.88612ms","start":"2026-07-16T00:17:39.080683Z","end":"2026-07-16T00:17:39.51457Z","steps":["trace[1810019549] 'agreement among raft nodes before linearized reading' (duration: 433.777538ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:17:39.514593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:17:39.080636Z","time spent":"433.952882ms","remote":"127.0.0.1:39188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-hbqrm\" "} {"level":"warn","ts":"2026-07-16T00:17:39.514978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:17:38.991677Z","time spent":"523.262995ms","remote":"127.0.0.1:39276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T00:17:39.515113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.429104ms","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":"info","ts":"2026-07-16T00:17:39.515234Z","caller":"traceutil/trace.go:171","msg":"trace[646925437] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:763; }","duration":"398.544766ms","start":"2026-07-16T00:17:39.116652Z","end":"2026-07-16T00:17:39.515197Z","steps":["trace[646925437] 'agreement among raft nodes before linearized reading' (duration: 398.029045ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:17:39.515345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:17:39.11664Z","time spent":"398.668779ms","remote":"127.0.0.1:39276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-16T00:18:46.38943Z","caller":"traceutil/trace.go:171","msg":"trace[968120211] transaction","detail":"{read_only:false; response_revision:1120; number_of_response:1; }","duration":"102.938089ms","start":"2026-07-16T00:18:46.286459Z","end":"2026-07-16T00:18:46.389397Z","steps":["trace[968120211] 'process raft request' (duration: 92.695381ms)","trace[968120211] 'compare' (duration: 10.127716ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T00:18:46.401136Z","caller":"traceutil/trace.go:171","msg":"trace[1567347467] transaction","detail":"{read_only:false; response_revision:1121; number_of_response:1; }","duration":"114.17821ms","start":"2026-07-16T00:18:46.286931Z","end":"2026-07-16T00:18:46.40111Z","steps":["trace[1567347467] 'process raft request' (duration: 114.019388ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:18:46.401297Z","caller":"traceutil/trace.go:171","msg":"trace[242825094] transaction","detail":"{read_only:false; response_revision:1122; number_of_response:1; }","duration":"113.631513ms","start":"2026-07-16T00:18:46.287592Z","end":"2026-07-16T00:18:46.401224Z","steps":["trace[242825094] 'process raft request' (duration: 113.48259ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:18:52.410198Z","caller":"traceutil/trace.go:171","msg":"trace[1505165779] linearizableReadLoop","detail":"{readStateIndex:1183; appliedIndex:1182; }","duration":"103.710781ms","start":"2026-07-16T00:18:52.306458Z","end":"2026-07-16T00:18:52.410169Z","steps":["trace[1505165779] 'read index received' (duration: 103.508798ms)","trace[1505165779] 'applied index is now lower than readState.Index' (duration: 200.973µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T00:18:52.410286Z","caller":"traceutil/trace.go:171","msg":"trace[395308412] transaction","detail":"{read_only:false; response_revision:1139; number_of_response:1; }","duration":"117.400294ms","start":"2026-07-16T00:18:52.292856Z","end":"2026-07-16T00:18:52.410256Z","steps":["trace[395308412] 'process raft request' (duration: 117.13744ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:18:52.410458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.984366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-16T00:18:52.410506Z","caller":"traceutil/trace.go:171","msg":"trace[1274089513] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1139; }","duration":"104.087528ms","start":"2026-07-16T00:18:52.306406Z","end":"2026-07-16T00:18:52.410494Z","steps":["trace[1274089513] 'agreement among raft nodes before linearized reading' (duration: 103.911185ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:18:54.054276Z","caller":"traceutil/trace.go:171","msg":"trace[285677048] transaction","detail":"{read_only:false; response_revision:1145; number_of_response:1; }","duration":"118.178595ms","start":"2026-07-16T00:18:53.936062Z","end":"2026-07-16T00:18:54.05424Z","steps":["trace[285677048] 'process raft request' (duration: 117.859071ms)"],"step_count":1}