{"level":"warn","ts":"2026-05-09T16:49:52.597915Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-09T16:49:52.598Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.109: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.109:2380","--initial-cluster=instance=https://199.204.45.109:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.109:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.109:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-09T16:49:52.59807Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-09T16:49:52.59808Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-05-09T16:49:52.598097Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-09T16:49:52.598723Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"]} {"level":"info","ts":"2026-05-09T16:49:52.598792Z","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.109:2380"],"listen-peer-urls":["https://199.204.45.109:2380"],"advertise-client-urls":["https://199.204.45.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.109:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-09T16:49:52.607459Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"8.454812ms"} {"level":"info","ts":"2026-05-09T16:49:52.617408Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d71a1778b31d4ce","cluster-id":"aaca35652fabf9b2"} {"level":"info","ts":"2026-05-09T16:49:52.617511Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=()"} {"level":"info","ts":"2026-05-09T16:49:52.617547Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became follower at term 0"} {"level":"info","ts":"2026-05-09T16:49:52.617561Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d71a1778b31d4ce [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-09T16:49:52.617575Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became follower at term 1"} {"level":"info","ts":"2026-05-09T16:49:52.61763Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=(968732929669846222)"} {"level":"warn","ts":"2026-05-09T16:49:52.628209Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-09T16:49:52.629601Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-09T16:49:52.631944Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-09T16:49:52.634181Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d71a1778b31d4ce","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-09T16:49:52.634684Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d71a1778b31d4ce","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-09T16:49:52.634681Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-09T16:49:52.634877Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-09T16:49:52.634911Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-09T16:49:52.638291Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=(968732929669846222)"} {"level":"info","ts":"2026-05-09T16:49:52.638585Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"aaca35652fabf9b2","local-member-id":"d71a1778b31d4ce","added-peer-id":"d71a1778b31d4ce","added-peer-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-05-09T16:49:52.639431Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-09T16:49:52.639524Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.109:2380"} {"level":"info","ts":"2026-05-09T16:49:52.639567Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.109:2380"} {"level":"info","ts":"2026-05-09T16:49:52.63971Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d71a1778b31d4ce","initial-advertise-peer-urls":["https://199.204.45.109:2380"],"listen-peer-urls":["https://199.204.45.109:2380"],"advertise-client-urls":["https://199.204.45.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-09T16:49:52.639741Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-09T16:49:53.618245Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce is starting a new election at term 1"} {"level":"info","ts":"2026-05-09T16:49:53.618372Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became pre-candidate at term 1"} {"level":"info","ts":"2026-05-09T16:49:53.618415Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce received MsgPreVoteResp from d71a1778b31d4ce at term 1"} {"level":"info","ts":"2026-05-09T16:49:53.618436Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became candidate at term 2"} {"level":"info","ts":"2026-05-09T16:49:53.618448Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce received MsgVoteResp from d71a1778b31d4ce at term 2"} {"level":"info","ts":"2026-05-09T16:49:53.618464Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became leader at term 2"} {"level":"info","ts":"2026-05-09T16:49:53.618484Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d71a1778b31d4ce elected leader d71a1778b31d4ce at term 2"} {"level":"info","ts":"2026-05-09T16:49:53.61963Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d71a1778b31d4ce","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.109:2379]}","request-path":"/0/members/d71a1778b31d4ce/attributes","cluster-id":"aaca35652fabf9b2","publish-timeout":"7s"} {"level":"info","ts":"2026-05-09T16:49:53.619632Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-09T16:49:53.619634Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T16:49:53.619708Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-09T16:49:53.620205Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-09T16:49:53.62024Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-09T16:49:53.620519Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"aaca35652fabf9b2","local-member-id":"d71a1778b31d4ce","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T16:49:53.620687Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T16:49:53.620725Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T16:49:53.62337Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-09T16:49:53.624049Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.109:2379"} {"level":"info","ts":"2026-05-09T16:50:01.880391Z","caller":"traceutil/trace.go:171","msg":"trace[1142019888] transaction","detail":"{read_only:false; response_revision:303; number_of_response:1; }","duration":"179.171772ms","start":"2026-05-09T16:50:01.70118Z","end":"2026-05-09T16:50:01.880352Z","steps":["trace[1142019888] 'process raft request' (duration: 109.206625ms)","trace[1142019888] 'compare' (duration: 69.748148ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:50:01.880687Z","caller":"traceutil/trace.go:171","msg":"trace[1632296844] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"178.253166ms","start":"2026-05-09T16:50:01.702388Z","end":"2026-05-09T16:50:01.880642Z","steps":["trace[1632296844] 'process raft request' (duration: 177.927693ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:50:01.881167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.49532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-05-09T16:50:01.881398Z","caller":"traceutil/trace.go:171","msg":"trace[269982599] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:304; }","duration":"136.977109ms","start":"2026-05-09T16:50:01.74439Z","end":"2026-05-09T16:50:01.881367Z","steps":["trace[269982599] 'agreement among raft nodes before linearized reading' (duration: 136.340425ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:50:01.88153Z","caller":"traceutil/trace.go:171","msg":"trace[1894122301] linearizableReadLoop","detail":"{readStateIndex:315; appliedIndex:313; }","duration":"136.142656ms","start":"2026-05-09T16:50:01.744444Z","end":"2026-05-09T16:50:01.880587Z","steps":["trace[1894122301] 'read index received' (duration: 66.035284ms)","trace[1894122301] 'applied index is now lower than readState.Index' (duration: 70.105962ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:50:03.329805Z","caller":"traceutil/trace.go:171","msg":"trace[887406484] linearizableReadLoop","detail":"{readStateIndex:357; appliedIndex:356; }","duration":"134.985498ms","start":"2026-05-09T16:50:03.194802Z","end":"2026-05-09T16:50:03.329788Z","steps":["trace[887406484] 'read index received' (duration: 134.858213ms)","trace[887406484] 'applied index is now lower than readState.Index' (duration: 126.515µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:50:03.329916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.097532ms","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-05-09T16:50:03.329898Z","caller":"traceutil/trace.go:171","msg":"trace[1602704101] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"167.146106ms","start":"2026-05-09T16:50:03.162725Z","end":"2026-05-09T16:50:03.329871Z","steps":["trace[1602704101] 'process raft request' (duration: 166.941088ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:50:03.32994Z","caller":"traceutil/trace.go:171","msg":"trace[1839983840] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:346; }","duration":"135.162535ms","start":"2026-05-09T16:50:03.194771Z","end":"2026-05-09T16:50:03.329933Z","steps":["trace[1839983840] 'agreement among raft nodes before linearized reading' (duration: 135.086652ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:50:03.451513Z","caller":"traceutil/trace.go:171","msg":"trace[1448563963] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"117.707947ms","start":"2026-05-09T16:50:03.333766Z","end":"2026-05-09T16:50:03.451474Z","steps":["trace[1448563963] 'process raft request' (duration: 116.568603ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:50:05.563126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.835273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-09T16:50:05.563222Z","caller":"traceutil/trace.go:171","msg":"trace[1802767457] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:356; }","duration":"228.98734ms","start":"2026-05-09T16:50:05.334211Z","end":"2026-05-09T16:50:05.563198Z","steps":["trace[1802767457] 'range keys from in-memory index tree' (duration: 228.666367ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:50:05.56322Z","caller":"traceutil/trace.go:171","msg":"trace[1764822182] linearizableReadLoop","detail":"{readStateIndex:369; appliedIndex:368; }","duration":"106.244024ms","start":"2026-05-09T16:50:05.456936Z","end":"2026-05-09T16:50:05.56318Z","steps":["trace[1764822182] 'read index received' (duration: 29.426385ms)","trace[1764822182] 'applied index is now lower than readState.Index' (duration: 76.816379ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:50:05.563336Z","caller":"traceutil/trace.go:171","msg":"trace[962207094] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"219.274732ms","start":"2026-05-09T16:50:05.344026Z","end":"2026-05-09T16:50:05.563301Z","steps":["trace[962207094] 'process raft request' (duration: 142.247355ms)","trace[962207094] 'compare' (duration: 76.760336ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:50:05.563412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.459343ms","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-05-09T16:50:05.563461Z","caller":"traceutil/trace.go:171","msg":"trace[159218066] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:357; }","duration":"106.575887ms","start":"2026-05-09T16:50:05.456871Z","end":"2026-05-09T16:50:05.563446Z","steps":["trace[159218066] 'agreement among raft nodes before linearized reading' (duration: 106.458473ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:50:07.108208Z","caller":"traceutil/trace.go:171","msg":"trace[293815675] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"120.387636ms","start":"2026-05-09T16:50:06.987714Z","end":"2026-05-09T16:50:07.108101Z","steps":["trace[293815675] 'process raft request' (duration: 120.169158ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:50:09.897595Z","caller":"traceutil/trace.go:171","msg":"trace[1997397423] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"285.650721ms","start":"2026-05-09T16:50:09.61192Z","end":"2026-05-09T16:50:09.897571Z","steps":["trace[1997397423] 'process raft request' (duration: 285.600349ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:50:09.897641Z","caller":"traceutil/trace.go:171","msg":"trace[1466926726] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"286.061287ms","start":"2026-05-09T16:50:09.611551Z","end":"2026-05-09T16:50:09.897612Z","steps":["trace[1466926726] 'process raft request' (duration: 285.794517ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:50:10.522956Z","caller":"traceutil/trace.go:171","msg":"trace[1289351658] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"197.432229ms","start":"2026-05-09T16:50:10.325489Z","end":"2026-05-09T16:50:10.522921Z","steps":["trace[1289351658] 'process raft request' (duration: 197.216611ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:50:16.427797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.819592ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T16:50:16.427862Z","caller":"traceutil/trace.go:171","msg":"trace[364496014] linearizableReadLoop","detail":"{readStateIndex:436; appliedIndex:435; }","duration":"328.540673ms","start":"2026-05-09T16:50:16.099311Z","end":"2026-05-09T16:50:16.427852Z","steps":["trace[364496014] 'read index received' (duration: 160.359788ms)","trace[364496014] 'applied index is now lower than readState.Index' (duration: 168.180235ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:50:16.427896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.593535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T16:50:16.427909Z","caller":"traceutil/trace.go:171","msg":"trace[1564913508] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:422; }","duration":"328.645307ms","start":"2026-05-09T16:50:16.099258Z","end":"2026-05-09T16:50:16.427903Z","steps":["trace[1564913508] 'agreement among raft nodes before linearized reading' (duration: 328.619916ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:50:16.427926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:50:16.099238Z","time spent":"328.683579ms","remote":"127.0.0.1:43558","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-05-09T16:50:16.427925Z","caller":"traceutil/trace.go:171","msg":"trace[1748405102] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"408.362458ms","start":"2026-05-09T16:50:16.019539Z","end":"2026-05-09T16:50:16.427902Z","steps":["trace[1748405102] 'process raft request' (duration: 240.109239ms)","trace[1748405102] 'compare' (duration: 167.611853ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:50:16.428054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:50:16.019517Z","time spent":"408.466542ms","remote":"127.0.0.1:43796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T16:50:18.488586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.930858986s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.109\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-09T16:50:18.488652Z","caller":"traceutil/trace.go:171","msg":"trace[348981316] range","detail":"{range_begin:/registry/masterleases/199.204.45.109; range_end:; response_count:1; response_revision:422; }","duration":"1.93096372s","start":"2026-05-09T16:50:16.557671Z","end":"2026-05-09T16:50:18.488635Z","steps":["trace[348981316] 'range keys from in-memory index tree' (duration: 1.930757653s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:50:18.488682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:50:16.557654Z","time spent":"1.931017512s","remote":"127.0.0.1:43592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.109\" "} {"level":"warn","ts":"2026-05-09T16:50:18.488789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.392554738s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T16:50:18.488806Z","caller":"traceutil/trace.go:171","msg":"trace[2019131052] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:422; }","duration":"1.392571448s","start":"2026-05-09T16:50:17.096229Z","end":"2026-05-09T16:50:18.4888Z","steps":["trace[2019131052] 'range keys from in-memory index tree' (duration: 1.392439093s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:50:18.488822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:50:17.096205Z","time spent":"1.39261344s","remote":"127.0.0.1:43548","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-05-09T16:50:18.488907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"810.478755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-09T16:50:18.488923Z","caller":"traceutil/trace.go:171","msg":"trace[581961984] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:422; }","duration":"810.552398ms","start":"2026-05-09T16:50:17.678366Z","end":"2026-05-09T16:50:18.488918Z","steps":["trace[581961984] 'range keys from in-memory index tree' (duration: 810.401422ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:50:18.48894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:50:17.678347Z","time spent":"810.58994ms","remote":"127.0.0.1:43796","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":"warn","ts":"2026-05-09T16:50:18.48903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"768.556122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-09T16:50:18.489047Z","caller":"traceutil/trace.go:171","msg":"trace[1259664342] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:422; }","duration":"768.581253ms","start":"2026-05-09T16:50:17.72046Z","end":"2026-05-09T16:50:18.489041Z","steps":["trace[1259664342] 'range keys from in-memory index tree' (duration: 768.481329ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:50:18.489073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:50:17.720403Z","time spent":"768.664237ms","remote":"127.0.0.1:43796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-09T16:50:18.489236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.901872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-09T16:50:18.489265Z","caller":"traceutil/trace.go:171","msg":"trace[1684837903] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:422; }","duration":"494.956214ms","start":"2026-05-09T16:50:17.9943Z","end":"2026-05-09T16:50:18.489256Z","steps":["trace[1684837903] 'range keys from in-memory index tree' (duration: 494.794218ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:50:18.489291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:50:17.994286Z","time spent":"494.999526ms","remote":"127.0.0.1:43796","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":"warn","ts":"2026-05-09T16:50:25.053524Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334367562686917904,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-09T16:50:25.268369Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.101428813s","expected-duration":"1s"} {"level":"info","ts":"2026-05-09T16:50:25.27491Z","caller":"traceutil/trace.go:171","msg":"trace[500471292] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"1.156752997s","start":"2026-05-09T16:50:24.118111Z","end":"2026-05-09T16:50:25.274864Z","steps":["trace[500471292] 'process raft request' (duration: 1.154264778s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:50:25.275132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:50:24.118093Z","time spent":"1.156957605s","remote":"127.0.0.1:43608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T16:50:25.275392Z","caller":"traceutil/trace.go:171","msg":"trace[1643515201] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"1.147920646s","start":"2026-05-09T16:50:24.127372Z","end":"2026-05-09T16:50:25.275293Z","steps":["trace[1643515201] 'process raft request' (duration: 1.145247401s)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:50:25.275501Z","caller":"traceutil/trace.go:171","msg":"trace[1358937801] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"1.161788568s","start":"2026-05-09T16:50:24.113693Z","end":"2026-05-09T16:50:25.275482Z","steps":["trace[1358937801] 'process raft request' (duration: 1.158510327s)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:50:25.275296Z","caller":"traceutil/trace.go:171","msg":"trace[1268493759] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"1.161650381s","start":"2026-05-09T16:50:24.113569Z","end":"2026-05-09T16:50:25.275219Z","steps":["trace[1268493759] 'process raft request' (duration: 1.155237377s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:50:25.275608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:50:24.113689Z","time spent":"1.161873601s","remote":"127.0.0.1:43608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T16:50:25.27579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:50:24.113552Z","time spent":"1.162078919s","remote":"127.0.0.1:43608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6888,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T16:50:25.275816Z","caller":"traceutil/trace.go:171","msg":"trace[2111347214] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"1.150131095s","start":"2026-05-09T16:50:24.125535Z","end":"2026-05-09T16:50:25.275666Z","steps":["trace[2111347214] 'process raft request' (duration: 1.147004091s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:50:25.275595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:50:24.127362Z","time spent":"1.148143876s","remote":"127.0.0.1:43608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T16:50:25.275892Z","caller":"traceutil/trace.go:171","msg":"trace[1469147457] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"1.134023856s","start":"2026-05-09T16:50:24.141688Z","end":"2026-05-09T16:50:25.275711Z","steps":["trace[1469147457] 'process raft request' (duration: 1.132731824s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:50:25.275922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:50:24.125521Z","time spent":"1.150343094s","remote":"127.0.0.1:43608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T16:50:25.275997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:50:24.141661Z","time spent":"1.134285016s","remote":"127.0.0.1:43608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T16:50:25.275825Z","caller":"traceutil/trace.go:171","msg":"trace[413399851] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"1.158446786s","start":"2026-05-09T16:50:24.117363Z","end":"2026-05-09T16:50:25.275809Z","steps":["trace[413399851] 'process raft request' (duration: 1.154934996s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:50:25.276317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:50:24.117355Z","time spent":"1.158909243s","remote":"127.0.0.1:43608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T16:50:25.275407Z","caller":"traceutil/trace.go:171","msg":"trace[2065244729] transaction","detail":"{read_only:false; number_of_response:1; response_revision:476; }","duration":"1.153798471s","start":"2026-05-09T16:50:24.121532Z","end":"2026-05-09T16:50:25.275331Z","steps":["trace[2065244729] 'process raft request' (duration: 1.150930206s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:50:25.276532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:50:24.121517Z","time spent":"1.154864493s","remote":"127.0.0.1:43608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":6937,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T16:50:25.276456Z","caller":"traceutil/trace.go:171","msg":"trace[36519554] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"1.12150491s","start":"2026-05-09T16:50:24.15492Z","end":"2026-05-09T16:50:25.276425Z","steps":["trace[36519554] 'process raft request' (duration: 1.120541172s)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:50:25.276602Z","caller":"traceutil/trace.go:171","msg":"trace[368558065] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"1.148878064s","start":"2026-05-09T16:50:24.12757Z","end":"2026-05-09T16:50:25.276448Z","steps":["trace[368558065] 'process raft request' (duration: 1.145123456s)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:50:25.276707Z","caller":"traceutil/trace.go:171","msg":"trace[1648564235] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"1.147216869s","start":"2026-05-09T16:50:24.12937Z","end":"2026-05-09T16:50:25.276587Z","steps":["trace[1648564235] 'process raft request' (duration: 1.143415459s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:50:25.276722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:50:24.1549Z","time spent":"1.121731549s","remote":"127.0.0.1:43608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T16:50:25.276812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:50:24.129353Z","time spent":"1.147404898s","remote":"127.0.0.1:43608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T16:50:25.277587Z","caller":"traceutil/trace.go:171","msg":"trace[1005276877] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"1.143906748s","start":"2026-05-09T16:50:24.13269Z","end":"2026-05-09T16:50:25.276597Z","steps":["trace[1005276877] 'process raft request' (duration: 1.141129388s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:50:25.277658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:50:24.127561Z","time spent":"1.149174337s","remote":"127.0.0.1:43608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T16:50:25.27786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:50:24.132669Z","time spent":"1.144990242s","remote":"127.0.0.1:43608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T16:50:27.005386Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.736462233s","expected-duration":"1s"} {"level":"info","ts":"2026-05-09T16:50:27.006112Z","caller":"traceutil/trace.go:171","msg":"trace[819085516] linearizableReadLoop","detail":"{readStateIndex:501; appliedIndex:488; }","duration":"2.453280616s","start":"2026-05-09T16:50:24.552714Z","end":"2026-05-09T16:50:27.005995Z","steps":["trace[819085516] 'read index received' (duration: 716.035321ms)","trace[819085516] 'applied index is now lower than readState.Index' (duration: 1.737244425s)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:50:27.006416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.453676262s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-09T16:50:27.006489Z","caller":"traceutil/trace.go:171","msg":"trace[532119052] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:484; }","duration":"2.453798946s","start":"2026-05-09T16:50:24.552675Z","end":"2026-05-09T16:50:27.006474Z","steps":["trace[532119052] 'agreement among raft nodes before linearized reading' (duration: 2.453607169s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:50:27.006557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:50:24.552656Z","time spent":"2.45388892s","remote":"127.0.0.1:43796","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-05-09T16:50:27.006241Z","caller":"traceutil/trace.go:171","msg":"trace[755473145] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"2.744338118s","start":"2026-05-09T16:50:24.261892Z","end":"2026-05-09T16:50:27.00623Z","steps":["trace[755473145] 'process raft request' (duration: 2.743761314s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:50:27.007023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:50:24.261873Z","time spent":"2.745072055s","remote":"127.0.0.1:43608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T16:50:27.098177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000483614s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-09T16:50:27.098291Z","caller":"traceutil/trace.go:171","msg":"trace[2062752910] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000659001s","start":"2026-05-09T16:50:25.097604Z","end":"2026-05-09T16:50:27.098263Z","steps":["trace[2062752910] 'agreement among raft nodes before linearized reading' (duration: 2.000503815s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:50:27.098362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:50:25.097589Z","time spent":"2.000760456s","remote":"127.0.0.1:43548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-09T16:50:27.118747Z","caller":"traceutil/trace.go:171","msg":"trace[799781162] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"1.827007543s","start":"2026-05-09T16:50:25.29171Z","end":"2026-05-09T16:50:27.118718Z","steps":["trace[799781162] 'process raft request' (duration: 1.826782474s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:50:27.118905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:50:25.291689Z","time spent":"1.827149448s","remote":"127.0.0.1:44064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T16:50:27.118954Z","caller":"traceutil/trace.go:171","msg":"trace[968898350] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"1.827222961s","start":"2026-05-09T16:50:25.29171Z","end":"2026-05-09T16:50:27.118933Z","steps":["trace[968898350] 'process raft request' (duration: 1.824295015s)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:50:27.118974Z","caller":"traceutil/trace.go:171","msg":"trace[745404164] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"1.825474572s","start":"2026-05-09T16:50:25.293428Z","end":"2026-05-09T16:50:27.118902Z","steps":["trace[745404164] 'process raft request' (duration: 1.825226172s)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:50:27.118974Z","caller":"traceutil/trace.go:171","msg":"trace[502016836] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"1.825338196s","start":"2026-05-09T16:50:25.293608Z","end":"2026-05-09T16:50:27.118946Z","steps":["trace[502016836] 'process raft request' (duration: 1.825097936s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:50:27.119044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:50:25.291689Z","time spent":"1.827306764s","remote":"127.0.0.1:44064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T16:50:27.119093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:50:25.293418Z","time spent":"1.825605937s","remote":"127.0.0.1:43608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T16:50:27.119124Z","caller":"traceutil/trace.go:171","msg":"trace[974139798] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"1.827223511s","start":"2026-05-09T16:50:25.291888Z","end":"2026-05-09T16:50:27.119112Z","steps":["trace[974139798] 'process raft request' (duration: 1.826643859s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:50:27.119131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:50:25.2936Z","time spent":"1.825469261s","remote":"127.0.0.1:44064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T16:50:27.119186Z","caller":"traceutil/trace.go:171","msg":"trace[1774574908] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"1.824920939s","start":"2026-05-09T16:50:25.294255Z","end":"2026-05-09T16:50:27.119176Z","steps":["trace[1774574908] 'process raft request' (duration: 1.824475881s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:50:27.119196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:50:25.291871Z","time spent":"1.827279543s","remote":"127.0.0.1:43608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7684,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T16:50:27.119231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:50:25.294248Z","time spent":"1.824961551s","remote":"127.0.0.1:44064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T16:50:27.119403Z","caller":"traceutil/trace.go:171","msg":"trace[689961751] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"1.824884179s","start":"2026-05-09T16:50:25.294508Z","end":"2026-05-09T16:50:27.119393Z","steps":["trace[689961751] 'process raft request' (duration: 1.824255704s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:50:27.119459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:50:25.294502Z","time spent":"1.824925151s","remote":"127.0.0.1:43608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T16:50:27.121953Z","caller":"traceutil/trace.go:171","msg":"trace[651029482] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"1.820954822s","start":"2026-05-09T16:50:25.300973Z","end":"2026-05-09T16:50:27.121928Z","steps":["trace[651029482] 'process raft request' (duration: 1.817898211s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:50:27.122069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:50:25.300967Z","time spent":"1.821048166s","remote":"127.0.0.1:43608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T16:50:27.122374Z","caller":"traceutil/trace.go:171","msg":"trace[1041055780] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"1.82112554s","start":"2026-05-09T16:50:25.301229Z","end":"2026-05-09T16:50:27.122355Z","steps":["trace[1041055780] 'process raft request' (duration: 1.820669381s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:50:27.122492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:50:25.301224Z","time spent":"1.821196943s","remote":"127.0.0.1:43608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T16:50:27.122547Z","caller":"traceutil/trace.go:171","msg":"trace[1312126130] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"1.126158485s","start":"2026-05-09T16:50:25.996375Z","end":"2026-05-09T16:50:27.122533Z","steps":["trace[1312126130] 'process raft request' (duration: 1.12577874s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:50:27.124998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:50:25.996343Z","time spent":"1.128588601s","remote":"127.0.0.1:43796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T16:50:27.122814Z","caller":"traceutil/trace.go:171","msg":"trace[963347] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"1.816653081s","start":"2026-05-09T16:50:25.30615Z","end":"2026-05-09T16:50:27.122803Z","steps":["trace[963347] 'process raft request' (duration: 1.815915672s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:50:27.12517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:50:25.306133Z","time spent":"1.819003525s","remote":"127.0.0.1:43608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T16:50:27.122971Z","caller":"traceutil/trace.go:171","msg":"trace[96254863] linearizableReadLoop","detail":"{readStateIndex:512; appliedIndex:501; }","duration":"116.750409ms","start":"2026-05-09T16:50:27.006198Z","end":"2026-05-09T16:50:27.122948Z","steps":["trace[96254863] 'read index received' (duration: 109.829484ms)","trace[96254863] 'applied index is now lower than readState.Index' (duration: 6.919035ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:50:27.123082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.568706292s","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-05-09T16:50:27.125396Z","caller":"traceutil/trace.go:171","msg":"trace[950572700] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:497; }","duration":"2.571056805s","start":"2026-05-09T16:50:24.554325Z","end":"2026-05-09T16:50:27.125382Z","steps":["trace[950572700] 'agreement among raft nodes before linearized reading' (duration: 2.568685061s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:50:27.125432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:50:24.554305Z","time spent":"2.571117208s","remote":"127.0.0.1:43796","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-05-09T16:50:27.12313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.390365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.109\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-09T16:50:27.125599Z","caller":"traceutil/trace.go:171","msg":"trace[127226698] range","detail":"{range_begin:/registry/masterleases/199.204.45.109; range_end:; response_count:1; response_revision:497; }","duration":"566.893055ms","start":"2026-05-09T16:50:26.558694Z","end":"2026-05-09T16:50:27.125587Z","steps":["trace[127226698] 'agreement among raft nodes before linearized reading' (duration: 564.411816ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:50:27.125635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:50:26.558676Z","time spent":"566.949747ms","remote":"127.0.0.1:43592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.109\" "} {"level":"warn","ts":"2026-05-09T16:50:27.123165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.568579766s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-09T16:50:27.125792Z","caller":"traceutil/trace.go:171","msg":"trace[128075212] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:497; }","duration":"2.571220812s","start":"2026-05-09T16:50:24.554561Z","end":"2026-05-09T16:50:27.125782Z","steps":["trace[128075212] 'agreement among raft nodes before linearized reading' (duration: 2.568583697s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:50:27.125825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:50:24.554553Z","time spent":"2.571264223s","remote":"127.0.0.1:43796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-09T16:50:27.123245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.568755083s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-09T16:50:27.126035Z","caller":"traceutil/trace.go:171","msg":"trace[252387812] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:497; }","duration":"2.571560925s","start":"2026-05-09T16:50:24.554463Z","end":"2026-05-09T16:50:27.126024Z","steps":["trace[252387812] 'agreement among raft nodes before linearized reading' (duration: 2.568727123s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:50:27.126072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:50:24.554456Z","time spent":"2.571605966s","remote":"127.0.0.1:43796","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-05-09T16:50:27.123373Z","caller":"traceutil/trace.go:171","msg":"trace[608848413] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"111.111865ms","start":"2026-05-09T16:50:27.012245Z","end":"2026-05-09T16:50:27.123357Z","steps":["trace[608848413] 'process raft request' (duration: 109.970621ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:50:27.905319Z","caller":"traceutil/trace.go:171","msg":"trace[956153343] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"254.886218ms","start":"2026-05-09T16:50:27.650411Z","end":"2026-05-09T16:50:27.905297Z","steps":["trace[956153343] 'process raft request' (duration: 247.13318ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:50:27.925808Z","caller":"traceutil/trace.go:171","msg":"trace[70050409] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"267.812332ms","start":"2026-05-09T16:50:27.657972Z","end":"2026-05-09T16:50:27.925785Z","steps":["trace[70050409] 'process raft request' (duration: 267.372294ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:50:27.92597Z","caller":"traceutil/trace.go:171","msg":"trace[1283080022] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"207.826419ms","start":"2026-05-09T16:50:27.718115Z","end":"2026-05-09T16:50:27.925942Z","steps":["trace[1283080022] 'process raft request' (duration: 207.546937ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:50:33.297982Z","caller":"traceutil/trace.go:171","msg":"trace[1805178090] linearizableReadLoop","detail":"{readStateIndex:601; appliedIndex:600; }","duration":"149.543005ms","start":"2026-05-09T16:50:33.148413Z","end":"2026-05-09T16:50:33.297956Z","steps":["trace[1805178090] 'read index received' (duration: 128.324065ms)","trace[1805178090] 'applied index is now lower than readState.Index' (duration: 21.21799ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:50:33.298106Z","caller":"traceutil/trace.go:171","msg":"trace[1902437548] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"197.513823ms","start":"2026-05-09T16:50:33.100553Z","end":"2026-05-09T16:50:33.298067Z","steps":["trace[1902437548] 'process raft request' (duration: 176.286282ms)","trace[1902437548] 'compare' (duration: 20.957472ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:50:33.298372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.951079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-09T16:50:33.298371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.749955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-09T16:50:33.298428Z","caller":"traceutil/trace.go:171","msg":"trace[1124649895] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:580; }","duration":"150.039092ms","start":"2026-05-09T16:50:33.148374Z","end":"2026-05-09T16:50:33.298413Z","steps":["trace[1124649895] 'agreement among raft nodes before linearized reading' (duration: 149.828484ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:50:33.298433Z","caller":"traceutil/trace.go:171","msg":"trace[1191962746] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:580; }","duration":"137.85962ms","start":"2026-05-09T16:50:33.160559Z","end":"2026-05-09T16:50:33.298419Z","steps":["trace[1191962746] 'agreement among raft nodes before linearized reading' (duration: 137.696594ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:50:33.298592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.203168ms","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-05-09T16:50:33.29863Z","caller":"traceutil/trace.go:171","msg":"trace[975061966] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:580; }","duration":"132.260929ms","start":"2026-05-09T16:50:33.166357Z","end":"2026-05-09T16:50:33.298618Z","steps":["trace[975061966] 'agreement among raft nodes before linearized reading' (duration: 132.148685ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:50:33.298717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.491237ms","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-05-09T16:50:33.298812Z","caller":"traceutil/trace.go:171","msg":"trace[1192998230] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:580; }","duration":"132.645922ms","start":"2026-05-09T16:50:33.166146Z","end":"2026-05-09T16:50:33.298792Z","steps":["trace[1192998230] 'agreement among raft nodes before linearized reading' (duration: 132.371193ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:50:33.629187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.238175ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T16:50:33.62933Z","caller":"traceutil/trace.go:171","msg":"trace[2016177862] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"325.523128ms","start":"2026-05-09T16:50:33.303792Z","end":"2026-05-09T16:50:33.629315Z","steps":["trace[2016177862] 'process raft request' (duration: 125.072825ms)","trace[2016177862] 'compare' (duration: 200.0806ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:50:33.629387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:50:33.303767Z","time spent":"325.58801ms","remote":"127.0.0.1:43796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T16:50:33.937775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.807171ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T16:50:33.93805Z","caller":"traceutil/trace.go:171","msg":"trace[1995725865] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"633.458008ms","start":"2026-05-09T16:50:33.30455Z","end":"2026-05-09T16:50:33.938008Z","steps":["trace[1995725865] 'process raft request' (duration: 443.335177ms)","trace[1995725865] 'compare' (duration: 189.671216ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:50:33.938116Z","caller":"traceutil/trace.go:171","msg":"trace[2075787901] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:601; }","duration":"628.195618ms","start":"2026-05-09T16:50:33.3099Z","end":"2026-05-09T16:50:33.938095Z","steps":["trace[2075787901] 'read index received' (duration: 119.144073ms)","trace[2075787901] 'applied index is now lower than readState.Index' (duration: 509.050395ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:50:33.938242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:50:33.304523Z","time spent":"633.628984ms","remote":"127.0.0.1:43796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T16:50:33.938219Z","caller":"traceutil/trace.go:171","msg":"trace[745144395] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"633.244031ms","start":"2026-05-09T16:50:33.304927Z","end":"2026-05-09T16:50:33.938171Z","steps":["trace[745144395] 'process raft request' (duration: 632.994812ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:50:33.938304Z","caller":"traceutil/trace.go:171","msg":"trace[72340915] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"625.525189ms","start":"2026-05-09T16:50:33.312673Z","end":"2026-05-09T16:50:33.938198Z","steps":["trace[72340915] 'process raft request' (duration: 625.332731ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:50:33.938399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:50:33.312658Z","time spent":"625.694534ms","remote":"127.0.0.1:43744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T16:50:33.938459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:50:33.304918Z","time spent":"633.408927ms","remote":"127.0.0.1:43796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T16:50:33.93876Z","caller":"traceutil/trace.go:171","msg":"trace[1909613587] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"633.54885ms","start":"2026-05-09T16:50:33.304548Z","end":"2026-05-09T16:50:33.938097Z","steps":["trace[1909613587] 'process raft request' (duration: 633.319893ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:50:33.938857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.931484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T16:50:33.939Z","caller":"traceutil/trace.go:171","msg":"trace[887142129] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:585; }","duration":"629.11064ms","start":"2026-05-09T16:50:33.309866Z","end":"2026-05-09T16:50:33.938976Z","steps":["trace[887142129] 'agreement among raft nodes before linearized reading' (duration: 628.289642ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:50:33.939035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:50:33.304516Z","time spent":"634.395749ms","remote":"127.0.0.1:43796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T16:50:33.939121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:50:33.309854Z","time spent":"629.194602ms","remote":"127.0.0.1:43770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-05-09T16:50:34.600579Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334367562686918153,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-09T16:50:34.880547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.314944ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-09T16:50:34.90466Z","caller":"traceutil/trace.go:171","msg":"trace[476407022] linearizableReadLoop","detail":"{readStateIndex:608; appliedIndex:606; }","duration":"804.99092ms","start":"2026-05-09T16:50:34.099645Z","end":"2026-05-09T16:50:34.904636Z","steps":["trace[476407022] 'read index received' (duration: 652.704176ms)","trace[476407022] 'applied index is now lower than readState.Index' (duration: 152.285904ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:50:34.904728Z","caller":"traceutil/trace.go:171","msg":"trace[1832094413] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"588.932109ms","start":"2026-05-09T16:50:34.315745Z","end":"2026-05-09T16:50:34.904677Z","steps":["trace[1832094413] 'process raft request' (duration: 588.228687ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:50:34.90487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"805.204147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T16:50:34.905005Z","caller":"traceutil/trace.go:171","msg":"trace[822883614] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:586; }","duration":"805.431024ms","start":"2026-05-09T16:50:34.099542Z","end":"2026-05-09T16:50:34.904973Z","steps":["trace[822883614] 'agreement among raft nodes before linearized reading' (duration: 805.187477ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:50:34.905003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:50:34.315718Z","time spent":"589.091337ms","remote":"127.0.0.1:43652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T16:50:34.905084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:50:34.099516Z","time spent":"805.554502ms","remote":"127.0.0.1:43548","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-05-09T16:50:35.600832Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334367562686918156,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-09T16:50:36.10116Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334367562686918156,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-09T16:50:36.602007Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334367562686918156,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-09T16:50:37.099978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000184628s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-09T16:50:37.100056Z","caller":"traceutil/trace.go:171","msg":"trace[521482327] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000324706s","start":"2026-05-09T16:50:35.099716Z","end":"2026-05-09T16:50:37.100041Z","steps":["trace[521482327] 'agreement among raft nodes before linearized reading' (duration: 2.000229947s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:50:37.100086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:50:35.099695Z","time spent":"2.000385296s","remote":"127.0.0.1:43548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/09 16:50:37 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-09T16:50:37.102366Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334367562686918156,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-09T16:50:37.327661Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.36179544s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-09T16:50:37.656888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.883906ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-09T16:50:37.657063Z","caller":"traceutil/trace.go:171","msg":"trace[1453243326] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"518.722643ms","start":"2026-05-09T16:50:37.138325Z","end":"2026-05-09T16:50:37.657048Z","steps":["trace[1453243326] 'process raft request' (duration: 518.654513ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:50:37.657132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:50:37.138284Z","time spent":"518.810102ms","remote":"127.0.0.1:43796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T16:50:37.657372Z","caller":"traceutil/trace.go:171","msg":"trace[580584874] linearizableReadLoop","detail":"{readStateIndex:609; appliedIndex:608; }","duration":"2.557557716s","start":"2026-05-09T16:50:35.099779Z","end":"2026-05-09T16:50:37.657337Z","steps":["trace[580584874] 'read index received' (duration: 2.228237815s)","trace[580584874] 'applied index is now lower than readState.Index' (duration: 329.314461ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:50:37.657561Z","caller":"traceutil/trace.go:171","msg":"trace[1043355493] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"2.691953472s","start":"2026-05-09T16:50:34.965585Z","end":"2026-05-09T16:50:37.657539Z","steps":["trace[1043355493] 'process raft request' (duration: 2.362359724s)","trace[1043355493] 'compare' (duration: 328.272132ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:50:37.65766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:50:34.965558Z","time spent":"2.692037041s","remote":"127.0.0.1:43770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T16:50:37.657673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.022917731s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-09T16:50:37.657712Z","caller":"traceutil/trace.go:171","msg":"trace[731200353] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:588; }","duration":"2.02295854s","start":"2026-05-09T16:50:35.634743Z","end":"2026-05-09T16:50:37.657702Z","steps":["trace[731200353] 'agreement among raft nodes before linearized reading' (duration: 2.022843421s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:50:37.657742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:50:35.634663Z","time spent":"2.023072199s","remote":"127.0.0.1:43796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-09T16:50:37.657866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.483247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T16:50:37.657891Z","caller":"traceutil/trace.go:171","msg":"trace[1702295607] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:588; }","duration":"557.506877ms","start":"2026-05-09T16:50:37.100376Z","end":"2026-05-09T16:50:37.657883Z","steps":["trace[1702295607] 'agreement among raft nodes before linearized reading' (duration: 557.468068ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:50:37.657916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:50:37.100325Z","time spent":"557.584376ms","remote":"127.0.0.1:43558","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-05-09T16:50:37.658089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.71472236s","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-05-09T16:50:37.658129Z","caller":"traceutil/trace.go:171","msg":"trace[796608303] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:588; }","duration":"1.714822508s","start":"2026-05-09T16:50:35.943298Z","end":"2026-05-09T16:50:37.658121Z","steps":["trace[796608303] 'agreement among raft nodes before linearized reading' (duration: 1.71471168s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:50:37.658155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:50:35.943263Z","time spent":"1.714885998s","remote":"127.0.0.1:43796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-09T16:50:37.658147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.099009894s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.109\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-09T16:50:37.658196Z","caller":"traceutil/trace.go:171","msg":"trace[1975481507] range","detail":"{range_begin:/registry/masterleases/199.204.45.109; range_end:; response_count:1; response_revision:588; }","duration":"1.099099524s","start":"2026-05-09T16:50:36.559084Z","end":"2026-05-09T16:50:37.658184Z","steps":["trace[1975481507] 'agreement among raft nodes before linearized reading' (duration: 1.098951424s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:50:37.658203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.711131444s","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-05-09T16:50:37.658226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:50:36.559063Z","time spent":"1.099155474s","remote":"127.0.0.1:43592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.109\" "} {"level":"info","ts":"2026-05-09T16:50:37.658236Z","caller":"traceutil/trace.go:171","msg":"trace[912877476] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:588; }","duration":"1.711197804s","start":"2026-05-09T16:50:35.947028Z","end":"2026-05-09T16:50:37.658225Z","steps":["trace[912877476] 'agreement among raft nodes before linearized reading' (duration: 1.711090304s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:50:37.658268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:50:35.947007Z","time spent":"1.711253994s","remote":"127.0.0.1:43796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-09T16:50:37.658094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.711593778s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-09T16:50:37.658308Z","caller":"traceutil/trace.go:171","msg":"trace[1060470098] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:588; }","duration":"1.711867135s","start":"2026-05-09T16:50:35.946432Z","end":"2026-05-09T16:50:37.658299Z","steps":["trace[1060470098] 'agreement among raft nodes before linearized reading' (duration: 1.711537418s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:50:37.658341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:50:35.946407Z","time spent":"1.711926045s","remote":"127.0.0.1:43796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-09T16:50:37.857604Z","caller":"traceutil/trace.go:171","msg":"trace[1057763523] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"190.976203ms","start":"2026-05-09T16:50:37.666604Z","end":"2026-05-09T16:50:37.85758Z","steps":["trace[1057763523] 'process raft request' (duration: 190.946393ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:50:37.857714Z","caller":"traceutil/trace.go:171","msg":"trace[505539937] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"194.785623ms","start":"2026-05-09T16:50:37.662911Z","end":"2026-05-09T16:50:37.857697Z","steps":["trace[505539937] 'process raft request' (duration: 194.473206ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:50:37.857836Z","caller":"traceutil/trace.go:171","msg":"trace[1119807710] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"193.371608ms","start":"2026-05-09T16:50:37.664447Z","end":"2026-05-09T16:50:37.857819Z","steps":["trace[1119807710] 'process raft request' (duration: 193.081712ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:50:37.857966Z","caller":"traceutil/trace.go:171","msg":"trace[478390819] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"193.674525ms","start":"2026-05-09T16:50:37.664282Z","end":"2026-05-09T16:50:37.857956Z","steps":["trace[478390819] 'process raft request' (duration: 193.22085ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:50:37.858028Z","caller":"traceutil/trace.go:171","msg":"trace[302108892] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:610; }","duration":"195.582104ms","start":"2026-05-09T16:50:37.66239Z","end":"2026-05-09T16:50:37.857972Z","steps":["trace[302108892] 'read index received' (duration: 189.193793ms)","trace[302108892] 'applied index is now lower than readState.Index' (duration: 6.385711ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:50:37.858321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.688573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18adf3be6fefc253\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-05-09T16:50:37.858362Z","caller":"traceutil/trace.go:171","msg":"trace[1033048337] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18adf3be6fefc253; range_end:; response_count:1; response_revision:592; }","duration":"195.755132ms","start":"2026-05-09T16:50:37.662595Z","end":"2026-05-09T16:50:37.85835Z","steps":["trace[1033048337] 'agreement among raft nodes before linearized reading' (duration: 195.680133ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:50:37.858395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.598137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-09T16:50:37.858316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.89973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-05-09T16:50:37.858439Z","caller":"traceutil/trace.go:171","msg":"trace[262150936] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:592; }","duration":"191.692146ms","start":"2026-05-09T16:50:37.666733Z","end":"2026-05-09T16:50:37.858425Z","steps":["trace[262150936] 'agreement among raft nodes before linearized reading' (duration: 191.636327ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:50:37.85848Z","caller":"traceutil/trace.go:171","msg":"trace[670381024] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:592; }","duration":"196.104309ms","start":"2026-05-09T16:50:37.662359Z","end":"2026-05-09T16:50:37.858463Z","steps":["trace[670381024] 'agreement among raft nodes before linearized reading' (duration: 195.876371ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:50:39.913311Z","caller":"traceutil/trace.go:171","msg":"trace[1209859812] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"476.607243ms","start":"2026-05-09T16:50:39.436677Z","end":"2026-05-09T16:50:39.913284Z","steps":["trace[1209859812] 'process raft request' (duration: 476.416555ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:50:39.913494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:50:39.436641Z","time spent":"476.765771ms","remote":"127.0.0.1:43608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3202,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T16:50:39.918458Z","caller":"traceutil/trace.go:171","msg":"trace[1218066732] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"282.998875ms","start":"2026-05-09T16:50:39.635433Z","end":"2026-05-09T16:50:39.918432Z","steps":["trace[1218066732] 'process raft request' (duration: 282.810057ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:50:42.552882Z","caller":"traceutil/trace.go:171","msg":"trace[1923663311] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"117.086527ms","start":"2026-05-09T16:50:42.435771Z","end":"2026-05-09T16:50:42.552857Z","steps":["trace[1923663311] 'process raft request' (duration: 116.925218ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:50:50.10908Z","caller":"traceutil/trace.go:171","msg":"trace[1387184802] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"104.082987ms","start":"2026-05-09T16:50:50.004976Z","end":"2026-05-09T16:50:50.109059Z","steps":["trace[1387184802] 'process raft request' (duration: 103.720469ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:50:53.745698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.42244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T16:50:53.745792Z","caller":"traceutil/trace.go:171","msg":"trace[551602617] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:750; }","duration":"645.5688ms","start":"2026-05-09T16:50:53.100199Z","end":"2026-05-09T16:50:53.745768Z","steps":["trace[551602617] 'range keys from in-memory index tree' (duration: 645.3444ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:50:53.745843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:50:53.100174Z","time spent":"645.65835ms","remote":"127.0.0.1:43548","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-05-09T16:50:53.745884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.402327662s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-pj9hr\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-05-09T16:50:53.746011Z","caller":"traceutil/trace.go:171","msg":"trace[1144784679] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-pj9hr; range_end:; response_count:1; response_revision:750; }","duration":"1.402548032s","start":"2026-05-09T16:50:52.343439Z","end":"2026-05-09T16:50:53.745987Z","steps":["trace[1144784679] 'range keys from in-memory index tree' (duration: 1.402157612s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:50:53.746092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:50:52.343417Z","time spent":"1.402662332s","remote":"127.0.0.1:43744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-pj9hr\" "} {"level":"info","ts":"2026-05-09T16:50:53.749112Z","caller":"traceutil/trace.go:171","msg":"trace[548005862] linearizableReadLoop","detail":"{readStateIndex:778; appliedIndex:777; }","duration":"401.558441ms","start":"2026-05-09T16:50:53.347494Z","end":"2026-05-09T16:50:53.749052Z","steps":["trace[548005862] 'read index received' (duration: 384.640201ms)","trace[548005862] 'applied index is now lower than readState.Index' (duration: 16.91709ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:50:53.749455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.94142ms","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-05-09T16:50:53.74953Z","caller":"traceutil/trace.go:171","msg":"trace[1738980112] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:750; }","duration":"402.055671ms","start":"2026-05-09T16:50:53.347458Z","end":"2026-05-09T16:50:53.749514Z","steps":["trace[1738980112] 'agreement among raft nodes before linearized reading' (duration: 401.688051ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:50:53.749845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:50:53.347443Z","time spent":"402.208651ms","remote":"127.0.0.1:43796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-09T16:52:44.431959Z","caller":"traceutil/trace.go:171","msg":"trace[270356386] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"141.694705ms","start":"2026-05-09T16:52:44.290244Z","end":"2026-05-09T16:52:44.431939Z","steps":["trace[270356386] 'process raft request' (duration: 141.570262ms)"],"step_count":1}